Mar 17 17:46:21.353765 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Mar 17 17:46:21.353789 kernel: Linux version 6.6.83-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 Mon Mar 17 16:11:40 -00 2025 Mar 17 17:46:21.353797 kernel: KASLR enabled Mar 17 17:46:21.353803 kernel: earlycon: pl11 at MMIO 0x00000000effec000 (options '') Mar 17 17:46:21.353811 kernel: printk: bootconsole [pl11] enabled Mar 17 17:46:21.353817 kernel: efi: EFI v2.7 by EDK II Mar 17 17:46:21.353824 kernel: efi: ACPI 2.0=0x3fd5f018 SMBIOS=0x3e580000 SMBIOS 3.0=0x3e560000 MEMATTR=0x3f20e698 RNG=0x3fd5f998 MEMRESERVE=0x3e477598 Mar 17 17:46:21.353831 kernel: random: crng init done Mar 17 17:46:21.353837 kernel: secureboot: Secure boot disabled Mar 17 17:46:21.353843 kernel: ACPI: Early table checksum verification disabled Mar 17 17:46:21.353849 kernel: ACPI: RSDP 0x000000003FD5F018 000024 (v02 VRTUAL) Mar 17 17:46:21.353855 kernel: ACPI: XSDT 0x000000003FD5FF18 00006C (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 17:46:21.353861 kernel: ACPI: FACP 0x000000003FD5FC18 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 17:46:21.353868 kernel: ACPI: DSDT 0x000000003FD41018 01DFCD (v02 MSFTVM DSDT01 00000001 INTL 20230628) Mar 17 17:46:21.353876 kernel: ACPI: DBG2 0x000000003FD5FB18 000072 (v00 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 17:46:21.353882 kernel: ACPI: GTDT 0x000000003FD5FD98 000060 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 17:46:21.353889 kernel: ACPI: OEM0 0x000000003FD5F098 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 17:46:21.353897 kernel: ACPI: SPCR 0x000000003FD5FA98 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 17:46:21.353903 kernel: ACPI: APIC 0x000000003FD5F818 0000FC (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 17:46:21.353910 kernel: ACPI: SRAT 0x000000003FD5F198 000234 (v03 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 17:46:21.353916 kernel: ACPI: PPTT 0x000000003FD5F418 000120 (v01 VRTUAL MICROSFT 00000000 MSFT 00000000) Mar 17 17:46:21.353923 kernel: ACPI: BGRT 0x000000003FD5FE98 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 17:46:21.353929 kernel: ACPI: SPCR: console: pl011,mmio32,0xeffec000,115200 Mar 17 17:46:21.353935 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] Mar 17 17:46:21.353942 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0x1bfffffff] Mar 17 17:46:21.353948 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x1c0000000-0xfbfffffff] Mar 17 17:46:21.353954 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x1000000000-0xffffffffff] Mar 17 17:46:21.353961 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000000000-0x1ffffffffff] Mar 17 17:46:21.353969 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000000000-0x3ffffffffff] Mar 17 17:46:21.353975 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000000000-0x7ffffffffff] Mar 17 17:46:21.353982 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000000000-0xfffffffffff] Mar 17 17:46:21.353988 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000000-0x1fffffffffff] Mar 17 17:46:21.353994 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x200000000000-0x3fffffffffff] Mar 17 17:46:21.354001 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x400000000000-0x7fffffffffff] Mar 17 17:46:21.354007 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x800000000000-0xffffffffffff] Mar 17 17:46:21.354013 kernel: NUMA: NODE_DATA [mem 0x1bf7ef800-0x1bf7f4fff] Mar 17 17:46:21.354020 kernel: Zone ranges: Mar 17 17:46:21.355097 kernel: DMA [mem 0x0000000000000000-0x00000000ffffffff] Mar 17 17:46:21.355113 kernel: DMA32 empty Mar 17 17:46:21.355121 kernel: Normal [mem 0x0000000100000000-0x00000001bfffffff] Mar 17 17:46:21.355136 kernel: Movable zone start for each node Mar 17 17:46:21.355143 kernel: Early memory node ranges Mar 17 17:46:21.355150 kernel: node 0: [mem 0x0000000000000000-0x00000000007fffff] Mar 17 17:46:21.355157 kernel: node 0: [mem 0x0000000000824000-0x000000003e45ffff] Mar 17 17:46:21.355164 kernel: node 0: [mem 0x000000003e460000-0x000000003e46ffff] Mar 17 17:46:21.355172 kernel: node 0: [mem 0x000000003e470000-0x000000003e54ffff] Mar 17 17:46:21.355179 kernel: node 0: [mem 0x000000003e550000-0x000000003e87ffff] Mar 17 17:46:21.355186 kernel: node 0: [mem 0x000000003e880000-0x000000003fc7ffff] Mar 17 17:46:21.355193 kernel: node 0: [mem 0x000000003fc80000-0x000000003fcfffff] Mar 17 17:46:21.355200 kernel: node 0: [mem 0x000000003fd00000-0x000000003fffffff] Mar 17 17:46:21.355206 kernel: node 0: [mem 0x0000000100000000-0x00000001bfffffff] Mar 17 17:46:21.355214 kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000001bfffffff] Mar 17 17:46:21.355221 kernel: On node 0, zone DMA: 36 pages in unavailable ranges Mar 17 17:46:21.355228 kernel: psci: probing for conduit method from ACPI. Mar 17 17:46:21.355234 kernel: psci: PSCIv1.1 detected in firmware. Mar 17 17:46:21.355241 kernel: psci: Using standard PSCI v0.2 function IDs Mar 17 17:46:21.355248 kernel: psci: MIGRATE_INFO_TYPE not supported. Mar 17 17:46:21.355257 kernel: psci: SMC Calling Convention v1.4 Mar 17 17:46:21.355263 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x0 -> Node 0 Mar 17 17:46:21.355270 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x1 -> Node 0 Mar 17 17:46:21.355277 kernel: percpu: Embedded 31 pages/cpu s86696 r8192 d32088 u126976 Mar 17 17:46:21.355284 kernel: pcpu-alloc: s86696 r8192 d32088 u126976 alloc=31*4096 Mar 17 17:46:21.355291 kernel: pcpu-alloc: [0] 0 [0] 1 Mar 17 17:46:21.355298 kernel: Detected PIPT I-cache on CPU0 Mar 17 17:46:21.355304 kernel: CPU features: detected: GIC system register CPU interface Mar 17 17:46:21.355311 kernel: CPU features: detected: Hardware dirty bit management Mar 17 17:46:21.355318 kernel: CPU features: detected: Spectre-BHB Mar 17 17:46:21.355325 kernel: CPU features: kernel page table isolation forced ON by KASLR Mar 17 17:46:21.355333 kernel: CPU features: detected: Kernel page table isolation (KPTI) Mar 17 17:46:21.355340 kernel: CPU features: detected: ARM erratum 1418040 Mar 17 17:46:21.355347 kernel: CPU features: detected: ARM erratum 1542419 (kernel portion) Mar 17 17:46:21.355354 kernel: CPU features: detected: SSBS not fully self-synchronizing Mar 17 17:46:21.355361 kernel: alternatives: applying boot alternatives Mar 17 17:46:21.355369 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=f8298a09e890fc732131b7281e24befaf65b596eb5216e969c8eca4cab4a2b3a Mar 17 17:46:21.355376 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Mar 17 17:46:21.355383 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Mar 17 17:46:21.355390 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Mar 17 17:46:21.355397 kernel: Fallback order for Node 0: 0 Mar 17 17:46:21.355404 kernel: Built 1 zonelists, mobility grouping on. Total pages: 1032156 Mar 17 17:46:21.355413 kernel: Policy zone: Normal Mar 17 17:46:21.355430 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Mar 17 17:46:21.355437 kernel: software IO TLB: area num 2. Mar 17 17:46:21.355444 kernel: software IO TLB: mapped [mem 0x0000000036550000-0x000000003a550000] (64MB) Mar 17 17:46:21.355451 kernel: Memory: 3983656K/4194160K available (10304K kernel code, 2186K rwdata, 8096K rodata, 38336K init, 897K bss, 210504K reserved, 0K cma-reserved) Mar 17 17:46:21.355458 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Mar 17 17:46:21.355465 kernel: rcu: Preemptible hierarchical RCU implementation. Mar 17 17:46:21.355472 kernel: rcu: RCU event tracing is enabled. Mar 17 17:46:21.355479 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Mar 17 17:46:21.355486 kernel: Trampoline variant of Tasks RCU enabled. Mar 17 17:46:21.355493 kernel: Tracing variant of Tasks RCU enabled. Mar 17 17:46:21.355502 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Mar 17 17:46:21.355509 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Mar 17 17:46:21.355516 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Mar 17 17:46:21.355522 kernel: GICv3: 960 SPIs implemented Mar 17 17:46:21.355529 kernel: GICv3: 0 Extended SPIs implemented Mar 17 17:46:21.355536 kernel: Root IRQ handler: gic_handle_irq Mar 17 17:46:21.355543 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Mar 17 17:46:21.355550 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000effee000 Mar 17 17:46:21.355556 kernel: ITS: No ITS available, not enabling LPIs Mar 17 17:46:21.355563 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Mar 17 17:46:21.355571 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Mar 17 17:46:21.355577 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Mar 17 17:46:21.355586 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Mar 17 17:46:21.355593 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Mar 17 17:46:21.355600 kernel: Console: colour dummy device 80x25 Mar 17 17:46:21.355608 kernel: printk: console [tty1] enabled Mar 17 17:46:21.355615 kernel: ACPI: Core revision 20230628 Mar 17 17:46:21.355622 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Mar 17 17:46:21.355629 kernel: pid_max: default: 32768 minimum: 301 Mar 17 17:46:21.355637 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Mar 17 17:46:21.355644 kernel: landlock: Up and running. Mar 17 17:46:21.355652 kernel: SELinux: Initializing. Mar 17 17:46:21.355659 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Mar 17 17:46:21.355667 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Mar 17 17:46:21.355674 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Mar 17 17:46:21.355681 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Mar 17 17:46:21.355688 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3a8030, hints 0xe, misc 0x31e1 Mar 17 17:46:21.355696 kernel: Hyper-V: Host Build 10.0.22477.1619-1-0 Mar 17 17:46:21.355710 kernel: Hyper-V: enabling crash_kexec_post_notifiers Mar 17 17:46:21.355717 kernel: rcu: Hierarchical SRCU implementation. Mar 17 17:46:21.355725 kernel: rcu: Max phase no-delay instances is 400. Mar 17 17:46:21.355732 kernel: Remapping and enabling EFI services. Mar 17 17:46:21.355739 kernel: smp: Bringing up secondary CPUs ... Mar 17 17:46:21.355748 kernel: Detected PIPT I-cache on CPU1 Mar 17 17:46:21.355755 kernel: GICv3: CPU1: found redistributor 1 region 1:0x00000000f000e000 Mar 17 17:46:21.355763 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Mar 17 17:46:21.355770 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Mar 17 17:46:21.355778 kernel: smp: Brought up 1 node, 2 CPUs Mar 17 17:46:21.355787 kernel: SMP: Total of 2 processors activated. Mar 17 17:46:21.355794 kernel: CPU features: detected: 32-bit EL0 Support Mar 17 17:46:21.355802 kernel: CPU features: detected: Instruction cache invalidation not required for I/D coherence Mar 17 17:46:21.355810 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Mar 17 17:46:21.355817 kernel: CPU features: detected: CRC32 instructions Mar 17 17:46:21.355825 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Mar 17 17:46:21.355832 kernel: CPU features: detected: LSE atomic instructions Mar 17 17:46:21.355840 kernel: CPU features: detected: Privileged Access Never Mar 17 17:46:21.355847 kernel: CPU: All CPU(s) started at EL1 Mar 17 17:46:21.355856 kernel: alternatives: applying system-wide alternatives Mar 17 17:46:21.355863 kernel: devtmpfs: initialized Mar 17 17:46:21.355871 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Mar 17 17:46:21.355879 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Mar 17 17:46:21.355886 kernel: pinctrl core: initialized pinctrl subsystem Mar 17 17:46:21.355893 kernel: SMBIOS 3.1.0 present. Mar 17 17:46:21.355901 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 09/28/2024 Mar 17 17:46:21.355908 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Mar 17 17:46:21.355916 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Mar 17 17:46:21.355925 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Mar 17 17:46:21.355933 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Mar 17 17:46:21.355940 kernel: audit: initializing netlink subsys (disabled) Mar 17 17:46:21.355948 kernel: audit: type=2000 audit(0.047:1): state=initialized audit_enabled=0 res=1 Mar 17 17:46:21.355955 kernel: thermal_sys: Registered thermal governor 'step_wise' Mar 17 17:46:21.355962 kernel: cpuidle: using governor menu Mar 17 17:46:21.355970 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Mar 17 17:46:21.355977 kernel: ASID allocator initialised with 32768 entries Mar 17 17:46:21.355985 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Mar 17 17:46:21.355994 kernel: Serial: AMBA PL011 UART driver Mar 17 17:46:21.356001 kernel: Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Mar 17 17:46:21.356009 kernel: Modules: 0 pages in range for non-PLT usage Mar 17 17:46:21.356016 kernel: Modules: 509280 pages in range for PLT usage Mar 17 17:46:21.356034 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Mar 17 17:46:21.356043 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Mar 17 17:46:21.356051 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Mar 17 17:46:21.356058 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Mar 17 17:46:21.356066 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Mar 17 17:46:21.356076 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Mar 17 17:46:21.356083 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Mar 17 17:46:21.356091 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Mar 17 17:46:21.356098 kernel: ACPI: Added _OSI(Module Device) Mar 17 17:46:21.356106 kernel: ACPI: Added _OSI(Processor Device) Mar 17 17:46:21.356113 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Mar 17 17:46:21.356120 kernel: ACPI: Added _OSI(Processor Aggregator Device) Mar 17 17:46:21.356128 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Mar 17 17:46:21.356136 kernel: ACPI: Interpreter enabled Mar 17 17:46:21.356145 kernel: ACPI: Using GIC for interrupt routing Mar 17 17:46:21.356152 kernel: ARMH0011:00: ttyAMA0 at MMIO 0xeffec000 (irq = 12, base_baud = 0) is a SBSA Mar 17 17:46:21.356160 kernel: printk: console [ttyAMA0] enabled Mar 17 17:46:21.356167 kernel: printk: bootconsole [pl11] disabled Mar 17 17:46:21.356175 kernel: ARMH0011:01: ttyAMA1 at MMIO 0xeffeb000 (irq = 13, base_baud = 0) is a SBSA Mar 17 17:46:21.356183 kernel: iommu: Default domain type: Translated Mar 17 17:46:21.356190 kernel: iommu: DMA domain TLB invalidation policy: strict mode Mar 17 17:46:21.356198 kernel: efivars: Registered efivars operations Mar 17 17:46:21.356205 kernel: vgaarb: loaded Mar 17 17:46:21.356214 kernel: clocksource: Switched to clocksource arch_sys_counter Mar 17 17:46:21.356222 kernel: VFS: Disk quotas dquot_6.6.0 Mar 17 17:46:21.356230 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Mar 17 17:46:21.356237 kernel: pnp: PnP ACPI init Mar 17 17:46:21.356244 kernel: pnp: PnP ACPI: found 0 devices Mar 17 17:46:21.356252 kernel: NET: Registered PF_INET protocol family Mar 17 17:46:21.356259 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Mar 17 17:46:21.356267 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Mar 17 17:46:21.356274 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Mar 17 17:46:21.356283 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Mar 17 17:46:21.356291 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Mar 17 17:46:21.356298 kernel: TCP: Hash tables configured (established 32768 bind 32768) Mar 17 17:46:21.356306 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Mar 17 17:46:21.356313 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Mar 17 17:46:21.356321 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Mar 17 17:46:21.356328 kernel: PCI: CLS 0 bytes, default 64 Mar 17 17:46:21.356336 kernel: kvm [1]: HYP mode not available Mar 17 17:46:21.356343 kernel: Initialise system trusted keyrings Mar 17 17:46:21.356353 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Mar 17 17:46:21.356360 kernel: Key type asymmetric registered Mar 17 17:46:21.356368 kernel: Asymmetric key parser 'x509' registered Mar 17 17:46:21.356375 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Mar 17 17:46:21.356382 kernel: io scheduler mq-deadline registered Mar 17 17:46:21.356390 kernel: io scheduler kyber registered Mar 17 17:46:21.356397 kernel: io scheduler bfq registered Mar 17 17:46:21.356405 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Mar 17 17:46:21.356412 kernel: thunder_xcv, ver 1.0 Mar 17 17:46:21.356421 kernel: thunder_bgx, ver 1.0 Mar 17 17:46:21.356428 kernel: nicpf, ver 1.0 Mar 17 17:46:21.356436 kernel: nicvf, ver 1.0 Mar 17 17:46:21.356604 kernel: rtc-efi rtc-efi.0: registered as rtc0 Mar 17 17:46:21.356724 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-03-17T17:46:20 UTC (1742233580) Mar 17 17:46:21.356738 kernel: efifb: probing for efifb Mar 17 17:46:21.356746 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Mar 17 17:46:21.356754 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Mar 17 17:46:21.356764 kernel: efifb: scrolling: redraw Mar 17 17:46:21.356772 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Mar 17 17:46:21.356780 kernel: Console: switching to colour frame buffer device 128x48 Mar 17 17:46:21.356787 kernel: fb0: EFI VGA frame buffer device Mar 17 17:46:21.356795 kernel: SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping .... Mar 17 17:46:21.356802 kernel: hid: raw HID events driver (C) Jiri Kosina Mar 17 17:46:21.356809 kernel: No ACPI PMU IRQ for CPU0 Mar 17 17:46:21.356817 kernel: No ACPI PMU IRQ for CPU1 Mar 17 17:46:21.356824 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 1 counters available Mar 17 17:46:21.356833 kernel: watchdog: Delayed init of the lockup detector failed: -19 Mar 17 17:46:21.356841 kernel: watchdog: Hard watchdog permanently disabled Mar 17 17:46:21.356848 kernel: NET: Registered PF_INET6 protocol family Mar 17 17:46:21.356856 kernel: Segment Routing with IPv6 Mar 17 17:46:21.356863 kernel: In-situ OAM (IOAM) with IPv6 Mar 17 17:46:21.356871 kernel: NET: Registered PF_PACKET protocol family Mar 17 17:46:21.356878 kernel: Key type dns_resolver registered Mar 17 17:46:21.356886 kernel: registered taskstats version 1 Mar 17 17:46:21.356894 kernel: Loading compiled-in X.509 certificates Mar 17 17:46:21.356907 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.83-flatcar: f4ff2820cf7379ce82b759137d15b536f0a99b51' Mar 17 17:46:21.356917 kernel: Key type .fscrypt registered Mar 17 17:46:21.356925 kernel: Key type fscrypt-provisioning registered Mar 17 17:46:21.356933 kernel: ima: No TPM chip found, activating TPM-bypass! Mar 17 17:46:21.356940 kernel: ima: Allocated hash algorithm: sha1 Mar 17 17:46:21.356947 kernel: ima: No architecture policies found Mar 17 17:46:21.356955 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Mar 17 17:46:21.356962 kernel: clk: Disabling unused clocks Mar 17 17:46:21.356970 kernel: Freeing unused kernel memory: 38336K Mar 17 17:46:21.356983 kernel: Run /init as init process Mar 17 17:46:21.356992 kernel: with arguments: Mar 17 17:46:21.357000 kernel: /init Mar 17 17:46:21.357007 kernel: with environment: Mar 17 17:46:21.357015 kernel: HOME=/ Mar 17 17:46:21.357022 kernel: TERM=linux Mar 17 17:46:21.358981 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Mar 17 17:46:21.358993 systemd[1]: Successfully made /usr/ read-only. Mar 17 17:46:21.359011 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) Mar 17 17:46:21.359020 systemd[1]: Detected virtualization microsoft. Mar 17 17:46:21.359047 systemd[1]: Detected architecture arm64. Mar 17 17:46:21.359056 systemd[1]: Running in initrd. Mar 17 17:46:21.359064 systemd[1]: No hostname configured, using default hostname. Mar 17 17:46:21.359073 systemd[1]: Hostname set to . Mar 17 17:46:21.359081 systemd[1]: Initializing machine ID from random generator. Mar 17 17:46:21.359089 systemd[1]: Queued start job for default target initrd.target. Mar 17 17:46:21.359100 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 17 17:46:21.359108 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 17 17:46:21.359117 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Mar 17 17:46:21.359126 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Mar 17 17:46:21.359134 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Mar 17 17:46:21.359143 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Mar 17 17:46:21.359152 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Mar 17 17:46:21.359162 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Mar 17 17:46:21.359171 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 17 17:46:21.359179 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Mar 17 17:46:21.359187 systemd[1]: Reached target paths.target - Path Units. Mar 17 17:46:21.359195 systemd[1]: Reached target slices.target - Slice Units. Mar 17 17:46:21.359203 systemd[1]: Reached target swap.target - Swaps. Mar 17 17:46:21.359211 systemd[1]: Reached target timers.target - Timer Units. Mar 17 17:46:21.359219 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Mar 17 17:46:21.359229 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Mar 17 17:46:21.359237 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Mar 17 17:46:21.359245 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Mar 17 17:46:21.359254 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Mar 17 17:46:21.359262 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Mar 17 17:46:21.359270 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Mar 17 17:46:21.359278 systemd[1]: Reached target sockets.target - Socket Units. Mar 17 17:46:21.359286 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Mar 17 17:46:21.359294 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Mar 17 17:46:21.359304 systemd[1]: Finished network-cleanup.service - Network Cleanup. Mar 17 17:46:21.359312 systemd[1]: Starting systemd-fsck-usr.service... Mar 17 17:46:21.359320 systemd[1]: Starting systemd-journald.service - Journal Service... Mar 17 17:46:21.359329 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Mar 17 17:46:21.359370 systemd-journald[218]: Collecting audit messages is disabled. Mar 17 17:46:21.359394 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 17 17:46:21.359403 systemd-journald[218]: Journal started Mar 17 17:46:21.359422 systemd-journald[218]: Runtime Journal (/run/log/journal/9659efd6205f49b29c6e9e6b4ffe798c) is 8M, max 78.5M, 70.5M free. Mar 17 17:46:21.356963 systemd-modules-load[220]: Inserted module 'overlay' Mar 17 17:46:21.388746 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Mar 17 17:46:21.388797 systemd[1]: Started systemd-journald.service - Journal Service. Mar 17 17:46:21.388812 kernel: Bridge firewalling registered Mar 17 17:46:21.392570 systemd-modules-load[220]: Inserted module 'br_netfilter' Mar 17 17:46:21.400057 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Mar 17 17:46:21.406501 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Mar 17 17:46:21.425313 systemd[1]: Finished systemd-fsck-usr.service. Mar 17 17:46:21.438054 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Mar 17 17:46:21.447629 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 17 17:46:21.469335 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 17 17:46:21.477203 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Mar 17 17:46:21.504277 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Mar 17 17:46:21.533191 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Mar 17 17:46:21.543293 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 17 17:46:21.558885 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Mar 17 17:46:21.572082 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Mar 17 17:46:21.592518 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 17 17:46:21.610271 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Mar 17 17:46:21.620221 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Mar 17 17:46:21.644787 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Mar 17 17:46:21.667123 dracut-cmdline[254]: dracut-dracut-053 Mar 17 17:46:21.667123 dracut-cmdline[254]: 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=f8298a09e890fc732131b7281e24befaf65b596eb5216e969c8eca4cab4a2b3a Mar 17 17:46:21.674049 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 17 17:46:21.721481 systemd-resolved[256]: Positive Trust Anchors: Mar 17 17:46:21.721491 systemd-resolved[256]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 17 17:46:21.721522 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 Mar 17 17:46:21.724588 systemd-resolved[256]: Defaulting to hostname 'linux'. Mar 17 17:46:21.725692 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Mar 17 17:46:21.738131 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Mar 17 17:46:21.868061 kernel: SCSI subsystem initialized Mar 17 17:46:21.876049 kernel: Loading iSCSI transport class v2.0-870. Mar 17 17:46:21.886047 kernel: iscsi: registered transport (tcp) Mar 17 17:46:21.904321 kernel: iscsi: registered transport (qla4xxx) Mar 17 17:46:21.904380 kernel: QLogic iSCSI HBA Driver Mar 17 17:46:21.944817 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Mar 17 17:46:21.959295 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Mar 17 17:46:21.995838 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Mar 17 17:46:21.995929 kernel: device-mapper: uevent: version 1.0.3 Mar 17 17:46:22.002205 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Mar 17 17:46:22.053052 kernel: raid6: neonx8 gen() 15755 MB/s Mar 17 17:46:22.073038 kernel: raid6: neonx4 gen() 15793 MB/s Mar 17 17:46:22.093037 kernel: raid6: neonx2 gen() 13160 MB/s Mar 17 17:46:22.115041 kernel: raid6: neonx1 gen() 10500 MB/s Mar 17 17:46:22.135037 kernel: raid6: int64x8 gen() 6786 MB/s Mar 17 17:46:22.155036 kernel: raid6: int64x4 gen() 7354 MB/s Mar 17 17:46:22.176040 kernel: raid6: int64x2 gen() 6112 MB/s Mar 17 17:46:22.199472 kernel: raid6: int64x1 gen() 5059 MB/s Mar 17 17:46:22.199493 kernel: raid6: using algorithm neonx4 gen() 15793 MB/s Mar 17 17:46:22.223346 kernel: raid6: .... xor() 12343 MB/s, rmw enabled Mar 17 17:46:22.223368 kernel: raid6: using neon recovery algorithm Mar 17 17:46:22.236278 kernel: xor: measuring software checksum speed Mar 17 17:46:22.236295 kernel: 8regs : 21601 MB/sec Mar 17 17:46:22.240010 kernel: 32regs : 21664 MB/sec Mar 17 17:46:22.243874 kernel: arm64_neon : 27946 MB/sec Mar 17 17:46:22.248336 kernel: xor: using function: arm64_neon (27946 MB/sec) Mar 17 17:46:22.299240 kernel: Btrfs loaded, zoned=no, fsverity=no Mar 17 17:46:22.310184 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Mar 17 17:46:22.325190 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 17 17:46:22.350296 systemd-udevd[441]: Using default interface naming scheme 'v255'. Mar 17 17:46:22.355669 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 17 17:46:22.375163 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Mar 17 17:46:22.405791 dracut-pre-trigger[453]: rd.md=0: removing MD RAID activation Mar 17 17:46:22.435731 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Mar 17 17:46:22.456283 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Mar 17 17:46:22.498921 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Mar 17 17:46:22.522277 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Mar 17 17:46:22.556752 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Mar 17 17:46:22.573339 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Mar 17 17:46:22.589126 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 17 17:46:22.608392 systemd[1]: Reached target remote-fs.target - Remote File Systems. Mar 17 17:46:22.615665 kernel: hv_vmbus: Vmbus version:5.3 Mar 17 17:46:22.630382 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Mar 17 17:46:22.662771 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Mar 17 17:46:22.712002 kernel: pps_core: LinuxPPS API ver. 1 registered Mar 17 17:46:22.712829 kernel: hv_vmbus: registering driver hyperv_keyboard Mar 17 17:46:22.712842 kernel: hv_vmbus: registering driver hid_hyperv Mar 17 17:46:22.712862 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/MSFT1000:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input0 Mar 17 17:46:22.712873 kernel: hv_vmbus: registering driver hv_storvsc Mar 17 17:46:22.712882 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Mar 17 17:46:22.712892 kernel: scsi host1: storvsc_host_t Mar 17 17:46:22.713084 kernel: hv_vmbus: registering driver hv_netvsc Mar 17 17:46:22.713095 kernel: scsi host0: storvsc_host_t Mar 17 17:46:22.697374 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Mar 17 17:46:22.761263 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Mar 17 17:46:22.761335 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Mar 17 17:46:22.761348 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Mar 17 17:46:22.761364 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Mar 17 17:46:22.697550 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 17 17:46:22.754639 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 17 17:46:22.767117 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 17 17:46:22.767417 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 17 17:46:22.826439 kernel: PTP clock support registered Mar 17 17:46:22.826463 kernel: hv_utils: Registering HyperV Utility Driver Mar 17 17:46:22.826473 kernel: hv_vmbus: registering driver hv_utils Mar 17 17:46:22.826483 kernel: hv_utils: Heartbeat IC version 3.0 Mar 17 17:46:22.826492 kernel: hv_utils: Shutdown IC version 3.2 Mar 17 17:46:22.830111 kernel: hv_utils: TimeSync IC version 4.0 Mar 17 17:46:22.783983 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Mar 17 17:46:22.493781 kernel: hv_netvsc 00224878-88aa-0022-4878-88aa00224878 eth0: VF slot 1 added Mar 17 17:46:22.694236 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Mar 17 17:46:22.708030 kernel: hv_vmbus: registering driver hv_pci Mar 17 17:46:22.708047 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Mar 17 17:46:22.708056 kernel: hv_pci b4da77d1-238a-481d-a403-cefc2f877255: PCI VMBus probing: Using version 0x10004 Mar 17 17:46:22.798059 kernel: hv_pci b4da77d1-238a-481d-a403-cefc2f877255: PCI host bridge to bus 238a:00 Mar 17 17:46:22.798191 systemd-journald[218]: Time jumped backwards, rotating. Mar 17 17:46:22.798246 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Mar 17 17:46:22.798358 kernel: pci_bus 238a:00: root bus resource [mem 0xfc0000000-0xfc00fffff window] Mar 17 17:46:22.798502 kernel: pci_bus 238a:00: No busn resource found for root bus, will use [bus 00-ff] Mar 17 17:46:22.798593 kernel: pci 238a:00:02.0: [15b3:1018] type 00 class 0x020000 Mar 17 17:46:22.798697 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Mar 17 17:46:22.798794 kernel: pci 238a:00:02.0: reg 0x10: [mem 0xfc0000000-0xfc00fffff 64bit pref] Mar 17 17:46:22.798883 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Mar 17 17:46:22.798976 kernel: pci 238a:00:02.0: enabling Extended Tags Mar 17 17:46:22.799066 kernel: sd 0:0:0:0: [sda] Write Protect is off Mar 17 17:46:22.799153 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Mar 17 17:46:22.799240 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Mar 17 17:46:22.799326 kernel: pci 238a:00:02.0: 0.000 Gb/s available PCIe bandwidth, limited by Unknown x0 link at 238a:00:02.0 (capable of 126.016 Gb/s with 8.0 GT/s PCIe x16 link) Mar 17 17:46:22.799413 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Mar 17 17:46:22.799426 kernel: pci_bus 238a:00: busn_res: [bus 00-ff] end is updated to 00 Mar 17 17:46:22.799525 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Mar 17 17:46:22.799616 kernel: pci 238a:00:02.0: BAR 0: assigned [mem 0xfc0000000-0xfc00fffff 64bit pref] Mar 17 17:46:22.805394 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 17 17:46:22.460145 systemd-resolved[256]: Clock change detected. Flushing caches. Mar 17 17:46:22.498490 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 17 17:46:22.708112 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Mar 17 17:46:22.798251 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 17 17:46:22.863630 kernel: mlx5_core 238a:00:02.0: enabling device (0000 -> 0002) Mar 17 17:46:23.170301 kernel: mlx5_core 238a:00:02.0: firmware version: 16.31.2424 Mar 17 17:46:23.170472 kernel: hv_netvsc 00224878-88aa-0022-4878-88aa00224878 eth0: VF registering: eth1 Mar 17 17:46:23.170578 kernel: mlx5_core 238a:00:02.0 eth1: joined to eth0 Mar 17 17:46:23.170683 kernel: mlx5_core 238a:00:02.0: MLX5E: StrdRq(1) RqSz(8) StrdSz(2048) RxCqeCmprss(0 basic) Mar 17 17:46:23.178469 kernel: mlx5_core 238a:00:02.0 enP9098s1: renamed from eth1 Mar 17 17:46:23.438458 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Mar 17 17:46:23.472644 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/sda6 scanned by (udev-worker) (490) Mar 17 17:46:23.491235 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Mar 17 17:46:23.514476 kernel: BTRFS: device fsid 5ecee764-de70-4de1-8711-3798360e0d13 devid 1 transid 39 /dev/sda3 scanned by (udev-worker) (492) Mar 17 17:46:23.532618 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Mar 17 17:46:23.552510 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Mar 17 17:46:23.577323 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Mar 17 17:46:23.602653 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Mar 17 17:46:23.629472 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Mar 17 17:46:24.645531 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Mar 17 17:46:24.646307 disk-uuid[603]: The operation has completed successfully. Mar 17 17:46:24.706023 systemd[1]: disk-uuid.service: Deactivated successfully. Mar 17 17:46:24.707470 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Mar 17 17:46:24.771641 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Mar 17 17:46:24.785030 sh[689]: Success Mar 17 17:46:24.815706 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Mar 17 17:46:25.026635 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Mar 17 17:46:25.049569 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Mar 17 17:46:25.056889 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Mar 17 17:46:25.093805 kernel: BTRFS info (device dm-0): first mount of filesystem 5ecee764-de70-4de1-8711-3798360e0d13 Mar 17 17:46:25.093851 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Mar 17 17:46:25.101135 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Mar 17 17:46:25.106148 kernel: BTRFS info (device dm-0): disabling log replay at mount time Mar 17 17:46:25.110311 kernel: BTRFS info (device dm-0): using free space tree Mar 17 17:46:25.409237 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Mar 17 17:46:25.414821 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Mar 17 17:46:25.434689 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Mar 17 17:46:25.442556 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Mar 17 17:46:25.482728 kernel: BTRFS info (device sda6): first mount of filesystem 8369c249-c0a6-415d-8511-1f18dbf3bf45 Mar 17 17:46:25.482774 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Mar 17 17:46:25.487022 kernel: BTRFS info (device sda6): using free space tree Mar 17 17:46:25.509476 kernel: BTRFS info (device sda6): auto enabling async discard Mar 17 17:46:25.519020 systemd[1]: mnt-oem.mount: Deactivated successfully. Mar 17 17:46:25.531840 kernel: BTRFS info (device sda6): last unmount of filesystem 8369c249-c0a6-415d-8511-1f18dbf3bf45 Mar 17 17:46:25.539343 systemd[1]: Finished ignition-setup.service - Ignition (setup). Mar 17 17:46:25.553667 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Mar 17 17:46:25.578155 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Mar 17 17:46:25.596591 systemd[1]: Starting systemd-networkd.service - Network Configuration... Mar 17 17:46:25.626025 systemd-networkd[874]: lo: Link UP Mar 17 17:46:25.626037 systemd-networkd[874]: lo: Gained carrier Mar 17 17:46:25.627653 systemd-networkd[874]: Enumeration completed Mar 17 17:46:25.627741 systemd[1]: Started systemd-networkd.service - Network Configuration. Mar 17 17:46:25.635614 systemd[1]: Reached target network.target - Network. Mar 17 17:46:25.635954 systemd-networkd[874]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 17 17:46:25.635958 systemd-networkd[874]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 17 17:46:25.703462 kernel: mlx5_core 238a:00:02.0 enP9098s1: Link up Mar 17 17:46:25.788459 kernel: hv_netvsc 00224878-88aa-0022-4878-88aa00224878 eth0: Data path switched to VF: enP9098s1 Mar 17 17:46:25.788104 systemd-networkd[874]: enP9098s1: Link UP Mar 17 17:46:25.788183 systemd-networkd[874]: eth0: Link UP Mar 17 17:46:25.788308 systemd-networkd[874]: eth0: Gained carrier Mar 17 17:46:25.788316 systemd-networkd[874]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 17 17:46:25.800671 systemd-networkd[874]: enP9098s1: Gained carrier Mar 17 17:46:25.842081 systemd-networkd[874]: eth0: DHCPv4 address 10.200.20.33/24, gateway 10.200.20.1 acquired from 168.63.129.16 Mar 17 17:46:26.346986 ignition[841]: Ignition 2.20.0 Mar 17 17:46:26.346997 ignition[841]: Stage: fetch-offline Mar 17 17:46:26.352737 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Mar 17 17:46:26.347034 ignition[841]: no configs at "/usr/lib/ignition/base.d" Mar 17 17:46:26.347042 ignition[841]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 17:46:26.347144 ignition[841]: parsed url from cmdline: "" Mar 17 17:46:26.347147 ignition[841]: no config URL provided Mar 17 17:46:26.347152 ignition[841]: reading system config file "/usr/lib/ignition/user.ign" Mar 17 17:46:26.377634 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Mar 17 17:46:26.347159 ignition[841]: no config at "/usr/lib/ignition/user.ign" Mar 17 17:46:26.347164 ignition[841]: failed to fetch config: resource requires networking Mar 17 17:46:26.347328 ignition[841]: Ignition finished successfully Mar 17 17:46:26.398805 ignition[885]: Ignition 2.20.0 Mar 17 17:46:26.398811 ignition[885]: Stage: fetch Mar 17 17:46:26.398979 ignition[885]: no configs at "/usr/lib/ignition/base.d" Mar 17 17:46:26.398989 ignition[885]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 17:46:26.399068 ignition[885]: parsed url from cmdline: "" Mar 17 17:46:26.399072 ignition[885]: no config URL provided Mar 17 17:46:26.399077 ignition[885]: reading system config file "/usr/lib/ignition/user.ign" Mar 17 17:46:26.399083 ignition[885]: no config at "/usr/lib/ignition/user.ign" Mar 17 17:46:26.399108 ignition[885]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Mar 17 17:46:26.505346 ignition[885]: GET result: OK Mar 17 17:46:26.505402 ignition[885]: config has been read from IMDS userdata Mar 17 17:46:26.505416 ignition[885]: parsing config with SHA512: 4db1994207d67f163a60137e16be5e2ed24773002d641d757c16920a6d09816c20fd9e813169b430d6edc845bb36aedbbe56fd140ca72003044e7347b2ed1a72 Mar 17 17:46:26.509199 unknown[885]: fetched base config from "system" Mar 17 17:46:26.509382 ignition[885]: fetch: fetch complete Mar 17 17:46:26.509206 unknown[885]: fetched base config from "system" Mar 17 17:46:26.509386 ignition[885]: fetch: fetch passed Mar 17 17:46:26.509211 unknown[885]: fetched user config from "azure" Mar 17 17:46:26.509423 ignition[885]: Ignition finished successfully Mar 17 17:46:26.515058 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Mar 17 17:46:26.538653 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Mar 17 17:46:26.569757 ignition[892]: Ignition 2.20.0 Mar 17 17:46:26.572886 ignition[892]: Stage: kargs Mar 17 17:46:26.573056 ignition[892]: no configs at "/usr/lib/ignition/base.d" Mar 17 17:46:26.581569 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Mar 17 17:46:26.573066 ignition[892]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 17:46:26.573604 ignition[892]: kargs: kargs passed Mar 17 17:46:26.573643 ignition[892]: Ignition finished successfully Mar 17 17:46:26.601682 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Mar 17 17:46:26.621238 ignition[898]: Ignition 2.20.0 Mar 17 17:46:26.621249 ignition[898]: Stage: disks Mar 17 17:46:26.625688 systemd[1]: Finished ignition-disks.service - Ignition (disks). Mar 17 17:46:26.621404 ignition[898]: no configs at "/usr/lib/ignition/base.d" Mar 17 17:46:26.633835 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Mar 17 17:46:26.621414 ignition[898]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 17:46:26.645162 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Mar 17 17:46:26.621989 ignition[898]: disks: disks passed Mar 17 17:46:26.656417 systemd[1]: Reached target local-fs.target - Local File Systems. Mar 17 17:46:26.622026 ignition[898]: Ignition finished successfully Mar 17 17:46:26.667675 systemd[1]: Reached target sysinit.target - System Initialization. Mar 17 17:46:26.679085 systemd[1]: Reached target basic.target - Basic System. Mar 17 17:46:26.713722 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Mar 17 17:46:26.769601 systemd-fsck[906]: ROOT: clean, 14/7326000 files, 477710/7359488 blocks Mar 17 17:46:26.775640 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Mar 17 17:46:26.792672 systemd[1]: Mounting sysroot.mount - /sysroot... Mar 17 17:46:26.852467 kernel: EXT4-fs (sda9): mounted filesystem 3914ef65-c5cd-468c-8ee7-964383d8e9e2 r/w with ordered data mode. Quota mode: none. Mar 17 17:46:26.852885 systemd[1]: Mounted sysroot.mount - /sysroot. Mar 17 17:46:26.857945 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Mar 17 17:46:26.903519 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Mar 17 17:46:26.911563 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Mar 17 17:46:26.922617 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Mar 17 17:46:26.946068 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/sda6 scanned by mount (917) Mar 17 17:46:26.959055 kernel: BTRFS info (device sda6): first mount of filesystem 8369c249-c0a6-415d-8511-1f18dbf3bf45 Mar 17 17:46:26.959092 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Mar 17 17:46:26.953029 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Mar 17 17:46:26.983166 kernel: BTRFS info (device sda6): using free space tree Mar 17 17:46:26.953083 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Mar 17 17:46:26.971656 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Mar 17 17:46:27.000649 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Mar 17 17:46:27.018227 kernel: BTRFS info (device sda6): auto enabling async discard Mar 17 17:46:27.018827 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Mar 17 17:46:27.531835 coreos-metadata[919]: Mar 17 17:46:27.531 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Mar 17 17:46:27.552289 coreos-metadata[919]: Mar 17 17:46:27.552 INFO Fetch successful Mar 17 17:46:27.558161 coreos-metadata[919]: Mar 17 17:46:27.557 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Mar 17 17:46:27.569037 coreos-metadata[919]: Mar 17 17:46:27.568 INFO Fetch successful Mar 17 17:46:27.577303 coreos-metadata[919]: Mar 17 17:46:27.577 INFO wrote hostname ci-4230.1.0-a-6ce3c60530 to /sysroot/etc/hostname Mar 17 17:46:27.586560 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Mar 17 17:46:27.645615 systemd-networkd[874]: eth0: Gained IPv6LL Mar 17 17:46:27.790916 initrd-setup-root[947]: cut: /sysroot/etc/passwd: No such file or directory Mar 17 17:46:27.814508 initrd-setup-root[954]: cut: /sysroot/etc/group: No such file or directory Mar 17 17:46:27.823803 initrd-setup-root[961]: cut: /sysroot/etc/shadow: No such file or directory Mar 17 17:46:27.837594 systemd-networkd[874]: enP9098s1: Gained IPv6LL Mar 17 17:46:27.850303 initrd-setup-root[968]: cut: /sysroot/etc/gshadow: No such file or directory Mar 17 17:46:28.770373 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Mar 17 17:46:28.784624 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Mar 17 17:46:28.791651 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Mar 17 17:46:28.816657 kernel: BTRFS info (device sda6): last unmount of filesystem 8369c249-c0a6-415d-8511-1f18dbf3bf45 Mar 17 17:46:28.815875 systemd[1]: sysroot-oem.mount: Deactivated successfully. Mar 17 17:46:28.838473 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Mar 17 17:46:28.853423 ignition[1037]: INFO : Ignition 2.20.0 Mar 17 17:46:28.853423 ignition[1037]: INFO : Stage: mount Mar 17 17:46:28.861926 ignition[1037]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 17 17:46:28.861926 ignition[1037]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 17:46:28.861926 ignition[1037]: INFO : mount: mount passed Mar 17 17:46:28.861926 ignition[1037]: INFO : Ignition finished successfully Mar 17 17:46:28.859243 systemd[1]: Finished ignition-mount.service - Ignition (mount). Mar 17 17:46:28.883589 systemd[1]: Starting ignition-files.service - Ignition (files)... Mar 17 17:46:28.904639 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Mar 17 17:46:28.942581 kernel: BTRFS: device label OEM devid 1 transid 17 /dev/sda6 scanned by mount (1047) Mar 17 17:46:28.942623 kernel: BTRFS info (device sda6): first mount of filesystem 8369c249-c0a6-415d-8511-1f18dbf3bf45 Mar 17 17:46:28.948501 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Mar 17 17:46:28.952428 kernel: BTRFS info (device sda6): using free space tree Mar 17 17:46:28.959471 kernel: BTRFS info (device sda6): auto enabling async discard Mar 17 17:46:28.960281 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Mar 17 17:46:28.986399 ignition[1065]: INFO : Ignition 2.20.0 Mar 17 17:46:28.986399 ignition[1065]: INFO : Stage: files Mar 17 17:46:28.993942 ignition[1065]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 17 17:46:28.993942 ignition[1065]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 17:46:28.993942 ignition[1065]: DEBUG : files: compiled without relabeling support, skipping Mar 17 17:46:29.034608 ignition[1065]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Mar 17 17:46:29.034608 ignition[1065]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Mar 17 17:46:29.123364 ignition[1065]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Mar 17 17:46:29.131141 ignition[1065]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Mar 17 17:46:29.131141 ignition[1065]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Mar 17 17:46:29.123859 unknown[1065]: wrote ssh authorized keys file for user: core Mar 17 17:46:29.151083 ignition[1065]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Mar 17 17:46:29.151083 ignition[1065]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Mar 17 17:46:29.151083 ignition[1065]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Mar 17 17:46:29.151083 ignition[1065]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Mar 17 17:46:29.151083 ignition[1065]: INFO : files: files passed Mar 17 17:46:29.151083 ignition[1065]: INFO : Ignition finished successfully Mar 17 17:46:29.147760 systemd[1]: Finished ignition-files.service - Ignition (files). Mar 17 17:46:29.177960 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Mar 17 17:46:29.194612 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Mar 17 17:46:29.212404 systemd[1]: ignition-quench.service: Deactivated successfully. Mar 17 17:46:29.254424 initrd-setup-root-after-ignition[1093]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Mar 17 17:46:29.254424 initrd-setup-root-after-ignition[1093]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Mar 17 17:46:29.212505 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Mar 17 17:46:29.292263 initrd-setup-root-after-ignition[1097]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Mar 17 17:46:29.242363 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Mar 17 17:46:29.249989 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Mar 17 17:46:29.272586 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Mar 17 17:46:29.310469 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Mar 17 17:46:29.310591 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Mar 17 17:46:29.321363 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Mar 17 17:46:29.333493 systemd[1]: Reached target initrd.target - Initrd Default Target. Mar 17 17:46:29.344545 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Mar 17 17:46:29.369643 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Mar 17 17:46:29.388911 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Mar 17 17:46:29.403816 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Mar 17 17:46:29.421506 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Mar 17 17:46:29.427854 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 17 17:46:29.440254 systemd[1]: Stopped target timers.target - Timer Units. Mar 17 17:46:29.451463 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Mar 17 17:46:29.451589 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Mar 17 17:46:29.468164 systemd[1]: Stopped target initrd.target - Initrd Default Target. Mar 17 17:46:29.473997 systemd[1]: Stopped target basic.target - Basic System. Mar 17 17:46:29.485485 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Mar 17 17:46:29.496737 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Mar 17 17:46:29.507784 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Mar 17 17:46:29.519584 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Mar 17 17:46:29.531138 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Mar 17 17:46:29.543800 systemd[1]: Stopped target sysinit.target - System Initialization. Mar 17 17:46:29.555103 systemd[1]: Stopped target local-fs.target - Local File Systems. Mar 17 17:46:29.567459 systemd[1]: Stopped target swap.target - Swaps. Mar 17 17:46:29.581551 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Mar 17 17:46:29.581679 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Mar 17 17:46:29.596730 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Mar 17 17:46:29.602794 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 17 17:46:29.614226 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Mar 17 17:46:29.619618 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 17 17:46:29.626802 systemd[1]: dracut-initqueue.service: Deactivated successfully. Mar 17 17:46:29.626941 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Mar 17 17:46:29.644246 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Mar 17 17:46:29.644420 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Mar 17 17:46:29.656126 systemd[1]: ignition-files.service: Deactivated successfully. Mar 17 17:46:29.656273 systemd[1]: Stopped ignition-files.service - Ignition (files). Mar 17 17:46:29.668805 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Mar 17 17:46:29.668960 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Mar 17 17:46:29.741519 ignition[1117]: INFO : Ignition 2.20.0 Mar 17 17:46:29.741519 ignition[1117]: INFO : Stage: umount Mar 17 17:46:29.741519 ignition[1117]: INFO : no configs at "/usr/lib/ignition/base.d" Mar 17 17:46:29.741519 ignition[1117]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 17:46:29.707568 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Mar 17 17:46:29.781852 ignition[1117]: INFO : umount: umount passed Mar 17 17:46:29.781852 ignition[1117]: INFO : Ignition finished successfully Mar 17 17:46:29.723960 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Mar 17 17:46:29.736348 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Mar 17 17:46:29.736533 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Mar 17 17:46:29.743527 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Mar 17 17:46:29.743625 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Mar 17 17:46:29.765798 systemd[1]: sysroot-boot.mount: Deactivated successfully. Mar 17 17:46:29.766764 systemd[1]: ignition-mount.service: Deactivated successfully. Mar 17 17:46:29.768470 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Mar 17 17:46:29.778599 systemd[1]: initrd-cleanup.service: Deactivated successfully. Mar 17 17:46:29.778698 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Mar 17 17:46:29.789952 systemd[1]: ignition-disks.service: Deactivated successfully. Mar 17 17:46:29.790006 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Mar 17 17:46:29.800532 systemd[1]: ignition-kargs.service: Deactivated successfully. Mar 17 17:46:29.800581 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Mar 17 17:46:29.809321 systemd[1]: ignition-fetch.service: Deactivated successfully. Mar 17 17:46:29.809366 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Mar 17 17:46:29.819876 systemd[1]: Stopped target network.target - Network. Mar 17 17:46:29.829847 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Mar 17 17:46:29.829903 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Mar 17 17:46:29.842158 systemd[1]: Stopped target paths.target - Path Units. Mar 17 17:46:29.852180 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Mar 17 17:46:29.862475 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 17 17:46:29.869281 systemd[1]: Stopped target slices.target - Slice Units. Mar 17 17:46:29.879067 systemd[1]: Stopped target sockets.target - Socket Units. Mar 17 17:46:29.889570 systemd[1]: iscsid.socket: Deactivated successfully. Mar 17 17:46:29.889615 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Mar 17 17:46:29.899639 systemd[1]: iscsiuio.socket: Deactivated successfully. Mar 17 17:46:29.899671 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Mar 17 17:46:29.911481 systemd[1]: ignition-setup.service: Deactivated successfully. Mar 17 17:46:29.911527 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Mar 17 17:46:29.921994 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Mar 17 17:46:29.922036 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Mar 17 17:46:29.932671 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Mar 17 17:46:29.943028 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Mar 17 17:46:29.953831 systemd[1]: sysroot-boot.service: Deactivated successfully. Mar 17 17:46:29.953913 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Mar 17 17:46:29.965192 systemd[1]: systemd-resolved.service: Deactivated successfully. Mar 17 17:46:29.965295 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Mar 17 17:46:29.987306 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Mar 17 17:46:29.987597 systemd[1]: systemd-networkd.service: Deactivated successfully. Mar 17 17:46:29.987702 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Mar 17 17:46:30.003319 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Mar 17 17:46:30.004556 systemd[1]: systemd-networkd.socket: Deactivated successfully. Mar 17 17:46:30.004612 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Mar 17 17:46:30.240426 kernel: hv_netvsc 00224878-88aa-0022-4878-88aa00224878 eth0: Data path switched from VF: enP9098s1 Mar 17 17:46:30.014639 systemd[1]: initrd-setup-root.service: Deactivated successfully. Mar 17 17:46:30.014701 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Mar 17 17:46:30.042564 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Mar 17 17:46:30.052195 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Mar 17 17:46:30.052248 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Mar 17 17:46:30.065963 systemd[1]: systemd-sysctl.service: Deactivated successfully. Mar 17 17:46:30.066012 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Mar 17 17:46:30.084270 systemd[1]: systemd-modules-load.service: Deactivated successfully. Mar 17 17:46:30.084311 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Mar 17 17:46:30.091564 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Mar 17 17:46:30.091606 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 17 17:46:30.108989 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 17 17:46:30.120311 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Mar 17 17:46:30.120383 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Mar 17 17:46:30.139121 systemd[1]: systemd-udevd.service: Deactivated successfully. Mar 17 17:46:30.139285 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 17 17:46:30.151165 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Mar 17 17:46:30.151206 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Mar 17 17:46:30.161571 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Mar 17 17:46:30.161604 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Mar 17 17:46:30.172317 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Mar 17 17:46:30.172364 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Mar 17 17:46:30.190507 systemd[1]: dracut-cmdline.service: Deactivated successfully. Mar 17 17:46:30.190559 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Mar 17 17:46:30.207186 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Mar 17 17:46:30.207234 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Mar 17 17:46:30.253644 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Mar 17 17:46:30.268510 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Mar 17 17:46:30.268579 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 17 17:46:30.285637 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Mar 17 17:46:30.285689 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Mar 17 17:46:30.292492 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Mar 17 17:46:30.292538 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Mar 17 17:46:30.304157 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 17 17:46:30.304207 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Mar 17 17:46:30.322338 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Mar 17 17:46:30.322397 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Mar 17 17:46:30.531932 systemd-journald[218]: Received SIGTERM from PID 1 (systemd). Mar 17 17:46:30.322699 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Mar 17 17:46:30.322845 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Mar 17 17:46:30.361546 systemd[1]: network-cleanup.service: Deactivated successfully. Mar 17 17:46:30.361670 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Mar 17 17:46:30.373415 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Mar 17 17:46:30.403657 systemd[1]: Starting initrd-switch-root.service - Switch Root... Mar 17 17:46:30.419461 systemd[1]: Switching root. Mar 17 17:46:30.569006 systemd-journald[218]: Journal stopped Mar 17 17:46:34.375755 kernel: SELinux: policy capability network_peer_controls=1 Mar 17 17:46:34.375780 kernel: SELinux: policy capability open_perms=1 Mar 17 17:46:34.375790 kernel: SELinux: policy capability extended_socket_class=1 Mar 17 17:46:34.375797 kernel: SELinux: policy capability always_check_network=0 Mar 17 17:46:34.375807 kernel: SELinux: policy capability cgroup_seclabel=1 Mar 17 17:46:34.375815 kernel: SELinux: policy capability nnp_nosuid_transition=1 Mar 17 17:46:34.375824 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Mar 17 17:46:34.375831 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Mar 17 17:46:34.375839 kernel: audit: type=1403 audit(1742233591.314:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Mar 17 17:46:34.375849 systemd[1]: Successfully loaded SELinux policy in 139.718ms. Mar 17 17:46:34.375861 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 11.018ms. Mar 17 17:46:34.375871 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) Mar 17 17:46:34.375879 systemd[1]: Detected virtualization microsoft. Mar 17 17:46:34.375888 systemd[1]: Detected architecture arm64. Mar 17 17:46:34.375897 systemd[1]: Detected first boot. Mar 17 17:46:34.375907 systemd[1]: Hostname set to . Mar 17 17:46:34.375916 systemd[1]: Initializing machine ID from random generator. Mar 17 17:46:34.375925 zram_generator::config[1160]: No configuration found. Mar 17 17:46:34.375934 kernel: NET: Registered PF_VSOCK protocol family Mar 17 17:46:34.375942 systemd[1]: Populated /etc with preset unit settings. Mar 17 17:46:34.375952 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Mar 17 17:46:34.375960 systemd[1]: initrd-switch-root.service: Deactivated successfully. Mar 17 17:46:34.375973 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Mar 17 17:46:34.375982 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Mar 17 17:46:34.375991 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Mar 17 17:46:34.376000 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Mar 17 17:46:34.376009 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Mar 17 17:46:34.376018 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Mar 17 17:46:34.376027 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Mar 17 17:46:34.376038 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Mar 17 17:46:34.376047 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Mar 17 17:46:34.376056 systemd[1]: Created slice user.slice - User and Session Slice. Mar 17 17:46:34.376065 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Mar 17 17:46:34.376074 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Mar 17 17:46:34.376083 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Mar 17 17:46:34.376092 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Mar 17 17:46:34.376101 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Mar 17 17:46:34.376111 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Mar 17 17:46:34.376120 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Mar 17 17:46:34.376129 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Mar 17 17:46:34.376140 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Mar 17 17:46:34.376149 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Mar 17 17:46:34.376159 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Mar 17 17:46:34.376169 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Mar 17 17:46:34.376178 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Mar 17 17:46:34.376188 systemd[1]: Reached target remote-fs.target - Remote File Systems. Mar 17 17:46:34.376198 systemd[1]: Reached target slices.target - Slice Units. Mar 17 17:46:34.376206 systemd[1]: Reached target swap.target - Swaps. Mar 17 17:46:34.376216 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Mar 17 17:46:34.376225 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Mar 17 17:46:34.376234 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Mar 17 17:46:34.376245 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Mar 17 17:46:34.376254 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Mar 17 17:46:34.376264 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Mar 17 17:46:34.376273 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Mar 17 17:46:34.376282 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Mar 17 17:46:34.376291 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Mar 17 17:46:34.376301 systemd[1]: Mounting media.mount - External Media Directory... Mar 17 17:46:34.376311 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Mar 17 17:46:34.376321 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Mar 17 17:46:34.376330 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Mar 17 17:46:34.376340 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). Mar 17 17:46:34.376349 systemd[1]: Reached target machines.target - Containers. Mar 17 17:46:34.376358 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Mar 17 17:46:34.376369 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 17 17:46:34.376378 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Mar 17 17:46:34.376389 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Mar 17 17:46:34.376398 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 17 17:46:34.376407 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Mar 17 17:46:34.376416 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 17 17:46:34.376425 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Mar 17 17:46:34.376435 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 17 17:46:34.376455 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Mar 17 17:46:34.376465 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Mar 17 17:46:34.376477 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Mar 17 17:46:34.376486 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Mar 17 17:46:34.376495 systemd[1]: Stopped systemd-fsck-usr.service. Mar 17 17:46:34.376505 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). Mar 17 17:46:34.376514 kernel: fuse: init (API version 7.39) Mar 17 17:46:34.376523 systemd[1]: Starting systemd-journald.service - Journal Service... Mar 17 17:46:34.376532 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Mar 17 17:46:34.376541 kernel: loop: module loaded Mar 17 17:46:34.376550 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Mar 17 17:46:34.376588 systemd-journald[1264]: Collecting audit messages is disabled. Mar 17 17:46:34.376613 kernel: ACPI: bus type drm_connector registered Mar 17 17:46:34.376623 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Mar 17 17:46:34.376633 systemd-journald[1264]: Journal started Mar 17 17:46:34.376654 systemd-journald[1264]: Runtime Journal (/run/log/journal/5fefb89d3c3c4f6482945287d978b5bf) is 8M, max 78.5M, 70.5M free. Mar 17 17:46:33.461366 systemd[1]: Queued start job for default target multi-user.target. Mar 17 17:46:33.466165 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Mar 17 17:46:33.466514 systemd[1]: systemd-journald.service: Deactivated successfully. Mar 17 17:46:33.466813 systemd[1]: systemd-journald.service: Consumed 2.987s CPU time. Mar 17 17:46:34.396164 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Mar 17 17:46:34.411596 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Mar 17 17:46:34.421962 systemd[1]: verity-setup.service: Deactivated successfully. Mar 17 17:46:34.422019 systemd[1]: Stopped verity-setup.service. Mar 17 17:46:34.435475 systemd[1]: Started systemd-journald.service - Journal Service. Mar 17 17:46:34.441632 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Mar 17 17:46:34.447828 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Mar 17 17:46:34.454050 systemd[1]: Mounted media.mount - External Media Directory. Mar 17 17:46:34.459584 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Mar 17 17:46:34.465761 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Mar 17 17:46:34.472226 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Mar 17 17:46:34.479480 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Mar 17 17:46:34.486391 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Mar 17 17:46:34.493628 systemd[1]: modprobe@configfs.service: Deactivated successfully. Mar 17 17:46:34.493781 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Mar 17 17:46:34.500590 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 17 17:46:34.500743 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 17 17:46:34.507676 systemd[1]: modprobe@drm.service: Deactivated successfully. Mar 17 17:46:34.507837 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Mar 17 17:46:34.514028 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 17 17:46:34.514171 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 17 17:46:34.521386 systemd[1]: modprobe@fuse.service: Deactivated successfully. Mar 17 17:46:34.521572 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Mar 17 17:46:34.527651 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 17 17:46:34.527806 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 17 17:46:34.534166 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Mar 17 17:46:34.541090 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Mar 17 17:46:34.548665 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Mar 17 17:46:34.556154 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Mar 17 17:46:34.563652 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Mar 17 17:46:34.582044 systemd[1]: Reached target network-pre.target - Preparation for Network. Mar 17 17:46:34.593528 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Mar 17 17:46:34.600585 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Mar 17 17:46:34.606876 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Mar 17 17:46:34.606914 systemd[1]: Reached target local-fs.target - Local File Systems. Mar 17 17:46:34.613529 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Mar 17 17:46:34.629642 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Mar 17 17:46:34.636959 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Mar 17 17:46:34.642691 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 17 17:46:34.681582 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Mar 17 17:46:34.689648 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Mar 17 17:46:34.697468 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 17 17:46:34.698430 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Mar 17 17:46:34.704481 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Mar 17 17:46:34.706659 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Mar 17 17:46:34.719431 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Mar 17 17:46:34.732537 systemd-journald[1264]: Time spent on flushing to /var/log/journal/5fefb89d3c3c4f6482945287d978b5bf is 62.729ms for 884 entries. Mar 17 17:46:34.732537 systemd-journald[1264]: System Journal (/var/log/journal/5fefb89d3c3c4f6482945287d978b5bf) is 11.8M, max 2.6G, 2.6G free. Mar 17 17:46:34.946209 systemd-journald[1264]: Received client request to flush runtime journal. Mar 17 17:46:34.946257 kernel: loop0: detected capacity change from 0 to 28720 Mar 17 17:46:34.946277 systemd-journald[1264]: /var/log/journal/5fefb89d3c3c4f6482945287d978b5bf/system.journal: Realtime clock jumped backwards relative to last journal entry, rotating. Mar 17 17:46:34.946299 systemd-journald[1264]: Rotating system journal. Mar 17 17:46:34.740814 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Mar 17 17:46:34.751785 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Mar 17 17:46:34.760225 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Mar 17 17:46:34.767418 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Mar 17 17:46:34.774243 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Mar 17 17:46:34.783485 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Mar 17 17:46:34.798628 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Mar 17 17:46:34.828061 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Mar 17 17:46:34.838474 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Mar 17 17:46:34.848684 udevadm[1304]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Mar 17 17:46:34.903729 systemd-tmpfiles[1302]: ACLs are not supported, ignoring. Mar 17 17:46:34.903740 systemd-tmpfiles[1302]: ACLs are not supported, ignoring. Mar 17 17:46:34.908564 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Mar 17 17:46:34.920608 systemd[1]: Starting systemd-sysusers.service - Create System Users... Mar 17 17:46:34.948674 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Mar 17 17:46:34.958925 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Mar 17 17:46:34.959674 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Mar 17 17:46:35.109958 systemd[1]: Finished systemd-sysusers.service - Create System Users. Mar 17 17:46:35.122660 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Mar 17 17:46:35.138670 systemd-tmpfiles[1320]: ACLs are not supported, ignoring. Mar 17 17:46:35.138687 systemd-tmpfiles[1320]: ACLs are not supported, ignoring. Mar 17 17:46:35.142599 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Mar 17 17:46:35.220484 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Mar 17 17:46:35.285477 kernel: loop1: detected capacity change from 0 to 123192 Mar 17 17:46:35.709491 kernel: loop2: detected capacity change from 0 to 113512 Mar 17 17:46:35.853792 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Mar 17 17:46:35.866600 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Mar 17 17:46:35.890658 systemd-udevd[1328]: Using default interface naming scheme 'v255'. Mar 17 17:46:36.008177 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Mar 17 17:46:36.028561 systemd[1]: Starting systemd-networkd.service - Network Configuration... Mar 17 17:46:36.069474 kernel: loop3: detected capacity change from 0 to 28720 Mar 17 17:46:36.087471 kernel: loop4: detected capacity change from 0 to 123192 Mar 17 17:46:36.090721 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Mar 17 17:46:36.099762 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Mar 17 17:46:36.114509 kernel: loop5: detected capacity change from 0 to 113512 Mar 17 17:46:36.123001 (sd-merge)[1350]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Mar 17 17:46:36.123764 (sd-merge)[1350]: Merged extensions into '/usr'. Mar 17 17:46:36.132485 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Mar 17 17:46:36.146753 systemd[1]: Starting ensure-sysext.service... Mar 17 17:46:36.153643 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Mar 17 17:46:36.193386 systemd[1]: Started systemd-userdbd.service - User Database Manager. Mar 17 17:46:36.217539 systemd[1]: Reload requested from client PID 1359 ('systemctl') (unit ensure-sysext.service)... Mar 17 17:46:36.217563 systemd[1]: Reloading... Mar 17 17:46:36.218360 systemd-tmpfiles[1360]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Mar 17 17:46:36.219258 systemd-tmpfiles[1360]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Mar 17 17:46:36.220402 systemd-tmpfiles[1360]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Mar 17 17:46:36.221142 systemd-tmpfiles[1360]: ACLs are not supported, ignoring. Mar 17 17:46:36.221193 systemd-tmpfiles[1360]: ACLs are not supported, ignoring. Mar 17 17:46:36.226618 systemd-tmpfiles[1360]: Detected autofs mount point /boot during canonicalization of boot. Mar 17 17:46:36.226744 systemd-tmpfiles[1360]: Skipping /boot Mar 17 17:46:36.242343 systemd-tmpfiles[1360]: Detected autofs mount point /boot during canonicalization of boot. Mar 17 17:46:36.243012 systemd-tmpfiles[1360]: Skipping /boot Mar 17 17:46:36.371872 kernel: mousedev: PS/2 mouse device common for all mice Mar 17 17:46:36.371965 kernel: hv_vmbus: registering driver hyperv_fb Mar 17 17:46:36.371981 zram_generator::config[1416]: No configuration found. Mar 17 17:46:36.372004 kernel: hv_vmbus: registering driver hv_balloon Mar 17 17:46:36.384151 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Mar 17 17:46:36.393488 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Mar 17 17:46:36.402323 kernel: Console: switching to colour dummy device 80x25 Mar 17 17:46:36.402423 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Mar 17 17:46:36.414927 kernel: Console: switching to colour frame buffer device 128x48 Mar 17 17:46:36.415043 kernel: hv_balloon: Memory hot add disabled on ARM64 Mar 17 17:46:36.434700 systemd-networkd[1340]: lo: Link UP Mar 17 17:46:36.435032 systemd-networkd[1340]: lo: Gained carrier Mar 17 17:46:36.437551 systemd-networkd[1340]: Enumeration completed Mar 17 17:46:36.437971 systemd-networkd[1340]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 17 17:46:36.438055 systemd-networkd[1340]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 17 17:46:36.497502 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 39 scanned by (udev-worker) (1348) Mar 17 17:46:36.497609 kernel: mlx5_core 238a:00:02.0 enP9098s1: Link up Mar 17 17:46:36.523139 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. Mar 17 17:46:36.548582 kernel: hv_netvsc 00224878-88aa-0022-4878-88aa00224878 eth0: Data path switched to VF: enP9098s1 Mar 17 17:46:36.548660 systemd-networkd[1340]: enP9098s1: Link UP Mar 17 17:46:36.548749 systemd-networkd[1340]: eth0: Link UP Mar 17 17:46:36.548752 systemd-networkd[1340]: eth0: Gained carrier Mar 17 17:46:36.548768 systemd-networkd[1340]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 17 17:46:36.552753 systemd-networkd[1340]: enP9098s1: Gained carrier Mar 17 17:46:36.561662 systemd-networkd[1340]: eth0: DHCPv4 address 10.200.20.33/24, gateway 10.200.20.1 acquired from 168.63.129.16 Mar 17 17:46:36.637324 systemd[1]: Reloading finished in 419 ms. Mar 17 17:46:36.658821 systemd[1]: Started systemd-networkd.service - Network Configuration. Mar 17 17:46:36.678254 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Mar 17 17:46:36.735648 systemd[1]: Finished ensure-sysext.service. Mar 17 17:46:36.741292 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Mar 17 17:46:36.752735 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Mar 17 17:46:36.765653 systemd[1]: Starting audit-rules.service - Load Audit Rules... Mar 17 17:46:36.772044 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Mar 17 17:46:36.778482 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Mar 17 17:46:36.780301 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Mar 17 17:46:36.791569 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Mar 17 17:46:36.799648 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Mar 17 17:46:36.808130 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Mar 17 17:46:36.826977 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Mar 17 17:46:36.833221 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Mar 17 17:46:36.834400 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Mar 17 17:46:36.841149 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). Mar 17 17:46:36.843742 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Mar 17 17:46:36.859423 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Mar 17 17:46:36.870768 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Mar 17 17:46:36.882466 lvm[1531]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Mar 17 17:46:36.883416 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Mar 17 17:46:36.893970 systemd[1]: Reached target time-set.target - System Time Set. Mar 17 17:46:36.905694 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Mar 17 17:46:36.913213 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Mar 17 17:46:36.923878 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 17 17:46:36.924088 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Mar 17 17:46:36.939219 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Mar 17 17:46:36.949487 systemd[1]: modprobe@drm.service: Deactivated successfully. Mar 17 17:46:36.949669 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Mar 17 17:46:36.956291 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 17 17:46:36.956547 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Mar 17 17:46:36.964566 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 17 17:46:36.964729 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Mar 17 17:46:36.971053 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Mar 17 17:46:36.979951 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Mar 17 17:46:36.987550 augenrules[1565]: No rules Mar 17 17:46:36.989004 systemd[1]: audit-rules.service: Deactivated successfully. Mar 17 17:46:36.989235 systemd[1]: Finished audit-rules.service - Load Audit Rules. Mar 17 17:46:37.015878 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Mar 17 17:46:37.025100 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Mar 17 17:46:37.031864 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 17 17:46:37.031950 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Mar 17 17:46:37.037898 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Mar 17 17:46:37.047484 lvm[1579]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Mar 17 17:46:37.051922 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Mar 17 17:46:37.077578 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Mar 17 17:46:37.165426 systemd-resolved[1554]: Positive Trust Anchors: Mar 17 17:46:37.166249 systemd-resolved[1554]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 17 17:46:37.166286 systemd-resolved[1554]: 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 Mar 17 17:46:37.183432 systemd-resolved[1554]: Using system hostname 'ci-4230.1.0-a-6ce3c60530'. Mar 17 17:46:37.185348 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Mar 17 17:46:37.193939 systemd[1]: Reached target network.target - Network. Mar 17 17:46:37.200040 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Mar 17 17:46:37.207975 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Mar 17 17:46:37.215419 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Mar 17 17:46:37.222882 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). Mar 17 17:46:37.629553 systemd-networkd[1340]: enP9098s1: Gained IPv6LL Mar 17 17:46:37.757576 systemd-networkd[1340]: eth0: Gained IPv6LL Mar 17 17:46:37.764004 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Mar 17 17:46:37.771952 systemd[1]: Reached target network-online.target - Network is Online. Mar 17 17:46:40.083826 ldconfig[1295]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Mar 17 17:46:40.098396 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Mar 17 17:46:40.109694 systemd[1]: Starting systemd-update-done.service - Update is Completed... Mar 17 17:46:40.124520 systemd[1]: Finished systemd-update-done.service - Update is Completed. Mar 17 17:46:40.130886 systemd[1]: Reached target sysinit.target - System Initialization. Mar 17 17:46:40.136763 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Mar 17 17:46:40.144433 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Mar 17 17:46:40.153013 systemd[1]: Started logrotate.timer - Daily rotation of log files. Mar 17 17:46:40.159610 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Mar 17 17:46:40.168328 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Mar 17 17:46:40.176193 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Mar 17 17:46:40.176222 systemd[1]: Reached target paths.target - Path Units. Mar 17 17:46:40.181361 systemd[1]: Reached target timers.target - Timer Units. Mar 17 17:46:40.187962 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Mar 17 17:46:40.195705 systemd[1]: Starting docker.socket - Docker Socket for the API... Mar 17 17:46:40.203814 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Mar 17 17:46:40.210834 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Mar 17 17:46:40.218240 systemd[1]: Reached target ssh-access.target - SSH Access Available. Mar 17 17:46:40.234271 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Mar 17 17:46:40.241474 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Mar 17 17:46:40.248828 systemd[1]: Listening on docker.socket - Docker Socket for the API. Mar 17 17:46:40.254828 systemd[1]: Reached target sockets.target - Socket Units. Mar 17 17:46:40.259911 systemd[1]: Reached target basic.target - Basic System. Mar 17 17:46:40.265133 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Mar 17 17:46:40.265164 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Mar 17 17:46:40.274554 systemd[1]: Starting chronyd.service - NTP client/server... Mar 17 17:46:40.283952 systemd[1]: Starting containerd.service - containerd container runtime... Mar 17 17:46:40.307657 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Mar 17 17:46:40.314792 (chronyd)[1593]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Mar 17 17:46:40.322581 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Mar 17 17:46:40.330021 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Mar 17 17:46:40.338649 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Mar 17 17:46:40.341247 chronyd[1603]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Mar 17 17:46:40.344773 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). Mar 17 17:46:40.344816 systemd[1]: hv_fcopy_daemon.service - Hyper-V FCOPY daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_fcopy). Mar 17 17:46:40.347642 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Mar 17 17:46:40.356535 jq[1600]: false Mar 17 17:46:40.356189 KVP[1604]: KVP starting; pid is:1604 Mar 17 17:46:40.356641 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Mar 17 17:46:40.361598 KVP[1604]: KVP LIC Version: 3.1 Mar 17 17:46:40.362492 kernel: hv_utils: KVP IC version 4.0 Mar 17 17:46:40.372699 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Mar 17 17:46:40.379869 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Mar 17 17:46:40.388644 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Mar 17 17:46:40.401834 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Mar 17 17:46:40.402641 extend-filesystems[1601]: Found loop3 Mar 17 17:46:40.402641 extend-filesystems[1601]: Found loop4 Mar 17 17:46:40.402641 extend-filesystems[1601]: Found loop5 Mar 17 17:46:40.402641 extend-filesystems[1601]: Found sda Mar 17 17:46:40.402641 extend-filesystems[1601]: Found sda1 Mar 17 17:46:40.402641 extend-filesystems[1601]: Found sda2 Mar 17 17:46:40.402641 extend-filesystems[1601]: Found sda3 Mar 17 17:46:40.402641 extend-filesystems[1601]: Found usr Mar 17 17:46:40.402641 extend-filesystems[1601]: Found sda4 Mar 17 17:46:40.402641 extend-filesystems[1601]: Found sda6 Mar 17 17:46:40.402641 extend-filesystems[1601]: Found sda7 Mar 17 17:46:40.402641 extend-filesystems[1601]: Found sda9 Mar 17 17:46:40.429103 systemd[1]: Starting systemd-logind.service - User Login Management... Mar 17 17:46:40.594539 coreos-metadata[1595]: Mar 17 17:46:40.533 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Mar 17 17:46:40.594539 coreos-metadata[1595]: Mar 17 17:46:40.543 INFO Fetch successful Mar 17 17:46:40.594539 coreos-metadata[1595]: Mar 17 17:46:40.543 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Mar 17 17:46:40.594539 coreos-metadata[1595]: Mar 17 17:46:40.550 INFO Fetch successful Mar 17 17:46:40.594539 coreos-metadata[1595]: Mar 17 17:46:40.550 INFO Fetching http://168.63.129.16/machine/4f0360db-2296-48e3-9f1d-112df574b54e/d21e3ee8%2D0fbb%2D49a8%2D8799%2Dd05ab03d754a.%5Fci%2D4230.1.0%2Da%2D6ce3c60530?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Mar 17 17:46:40.600841 extend-filesystems[1601]: Checking size of /dev/sda9 Mar 17 17:46:40.600841 extend-filesystems[1601]: Old size kept for /dev/sda9 Mar 17 17:46:40.600841 extend-filesystems[1601]: Found sr0 Mar 17 17:46:40.412016 chronyd[1603]: Timezone right/UTC failed leap second check, ignoring Mar 17 17:46:40.444681 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Mar 17 17:46:40.650589 coreos-metadata[1595]: Mar 17 17:46:40.599 INFO Fetch successful Mar 17 17:46:40.650589 coreos-metadata[1595]: Mar 17 17:46:40.599 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Mar 17 17:46:40.650589 coreos-metadata[1595]: Mar 17 17:46:40.615 INFO Fetch successful Mar 17 17:46:40.412286 chronyd[1603]: Loaded seccomp filter (level 2) Mar 17 17:46:40.445222 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Mar 17 17:46:40.434339 dbus-daemon[1596]: [system] SELinux support is enabled Mar 17 17:46:40.453701 systemd[1]: Starting update-engine.service - Update Engine... Mar 17 17:46:40.652359 update_engine[1621]: I20250317 17:46:40.536218 1621 main.cc:92] Flatcar Update Engine starting Mar 17 17:46:40.652359 update_engine[1621]: I20250317 17:46:40.543017 1621 update_check_scheduler.cc:74] Next update check in 4m27s Mar 17 17:46:40.464994 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Mar 17 17:46:40.652678 jq[1628]: true Mar 17 17:46:40.484326 systemd[1]: Started dbus.service - D-Bus System Message Bus. Mar 17 17:46:40.506950 systemd[1]: Started chronyd.service - NTP client/server. Mar 17 17:46:40.652987 jq[1641]: true Mar 17 17:46:40.533012 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Mar 17 17:46:40.533209 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Mar 17 17:46:40.533569 systemd[1]: extend-filesystems.service: Deactivated successfully. Mar 17 17:46:40.533741 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Mar 17 17:46:40.549837 systemd[1]: motdgen.service: Deactivated successfully. Mar 17 17:46:40.550051 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Mar 17 17:46:40.561304 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Mar 17 17:46:40.571973 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Mar 17 17:46:40.572162 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Mar 17 17:46:40.610325 systemd-logind[1613]: New seat seat0. Mar 17 17:46:40.614485 systemd-logind[1613]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Mar 17 17:46:40.615084 systemd[1]: Started systemd-logind.service - User Login Management. Mar 17 17:46:40.620981 (ntainerd)[1645]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Mar 17 17:46:40.700705 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 39 scanned by (udev-worker) (1658) Mar 17 17:46:40.695167 systemd[1]: Started update-engine.service - Update Engine. Mar 17 17:46:40.708470 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Mar 17 17:46:40.719492 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Mar 17 17:46:40.719694 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). Mar 17 17:46:40.719815 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Mar 17 17:46:40.734175 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). Mar 17 17:46:40.734317 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Mar 17 17:46:40.760608 systemd[1]: Started locksmithd.service - Cluster reboot manager. Mar 17 17:46:40.832501 bash[1693]: Updated "/home/core/.ssh/authorized_keys" Mar 17 17:46:40.833777 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Mar 17 17:46:40.858015 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Mar 17 17:46:40.915459 sshd_keygen[1627]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Mar 17 17:46:40.932899 locksmithd[1706]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Mar 17 17:46:40.938510 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Mar 17 17:46:40.953776 systemd[1]: Starting issuegen.service - Generate /run/issue... Mar 17 17:46:40.960709 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Mar 17 17:46:40.967938 systemd[1]: issuegen.service: Deactivated successfully. Mar 17 17:46:40.969494 systemd[1]: Finished issuegen.service - Generate /run/issue. Mar 17 17:46:40.988795 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Mar 17 17:46:40.997572 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Mar 17 17:46:41.018885 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Mar 17 17:46:41.032743 systemd[1]: Started getty@tty1.service - Getty on tty1. Mar 17 17:46:41.048921 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Mar 17 17:46:41.056391 systemd[1]: Reached target getty.target - Login Prompts. Mar 17 17:46:41.126840 containerd[1645]: time="2025-03-17T17:46:41.126700460Z" level=info msg="starting containerd" revision=9b2ad7760328148397346d10c7b2004271249db4 version=v1.7.23 Mar 17 17:46:41.154014 containerd[1645]: time="2025-03-17T17:46:41.153959580Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Mar 17 17:46:41.155592 containerd[1645]: time="2025-03-17T17:46:41.155546940Z" 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.83-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Mar 17 17:46:41.155592 containerd[1645]: time="2025-03-17T17:46:41.155588820Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Mar 17 17:46:41.155690 containerd[1645]: time="2025-03-17T17:46:41.155609420Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Mar 17 17:46:41.155804 containerd[1645]: time="2025-03-17T17:46:41.155777940Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Mar 17 17:46:41.155829 containerd[1645]: time="2025-03-17T17:46:41.155804780Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Mar 17 17:46:41.155895 containerd[1645]: time="2025-03-17T17:46:41.155875620Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Mar 17 17:46:41.155920 containerd[1645]: time="2025-03-17T17:46:41.155893820Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Mar 17 17:46:41.156129 containerd[1645]: time="2025-03-17T17:46:41.156105100Z" 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 Mar 17 17:46:41.156156 containerd[1645]: time="2025-03-17T17:46:41.156127580Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Mar 17 17:46:41.156156 containerd[1645]: time="2025-03-17T17:46:41.156142020Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Mar 17 17:46:41.156156 containerd[1645]: time="2025-03-17T17:46:41.156150660Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Mar 17 17:46:41.156243 containerd[1645]: time="2025-03-17T17:46:41.156223380Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Mar 17 17:46:41.156454 containerd[1645]: time="2025-03-17T17:46:41.156422300Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Mar 17 17:46:41.156611 containerd[1645]: time="2025-03-17T17:46:41.156583300Z" 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 Mar 17 17:46:41.156660 containerd[1645]: time="2025-03-17T17:46:41.156622660Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Mar 17 17:46:41.157465 containerd[1645]: time="2025-03-17T17:46:41.156708580Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Mar 17 17:46:41.157465 containerd[1645]: time="2025-03-17T17:46:41.156767540Z" level=info msg="metadata content store policy set" policy=shared Mar 17 17:46:41.173335 containerd[1645]: time="2025-03-17T17:46:41.173291780Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Mar 17 17:46:41.173607 containerd[1645]: time="2025-03-17T17:46:41.173526820Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Mar 17 17:46:41.173607 containerd[1645]: time="2025-03-17T17:46:41.173552500Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Mar 17 17:46:41.173674 containerd[1645]: time="2025-03-17T17:46:41.173628380Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Mar 17 17:46:41.173674 containerd[1645]: time="2025-03-17T17:46:41.173661780Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Mar 17 17:46:41.173870 containerd[1645]: time="2025-03-17T17:46:41.173842740Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Mar 17 17:46:41.174464 containerd[1645]: time="2025-03-17T17:46:41.174129780Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Mar 17 17:46:41.174464 containerd[1645]: time="2025-03-17T17:46:41.174270900Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Mar 17 17:46:41.174464 containerd[1645]: time="2025-03-17T17:46:41.174287700Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Mar 17 17:46:41.174464 containerd[1645]: time="2025-03-17T17:46:41.174303620Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Mar 17 17:46:41.174464 containerd[1645]: time="2025-03-17T17:46:41.174317140Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Mar 17 17:46:41.174464 containerd[1645]: time="2025-03-17T17:46:41.174330180Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Mar 17 17:46:41.174464 containerd[1645]: time="2025-03-17T17:46:41.174342820Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Mar 17 17:46:41.174464 containerd[1645]: time="2025-03-17T17:46:41.174359660Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Mar 17 17:46:41.174464 containerd[1645]: time="2025-03-17T17:46:41.174375660Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Mar 17 17:46:41.174464 containerd[1645]: time="2025-03-17T17:46:41.174387860Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Mar 17 17:46:41.174464 containerd[1645]: time="2025-03-17T17:46:41.174399740Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Mar 17 17:46:41.174464 containerd[1645]: time="2025-03-17T17:46:41.174410060Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Mar 17 17:46:41.174464 containerd[1645]: time="2025-03-17T17:46:41.174430380Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Mar 17 17:46:41.174464 containerd[1645]: time="2025-03-17T17:46:41.174477980Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Mar 17 17:46:41.174865 containerd[1645]: time="2025-03-17T17:46:41.174493420Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Mar 17 17:46:41.174865 containerd[1645]: time="2025-03-17T17:46:41.174507740Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Mar 17 17:46:41.174865 containerd[1645]: time="2025-03-17T17:46:41.174520300Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Mar 17 17:46:41.174865 containerd[1645]: time="2025-03-17T17:46:41.174532980Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Mar 17 17:46:41.174865 containerd[1645]: time="2025-03-17T17:46:41.174544060Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Mar 17 17:46:41.174865 containerd[1645]: time="2025-03-17T17:46:41.174556780Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Mar 17 17:46:41.174865 containerd[1645]: time="2025-03-17T17:46:41.174568700Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Mar 17 17:46:41.174865 containerd[1645]: time="2025-03-17T17:46:41.174584300Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Mar 17 17:46:41.174865 containerd[1645]: time="2025-03-17T17:46:41.174595460Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Mar 17 17:46:41.174865 containerd[1645]: time="2025-03-17T17:46:41.174606500Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Mar 17 17:46:41.174865 containerd[1645]: time="2025-03-17T17:46:41.174618580Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Mar 17 17:46:41.174865 containerd[1645]: time="2025-03-17T17:46:41.174632620Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Mar 17 17:46:41.174865 containerd[1645]: time="2025-03-17T17:46:41.174653340Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Mar 17 17:46:41.174865 containerd[1645]: time="2025-03-17T17:46:41.174665660Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Mar 17 17:46:41.174865 containerd[1645]: time="2025-03-17T17:46:41.174676620Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Mar 17 17:46:41.175198 containerd[1645]: time="2025-03-17T17:46:41.174734260Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Mar 17 17:46:41.175198 containerd[1645]: time="2025-03-17T17:46:41.174754580Z" 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 Mar 17 17:46:41.175198 containerd[1645]: time="2025-03-17T17:46:41.174765380Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Mar 17 17:46:41.175198 containerd[1645]: time="2025-03-17T17:46:41.174777060Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Mar 17 17:46:41.175198 containerd[1645]: time="2025-03-17T17:46:41.174786060Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Mar 17 17:46:41.175198 containerd[1645]: time="2025-03-17T17:46:41.174797620Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Mar 17 17:46:41.175198 containerd[1645]: time="2025-03-17T17:46:41.174807340Z" level=info msg="NRI interface is disabled by configuration." Mar 17 17:46:41.175198 containerd[1645]: time="2025-03-17T17:46:41.174817380Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Mar 17 17:46:41.175456 containerd[1645]: time="2025-03-17T17:46:41.175091900Z" 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}" Mar 17 17:46:41.175456 containerd[1645]: time="2025-03-17T17:46:41.175146140Z" level=info msg="Connect containerd service" Mar 17 17:46:41.175456 containerd[1645]: time="2025-03-17T17:46:41.175186540Z" level=info msg="using legacy CRI server" Mar 17 17:46:41.175456 containerd[1645]: time="2025-03-17T17:46:41.175193780Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Mar 17 17:46:41.175456 containerd[1645]: time="2025-03-17T17:46:41.175413460Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Mar 17 17:46:41.176769 containerd[1645]: time="2025-03-17T17:46:41.176564540Z" 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" Mar 17 17:46:41.177164 containerd[1645]: time="2025-03-17T17:46:41.177076180Z" level=info msg="Start subscribing containerd event" Mar 17 17:46:41.177164 containerd[1645]: time="2025-03-17T17:46:41.177139620Z" level=info msg="Start recovering state" Mar 17 17:46:41.177923 containerd[1645]: time="2025-03-17T17:46:41.177520100Z" level=info msg="Start event monitor" Mar 17 17:46:41.177923 containerd[1645]: time="2025-03-17T17:46:41.177542100Z" level=info msg="Start snapshots syncer" Mar 17 17:46:41.177923 containerd[1645]: time="2025-03-17T17:46:41.177552180Z" level=info msg="Start cni network conf syncer for default" Mar 17 17:46:41.177923 containerd[1645]: time="2025-03-17T17:46:41.177558740Z" level=info msg="Start streaming server" Mar 17 17:46:41.177923 containerd[1645]: time="2025-03-17T17:46:41.177584460Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Mar 17 17:46:41.177923 containerd[1645]: time="2025-03-17T17:46:41.177642500Z" level=info msg=serving... address=/run/containerd/containerd.sock Mar 17 17:46:41.177923 containerd[1645]: time="2025-03-17T17:46:41.177693300Z" level=info msg="containerd successfully booted in 0.052480s" Mar 17 17:46:41.178248 systemd[1]: Started containerd.service - containerd container runtime. Mar 17 17:46:41.187347 systemd[1]: Reached target multi-user.target - Multi-User System. Mar 17 17:46:41.196543 systemd[1]: Startup finished in 685ms (kernel) + 10.811s (initrd) + 10.021s (userspace) = 21.518s. Mar 17 17:46:41.704577 login[1762]: pam_unix(login:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:46:41.710870 login[1763]: pam_unix(login:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:46:41.715430 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Mar 17 17:46:41.721669 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Mar 17 17:46:41.729516 systemd-logind[1613]: New session 2 of user core. Mar 17 17:46:41.732981 systemd-logind[1613]: New session 1 of user core. Mar 17 17:46:41.737775 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Mar 17 17:46:41.743823 systemd[1]: Starting user@500.service - User Manager for UID 500... Mar 17 17:46:41.747850 (systemd)[1774]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Mar 17 17:46:41.750221 systemd-logind[1613]: New session c1 of user core. Mar 17 17:46:41.906015 systemd[1774]: Queued start job for default target default.target. Mar 17 17:46:41.916500 systemd[1774]: Created slice app.slice - User Application Slice. Mar 17 17:46:41.916690 systemd[1774]: Reached target paths.target - Paths. Mar 17 17:46:41.916794 systemd[1774]: Reached target timers.target - Timers. Mar 17 17:46:41.918407 systemd[1774]: Starting dbus.socket - D-Bus User Message Bus Socket... Mar 17 17:46:41.928506 systemd[1774]: Listening on dbus.socket - D-Bus User Message Bus Socket. Mar 17 17:46:41.928578 systemd[1774]: Reached target sockets.target - Sockets. Mar 17 17:46:41.928628 systemd[1774]: Reached target basic.target - Basic System. Mar 17 17:46:41.928657 systemd[1774]: Reached target default.target - Main User Target. Mar 17 17:46:41.928682 systemd[1774]: Startup finished in 171ms. Mar 17 17:46:41.928912 systemd[1]: Started user@500.service - User Manager for UID 500. Mar 17 17:46:41.930613 systemd[1]: Started session-1.scope - Session 1 of User core. Mar 17 17:46:41.932021 systemd[1]: Started session-2.scope - Session 2 of User core. Mar 17 17:46:42.771463 waagent[1758]: 2025-03-17T17:46:42.771285Z INFO Daemon Daemon Azure Linux Agent Version: 2.9.1.1 Mar 17 17:46:42.777009 waagent[1758]: 2025-03-17T17:46:42.776938Z INFO Daemon Daemon OS: flatcar 4230.1.0 Mar 17 17:46:42.781542 waagent[1758]: 2025-03-17T17:46:42.781486Z INFO Daemon Daemon Python: 3.11.11 Mar 17 17:46:42.786419 waagent[1758]: 2025-03-17T17:46:42.786220Z INFO Daemon Daemon Run daemon Mar 17 17:46:42.790265 waagent[1758]: 2025-03-17T17:46:42.790211Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4230.1.0' Mar 17 17:46:42.799045 waagent[1758]: 2025-03-17T17:46:42.798979Z INFO Daemon Daemon Using waagent for provisioning Mar 17 17:46:42.804314 waagent[1758]: 2025-03-17T17:46:42.804261Z INFO Daemon Daemon Activate resource disk Mar 17 17:46:42.808959 waagent[1758]: 2025-03-17T17:46:42.808905Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Mar 17 17:46:42.821678 waagent[1758]: 2025-03-17T17:46:42.821609Z INFO Daemon Daemon Found device: None Mar 17 17:46:42.826049 waagent[1758]: 2025-03-17T17:46:42.825991Z ERROR Daemon Daemon Failed to mount resource disk [ResourceDiskError] unable to detect disk topology Mar 17 17:46:42.834541 waagent[1758]: 2025-03-17T17:46:42.834476Z ERROR Daemon Daemon Event: name=WALinuxAgent, op=ActivateResourceDisk, message=[ResourceDiskError] unable to detect disk topology, duration=0 Mar 17 17:46:42.845773 waagent[1758]: 2025-03-17T17:46:42.845714Z INFO Daemon Daemon Clean protocol and wireserver endpoint Mar 17 17:46:42.851622 waagent[1758]: 2025-03-17T17:46:42.851567Z INFO Daemon Daemon Running default provisioning handler Mar 17 17:46:42.863115 waagent[1758]: 2025-03-17T17:46:42.863034Z 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. Mar 17 17:46:42.876934 waagent[1758]: 2025-03-17T17:46:42.876858Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Mar 17 17:46:42.886628 waagent[1758]: 2025-03-17T17:46:42.886561Z INFO Daemon Daemon cloud-init is enabled: False Mar 17 17:46:42.891562 waagent[1758]: 2025-03-17T17:46:42.891506Z INFO Daemon Daemon Copying ovf-env.xml Mar 17 17:46:42.984939 waagent[1758]: 2025-03-17T17:46:42.981271Z INFO Daemon Daemon Successfully mounted dvd Mar 17 17:46:42.997845 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Mar 17 17:46:42.999636 waagent[1758]: 2025-03-17T17:46:42.999351Z INFO Daemon Daemon Detect protocol endpoint Mar 17 17:46:43.005140 waagent[1758]: 2025-03-17T17:46:43.005065Z INFO Daemon Daemon Clean protocol and wireserver endpoint Mar 17 17:46:43.010987 waagent[1758]: 2025-03-17T17:46:43.010927Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Mar 17 17:46:43.017499 waagent[1758]: 2025-03-17T17:46:43.017431Z INFO Daemon Daemon Test for route to 168.63.129.16 Mar 17 17:46:43.023340 waagent[1758]: 2025-03-17T17:46:43.023248Z INFO Daemon Daemon Route to 168.63.129.16 exists Mar 17 17:46:43.028716 waagent[1758]: 2025-03-17T17:46:43.028663Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Mar 17 17:46:43.067717 waagent[1758]: 2025-03-17T17:46:43.067670Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Mar 17 17:46:43.074860 waagent[1758]: 2025-03-17T17:46:43.074828Z INFO Daemon Daemon Wire protocol version:2012-11-30 Mar 17 17:46:43.080349 waagent[1758]: 2025-03-17T17:46:43.080300Z INFO Daemon Daemon Server preferred version:2015-04-05 Mar 17 17:46:43.406564 waagent[1758]: 2025-03-17T17:46:43.402584Z INFO Daemon Daemon Initializing goal state during protocol detection Mar 17 17:46:43.409584 waagent[1758]: 2025-03-17T17:46:43.409511Z INFO Daemon Daemon Forcing an update of the goal state. Mar 17 17:46:43.419236 waagent[1758]: 2025-03-17T17:46:43.419179Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Mar 17 17:46:43.453966 waagent[1758]: 2025-03-17T17:46:43.453900Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.164 Mar 17 17:46:43.459773 waagent[1758]: 2025-03-17T17:46:43.459720Z INFO Daemon Mar 17 17:46:43.462807 waagent[1758]: 2025-03-17T17:46:43.462757Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: 42221e70-9164-43d1-b4ce-f88c66b412a7 eTag: 5330647909414908306 source: Fabric] Mar 17 17:46:43.474394 waagent[1758]: 2025-03-17T17:46:43.474345Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Mar 17 17:46:43.481604 waagent[1758]: 2025-03-17T17:46:43.481556Z INFO Daemon Mar 17 17:46:43.484865 waagent[1758]: 2025-03-17T17:46:43.484811Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Mar 17 17:46:43.496424 waagent[1758]: 2025-03-17T17:46:43.496370Z INFO Daemon Daemon Downloading artifacts profile blob Mar 17 17:46:43.680342 waagent[1758]: 2025-03-17T17:46:43.680197Z INFO Daemon Downloaded certificate {'thumbprint': '407E2CBD7993D46574AF4772FC7CF07D2D12F952', 'hasPrivateKey': False} Mar 17 17:46:43.691225 waagent[1758]: 2025-03-17T17:46:43.691173Z INFO Daemon Downloaded certificate {'thumbprint': '2CC2C0EEA10BAE4DDFF14D9632B18C4045DF59E8', 'hasPrivateKey': True} Mar 17 17:46:43.701262 waagent[1758]: 2025-03-17T17:46:43.701209Z INFO Daemon Fetch goal state completed Mar 17 17:46:43.748040 waagent[1758]: 2025-03-17T17:46:43.747966Z INFO Daemon Daemon Starting provisioning Mar 17 17:46:43.753049 waagent[1758]: 2025-03-17T17:46:43.752986Z INFO Daemon Daemon Handle ovf-env.xml. Mar 17 17:46:43.757589 waagent[1758]: 2025-03-17T17:46:43.757542Z INFO Daemon Daemon Set hostname [ci-4230.1.0-a-6ce3c60530] Mar 17 17:46:43.780483 waagent[1758]: 2025-03-17T17:46:43.780392Z INFO Daemon Daemon Publish hostname [ci-4230.1.0-a-6ce3c60530] Mar 17 17:46:43.786874 waagent[1758]: 2025-03-17T17:46:43.786807Z INFO Daemon Daemon Examine /proc/net/route for primary interface Mar 17 17:46:43.793183 waagent[1758]: 2025-03-17T17:46:43.793129Z INFO Daemon Daemon Primary interface is [eth0] Mar 17 17:46:43.805527 systemd-networkd[1340]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Mar 17 17:46:43.805534 systemd-networkd[1340]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 17 17:46:43.806374 waagent[1758]: 2025-03-17T17:46:43.806239Z INFO Daemon Daemon Create user account if not exists Mar 17 17:46:43.805563 systemd-networkd[1340]: eth0: DHCP lease lost Mar 17 17:46:43.812198 waagent[1758]: 2025-03-17T17:46:43.812134Z INFO Daemon Daemon User core already exists, skip useradd Mar 17 17:46:43.818263 waagent[1758]: 2025-03-17T17:46:43.818186Z INFO Daemon Daemon Configure sudoer Mar 17 17:46:43.823184 waagent[1758]: 2025-03-17T17:46:43.823119Z INFO Daemon Daemon Configure sshd Mar 17 17:46:43.827840 waagent[1758]: 2025-03-17T17:46:43.827783Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Mar 17 17:46:43.841039 waagent[1758]: 2025-03-17T17:46:43.840964Z INFO Daemon Daemon Deploy ssh public key. Mar 17 17:46:43.857498 systemd-networkd[1340]: eth0: DHCPv4 address 10.200.20.33/24, gateway 10.200.20.1 acquired from 168.63.129.16 Mar 17 17:46:44.975461 waagent[1758]: 2025-03-17T17:46:44.975393Z INFO Daemon Daemon Provisioning complete Mar 17 17:46:44.990311 waagent[1758]: 2025-03-17T17:46:44.990253Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Mar 17 17:46:44.996793 waagent[1758]: 2025-03-17T17:46:44.996731Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Mar 17 17:46:45.007065 waagent[1758]: 2025-03-17T17:46:45.007000Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.9.1.1 is the most current agent Mar 17 17:46:45.144038 waagent[1831]: 2025-03-17T17:46:45.143481Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.9.1.1) Mar 17 17:46:45.144038 waagent[1831]: 2025-03-17T17:46:45.143642Z INFO ExtHandler ExtHandler OS: flatcar 4230.1.0 Mar 17 17:46:45.144038 waagent[1831]: 2025-03-17T17:46:45.143695Z INFO ExtHandler ExtHandler Python: 3.11.11 Mar 17 17:46:45.181476 waagent[1831]: 2025-03-17T17:46:45.181338Z INFO ExtHandler ExtHandler Distro: flatcar-4230.1.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.11; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Mar 17 17:46:45.181678 waagent[1831]: 2025-03-17T17:46:45.181632Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Mar 17 17:46:45.181750 waagent[1831]: 2025-03-17T17:46:45.181718Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Mar 17 17:46:45.203512 waagent[1831]: 2025-03-17T17:46:45.203409Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Mar 17 17:46:45.210379 waagent[1831]: 2025-03-17T17:46:45.210332Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.164 Mar 17 17:46:45.210943 waagent[1831]: 2025-03-17T17:46:45.210895Z INFO ExtHandler Mar 17 17:46:45.211022 waagent[1831]: 2025-03-17T17:46:45.210989Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: a6bac29b-2be8-4b01-840b-4e5ef814260a eTag: 5330647909414908306 source: Fabric] Mar 17 17:46:45.211321 waagent[1831]: 2025-03-17T17:46:45.211281Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Mar 17 17:46:45.211959 waagent[1831]: 2025-03-17T17:46:45.211893Z INFO ExtHandler Mar 17 17:46:45.212007 waagent[1831]: 2025-03-17T17:46:45.211986Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Mar 17 17:46:45.216332 waagent[1831]: 2025-03-17T17:46:45.216289Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Mar 17 17:46:45.294020 waagent[1831]: 2025-03-17T17:46:45.293919Z INFO ExtHandler Downloaded certificate {'thumbprint': '407E2CBD7993D46574AF4772FC7CF07D2D12F952', 'hasPrivateKey': False} Mar 17 17:46:45.294472 waagent[1831]: 2025-03-17T17:46:45.294410Z INFO ExtHandler Downloaded certificate {'thumbprint': '2CC2C0EEA10BAE4DDFF14D9632B18C4045DF59E8', 'hasPrivateKey': True} Mar 17 17:46:45.294902 waagent[1831]: 2025-03-17T17:46:45.294860Z INFO ExtHandler Fetch goal state completed Mar 17 17:46:45.308032 waagent[1831]: 2025-03-17T17:46:45.307974Z INFO ExtHandler ExtHandler WALinuxAgent-2.9.1.1 running as process 1831 Mar 17 17:46:45.308195 waagent[1831]: 2025-03-17T17:46:45.308159Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Mar 17 17:46:45.309889 waagent[1831]: 2025-03-17T17:46:45.309840Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '4230.1.0', '', 'Flatcar Container Linux by Kinvolk'] Mar 17 17:46:45.310281 waagent[1831]: 2025-03-17T17:46:45.310243Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Mar 17 17:46:45.345291 waagent[1831]: 2025-03-17T17:46:45.345243Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Mar 17 17:46:45.345504 waagent[1831]: 2025-03-17T17:46:45.345465Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Mar 17 17:46:45.351430 waagent[1831]: 2025-03-17T17:46:45.350965Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Mar 17 17:46:45.357539 systemd[1]: Reload requested from client PID 1846 ('systemctl') (unit waagent.service)... Mar 17 17:46:45.357552 systemd[1]: Reloading... Mar 17 17:46:45.451511 zram_generator::config[1891]: No configuration found. Mar 17 17:46:45.543367 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. Mar 17 17:46:45.642315 systemd[1]: Reloading finished in 284 ms. Mar 17 17:46:45.656026 waagent[1831]: 2025-03-17T17:46:45.655642Z INFO ExtHandler ExtHandler Executing systemctl daemon-reload for setting up waagent-network-setup.service Mar 17 17:46:45.662032 systemd[1]: Reload requested from client PID 1939 ('systemctl') (unit waagent.service)... Mar 17 17:46:45.662046 systemd[1]: Reloading... Mar 17 17:46:45.749473 zram_generator::config[1978]: No configuration found. Mar 17 17:46:45.855603 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. Mar 17 17:46:45.954872 systemd[1]: Reloading finished in 292 ms. Mar 17 17:46:45.974007 waagent[1831]: 2025-03-17T17:46:45.973121Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Mar 17 17:46:45.974007 waagent[1831]: 2025-03-17T17:46:45.973297Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Mar 17 17:46:46.352966 waagent[1831]: 2025-03-17T17:46:46.352887Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Mar 17 17:46:46.354478 waagent[1831]: 2025-03-17T17:46:46.353913Z 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] Mar 17 17:46:46.354937 waagent[1831]: 2025-03-17T17:46:46.354880Z INFO ExtHandler ExtHandler Starting env monitor service. Mar 17 17:46:46.355118 waagent[1831]: 2025-03-17T17:46:46.355060Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Mar 17 17:46:46.355282 waagent[1831]: 2025-03-17T17:46:46.355240Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Mar 17 17:46:46.355674 waagent[1831]: 2025-03-17T17:46:46.355483Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Mar 17 17:46:46.355885 waagent[1831]: 2025-03-17T17:46:46.355827Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Mar 17 17:46:46.356295 waagent[1831]: 2025-03-17T17:46:46.356239Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Mar 17 17:46:46.356436 waagent[1831]: 2025-03-17T17:46:46.356397Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Mar 17 17:46:46.356531 waagent[1831]: 2025-03-17T17:46:46.356495Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Mar 17 17:46:46.356679 waagent[1831]: 2025-03-17T17:46:46.356642Z INFO EnvHandler ExtHandler Configure routes Mar 17 17:46:46.356742 waagent[1831]: 2025-03-17T17:46:46.356713Z INFO EnvHandler ExtHandler Gateway:None Mar 17 17:46:46.356792 waagent[1831]: 2025-03-17T17:46:46.356764Z INFO EnvHandler ExtHandler Routes:None Mar 17 17:46:46.357620 waagent[1831]: 2025-03-17T17:46:46.357568Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Mar 17 17:46:46.357620 waagent[1831]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Mar 17 17:46:46.357620 waagent[1831]: eth0 00000000 0114C80A 0003 0 0 1024 00000000 0 0 0 Mar 17 17:46:46.357620 waagent[1831]: eth0 0014C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Mar 17 17:46:46.357620 waagent[1831]: eth0 0114C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Mar 17 17:46:46.357620 waagent[1831]: eth0 10813FA8 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Mar 17 17:46:46.357620 waagent[1831]: eth0 FEA9FEA9 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Mar 17 17:46:46.358521 waagent[1831]: 2025-03-17T17:46:46.357369Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Mar 17 17:46:46.359794 waagent[1831]: 2025-03-17T17:46:46.359607Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Mar 17 17:46:46.359794 waagent[1831]: 2025-03-17T17:46:46.359703Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Mar 17 17:46:46.360095 waagent[1831]: 2025-03-17T17:46:46.360039Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Mar 17 17:46:46.372970 waagent[1831]: 2025-03-17T17:46:46.372922Z INFO ExtHandler ExtHandler Mar 17 17:46:46.374470 waagent[1831]: 2025-03-17T17:46:46.373168Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 4d2dd5dc-6409-49c2-a29f-c8e8539c2561 correlation 2bbf5e82-dd4b-492f-b426-f479533f24f6 created: 2025-03-17T17:45:32.079140Z] Mar 17 17:46:46.374470 waagent[1831]: 2025-03-17T17:46:46.373612Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Mar 17 17:46:46.374470 waagent[1831]: 2025-03-17T17:46:46.374194Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 1 ms] Mar 17 17:46:46.421686 waagent[1831]: 2025-03-17T17:46:46.421611Z INFO MonitorHandler ExtHandler Network interfaces: Mar 17 17:46:46.421686 waagent[1831]: Executing ['ip', '-a', '-o', 'link']: Mar 17 17:46:46.421686 waagent[1831]: 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 Mar 17 17:46:46.421686 waagent[1831]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:22:48:78:88:aa brd ff:ff:ff:ff:ff:ff Mar 17 17:46:46.421686 waagent[1831]: 3: enP9098s1: mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\ link/ether 00:22:48:78:88:aa brd ff:ff:ff:ff:ff:ff\ altname enP9098p0s2 Mar 17 17:46:46.421686 waagent[1831]: Executing ['ip', '-4', '-a', '-o', 'address']: Mar 17 17:46:46.421686 waagent[1831]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Mar 17 17:46:46.421686 waagent[1831]: 2: eth0 inet 10.200.20.33/24 metric 1024 brd 10.200.20.255 scope global eth0\ valid_lft forever preferred_lft forever Mar 17 17:46:46.421686 waagent[1831]: Executing ['ip', '-6', '-a', '-o', 'address']: Mar 17 17:46:46.421686 waagent[1831]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Mar 17 17:46:46.421686 waagent[1831]: 2: eth0 inet6 fe80::222:48ff:fe78:88aa/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Mar 17 17:46:46.421686 waagent[1831]: 3: enP9098s1 inet6 fe80::222:48ff:fe78:88aa/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Mar 17 17:46:46.424371 waagent[1831]: 2025-03-17T17:46:46.424314Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.9.1.1 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: ACC33E43-6A6F-4366-ABA9-869CD37EDE08;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 0] Mar 17 17:46:46.523569 waagent[1831]: 2025-03-17T17:46:46.523435Z INFO EnvHandler ExtHandler Successfully added Azure fabric firewall rules. Current Firewall rules: Mar 17 17:46:46.523569 waagent[1831]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Mar 17 17:46:46.523569 waagent[1831]: pkts bytes target prot opt in out source destination Mar 17 17:46:46.523569 waagent[1831]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Mar 17 17:46:46.523569 waagent[1831]: pkts bytes target prot opt in out source destination Mar 17 17:46:46.523569 waagent[1831]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Mar 17 17:46:46.523569 waagent[1831]: pkts bytes target prot opt in out source destination Mar 17 17:46:46.523569 waagent[1831]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Mar 17 17:46:46.523569 waagent[1831]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Mar 17 17:46:46.523569 waagent[1831]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Mar 17 17:46:46.526884 waagent[1831]: 2025-03-17T17:46:46.526804Z INFO EnvHandler ExtHandler Current Firewall rules: Mar 17 17:46:46.526884 waagent[1831]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Mar 17 17:46:46.526884 waagent[1831]: pkts bytes target prot opt in out source destination Mar 17 17:46:46.526884 waagent[1831]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Mar 17 17:46:46.526884 waagent[1831]: pkts bytes target prot opt in out source destination Mar 17 17:46:46.526884 waagent[1831]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Mar 17 17:46:46.526884 waagent[1831]: pkts bytes target prot opt in out source destination Mar 17 17:46:46.526884 waagent[1831]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Mar 17 17:46:46.526884 waagent[1831]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Mar 17 17:46:46.526884 waagent[1831]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Mar 17 17:46:46.527147 waagent[1831]: 2025-03-17T17:46:46.527118Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Mar 17 17:47:04.201521 chronyd[1603]: Selected source PHC0 Mar 17 17:47:09.006837 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Mar 17 17:47:09.012742 systemd[1]: Started sshd@0-10.200.20.33:22-10.200.16.10:37254.service - OpenSSH per-connection server daemon (10.200.16.10:37254). Mar 17 17:47:09.747417 sshd[2067]: Accepted publickey for core from 10.200.16.10 port 37254 ssh2: RSA SHA256:o263vcH4SuOysIKXZsTOtlkNJCrs70lnHQg7wniZ3pY Mar 17 17:47:09.748621 sshd-session[2067]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:47:09.752574 systemd-logind[1613]: New session 3 of user core. Mar 17 17:47:09.758580 systemd[1]: Started session-3.scope - Session 3 of User core. Mar 17 17:47:10.187943 systemd[1]: Started sshd@1-10.200.20.33:22-10.200.16.10:37256.service - OpenSSH per-connection server daemon (10.200.16.10:37256). Mar 17 17:47:10.689205 sshd[2072]: Accepted publickey for core from 10.200.16.10 port 37256 ssh2: RSA SHA256:o263vcH4SuOysIKXZsTOtlkNJCrs70lnHQg7wniZ3pY Mar 17 17:47:10.690431 sshd-session[2072]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:47:10.695511 systemd-logind[1613]: New session 4 of user core. Mar 17 17:47:10.700655 systemd[1]: Started session-4.scope - Session 4 of User core. Mar 17 17:47:11.044870 sshd[2074]: Connection closed by 10.200.16.10 port 37256 Mar 17 17:47:11.044279 sshd-session[2072]: pam_unix(sshd:session): session closed for user core Mar 17 17:47:11.047922 systemd[1]: sshd@1-10.200.20.33:22-10.200.16.10:37256.service: Deactivated successfully. Mar 17 17:47:11.049556 systemd[1]: session-4.scope: Deactivated successfully. Mar 17 17:47:11.050727 systemd-logind[1613]: Session 4 logged out. Waiting for processes to exit. Mar 17 17:47:11.051546 systemd-logind[1613]: Removed session 4. Mar 17 17:47:11.139820 systemd[1]: Started sshd@2-10.200.20.33:22-10.200.16.10:37260.service - OpenSSH per-connection server daemon (10.200.16.10:37260). Mar 17 17:47:11.621995 sshd[2080]: Accepted publickey for core from 10.200.16.10 port 37260 ssh2: RSA SHA256:o263vcH4SuOysIKXZsTOtlkNJCrs70lnHQg7wniZ3pY Mar 17 17:47:11.623227 sshd-session[2080]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:47:11.628503 systemd-logind[1613]: New session 5 of user core. Mar 17 17:47:11.634582 systemd[1]: Started session-5.scope - Session 5 of User core. Mar 17 17:47:11.971243 sshd[2082]: Connection closed by 10.200.16.10 port 37260 Mar 17 17:47:11.970890 sshd-session[2080]: pam_unix(sshd:session): session closed for user core Mar 17 17:47:11.974586 systemd[1]: sshd@2-10.200.20.33:22-10.200.16.10:37260.service: Deactivated successfully. Mar 17 17:47:11.976029 systemd[1]: session-5.scope: Deactivated successfully. Mar 17 17:47:11.976657 systemd-logind[1613]: Session 5 logged out. Waiting for processes to exit. Mar 17 17:47:11.977619 systemd-logind[1613]: Removed session 5. Mar 17 17:47:12.061382 systemd[1]: Started sshd@3-10.200.20.33:22-10.200.16.10:37262.service - OpenSSH per-connection server daemon (10.200.16.10:37262). Mar 17 17:47:12.551791 sshd[2088]: Accepted publickey for core from 10.200.16.10 port 37262 ssh2: RSA SHA256:o263vcH4SuOysIKXZsTOtlkNJCrs70lnHQg7wniZ3pY Mar 17 17:47:12.553008 sshd-session[2088]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:47:12.558412 systemd-logind[1613]: New session 6 of user core. Mar 17 17:47:12.563581 systemd[1]: Started session-6.scope - Session 6 of User core. Mar 17 17:47:12.906197 sshd[2090]: Connection closed by 10.200.16.10 port 37262 Mar 17 17:47:12.906887 sshd-session[2088]: pam_unix(sshd:session): session closed for user core Mar 17 17:47:12.910308 systemd[1]: sshd@3-10.200.20.33:22-10.200.16.10:37262.service: Deactivated successfully. Mar 17 17:47:12.912070 systemd[1]: session-6.scope: Deactivated successfully. Mar 17 17:47:12.913606 systemd-logind[1613]: Session 6 logged out. Waiting for processes to exit. Mar 17 17:47:12.914638 systemd-logind[1613]: Removed session 6. Mar 17 17:47:13.023786 systemd[1]: Started sshd@4-10.200.20.33:22-10.200.16.10:37278.service - OpenSSH per-connection server daemon (10.200.16.10:37278). Mar 17 17:47:13.474883 sshd[2096]: Accepted publickey for core from 10.200.16.10 port 37278 ssh2: RSA SHA256:o263vcH4SuOysIKXZsTOtlkNJCrs70lnHQg7wniZ3pY Mar 17 17:47:13.476105 sshd-session[2096]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:47:13.479968 systemd-logind[1613]: New session 7 of user core. Mar 17 17:47:13.489613 systemd[1]: Started session-7.scope - Session 7 of User core. Mar 17 17:47:13.927601 sudo[2099]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Mar 17 17:47:13.927878 sudo[2099]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Mar 17 17:47:13.965423 sudo[2099]: pam_unix(sudo:session): session closed for user root Mar 17 17:47:14.046125 sshd[2098]: Connection closed by 10.200.16.10 port 37278 Mar 17 17:47:14.045302 sshd-session[2096]: pam_unix(sshd:session): session closed for user core Mar 17 17:47:14.048356 systemd[1]: sshd@4-10.200.20.33:22-10.200.16.10:37278.service: Deactivated successfully. Mar 17 17:47:14.050086 systemd[1]: session-7.scope: Deactivated successfully. Mar 17 17:47:14.051750 systemd-logind[1613]: Session 7 logged out. Waiting for processes to exit. Mar 17 17:47:14.053136 systemd-logind[1613]: Removed session 7. Mar 17 17:47:14.136660 systemd[1]: Started sshd@5-10.200.20.33:22-10.200.16.10:37280.service - OpenSSH per-connection server daemon (10.200.16.10:37280). Mar 17 17:47:14.583056 sshd[2105]: Accepted publickey for core from 10.200.16.10 port 37280 ssh2: RSA SHA256:o263vcH4SuOysIKXZsTOtlkNJCrs70lnHQg7wniZ3pY Mar 17 17:47:14.584304 sshd-session[2105]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:47:14.589491 systemd-logind[1613]: New session 8 of user core. Mar 17 17:47:14.599643 systemd[1]: Started session-8.scope - Session 8 of User core. Mar 17 17:47:14.835548 sudo[2109]: 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 Mar 17 17:47:14.836002 sudo[2109]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Mar 17 17:47:14.839071 sudo[2109]: pam_unix(sudo:session): session closed for user root Mar 17 17:47:14.843830 sudo[2108]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Mar 17 17:47:14.844099 sudo[2108]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Mar 17 17:47:14.857940 systemd[1]: Starting audit-rules.service - Load Audit Rules... Mar 17 17:47:14.880003 augenrules[2131]: No rules Mar 17 17:47:14.881120 systemd[1]: audit-rules.service: Deactivated successfully. Mar 17 17:47:14.881318 systemd[1]: Finished audit-rules.service - Load Audit Rules. Mar 17 17:47:14.882640 sudo[2108]: pam_unix(sudo:session): session closed for user root Mar 17 17:47:14.952721 sshd[2107]: Connection closed by 10.200.16.10 port 37280 Mar 17 17:47:14.953254 sshd-session[2105]: pam_unix(sshd:session): session closed for user core Mar 17 17:47:14.956850 systemd[1]: sshd@5-10.200.20.33:22-10.200.16.10:37280.service: Deactivated successfully. Mar 17 17:47:14.958428 systemd[1]: session-8.scope: Deactivated successfully. Mar 17 17:47:14.959047 systemd-logind[1613]: Session 8 logged out. Waiting for processes to exit. Mar 17 17:47:14.960065 systemd-logind[1613]: Removed session 8. Mar 17 17:47:24.526608 kernel: hv_balloon: Max. dynamic memory size: 4096 MB Mar 17 17:47:25.686100 update_engine[1621]: I20250317 17:47:25.685482 1621 update_attempter.cc:509] Updating boot flags... Mar 17 17:47:25.733500 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 39 scanned by (udev-worker) (2154) Mar 17 17:47:25.865539 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 39 scanned by (udev-worker) (2153) Mar 17 17:47:30.255593 systemd[1]: Started sshd@6-10.200.20.33:22-10.200.16.10:60640.service - OpenSSH per-connection server daemon (10.200.16.10:60640). Mar 17 17:47:30.747848 sshd[2254]: Accepted publickey for core from 10.200.16.10 port 60640 ssh2: RSA SHA256:o263vcH4SuOysIKXZsTOtlkNJCrs70lnHQg7wniZ3pY Mar 17 17:47:30.749038 sshd-session[2254]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:47:30.754482 systemd-logind[1613]: New session 9 of user core. Mar 17 17:47:30.760874 systemd[1]: Started session-9.scope - Session 9 of User core. Mar 17 17:47:31.023901 sudo[2261]: core : PWD=/tmp/tmp.d3Y1mQJwSn ; USER=root ; COMMAND=/usr/sbin/ldd /usr/bin/nc /usr/bin/timeout Mar 17 17:47:31.024504 sudo[2261]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Mar 17 17:47:31.256093 sudo[2261]: pam_unix(sudo:session): session closed for user root Mar 17 17:47:31.261351 sudo[2273]: core : PWD=/tmp/tmp.d3Y1mQJwSn ; USER=root ; COMMAND=/usr/sbin/rsync -av --relative --copy-links /usr/bin/nc /usr/bin/timeout /lib/ld-linux-aarch64.so.1 /lib64/ld-linux-aarch64.so.1 /lib64/libbsd.so.0 /lib64/libc.so.6 /lib64/libmd.so.0 /lib64/libresolv.so.2 ./ Mar 17 17:47:31.261699 sudo[2273]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Mar 17 17:47:31.412436 sudo[2273]: pam_unix(sudo:session): session closed for user root Mar 17 17:47:31.417469 sudo[2257]: core : PWD=/tmp/tmp.d3Y1mQJwSn ; USER=root ; COMMAND=/usr/sbin/docker build -t netcat . Mar 17 17:47:31.417732 sudo[2257]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Mar 17 17:47:31.903700 systemd[1]: Starting docker.service - Docker Application Container Engine... Mar 17 17:47:31.903912 (dockerd)[2284]: 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 Mar 17 17:47:32.792840 dockerd[2284]: time="2025-03-17T17:47:32.792777613Z" level=info msg="Starting up" Mar 17 17:47:33.079027 systemd[1]: var-lib-docker-check\x2doverlayfs\x2dsupport2366989426-merged.mount: Deactivated successfully. Mar 17 17:47:33.135511 dockerd[2284]: time="2025-03-17T17:47:33.135239691Z" level=info msg="Loading containers: start." Mar 17 17:47:33.353485 kernel: Initializing XFRM netlink socket Mar 17 17:47:33.476651 systemd-networkd[1340]: docker0: Link UP Mar 17 17:47:33.512667 dockerd[2284]: time="2025-03-17T17:47:33.512620181Z" level=info msg="Loading containers: done." Mar 17 17:47:33.524020 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck1025966722-merged.mount: Deactivated successfully. Mar 17 17:47:33.546961 dockerd[2284]: time="2025-03-17T17:47:33.546896872Z" 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 Mar 17 17:47:33.547090 dockerd[2284]: time="2025-03-17T17:47:33.547009672Z" level=info msg="Docker daemon" commit=41ca978a0a5400cc24b274137efa9f25517fcc0b containerd-snapshotter=false storage-driver=overlay2 version=27.3.1 Mar 17 17:47:33.547166 dockerd[2284]: time="2025-03-17T17:47:33.547140992Z" level=info msg="Daemon has completed initialization" Mar 17 17:47:33.612989 dockerd[2284]: time="2025-03-17T17:47:33.612934098Z" level=info msg="API listen on /run/docker.sock" Mar 17 17:47:33.613111 systemd[1]: Started docker.service - Docker Application Container Engine. Mar 17 17:47:35.676956 systemd[1]: var-lib-docker-tmp-buildkit\x2dmount1461354474.mount: Deactivated successfully. Mar 17 17:47:35.955951 sudo[2257]: pam_unix(sudo:session): session closed for user root Mar 17 17:47:36.037490 sshd[2256]: Connection closed by 10.200.16.10 port 60640 Mar 17 17:47:36.038014 sshd-session[2254]: pam_unix(sshd:session): session closed for user core Mar 17 17:47:36.041560 systemd[1]: sshd@6-10.200.20.33:22-10.200.16.10:60640.service: Deactivated successfully. Mar 17 17:47:36.043190 systemd[1]: session-9.scope: Deactivated successfully. Mar 17 17:47:36.043387 systemd[1]: session-9.scope: Consumed 333ms CPU time, 128.6M memory peak. Mar 17 17:47:36.043878 systemd-logind[1613]: Session 9 logged out. Waiting for processes to exit. Mar 17 17:47:36.044733 systemd-logind[1613]: Removed session 9. Mar 17 17:47:42.910788 systemd[1]: Started sshd@7-10.200.20.33:22-10.200.16.10:52252.service - OpenSSH per-connection server daemon (10.200.16.10:52252). Mar 17 17:47:43.359435 sshd[2499]: Accepted publickey for core from 10.200.16.10 port 52252 ssh2: RSA SHA256:o263vcH4SuOysIKXZsTOtlkNJCrs70lnHQg7wniZ3pY Mar 17 17:47:43.360755 sshd-session[2499]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Mar 17 17:47:43.365384 systemd-logind[1613]: New session 10 of user core. Mar 17 17:47:43.377623 systemd[1]: Started session-10.scope - Session 10 of User core. Mar 17 17:47:43.643075 systemd[1]: var-lib-docker-overlay2-756710dcf645fd4b09597548e3b43cc95ade70a3d991cbee0ef2068422e0a382\x2dinit-merged.mount: Deactivated successfully. Mar 17 17:47:43.814687 kernel: docker0: port 1(veth70e5606) entered blocking state Mar 17 17:47:43.814834 kernel: docker0: port 1(veth70e5606) entered disabled state Mar 17 17:47:43.814866 kernel: veth70e5606: entered allmulticast mode Mar 17 17:47:43.822669 kernel: veth70e5606: entered promiscuous mode Mar 17 17:47:43.824913 systemd-networkd[1340]: veth70e5606: Link UP Mar 17 17:47:44.561071 containerd[1645]: time="2025-03-17T17:47:44.560792106Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Mar 17 17:47:44.561071 containerd[1645]: time="2025-03-17T17:47:44.560868386Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Mar 17 17:47:44.561071 containerd[1645]: time="2025-03-17T17:47:44.560883786Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Mar 17 17:47:44.561071 containerd[1645]: time="2025-03-17T17:47:44.560968306Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Mar 17 17:47:44.584626 systemd[1]: Started docker-5070eebbeec1a02098d2c8d21eac6966e50c524e8a387166d65d55335f16a624.scope - libcontainer container 5070eebbeec1a02098d2c8d21eac6966e50c524e8a387166d65d55335f16a624. Mar 17 17:47:44.638492 kernel: eth0: renamed from veth8db3a64 Mar 17 17:47:44.652014 kernel: docker0: port 1(veth70e5606) entered blocking state Mar 17 17:47:44.652157 kernel: docker0: port 1(veth70e5606) entered forwarding state Mar 17 17:47:44.652255 systemd-networkd[1340]: veth70e5606: Gained carrier Mar 17 17:47:44.652523 systemd-networkd[1340]: docker0: Gained carrier Mar 17 17:47:44.680702 systemd[1]: docker-5070eebbeec1a02098d2c8d21eac6966e50c524e8a387166d65d55335f16a624.scope: Deactivated successfully. Mar 17 17:47:44.699944 containerd[1645]: time="2025-03-17T17:47:44.699681733Z" level=info msg="shim disconnected" id=5070eebbeec1a02098d2c8d21eac6966e50c524e8a387166d65d55335f16a624 namespace=moby Mar 17 17:47:44.699944 containerd[1645]: time="2025-03-17T17:47:44.699715613Z" level=warning msg="cleaning up after shim disconnected" id=5070eebbeec1a02098d2c8d21eac6966e50c524e8a387166d65d55335f16a624 namespace=moby Mar 17 17:47:44.699944 containerd[1645]: time="2025-03-17T17:47:44.699723413Z" level=info msg="cleaning up dead shim" namespace=moby Mar 17 17:47:44.700086 dockerd[2284]: time="2025-03-17T17:47:44.700007652Z" level=info msg="ignoring event" container=5070eebbeec1a02098d2c8d21eac6966e50c524e8a387166d65d55335f16a624 module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Mar 17 17:47:44.724159 systemd-networkd[1340]: veth70e5606: Lost carrier Mar 17 17:47:44.724476 kernel: docker0: port 1(veth70e5606) entered disabled state Mar 17 17:47:44.724528 kernel: veth8db3a64: renamed from eth0 Mar 17 17:47:44.772920 kernel: docker0: port 1(veth70e5606) entered disabled state Mar 17 17:47:44.773017 kernel: veth70e5606 (unregistering): left allmulticast mode Mar 17 17:47:44.773043 kernel: veth70e5606 (unregistering): left promiscuous mode Mar 17 17:47:44.790039 kernel: docker0: port 1(veth70e5606) entered disabled state Mar 17 17:47:44.795914 systemd-networkd[1340]: veth70e5606: Link DOWN Mar 17 17:47:44.805609 systemd-networkd[1340]: docker0: Lost carrier Mar 17 17:47:44.812991 systemd[1]: run-docker-netns-2319e2e23445.mount: Deactivated successfully. Mar 17 17:47:44.820908 systemd[1]: var-lib-docker-overlay2-756710dcf645fd4b09597548e3b43cc95ade70a3d991cbee0ef2068422e0a382-merged.mount: Deactivated successfully. Mar 17 17:47:44.915289 sshd[2501]: Connection closed by 10.200.16.10 port 52252 Mar 17 17:47:44.916125 sshd-session[2499]: pam_unix(sshd:session): session closed for user core Mar 17 17:47:44.918894 systemd[1]: sshd@7-10.200.20.33:22-10.200.16.10:52252.service: Deactivated successfully. Mar 17 17:47:44.920991 systemd[1]: session-10.scope: Deactivated successfully. Mar 17 17:47:44.922414 systemd-logind[1613]: Session 10 logged out. Waiting for processes to exit. Mar 17 17:47:44.923668 systemd-logind[1613]: Removed session 10. Mar 17 17:47:46.493659 systemd-networkd[1340]: docker0: Gained IPv6LL