May 7 23:43:56.340303 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] May 7 23:43:56.340326 kernel: Linux version 6.6.88-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 14.2.1_p20241221 p7) 14.2.1 20241221, GNU ld (Gentoo 2.43 p3) 2.43.1) #1 SMP PREEMPT Wed May 7 22:21:35 -00 2025 May 7 23:43:56.340335 kernel: KASLR enabled May 7 23:43:56.340340 kernel: earlycon: pl11 at MMIO 0x00000000effec000 (options '') May 7 23:43:56.340348 kernel: printk: bootconsole [pl11] enabled May 7 23:43:56.340354 kernel: efi: EFI v2.7 by EDK II May 7 23:43:56.340361 kernel: efi: ACPI 2.0=0x3fd5f018 SMBIOS=0x3e580000 SMBIOS 3.0=0x3e560000 MEMATTR=0x3f20f698 RNG=0x3fd5f998 MEMRESERVE=0x3e477598 May 7 23:43:56.340367 kernel: random: crng init done May 7 23:43:56.340372 kernel: secureboot: Secure boot disabled May 7 23:43:56.340378 kernel: ACPI: Early table checksum verification disabled May 7 23:43:56.340384 kernel: ACPI: RSDP 0x000000003FD5F018 000024 (v02 VRTUAL) May 7 23:43:56.340390 kernel: ACPI: XSDT 0x000000003FD5FF18 00006C (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) May 7 23:43:56.340396 kernel: ACPI: FACP 0x000000003FD5FC18 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) May 7 23:43:56.340404 kernel: ACPI: DSDT 0x000000003FD41018 01DFCD (v02 MSFTVM DSDT01 00000001 INTL 20230628) May 7 23:43:56.340411 kernel: ACPI: DBG2 0x000000003FD5FB18 000072 (v00 VRTUAL MICROSFT 00000001 MSFT 00000001) May 7 23:43:56.340417 kernel: ACPI: GTDT 0x000000003FD5FD98 000060 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) May 7 23:43:56.340423 kernel: ACPI: OEM0 0x000000003FD5F098 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) May 7 23:43:56.340431 kernel: ACPI: SPCR 0x000000003FD5FA98 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) May 7 23:43:56.340438 kernel: ACPI: APIC 0x000000003FD5F818 0000FC (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) May 7 23:43:56.340444 kernel: ACPI: SRAT 0x000000003FD5F198 000234 (v03 VRTUAL MICROSFT 00000001 MSFT 00000001) May 7 23:43:56.340450 kernel: ACPI: PPTT 0x000000003FD5F418 000120 (v01 VRTUAL MICROSFT 00000000 MSFT 00000000) May 7 23:43:56.340456 kernel: ACPI: BGRT 0x000000003FD5FE98 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) May 7 23:43:56.340463 kernel: ACPI: SPCR: console: pl011,mmio32,0xeffec000,115200 May 7 23:43:56.340469 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] May 7 23:43:56.340475 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0x1bfffffff] May 7 23:43:56.340481 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x1c0000000-0xfbfffffff] May 7 23:43:56.340487 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x1000000000-0xffffffffff] May 7 23:43:56.340493 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000000000-0x1ffffffffff] May 7 23:43:56.340501 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000000000-0x3ffffffffff] May 7 23:43:56.340507 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000000000-0x7ffffffffff] May 7 23:43:56.340513 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000000000-0xfffffffffff] May 7 23:43:56.340519 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000000-0x1fffffffffff] May 7 23:43:56.340526 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x200000000000-0x3fffffffffff] May 7 23:43:56.340532 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x400000000000-0x7fffffffffff] May 7 23:43:56.340538 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x800000000000-0xffffffffffff] May 7 23:43:56.340544 kernel: NUMA: NODE_DATA [mem 0x1bf7ee800-0x1bf7f3fff] May 7 23:43:56.340550 kernel: Zone ranges: May 7 23:43:56.340556 kernel: DMA [mem 0x0000000000000000-0x00000000ffffffff] May 7 23:43:56.340563 kernel: DMA32 empty May 7 23:43:56.340569 kernel: Normal [mem 0x0000000100000000-0x00000001bfffffff] May 7 23:43:56.340579 kernel: Movable zone start for each node May 7 23:43:56.340586 kernel: Early memory node ranges May 7 23:43:56.340592 kernel: node 0: [mem 0x0000000000000000-0x00000000007fffff] May 7 23:43:56.340599 kernel: node 0: [mem 0x0000000000824000-0x000000003e45ffff] May 7 23:43:56.340605 kernel: node 0: [mem 0x000000003e460000-0x000000003e46ffff] May 7 23:43:56.340613 kernel: node 0: [mem 0x000000003e470000-0x000000003e54ffff] May 7 23:43:56.340620 kernel: node 0: [mem 0x000000003e550000-0x000000003e87ffff] May 7 23:43:56.340626 kernel: node 0: [mem 0x000000003e880000-0x000000003fc7ffff] May 7 23:43:56.340633 kernel: node 0: [mem 0x000000003fc80000-0x000000003fcfffff] May 7 23:43:56.340639 kernel: node 0: [mem 0x000000003fd00000-0x000000003fffffff] May 7 23:43:56.340646 kernel: node 0: [mem 0x0000000100000000-0x00000001bfffffff] May 7 23:43:56.340652 kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000001bfffffff] May 7 23:43:56.340659 kernel: On node 0, zone DMA: 36 pages in unavailable ranges May 7 23:43:56.340665 kernel: psci: probing for conduit method from ACPI. May 7 23:43:56.342766 kernel: psci: PSCIv1.1 detected in firmware. May 7 23:43:56.342774 kernel: psci: Using standard PSCI v0.2 function IDs May 7 23:43:56.342781 kernel: psci: MIGRATE_INFO_TYPE not supported. May 7 23:43:56.342793 kernel: psci: SMC Calling Convention v1.4 May 7 23:43:56.342800 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x0 -> Node 0 May 7 23:43:56.342806 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x1 -> Node 0 May 7 23:43:56.342813 kernel: percpu: Embedded 31 pages/cpu s86696 r8192 d32088 u126976 May 7 23:43:56.342820 kernel: pcpu-alloc: s86696 r8192 d32088 u126976 alloc=31*4096 May 7 23:43:56.342827 kernel: pcpu-alloc: [0] 0 [0] 1 May 7 23:43:56.342833 kernel: Detected PIPT I-cache on CPU0 May 7 23:43:56.342840 kernel: CPU features: detected: GIC system register CPU interface May 7 23:43:56.342847 kernel: CPU features: detected: Hardware dirty bit management May 7 23:43:56.342854 kernel: CPU features: detected: Spectre-BHB May 7 23:43:56.342860 kernel: CPU features: kernel page table isolation forced ON by KASLR May 7 23:43:56.342869 kernel: CPU features: detected: Kernel page table isolation (KPTI) May 7 23:43:56.342875 kernel: CPU features: detected: ARM erratum 1418040 May 7 23:43:56.342882 kernel: CPU features: detected: ARM erratum 1542419 (kernel portion) May 7 23:43:56.342889 kernel: CPU features: detected: SSBS not fully self-synchronizing May 7 23:43:56.342895 kernel: alternatives: applying boot alternatives May 7 23:43:56.342903 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=82f9441f083668f7b43f8fe99c3dc9ee441b8a3ef2f63ecd1e548de4dde5b207 May 7 23:43:56.342911 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. May 7 23:43:56.342917 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) May 7 23:43:56.342924 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) May 7 23:43:56.342931 kernel: Fallback order for Node 0: 0 May 7 23:43:56.342937 kernel: Built 1 zonelists, mobility grouping on. Total pages: 1032156 May 7 23:43:56.342946 kernel: Policy zone: Normal May 7 23:43:56.342952 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off May 7 23:43:56.342959 kernel: software IO TLB: area num 2. May 7 23:43:56.342965 kernel: software IO TLB: mapped [mem 0x0000000036540000-0x000000003a540000] (64MB) May 7 23:43:56.342972 kernel: Memory: 3983588K/4194160K available (10368K kernel code, 2186K rwdata, 8100K rodata, 38336K init, 897K bss, 210572K reserved, 0K cma-reserved) May 7 23:43:56.342979 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 May 7 23:43:56.342986 kernel: rcu: Preemptible hierarchical RCU implementation. May 7 23:43:56.342993 kernel: rcu: RCU event tracing is enabled. May 7 23:43:56.343000 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. May 7 23:43:56.343007 kernel: Trampoline variant of Tasks RCU enabled. May 7 23:43:56.343014 kernel: Tracing variant of Tasks RCU enabled. May 7 23:43:56.343022 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. May 7 23:43:56.343029 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 May 7 23:43:56.343035 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 May 7 23:43:56.343042 kernel: GICv3: 960 SPIs implemented May 7 23:43:56.343048 kernel: GICv3: 0 Extended SPIs implemented May 7 23:43:56.343055 kernel: Root IRQ handler: gic_handle_irq May 7 23:43:56.343061 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI May 7 23:43:56.343068 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000effee000 May 7 23:43:56.343074 kernel: ITS: No ITS available, not enabling LPIs May 7 23:43:56.343081 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. May 7 23:43:56.343088 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 7 23:43:56.343094 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). May 7 23:43:56.343103 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns May 7 23:43:56.343113 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns May 7 23:43:56.343120 kernel: Console: colour dummy device 80x25 May 7 23:43:56.343127 kernel: printk: console [tty1] enabled May 7 23:43:56.343134 kernel: ACPI: Core revision 20230628 May 7 23:43:56.343141 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) May 7 23:43:56.343148 kernel: pid_max: default: 32768 minimum: 301 May 7 23:43:56.343155 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity May 7 23:43:56.343162 kernel: landlock: Up and running. May 7 23:43:56.343170 kernel: SELinux: Initializing. May 7 23:43:56.343177 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 7 23:43:56.343184 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) May 7 23:43:56.343191 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. May 7 23:43:56.343197 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. May 7 23:43:56.343204 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3a8030, hints 0xe, misc 0x31e1 May 7 23:43:56.343212 kernel: Hyper-V: Host Build 10.0.22477.1619-1-0 May 7 23:43:56.343225 kernel: Hyper-V: enabling crash_kexec_post_notifiers May 7 23:43:56.343232 kernel: rcu: Hierarchical SRCU implementation. May 7 23:43:56.343240 kernel: rcu: Max phase no-delay instances is 400. May 7 23:43:56.343248 kernel: Remapping and enabling EFI services. May 7 23:43:56.343255 kernel: smp: Bringing up secondary CPUs ... May 7 23:43:56.343263 kernel: Detected PIPT I-cache on CPU1 May 7 23:43:56.343270 kernel: GICv3: CPU1: found redistributor 1 region 1:0x00000000f000e000 May 7 23:43:56.343277 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 May 7 23:43:56.343284 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] May 7 23:43:56.343291 kernel: smp: Brought up 1 node, 2 CPUs May 7 23:43:56.343300 kernel: SMP: Total of 2 processors activated. May 7 23:43:56.343308 kernel: CPU features: detected: 32-bit EL0 Support May 7 23:43:56.343315 kernel: CPU features: detected: Instruction cache invalidation not required for I/D coherence May 7 23:43:56.343322 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence May 7 23:43:56.343329 kernel: CPU features: detected: CRC32 instructions May 7 23:43:56.343337 kernel: CPU features: detected: RCpc load-acquire (LDAPR) May 7 23:43:56.343344 kernel: CPU features: detected: LSE atomic instructions May 7 23:43:56.343351 kernel: CPU features: detected: Privileged Access Never May 7 23:43:56.343358 kernel: CPU: All CPU(s) started at EL1 May 7 23:43:56.343366 kernel: alternatives: applying system-wide alternatives May 7 23:43:56.343374 kernel: devtmpfs: initialized May 7 23:43:56.343381 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns May 7 23:43:56.343388 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) May 7 23:43:56.343395 kernel: pinctrl core: initialized pinctrl subsystem May 7 23:43:56.343402 kernel: SMBIOS 3.1.0 present. May 7 23:43:56.343409 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 09/28/2024 May 7 23:43:56.343417 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family May 7 23:43:56.343424 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations May 7 23:43:56.343433 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations May 7 23:43:56.343440 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations May 7 23:43:56.343447 kernel: audit: initializing netlink subsys (disabled) May 7 23:43:56.343454 kernel: audit: type=2000 audit(0.047:1): state=initialized audit_enabled=0 res=1 May 7 23:43:56.343462 kernel: thermal_sys: Registered thermal governor 'step_wise' May 7 23:43:56.343469 kernel: cpuidle: using governor menu May 7 23:43:56.343476 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. May 7 23:43:56.343483 kernel: ASID allocator initialised with 32768 entries May 7 23:43:56.343490 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 May 7 23:43:56.343499 kernel: Serial: AMBA PL011 UART driver May 7 23:43:56.343506 kernel: Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL May 7 23:43:56.343513 kernel: Modules: 0 pages in range for non-PLT usage May 7 23:43:56.343521 kernel: Modules: 509264 pages in range for PLT usage May 7 23:43:56.343528 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages May 7 23:43:56.343535 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page May 7 23:43:56.343543 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages May 7 23:43:56.343550 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page May 7 23:43:56.343557 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages May 7 23:43:56.343566 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page May 7 23:43:56.343574 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages May 7 23:43:56.343581 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page May 7 23:43:56.343588 kernel: ACPI: Added _OSI(Module Device) May 7 23:43:56.343595 kernel: ACPI: Added _OSI(Processor Device) May 7 23:43:56.343602 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) May 7 23:43:56.343609 kernel: ACPI: Added _OSI(Processor Aggregator Device) May 7 23:43:56.343616 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded May 7 23:43:56.343623 kernel: ACPI: Interpreter enabled May 7 23:43:56.343632 kernel: ACPI: Using GIC for interrupt routing May 7 23:43:56.343640 kernel: ARMH0011:00: ttyAMA0 at MMIO 0xeffec000 (irq = 12, base_baud = 0) is a SBSA May 7 23:43:56.343647 kernel: printk: console [ttyAMA0] enabled May 7 23:43:56.343654 kernel: printk: bootconsole [pl11] disabled May 7 23:43:56.343661 kernel: ARMH0011:01: ttyAMA1 at MMIO 0xeffeb000 (irq = 13, base_baud = 0) is a SBSA May 7 23:43:56.343682 kernel: iommu: Default domain type: Translated May 7 23:43:56.343690 kernel: iommu: DMA domain TLB invalidation policy: strict mode May 7 23:43:56.343697 kernel: efivars: Registered efivars operations May 7 23:43:56.343704 kernel: vgaarb: loaded May 7 23:43:56.343713 kernel: clocksource: Switched to clocksource arch_sys_counter May 7 23:43:56.343720 kernel: VFS: Disk quotas dquot_6.6.0 May 7 23:43:56.343727 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) May 7 23:43:56.343734 kernel: pnp: PnP ACPI init May 7 23:43:56.343741 kernel: pnp: PnP ACPI: found 0 devices May 7 23:43:56.343748 kernel: NET: Registered PF_INET protocol family May 7 23:43:56.343756 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) May 7 23:43:56.343763 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) May 7 23:43:56.343770 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) May 7 23:43:56.343779 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) May 7 23:43:56.343786 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) May 7 23:43:56.343794 kernel: TCP: Hash tables configured (established 32768 bind 32768) May 7 23:43:56.343801 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) May 7 23:43:56.343808 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) May 7 23:43:56.343815 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family May 7 23:43:56.343822 kernel: PCI: CLS 0 bytes, default 64 May 7 23:43:56.343830 kernel: kvm [1]: HYP mode not available May 7 23:43:56.343837 kernel: Initialise system trusted keyrings May 7 23:43:56.343845 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 May 7 23:43:56.343852 kernel: Key type asymmetric registered May 7 23:43:56.343859 kernel: Asymmetric key parser 'x509' registered May 7 23:43:56.343866 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) May 7 23:43:56.343874 kernel: io scheduler mq-deadline registered May 7 23:43:56.343881 kernel: io scheduler kyber registered May 7 23:43:56.343888 kernel: io scheduler bfq registered May 7 23:43:56.343895 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled May 7 23:43:56.343902 kernel: thunder_xcv, ver 1.0 May 7 23:43:56.343910 kernel: thunder_bgx, ver 1.0 May 7 23:43:56.343917 kernel: nicpf, ver 1.0 May 7 23:43:56.343924 kernel: nicvf, ver 1.0 May 7 23:43:56.344079 kernel: rtc-efi rtc-efi.0: registered as rtc0 May 7 23:43:56.344153 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-05-07T23:43:55 UTC (1746661435) May 7 23:43:56.344164 kernel: efifb: probing for efifb May 7 23:43:56.344171 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k May 7 23:43:56.344179 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 May 7 23:43:56.344188 kernel: efifb: scrolling: redraw May 7 23:43:56.344195 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 May 7 23:43:56.344203 kernel: Console: switching to colour frame buffer device 128x48 May 7 23:43:56.344210 kernel: fb0: EFI VGA frame buffer device May 7 23:43:56.344218 kernel: SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping .... May 7 23:43:56.344225 kernel: hid: raw HID events driver (C) Jiri Kosina May 7 23:43:56.344232 kernel: No ACPI PMU IRQ for CPU0 May 7 23:43:56.344239 kernel: No ACPI PMU IRQ for CPU1 May 7 23:43:56.344246 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 1 counters available May 7 23:43:56.344255 kernel: watchdog: Delayed init of the lockup detector failed: -19 May 7 23:43:56.344262 kernel: watchdog: Hard watchdog permanently disabled May 7 23:43:56.344270 kernel: NET: Registered PF_INET6 protocol family May 7 23:43:56.344277 kernel: Segment Routing with IPv6 May 7 23:43:56.344284 kernel: In-situ OAM (IOAM) with IPv6 May 7 23:43:56.344291 kernel: NET: Registered PF_PACKET protocol family May 7 23:43:56.344298 kernel: Key type dns_resolver registered May 7 23:43:56.344305 kernel: registered taskstats version 1 May 7 23:43:56.344312 kernel: Loading compiled-in X.509 certificates May 7 23:43:56.344321 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.88-flatcar: f45666b1b2057b901dda15e57012558a26abdeb0' May 7 23:43:56.344328 kernel: Key type .fscrypt registered May 7 23:43:56.344335 kernel: Key type fscrypt-provisioning registered May 7 23:43:56.344343 kernel: ima: No TPM chip found, activating TPM-bypass! May 7 23:43:56.344350 kernel: ima: Allocated hash algorithm: sha1 May 7 23:43:56.344357 kernel: ima: No architecture policies found May 7 23:43:56.344365 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) May 7 23:43:56.344372 kernel: clk: Disabling unused clocks May 7 23:43:56.344379 kernel: Freeing unused kernel memory: 38336K May 7 23:43:56.344388 kernel: Run /init as init process May 7 23:43:56.344395 kernel: with arguments: May 7 23:43:56.344402 kernel: /init May 7 23:43:56.344409 kernel: with environment: May 7 23:43:56.344416 kernel: HOME=/ May 7 23:43:56.344423 kernel: TERM=linux May 7 23:43:56.344430 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a May 7 23:43:56.344439 systemd[1]: Successfully made /usr/ read-only. May 7 23:43:56.344451 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) May 7 23:43:56.344460 systemd[1]: Detected virtualization microsoft. May 7 23:43:56.344467 systemd[1]: Detected architecture arm64. May 7 23:43:56.344475 systemd[1]: Running in initrd. May 7 23:43:56.344483 systemd[1]: No hostname configured, using default hostname. May 7 23:43:56.344491 systemd[1]: Hostname set to . May 7 23:43:56.344498 systemd[1]: Initializing machine ID from random generator. May 7 23:43:56.344506 systemd[1]: Queued start job for default target initrd.target. May 7 23:43:56.344516 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 7 23:43:56.344524 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 7 23:43:56.344533 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... May 7 23:43:56.344541 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 7 23:43:56.344549 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... May 7 23:43:56.344557 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... May 7 23:43:56.344566 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... May 7 23:43:56.344576 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... May 7 23:43:56.344584 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 7 23:43:56.344592 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 7 23:43:56.344600 systemd[1]: Reached target paths.target - Path Units. May 7 23:43:56.344607 systemd[1]: Reached target slices.target - Slice Units. May 7 23:43:56.344615 systemd[1]: Reached target swap.target - Swaps. May 7 23:43:56.344623 systemd[1]: Reached target timers.target - Timer Units. May 7 23:43:56.344631 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. May 7 23:43:56.344640 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 7 23:43:56.344648 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). May 7 23:43:56.344656 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. May 7 23:43:56.344664 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 7 23:43:56.349600 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 7 23:43:56.349612 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 7 23:43:56.349620 systemd[1]: Reached target sockets.target - Socket Units. May 7 23:43:56.349628 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... May 7 23:43:56.349637 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 7 23:43:56.349652 systemd[1]: Finished network-cleanup.service - Network Cleanup. May 7 23:43:56.349660 systemd[1]: Starting systemd-fsck-usr.service... May 7 23:43:56.349677 systemd[1]: Starting systemd-journald.service - Journal Service... May 7 23:43:56.349686 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 7 23:43:56.349733 systemd-journald[218]: Collecting audit messages is disabled. May 7 23:43:56.349757 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 7 23:43:56.349767 systemd-journald[218]: Journal started May 7 23:43:56.349785 systemd-journald[218]: Runtime Journal (/run/log/journal/75f84258634248b2b65478da2d8d7e9d) is 8M, max 78.5M, 70.5M free. May 7 23:43:56.386893 systemd-modules-load[220]: Inserted module 'overlay' May 7 23:43:56.405939 systemd[1]: Started systemd-journald.service - Journal Service. May 7 23:43:56.409834 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. May 7 23:43:56.419028 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 7 23:43:56.444684 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. May 7 23:43:56.455133 systemd-modules-load[220]: Inserted module 'br_netfilter' May 7 23:43:56.461438 kernel: Bridge firewalling registered May 7 23:43:56.455208 systemd[1]: Finished systemd-fsck-usr.service. May 7 23:43:56.466222 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 7 23:43:56.476336 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 7 23:43:56.499972 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 7 23:43:56.509859 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 7 23:43:56.538858 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 7 23:43:56.548843 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 7 23:43:56.563947 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 7 23:43:56.581113 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 7 23:43:56.595471 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 7 23:43:56.608467 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 7 23:43:56.634136 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... May 7 23:43:56.649335 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 7 23:43:56.663081 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 7 23:43:56.673394 dracut-cmdline[251]: dracut-dracut-053 May 7 23:43:56.687873 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 7 23:43:56.706779 dracut-cmdline[251]: Using kernel command line parameters: rd.driver.pre=btrfs 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=82f9441f083668f7b43f8fe99c3dc9ee441b8a3ef2f63ecd1e548de4dde5b207 May 7 23:43:56.744261 systemd-resolved[256]: Positive Trust Anchors: May 7 23:43:56.744277 systemd-resolved[256]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 7 23:43:56.744308 systemd-resolved[256]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test May 7 23:43:56.746543 systemd-resolved[256]: Defaulting to hostname 'linux'. May 7 23:43:56.748988 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 7 23:43:56.755760 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 7 23:43:56.856757 kernel: SCSI subsystem initialized May 7 23:43:56.865699 kernel: Loading iSCSI transport class v2.0-870. May 7 23:43:56.875683 kernel: iscsi: registered transport (tcp) May 7 23:43:56.894226 kernel: iscsi: registered transport (qla4xxx) May 7 23:43:56.894290 kernel: QLogic iSCSI HBA Driver May 7 23:43:56.928310 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. May 7 23:43:56.945072 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... May 7 23:43:56.975897 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. May 7 23:43:56.975940 kernel: device-mapper: uevent: version 1.0.3 May 7 23:43:56.982447 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com May 7 23:43:57.031701 kernel: raid6: neonx8 gen() 15754 MB/s May 7 23:43:57.051684 kernel: raid6: neonx4 gen() 15817 MB/s May 7 23:43:57.071680 kernel: raid6: neonx2 gen() 13211 MB/s May 7 23:43:57.093686 kernel: raid6: neonx1 gen() 10500 MB/s May 7 23:43:57.113707 kernel: raid6: int64x8 gen() 6793 MB/s May 7 23:43:57.133690 kernel: raid6: int64x4 gen() 7350 MB/s May 7 23:43:57.154688 kernel: raid6: int64x2 gen() 6112 MB/s May 7 23:43:57.178217 kernel: raid6: int64x1 gen() 5061 MB/s May 7 23:43:57.178252 kernel: raid6: using algorithm neonx4 gen() 15817 MB/s May 7 23:43:57.202187 kernel: raid6: .... xor() 12455 MB/s, rmw enabled May 7 23:43:57.202209 kernel: raid6: using neon recovery algorithm May 7 23:43:57.211684 kernel: xor: measuring software checksum speed May 7 23:43:57.218292 kernel: 8regs : 20363 MB/sec May 7 23:43:57.218306 kernel: 32regs : 21664 MB/sec May 7 23:43:57.221608 kernel: arm64_neon : 27974 MB/sec May 7 23:43:57.225678 kernel: xor: using function: arm64_neon (27974 MB/sec) May 7 23:43:57.275697 kernel: Btrfs loaded, zoned=no, fsverity=no May 7 23:43:57.284889 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. May 7 23:43:57.301817 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 7 23:43:57.327236 systemd-udevd[439]: Using default interface naming scheme 'v255'. May 7 23:43:57.333039 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 7 23:43:57.353877 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... May 7 23:43:57.370237 dracut-pre-trigger[441]: rd.md=0: removing MD RAID activation May 7 23:43:57.396040 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. May 7 23:43:57.414098 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 7 23:43:57.453753 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 7 23:43:57.472872 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... May 7 23:43:57.488478 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. May 7 23:43:57.504141 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. May 7 23:43:57.523893 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 7 23:43:57.539352 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 7 23:43:57.558878 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... May 7 23:43:57.576800 kernel: hv_vmbus: Vmbus version:5.3 May 7 23:43:57.593350 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. May 7 23:43:57.615318 kernel: hv_vmbus: registering driver hyperv_keyboard May 7 23:43:57.615348 kernel: hv_vmbus: registering driver hid_hyperv May 7 23:43:57.615363 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input0 May 7 23:43:57.628362 kernel: hv_vmbus: registering driver hv_netvsc May 7 23:43:57.628411 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on May 7 23:43:57.640461 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 7 23:43:57.651280 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/MSFT1000:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input1 May 7 23:43:57.651302 kernel: hv_vmbus: registering driver hv_storvsc May 7 23:43:57.640636 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 7 23:43:57.695056 kernel: pps_core: LinuxPPS API ver. 1 registered May 7 23:43:57.695081 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti May 7 23:43:57.695092 kernel: PTP clock support registered May 7 23:43:57.695101 kernel: scsi host1: storvsc_host_t May 7 23:43:57.695255 kernel: scsi host0: storvsc_host_t May 7 23:43:57.695344 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 May 7 23:43:57.685567 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 7 23:43:57.715343 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 May 7 23:43:57.708780 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 7 23:43:57.708999 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 7 23:43:57.726986 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... May 7 23:43:57.748058 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 7 23:43:57.718675 kernel: hv_utils: Registering HyperV Utility Driver May 7 23:43:57.733691 kernel: hv_vmbus: registering driver hv_utils May 7 23:43:57.733709 kernel: hv_utils: Shutdown IC version 3.2 May 7 23:43:57.733717 kernel: hv_utils: Heartbeat IC version 3.0 May 7 23:43:57.733727 kernel: hv_utils: TimeSync IC version 4.0 May 7 23:43:57.733735 systemd-journald[218]: Time jumped backwards, rotating. May 7 23:43:57.733771 kernel: hv_netvsc 002248b4-da6c-0022-48b4-da6c002248b4 eth0: VF slot 1 added May 7 23:43:57.779293 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 7 23:43:57.779383 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 7 23:43:57.768863 kernel: hv_vmbus: registering driver hv_pci May 7 23:43:57.768888 kernel: hv_pci 0fcb75cd-93de-42d5-9bea-027cfcb52338: PCI VMBus probing: Using version 0x10004 May 7 23:43:58.092574 kernel: sr 0:0:0:2: [sr0] scsi-1 drive May 7 23:43:58.092768 kernel: hv_pci 0fcb75cd-93de-42d5-9bea-027cfcb52338: PCI host bridge to bus 93de:00 May 7 23:43:58.092869 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) May 7 23:43:58.092982 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 May 7 23:43:58.092992 kernel: pci_bus 93de:00: root bus resource [mem 0xfc0000000-0xfc00fffff window] May 7 23:43:58.093099 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks May 7 23:43:58.093189 kernel: pci_bus 93de:00: No busn resource found for root bus, will use [bus 00-ff] May 7 23:43:58.093270 kernel: sd 0:0:0:0: [sda] Write Protect is off May 7 23:43:58.093356 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 May 7 23:43:58.093466 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA May 7 23:43:58.093558 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 May 7 23:43:58.093648 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 May 7 23:43:58.093657 kernel: pci 93de:00:02.0: [15b3:1018] type 00 class 0x020000 May 7 23:43:58.093770 kernel: pci 93de:00:02.0: reg 0x10: [mem 0xfc0000000-0xfc00fffff 64bit pref] May 7 23:43:58.093864 kernel: sd 0:0:0:0: [sda] Attached SCSI disk May 7 23:43:58.093949 kernel: pci 93de:00:02.0: enabling Extended Tags May 7 23:43:58.094041 kernel: pci 93de:00:02.0: 0.000 Gb/s available PCIe bandwidth, limited by Unknown x0 link at 93de:00:02.0 (capable of 126.016 Gb/s with 8.0 GT/s PCIe x16 link) May 7 23:43:58.094139 kernel: pci_bus 93de:00: busn_res: [bus 00-ff] end is updated to 00 May 7 23:43:58.094218 kernel: pci 93de:00:02.0: BAR 0: assigned [mem 0xfc0000000-0xfc00fffff 64bit pref] May 7 23:43:57.724904 systemd-resolved[256]: Clock change detected. Flushing caches. May 7 23:43:57.739629 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. May 7 23:43:57.755976 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 7 23:43:57.810460 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 7 23:43:58.018700 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... May 7 23:43:58.076848 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 7 23:43:58.152788 kernel: mlx5_core 93de:00:02.0: enabling device (0000 -> 0002) May 7 23:43:58.455618 kernel: mlx5_core 93de:00:02.0: firmware version: 16.31.2424 May 7 23:43:58.455967 kernel: hv_netvsc 002248b4-da6c-0022-48b4-da6c002248b4 eth0: VF registering: eth1 May 7 23:43:58.456076 kernel: mlx5_core 93de:00:02.0 eth1: joined to eth0 May 7 23:43:58.456173 kernel: mlx5_core 93de:00:02.0: MLX5E: StrdRq(1) RqSz(8) StrdSz(2048) RxCqeCmprss(0 basic) May 7 23:43:58.468458 kernel: mlx5_core 93de:00:02.0 enP37854s1: renamed from eth1 May 7 23:43:58.743441 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/sda6 scanned by (udev-worker) (507) May 7 23:43:58.771481 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. May 7 23:43:58.795478 kernel: BTRFS: device fsid a4d66dad-2d34-4ed0-87a7-f6519531b08f devid 1 transid 42 /dev/sda3 scanned by (udev-worker) (498) May 7 23:43:58.809747 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. May 7 23:43:58.816573 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. May 7 23:43:58.839411 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. May 7 23:43:58.855671 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. May 7 23:43:58.879526 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... May 7 23:43:58.905454 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 May 7 23:43:58.913418 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 May 7 23:43:59.921971 disk-uuid[606]: The operation has completed successfully. May 7 23:43:59.927033 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 May 7 23:43:59.985034 systemd[1]: disk-uuid.service: Deactivated successfully. May 7 23:43:59.985144 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. May 7 23:44:00.033537 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... May 7 23:44:00.046107 sh[693]: Success May 7 23:44:00.076451 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" May 7 23:44:00.288347 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. May 7 23:44:00.308546 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... May 7 23:44:00.314056 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. May 7 23:44:00.350458 kernel: BTRFS info (device dm-0): first mount of filesystem a4d66dad-2d34-4ed0-87a7-f6519531b08f May 7 23:44:00.350512 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm May 7 23:44:00.358161 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead May 7 23:44:00.363506 kernel: BTRFS info (device dm-0): disabling log replay at mount time May 7 23:44:00.368066 kernel: BTRFS info (device dm-0): using free space tree May 7 23:44:00.769353 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. May 7 23:44:00.774498 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. May 7 23:44:00.794604 systemd[1]: Starting ignition-setup.service - Ignition (setup)... May 7 23:44:00.802576 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... May 7 23:44:00.844191 kernel: BTRFS info (device sda6): first mount of filesystem 28594331-30e6-4c58-8ddc-9d8448a320bb May 7 23:44:00.844244 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm May 7 23:44:00.848868 kernel: BTRFS info (device sda6): using free space tree May 7 23:44:00.885474 kernel: BTRFS info (device sda6): auto enabling async discard May 7 23:44:00.897428 kernel: BTRFS info (device sda6): last unmount of filesystem 28594331-30e6-4c58-8ddc-9d8448a320bb May 7 23:44:00.904513 systemd[1]: Finished ignition-setup.service - Ignition (setup). May 7 23:44:00.919609 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... May 7 23:44:00.926252 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 7 23:44:00.941582 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 7 23:44:00.983910 systemd-networkd[874]: lo: Link UP May 7 23:44:00.983925 systemd-networkd[874]: lo: Gained carrier May 7 23:44:00.985574 systemd-networkd[874]: Enumeration completed May 7 23:44:00.985735 systemd[1]: Started systemd-networkd.service - Network Configuration. May 7 23:44:00.988074 systemd-networkd[874]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 7 23:44:00.988078 systemd-networkd[874]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 7 23:44:00.998086 systemd[1]: Reached target network.target - Network. May 7 23:44:01.064429 kernel: mlx5_core 93de:00:02.0 enP37854s1: Link up May 7 23:44:01.148431 kernel: hv_netvsc 002248b4-da6c-0022-48b4-da6c002248b4 eth0: Data path switched to VF: enP37854s1 May 7 23:44:01.148779 systemd-networkd[874]: enP37854s1: Link UP May 7 23:44:01.149001 systemd-networkd[874]: eth0: Link UP May 7 23:44:01.149170 systemd-networkd[874]: eth0: Gained carrier May 7 23:44:01.149180 systemd-networkd[874]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 7 23:44:01.162677 systemd-networkd[874]: enP37854s1: Gained carrier May 7 23:44:01.189471 systemd-networkd[874]: eth0: DHCPv4 address 10.200.20.36/24, gateway 10.200.20.1 acquired from 168.63.129.16 May 7 23:44:01.665731 ignition[869]: Ignition 2.20.0 May 7 23:44:01.665742 ignition[869]: Stage: fetch-offline May 7 23:44:01.669768 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). May 7 23:44:01.665782 ignition[869]: no configs at "/usr/lib/ignition/base.d" May 7 23:44:01.665790 ignition[869]: no config dir at "/usr/lib/ignition/base.platform.d/azure" May 7 23:44:01.665882 ignition[869]: parsed url from cmdline: "" May 7 23:44:01.665885 ignition[869]: no config URL provided May 7 23:44:01.665890 ignition[869]: reading system config file "/usr/lib/ignition/user.ign" May 7 23:44:01.704626 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... May 7 23:44:01.665897 ignition[869]: no config at "/usr/lib/ignition/user.ign" May 7 23:44:01.665902 ignition[869]: failed to fetch config: resource requires networking May 7 23:44:01.666092 ignition[869]: Ignition finished successfully May 7 23:44:01.726943 ignition[885]: Ignition 2.20.0 May 7 23:44:01.726950 ignition[885]: Stage: fetch May 7 23:44:01.727137 ignition[885]: no configs at "/usr/lib/ignition/base.d" May 7 23:44:01.727146 ignition[885]: no config dir at "/usr/lib/ignition/base.platform.d/azure" May 7 23:44:01.727287 ignition[885]: parsed url from cmdline: "" May 7 23:44:01.727292 ignition[885]: no config URL provided May 7 23:44:01.727296 ignition[885]: reading system config file "/usr/lib/ignition/user.ign" May 7 23:44:01.727307 ignition[885]: no config at "/usr/lib/ignition/user.ign" May 7 23:44:01.727334 ignition[885]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 May 7 23:44:01.812905 ignition[885]: GET result: OK May 7 23:44:01.812977 ignition[885]: config has been read from IMDS userdata May 7 23:44:01.812996 ignition[885]: parsing config with SHA512: fc80d3b6560a1c29dc2c884cd2cbd3f9b1926519c25c38e0cd426ae7901bfda972ce2dd5b43de98eb07c3edd141f1b957f4480cf265fc977ee5122d7ced98916 May 7 23:44:01.817712 unknown[885]: fetched base config from "system" May 7 23:44:01.817942 ignition[885]: fetch: fetch complete May 7 23:44:01.817721 unknown[885]: fetched base config from "system" May 7 23:44:01.817948 ignition[885]: fetch: fetch passed May 7 23:44:01.817727 unknown[885]: fetched user config from "azure" May 7 23:44:01.817997 ignition[885]: Ignition finished successfully May 7 23:44:01.820063 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). May 7 23:44:01.839140 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... May 7 23:44:01.869240 ignition[892]: Ignition 2.20.0 May 7 23:44:01.872800 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). May 7 23:44:01.869249 ignition[892]: Stage: kargs May 7 23:44:01.869463 ignition[892]: no configs at "/usr/lib/ignition/base.d" May 7 23:44:01.869474 ignition[892]: no config dir at "/usr/lib/ignition/base.platform.d/azure" May 7 23:44:01.892556 systemd[1]: Starting ignition-disks.service - Ignition (disks)... May 7 23:44:01.870120 ignition[892]: kargs: kargs passed May 7 23:44:01.912358 systemd[1]: Finished ignition-disks.service - Ignition (disks). May 7 23:44:01.870162 ignition[892]: Ignition finished successfully May 7 23:44:01.921269 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. May 7 23:44:01.905332 ignition[898]: Ignition 2.20.0 May 7 23:44:01.933482 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. May 7 23:44:01.905340 ignition[898]: Stage: disks May 7 23:44:01.947356 systemd[1]: Reached target local-fs.target - Local File Systems. May 7 23:44:01.905590 ignition[898]: no configs at "/usr/lib/ignition/base.d" May 7 23:44:01.957257 systemd[1]: Reached target sysinit.target - System Initialization. May 7 23:44:01.905600 ignition[898]: no config dir at "/usr/lib/ignition/base.platform.d/azure" May 7 23:44:01.969779 systemd[1]: Reached target basic.target - Basic System. May 7 23:44:01.906777 ignition[898]: disks: disks passed May 7 23:44:02.007838 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... May 7 23:44:01.906839 ignition[898]: Ignition finished successfully May 7 23:44:02.073027 systemd-fsck[906]: ROOT: clean, 14/7326000 files, 477710/7359488 blocks May 7 23:44:02.082761 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. May 7 23:44:02.102592 systemd[1]: Mounting sysroot.mount - /sysroot... May 7 23:44:02.166416 kernel: EXT4-fs (sda9): mounted filesystem f291ddc8-664e-45dc-bbf9-8344dca1a297 r/w with ordered data mode. Quota mode: none. May 7 23:44:02.167800 systemd[1]: Mounted sysroot.mount - /sysroot. May 7 23:44:02.172469 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. May 7 23:44:02.216482 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 7 23:44:02.224552 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... May 7 23:44:02.237166 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... May 7 23:44:02.264202 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/sda6 scanned by mount (917) May 7 23:44:02.250933 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). May 7 23:44:02.293571 kernel: BTRFS info (device sda6): first mount of filesystem 28594331-30e6-4c58-8ddc-9d8448a320bb May 7 23:44:02.293593 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm May 7 23:44:02.293603 kernel: BTRFS info (device sda6): using free space tree May 7 23:44:02.250974 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. May 7 23:44:02.284158 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. May 7 23:44:02.315692 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... May 7 23:44:02.334619 kernel: BTRFS info (device sda6): auto enabling async discard May 7 23:44:02.329748 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 7 23:44:02.526590 systemd-networkd[874]: eth0: Gained IPv6LL May 7 23:44:02.718567 systemd-networkd[874]: enP37854s1: Gained IPv6LL May 7 23:44:02.822444 coreos-metadata[919]: May 07 23:44:02.822 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 May 7 23:44:02.833086 coreos-metadata[919]: May 07 23:44:02.833 INFO Fetch successful May 7 23:44:02.833086 coreos-metadata[919]: May 07 23:44:02.833 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 May 7 23:44:02.851848 coreos-metadata[919]: May 07 23:44:02.841 INFO Fetch successful May 7 23:44:02.857689 coreos-metadata[919]: May 07 23:44:02.856 INFO wrote hostname ci-4230.1.1-n-f8d7d4446e to /sysroot/etc/hostname May 7 23:44:02.859197 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. May 7 23:44:03.003464 initrd-setup-root[947]: cut: /sysroot/etc/passwd: No such file or directory May 7 23:44:03.040658 initrd-setup-root[954]: cut: /sysroot/etc/group: No such file or directory May 7 23:44:03.063880 initrd-setup-root[961]: cut: /sysroot/etc/shadow: No such file or directory May 7 23:44:03.105969 initrd-setup-root[968]: cut: /sysroot/etc/gshadow: No such file or directory May 7 23:44:03.852255 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. May 7 23:44:03.870568 systemd[1]: Starting ignition-mount.service - Ignition (mount)... May 7 23:44:03.884615 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... May 7 23:44:03.903947 kernel: BTRFS info (device sda6): last unmount of filesystem 28594331-30e6-4c58-8ddc-9d8448a320bb May 7 23:44:03.897741 systemd[1]: sysroot-oem.mount: Deactivated successfully. May 7 23:44:03.927167 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. May 7 23:44:03.941493 ignition[1042]: INFO : Ignition 2.20.0 May 7 23:44:03.941493 ignition[1042]: INFO : Stage: mount May 7 23:44:03.951550 ignition[1042]: INFO : no configs at "/usr/lib/ignition/base.d" May 7 23:44:03.951550 ignition[1042]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" May 7 23:44:03.951550 ignition[1042]: INFO : mount: mount passed May 7 23:44:03.951550 ignition[1042]: INFO : Ignition finished successfully May 7 23:44:03.951770 systemd[1]: Finished ignition-mount.service - Ignition (mount). May 7 23:44:03.975600 systemd[1]: Starting ignition-files.service - Ignition (files)... May 7 23:44:03.996623 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... May 7 23:44:04.027343 kernel: BTRFS: device label OEM devid 1 transid 17 /dev/sda6 scanned by mount (1052) May 7 23:44:04.027392 kernel: BTRFS info (device sda6): first mount of filesystem 28594331-30e6-4c58-8ddc-9d8448a320bb May 7 23:44:04.033518 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm May 7 23:44:04.037923 kernel: BTRFS info (device sda6): using free space tree May 7 23:44:04.044420 kernel: BTRFS info (device sda6): auto enabling async discard May 7 23:44:04.046256 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. May 7 23:44:04.071782 ignition[1069]: INFO : Ignition 2.20.0 May 7 23:44:04.071782 ignition[1069]: INFO : Stage: files May 7 23:44:04.080106 ignition[1069]: INFO : no configs at "/usr/lib/ignition/base.d" May 7 23:44:04.080106 ignition[1069]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" May 7 23:44:04.080106 ignition[1069]: DEBUG : files: compiled without relabeling support, skipping May 7 23:44:04.105318 ignition[1069]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" May 7 23:44:04.105318 ignition[1069]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" May 7 23:44:04.166324 ignition[1069]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" May 7 23:44:04.174787 ignition[1069]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" May 7 23:44:04.174787 ignition[1069]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" May 7 23:44:04.166744 unknown[1069]: wrote ssh authorized keys file for user: core May 7 23:44:04.196609 ignition[1069]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" May 7 23:44:04.196609 ignition[1069]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" May 7 23:44:04.196609 ignition[1069]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" May 7 23:44:04.196609 ignition[1069]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" May 7 23:44:04.196609 ignition[1069]: INFO : files: files passed May 7 23:44:04.196609 ignition[1069]: INFO : Ignition finished successfully May 7 23:44:04.191825 systemd[1]: Finished ignition-files.service - Ignition (files). May 7 23:44:04.236188 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... May 7 23:44:04.253573 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... May 7 23:44:04.267031 systemd[1]: ignition-quench.service: Deactivated successfully. May 7 23:44:04.268686 systemd[1]: Finished ignition-quench.service - Ignition (record completion). May 7 23:44:04.308330 initrd-setup-root-after-ignition[1102]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 7 23:44:04.301690 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. May 7 23:44:04.340696 initrd-setup-root-after-ignition[1098]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory May 7 23:44:04.340696 initrd-setup-root-after-ignition[1098]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory May 7 23:44:04.315736 systemd[1]: Reached target ignition-complete.target - Ignition Complete. May 7 23:44:04.359598 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... May 7 23:44:04.396108 systemd[1]: initrd-parse-etc.service: Deactivated successfully. May 7 23:44:04.398319 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. May 7 23:44:04.408699 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. May 7 23:44:04.420896 systemd[1]: Reached target initrd.target - Initrd Default Target. May 7 23:44:04.431730 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. May 7 23:44:04.446641 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... May 7 23:44:04.466183 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 7 23:44:04.485675 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... May 7 23:44:04.506482 systemd[1]: initrd-cleanup.service: Deactivated successfully. May 7 23:44:04.508422 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. May 7 23:44:04.520022 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. May 7 23:44:04.530746 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. May 7 23:44:04.543426 systemd[1]: Stopped target timers.target - Timer Units. May 7 23:44:04.554128 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. May 7 23:44:04.554199 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. May 7 23:44:04.569825 systemd[1]: Stopped target initrd.target - Initrd Default Target. May 7 23:44:04.581708 systemd[1]: Stopped target basic.target - Basic System. May 7 23:44:04.591565 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. May 7 23:44:04.602041 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. May 7 23:44:04.613968 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. May 7 23:44:04.626020 systemd[1]: Stopped target remote-fs.target - Remote File Systems. May 7 23:44:04.636985 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. May 7 23:44:04.648660 systemd[1]: Stopped target sysinit.target - System Initialization. May 7 23:44:04.660469 systemd[1]: Stopped target local-fs.target - Local File Systems. May 7 23:44:04.670853 systemd[1]: Stopped target swap.target - Swaps. May 7 23:44:04.680213 systemd[1]: dracut-pre-mount.service: Deactivated successfully. May 7 23:44:04.680314 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. May 7 23:44:04.694997 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. May 7 23:44:04.706430 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 7 23:44:04.718215 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. May 7 23:44:04.724256 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 7 23:44:04.730816 systemd[1]: dracut-initqueue.service: Deactivated successfully. May 7 23:44:04.730881 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. May 7 23:44:04.748957 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. May 7 23:44:04.749007 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. May 7 23:44:04.760647 systemd[1]: ignition-files.service: Deactivated successfully. May 7 23:44:04.760691 systemd[1]: Stopped ignition-files.service - Ignition (files). May 7 23:44:04.771467 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. May 7 23:44:04.771515 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. May 7 23:44:04.806627 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... May 7 23:44:04.827068 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... May 7 23:44:04.846026 ignition[1123]: INFO : Ignition 2.20.0 May 7 23:44:04.846026 ignition[1123]: INFO : Stage: umount May 7 23:44:04.846026 ignition[1123]: INFO : no configs at "/usr/lib/ignition/base.d" May 7 23:44:04.846026 ignition[1123]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" May 7 23:44:04.839563 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. May 7 23:44:04.889623 ignition[1123]: INFO : umount: umount passed May 7 23:44:04.889623 ignition[1123]: INFO : Ignition finished successfully May 7 23:44:04.839631 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. May 7 23:44:04.853359 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. May 7 23:44:04.853429 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. May 7 23:44:04.868165 systemd[1]: sysroot-boot.mount: Deactivated successfully. May 7 23:44:04.868773 systemd[1]: ignition-mount.service: Deactivated successfully. May 7 23:44:04.870443 systemd[1]: Stopped ignition-mount.service - Ignition (mount). May 7 23:44:04.884646 systemd[1]: sysroot-boot.service: Deactivated successfully. May 7 23:44:04.884728 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. May 7 23:44:04.895279 systemd[1]: ignition-disks.service: Deactivated successfully. May 7 23:44:04.895376 systemd[1]: Stopped ignition-disks.service - Ignition (disks). May 7 23:44:04.904069 systemd[1]: ignition-kargs.service: Deactivated successfully. May 7 23:44:04.904118 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). May 7 23:44:04.913864 systemd[1]: ignition-fetch.service: Deactivated successfully. May 7 23:44:04.913908 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). May 7 23:44:04.925329 systemd[1]: Stopped target network.target - Network. May 7 23:44:04.934895 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. May 7 23:44:04.934967 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). May 7 23:44:04.946666 systemd[1]: Stopped target paths.target - Path Units. May 7 23:44:04.955850 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. May 7 23:44:04.960621 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 7 23:44:04.967497 systemd[1]: Stopped target slices.target - Slice Units. May 7 23:44:04.977823 systemd[1]: Stopped target sockets.target - Socket Units. May 7 23:44:04.987894 systemd[1]: iscsid.socket: Deactivated successfully. May 7 23:44:04.987948 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. May 7 23:44:04.998067 systemd[1]: iscsiuio.socket: Deactivated successfully. May 7 23:44:04.998104 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. May 7 23:44:05.008332 systemd[1]: ignition-setup.service: Deactivated successfully. May 7 23:44:05.008384 systemd[1]: Stopped ignition-setup.service - Ignition (setup). May 7 23:44:05.018624 systemd[1]: ignition-setup-pre.service: Deactivated successfully. May 7 23:44:05.018675 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. May 7 23:44:05.029681 systemd[1]: initrd-setup-root.service: Deactivated successfully. May 7 23:44:05.029731 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. May 7 23:44:05.040996 systemd[1]: Stopping systemd-networkd.service - Network Configuration... May 7 23:44:05.052614 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... May 7 23:44:05.073845 systemd[1]: systemd-networkd.service: Deactivated successfully. May 7 23:44:05.073987 systemd[1]: Stopped systemd-networkd.service - Network Configuration. May 7 23:44:05.096924 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. May 7 23:44:05.097205 systemd[1]: systemd-resolved.service: Deactivated successfully. May 7 23:44:05.097427 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. May 7 23:44:05.117010 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. May 7 23:44:05.117604 systemd[1]: systemd-networkd.socket: Deactivated successfully. May 7 23:44:05.117663 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. May 7 23:44:05.141554 systemd[1]: Stopping network-cleanup.service - Network Cleanup... May 7 23:44:05.151578 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. May 7 23:44:05.151668 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. May 7 23:44:05.163083 systemd[1]: systemd-sysctl.service: Deactivated successfully. May 7 23:44:05.163134 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. May 7 23:44:05.178773 systemd[1]: systemd-modules-load.service: Deactivated successfully. May 7 23:44:05.364484 kernel: hv_netvsc 002248b4-da6c-0022-48b4-da6c002248b4 eth0: Data path switched from VF: enP37854s1 May 7 23:44:05.178818 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. May 7 23:44:05.184763 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. May 7 23:44:05.184801 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. May 7 23:44:05.201576 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... May 7 23:44:05.218515 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. May 7 23:44:05.218590 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. May 7 23:44:05.235772 systemd[1]: systemd-udevd.service: Deactivated successfully. May 7 23:44:05.236877 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. May 7 23:44:05.254609 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. May 7 23:44:05.254690 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. May 7 23:44:05.268703 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. May 7 23:44:05.268736 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. May 7 23:44:05.279974 systemd[1]: dracut-pre-udev.service: Deactivated successfully. May 7 23:44:05.280024 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. May 7 23:44:05.297025 systemd[1]: dracut-cmdline.service: Deactivated successfully. May 7 23:44:05.297076 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. May 7 23:44:05.313992 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. May 7 23:44:05.314057 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. May 7 23:44:05.351215 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... May 7 23:44:05.357568 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. May 7 23:44:05.357630 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 7 23:44:05.378768 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. May 7 23:44:05.378833 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 7 23:44:05.385859 systemd[1]: kmod-static-nodes.service: Deactivated successfully. May 7 23:44:05.385907 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. May 7 23:44:05.398030 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 7 23:44:05.398083 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 7 23:44:05.418354 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. May 7 23:44:05.418440 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. May 7 23:44:05.418830 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. May 7 23:44:05.420425 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. May 7 23:44:05.524568 systemd[1]: network-cleanup.service: Deactivated successfully. May 7 23:44:05.524708 systemd[1]: Stopped network-cleanup.service - Network Cleanup. May 7 23:44:05.535550 systemd[1]: Reached target initrd-switch-root.target - Switch Root. May 7 23:44:05.566671 systemd[1]: Starting initrd-switch-root.service - Switch Root... May 7 23:44:05.585925 systemd[1]: Switching root. May 7 23:44:05.669517 systemd-journald[218]: Journal stopped May 7 23:44:09.802309 systemd-journald[218]: Received SIGTERM from PID 1 (systemd). May 7 23:44:09.802332 kernel: SELinux: policy capability network_peer_controls=1 May 7 23:44:09.802342 kernel: SELinux: policy capability open_perms=1 May 7 23:44:09.802353 kernel: SELinux: policy capability extended_socket_class=1 May 7 23:44:09.802360 kernel: SELinux: policy capability always_check_network=0 May 7 23:44:09.802368 kernel: SELinux: policy capability cgroup_seclabel=1 May 7 23:44:09.802377 kernel: SELinux: policy capability nnp_nosuid_transition=1 May 7 23:44:09.802385 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 May 7 23:44:09.802393 kernel: SELinux: policy capability ioctl_skip_cloexec=0 May 7 23:44:09.802428 kernel: audit: type=1403 audit(1746661446.264:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 May 7 23:44:09.802440 systemd[1]: Successfully loaded SELinux policy in 142.924ms. May 7 23:44:09.802451 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 10.400ms. May 7 23:44:09.802461 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) May 7 23:44:09.802470 systemd[1]: Detected virtualization microsoft. May 7 23:44:09.802479 systemd[1]: Detected architecture arm64. May 7 23:44:09.802490 systemd[1]: Detected first boot. May 7 23:44:09.802500 systemd[1]: Hostname set to . May 7 23:44:09.802509 systemd[1]: Initializing machine ID from random generator. May 7 23:44:09.802519 zram_generator::config[1165]: No configuration found. May 7 23:44:09.802528 kernel: NET: Registered PF_VSOCK protocol family May 7 23:44:09.802696 kernel: mlx5_core 93de:00:02.0: poll_health:835:(pid 0): device's health compromised - reached miss count May 7 23:44:09.802715 systemd[1]: Populated /etc with preset unit settings. May 7 23:44:09.802725 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. May 7 23:44:09.802735 systemd[1]: initrd-switch-root.service: Deactivated successfully. May 7 23:44:09.802744 systemd[1]: Stopped initrd-switch-root.service - Switch Root. May 7 23:44:09.802753 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. May 7 23:44:09.802762 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. May 7 23:44:09.802772 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. May 7 23:44:09.802784 systemd[1]: Created slice system-getty.slice - Slice /system/getty. May 7 23:44:09.802793 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. May 7 23:44:09.802803 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. May 7 23:44:09.802812 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. May 7 23:44:09.802822 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. May 7 23:44:09.802831 systemd[1]: Created slice user.slice - User and Session Slice. May 7 23:44:09.802840 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. May 7 23:44:09.802849 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. May 7 23:44:09.802860 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. May 7 23:44:09.802869 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. May 7 23:44:09.802879 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. May 7 23:44:09.802889 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... May 7 23:44:09.802900 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... May 7 23:44:09.802910 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). May 7 23:44:09.802919 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. May 7 23:44:09.802929 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. May 7 23:44:09.802940 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. May 7 23:44:09.802949 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. May 7 23:44:09.802958 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. May 7 23:44:09.802968 systemd[1]: Reached target remote-fs.target - Remote File Systems. May 7 23:44:09.802977 systemd[1]: Reached target slices.target - Slice Units. May 7 23:44:09.802987 systemd[1]: Reached target swap.target - Swaps. May 7 23:44:09.802996 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. May 7 23:44:09.803006 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. May 7 23:44:09.803017 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. May 7 23:44:09.803027 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. May 7 23:44:09.803036 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. May 7 23:44:09.803046 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. May 7 23:44:09.803057 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. May 7 23:44:09.803066 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... May 7 23:44:09.803075 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... May 7 23:44:09.803085 systemd[1]: Mounting media.mount - External Media Directory... May 7 23:44:09.803094 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... May 7 23:44:09.803104 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... May 7 23:44:09.803113 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... May 7 23:44:09.803123 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). May 7 23:44:09.803134 systemd[1]: Reached target machines.target - Containers. May 7 23:44:09.803144 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... May 7 23:44:09.803153 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 7 23:44:09.803163 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... May 7 23:44:09.803172 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... May 7 23:44:09.803182 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 7 23:44:09.803192 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 7 23:44:09.803201 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 7 23:44:09.803211 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... May 7 23:44:09.803223 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 7 23:44:09.803238 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). May 7 23:44:09.803265 systemd[1]: systemd-fsck-root.service: Deactivated successfully. May 7 23:44:09.803276 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. May 7 23:44:09.803286 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. May 7 23:44:09.803296 systemd[1]: Stopped systemd-fsck-usr.service. May 7 23:44:09.803306 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). May 7 23:44:09.803315 kernel: fuse: init (API version 7.39) May 7 23:44:09.803326 kernel: loop: module loaded May 7 23:44:09.803335 systemd[1]: Starting systemd-journald.service - Journal Service... May 7 23:44:09.803344 kernel: ACPI: bus type drm_connector registered May 7 23:44:09.803353 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... May 7 23:44:09.803362 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... May 7 23:44:09.803391 systemd-journald[1269]: Collecting audit messages is disabled. May 7 23:44:09.803434 systemd-journald[1269]: Journal started May 7 23:44:09.803469 systemd-journald[1269]: Runtime Journal (/run/log/journal/2c4a4c87a8bd47c39e0f971495f52e43) is 8M, max 78.5M, 70.5M free. May 7 23:44:09.803512 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... May 7 23:44:08.913629 systemd[1]: Queued start job for default target multi-user.target. May 7 23:44:08.921203 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. May 7 23:44:08.921676 systemd[1]: systemd-journald.service: Deactivated successfully. May 7 23:44:08.922006 systemd[1]: systemd-journald.service: Consumed 3.031s CPU time. May 7 23:44:09.835013 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... May 7 23:44:09.851745 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... May 7 23:44:09.861575 systemd[1]: verity-setup.service: Deactivated successfully. May 7 23:44:09.861632 systemd[1]: Stopped verity-setup.service. May 7 23:44:09.879582 systemd[1]: Started systemd-journald.service - Journal Service. May 7 23:44:09.880350 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. May 7 23:44:09.886121 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. May 7 23:44:09.891997 systemd[1]: Mounted media.mount - External Media Directory. May 7 23:44:09.897303 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. May 7 23:44:09.903581 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. May 7 23:44:09.909893 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. May 7 23:44:09.915949 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. May 7 23:44:09.922655 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. May 7 23:44:09.929970 systemd[1]: modprobe@configfs.service: Deactivated successfully. May 7 23:44:09.930135 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. May 7 23:44:09.936999 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 7 23:44:09.937163 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 7 23:44:09.943867 systemd[1]: modprobe@drm.service: Deactivated successfully. May 7 23:44:09.944020 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 7 23:44:09.950886 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 7 23:44:09.951035 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 7 23:44:09.959679 systemd[1]: modprobe@fuse.service: Deactivated successfully. May 7 23:44:09.959848 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. May 7 23:44:09.968813 systemd[1]: modprobe@loop.service: Deactivated successfully. May 7 23:44:09.968972 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 7 23:44:09.976884 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. May 7 23:44:09.983797 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. May 7 23:44:09.990876 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. May 7 23:44:09.998076 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. May 7 23:44:10.012597 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. May 7 23:44:10.022246 systemd[1]: Reached target network-pre.target - Preparation for Network. May 7 23:44:10.033483 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... May 7 23:44:10.041533 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... May 7 23:44:10.047682 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). May 7 23:44:10.047720 systemd[1]: Reached target local-fs.target - Local File Systems. May 7 23:44:10.054094 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. May 7 23:44:10.063496 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... May 7 23:44:10.070950 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... May 7 23:44:10.077165 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 7 23:44:10.078757 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... May 7 23:44:10.086284 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... May 7 23:44:10.093296 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 7 23:44:10.094376 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... May 7 23:44:10.100619 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 7 23:44:10.101827 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... May 7 23:44:10.111610 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... May 7 23:44:10.120225 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... May 7 23:44:10.128559 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... May 7 23:44:10.142416 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. May 7 23:44:10.151040 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. May 7 23:44:10.153521 systemd-journald[1269]: Time spent on flushing to /var/log/journal/2c4a4c87a8bd47c39e0f971495f52e43 is 72.067ms for 893 entries. May 7 23:44:10.153521 systemd-journald[1269]: System Journal (/var/log/journal/2c4a4c87a8bd47c39e0f971495f52e43) is 11.8M, max 2.6G, 2.6G free. May 7 23:44:10.300356 systemd-journald[1269]: Received client request to flush runtime journal. May 7 23:44:10.300458 systemd-journald[1269]: /var/log/journal/2c4a4c87a8bd47c39e0f971495f52e43/system.journal: Realtime clock jumped backwards relative to last journal entry, rotating. May 7 23:44:10.300508 kernel: loop0: detected capacity change from 0 to 123192 May 7 23:44:10.300528 systemd-journald[1269]: Rotating system journal. May 7 23:44:10.163806 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. May 7 23:44:10.176442 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. May 7 23:44:10.190241 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. May 7 23:44:10.222791 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... May 7 23:44:10.229659 udevadm[1308]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. May 7 23:44:10.234253 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. May 7 23:44:10.302881 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. May 7 23:44:10.307271 systemd-tmpfiles[1307]: ACLs are not supported, ignoring. May 7 23:44:10.307282 systemd-tmpfiles[1307]: ACLs are not supported, ignoring. May 7 23:44:10.314574 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. May 7 23:44:10.327663 systemd[1]: Starting systemd-sysusers.service - Create System Users... May 7 23:44:10.343422 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. May 7 23:44:10.344655 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. May 7 23:44:10.424784 systemd[1]: Finished systemd-sysusers.service - Create System Users. May 7 23:44:10.439629 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... May 7 23:44:10.454269 systemd-tmpfiles[1326]: ACLs are not supported, ignoring. May 7 23:44:10.454286 systemd-tmpfiles[1326]: ACLs are not supported, ignoring. May 7 23:44:10.459444 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. May 7 23:44:10.571427 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher May 7 23:44:10.604433 kernel: loop1: detected capacity change from 0 to 28720 May 7 23:44:11.064440 kernel: loop2: detected capacity change from 0 to 113512 May 7 23:44:11.216543 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. May 7 23:44:11.228531 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... May 7 23:44:11.251235 systemd-udevd[1333]: Using default interface naming scheme 'v255'. May 7 23:44:11.343434 kernel: loop3: detected capacity change from 0 to 123192 May 7 23:44:11.353424 kernel: loop4: detected capacity change from 0 to 28720 May 7 23:44:11.362424 kernel: loop5: detected capacity change from 0 to 113512 May 7 23:44:11.366858 (sd-merge)[1335]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. May 7 23:44:11.367245 (sd-merge)[1335]: Merged extensions into '/usr'. May 7 23:44:11.371470 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. May 7 23:44:11.384566 systemd[1]: Starting ensure-sysext.service... May 7 23:44:11.391733 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... May 7 23:44:11.437326 systemd[1]: Reload requested from client PID 1337 ('systemctl') (unit ensure-sysext.service)... May 7 23:44:11.437340 systemd[1]: Reloading... May 7 23:44:11.455625 systemd-tmpfiles[1338]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. May 7 23:44:11.455829 systemd-tmpfiles[1338]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. May 7 23:44:11.456486 systemd-tmpfiles[1338]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. May 7 23:44:11.456696 systemd-tmpfiles[1338]: ACLs are not supported, ignoring. May 7 23:44:11.456740 systemd-tmpfiles[1338]: ACLs are not supported, ignoring. May 7 23:44:11.473841 systemd-tmpfiles[1338]: Detected autofs mount point /boot during canonicalization of boot. May 7 23:44:11.473852 systemd-tmpfiles[1338]: Skipping /boot May 7 23:44:11.486522 systemd-tmpfiles[1338]: Detected autofs mount point /boot during canonicalization of boot. May 7 23:44:11.486650 systemd-tmpfiles[1338]: Skipping /boot May 7 23:44:11.517592 zram_generator::config[1368]: No configuration found. May 7 23:44:11.701359 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. May 7 23:44:11.713413 kernel: hv_vmbus: registering driver hv_balloon May 7 23:44:11.713505 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 May 7 23:44:11.719908 kernel: hv_balloon: Memory hot add disabled on ARM64 May 7 23:44:11.724443 kernel: mousedev: PS/2 mouse device common for all mice May 7 23:44:11.777421 kernel: hv_vmbus: registering driver hyperv_fb May 7 23:44:11.777510 kernel: hyperv_fb: Synthvid Version major 3, minor 5 May 7 23:44:11.788427 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 May 7 23:44:11.800660 kernel: Console: switching to colour dummy device 80x25 May 7 23:44:11.801420 kernel: Console: switching to colour frame buffer device 128x48 May 7 23:44:11.819556 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. May 7 23:44:11.819857 systemd[1]: Reloading finished in 382 ms. May 7 23:44:11.832309 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. May 7 23:44:11.862462 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 42 scanned by (udev-worker) (1397) May 7 23:44:11.873549 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. May 7 23:44:11.916551 systemd[1]: Condition check resulted in dev-ptp_hyperv.device - /dev/ptp_hyperv being skipped. May 7 23:44:11.916877 systemd[1]: Finished ensure-sysext.service. May 7 23:44:11.939592 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 7 23:44:11.986576 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... May 7 23:44:11.993096 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. May 7 23:44:11.994221 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... May 7 23:44:12.002574 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... May 7 23:44:12.016559 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... May 7 23:44:12.026581 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... May 7 23:44:12.037025 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. May 7 23:44:12.037095 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). May 7 23:44:12.038191 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... May 7 23:44:12.047593 systemd[1]: Starting systemd-networkd.service - Network Configuration... May 7 23:44:12.064667 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... May 7 23:44:12.070314 systemd[1]: Reached target time-set.target - System Time Set. May 7 23:44:12.082561 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... May 7 23:44:12.090553 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 7 23:44:12.098217 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. May 7 23:44:12.100390 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. May 7 23:44:12.108617 systemd[1]: modprobe@drm.service: Deactivated successfully. May 7 23:44:12.108786 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. May 7 23:44:12.114907 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. May 7 23:44:12.115060 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. May 7 23:44:12.122785 systemd[1]: modprobe@loop.service: Deactivated successfully. May 7 23:44:12.122956 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. May 7 23:44:12.130570 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. May 7 23:44:12.167367 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. May 7 23:44:12.179390 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. May 7 23:44:12.191626 augenrules[1562]: No rules May 7 23:44:12.193736 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... May 7 23:44:12.200104 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). May 7 23:44:12.200171 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. May 7 23:44:12.201625 systemd[1]: Starting systemd-userdbd.service - User Database Manager... May 7 23:44:12.209435 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. May 7 23:44:12.216755 systemd[1]: audit-rules.service: Deactivated successfully. May 7 23:44:12.216936 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 7 23:44:12.222903 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. May 7 23:44:12.223073 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. May 7 23:44:12.231054 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. May 7 23:44:12.231440 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. May 7 23:44:12.246772 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... May 7 23:44:12.255599 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... May 7 23:44:12.267898 systemd[1]: Started systemd-userdbd.service - User Database Manager. May 7 23:44:12.316427 lvm[1574]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 7 23:44:12.359434 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. May 7 23:44:12.369332 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. May 7 23:44:12.384566 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... May 7 23:44:12.389207 lvm[1586]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. May 7 23:44:12.391749 systemd-resolved[1547]: Positive Trust Anchors: May 7 23:44:12.391764 systemd-resolved[1547]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d May 7 23:44:12.391795 systemd-resolved[1547]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test May 7 23:44:12.411496 systemd-resolved[1547]: Using system hostname 'ci-4230.1.1-n-f8d7d4446e'. May 7 23:44:12.413052 systemd[1]: Started systemd-resolved.service - Network Name Resolution. May 7 23:44:12.423444 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. May 7 23:44:12.432748 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. May 7 23:44:12.441523 systemd-networkd[1546]: lo: Link UP May 7 23:44:12.441531 systemd-networkd[1546]: lo: Gained carrier May 7 23:44:12.443513 systemd-networkd[1546]: Enumeration completed May 7 23:44:12.443826 systemd-networkd[1546]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 7 23:44:12.443829 systemd-networkd[1546]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 7 23:44:12.444065 systemd[1]: Started systemd-networkd.service - Network Configuration. May 7 23:44:12.450206 systemd[1]: Reached target network.target - Network. May 7 23:44:12.462583 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... May 7 23:44:12.472659 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... May 7 23:44:12.480280 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. May 7 23:44:12.530439 kernel: mlx5_core 93de:00:02.0 enP37854s1: Link up May 7 23:44:12.575471 kernel: hv_netvsc 002248b4-da6c-0022-48b4-da6c002248b4 eth0: Data path switched to VF: enP37854s1 May 7 23:44:12.576308 systemd-networkd[1546]: enP37854s1: Link UP May 7 23:44:12.576429 systemd-networkd[1546]: eth0: Link UP May 7 23:44:12.576432 systemd-networkd[1546]: eth0: Gained carrier May 7 23:44:12.576448 systemd-networkd[1546]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 7 23:44:12.578222 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. May 7 23:44:12.586976 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. May 7 23:44:12.595100 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). May 7 23:44:12.597093 systemd-networkd[1546]: enP37854s1: Gained carrier May 7 23:44:12.605455 systemd-networkd[1546]: eth0: DHCPv4 address 10.200.20.36/24, gateway 10.200.20.1 acquired from 168.63.129.16 May 7 23:44:13.918571 systemd-networkd[1546]: eth0: Gained IPv6LL May 7 23:44:13.920916 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. May 7 23:44:13.928913 systemd[1]: Reached target network-online.target - Network is Online. May 7 23:44:14.622553 systemd-networkd[1546]: enP37854s1: Gained IPv6LL May 7 23:44:15.179429 ldconfig[1300]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. May 7 23:44:15.197213 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. May 7 23:44:15.209550 systemd[1]: Starting systemd-update-done.service - Update is Completed... May 7 23:44:15.222376 systemd[1]: Finished systemd-update-done.service - Update is Completed. May 7 23:44:15.230707 systemd[1]: Reached target sysinit.target - System Initialization. May 7 23:44:15.236358 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. May 7 23:44:15.243166 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. May 7 23:44:15.250491 systemd[1]: Started logrotate.timer - Daily rotation of log files. May 7 23:44:15.256258 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. May 7 23:44:15.263361 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. May 7 23:44:15.270189 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). May 7 23:44:15.270224 systemd[1]: Reached target paths.target - Path Units. May 7 23:44:15.275092 systemd[1]: Reached target timers.target - Timer Units. May 7 23:44:15.293987 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. May 7 23:44:15.301339 systemd[1]: Starting docker.socket - Docker Socket for the API... May 7 23:44:15.308532 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). May 7 23:44:15.315519 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). May 7 23:44:15.322421 systemd[1]: Reached target ssh-access.target - SSH Access Available. May 7 23:44:15.331003 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. May 7 23:44:15.336879 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. May 7 23:44:15.344052 systemd[1]: Listening on docker.socket - Docker Socket for the API. May 7 23:44:15.349886 systemd[1]: Reached target sockets.target - Socket Units. May 7 23:44:15.355061 systemd[1]: Reached target basic.target - Basic System. May 7 23:44:15.360692 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. May 7 23:44:15.360722 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. May 7 23:44:15.368498 systemd[1]: Starting chronyd.service - NTP client/server... May 7 23:44:15.376751 systemd[1]: Starting containerd.service - containerd container runtime... May 7 23:44:15.387314 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... May 7 23:44:15.401061 systemd[1]: Starting dbus.service - D-Bus System Message Bus... May 7 23:44:15.407527 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... May 7 23:44:15.414240 (chronyd)[1602]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS May 7 23:44:15.415599 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... May 7 23:44:15.421529 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). May 7 23:44:15.421569 systemd[1]: hv_fcopy_daemon.service - Hyper-V FCOPY daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_fcopy). May 7 23:44:15.432375 jq[1609]: false May 7 23:44:15.434601 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. May 7 23:44:15.441978 chronyd[1614]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) May 7 23:44:15.443105 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). May 7 23:44:15.444745 KVP[1611]: KVP starting; pid is:1611 May 7 23:44:15.445338 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... May 7 23:44:15.454059 KVP[1611]: KVP LIC Version: 3.1 May 7 23:44:15.454485 kernel: hv_utils: KVP IC version 4.0 May 7 23:44:15.461984 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... May 7 23:44:15.473572 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... May 7 23:44:15.480176 extend-filesystems[1610]: Found loop3 May 7 23:44:15.480176 extend-filesystems[1610]: Found loop4 May 7 23:44:15.480176 extend-filesystems[1610]: Found loop5 May 7 23:44:15.480176 extend-filesystems[1610]: Found sda May 7 23:44:15.480176 extend-filesystems[1610]: Found sda1 May 7 23:44:15.480176 extend-filesystems[1610]: Found sda2 May 7 23:44:15.480176 extend-filesystems[1610]: Found sda3 May 7 23:44:15.480176 extend-filesystems[1610]: Found usr May 7 23:44:15.480176 extend-filesystems[1610]: Found sda4 May 7 23:44:15.480176 extend-filesystems[1610]: Found sda6 May 7 23:44:15.480176 extend-filesystems[1610]: Found sda7 May 7 23:44:15.480176 extend-filesystems[1610]: Found sda9 May 7 23:44:15.480176 extend-filesystems[1610]: Checking size of /dev/sda9 May 7 23:44:15.639008 extend-filesystems[1610]: Old size kept for /dev/sda9 May 7 23:44:15.639008 extend-filesystems[1610]: Found sr0 May 7 23:44:15.649867 coreos-metadata[1604]: May 07 23:44:15.637 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 May 7 23:44:15.508241 chronyd[1614]: Timezone right/UTC failed leap second check, ignoring May 7 23:44:15.486595 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... May 7 23:44:15.508681 chronyd[1614]: Loaded seccomp filter (level 2) May 7 23:44:15.508573 systemd[1]: Starting systemd-logind.service - User Login Management... May 7 23:44:15.529035 dbus-daemon[1605]: [system] SELinux support is enabled May 7 23:44:15.516415 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). May 7 23:44:15.517714 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. May 7 23:44:15.651059 update_engine[1630]: I20250507 23:44:15.606175 1630 main.cc:92] Flatcar Update Engine starting May 7 23:44:15.651059 update_engine[1630]: I20250507 23:44:15.609330 1630 update_check_scheduler.cc:74] Next update check in 3m40s May 7 23:44:15.534039 systemd[1]: Starting update-engine.service - Update Engine... May 7 23:44:15.651339 jq[1635]: true May 7 23:44:15.547511 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... May 7 23:44:15.561928 systemd[1]: Started dbus.service - D-Bus System Message Bus. May 7 23:44:15.578840 systemd[1]: Started chronyd.service - NTP client/server. May 7 23:44:15.598809 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. May 7 23:44:15.600444 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. May 7 23:44:15.600739 systemd[1]: extend-filesystems.service: Deactivated successfully. May 7 23:44:15.604956 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. May 7 23:44:15.620269 systemd[1]: motdgen.service: Deactivated successfully. May 7 23:44:15.620476 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. May 7 23:44:15.631928 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. May 7 23:44:15.644801 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. May 7 23:44:15.644996 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. May 7 23:44:15.657904 coreos-metadata[1604]: May 07 23:44:15.657 INFO Fetch successful May 7 23:44:15.658056 coreos-metadata[1604]: May 07 23:44:15.658 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 May 7 23:44:15.662239 coreos-metadata[1604]: May 07 23:44:15.662 INFO Fetch successful May 7 23:44:15.663450 coreos-metadata[1604]: May 07 23:44:15.663 INFO Fetching http://168.63.129.16/machine/007fcf24-7967-4be7-82a6-7bdefcd4ec7d/9fd60f18%2Db7eb%2D40b2%2D8be0%2Dcce6e7f4ab1f.%5Fci%2D4230.1.1%2Dn%2Df8d7d4446e?comp=config&type=sharedConfig&incarnation=1: Attempt #1 May 7 23:44:15.664274 systemd-logind[1628]: New seat seat0. May 7 23:44:15.666721 systemd-logind[1628]: Watching system buttons on /dev/input/event1 (AT Translated Set 2 keyboard) May 7 23:44:15.667684 coreos-metadata[1604]: May 07 23:44:15.667 INFO Fetch successful May 7 23:44:15.667824 coreos-metadata[1604]: May 07 23:44:15.667 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 May 7 23:44:15.668568 systemd[1]: Started systemd-logind.service - User Login Management. May 7 23:44:15.686639 coreos-metadata[1604]: May 07 23:44:15.686 INFO Fetch successful May 7 23:44:15.688839 (ntainerd)[1659]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR May 7 23:44:15.691751 jq[1647]: true May 7 23:44:15.702811 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). May 7 23:44:15.702873 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. May 7 23:44:15.706206 dbus-daemon[1605]: [system] Successfully activated service 'org.freedesktop.systemd1' May 7 23:44:15.716486 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). May 7 23:44:15.716512 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. May 7 23:44:15.731316 systemd[1]: Started update-engine.service - Update Engine. May 7 23:44:15.747636 systemd[1]: Started locksmithd.service - Cluster reboot manager. May 7 23:44:15.758968 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. May 7 23:44:15.778900 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. May 7 23:44:15.788541 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 42 scanned by (udev-worker) (1652) May 7 23:44:15.844082 bash[1695]: Updated "/home/core/.ssh/authorized_keys" May 7 23:44:15.846333 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. May 7 23:44:15.857833 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. May 7 23:44:15.949508 locksmithd[1694]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" May 7 23:44:16.112438 containerd[1659]: time="2025-05-07T23:44:16.110687680Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 May 7 23:44:16.159840 containerd[1659]: time="2025-05-07T23:44:16.159791240Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 May 7 23:44:16.161295 containerd[1659]: time="2025-05-07T23:44:16.161265880Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.88-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 May 7 23:44:16.161392 containerd[1659]: time="2025-05-07T23:44:16.161380000Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 May 7 23:44:16.161474 containerd[1659]: time="2025-05-07T23:44:16.161460120Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 May 7 23:44:16.161680 containerd[1659]: time="2025-05-07T23:44:16.161664080Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 May 7 23:44:16.161742 containerd[1659]: time="2025-05-07T23:44:16.161730480Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 May 7 23:44:16.161850 containerd[1659]: time="2025-05-07T23:44:16.161834560Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 May 7 23:44:16.161904 containerd[1659]: time="2025-05-07T23:44:16.161891520Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 May 7 23:44:16.162158 containerd[1659]: time="2025-05-07T23:44:16.162138200Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." 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" type=io.containerd.snapshotter.v1 May 7 23:44:16.162231 containerd[1659]: time="2025-05-07T23:44:16.162217960Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 May 7 23:44:16.162290 containerd[1659]: time="2025-05-07T23:44:16.162276520Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 May 7 23:44:16.162335 containerd[1659]: time="2025-05-07T23:44:16.162324640Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 May 7 23:44:16.162495 containerd[1659]: time="2025-05-07T23:44:16.162479160Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 May 7 23:44:16.162758 containerd[1659]: time="2025-05-07T23:44:16.162740920Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 May 7 23:44:16.162946 containerd[1659]: time="2025-05-07T23:44:16.162929360Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 May 7 23:44:16.162999 containerd[1659]: time="2025-05-07T23:44:16.162988000Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 May 7 23:44:16.163118 containerd[1659]: time="2025-05-07T23:44:16.163103520Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 May 7 23:44:16.163243 containerd[1659]: time="2025-05-07T23:44:16.163228280Z" level=info msg="metadata content store policy set" policy=shared May 7 23:44:16.182578 containerd[1659]: time="2025-05-07T23:44:16.182533600Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 May 7 23:44:16.182745 containerd[1659]: time="2025-05-07T23:44:16.182731520Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 May 7 23:44:16.182842 containerd[1659]: time="2025-05-07T23:44:16.182830040Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 May 7 23:44:16.182922 containerd[1659]: time="2025-05-07T23:44:16.182910160Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 May 7 23:44:16.183018 containerd[1659]: time="2025-05-07T23:44:16.183002880Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 May 7 23:44:16.183272 containerd[1659]: time="2025-05-07T23:44:16.183255120Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 May 7 23:44:16.184038 containerd[1659]: time="2025-05-07T23:44:16.184015440Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 May 7 23:44:16.185318 containerd[1659]: time="2025-05-07T23:44:16.185292000Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 May 7 23:44:16.185429 containerd[1659]: time="2025-05-07T23:44:16.185391760Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 May 7 23:44:16.185501 containerd[1659]: time="2025-05-07T23:44:16.185484160Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 May 7 23:44:16.185559 containerd[1659]: time="2025-05-07T23:44:16.185544200Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 May 7 23:44:16.185614 containerd[1659]: time="2025-05-07T23:44:16.185602760Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 May 7 23:44:16.185691 containerd[1659]: time="2025-05-07T23:44:16.185679400Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 May 7 23:44:16.185771 containerd[1659]: time="2025-05-07T23:44:16.185758440Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 May 7 23:44:16.185847 containerd[1659]: time="2025-05-07T23:44:16.185834080Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 May 7 23:44:16.185913 containerd[1659]: time="2025-05-07T23:44:16.185892720Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 May 7 23:44:16.185973 containerd[1659]: time="2025-05-07T23:44:16.185961960Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 May 7 23:44:16.186039 containerd[1659]: time="2025-05-07T23:44:16.186028080Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 May 7 23:44:16.186123 containerd[1659]: time="2025-05-07T23:44:16.186110760Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 May 7 23:44:16.186200 containerd[1659]: time="2025-05-07T23:44:16.186185880Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 May 7 23:44:16.186267 containerd[1659]: time="2025-05-07T23:44:16.186255600Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 May 7 23:44:16.186455 containerd[1659]: time="2025-05-07T23:44:16.186344080Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 May 7 23:44:16.187531 containerd[1659]: time="2025-05-07T23:44:16.187514000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 May 7 23:44:16.187633 containerd[1659]: time="2025-05-07T23:44:16.187619080Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 May 7 23:44:16.187698 containerd[1659]: time="2025-05-07T23:44:16.187686000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 May 7 23:44:16.187839 containerd[1659]: time="2025-05-07T23:44:16.187743200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 May 7 23:44:16.187839 containerd[1659]: time="2025-05-07T23:44:16.187777520Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 May 7 23:44:16.187839 containerd[1659]: time="2025-05-07T23:44:16.187796240Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 May 7 23:44:16.187839 containerd[1659]: time="2025-05-07T23:44:16.187809200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 May 7 23:44:16.187839 containerd[1659]: time="2025-05-07T23:44:16.187821800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 May 7 23:44:16.188027 containerd[1659]: time="2025-05-07T23:44:16.187955320Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 May 7 23:44:16.188027 containerd[1659]: time="2025-05-07T23:44:16.187977400Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 May 7 23:44:16.188027 containerd[1659]: time="2025-05-07T23:44:16.188003000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 May 7 23:44:16.188178 containerd[1659]: time="2025-05-07T23:44:16.188016360Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 May 7 23:44:16.188178 containerd[1659]: time="2025-05-07T23:44:16.188117680Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 May 7 23:44:16.188248 containerd[1659]: time="2025-05-07T23:44:16.188167800Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 May 7 23:44:16.188390 containerd[1659]: time="2025-05-07T23:44:16.188291040Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 May 7 23:44:16.188390 containerd[1659]: time="2025-05-07T23:44:16.188317680Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 May 7 23:44:16.188390 containerd[1659]: time="2025-05-07T23:44:16.188333200Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 May 7 23:44:16.188390 containerd[1659]: time="2025-05-07T23:44:16.188343280Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 May 7 23:44:16.188390 containerd[1659]: time="2025-05-07T23:44:16.188356080Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 May 7 23:44:16.188390 containerd[1659]: time="2025-05-07T23:44:16.188365680Z" level=info msg="NRI interface is disabled by configuration." May 7 23:44:16.188390 containerd[1659]: time="2025-05-07T23:44:16.188375680Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 May 7 23:44:16.189010 containerd[1659]: time="2025-05-07T23:44:16.188893040Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" May 7 23:44:16.189010 containerd[1659]: time="2025-05-07T23:44:16.188955520Z" level=info msg="Connect containerd service" May 7 23:44:16.189311 containerd[1659]: time="2025-05-07T23:44:16.188987400Z" level=info msg="using legacy CRI server" May 7 23:44:16.189311 containerd[1659]: time="2025-05-07T23:44:16.189166000Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" May 7 23:44:16.189407 containerd[1659]: time="2025-05-07T23:44:16.189297600Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" May 7 23:44:16.190253 containerd[1659]: time="2025-05-07T23:44:16.190208840Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" May 7 23:44:16.190552 containerd[1659]: time="2025-05-07T23:44:16.190431240Z" level=info msg="Start subscribing containerd event" May 7 23:44:16.190552 containerd[1659]: time="2025-05-07T23:44:16.190487880Z" level=info msg="Start recovering state" May 7 23:44:16.190760 containerd[1659]: time="2025-05-07T23:44:16.190690160Z" level=info msg="Start event monitor" May 7 23:44:16.190760 containerd[1659]: time="2025-05-07T23:44:16.190709000Z" level=info msg="Start snapshots syncer" May 7 23:44:16.190760 containerd[1659]: time="2025-05-07T23:44:16.190719000Z" level=info msg="Start cni network conf syncer for default" May 7 23:44:16.190760 containerd[1659]: time="2025-05-07T23:44:16.190728880Z" level=info msg="Start streaming server" May 7 23:44:16.191329 containerd[1659]: time="2025-05-07T23:44:16.191238200Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc May 7 23:44:16.191329 containerd[1659]: time="2025-05-07T23:44:16.191299400Z" level=info msg=serving... address=/run/containerd/containerd.sock May 7 23:44:16.191580 systemd[1]: Started containerd.service - containerd container runtime. May 7 23:44:16.198270 sshd_keygen[1636]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 May 7 23:44:16.204504 containerd[1659]: time="2025-05-07T23:44:16.204428320Z" level=info msg="containerd successfully booted in 0.097204s" May 7 23:44:16.219488 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. May 7 23:44:16.232616 systemd[1]: Starting issuegen.service - Generate /run/issue... May 7 23:44:16.239633 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... May 7 23:44:16.256114 systemd[1]: issuegen.service: Deactivated successfully. May 7 23:44:16.258439 systemd[1]: Finished issuegen.service - Generate /run/issue. May 7 23:44:16.272242 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... May 7 23:44:16.279244 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. May 7 23:44:16.293886 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. May 7 23:44:16.307755 systemd[1]: Started getty@tty1.service - Getty on tty1. May 7 23:44:16.314499 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. May 7 23:44:16.323499 systemd[1]: Reached target getty.target - Login Prompts. May 7 23:44:16.329003 systemd[1]: Reached target multi-user.target - Multi-User System. May 7 23:44:16.338461 systemd[1]: Startup finished in 677ms (kernel) + 10.438s (initrd) + 10.215s (userspace) = 21.331s. May 7 23:44:16.531753 login[1777]: pam_lastlog(login:session): file /var/log/lastlog is locked/read, retrying May 7 23:44:16.532151 login[1776]: pam_unix(login:session): session opened for user core(uid=500) by core(uid=0) May 7 23:44:16.544812 systemd-logind[1628]: New session 1 of user core. May 7 23:44:16.546267 systemd[1]: Created slice user-500.slice - User Slice of UID 500. May 7 23:44:16.552681 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... May 7 23:44:16.563245 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. May 7 23:44:16.570019 systemd[1]: Starting user@500.service - User Manager for UID 500... May 7 23:44:16.572632 (systemd)[1784]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) May 7 23:44:16.576023 systemd-logind[1628]: New session c1 of user core. May 7 23:44:16.760980 systemd[1784]: Queued start job for default target default.target. May 7 23:44:16.766259 systemd[1784]: Created slice app.slice - User Application Slice. May 7 23:44:16.766280 systemd[1784]: Reached target paths.target - Paths. May 7 23:44:16.766314 systemd[1784]: Reached target timers.target - Timers. May 7 23:44:16.767513 systemd[1784]: Starting dbus.socket - D-Bus User Message Bus Socket... May 7 23:44:16.776356 systemd[1784]: Listening on dbus.socket - D-Bus User Message Bus Socket. May 7 23:44:16.776438 systemd[1784]: Reached target sockets.target - Sockets. May 7 23:44:16.776481 systemd[1784]: Reached target basic.target - Basic System. May 7 23:44:16.776508 systemd[1784]: Reached target default.target - Main User Target. May 7 23:44:16.776534 systemd[1784]: Startup finished in 193ms. May 7 23:44:16.776710 systemd[1]: Started user@500.service - User Manager for UID 500. May 7 23:44:16.782622 systemd[1]: Started session-1.scope - Session 1 of User core. May 7 23:44:17.537265 login[1777]: pam_unix(login:session): session opened for user core(uid=500) by core(uid=0) May 7 23:44:17.542074 systemd-logind[1628]: New session 2 of user core. May 7 23:44:17.549548 systemd[1]: Started session-2.scope - Session 2 of User core. May 7 23:44:17.835581 waagent[1773]: 2025-05-07T23:44:17.835432Z INFO Daemon Daemon Azure Linux Agent Version: 2.9.1.1 May 7 23:44:17.841137 waagent[1773]: 2025-05-07T23:44:17.841078Z INFO Daemon Daemon OS: flatcar 4230.1.1 May 7 23:44:17.845633 waagent[1773]: 2025-05-07T23:44:17.845586Z INFO Daemon Daemon Python: 3.11.11 May 7 23:44:17.850145 waagent[1773]: 2025-05-07T23:44:17.850095Z INFO Daemon Daemon Run daemon May 7 23:44:17.853990 waagent[1773]: 2025-05-07T23:44:17.853947Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4230.1.1' May 7 23:44:17.863111 waagent[1773]: 2025-05-07T23:44:17.863058Z INFO Daemon Daemon Using waagent for provisioning May 7 23:44:17.868660 waagent[1773]: 2025-05-07T23:44:17.868615Z INFO Daemon Daemon Activate resource disk May 7 23:44:17.873357 waagent[1773]: 2025-05-07T23:44:17.873312Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb May 7 23:44:17.886782 waagent[1773]: 2025-05-07T23:44:17.886717Z INFO Daemon Daemon Found device: None May 7 23:44:17.891717 waagent[1773]: 2025-05-07T23:44:17.891658Z ERROR Daemon Daemon Failed to mount resource disk [ResourceDiskError] unable to detect disk topology May 7 23:44:17.901003 waagent[1773]: 2025-05-07T23:44:17.900947Z ERROR Daemon Daemon Event: name=WALinuxAgent, op=ActivateResourceDisk, message=[ResourceDiskError] unable to detect disk topology, duration=0 May 7 23:44:17.912644 waagent[1773]: 2025-05-07T23:44:17.912590Z INFO Daemon Daemon Clean protocol and wireserver endpoint May 7 23:44:17.919833 waagent[1773]: 2025-05-07T23:44:17.919778Z INFO Daemon Daemon Running default provisioning handler May 7 23:44:17.931435 waagent[1773]: 2025-05-07T23:44:17.930888Z INFO Daemon Daemon Unable to get cloud-init enabled status from systemctl: Command '['systemctl', 'is-enabled', 'cloud-init-local.service']' returned non-zero exit status 4. May 7 23:44:17.944521 waagent[1773]: 2025-05-07T23:44:17.944461Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' May 7 23:44:17.953798 waagent[1773]: 2025-05-07T23:44:17.953748Z INFO Daemon Daemon cloud-init is enabled: False May 7 23:44:17.959095 waagent[1773]: 2025-05-07T23:44:17.959047Z INFO Daemon Daemon Copying ovf-env.xml May 7 23:44:18.050225 waagent[1773]: 2025-05-07T23:44:18.050084Z INFO Daemon Daemon Successfully mounted dvd May 7 23:44:18.065167 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. May 7 23:44:18.067261 waagent[1773]: 2025-05-07T23:44:18.067181Z INFO Daemon Daemon Detect protocol endpoint May 7 23:44:18.072392 waagent[1773]: 2025-05-07T23:44:18.072327Z INFO Daemon Daemon Clean protocol and wireserver endpoint May 7 23:44:18.077933 waagent[1773]: 2025-05-07T23:44:18.077881Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler May 7 23:44:18.084800 waagent[1773]: 2025-05-07T23:44:18.084740Z INFO Daemon Daemon Test for route to 168.63.129.16 May 7 23:44:18.090062 waagent[1773]: 2025-05-07T23:44:18.089977Z INFO Daemon Daemon Route to 168.63.129.16 exists May 7 23:44:18.094995 waagent[1773]: 2025-05-07T23:44:18.094954Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 May 7 23:44:18.128899 waagent[1773]: 2025-05-07T23:44:18.128857Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 May 7 23:44:18.137062 waagent[1773]: 2025-05-07T23:44:18.137033Z INFO Daemon Daemon Wire protocol version:2012-11-30 May 7 23:44:18.143419 waagent[1773]: 2025-05-07T23:44:18.143364Z INFO Daemon Daemon Server preferred version:2015-04-05 May 7 23:44:18.422535 waagent[1773]: 2025-05-07T23:44:18.422443Z INFO Daemon Daemon Initializing goal state during protocol detection May 7 23:44:18.429015 waagent[1773]: 2025-05-07T23:44:18.428954Z INFO Daemon Daemon Forcing an update of the goal state. May 7 23:44:18.437989 waagent[1773]: 2025-05-07T23:44:18.437938Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] May 7 23:44:18.457933 waagent[1773]: 2025-05-07T23:44:18.457890Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.164 May 7 23:44:18.463738 waagent[1773]: 2025-05-07T23:44:18.463694Z INFO Daemon May 7 23:44:18.466519 waagent[1773]: 2025-05-07T23:44:18.466473Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: b8653f1d-adc2-475f-9c67-c4823bf26edf eTag: 6677834598841246624 source: Fabric] May 7 23:44:18.477785 waagent[1773]: 2025-05-07T23:44:18.477741Z INFO Daemon The vmSettings originated via Fabric; will ignore them. May 7 23:44:18.484497 waagent[1773]: 2025-05-07T23:44:18.484451Z INFO Daemon May 7 23:44:18.487199 waagent[1773]: 2025-05-07T23:44:18.487156Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] May 7 23:44:18.497739 waagent[1773]: 2025-05-07T23:44:18.497691Z INFO Daemon Daemon Downloading artifacts profile blob May 7 23:44:18.600831 waagent[1773]: 2025-05-07T23:44:18.600741Z INFO Daemon Downloaded certificate {'thumbprint': 'AC5261D4179CA46C3F0131A167A803053CC99B70', 'hasPrivateKey': False} May 7 23:44:18.611003 waagent[1773]: 2025-05-07T23:44:18.610953Z INFO Daemon Downloaded certificate {'thumbprint': '86AA7624C7C04A6F10A663A03D78230C5F9A9FD8', 'hasPrivateKey': True} May 7 23:44:18.620645 waagent[1773]: 2025-05-07T23:44:18.620598Z INFO Daemon Fetch goal state completed May 7 23:44:18.631196 waagent[1773]: 2025-05-07T23:44:18.631128Z INFO Daemon Daemon Starting provisioning May 7 23:44:18.637490 waagent[1773]: 2025-05-07T23:44:18.637431Z INFO Daemon Daemon Handle ovf-env.xml. May 7 23:44:18.642498 waagent[1773]: 2025-05-07T23:44:18.642444Z INFO Daemon Daemon Set hostname [ci-4230.1.1-n-f8d7d4446e] May 7 23:44:18.663684 waagent[1773]: 2025-05-07T23:44:18.663616Z INFO Daemon Daemon Publish hostname [ci-4230.1.1-n-f8d7d4446e] May 7 23:44:18.670573 waagent[1773]: 2025-05-07T23:44:18.670518Z INFO Daemon Daemon Examine /proc/net/route for primary interface May 7 23:44:18.676856 waagent[1773]: 2025-05-07T23:44:18.676765Z INFO Daemon Daemon Primary interface is [eth0] May 7 23:44:18.688822 systemd-networkd[1546]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. May 7 23:44:18.688830 systemd-networkd[1546]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. May 7 23:44:18.688857 systemd-networkd[1546]: eth0: DHCP lease lost May 7 23:44:18.690429 waagent[1773]: 2025-05-07T23:44:18.689991Z INFO Daemon Daemon Create user account if not exists May 7 23:44:18.695558 waagent[1773]: 2025-05-07T23:44:18.695506Z INFO Daemon Daemon User core already exists, skip useradd May 7 23:44:18.701606 waagent[1773]: 2025-05-07T23:44:18.701548Z INFO Daemon Daemon Configure sudoer May 7 23:44:18.706138 waagent[1773]: 2025-05-07T23:44:18.706084Z INFO Daemon Daemon Configure sshd May 7 23:44:18.710483 waagent[1773]: 2025-05-07T23:44:18.710436Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. May 7 23:44:18.723131 waagent[1773]: 2025-05-07T23:44:18.723069Z INFO Daemon Daemon Deploy ssh public key. May 7 23:44:18.739453 systemd-networkd[1546]: eth0: DHCPv4 address 10.200.20.36/24, gateway 10.200.20.1 acquired from 168.63.129.16 May 7 23:44:19.819601 waagent[1773]: 2025-05-07T23:44:19.819549Z INFO Daemon Daemon Provisioning complete May 7 23:44:19.834758 waagent[1773]: 2025-05-07T23:44:19.834713Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping May 7 23:44:19.840935 waagent[1773]: 2025-05-07T23:44:19.840882Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. May 7 23:44:19.851106 waagent[1773]: 2025-05-07T23:44:19.851050Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.9.1.1 is the most current agent May 7 23:44:19.983381 waagent[1838]: 2025-05-07T23:44:19.983306Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.9.1.1) May 7 23:44:19.984215 waagent[1838]: 2025-05-07T23:44:19.983814Z INFO ExtHandler ExtHandler OS: flatcar 4230.1.1 May 7 23:44:19.984215 waagent[1838]: 2025-05-07T23:44:19.983890Z INFO ExtHandler ExtHandler Python: 3.11.11 May 7 23:44:20.022449 waagent[1838]: 2025-05-07T23:44:20.020712Z INFO ExtHandler ExtHandler Distro: flatcar-4230.1.1; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.11; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; May 7 23:44:20.022449 waagent[1838]: 2025-05-07T23:44:20.020976Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file May 7 23:44:20.022449 waagent[1838]: 2025-05-07T23:44:20.021038Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 May 7 23:44:20.033425 waagent[1838]: 2025-05-07T23:44:20.033345Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] May 7 23:44:20.039089 waagent[1838]: 2025-05-07T23:44:20.039046Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.164 May 7 23:44:20.039705 waagent[1838]: 2025-05-07T23:44:20.039659Z INFO ExtHandler May 7 23:44:20.039857 waagent[1838]: 2025-05-07T23:44:20.039823Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 0865958a-c666-45d9-9ab6-0044a224804b eTag: 6677834598841246624 source: Fabric] May 7 23:44:20.040229 waagent[1838]: 2025-05-07T23:44:20.040189Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. May 7 23:44:20.040930 waagent[1838]: 2025-05-07T23:44:20.040886Z INFO ExtHandler May 7 23:44:20.041090 waagent[1838]: 2025-05-07T23:44:20.041055Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] May 7 23:44:20.045047 waagent[1838]: 2025-05-07T23:44:20.045015Z INFO ExtHandler ExtHandler Downloading artifacts profile blob May 7 23:44:20.136606 waagent[1838]: 2025-05-07T23:44:20.136464Z INFO ExtHandler Downloaded certificate {'thumbprint': 'AC5261D4179CA46C3F0131A167A803053CC99B70', 'hasPrivateKey': False} May 7 23:44:20.136985 waagent[1838]: 2025-05-07T23:44:20.136938Z INFO ExtHandler Downloaded certificate {'thumbprint': '86AA7624C7C04A6F10A663A03D78230C5F9A9FD8', 'hasPrivateKey': True} May 7 23:44:20.137409 waagent[1838]: 2025-05-07T23:44:20.137355Z INFO ExtHandler Fetch goal state completed May 7 23:44:20.150232 waagent[1838]: 2025-05-07T23:44:20.150175Z INFO ExtHandler ExtHandler WALinuxAgent-2.9.1.1 running as process 1838 May 7 23:44:20.150394 waagent[1838]: 2025-05-07T23:44:20.150356Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** May 7 23:44:20.152082 waagent[1838]: 2025-05-07T23:44:20.152037Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '4230.1.1', '', 'Flatcar Container Linux by Kinvolk'] May 7 23:44:20.152505 waagent[1838]: 2025-05-07T23:44:20.152456Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules May 7 23:44:20.170548 waagent[1838]: 2025-05-07T23:44:20.170506Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service May 7 23:44:20.170761 waagent[1838]: 2025-05-07T23:44:20.170711Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup May 7 23:44:20.176790 waagent[1838]: 2025-05-07T23:44:20.176742Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now May 7 23:44:20.183001 systemd[1]: Reload requested from client PID 1853 ('systemctl') (unit waagent.service)... May 7 23:44:20.183241 systemd[1]: Reloading... May 7 23:44:20.268485 zram_generator::config[1902]: No configuration found. May 7 23:44:20.356793 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. May 7 23:44:20.457106 systemd[1]: Reloading finished in 273 ms. May 7 23:44:20.471957 waagent[1838]: 2025-05-07T23:44:20.471598Z INFO ExtHandler ExtHandler Executing systemctl daemon-reload for setting up waagent-network-setup.service May 7 23:44:20.478167 systemd[1]: Reload requested from client PID 1946 ('systemctl') (unit waagent.service)... May 7 23:44:20.478183 systemd[1]: Reloading... May 7 23:44:20.547511 zram_generator::config[1985]: No configuration found. May 7 23:44:20.651748 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. May 7 23:44:20.754070 systemd[1]: Reloading finished in 275 ms. May 7 23:44:20.773118 waagent[1838]: 2025-05-07T23:44:20.772098Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service May 7 23:44:20.773118 waagent[1838]: 2025-05-07T23:44:20.772285Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully May 7 23:44:21.104820 waagent[1838]: 2025-05-07T23:44:21.104667Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. May 7 23:44:21.105383 waagent[1838]: 2025-05-07T23:44:21.105300Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: configuration enabled [True], cgroups enabled [False], python supported: [True] May 7 23:44:21.106261 waagent[1838]: 2025-05-07T23:44:21.106166Z INFO ExtHandler ExtHandler Starting env monitor service. May 7 23:44:21.106863 waagent[1838]: 2025-05-07T23:44:21.106591Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. May 7 23:44:21.106863 waagent[1838]: 2025-05-07T23:44:21.106769Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file May 7 23:44:21.106956 waagent[1838]: 2025-05-07T23:44:21.106881Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 May 7 23:44:21.107138 waagent[1838]: 2025-05-07T23:44:21.107031Z INFO EnvHandler ExtHandler Configure routes May 7 23:44:21.107231 waagent[1838]: 2025-05-07T23:44:21.107184Z INFO EnvHandler ExtHandler Gateway:None May 7 23:44:21.107560 waagent[1838]: 2025-05-07T23:44:21.107485Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread May 7 23:44:21.107683 waagent[1838]: 2025-05-07T23:44:21.107578Z INFO EnvHandler ExtHandler Routes:None May 7 23:44:21.107880 waagent[1838]: 2025-05-07T23:44:21.107798Z INFO ExtHandler ExtHandler Start Extension Telemetry service. May 7 23:44:21.108169 waagent[1838]: 2025-05-07T23:44:21.108133Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file May 7 23:44:21.108251 waagent[1838]: 2025-05-07T23:44:21.108215Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 May 7 23:44:21.108541 waagent[1838]: 2025-05-07T23:44:21.108451Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. May 7 23:44:21.108948 waagent[1838]: 2025-05-07T23:44:21.108885Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True May 7 23:44:21.109123 waagent[1838]: 2025-05-07T23:44:21.109057Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. May 7 23:44:21.109823 waagent[1838]: 2025-05-07T23:44:21.109779Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread May 7 23:44:21.110511 waagent[1838]: 2025-05-07T23:44:21.110452Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: May 7 23:44:21.110511 waagent[1838]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT May 7 23:44:21.110511 waagent[1838]: eth0 00000000 0114C80A 0003 0 0 1024 00000000 0 0 0 May 7 23:44:21.110511 waagent[1838]: eth0 0014C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 May 7 23:44:21.110511 waagent[1838]: eth0 0114C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 May 7 23:44:21.110511 waagent[1838]: eth0 10813FA8 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 May 7 23:44:21.110511 waagent[1838]: eth0 FEA9FEA9 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 May 7 23:44:21.120591 waagent[1838]: 2025-05-07T23:44:21.120529Z INFO ExtHandler ExtHandler May 7 23:44:21.120697 waagent[1838]: 2025-05-07T23:44:21.120663Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 3c95efcf-f12a-4027-bd85-5588fdf0c0a0 correlation 7e868d4c-6fa6-41de-951c-f9c165776f1c created: 2025-05-07T23:43:12.296242Z] May 7 23:44:21.121125 waagent[1838]: 2025-05-07T23:44:21.121074Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. May 7 23:44:21.122582 waagent[1838]: 2025-05-07T23:44:21.121712Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 1 ms] May 7 23:44:21.155390 waagent[1838]: 2025-05-07T23:44:21.155331Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.9.1.1 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: BC0EBFDA-04BC-4231-9F1E-7C1CEB68E6EE;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 0] May 7 23:44:21.157171 waagent[1838]: 2025-05-07T23:44:21.156681Z INFO MonitorHandler ExtHandler Network interfaces: May 7 23:44:21.157171 waagent[1838]: Executing ['ip', '-a', '-o', 'link']: May 7 23:44:21.157171 waagent[1838]: 1: lo: mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000\ link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00 May 7 23:44:21.157171 waagent[1838]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:22:48:b4:da:6c brd ff:ff:ff:ff:ff:ff May 7 23:44:21.157171 waagent[1838]: 3: enP37854s1: mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\ link/ether 00:22:48:b4:da:6c brd ff:ff:ff:ff:ff:ff\ altname enP37854p0s2 May 7 23:44:21.157171 waagent[1838]: Executing ['ip', '-4', '-a', '-o', 'address']: May 7 23:44:21.157171 waagent[1838]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever May 7 23:44:21.157171 waagent[1838]: 2: eth0 inet 10.200.20.36/24 metric 1024 brd 10.200.20.255 scope global eth0\ valid_lft forever preferred_lft forever May 7 23:44:21.157171 waagent[1838]: Executing ['ip', '-6', '-a', '-o', 'address']: May 7 23:44:21.157171 waagent[1838]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever May 7 23:44:21.157171 waagent[1838]: 2: eth0 inet6 fe80::222:48ff:feb4:da6c/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever May 7 23:44:21.157171 waagent[1838]: 3: enP37854s1 inet6 fe80::222:48ff:feb4:da6c/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever May 7 23:44:21.582377 waagent[1838]: 2025-05-07T23:44:21.582284Z INFO EnvHandler ExtHandler Successfully added Azure fabric firewall rules. Current Firewall rules: May 7 23:44:21.582377 waagent[1838]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) May 7 23:44:21.582377 waagent[1838]: pkts bytes target prot opt in out source destination May 7 23:44:21.582377 waagent[1838]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) May 7 23:44:21.582377 waagent[1838]: pkts bytes target prot opt in out source destination May 7 23:44:21.582377 waagent[1838]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) May 7 23:44:21.582377 waagent[1838]: pkts bytes target prot opt in out source destination May 7 23:44:21.582377 waagent[1838]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 May 7 23:44:21.582377 waagent[1838]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 May 7 23:44:21.582377 waagent[1838]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW May 7 23:44:21.585473 waagent[1838]: 2025-05-07T23:44:21.585376Z INFO EnvHandler ExtHandler Current Firewall rules: May 7 23:44:21.585473 waagent[1838]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) May 7 23:44:21.585473 waagent[1838]: pkts bytes target prot opt in out source destination May 7 23:44:21.585473 waagent[1838]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) May 7 23:44:21.585473 waagent[1838]: pkts bytes target prot opt in out source destination May 7 23:44:21.585473 waagent[1838]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) May 7 23:44:21.585473 waagent[1838]: pkts bytes target prot opt in out source destination May 7 23:44:21.585473 waagent[1838]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 May 7 23:44:21.585473 waagent[1838]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 May 7 23:44:21.585473 waagent[1838]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW May 7 23:44:21.585745 waagent[1838]: 2025-05-07T23:44:21.585706Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 May 7 23:44:29.583346 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. May 7 23:44:29.594768 systemd[1]: Started sshd@0-10.200.20.36:22-10.200.16.10:48488.service - OpenSSH per-connection server daemon (10.200.16.10:48488). May 7 23:44:30.129651 sshd[2071]: Accepted publickey for core from 10.200.16.10 port 48488 ssh2: RSA SHA256:Xf+vpbdJh3/esr9OIwFY2Rj6mXkq4UjyjZIQRU1uG/Y May 7 23:44:30.131035 sshd-session[2071]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 7 23:44:30.136180 systemd-logind[1628]: New session 3 of user core. May 7 23:44:30.150655 systemd[1]: Started session-3.scope - Session 3 of User core. May 7 23:44:30.530673 systemd[1]: Started sshd@1-10.200.20.36:22-10.200.16.10:48498.service - OpenSSH per-connection server daemon (10.200.16.10:48498). May 7 23:44:30.952769 sshd[2076]: Accepted publickey for core from 10.200.16.10 port 48498 ssh2: RSA SHA256:Xf+vpbdJh3/esr9OIwFY2Rj6mXkq4UjyjZIQRU1uG/Y May 7 23:44:30.954218 sshd-session[2076]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 7 23:44:30.960290 systemd-logind[1628]: New session 4 of user core. May 7 23:44:30.972125 systemd[1]: Started session-4.scope - Session 4 of User core. May 7 23:44:31.256657 sshd[2078]: Connection closed by 10.200.16.10 port 48498 May 7 23:44:31.256858 sshd-session[2076]: pam_unix(sshd:session): session closed for user core May 7 23:44:31.259844 systemd[1]: sshd@1-10.200.20.36:22-10.200.16.10:48498.service: Deactivated successfully. May 7 23:44:31.262824 systemd[1]: session-4.scope: Deactivated successfully. May 7 23:44:31.264887 systemd-logind[1628]: Session 4 logged out. Waiting for processes to exit. May 7 23:44:31.266540 systemd-logind[1628]: Removed session 4. May 7 23:44:31.343836 systemd[1]: Started sshd@2-10.200.20.36:22-10.200.16.10:48506.service - OpenSSH per-connection server daemon (10.200.16.10:48506). May 7 23:44:31.759174 sshd[2084]: Accepted publickey for core from 10.200.16.10 port 48506 ssh2: RSA SHA256:Xf+vpbdJh3/esr9OIwFY2Rj6mXkq4UjyjZIQRU1uG/Y May 7 23:44:31.761108 sshd-session[2084]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 7 23:44:31.768227 systemd-logind[1628]: New session 5 of user core. May 7 23:44:31.774655 systemd[1]: Started session-5.scope - Session 5 of User core. May 7 23:44:32.066904 sshd[2086]: Connection closed by 10.200.16.10 port 48506 May 7 23:44:32.067477 sshd-session[2084]: pam_unix(sshd:session): session closed for user core May 7 23:44:32.072118 systemd-logind[1628]: Session 5 logged out. Waiting for processes to exit. May 7 23:44:32.072373 systemd[1]: sshd@2-10.200.20.36:22-10.200.16.10:48506.service: Deactivated successfully. May 7 23:44:32.074372 systemd[1]: session-5.scope: Deactivated successfully. May 7 23:44:32.075825 systemd-logind[1628]: Removed session 5. May 7 23:44:32.156170 systemd[1]: Started sshd@3-10.200.20.36:22-10.200.16.10:48518.service - OpenSSH per-connection server daemon (10.200.16.10:48518). May 7 23:44:32.614734 sshd[2092]: Accepted publickey for core from 10.200.16.10 port 48518 ssh2: RSA SHA256:Xf+vpbdJh3/esr9OIwFY2Rj6mXkq4UjyjZIQRU1uG/Y May 7 23:44:32.616136 sshd-session[2092]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 7 23:44:32.620734 systemd-logind[1628]: New session 6 of user core. May 7 23:44:32.633616 systemd[1]: Started session-6.scope - Session 6 of User core. May 7 23:44:32.943192 sshd[2094]: Connection closed by 10.200.16.10 port 48518 May 7 23:44:32.943030 sshd-session[2092]: pam_unix(sshd:session): session closed for user core May 7 23:44:32.947593 systemd[1]: sshd@3-10.200.20.36:22-10.200.16.10:48518.service: Deactivated successfully. May 7 23:44:32.949665 systemd[1]: session-6.scope: Deactivated successfully. May 7 23:44:32.951047 systemd-logind[1628]: Session 6 logged out. Waiting for processes to exit. May 7 23:44:32.952152 systemd-logind[1628]: Removed session 6. May 7 23:44:33.028956 systemd[1]: Started sshd@4-10.200.20.36:22-10.200.16.10:48528.service - OpenSSH per-connection server daemon (10.200.16.10:48528). May 7 23:44:33.449086 sshd[2100]: Accepted publickey for core from 10.200.16.10 port 48528 ssh2: RSA SHA256:Xf+vpbdJh3/esr9OIwFY2Rj6mXkq4UjyjZIQRU1uG/Y May 7 23:44:33.450502 sshd-session[2100]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 7 23:44:33.455165 systemd-logind[1628]: New session 7 of user core. May 7 23:44:33.464617 systemd[1]: Started session-7.scope - Session 7 of User core. May 7 23:44:33.741544 sudo[2103]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 May 7 23:44:33.741874 sudo[2103]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 7 23:44:33.767728 sudo[2103]: pam_unix(sudo:session): session closed for user root May 7 23:44:33.833572 sshd[2102]: Connection closed by 10.200.16.10 port 48528 May 7 23:44:33.832663 sshd-session[2100]: pam_unix(sshd:session): session closed for user core May 7 23:44:33.836052 systemd[1]: sshd@4-10.200.20.36:22-10.200.16.10:48528.service: Deactivated successfully. May 7 23:44:33.839014 systemd[1]: session-7.scope: Deactivated successfully. May 7 23:44:33.840945 systemd-logind[1628]: Session 7 logged out. Waiting for processes to exit. May 7 23:44:33.842498 systemd-logind[1628]: Removed session 7. May 7 23:44:33.919698 systemd[1]: Started sshd@5-10.200.20.36:22-10.200.16.10:48532.service - OpenSSH per-connection server daemon (10.200.16.10:48532). May 7 23:44:34.370986 sshd[2109]: Accepted publickey for core from 10.200.16.10 port 48532 ssh2: RSA SHA256:Xf+vpbdJh3/esr9OIwFY2Rj6mXkq4UjyjZIQRU1uG/Y May 7 23:44:34.372605 sshd-session[2109]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 7 23:44:34.377306 systemd-logind[1628]: New session 8 of user core. May 7 23:44:34.392621 systemd[1]: Started session-8.scope - Session 8 of User core. May 7 23:44:34.626545 sudo[2113]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules May 7 23:44:34.627223 sudo[2113]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 7 23:44:34.631426 sudo[2113]: pam_unix(sudo:session): session closed for user root May 7 23:44:34.637167 sudo[2112]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules May 7 23:44:34.637668 sudo[2112]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 7 23:44:34.650727 systemd[1]: Starting audit-rules.service - Load Audit Rules... May 7 23:44:34.676605 augenrules[2135]: No rules May 7 23:44:34.678277 systemd[1]: audit-rules.service: Deactivated successfully. May 7 23:44:34.678562 systemd[1]: Finished audit-rules.service - Load Audit Rules. May 7 23:44:34.680598 sudo[2112]: pam_unix(sudo:session): session closed for user root May 7 23:44:34.750711 sshd[2111]: Connection closed by 10.200.16.10 port 48532 May 7 23:44:34.751280 sshd-session[2109]: pam_unix(sshd:session): session closed for user core May 7 23:44:34.755504 systemd[1]: sshd@5-10.200.20.36:22-10.200.16.10:48532.service: Deactivated successfully. May 7 23:44:34.757389 systemd[1]: session-8.scope: Deactivated successfully. May 7 23:44:34.758152 systemd-logind[1628]: Session 8 logged out. Waiting for processes to exit. May 7 23:44:34.759215 systemd-logind[1628]: Removed session 8. May 7 23:44:34.837788 systemd[1]: Started sshd@6-10.200.20.36:22-10.200.16.10:48534.service - OpenSSH per-connection server daemon (10.200.16.10:48534). May 7 23:44:35.284957 sshd[2144]: Accepted publickey for core from 10.200.16.10 port 48534 ssh2: RSA SHA256:Xf+vpbdJh3/esr9OIwFY2Rj6mXkq4UjyjZIQRU1uG/Y May 7 23:44:35.286373 sshd-session[2144]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 7 23:44:35.290781 systemd-logind[1628]: New session 9 of user core. May 7 23:44:35.304887 systemd[1]: Started session-9.scope - Session 9 of User core. May 7 23:44:35.541133 sudo[2147]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/mkdir -p . May 7 23:44:35.541521 sudo[2147]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 7 23:44:35.544984 sudo[2147]: pam_unix(sudo:session): session closed for user root May 7 23:44:35.621469 sshd[2146]: Connection closed by 10.200.16.10 port 48534 May 7 23:44:35.621297 sshd-session[2144]: pam_unix(sshd:session): session closed for user core May 7 23:44:35.625630 systemd[1]: sshd@6-10.200.20.36:22-10.200.16.10:48534.service: Deactivated successfully. May 7 23:44:35.627551 systemd[1]: session-9.scope: Deactivated successfully. May 7 23:44:35.628255 systemd-logind[1628]: Session 9 logged out. Waiting for processes to exit. May 7 23:44:35.629445 systemd-logind[1628]: Removed session 9. May 7 23:44:35.707792 systemd[1]: Started sshd@7-10.200.20.36:22-10.200.16.10:48538.service - OpenSSH per-connection server daemon (10.200.16.10:48538). May 7 23:44:36.156724 sshd[2153]: Accepted publickey for core from 10.200.16.10 port 48538 ssh2: RSA SHA256:Xf+vpbdJh3/esr9OIwFY2Rj6mXkq4UjyjZIQRU1uG/Y May 7 23:44:36.158158 sshd-session[2153]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 7 23:44:36.162590 systemd-logind[1628]: New session 10 of user core. May 7 23:44:36.176613 systemd[1]: Started session-10.scope - Session 10 of User core. May 7 23:44:36.411344 sudo[2156]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/install -m 0755 /dev/stdin kolet May 7 23:44:36.411666 sudo[2156]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) May 7 23:44:38.672504 sudo[2156]: pam_unix(sudo:session): session closed for user root May 7 23:44:38.747842 sshd[2155]: Connection closed by 10.200.16.10 port 48538 May 7 23:44:38.748357 sshd-session[2153]: pam_unix(sshd:session): session closed for user core May 7 23:44:38.751957 systemd-logind[1628]: Session 10 logged out. Waiting for processes to exit. May 7 23:44:38.752724 systemd[1]: sshd@7-10.200.20.36:22-10.200.16.10:48538.service: Deactivated successfully. May 7 23:44:38.754633 systemd[1]: session-10.scope: Deactivated successfully. May 7 23:44:38.756031 systemd-logind[1628]: Removed session 10. May 7 23:44:38.842694 systemd[1]: Started sshd@8-10.200.20.36:22-10.200.16.10:48554.service - OpenSSH per-connection server daemon (10.200.16.10:48554). May 7 23:44:39.292010 sshd[2162]: Accepted publickey for core from 10.200.16.10 port 48554 ssh2: RSA SHA256:Xf+vpbdJh3/esr9OIwFY2Rj6mXkq4UjyjZIQRU1uG/Y May 7 23:44:39.293393 sshd-session[2162]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 7 23:44:39.297452 systemd-logind[1628]: New session 11 of user core. May 7 23:44:39.299477 chronyd[1614]: Selected source PHC0 May 7 23:44:39.304574 systemd[1]: Started session-11.scope - Session 11 of User core. May 7 23:44:39.685371 sshd[2164]: Connection closed by 10.200.16.10 port 48554 May 7 23:44:39.685965 sshd-session[2162]: pam_unix(sshd:session): session closed for user core May 7 23:44:39.689456 systemd[1]: sshd@8-10.200.20.36:22-10.200.16.10:48554.service: Deactivated successfully. May 7 23:44:39.691184 systemd[1]: session-11.scope: Deactivated successfully. May 7 23:44:39.691936 systemd-logind[1628]: Session 11 logged out. Waiting for processes to exit. May 7 23:44:39.692818 systemd-logind[1628]: Removed session 11. May 7 23:44:39.773723 systemd[1]: Started sshd@9-10.200.20.36:22-10.200.16.10:36846.service - OpenSSH per-connection server daemon (10.200.16.10:36846). May 7 23:44:40.222431 sshd[2177]: Accepted publickey for core from 10.200.16.10 port 36846 ssh2: RSA SHA256:Xf+vpbdJh3/esr9OIwFY2Rj6mXkq4UjyjZIQRU1uG/Y May 7 23:44:40.223805 sshd-session[2177]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 7 23:44:40.228647 systemd-logind[1628]: New session 12 of user core. May 7 23:44:40.234658 systemd[1]: Started session-12.scope - Session 12 of User core. May 7 23:44:40.750663 systemd[1]: Starting docker.service - Docker Application Container Engine... May 7 23:44:40.751087 (dockerd)[2194]: 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 May 7 23:44:41.420453 dockerd[2194]: time="2025-05-07T23:44:41.420273447Z" level=info msg="Starting up" May 7 23:44:42.005371 dockerd[2194]: time="2025-05-07T23:44:42.005288127Z" level=info msg="Loading containers: start." May 7 23:44:42.223491 kernel: Initializing XFRM netlink socket May 7 23:44:42.339845 systemd-networkd[1546]: docker0: Link UP May 7 23:44:42.384073 dockerd[2194]: time="2025-05-07T23:44:42.383466847Z" level=info msg="Loading containers: done." May 7 23:44:42.394917 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck4012316256-merged.mount: Deactivated successfully. May 7 23:44:42.420759 dockerd[2194]: time="2025-05-07T23:44:42.420673127Z" 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 May 7 23:44:42.421138 dockerd[2194]: time="2025-05-07T23:44:42.420822727Z" level=info msg="Docker daemon" commit=41ca978a0a5400cc24b274137efa9f25517fcc0b containerd-snapshotter=false storage-driver=overlay2 version=27.3.1 May 7 23:44:42.421138 dockerd[2194]: time="2025-05-07T23:44:42.420962087Z" level=info msg="Daemon has completed initialization" May 7 23:44:42.480568 dockerd[2194]: time="2025-05-07T23:44:42.480434847Z" level=info msg="API listen on /run/docker.sock" May 7 23:44:42.482186 systemd[1]: Started docker.service - Docker Application Container Engine. May 7 23:44:43.481526 systemd[1]: var-lib-docker-overlay2-152a1ed4ec4cd61a34bb2846ef2ed82d553435cc2c588d875add55857400ea0b\x2dinit-merged.mount: Deactivated successfully. May 7 23:44:43.598369 kernel: docker0: port 1(veth3e28639) entered blocking state May 7 23:44:43.598876 kernel: docker0: port 1(veth3e28639) entered disabled state May 7 23:44:43.602824 kernel: veth3e28639: entered allmulticast mode May 7 23:44:43.608259 kernel: veth3e28639: entered promiscuous mode May 7 23:44:43.609073 systemd-networkd[1546]: veth3e28639: Link UP May 7 23:44:44.248427 containerd[1659]: time="2025-05-07T23:44:44.248251967Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 7 23:44:44.248427 containerd[1659]: time="2025-05-07T23:44:44.248336767Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 7 23:44:44.248427 containerd[1659]: time="2025-05-07T23:44:44.248347927Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 7 23:44:44.249068 containerd[1659]: time="2025-05-07T23:44:44.248925567Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 7 23:44:44.271593 systemd[1]: Started docker-5895ff9cfe9800bc39c2f2808d6d8b7725fe51d3bdb45f649cd0065b47375af3.scope - libcontainer container 5895ff9cfe9800bc39c2f2808d6d8b7725fe51d3bdb45f649cd0065b47375af3. May 7 23:44:44.323442 kernel: eth0: renamed from vethcbe5a34 May 7 23:44:44.341295 kernel: docker0: port 1(veth3e28639) entered blocking state May 7 23:44:44.341419 kernel: docker0: port 1(veth3e28639) entered forwarding state May 7 23:44:44.341373 systemd-networkd[1546]: veth3e28639: Gained carrier May 7 23:44:44.342248 systemd-networkd[1546]: docker0: Gained carrier May 7 23:44:45.406565 systemd-networkd[1546]: docker0: Gained IPv6LL May 7 23:44:46.174579 systemd-networkd[1546]: veth3e28639: Gained IPv6LL May 7 23:44:47.500959 systemd[1]: docker-5895ff9cfe9800bc39c2f2808d6d8b7725fe51d3bdb45f649cd0065b47375af3.scope: Deactivated successfully. May 7 23:44:47.514045 dockerd[2194]: time="2025-05-07T23:44:47.513988439Z" level=info msg="ignoring event" container=5895ff9cfe9800bc39c2f2808d6d8b7725fe51d3bdb45f649cd0065b47375af3 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" May 7 23:44:47.514523 containerd[1659]: time="2025-05-07T23:44:47.514111919Z" level=info msg="shim disconnected" id=5895ff9cfe9800bc39c2f2808d6d8b7725fe51d3bdb45f649cd0065b47375af3 namespace=moby May 7 23:44:47.514523 containerd[1659]: time="2025-05-07T23:44:47.514145798Z" level=warning msg="cleaning up after shim disconnected" id=5895ff9cfe9800bc39c2f2808d6d8b7725fe51d3bdb45f649cd0065b47375af3 namespace=moby May 7 23:44:47.514523 containerd[1659]: time="2025-05-07T23:44:47.514155638Z" level=info msg="cleaning up dead shim" namespace=moby May 7 23:44:47.541797 systemd-networkd[1546]: veth3e28639: Lost carrier May 7 23:44:47.542538 kernel: docker0: port 1(veth3e28639) entered disabled state May 7 23:44:47.542600 kernel: vethcbe5a34: renamed from eth0 May 7 23:44:47.572009 systemd-networkd[1546]: veth3e28639: Link DOWN May 7 23:44:47.577208 kernel: docker0: port 1(veth3e28639) entered disabled state May 7 23:44:47.577290 kernel: veth3e28639 (unregistering): left allmulticast mode May 7 23:44:47.585142 kernel: veth3e28639 (unregistering): left promiscuous mode May 7 23:44:47.585244 kernel: docker0: port 1(veth3e28639) entered disabled state May 7 23:44:47.613315 systemd[1]: run-docker-netns-3af1c16abd55.mount: Deactivated successfully. May 7 23:44:47.618481 systemd[1]: var-lib-docker-overlay2-152a1ed4ec4cd61a34bb2846ef2ed82d553435cc2c588d875add55857400ea0b-merged.mount: Deactivated successfully. May 7 23:44:47.731644 sshd[2179]: Connection closed by 10.200.16.10 port 36846 May 7 23:44:47.732316 sshd-session[2177]: pam_unix(sshd:session): session closed for user core May 7 23:44:47.735967 systemd[1]: sshd@9-10.200.20.36:22-10.200.16.10:36846.service: Deactivated successfully. May 7 23:44:47.738217 systemd[1]: session-12.scope: Deactivated successfully. May 7 23:44:47.739004 systemd-logind[1628]: Session 12 logged out. Waiting for processes to exit. May 7 23:44:47.739939 systemd-logind[1628]: Removed session 12. May 7 23:44:47.818721 systemd[1]: Started sshd@10-10.200.20.36:22-10.200.16.10:36848.service - OpenSSH per-connection server daemon (10.200.16.10:36848). May 7 23:44:48.234137 sshd[2454]: Accepted publickey for core from 10.200.16.10 port 36848 ssh2: RSA SHA256:Xf+vpbdJh3/esr9OIwFY2Rj6mXkq4UjyjZIQRU1uG/Y May 7 23:44:48.235558 sshd-session[2454]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 7 23:44:48.240039 systemd-logind[1628]: New session 13 of user core. May 7 23:44:48.246572 systemd[1]: Started session-13.scope - Session 13 of User core. May 7 23:44:48.526251 systemd[1]: var-lib-docker-overlay2-d82c7a07378e722ada9aa432a08f602ebcf76aa5c6f4de435e1de3c74c3153cb\x2dinit-merged.mount: Deactivated successfully. May 7 23:44:48.542666 systemd-networkd[1546]: docker0: Lost carrier May 7 23:44:48.664615 kernel: docker0: port 1(veth0671f90) entered blocking state May 7 23:44:48.664812 kernel: docker0: port 1(veth0671f90) entered disabled state May 7 23:44:48.664841 kernel: veth0671f90: entered allmulticast mode May 7 23:44:48.673917 kernel: veth0671f90: entered promiscuous mode May 7 23:44:48.679197 kernel: docker0: port 1(veth0671f90) entered blocking state May 7 23:44:48.679426 kernel: docker0: port 1(veth0671f90) entered forwarding state May 7 23:44:48.686706 systemd-networkd[1546]: veth0671f90: Link UP May 7 23:44:48.692471 kernel: docker0: port 1(veth0671f90) entered disabled state May 7 23:44:48.767066 containerd[1659]: time="2025-05-07T23:44:48.766807117Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 May 7 23:44:48.767066 containerd[1659]: time="2025-05-07T23:44:48.766900597Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 May 7 23:44:48.767066 containerd[1659]: time="2025-05-07T23:44:48.766913917Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 7 23:44:48.767645 containerd[1659]: time="2025-05-07T23:44:48.767120117Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 May 7 23:44:48.781451 systemd[1]: run-docker-runtime\x2drunc-moby-e803f4b917fabe1bf871cc76b8115e6dec7fbeb4b7d3621ae586e42d2078b55c-runc.OYo3iz.mount: Deactivated successfully. May 7 23:44:48.792623 systemd[1]: Started docker-e803f4b917fabe1bf871cc76b8115e6dec7fbeb4b7d3621ae586e42d2078b55c.scope - libcontainer container e803f4b917fabe1bf871cc76b8115e6dec7fbeb4b7d3621ae586e42d2078b55c. May 7 23:44:48.842424 kernel: eth0: renamed from vethf101ec5 May 7 23:44:48.858223 kernel: docker0: port 1(veth0671f90) entered blocking state May 7 23:44:48.858319 kernel: docker0: port 1(veth0671f90) entered forwarding state May 7 23:44:48.858321 systemd-networkd[1546]: veth0671f90: Gained carrier May 7 23:44:48.858612 systemd-networkd[1546]: docker0: Gained carrier May 7 23:44:48.882031 systemd[1]: docker-e803f4b917fabe1bf871cc76b8115e6dec7fbeb4b7d3621ae586e42d2078b55c.scope: Deactivated successfully. May 7 23:44:48.882835 containerd[1659]: time="2025-05-07T23:44:48.882622941Z" level=warning msg="error from *cgroupsv2.Manager.EventChan" error="failed to add inotify watch for \"/sys/fs/cgroup/system.slice/docker-e803f4b917fabe1bf871cc76b8115e6dec7fbeb4b7d3621ae586e42d2078b55c.scope/memory.events\": no such file or directory" May 7 23:44:48.909609 dockerd[2194]: time="2025-05-07T23:44:48.909518558Z" level=info msg="ignoring event" container=e803f4b917fabe1bf871cc76b8115e6dec7fbeb4b7d3621ae586e42d2078b55c module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" May 7 23:44:48.910069 containerd[1659]: time="2025-05-07T23:44:48.909874998Z" level=info msg="shim disconnected" id=e803f4b917fabe1bf871cc76b8115e6dec7fbeb4b7d3621ae586e42d2078b55c namespace=moby May 7 23:44:48.910069 containerd[1659]: time="2025-05-07T23:44:48.909956438Z" level=warning msg="cleaning up after shim disconnected" id=e803f4b917fabe1bf871cc76b8115e6dec7fbeb4b7d3621ae586e42d2078b55c namespace=moby May 7 23:44:48.910069 containerd[1659]: time="2025-05-07T23:44:48.909975718Z" level=info msg="cleaning up dead shim" namespace=moby May 7 23:44:48.945472 kernel: docker0: port 1(veth0671f90) entered disabled state May 7 23:44:48.945572 kernel: vethf101ec5: renamed from eth0 May 7 23:44:48.942037 systemd-networkd[1546]: veth0671f90: Lost carrier May 7 23:44:48.970598 systemd-networkd[1546]: veth0671f90: Link DOWN May 7 23:44:48.975433 kernel: docker0: port 1(veth0671f90) entered disabled state May 7 23:44:48.985005 kernel: veth0671f90 (unregistering): left allmulticast mode May 7 23:44:48.985103 kernel: veth0671f90 (unregistering): left promiscuous mode May 7 23:44:48.985126 kernel: docker0: port 1(veth0671f90) entered disabled state May 7 23:44:49.098870 sshd[2456]: Connection closed by 10.200.16.10 port 36848 May 7 23:44:49.099826 sshd-session[2454]: pam_unix(sshd:session): session closed for user core May 7 23:44:49.103216 systemd-logind[1628]: Session 13 logged out. Waiting for processes to exit. May 7 23:44:49.104155 systemd[1]: sshd@10-10.200.20.36:22-10.200.16.10:36848.service: Deactivated successfully. May 7 23:44:49.106086 systemd[1]: session-13.scope: Deactivated successfully. May 7 23:44:49.108242 systemd-logind[1628]: Removed session 13. May 7 23:44:49.180606 systemd[1]: Started sshd@11-10.200.20.36:22-10.200.16.10:38048.service - OpenSSH per-connection server daemon (10.200.16.10:38048). May 7 23:44:49.566628 systemd-networkd[1546]: docker0: Lost carrier May 7 23:44:49.619293 systemd[1]: var-lib-docker-overlay2-d82c7a07378e722ada9aa432a08f602ebcf76aa5c6f4de435e1de3c74c3153cb-merged.mount: Deactivated successfully. May 7 23:44:49.629616 sshd[2548]: Accepted publickey for core from 10.200.16.10 port 38048 ssh2: RSA SHA256:Xf+vpbdJh3/esr9OIwFY2Rj6mXkq4UjyjZIQRU1uG/Y May 7 23:44:49.630978 sshd-session[2548]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) May 7 23:44:49.636451 systemd-logind[1628]: New session 14 of user core. May 7 23:44:49.642620 systemd[1]: Started session-14.scope - Session 14 of User core. May 7 23:44:49.935492 ntpdate[2558]: ntpdate 4.2.8p17@1.4004-o Wed May 7 21:39:10 UTC 2025 (1) May 7 23:44:56.734149 ntpdate[2558]: adjust time server 155.248.196.28 offset -0.000639 sec May 7 23:44:56.810548 sshd[2550]: Connection closed by 10.200.16.10 port 38048 May 7 23:44:56.810456 sshd-session[2548]: pam_unix(sshd:session): session closed for user core May 7 23:44:56.814098 systemd[1]: sshd@11-10.200.20.36:22-10.200.16.10:38048.service: Deactivated successfully. May 7 23:44:56.816335 systemd[1]: session-14.scope: Deactivated successfully. May 7 23:44:56.817285 systemd-logind[1628]: Session 14 logged out. Waiting for processes to exit. May 7 23:44:56.818234 systemd-logind[1628]: Removed session 14.