Sep 4 18:19:25.324196 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Sep 4 18:19:25.324217 kernel: Linux version 6.6.48-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p17) 13.3.1 20240614, GNU ld (Gentoo 2.42 p3) 2.42.0) #1 SMP PREEMPT Wed Sep 4 15:58:01 -00 2024 Sep 4 18:19:25.324225 kernel: KASLR enabled Sep 4 18:19:25.324233 kernel: earlycon: pl11 at MMIO 0x00000000effec000 (options '') Sep 4 18:19:25.324239 kernel: printk: bootconsole [pl11] enabled Sep 4 18:19:25.324244 kernel: efi: EFI v2.7 by EDK II Sep 4 18:19:25.324252 kernel: efi: ACPI 2.0=0x3fd89018 SMBIOS=0x3fd66000 SMBIOS 3.0=0x3fd64000 MEMATTR=0x3ef2e698 RNG=0x3fd89998 MEMRESERVE=0x3e925e18 Sep 4 18:19:25.324258 kernel: random: crng init done Sep 4 18:19:25.324264 kernel: ACPI: Early table checksum verification disabled Sep 4 18:19:25.324270 kernel: ACPI: RSDP 0x000000003FD89018 000024 (v02 VRTUAL) Sep 4 18:19:25.324276 kernel: ACPI: XSDT 0x000000003FD89F18 00006C (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 18:19:25.324283 kernel: ACPI: FACP 0x000000003FD89C18 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 18:19:25.324290 kernel: ACPI: DSDT 0x000000003EBD2018 01DEC0 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Sep 4 18:19:25.324296 kernel: ACPI: DBG2 0x000000003FD89B18 000072 (v00 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 18:19:25.324304 kernel: ACPI: GTDT 0x000000003FD89D98 000060 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 18:19:25.324310 kernel: ACPI: OEM0 0x000000003FD89098 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 18:19:25.324317 kernel: ACPI: SPCR 0x000000003FD89A98 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 18:19:25.324324 kernel: ACPI: APIC 0x000000003FD89818 0000FC (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 18:19:25.324331 kernel: ACPI: SRAT 0x000000003FD89198 000234 (v03 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 18:19:25.324337 kernel: ACPI: PPTT 0x000000003FD89418 000120 (v01 VRTUAL MICROSFT 00000000 MSFT 00000000) Sep 4 18:19:25.324344 kernel: ACPI: BGRT 0x000000003FD89E98 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 18:19:25.324350 kernel: ACPI: SPCR: console: pl011,mmio32,0xeffec000,115200 Sep 4 18:19:25.324356 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] Sep 4 18:19:25.324363 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0x1bfffffff] Sep 4 18:19:25.324369 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x1c0000000-0xfbfffffff] Sep 4 18:19:25.324376 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x1000000000-0xffffffffff] Sep 4 18:19:25.324382 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000000000-0x1ffffffffff] Sep 4 18:19:25.324389 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000000000-0x3ffffffffff] Sep 4 18:19:25.324397 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000000000-0x7ffffffffff] Sep 4 18:19:25.324403 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000000000-0xfffffffffff] Sep 4 18:19:25.324409 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000000-0x1fffffffffff] Sep 4 18:19:25.324416 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x200000000000-0x3fffffffffff] Sep 4 18:19:25.324422 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x400000000000-0x7fffffffffff] Sep 4 18:19:25.324429 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x800000000000-0xffffffffffff] Sep 4 18:19:25.324435 kernel: NUMA: NODE_DATA [mem 0x1bf7ee800-0x1bf7f3fff] Sep 4 18:19:25.324441 kernel: Zone ranges: Sep 4 18:19:25.324448 kernel: DMA [mem 0x0000000000000000-0x00000000ffffffff] Sep 4 18:19:25.324454 kernel: DMA32 empty Sep 4 18:19:25.324460 kernel: Normal [mem 0x0000000100000000-0x00000001bfffffff] Sep 4 18:19:25.324468 kernel: Movable zone start for each node Sep 4 18:19:25.324477 kernel: Early memory node ranges Sep 4 18:19:25.324484 kernel: node 0: [mem 0x0000000000000000-0x00000000007fffff] Sep 4 18:19:25.324491 kernel: node 0: [mem 0x0000000000824000-0x000000003ec80fff] Sep 4 18:19:25.324498 kernel: node 0: [mem 0x000000003ec81000-0x000000003eca9fff] Sep 4 18:19:25.324506 kernel: node 0: [mem 0x000000003ecaa000-0x000000003fd29fff] Sep 4 18:19:25.324513 kernel: node 0: [mem 0x000000003fd2a000-0x000000003fd7dfff] Sep 4 18:19:25.324519 kernel: node 0: [mem 0x000000003fd7e000-0x000000003fd89fff] Sep 4 18:19:25.324526 kernel: node 0: [mem 0x000000003fd8a000-0x000000003fd8dfff] Sep 4 18:19:25.324533 kernel: node 0: [mem 0x000000003fd8e000-0x000000003fffffff] Sep 4 18:19:25.324540 kernel: node 0: [mem 0x0000000100000000-0x00000001bfffffff] Sep 4 18:19:25.324546 kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000001bfffffff] Sep 4 18:19:25.324553 kernel: On node 0, zone DMA: 36 pages in unavailable ranges Sep 4 18:19:25.324560 kernel: psci: probing for conduit method from ACPI. Sep 4 18:19:25.324567 kernel: psci: PSCIv1.1 detected in firmware. Sep 4 18:19:25.324573 kernel: psci: Using standard PSCI v0.2 function IDs Sep 4 18:19:25.324580 kernel: psci: MIGRATE_INFO_TYPE not supported. Sep 4 18:19:25.324588 kernel: psci: SMC Calling Convention v1.4 Sep 4 18:19:25.324595 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x0 -> Node 0 Sep 4 18:19:25.324601 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x1 -> Node 0 Sep 4 18:19:25.324608 kernel: percpu: Embedded 31 pages/cpu s86632 r8192 d32152 u126976 Sep 4 18:19:25.324615 kernel: pcpu-alloc: s86632 r8192 d32152 u126976 alloc=31*4096 Sep 4 18:19:25.324622 kernel: pcpu-alloc: [0] 0 [0] 1 Sep 4 18:19:25.324629 kernel: Detected PIPT I-cache on CPU0 Sep 4 18:19:25.324635 kernel: CPU features: detected: GIC system register CPU interface Sep 4 18:19:25.324642 kernel: CPU features: detected: Hardware dirty bit management Sep 4 18:19:25.324649 kernel: CPU features: detected: Spectre-BHB Sep 4 18:19:25.324656 kernel: CPU features: kernel page table isolation forced ON by KASLR Sep 4 18:19:25.326691 kernel: CPU features: detected: Kernel page table isolation (KPTI) Sep 4 18:19:25.326709 kernel: CPU features: detected: ARM erratum 1418040 Sep 4 18:19:25.326717 kernel: CPU features: detected: ARM erratum 1542419 (kernel portion) Sep 4 18:19:25.326724 kernel: CPU features: detected: SSBS not fully self-synchronizing Sep 4 18:19:25.326730 kernel: alternatives: applying boot alternatives Sep 4 18:19:25.326739 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=28a986328b36e7de6a755f88bb335afbeb3e3932bc9a20c5f8e57b952c2d23a9 Sep 4 18:19:25.326746 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 4 18:19:25.326754 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 4 18:19:25.326761 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 4 18:19:25.326768 kernel: Fallback order for Node 0: 0 Sep 4 18:19:25.326775 kernel: Built 1 zonelists, mobility grouping on. Total pages: 1032156 Sep 4 18:19:25.326783 kernel: Policy zone: Normal Sep 4 18:19:25.326790 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 4 18:19:25.326797 kernel: software IO TLB: area num 2. Sep 4 18:19:25.326804 kernel: software IO TLB: mapped [mem 0x000000003a925000-0x000000003e925000] (64MB) Sep 4 18:19:25.326811 kernel: Memory: 3986076K/4194160K available (10240K kernel code, 2184K rwdata, 8084K rodata, 39296K init, 897K bss, 208084K reserved, 0K cma-reserved) Sep 4 18:19:25.326818 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Sep 4 18:19:25.326825 kernel: trace event string verifier disabled Sep 4 18:19:25.326832 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 4 18:19:25.326839 kernel: rcu: RCU event tracing is enabled. Sep 4 18:19:25.326846 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Sep 4 18:19:25.326853 kernel: Trampoline variant of Tasks RCU enabled. Sep 4 18:19:25.326860 kernel: Tracing variant of Tasks RCU enabled. Sep 4 18:19:25.326868 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 4 18:19:25.326875 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Sep 4 18:19:25.326882 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Sep 4 18:19:25.326893 kernel: GICv3: 960 SPIs implemented Sep 4 18:19:25.326899 kernel: GICv3: 0 Extended SPIs implemented Sep 4 18:19:25.326906 kernel: Root IRQ handler: gic_handle_irq Sep 4 18:19:25.326913 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Sep 4 18:19:25.326920 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000effee000 Sep 4 18:19:25.326927 kernel: ITS: No ITS available, not enabling LPIs Sep 4 18:19:25.326934 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 4 18:19:25.326941 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 4 18:19:25.326949 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Sep 4 18:19:25.326956 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Sep 4 18:19:25.326963 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Sep 4 18:19:25.326970 kernel: Console: colour dummy device 80x25 Sep 4 18:19:25.326977 kernel: printk: console [tty1] enabled Sep 4 18:19:25.326984 kernel: ACPI: Core revision 20230628 Sep 4 18:19:25.326992 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Sep 4 18:19:25.326999 kernel: pid_max: default: 32768 minimum: 301 Sep 4 18:19:25.327006 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Sep 4 18:19:25.327013 kernel: landlock: Up and running. Sep 4 18:19:25.327021 kernel: SELinux: Initializing. Sep 4 18:19:25.327028 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 4 18:19:25.327035 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 4 18:19:25.327042 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1. Sep 4 18:19:25.327050 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1. Sep 4 18:19:25.327057 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3a8030, hints 0xe, misc 0x31e1 Sep 4 18:19:25.327064 kernel: Hyper-V: Host Build 10.0.22477.1369-1-0 Sep 4 18:19:25.327077 kernel: Hyper-V: enabling crash_kexec_post_notifiers Sep 4 18:19:25.327084 kernel: rcu: Hierarchical SRCU implementation. Sep 4 18:19:25.327092 kernel: rcu: Max phase no-delay instances is 400. Sep 4 18:19:25.327099 kernel: Remapping and enabling EFI services. Sep 4 18:19:25.327108 kernel: smp: Bringing up secondary CPUs ... Sep 4 18:19:25.327115 kernel: Detected PIPT I-cache on CPU1 Sep 4 18:19:25.327123 kernel: GICv3: CPU1: found redistributor 1 region 1:0x00000000f000e000 Sep 4 18:19:25.327130 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 4 18:19:25.327138 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Sep 4 18:19:25.327145 kernel: smp: Brought up 1 node, 2 CPUs Sep 4 18:19:25.327154 kernel: SMP: Total of 2 processors activated. Sep 4 18:19:25.327161 kernel: CPU features: detected: 32-bit EL0 Support Sep 4 18:19:25.327168 kernel: CPU features: detected: Instruction cache invalidation not required for I/D coherence Sep 4 18:19:25.327176 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Sep 4 18:19:25.327183 kernel: CPU features: detected: CRC32 instructions Sep 4 18:19:25.327191 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Sep 4 18:19:25.327198 kernel: CPU features: detected: LSE atomic instructions Sep 4 18:19:25.327206 kernel: CPU features: detected: Privileged Access Never Sep 4 18:19:25.327213 kernel: CPU: All CPU(s) started at EL1 Sep 4 18:19:25.327222 kernel: alternatives: applying system-wide alternatives Sep 4 18:19:25.327229 kernel: devtmpfs: initialized Sep 4 18:19:25.327236 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 4 18:19:25.327244 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Sep 4 18:19:25.327251 kernel: pinctrl core: initialized pinctrl subsystem Sep 4 18:19:25.327259 kernel: SMBIOS 3.1.0 present. Sep 4 18:19:25.327266 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 11/28/2023 Sep 4 18:19:25.327274 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 4 18:19:25.327281 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Sep 4 18:19:25.327290 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Sep 4 18:19:25.327298 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Sep 4 18:19:25.327305 kernel: audit: initializing netlink subsys (disabled) Sep 4 18:19:25.327313 kernel: audit: type=2000 audit(0.047:1): state=initialized audit_enabled=0 res=1 Sep 4 18:19:25.327320 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 4 18:19:25.327327 kernel: cpuidle: using governor menu Sep 4 18:19:25.327335 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Sep 4 18:19:25.327342 kernel: ASID allocator initialised with 32768 entries Sep 4 18:19:25.327351 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 4 18:19:25.327358 kernel: Serial: AMBA PL011 UART driver Sep 4 18:19:25.327366 kernel: Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Sep 4 18:19:25.327373 kernel: Modules: 0 pages in range for non-PLT usage Sep 4 18:19:25.327380 kernel: Modules: 509056 pages in range for PLT usage Sep 4 18:19:25.327388 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 4 18:19:25.327395 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Sep 4 18:19:25.327403 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Sep 4 18:19:25.327410 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Sep 4 18:19:25.327419 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 4 18:19:25.327427 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Sep 4 18:19:25.327434 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Sep 4 18:19:25.327441 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Sep 4 18:19:25.327449 kernel: ACPI: Added _OSI(Module Device) Sep 4 18:19:25.327456 kernel: ACPI: Added _OSI(Processor Device) Sep 4 18:19:25.327463 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Sep 4 18:19:25.327471 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 4 18:19:25.327478 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 4 18:19:25.327485 kernel: ACPI: Interpreter enabled Sep 4 18:19:25.327494 kernel: ACPI: Using GIC for interrupt routing Sep 4 18:19:25.327501 kernel: ARMH0011:00: ttyAMA0 at MMIO 0xeffec000 (irq = 12, base_baud = 0) is a SBSA Sep 4 18:19:25.327509 kernel: printk: console [ttyAMA0] enabled Sep 4 18:19:25.327516 kernel: printk: bootconsole [pl11] disabled Sep 4 18:19:25.327523 kernel: ARMH0011:01: ttyAMA1 at MMIO 0xeffeb000 (irq = 13, base_baud = 0) is a SBSA Sep 4 18:19:25.327531 kernel: iommu: Default domain type: Translated Sep 4 18:19:25.327538 kernel: iommu: DMA domain TLB invalidation policy: strict mode Sep 4 18:19:25.327545 kernel: efivars: Registered efivars operations Sep 4 18:19:25.327553 kernel: vgaarb: loaded Sep 4 18:19:25.327562 kernel: clocksource: Switched to clocksource arch_sys_counter Sep 4 18:19:25.327569 kernel: VFS: Disk quotas dquot_6.6.0 Sep 4 18:19:25.327576 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 4 18:19:25.327584 kernel: pnp: PnP ACPI init Sep 4 18:19:25.327591 kernel: pnp: PnP ACPI: found 0 devices Sep 4 18:19:25.327598 kernel: NET: Registered PF_INET protocol family Sep 4 18:19:25.327606 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Sep 4 18:19:25.327613 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Sep 4 18:19:25.327621 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 4 18:19:25.327630 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Sep 4 18:19:25.327637 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Sep 4 18:19:25.327644 kernel: TCP: Hash tables configured (established 32768 bind 32768) Sep 4 18:19:25.327652 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 4 18:19:25.327676 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 4 18:19:25.327686 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 4 18:19:25.327693 kernel: PCI: CLS 0 bytes, default 64 Sep 4 18:19:25.327701 kernel: kvm [1]: HYP mode not available Sep 4 18:19:25.327710 kernel: Initialise system trusted keyrings Sep 4 18:19:25.327717 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Sep 4 18:19:25.327725 kernel: Key type asymmetric registered Sep 4 18:19:25.327732 kernel: Asymmetric key parser 'x509' registered Sep 4 18:19:25.327739 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Sep 4 18:19:25.327747 kernel: io scheduler mq-deadline registered Sep 4 18:19:25.327754 kernel: io scheduler kyber registered Sep 4 18:19:25.327761 kernel: io scheduler bfq registered Sep 4 18:19:25.327769 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 4 18:19:25.327776 kernel: thunder_xcv, ver 1.0 Sep 4 18:19:25.327784 kernel: thunder_bgx, ver 1.0 Sep 4 18:19:25.327792 kernel: nicpf, ver 1.0 Sep 4 18:19:25.327799 kernel: nicvf, ver 1.0 Sep 4 18:19:25.327925 kernel: rtc-efi rtc-efi.0: registered as rtc0 Sep 4 18:19:25.327995 kernel: rtc-efi rtc-efi.0: setting system clock to 2024-09-04T18:19:24 UTC (1725473964) Sep 4 18:19:25.328006 kernel: efifb: probing for efifb Sep 4 18:19:25.328014 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Sep 4 18:19:25.328023 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Sep 4 18:19:25.328031 kernel: efifb: scrolling: redraw Sep 4 18:19:25.328038 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Sep 4 18:19:25.328046 kernel: Console: switching to colour frame buffer device 128x48 Sep 4 18:19:25.328053 kernel: fb0: EFI VGA frame buffer device Sep 4 18:19:25.328061 kernel: SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping .... Sep 4 18:19:25.328068 kernel: hid: raw HID events driver (C) Jiri Kosina Sep 4 18:19:25.328075 kernel: No ACPI PMU IRQ for CPU0 Sep 4 18:19:25.328083 kernel: No ACPI PMU IRQ for CPU1 Sep 4 18:19:25.328090 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 1 counters available Sep 4 18:19:25.328099 kernel: watchdog: Delayed init of the lockup detector failed: -19 Sep 4 18:19:25.328106 kernel: watchdog: Hard watchdog permanently disabled Sep 4 18:19:25.328114 kernel: NET: Registered PF_INET6 protocol family Sep 4 18:19:25.328121 kernel: Segment Routing with IPv6 Sep 4 18:19:25.328128 kernel: In-situ OAM (IOAM) with IPv6 Sep 4 18:19:25.328135 kernel: NET: Registered PF_PACKET protocol family Sep 4 18:19:25.328143 kernel: Key type dns_resolver registered Sep 4 18:19:25.328150 kernel: registered taskstats version 1 Sep 4 18:19:25.328157 kernel: Loading compiled-in X.509 certificates Sep 4 18:19:25.328166 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.48-flatcar: 6782952639b29daf968f5d0c3e73fb25e5af1d5e' Sep 4 18:19:25.328173 kernel: Key type .fscrypt registered Sep 4 18:19:25.328181 kernel: Key type fscrypt-provisioning registered Sep 4 18:19:25.328189 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 4 18:19:25.328196 kernel: ima: Allocated hash algorithm: sha1 Sep 4 18:19:25.328203 kernel: ima: No architecture policies found Sep 4 18:19:25.328211 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Sep 4 18:19:25.328218 kernel: clk: Disabling unused clocks Sep 4 18:19:25.328227 kernel: Freeing unused kernel memory: 39296K Sep 4 18:19:25.328234 kernel: Run /init as init process Sep 4 18:19:25.328242 kernel: with arguments: Sep 4 18:19:25.328249 kernel: /init Sep 4 18:19:25.328256 kernel: with environment: Sep 4 18:19:25.328263 kernel: HOME=/ Sep 4 18:19:25.328270 kernel: TERM=linux Sep 4 18:19:25.328278 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 4 18:19:25.328287 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Sep 4 18:19:25.328298 systemd[1]: Detected virtualization microsoft. Sep 4 18:19:25.328306 systemd[1]: Detected architecture arm64. Sep 4 18:19:25.328314 systemd[1]: Running in initrd. Sep 4 18:19:25.328322 systemd[1]: No hostname configured, using default hostname. Sep 4 18:19:25.328329 systemd[1]: Hostname set to . Sep 4 18:19:25.328338 systemd[1]: Initializing machine ID from random generator. Sep 4 18:19:25.328346 systemd[1]: Queued start job for default target initrd.target. Sep 4 18:19:25.328355 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 4 18:19:25.328363 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 4 18:19:25.328371 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 4 18:19:25.328380 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 4 18:19:25.328388 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 4 18:19:25.328396 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 4 18:19:25.328406 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Sep 4 18:19:25.328415 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 4 18:19:25.328424 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 4 18:19:25.328432 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 4 18:19:25.328440 systemd[1]: Reached target paths.target - Path Units. Sep 4 18:19:25.328448 systemd[1]: Reached target slices.target - Slice Units. Sep 4 18:19:25.328456 systemd[1]: Reached target swap.target - Swaps. Sep 4 18:19:25.328464 systemd[1]: Reached target timers.target - Timer Units. Sep 4 18:19:25.328472 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 4 18:19:25.328480 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 4 18:19:25.328489 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 4 18:19:25.328497 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Sep 4 18:19:25.328505 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 4 18:19:25.328513 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 4 18:19:25.328521 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 4 18:19:25.328529 systemd[1]: Reached target sockets.target - Socket Units. Sep 4 18:19:25.328537 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 4 18:19:25.328545 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 4 18:19:25.328555 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 4 18:19:25.328563 systemd[1]: Starting systemd-fsck-usr.service... Sep 4 18:19:25.328571 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 4 18:19:25.328579 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 4 18:19:25.328601 systemd-journald[216]: Collecting audit messages is disabled. Sep 4 18:19:25.328622 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 18:19:25.328630 systemd-journald[216]: Journal started Sep 4 18:19:25.328648 systemd-journald[216]: Runtime Journal (/run/log/journal/81336ec74b134e57ab7c058276b7e69d) is 8.0M, max 78.6M, 70.6M free. Sep 4 18:19:25.333580 systemd-modules-load[217]: Inserted module 'overlay' Sep 4 18:19:25.346925 systemd[1]: Started systemd-journald.service - Journal Service. Sep 4 18:19:25.371968 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Sep 4 18:19:25.365996 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 4 18:19:25.376018 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 4 18:19:25.389131 kernel: Bridge firewalling registered Sep 4 18:19:25.388312 systemd-modules-load[217]: Inserted module 'br_netfilter' Sep 4 18:19:25.396451 systemd[1]: Finished systemd-fsck-usr.service. Sep 4 18:19:25.406104 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 4 18:19:25.416551 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 18:19:25.437957 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 4 18:19:25.446020 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 4 18:19:25.471853 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 4 18:19:25.487857 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 4 18:19:25.504690 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 18:19:25.520702 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 4 18:19:25.526983 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 4 18:19:25.539886 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 4 18:19:25.564883 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 4 18:19:25.571873 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 4 18:19:25.584632 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 4 18:19:25.609962 dracut-cmdline[253]: dracut-dracut-053 Sep 4 18:19:25.613847 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 4 18:19:25.635314 dracut-cmdline[253]: 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=28a986328b36e7de6a755f88bb335afbeb3e3932bc9a20c5f8e57b952c2d23a9 Sep 4 18:19:25.673691 systemd-resolved[255]: Positive Trust Anchors: Sep 4 18:19:25.673706 systemd-resolved[255]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 4 18:19:25.673738 systemd-resolved[255]: 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 Sep 4 18:19:25.676053 systemd-resolved[255]: Defaulting to hostname 'linux'. Sep 4 18:19:25.676843 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 4 18:19:25.685442 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 4 18:19:25.812692 kernel: SCSI subsystem initialized Sep 4 18:19:25.820677 kernel: Loading iSCSI transport class v2.0-870. Sep 4 18:19:25.832688 kernel: iscsi: registered transport (tcp) Sep 4 18:19:25.849885 kernel: iscsi: registered transport (qla4xxx) Sep 4 18:19:25.849922 kernel: QLogic iSCSI HBA Driver Sep 4 18:19:25.888239 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 4 18:19:25.901877 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 4 18:19:25.934679 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 4 18:19:25.934726 kernel: device-mapper: uevent: version 1.0.3 Sep 4 18:19:25.941328 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Sep 4 18:19:25.989679 kernel: raid6: neonx8 gen() 15780 MB/s Sep 4 18:19:26.009680 kernel: raid6: neonx4 gen() 15651 MB/s Sep 4 18:19:26.029678 kernel: raid6: neonx2 gen() 13239 MB/s Sep 4 18:19:26.050677 kernel: raid6: neonx1 gen() 10479 MB/s Sep 4 18:19:26.070677 kernel: raid6: int64x8 gen() 6966 MB/s Sep 4 18:19:26.090685 kernel: raid6: int64x4 gen() 7347 MB/s Sep 4 18:19:26.111678 kernel: raid6: int64x2 gen() 6133 MB/s Sep 4 18:19:26.135300 kernel: raid6: int64x1 gen() 5063 MB/s Sep 4 18:19:26.135312 kernel: raid6: using algorithm neonx8 gen() 15780 MB/s Sep 4 18:19:26.159298 kernel: raid6: .... xor() 11937 MB/s, rmw enabled Sep 4 18:19:26.159326 kernel: raid6: using neon recovery algorithm Sep 4 18:19:26.168674 kernel: xor: measuring software checksum speed Sep 4 18:19:26.172671 kernel: 8regs : 19878 MB/sec Sep 4 18:19:26.176671 kernel: 32regs : 19692 MB/sec Sep 4 18:19:26.184461 kernel: arm64_neon : 27261 MB/sec Sep 4 18:19:26.184491 kernel: xor: using function: arm64_neon (27261 MB/sec) Sep 4 18:19:26.234713 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 4 18:19:26.244113 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 4 18:19:26.259783 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 4 18:19:26.282756 systemd-udevd[440]: Using default interface naming scheme 'v255'. Sep 4 18:19:26.286128 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 4 18:19:26.308832 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 4 18:19:26.322350 dracut-pre-trigger[448]: rd.md=0: removing MD RAID activation Sep 4 18:19:26.347409 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 4 18:19:26.363916 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 4 18:19:26.407700 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 4 18:19:26.428920 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 4 18:19:26.453225 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 4 18:19:26.468545 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 4 18:19:26.476583 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 4 18:19:26.493174 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 4 18:19:26.521820 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 4 18:19:26.541357 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 4 18:19:26.543593 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 18:19:26.562681 kernel: hv_vmbus: Vmbus version:5.3 Sep 4 18:19:26.567713 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 4 18:19:26.581544 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 4 18:19:26.661573 kernel: hv_vmbus: registering driver hyperv_keyboard Sep 4 18:19:26.661595 kernel: hv_vmbus: registering driver hid_hyperv Sep 4 18:19:26.661605 kernel: hv_vmbus: registering driver hv_netvsc Sep 4 18:19:26.661614 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/VMBUS:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input0 Sep 4 18:19:26.661630 kernel: pps_core: LinuxPPS API ver. 1 registered Sep 4 18:19:26.661640 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Sep 4 18:19:26.661650 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Sep 4 18:19:26.662696 kernel: hid-generic 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Sep 4 18:19:26.581731 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 18:19:26.680785 kernel: hv_vmbus: registering driver hv_storvsc Sep 4 18:19:26.680810 kernel: PTP clock support registered Sep 4 18:19:26.620892 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 18:19:26.714146 kernel: scsi host0: storvsc_host_t Sep 4 18:19:26.714316 kernel: scsi host1: storvsc_host_t Sep 4 18:19:26.714418 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Sep 4 18:19:26.669317 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 18:19:26.730682 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Sep 4 18:19:26.697017 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 4 18:19:26.725516 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 18:19:26.764143 kernel: hv_netvsc 0022487b-6c94-0022-487b-6c940022487b eth0: VF slot 1 added Sep 4 18:19:26.738140 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 4 18:19:26.738333 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 18:19:26.757451 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 18:19:26.783190 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 18:19:26.816058 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 18:19:26.833855 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 4 18:19:26.873212 kernel: hv_utils: Registering HyperV Utility Driver Sep 4 18:19:26.873242 kernel: hv_vmbus: registering driver hv_utils Sep 4 18:19:26.873253 kernel: hv_vmbus: registering driver hv_pci Sep 4 18:19:26.873262 kernel: hv_utils: Heartbeat IC version 3.0 Sep 4 18:19:26.873271 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Sep 4 18:19:26.873451 kernel: hv_utils: Shutdown IC version 3.2 Sep 4 18:19:26.873462 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Sep 4 18:19:26.877678 kernel: hv_utils: TimeSync IC version 4.0 Sep 4 18:19:26.894547 systemd-resolved[255]: Clock change detected. Flushing caches. Sep 4 18:19:26.916933 kernel: hv_pci 349b8c2e-64fa-4d44-abeb-0587307e841e: PCI VMBus probing: Using version 0x10004 Sep 4 18:19:26.917123 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Sep 4 18:19:26.917262 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Sep 4 18:19:26.927574 kernel: hv_pci 349b8c2e-64fa-4d44-abeb-0587307e841e: PCI host bridge to bus 64fa:00 Sep 4 18:19:26.927756 kernel: sd 0:0:0:0: [sda] Write Protect is off Sep 4 18:19:26.938630 kernel: pci_bus 64fa:00: root bus resource [mem 0xfc0000000-0xfc00fffff window] Sep 4 18:19:26.938837 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Sep 4 18:19:26.939523 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 18:19:26.960090 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Sep 4 18:19:26.960233 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Sep 4 18:19:26.960335 kernel: pci_bus 64fa:00: No busn resource found for root bus, will use [bus 00-ff] Sep 4 18:19:26.972910 kernel: pci 64fa:00:02.0: [15b3:1018] type 00 class 0x020000 Sep 4 18:19:26.980837 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 4 18:19:26.980871 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Sep 4 18:19:26.980998 kernel: pci 64fa:00:02.0: reg 0x10: [mem 0xfc0000000-0xfc00fffff 64bit pref] Sep 4 18:19:26.996757 kernel: pci 64fa:00:02.0: enabling Extended Tags Sep 4 18:19:27.016760 kernel: pci 64fa:00:02.0: 0.000 Gb/s available PCIe bandwidth, limited by Unknown x0 link at 64fa:00:02.0 (capable of 126.016 Gb/s with 8.0 GT/s PCIe x16 link) Sep 4 18:19:27.030398 kernel: pci_bus 64fa:00: busn_res: [bus 00-ff] end is updated to 00 Sep 4 18:19:27.030598 kernel: pci 64fa:00:02.0: BAR 0: assigned [mem 0xfc0000000-0xfc00fffff 64bit pref] Sep 4 18:19:27.072552 kernel: mlx5_core 64fa:00:02.0: enabling device (0000 -> 0002) Sep 4 18:19:27.079676 kernel: mlx5_core 64fa:00:02.0: firmware version: 16.30.1284 Sep 4 18:19:27.277882 kernel: hv_netvsc 0022487b-6c94-0022-487b-6c940022487b eth0: VF registering: eth1 Sep 4 18:19:27.278076 kernel: mlx5_core 64fa:00:02.0 eth1: joined to eth0 Sep 4 18:19:27.286360 kernel: mlx5_core 64fa:00:02.0: MLX5E: StrdRq(1) RqSz(8) StrdSz(2048) RxCqeCmprss(0 basic) Sep 4 18:19:27.298687 kernel: mlx5_core 64fa:00:02.0 enP25850s1: renamed from eth1 Sep 4 18:19:27.682113 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Sep 4 18:19:27.883685 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/sda6 scanned by (udev-worker) (486) Sep 4 18:19:27.897140 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Sep 4 18:19:27.917064 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Sep 4 18:19:28.010688 kernel: BTRFS: device fsid 3e706a0f-a579-4862-bc52-e66e95e66d87 devid 1 transid 42 /dev/sda3 scanned by (udev-worker) (489) Sep 4 18:19:28.023336 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Sep 4 18:19:28.030630 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Sep 4 18:19:28.064872 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 4 18:19:28.090691 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 4 18:19:28.100039 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 4 18:19:28.106705 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 4 18:19:29.115743 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 4 18:19:29.116038 disk-uuid[607]: The operation has completed successfully. Sep 4 18:19:29.172957 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 4 18:19:29.173052 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 4 18:19:29.201779 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 4 18:19:29.216928 sh[720]: Success Sep 4 18:19:29.250711 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Sep 4 18:19:29.652034 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 4 18:19:29.661765 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 4 18:19:29.670098 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 4 18:19:29.707071 kernel: BTRFS info (device dm-0): first mount of filesystem 3e706a0f-a579-4862-bc52-e66e95e66d87 Sep 4 18:19:29.707112 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Sep 4 18:19:29.714176 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Sep 4 18:19:29.719395 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 4 18:19:29.723830 kernel: BTRFS info (device dm-0): using free space tree Sep 4 18:19:30.594721 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 4 18:19:30.600381 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 4 18:19:30.615862 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 4 18:19:30.623808 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 4 18:19:30.661096 kernel: BTRFS info (device sda6): first mount of filesystem e85e5091-8620-4def-b250-7009f4048f6e Sep 4 18:19:30.661151 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Sep 4 18:19:30.665863 kernel: BTRFS info (device sda6): using free space tree Sep 4 18:19:30.695687 kernel: BTRFS info (device sda6): auto enabling async discard Sep 4 18:19:30.711897 systemd[1]: mnt-oem.mount: Deactivated successfully. Sep 4 18:19:30.717693 kernel: BTRFS info (device sda6): last unmount of filesystem e85e5091-8620-4def-b250-7009f4048f6e Sep 4 18:19:30.725257 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 4 18:19:30.732996 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 4 18:19:30.755797 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 4 18:19:30.776831 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 4 18:19:30.800069 systemd-networkd[904]: lo: Link UP Sep 4 18:19:30.800079 systemd-networkd[904]: lo: Gained carrier Sep 4 18:19:30.801596 systemd-networkd[904]: Enumeration completed Sep 4 18:19:30.801724 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 4 18:19:30.812027 systemd[1]: Reached target network.target - Network. Sep 4 18:19:30.815339 systemd-networkd[904]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 18:19:30.815341 systemd-networkd[904]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 4 18:19:30.907791 kernel: mlx5_core 64fa:00:02.0 enP25850s1: Link up Sep 4 18:19:30.949669 kernel: hv_netvsc 0022487b-6c94-0022-487b-6c940022487b eth0: Data path switched to VF: enP25850s1 Sep 4 18:19:30.950071 systemd-networkd[904]: enP25850s1: Link UP Sep 4 18:19:30.950159 systemd-networkd[904]: eth0: Link UP Sep 4 18:19:30.950279 systemd-networkd[904]: eth0: Gained carrier Sep 4 18:19:30.950287 systemd-networkd[904]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 18:19:30.975926 systemd-networkd[904]: enP25850s1: Gained carrier Sep 4 18:19:30.989692 systemd-networkd[904]: eth0: DHCPv4 address 10.200.20.47/24, gateway 10.200.20.1 acquired from 168.63.129.16 Sep 4 18:19:32.139899 systemd-networkd[904]: enP25850s1: Gained IPv6LL Sep 4 18:19:32.561766 ignition[903]: Ignition 2.19.0 Sep 4 18:19:32.561778 ignition[903]: Stage: fetch-offline Sep 4 18:19:32.566904 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 4 18:19:32.561812 ignition[903]: no configs at "/usr/lib/ignition/base.d" Sep 4 18:19:32.561820 ignition[903]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 4 18:19:32.561902 ignition[903]: parsed url from cmdline: "" Sep 4 18:19:32.561905 ignition[903]: no config URL provided Sep 4 18:19:32.561909 ignition[903]: reading system config file "/usr/lib/ignition/user.ign" Sep 4 18:19:32.594913 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Sep 4 18:19:32.561916 ignition[903]: no config at "/usr/lib/ignition/user.ign" Sep 4 18:19:32.561920 ignition[903]: failed to fetch config: resource requires networking Sep 4 18:19:32.562089 ignition[903]: Ignition finished successfully Sep 4 18:19:32.613684 ignition[915]: Ignition 2.19.0 Sep 4 18:19:32.613691 ignition[915]: Stage: fetch Sep 4 18:19:32.613921 ignition[915]: no configs at "/usr/lib/ignition/base.d" Sep 4 18:19:32.613933 ignition[915]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 4 18:19:32.614038 ignition[915]: parsed url from cmdline: "" Sep 4 18:19:32.614042 ignition[915]: no config URL provided Sep 4 18:19:32.614046 ignition[915]: reading system config file "/usr/lib/ignition/user.ign" Sep 4 18:19:32.614088 ignition[915]: no config at "/usr/lib/ignition/user.ign" Sep 4 18:19:32.614113 ignition[915]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Sep 4 18:19:32.711622 ignition[915]: GET result: OK Sep 4 18:19:32.711694 ignition[915]: config has been read from IMDS userdata Sep 4 18:19:32.711722 ignition[915]: parsing config with SHA512: fc632eea6abdf46392ce911589001bfbd862430caa3ec970091b3f5bd71dab782c429d7c3fee44669ed599e3cc12f400220d9dd4099c710f57860443096e13d9 Sep 4 18:19:32.716651 unknown[915]: fetched base config from "system" Sep 4 18:19:32.720154 ignition[915]: fetch: fetch complete Sep 4 18:19:32.716676 unknown[915]: fetched base config from "system" Sep 4 18:19:32.720159 ignition[915]: fetch: fetch passed Sep 4 18:19:32.716682 unknown[915]: fetched user config from "azure" Sep 4 18:19:32.720215 ignition[915]: Ignition finished successfully Sep 4 18:19:32.726613 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Sep 4 18:19:32.748881 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 4 18:19:32.766701 ignition[921]: Ignition 2.19.0 Sep 4 18:19:32.772931 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 4 18:19:32.766707 ignition[921]: Stage: kargs Sep 4 18:19:32.766860 ignition[921]: no configs at "/usr/lib/ignition/base.d" Sep 4 18:19:32.766869 ignition[921]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 4 18:19:32.767632 ignition[921]: kargs: kargs passed Sep 4 18:19:32.796520 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 4 18:19:32.767810 ignition[921]: Ignition finished successfully Sep 4 18:19:32.811384 ignition[928]: Ignition 2.19.0 Sep 4 18:19:32.821309 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 4 18:19:32.811390 ignition[928]: Stage: disks Sep 4 18:19:32.828373 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 4 18:19:32.811597 ignition[928]: no configs at "/usr/lib/ignition/base.d" Sep 4 18:19:32.837998 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 4 18:19:32.811609 ignition[928]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 4 18:19:32.850874 systemd-networkd[904]: eth0: Gained IPv6LL Sep 4 18:19:32.816648 ignition[928]: disks: disks passed Sep 4 18:19:32.851231 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 4 18:19:32.816713 ignition[928]: Ignition finished successfully Sep 4 18:19:32.857146 systemd[1]: Reached target sysinit.target - System Initialization. Sep 4 18:19:32.866453 systemd[1]: Reached target basic.target - Basic System. Sep 4 18:19:32.899894 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 4 18:19:33.025781 systemd-fsck[936]: ROOT: clean, 14/7326000 files, 477710/7359488 blocks Sep 4 18:19:33.033725 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 4 18:19:33.052802 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 4 18:19:33.108680 kernel: EXT4-fs (sda9): mounted filesystem 901d46b0-2319-4536-8a6d-46889db73e8c r/w with ordered data mode. Quota mode: none. Sep 4 18:19:33.109588 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 4 18:19:33.114973 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 4 18:19:33.212731 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 4 18:19:33.219779 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 4 18:19:33.231859 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Sep 4 18:19:33.246836 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 4 18:19:33.280971 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/sda6 scanned by mount (947) Sep 4 18:19:33.280993 kernel: BTRFS info (device sda6): first mount of filesystem e85e5091-8620-4def-b250-7009f4048f6e Sep 4 18:19:33.281004 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Sep 4 18:19:33.246871 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 4 18:19:33.304551 kernel: BTRFS info (device sda6): using free space tree Sep 4 18:19:33.304572 kernel: BTRFS info (device sda6): auto enabling async discard Sep 4 18:19:33.297644 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 4 18:19:33.310835 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 4 18:19:33.330891 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 4 18:19:34.382499 coreos-metadata[949]: Sep 04 18:19:34.382 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Sep 4 18:19:34.391487 coreos-metadata[949]: Sep 04 18:19:34.389 INFO Fetch successful Sep 4 18:19:34.391487 coreos-metadata[949]: Sep 04 18:19:34.389 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Sep 4 18:19:34.409201 coreos-metadata[949]: Sep 04 18:19:34.399 INFO Fetch successful Sep 4 18:19:34.409201 coreos-metadata[949]: Sep 04 18:19:34.404 INFO wrote hostname ci-4054.1.0-a-bc791940be to /sysroot/etc/hostname Sep 4 18:19:34.409588 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 4 18:19:35.297557 initrd-setup-root[976]: cut: /sysroot/etc/passwd: No such file or directory Sep 4 18:19:35.334819 initrd-setup-root[983]: cut: /sysroot/etc/group: No such file or directory Sep 4 18:19:35.374873 initrd-setup-root[990]: cut: /sysroot/etc/shadow: No such file or directory Sep 4 18:19:35.384023 initrd-setup-root[997]: cut: /sysroot/etc/gshadow: No such file or directory Sep 4 18:19:36.682727 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 4 18:19:36.698824 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 4 18:19:36.706825 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 4 18:19:36.732077 kernel: BTRFS info (device sda6): last unmount of filesystem e85e5091-8620-4def-b250-7009f4048f6e Sep 4 18:19:36.726283 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 4 18:19:36.761592 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 4 18:19:36.769100 ignition[1066]: INFO : Ignition 2.19.0 Sep 4 18:19:36.778986 ignition[1066]: INFO : Stage: mount Sep 4 18:19:36.778986 ignition[1066]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 4 18:19:36.778986 ignition[1066]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 4 18:19:36.778986 ignition[1066]: INFO : mount: mount passed Sep 4 18:19:36.778986 ignition[1066]: INFO : Ignition finished successfully Sep 4 18:19:36.783097 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 4 18:19:36.803766 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 4 18:19:36.819912 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 4 18:19:36.858333 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/sda6 scanned by mount (1077) Sep 4 18:19:36.858379 kernel: BTRFS info (device sda6): first mount of filesystem e85e5091-8620-4def-b250-7009f4048f6e Sep 4 18:19:36.869357 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Sep 4 18:19:36.869386 kernel: BTRFS info (device sda6): using free space tree Sep 4 18:19:36.875675 kernel: BTRFS info (device sda6): auto enabling async discard Sep 4 18:19:36.877202 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 4 18:19:36.903694 ignition[1094]: INFO : Ignition 2.19.0 Sep 4 18:19:36.903694 ignition[1094]: INFO : Stage: files Sep 4 18:19:36.903694 ignition[1094]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 4 18:19:36.903694 ignition[1094]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 4 18:19:36.924279 ignition[1094]: DEBUG : files: compiled without relabeling support, skipping Sep 4 18:19:36.924279 ignition[1094]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 4 18:19:36.924279 ignition[1094]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 4 18:19:37.039963 ignition[1094]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 4 18:19:37.047927 ignition[1094]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 4 18:19:37.047927 ignition[1094]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 4 18:19:37.040355 unknown[1094]: wrote ssh authorized keys file for user: core Sep 4 18:19:37.126066 ignition[1094]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/var/resource/data" Sep 4 18:19:37.136541 ignition[1094]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/var/resource/data" Sep 4 18:19:37.136541 ignition[1094]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/var/resource/http" Sep 4 18:19:37.136541 ignition[1094]: INFO : files: createFilesystemsFiles: createFiles: op(4): GET http://10.200.20.24/http: attempt #1 Sep 4 18:19:37.166878 ignition[1094]: INFO : files: createFilesystemsFiles: createFiles: op(4): GET result: OK Sep 4 18:19:37.166878 ignition[1094]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/var/resource/http" Sep 4 18:19:37.166878 ignition[1094]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing file "/sysroot/var/resource/tftp" Sep 4 18:19:37.166878 ignition[1094]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing file "/sysroot/var/resource/tftp" Sep 4 18:19:37.213925 ignition[1094]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 4 18:19:37.213925 ignition[1094]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 4 18:19:37.213925 ignition[1094]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 4 18:19:37.213925 ignition[1094]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 4 18:19:37.213925 ignition[1094]: INFO : files: files passed Sep 4 18:19:37.213925 ignition[1094]: INFO : Ignition finished successfully Sep 4 18:19:37.186707 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 4 18:19:37.234900 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 4 18:19:37.250817 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 4 18:19:37.268326 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 4 18:19:37.325745 initrd-setup-root-after-ignition[1121]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 4 18:19:37.325745 initrd-setup-root-after-ignition[1121]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 4 18:19:37.268445 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 4 18:19:37.358080 initrd-setup-root-after-ignition[1125]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 4 18:19:37.299221 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 4 18:19:37.306862 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 4 18:19:37.334867 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 4 18:19:37.383110 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 4 18:19:37.383224 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 4 18:19:37.401772 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 4 18:19:37.414246 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 4 18:19:37.425939 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 4 18:19:37.428847 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 4 18:19:37.466916 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 4 18:19:37.485976 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 4 18:19:37.504029 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 4 18:19:37.505695 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 4 18:19:37.517869 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 4 18:19:37.530103 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 4 18:19:37.543369 systemd[1]: Stopped target timers.target - Timer Units. Sep 4 18:19:37.555211 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 4 18:19:37.555271 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 4 18:19:37.572233 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 4 18:19:37.584650 systemd[1]: Stopped target basic.target - Basic System. Sep 4 18:19:37.595736 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 4 18:19:37.607017 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 4 18:19:37.619815 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 4 18:19:37.632645 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 4 18:19:37.644780 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 4 18:19:37.657400 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 4 18:19:37.670089 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 4 18:19:37.681691 systemd[1]: Stopped target swap.target - Swaps. Sep 4 18:19:37.691826 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 4 18:19:37.691896 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 4 18:19:37.707900 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 4 18:19:37.720065 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 4 18:19:37.732887 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 4 18:19:37.739156 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 4 18:19:37.746444 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 4 18:19:37.746518 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 4 18:19:37.765721 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 4 18:19:37.765774 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 4 18:19:37.778086 systemd[1]: ignition-files.service: Deactivated successfully. Sep 4 18:19:37.778139 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 4 18:19:37.790122 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Sep 4 18:19:37.790162 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 4 18:19:37.821901 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 4 18:19:37.853693 ignition[1147]: INFO : Ignition 2.19.0 Sep 4 18:19:37.853693 ignition[1147]: INFO : Stage: umount Sep 4 18:19:37.853693 ignition[1147]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 4 18:19:37.853693 ignition[1147]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 4 18:19:37.853693 ignition[1147]: INFO : umount: umount passed Sep 4 18:19:37.853693 ignition[1147]: INFO : Ignition finished successfully Sep 4 18:19:37.847931 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 4 18:19:37.857861 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 4 18:19:37.857922 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 4 18:19:37.874531 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 4 18:19:37.874577 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 4 18:19:37.889034 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 4 18:19:37.889492 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 4 18:19:37.889583 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 4 18:19:37.898322 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 4 18:19:37.898395 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 4 18:19:37.905714 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 4 18:19:37.905754 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 4 18:19:37.916508 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 4 18:19:37.916553 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 4 18:19:37.927610 systemd[1]: ignition-fetch.service: Deactivated successfully. Sep 4 18:19:37.927650 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Sep 4 18:19:37.939021 systemd[1]: Stopped target network.target - Network. Sep 4 18:19:37.950398 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 4 18:19:37.950445 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 4 18:19:37.962531 systemd[1]: Stopped target paths.target - Path Units. Sep 4 18:19:37.973563 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 4 18:19:37.978270 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 4 18:19:37.985592 systemd[1]: Stopped target slices.target - Slice Units. Sep 4 18:19:37.996171 systemd[1]: Stopped target sockets.target - Socket Units. Sep 4 18:19:38.006771 systemd[1]: iscsid.socket: Deactivated successfully. Sep 4 18:19:38.006807 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 4 18:19:38.017482 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 4 18:19:38.017522 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 4 18:19:38.028323 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 4 18:19:38.028373 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 4 18:19:38.039935 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 4 18:19:38.269244 kernel: hv_netvsc 0022487b-6c94-0022-487b-6c940022487b eth0: Data path switched from VF: enP25850s1 Sep 4 18:19:38.039976 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 4 18:19:38.051163 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 4 18:19:38.051202 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 4 18:19:38.064256 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 4 18:19:38.075324 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 4 18:19:38.086728 systemd-networkd[904]: eth0: DHCPv6 lease lost Sep 4 18:19:38.088182 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 4 18:19:38.088271 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 4 18:19:38.105075 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 4 18:19:38.105140 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 4 18:19:38.124989 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 4 18:19:38.135794 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 4 18:19:38.135851 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 4 18:19:38.143203 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 4 18:19:38.156462 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 4 18:19:38.156560 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 4 18:19:38.182732 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 4 18:19:38.182821 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 4 18:19:38.193593 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 4 18:19:38.193640 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 4 18:19:38.205541 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 4 18:19:38.205596 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 4 18:19:38.218232 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 4 18:19:38.218382 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 4 18:19:38.231036 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 4 18:19:38.231081 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 4 18:19:38.242226 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 4 18:19:38.242263 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 4 18:19:38.263591 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 4 18:19:38.263649 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 4 18:19:38.281114 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 4 18:19:38.281168 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 4 18:19:38.294338 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 4 18:19:38.294398 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 18:19:38.563928 systemd-journald[216]: Received SIGTERM from PID 1 (systemd). Sep 4 18:19:38.337931 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 4 18:19:38.350735 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 4 18:19:38.350795 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 4 18:19:38.365105 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Sep 4 18:19:38.365154 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 4 18:19:38.377953 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 4 18:19:38.377996 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 4 18:19:38.390696 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 4 18:19:38.390738 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 18:19:38.404704 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 4 18:19:38.404790 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 4 18:19:38.417488 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 4 18:19:38.417559 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 4 18:19:38.435978 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 4 18:19:38.470879 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 4 18:19:38.497426 systemd[1]: Switching root. Sep 4 18:19:38.590874 systemd-journald[216]: Journal stopped Sep 4 18:19:25.324196 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Sep 4 18:19:25.324217 kernel: Linux version 6.6.48-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p17) 13.3.1 20240614, GNU ld (Gentoo 2.42 p3) 2.42.0) #1 SMP PREEMPT Wed Sep 4 15:58:01 -00 2024 Sep 4 18:19:25.324225 kernel: KASLR enabled Sep 4 18:19:25.324233 kernel: earlycon: pl11 at MMIO 0x00000000effec000 (options '') Sep 4 18:19:25.324239 kernel: printk: bootconsole [pl11] enabled Sep 4 18:19:25.324244 kernel: efi: EFI v2.7 by EDK II Sep 4 18:19:25.324252 kernel: efi: ACPI 2.0=0x3fd89018 SMBIOS=0x3fd66000 SMBIOS 3.0=0x3fd64000 MEMATTR=0x3ef2e698 RNG=0x3fd89998 MEMRESERVE=0x3e925e18 Sep 4 18:19:25.324258 kernel: random: crng init done Sep 4 18:19:25.324264 kernel: ACPI: Early table checksum verification disabled Sep 4 18:19:25.324270 kernel: ACPI: RSDP 0x000000003FD89018 000024 (v02 VRTUAL) Sep 4 18:19:25.324276 kernel: ACPI: XSDT 0x000000003FD89F18 00006C (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 18:19:25.324283 kernel: ACPI: FACP 0x000000003FD89C18 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 18:19:25.324290 kernel: ACPI: DSDT 0x000000003EBD2018 01DEC0 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Sep 4 18:19:25.324296 kernel: ACPI: DBG2 0x000000003FD89B18 000072 (v00 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 18:19:25.324304 kernel: ACPI: GTDT 0x000000003FD89D98 000060 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 18:19:25.324310 kernel: ACPI: OEM0 0x000000003FD89098 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 18:19:25.324317 kernel: ACPI: SPCR 0x000000003FD89A98 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 18:19:25.324324 kernel: ACPI: APIC 0x000000003FD89818 0000FC (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 18:19:25.324331 kernel: ACPI: SRAT 0x000000003FD89198 000234 (v03 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 18:19:25.324337 kernel: ACPI: PPTT 0x000000003FD89418 000120 (v01 VRTUAL MICROSFT 00000000 MSFT 00000000) Sep 4 18:19:25.324344 kernel: ACPI: BGRT 0x000000003FD89E98 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Sep 4 18:19:25.324350 kernel: ACPI: SPCR: console: pl011,mmio32,0xeffec000,115200 Sep 4 18:19:25.324356 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] Sep 4 18:19:25.324363 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0x1bfffffff] Sep 4 18:19:25.324369 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x1c0000000-0xfbfffffff] Sep 4 18:19:25.324376 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x1000000000-0xffffffffff] Sep 4 18:19:25.324382 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000000000-0x1ffffffffff] Sep 4 18:19:25.324389 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000000000-0x3ffffffffff] Sep 4 18:19:25.324397 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000000000-0x7ffffffffff] Sep 4 18:19:25.324403 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000000000-0xfffffffffff] Sep 4 18:19:25.324409 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000000-0x1fffffffffff] Sep 4 18:19:25.324416 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x200000000000-0x3fffffffffff] Sep 4 18:19:25.324422 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x400000000000-0x7fffffffffff] Sep 4 18:19:25.324429 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x800000000000-0xffffffffffff] Sep 4 18:19:25.324435 kernel: NUMA: NODE_DATA [mem 0x1bf7ee800-0x1bf7f3fff] Sep 4 18:19:25.324441 kernel: Zone ranges: Sep 4 18:19:25.324448 kernel: DMA [mem 0x0000000000000000-0x00000000ffffffff] Sep 4 18:19:25.324454 kernel: DMA32 empty Sep 4 18:19:25.324460 kernel: Normal [mem 0x0000000100000000-0x00000001bfffffff] Sep 4 18:19:25.324468 kernel: Movable zone start for each node Sep 4 18:19:25.324477 kernel: Early memory node ranges Sep 4 18:19:25.324484 kernel: node 0: [mem 0x0000000000000000-0x00000000007fffff] Sep 4 18:19:25.324491 kernel: node 0: [mem 0x0000000000824000-0x000000003ec80fff] Sep 4 18:19:25.324498 kernel: node 0: [mem 0x000000003ec81000-0x000000003eca9fff] Sep 4 18:19:25.324506 kernel: node 0: [mem 0x000000003ecaa000-0x000000003fd29fff] Sep 4 18:19:25.324513 kernel: node 0: [mem 0x000000003fd2a000-0x000000003fd7dfff] Sep 4 18:19:25.324519 kernel: node 0: [mem 0x000000003fd7e000-0x000000003fd89fff] Sep 4 18:19:25.324526 kernel: node 0: [mem 0x000000003fd8a000-0x000000003fd8dfff] Sep 4 18:19:25.324533 kernel: node 0: [mem 0x000000003fd8e000-0x000000003fffffff] Sep 4 18:19:25.324540 kernel: node 0: [mem 0x0000000100000000-0x00000001bfffffff] Sep 4 18:19:25.324546 kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000001bfffffff] Sep 4 18:19:25.324553 kernel: On node 0, zone DMA: 36 pages in unavailable ranges Sep 4 18:19:25.324560 kernel: psci: probing for conduit method from ACPI. Sep 4 18:19:25.324567 kernel: psci: PSCIv1.1 detected in firmware. Sep 4 18:19:25.324573 kernel: psci: Using standard PSCI v0.2 function IDs Sep 4 18:19:25.324580 kernel: psci: MIGRATE_INFO_TYPE not supported. Sep 4 18:19:25.324588 kernel: psci: SMC Calling Convention v1.4 Sep 4 18:19:25.324595 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x0 -> Node 0 Sep 4 18:19:25.324601 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x1 -> Node 0 Sep 4 18:19:25.324608 kernel: percpu: Embedded 31 pages/cpu s86632 r8192 d32152 u126976 Sep 4 18:19:25.324615 kernel: pcpu-alloc: s86632 r8192 d32152 u126976 alloc=31*4096 Sep 4 18:19:25.324622 kernel: pcpu-alloc: [0] 0 [0] 1 Sep 4 18:19:25.324629 kernel: Detected PIPT I-cache on CPU0 Sep 4 18:19:25.324635 kernel: CPU features: detected: GIC system register CPU interface Sep 4 18:19:25.324642 kernel: CPU features: detected: Hardware dirty bit management Sep 4 18:19:25.324649 kernel: CPU features: detected: Spectre-BHB Sep 4 18:19:25.324656 kernel: CPU features: kernel page table isolation forced ON by KASLR Sep 4 18:19:25.326691 kernel: CPU features: detected: Kernel page table isolation (KPTI) Sep 4 18:19:25.326709 kernel: CPU features: detected: ARM erratum 1418040 Sep 4 18:19:25.326717 kernel: CPU features: detected: ARM erratum 1542419 (kernel portion) Sep 4 18:19:25.326724 kernel: CPU features: detected: SSBS not fully self-synchronizing Sep 4 18:19:25.326730 kernel: alternatives: applying boot alternatives Sep 4 18:19:25.326739 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=28a986328b36e7de6a755f88bb335afbeb3e3932bc9a20c5f8e57b952c2d23a9 Sep 4 18:19:25.326746 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 4 18:19:25.326754 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 4 18:19:25.326761 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Sep 4 18:19:25.326768 kernel: Fallback order for Node 0: 0 Sep 4 18:19:25.326775 kernel: Built 1 zonelists, mobility grouping on. Total pages: 1032156 Sep 4 18:19:25.326783 kernel: Policy zone: Normal Sep 4 18:19:25.326790 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 4 18:19:25.326797 kernel: software IO TLB: area num 2. Sep 4 18:19:25.326804 kernel: software IO TLB: mapped [mem 0x000000003a925000-0x000000003e925000] (64MB) Sep 4 18:19:25.326811 kernel: Memory: 3986076K/4194160K available (10240K kernel code, 2184K rwdata, 8084K rodata, 39296K init, 897K bss, 208084K reserved, 0K cma-reserved) Sep 4 18:19:25.326818 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Sep 4 18:19:25.326825 kernel: trace event string verifier disabled Sep 4 18:19:25.326832 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 4 18:19:25.326839 kernel: rcu: RCU event tracing is enabled. Sep 4 18:19:25.326846 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Sep 4 18:19:25.326853 kernel: Trampoline variant of Tasks RCU enabled. Sep 4 18:19:25.326860 kernel: Tracing variant of Tasks RCU enabled. Sep 4 18:19:25.326868 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 4 18:19:25.326875 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Sep 4 18:19:25.326882 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Sep 4 18:19:25.326893 kernel: GICv3: 960 SPIs implemented Sep 4 18:19:25.326899 kernel: GICv3: 0 Extended SPIs implemented Sep 4 18:19:25.326906 kernel: Root IRQ handler: gic_handle_irq Sep 4 18:19:25.326913 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Sep 4 18:19:25.326920 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000effee000 Sep 4 18:19:25.326927 kernel: ITS: No ITS available, not enabling LPIs Sep 4 18:19:25.326934 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 4 18:19:25.326941 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 4 18:19:25.326949 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Sep 4 18:19:25.326956 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Sep 4 18:19:25.326963 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Sep 4 18:19:25.326970 kernel: Console: colour dummy device 80x25 Sep 4 18:19:25.326977 kernel: printk: console [tty1] enabled Sep 4 18:19:25.326984 kernel: ACPI: Core revision 20230628 Sep 4 18:19:25.326992 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Sep 4 18:19:25.326999 kernel: pid_max: default: 32768 minimum: 301 Sep 4 18:19:25.327006 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Sep 4 18:19:25.327013 kernel: landlock: Up and running. Sep 4 18:19:25.327021 kernel: SELinux: Initializing. Sep 4 18:19:25.327028 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 4 18:19:25.327035 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Sep 4 18:19:25.327042 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1. Sep 4 18:19:25.327050 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1. Sep 4 18:19:25.327057 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3a8030, hints 0xe, misc 0x31e1 Sep 4 18:19:25.327064 kernel: Hyper-V: Host Build 10.0.22477.1369-1-0 Sep 4 18:19:25.327077 kernel: Hyper-V: enabling crash_kexec_post_notifiers Sep 4 18:19:25.327084 kernel: rcu: Hierarchical SRCU implementation. Sep 4 18:19:25.327092 kernel: rcu: Max phase no-delay instances is 400. Sep 4 18:19:25.327099 kernel: Remapping and enabling EFI services. Sep 4 18:19:25.327108 kernel: smp: Bringing up secondary CPUs ... Sep 4 18:19:25.327115 kernel: Detected PIPT I-cache on CPU1 Sep 4 18:19:25.327123 kernel: GICv3: CPU1: found redistributor 1 region 1:0x00000000f000e000 Sep 4 18:19:25.327130 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Sep 4 18:19:25.327138 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Sep 4 18:19:25.327145 kernel: smp: Brought up 1 node, 2 CPUs Sep 4 18:19:25.327154 kernel: SMP: Total of 2 processors activated. Sep 4 18:19:25.327161 kernel: CPU features: detected: 32-bit EL0 Support Sep 4 18:19:25.327168 kernel: CPU features: detected: Instruction cache invalidation not required for I/D coherence Sep 4 18:19:25.327176 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Sep 4 18:19:25.327183 kernel: CPU features: detected: CRC32 instructions Sep 4 18:19:25.327191 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Sep 4 18:19:25.327198 kernel: CPU features: detected: LSE atomic instructions Sep 4 18:19:25.327206 kernel: CPU features: detected: Privileged Access Never Sep 4 18:19:25.327213 kernel: CPU: All CPU(s) started at EL1 Sep 4 18:19:25.327222 kernel: alternatives: applying system-wide alternatives Sep 4 18:19:25.327229 kernel: devtmpfs: initialized Sep 4 18:19:25.327236 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 4 18:19:25.327244 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Sep 4 18:19:25.327251 kernel: pinctrl core: initialized pinctrl subsystem Sep 4 18:19:25.327259 kernel: SMBIOS 3.1.0 present. Sep 4 18:19:25.327266 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 11/28/2023 Sep 4 18:19:25.327274 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 4 18:19:25.327281 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Sep 4 18:19:25.327290 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Sep 4 18:19:25.327298 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Sep 4 18:19:25.327305 kernel: audit: initializing netlink subsys (disabled) Sep 4 18:19:25.327313 kernel: audit: type=2000 audit(0.047:1): state=initialized audit_enabled=0 res=1 Sep 4 18:19:25.327320 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 4 18:19:25.327327 kernel: cpuidle: using governor menu Sep 4 18:19:25.327335 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Sep 4 18:19:25.327342 kernel: ASID allocator initialised with 32768 entries Sep 4 18:19:25.327351 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 4 18:19:25.327358 kernel: Serial: AMBA PL011 UART driver Sep 4 18:19:25.327366 kernel: Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Sep 4 18:19:25.327373 kernel: Modules: 0 pages in range for non-PLT usage Sep 4 18:19:25.327380 kernel: Modules: 509056 pages in range for PLT usage Sep 4 18:19:25.327388 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 4 18:19:25.327395 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Sep 4 18:19:25.327403 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Sep 4 18:19:25.327410 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Sep 4 18:19:25.327419 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 4 18:19:25.327427 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Sep 4 18:19:25.327434 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Sep 4 18:19:25.327441 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Sep 4 18:19:25.327449 kernel: ACPI: Added _OSI(Module Device) Sep 4 18:19:25.327456 kernel: ACPI: Added _OSI(Processor Device) Sep 4 18:19:25.327463 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Sep 4 18:19:25.327471 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 4 18:19:25.327478 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Sep 4 18:19:25.327485 kernel: ACPI: Interpreter enabled Sep 4 18:19:25.327494 kernel: ACPI: Using GIC for interrupt routing Sep 4 18:19:25.327501 kernel: ARMH0011:00: ttyAMA0 at MMIO 0xeffec000 (irq = 12, base_baud = 0) is a SBSA Sep 4 18:19:25.327509 kernel: printk: console [ttyAMA0] enabled Sep 4 18:19:25.327516 kernel: printk: bootconsole [pl11] disabled Sep 4 18:19:25.327523 kernel: ARMH0011:01: ttyAMA1 at MMIO 0xeffeb000 (irq = 13, base_baud = 0) is a SBSA Sep 4 18:19:25.327531 kernel: iommu: Default domain type: Translated Sep 4 18:19:25.327538 kernel: iommu: DMA domain TLB invalidation policy: strict mode Sep 4 18:19:25.327545 kernel: efivars: Registered efivars operations Sep 4 18:19:25.327553 kernel: vgaarb: loaded Sep 4 18:19:25.327562 kernel: clocksource: Switched to clocksource arch_sys_counter Sep 4 18:19:25.327569 kernel: VFS: Disk quotas dquot_6.6.0 Sep 4 18:19:25.327576 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 4 18:19:25.327584 kernel: pnp: PnP ACPI init Sep 4 18:19:25.327591 kernel: pnp: PnP ACPI: found 0 devices Sep 4 18:19:25.327598 kernel: NET: Registered PF_INET protocol family Sep 4 18:19:25.327606 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Sep 4 18:19:25.327613 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Sep 4 18:19:25.327621 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 4 18:19:25.327630 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Sep 4 18:19:25.327637 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Sep 4 18:19:25.327644 kernel: TCP: Hash tables configured (established 32768 bind 32768) Sep 4 18:19:25.327652 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 4 18:19:25.327676 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Sep 4 18:19:25.327686 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 4 18:19:25.327693 kernel: PCI: CLS 0 bytes, default 64 Sep 4 18:19:25.327701 kernel: kvm [1]: HYP mode not available Sep 4 18:19:25.327710 kernel: Initialise system trusted keyrings Sep 4 18:19:25.327717 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Sep 4 18:19:25.327725 kernel: Key type asymmetric registered Sep 4 18:19:25.327732 kernel: Asymmetric key parser 'x509' registered Sep 4 18:19:25.327739 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Sep 4 18:19:25.327747 kernel: io scheduler mq-deadline registered Sep 4 18:19:25.327754 kernel: io scheduler kyber registered Sep 4 18:19:25.327761 kernel: io scheduler bfq registered Sep 4 18:19:25.327769 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 4 18:19:25.327776 kernel: thunder_xcv, ver 1.0 Sep 4 18:19:25.327784 kernel: thunder_bgx, ver 1.0 Sep 4 18:19:25.327792 kernel: nicpf, ver 1.0 Sep 4 18:19:25.327799 kernel: nicvf, ver 1.0 Sep 4 18:19:25.327925 kernel: rtc-efi rtc-efi.0: registered as rtc0 Sep 4 18:19:25.327995 kernel: rtc-efi rtc-efi.0: setting system clock to 2024-09-04T18:19:24 UTC (1725473964) Sep 4 18:19:25.328006 kernel: efifb: probing for efifb Sep 4 18:19:25.328014 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Sep 4 18:19:25.328023 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Sep 4 18:19:25.328031 kernel: efifb: scrolling: redraw Sep 4 18:19:25.328038 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Sep 4 18:19:25.328046 kernel: Console: switching to colour frame buffer device 128x48 Sep 4 18:19:25.328053 kernel: fb0: EFI VGA frame buffer device Sep 4 18:19:25.328061 kernel: SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping .... Sep 4 18:19:25.328068 kernel: hid: raw HID events driver (C) Jiri Kosina Sep 4 18:19:25.328075 kernel: No ACPI PMU IRQ for CPU0 Sep 4 18:19:25.328083 kernel: No ACPI PMU IRQ for CPU1 Sep 4 18:19:25.328090 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 1 counters available Sep 4 18:19:25.328099 kernel: watchdog: Delayed init of the lockup detector failed: -19 Sep 4 18:19:25.328106 kernel: watchdog: Hard watchdog permanently disabled Sep 4 18:19:25.328114 kernel: NET: Registered PF_INET6 protocol family Sep 4 18:19:25.328121 kernel: Segment Routing with IPv6 Sep 4 18:19:25.328128 kernel: In-situ OAM (IOAM) with IPv6 Sep 4 18:19:25.328135 kernel: NET: Registered PF_PACKET protocol family Sep 4 18:19:25.328143 kernel: Key type dns_resolver registered Sep 4 18:19:25.328150 kernel: registered taskstats version 1 Sep 4 18:19:25.328157 kernel: Loading compiled-in X.509 certificates Sep 4 18:19:25.328166 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.48-flatcar: 6782952639b29daf968f5d0c3e73fb25e5af1d5e' Sep 4 18:19:25.328173 kernel: Key type .fscrypt registered Sep 4 18:19:25.328181 kernel: Key type fscrypt-provisioning registered Sep 4 18:19:25.328189 kernel: ima: No TPM chip found, activating TPM-bypass! Sep 4 18:19:25.328196 kernel: ima: Allocated hash algorithm: sha1 Sep 4 18:19:25.328203 kernel: ima: No architecture policies found Sep 4 18:19:25.328211 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Sep 4 18:19:25.328218 kernel: clk: Disabling unused clocks Sep 4 18:19:25.328227 kernel: Freeing unused kernel memory: 39296K Sep 4 18:19:25.328234 kernel: Run /init as init process Sep 4 18:19:25.328242 kernel: with arguments: Sep 4 18:19:25.328249 kernel: /init Sep 4 18:19:25.328256 kernel: with environment: Sep 4 18:19:25.328263 kernel: HOME=/ Sep 4 18:19:25.328270 kernel: TERM=linux Sep 4 18:19:25.328278 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 4 18:19:25.328287 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Sep 4 18:19:25.328298 systemd[1]: Detected virtualization microsoft. Sep 4 18:19:25.328306 systemd[1]: Detected architecture arm64. Sep 4 18:19:25.328314 systemd[1]: Running in initrd. Sep 4 18:19:25.328322 systemd[1]: No hostname configured, using default hostname. Sep 4 18:19:25.328329 systemd[1]: Hostname set to . Sep 4 18:19:25.328338 systemd[1]: Initializing machine ID from random generator. Sep 4 18:19:25.328346 systemd[1]: Queued start job for default target initrd.target. Sep 4 18:19:25.328355 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 4 18:19:25.328363 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 4 18:19:25.328371 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 4 18:19:25.328380 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 4 18:19:25.328388 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 4 18:19:25.328396 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 4 18:19:25.328406 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Sep 4 18:19:25.328415 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 4 18:19:25.328424 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 4 18:19:25.328432 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 4 18:19:25.328440 systemd[1]: Reached target paths.target - Path Units. Sep 4 18:19:25.328448 systemd[1]: Reached target slices.target - Slice Units. Sep 4 18:19:25.328456 systemd[1]: Reached target swap.target - Swaps. Sep 4 18:19:25.328464 systemd[1]: Reached target timers.target - Timer Units. Sep 4 18:19:25.328472 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 4 18:19:25.328480 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 4 18:19:25.328489 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 4 18:19:25.328497 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Sep 4 18:19:25.328505 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 4 18:19:25.328513 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 4 18:19:25.328521 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 4 18:19:25.328529 systemd[1]: Reached target sockets.target - Socket Units. Sep 4 18:19:25.328537 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 4 18:19:25.328545 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 4 18:19:25.328555 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 4 18:19:25.328563 systemd[1]: Starting systemd-fsck-usr.service... Sep 4 18:19:25.328571 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 4 18:19:25.328579 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 4 18:19:25.328601 systemd-journald[216]: Collecting audit messages is disabled. Sep 4 18:19:25.328622 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 18:19:25.328630 systemd-journald[216]: Journal started Sep 4 18:19:25.328648 systemd-journald[216]: Runtime Journal (/run/log/journal/81336ec74b134e57ab7c058276b7e69d) is 8.0M, max 78.6M, 70.6M free. Sep 4 18:19:25.333580 systemd-modules-load[217]: Inserted module 'overlay' Sep 4 18:19:25.346925 systemd[1]: Started systemd-journald.service - Journal Service. Sep 4 18:19:25.371968 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Sep 4 18:19:25.365996 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 4 18:19:25.376018 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 4 18:19:25.389131 kernel: Bridge firewalling registered Sep 4 18:19:25.388312 systemd-modules-load[217]: Inserted module 'br_netfilter' Sep 4 18:19:25.396451 systemd[1]: Finished systemd-fsck-usr.service. Sep 4 18:19:25.406104 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 4 18:19:25.416551 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 18:19:25.437957 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 4 18:19:25.446020 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 4 18:19:25.471853 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 4 18:19:25.487857 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 4 18:19:25.504690 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 18:19:25.520702 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 4 18:19:25.526983 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 4 18:19:25.539886 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 4 18:19:25.564883 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 4 18:19:25.571873 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 4 18:19:25.584632 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 4 18:19:25.609962 dracut-cmdline[253]: dracut-dracut-053 Sep 4 18:19:25.613847 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 4 18:19:25.635314 dracut-cmdline[253]: 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=28a986328b36e7de6a755f88bb335afbeb3e3932bc9a20c5f8e57b952c2d23a9 Sep 4 18:19:25.673691 systemd-resolved[255]: Positive Trust Anchors: Sep 4 18:19:25.673706 systemd-resolved[255]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 4 18:19:25.673738 systemd-resolved[255]: 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 Sep 4 18:19:25.676053 systemd-resolved[255]: Defaulting to hostname 'linux'. Sep 4 18:19:25.676843 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 4 18:19:25.685442 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 4 18:19:25.812692 kernel: SCSI subsystem initialized Sep 4 18:19:25.820677 kernel: Loading iSCSI transport class v2.0-870. Sep 4 18:19:25.832688 kernel: iscsi: registered transport (tcp) Sep 4 18:19:25.849885 kernel: iscsi: registered transport (qla4xxx) Sep 4 18:19:25.849922 kernel: QLogic iSCSI HBA Driver Sep 4 18:19:25.888239 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 4 18:19:25.901877 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 4 18:19:25.934679 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 4 18:19:25.934726 kernel: device-mapper: uevent: version 1.0.3 Sep 4 18:19:25.941328 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Sep 4 18:19:25.989679 kernel: raid6: neonx8 gen() 15780 MB/s Sep 4 18:19:26.009680 kernel: raid6: neonx4 gen() 15651 MB/s Sep 4 18:19:26.029678 kernel: raid6: neonx2 gen() 13239 MB/s Sep 4 18:19:26.050677 kernel: raid6: neonx1 gen() 10479 MB/s Sep 4 18:19:26.070677 kernel: raid6: int64x8 gen() 6966 MB/s Sep 4 18:19:26.090685 kernel: raid6: int64x4 gen() 7347 MB/s Sep 4 18:19:26.111678 kernel: raid6: int64x2 gen() 6133 MB/s Sep 4 18:19:26.135300 kernel: raid6: int64x1 gen() 5063 MB/s Sep 4 18:19:26.135312 kernel: raid6: using algorithm neonx8 gen() 15780 MB/s Sep 4 18:19:26.159298 kernel: raid6: .... xor() 11937 MB/s, rmw enabled Sep 4 18:19:26.159326 kernel: raid6: using neon recovery algorithm Sep 4 18:19:26.168674 kernel: xor: measuring software checksum speed Sep 4 18:19:26.172671 kernel: 8regs : 19878 MB/sec Sep 4 18:19:26.176671 kernel: 32regs : 19692 MB/sec Sep 4 18:19:26.184461 kernel: arm64_neon : 27261 MB/sec Sep 4 18:19:26.184491 kernel: xor: using function: arm64_neon (27261 MB/sec) Sep 4 18:19:26.234713 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 4 18:19:26.244113 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 4 18:19:26.259783 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 4 18:19:26.282756 systemd-udevd[440]: Using default interface naming scheme 'v255'. Sep 4 18:19:26.286128 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 4 18:19:26.308832 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 4 18:19:26.322350 dracut-pre-trigger[448]: rd.md=0: removing MD RAID activation Sep 4 18:19:26.347409 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 4 18:19:26.363916 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 4 18:19:26.407700 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 4 18:19:26.428920 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 4 18:19:26.453225 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 4 18:19:26.468545 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 4 18:19:26.476583 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 4 18:19:26.493174 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 4 18:19:26.521820 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 4 18:19:26.541357 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 4 18:19:26.543593 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 18:19:26.562681 kernel: hv_vmbus: Vmbus version:5.3 Sep 4 18:19:26.567713 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 4 18:19:26.581544 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 4 18:19:26.661573 kernel: hv_vmbus: registering driver hyperv_keyboard Sep 4 18:19:26.661595 kernel: hv_vmbus: registering driver hid_hyperv Sep 4 18:19:26.661605 kernel: hv_vmbus: registering driver hv_netvsc Sep 4 18:19:26.661614 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/VMBUS:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input0 Sep 4 18:19:26.661630 kernel: pps_core: LinuxPPS API ver. 1 registered Sep 4 18:19:26.661640 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Sep 4 18:19:26.661650 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Sep 4 18:19:26.662696 kernel: hid-generic 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Sep 4 18:19:26.581731 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 18:19:26.680785 kernel: hv_vmbus: registering driver hv_storvsc Sep 4 18:19:26.680810 kernel: PTP clock support registered Sep 4 18:19:26.620892 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 18:19:26.714146 kernel: scsi host0: storvsc_host_t Sep 4 18:19:26.714316 kernel: scsi host1: storvsc_host_t Sep 4 18:19:26.714418 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Sep 4 18:19:26.669317 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 18:19:26.730682 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Sep 4 18:19:26.697017 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 4 18:19:26.725516 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 18:19:26.764143 kernel: hv_netvsc 0022487b-6c94-0022-487b-6c940022487b eth0: VF slot 1 added Sep 4 18:19:26.738140 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 4 18:19:26.738333 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 18:19:26.757451 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 18:19:26.783190 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 18:19:26.816058 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 18:19:26.833855 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 4 18:19:26.873212 kernel: hv_utils: Registering HyperV Utility Driver Sep 4 18:19:26.873242 kernel: hv_vmbus: registering driver hv_utils Sep 4 18:19:26.873253 kernel: hv_vmbus: registering driver hv_pci Sep 4 18:19:26.873262 kernel: hv_utils: Heartbeat IC version 3.0 Sep 4 18:19:26.873271 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Sep 4 18:19:26.873451 kernel: hv_utils: Shutdown IC version 3.2 Sep 4 18:19:26.873462 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Sep 4 18:19:26.877678 kernel: hv_utils: TimeSync IC version 4.0 Sep 4 18:19:26.894547 systemd-resolved[255]: Clock change detected. Flushing caches. Sep 4 18:19:26.916933 kernel: hv_pci 349b8c2e-64fa-4d44-abeb-0587307e841e: PCI VMBus probing: Using version 0x10004 Sep 4 18:19:26.917123 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Sep 4 18:19:26.917262 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Sep 4 18:19:26.927574 kernel: hv_pci 349b8c2e-64fa-4d44-abeb-0587307e841e: PCI host bridge to bus 64fa:00 Sep 4 18:19:26.927756 kernel: sd 0:0:0:0: [sda] Write Protect is off Sep 4 18:19:26.938630 kernel: pci_bus 64fa:00: root bus resource [mem 0xfc0000000-0xfc00fffff window] Sep 4 18:19:26.938837 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Sep 4 18:19:26.939523 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 18:19:26.960090 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Sep 4 18:19:26.960233 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Sep 4 18:19:26.960335 kernel: pci_bus 64fa:00: No busn resource found for root bus, will use [bus 00-ff] Sep 4 18:19:26.972910 kernel: pci 64fa:00:02.0: [15b3:1018] type 00 class 0x020000 Sep 4 18:19:26.980837 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 4 18:19:26.980871 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Sep 4 18:19:26.980998 kernel: pci 64fa:00:02.0: reg 0x10: [mem 0xfc0000000-0xfc00fffff 64bit pref] Sep 4 18:19:26.996757 kernel: pci 64fa:00:02.0: enabling Extended Tags Sep 4 18:19:27.016760 kernel: pci 64fa:00:02.0: 0.000 Gb/s available PCIe bandwidth, limited by Unknown x0 link at 64fa:00:02.0 (capable of 126.016 Gb/s with 8.0 GT/s PCIe x16 link) Sep 4 18:19:27.030398 kernel: pci_bus 64fa:00: busn_res: [bus 00-ff] end is updated to 00 Sep 4 18:19:27.030598 kernel: pci 64fa:00:02.0: BAR 0: assigned [mem 0xfc0000000-0xfc00fffff 64bit pref] Sep 4 18:19:27.072552 kernel: mlx5_core 64fa:00:02.0: enabling device (0000 -> 0002) Sep 4 18:19:27.079676 kernel: mlx5_core 64fa:00:02.0: firmware version: 16.30.1284 Sep 4 18:19:27.277882 kernel: hv_netvsc 0022487b-6c94-0022-487b-6c940022487b eth0: VF registering: eth1 Sep 4 18:19:27.278076 kernel: mlx5_core 64fa:00:02.0 eth1: joined to eth0 Sep 4 18:19:27.286360 kernel: mlx5_core 64fa:00:02.0: MLX5E: StrdRq(1) RqSz(8) StrdSz(2048) RxCqeCmprss(0 basic) Sep 4 18:19:27.298687 kernel: mlx5_core 64fa:00:02.0 enP25850s1: renamed from eth1 Sep 4 18:19:27.682113 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Sep 4 18:19:27.883685 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/sda6 scanned by (udev-worker) (486) Sep 4 18:19:27.897140 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Sep 4 18:19:27.917064 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Sep 4 18:19:28.010688 kernel: BTRFS: device fsid 3e706a0f-a579-4862-bc52-e66e95e66d87 devid 1 transid 42 /dev/sda3 scanned by (udev-worker) (489) Sep 4 18:19:28.023336 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Sep 4 18:19:28.030630 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Sep 4 18:19:28.064872 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 4 18:19:28.090691 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 4 18:19:28.100039 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 4 18:19:28.106705 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 4 18:19:29.115743 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 4 18:19:29.116038 disk-uuid[607]: The operation has completed successfully. Sep 4 18:19:29.172957 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 4 18:19:29.173052 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 4 18:19:29.201779 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 4 18:19:29.216928 sh[720]: Success Sep 4 18:19:29.250711 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Sep 4 18:19:29.652034 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 4 18:19:29.661765 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 4 18:19:29.670098 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 4 18:19:29.707071 kernel: BTRFS info (device dm-0): first mount of filesystem 3e706a0f-a579-4862-bc52-e66e95e66d87 Sep 4 18:19:29.707112 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Sep 4 18:19:29.714176 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Sep 4 18:19:29.719395 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 4 18:19:29.723830 kernel: BTRFS info (device dm-0): using free space tree Sep 4 18:19:30.594721 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 4 18:19:30.600381 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 4 18:19:30.615862 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 4 18:19:30.623808 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 4 18:19:30.661096 kernel: BTRFS info (device sda6): first mount of filesystem e85e5091-8620-4def-b250-7009f4048f6e Sep 4 18:19:30.661151 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Sep 4 18:19:30.665863 kernel: BTRFS info (device sda6): using free space tree Sep 4 18:19:30.695687 kernel: BTRFS info (device sda6): auto enabling async discard Sep 4 18:19:30.711897 systemd[1]: mnt-oem.mount: Deactivated successfully. Sep 4 18:19:30.717693 kernel: BTRFS info (device sda6): last unmount of filesystem e85e5091-8620-4def-b250-7009f4048f6e Sep 4 18:19:30.725257 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 4 18:19:30.732996 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 4 18:19:30.755797 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 4 18:19:30.776831 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 4 18:19:30.800069 systemd-networkd[904]: lo: Link UP Sep 4 18:19:30.800079 systemd-networkd[904]: lo: Gained carrier Sep 4 18:19:30.801596 systemd-networkd[904]: Enumeration completed Sep 4 18:19:30.801724 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 4 18:19:30.812027 systemd[1]: Reached target network.target - Network. Sep 4 18:19:30.815339 systemd-networkd[904]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 18:19:30.815341 systemd-networkd[904]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 4 18:19:30.907791 kernel: mlx5_core 64fa:00:02.0 enP25850s1: Link up Sep 4 18:19:30.949669 kernel: hv_netvsc 0022487b-6c94-0022-487b-6c940022487b eth0: Data path switched to VF: enP25850s1 Sep 4 18:19:30.950071 systemd-networkd[904]: enP25850s1: Link UP Sep 4 18:19:30.950159 systemd-networkd[904]: eth0: Link UP Sep 4 18:19:30.950279 systemd-networkd[904]: eth0: Gained carrier Sep 4 18:19:30.950287 systemd-networkd[904]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 18:19:30.975926 systemd-networkd[904]: enP25850s1: Gained carrier Sep 4 18:19:30.989692 systemd-networkd[904]: eth0: DHCPv4 address 10.200.20.47/24, gateway 10.200.20.1 acquired from 168.63.129.16 Sep 4 18:19:32.139899 systemd-networkd[904]: enP25850s1: Gained IPv6LL Sep 4 18:19:32.561766 ignition[903]: Ignition 2.19.0 Sep 4 18:19:32.561778 ignition[903]: Stage: fetch-offline Sep 4 18:19:32.566904 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 4 18:19:32.561812 ignition[903]: no configs at "/usr/lib/ignition/base.d" Sep 4 18:19:32.561820 ignition[903]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 4 18:19:32.561902 ignition[903]: parsed url from cmdline: "" Sep 4 18:19:32.561905 ignition[903]: no config URL provided Sep 4 18:19:32.561909 ignition[903]: reading system config file "/usr/lib/ignition/user.ign" Sep 4 18:19:32.594913 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Sep 4 18:19:32.561916 ignition[903]: no config at "/usr/lib/ignition/user.ign" Sep 4 18:19:32.561920 ignition[903]: failed to fetch config: resource requires networking Sep 4 18:19:32.562089 ignition[903]: Ignition finished successfully Sep 4 18:19:32.613684 ignition[915]: Ignition 2.19.0 Sep 4 18:19:32.613691 ignition[915]: Stage: fetch Sep 4 18:19:32.613921 ignition[915]: no configs at "/usr/lib/ignition/base.d" Sep 4 18:19:32.613933 ignition[915]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 4 18:19:32.614038 ignition[915]: parsed url from cmdline: "" Sep 4 18:19:32.614042 ignition[915]: no config URL provided Sep 4 18:19:32.614046 ignition[915]: reading system config file "/usr/lib/ignition/user.ign" Sep 4 18:19:32.614088 ignition[915]: no config at "/usr/lib/ignition/user.ign" Sep 4 18:19:32.614113 ignition[915]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Sep 4 18:19:32.711622 ignition[915]: GET result: OK Sep 4 18:19:32.711694 ignition[915]: config has been read from IMDS userdata Sep 4 18:19:32.711722 ignition[915]: parsing config with SHA512: fc632eea6abdf46392ce911589001bfbd862430caa3ec970091b3f5bd71dab782c429d7c3fee44669ed599e3cc12f400220d9dd4099c710f57860443096e13d9 Sep 4 18:19:32.716651 unknown[915]: fetched base config from "system" Sep 4 18:19:32.720154 ignition[915]: fetch: fetch complete Sep 4 18:19:32.716676 unknown[915]: fetched base config from "system" Sep 4 18:19:32.720159 ignition[915]: fetch: fetch passed Sep 4 18:19:32.716682 unknown[915]: fetched user config from "azure" Sep 4 18:19:32.720215 ignition[915]: Ignition finished successfully Sep 4 18:19:32.726613 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Sep 4 18:19:32.748881 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 4 18:19:32.766701 ignition[921]: Ignition 2.19.0 Sep 4 18:19:32.772931 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 4 18:19:32.766707 ignition[921]: Stage: kargs Sep 4 18:19:32.766860 ignition[921]: no configs at "/usr/lib/ignition/base.d" Sep 4 18:19:32.766869 ignition[921]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 4 18:19:32.767632 ignition[921]: kargs: kargs passed Sep 4 18:19:32.796520 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 4 18:19:32.767810 ignition[921]: Ignition finished successfully Sep 4 18:19:32.811384 ignition[928]: Ignition 2.19.0 Sep 4 18:19:32.821309 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 4 18:19:32.811390 ignition[928]: Stage: disks Sep 4 18:19:32.828373 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 4 18:19:32.811597 ignition[928]: no configs at "/usr/lib/ignition/base.d" Sep 4 18:19:32.837998 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 4 18:19:32.811609 ignition[928]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 4 18:19:32.850874 systemd-networkd[904]: eth0: Gained IPv6LL Sep 4 18:19:32.816648 ignition[928]: disks: disks passed Sep 4 18:19:32.851231 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 4 18:19:32.816713 ignition[928]: Ignition finished successfully Sep 4 18:19:32.857146 systemd[1]: Reached target sysinit.target - System Initialization. Sep 4 18:19:32.866453 systemd[1]: Reached target basic.target - Basic System. Sep 4 18:19:32.899894 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 4 18:19:33.025781 systemd-fsck[936]: ROOT: clean, 14/7326000 files, 477710/7359488 blocks Sep 4 18:19:33.033725 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 4 18:19:33.052802 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 4 18:19:33.108680 kernel: EXT4-fs (sda9): mounted filesystem 901d46b0-2319-4536-8a6d-46889db73e8c r/w with ordered data mode. Quota mode: none. Sep 4 18:19:33.109588 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 4 18:19:33.114973 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 4 18:19:33.212731 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 4 18:19:33.219779 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 4 18:19:33.231859 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Sep 4 18:19:33.246836 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 4 18:19:33.280971 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/sda6 scanned by mount (947) Sep 4 18:19:33.280993 kernel: BTRFS info (device sda6): first mount of filesystem e85e5091-8620-4def-b250-7009f4048f6e Sep 4 18:19:33.281004 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Sep 4 18:19:33.246871 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 4 18:19:33.304551 kernel: BTRFS info (device sda6): using free space tree Sep 4 18:19:33.304572 kernel: BTRFS info (device sda6): auto enabling async discard Sep 4 18:19:33.297644 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 4 18:19:33.310835 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 4 18:19:33.330891 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 4 18:19:34.382499 coreos-metadata[949]: Sep 04 18:19:34.382 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Sep 4 18:19:34.391487 coreos-metadata[949]: Sep 04 18:19:34.389 INFO Fetch successful Sep 4 18:19:34.391487 coreos-metadata[949]: Sep 04 18:19:34.389 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Sep 4 18:19:34.409201 coreos-metadata[949]: Sep 04 18:19:34.399 INFO Fetch successful Sep 4 18:19:34.409201 coreos-metadata[949]: Sep 04 18:19:34.404 INFO wrote hostname ci-4054.1.0-a-bc791940be to /sysroot/etc/hostname Sep 4 18:19:34.409588 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 4 18:19:35.297557 initrd-setup-root[976]: cut: /sysroot/etc/passwd: No such file or directory Sep 4 18:19:35.334819 initrd-setup-root[983]: cut: /sysroot/etc/group: No such file or directory Sep 4 18:19:35.374873 initrd-setup-root[990]: cut: /sysroot/etc/shadow: No such file or directory Sep 4 18:19:35.384023 initrd-setup-root[997]: cut: /sysroot/etc/gshadow: No such file or directory Sep 4 18:19:36.682727 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 4 18:19:36.698824 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 4 18:19:36.706825 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 4 18:19:36.732077 kernel: BTRFS info (device sda6): last unmount of filesystem e85e5091-8620-4def-b250-7009f4048f6e Sep 4 18:19:36.726283 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 4 18:19:36.761592 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 4 18:19:36.769100 ignition[1066]: INFO : Ignition 2.19.0 Sep 4 18:19:36.778986 ignition[1066]: INFO : Stage: mount Sep 4 18:19:36.778986 ignition[1066]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 4 18:19:36.778986 ignition[1066]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 4 18:19:36.778986 ignition[1066]: INFO : mount: mount passed Sep 4 18:19:36.778986 ignition[1066]: INFO : Ignition finished successfully Sep 4 18:19:36.783097 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 4 18:19:36.803766 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 4 18:19:36.819912 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 4 18:19:36.858333 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/sda6 scanned by mount (1077) Sep 4 18:19:36.858379 kernel: BTRFS info (device sda6): first mount of filesystem e85e5091-8620-4def-b250-7009f4048f6e Sep 4 18:19:36.869357 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Sep 4 18:19:36.869386 kernel: BTRFS info (device sda6): using free space tree Sep 4 18:19:36.875675 kernel: BTRFS info (device sda6): auto enabling async discard Sep 4 18:19:36.877202 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 4 18:19:36.903694 ignition[1094]: INFO : Ignition 2.19.0 Sep 4 18:19:36.903694 ignition[1094]: INFO : Stage: files Sep 4 18:19:36.903694 ignition[1094]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 4 18:19:36.903694 ignition[1094]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 4 18:19:36.924279 ignition[1094]: DEBUG : files: compiled without relabeling support, skipping Sep 4 18:19:36.924279 ignition[1094]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 4 18:19:36.924279 ignition[1094]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 4 18:19:37.039963 ignition[1094]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 4 18:19:37.047927 ignition[1094]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 4 18:19:37.047927 ignition[1094]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 4 18:19:37.040355 unknown[1094]: wrote ssh authorized keys file for user: core Sep 4 18:19:37.126066 ignition[1094]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/var/resource/data" Sep 4 18:19:37.136541 ignition[1094]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/var/resource/data" Sep 4 18:19:37.136541 ignition[1094]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/var/resource/http" Sep 4 18:19:37.136541 ignition[1094]: INFO : files: createFilesystemsFiles: createFiles: op(4): GET http://10.200.20.24/http: attempt #1 Sep 4 18:19:37.166878 ignition[1094]: INFO : files: createFilesystemsFiles: createFiles: op(4): GET result: OK Sep 4 18:19:37.166878 ignition[1094]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/var/resource/http" Sep 4 18:19:37.166878 ignition[1094]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing file "/sysroot/var/resource/tftp" Sep 4 18:19:37.166878 ignition[1094]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing file "/sysroot/var/resource/tftp" Sep 4 18:19:37.213925 ignition[1094]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 4 18:19:37.213925 ignition[1094]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 4 18:19:37.213925 ignition[1094]: INFO : files: createResultFile: createFiles: op(7): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 4 18:19:37.213925 ignition[1094]: INFO : files: createResultFile: createFiles: op(7): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 4 18:19:37.213925 ignition[1094]: INFO : files: files passed Sep 4 18:19:37.213925 ignition[1094]: INFO : Ignition finished successfully Sep 4 18:19:37.186707 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 4 18:19:37.234900 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 4 18:19:37.250817 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 4 18:19:37.268326 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 4 18:19:37.325745 initrd-setup-root-after-ignition[1121]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 4 18:19:37.325745 initrd-setup-root-after-ignition[1121]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 4 18:19:37.268445 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 4 18:19:37.358080 initrd-setup-root-after-ignition[1125]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 4 18:19:37.299221 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 4 18:19:37.306862 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 4 18:19:37.334867 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 4 18:19:37.383110 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 4 18:19:37.383224 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 4 18:19:37.401772 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 4 18:19:37.414246 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 4 18:19:37.425939 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 4 18:19:37.428847 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 4 18:19:37.466916 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 4 18:19:37.485976 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 4 18:19:37.504029 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 4 18:19:37.505695 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 4 18:19:37.517869 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 4 18:19:37.530103 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 4 18:19:37.543369 systemd[1]: Stopped target timers.target - Timer Units. Sep 4 18:19:37.555211 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 4 18:19:37.555271 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 4 18:19:37.572233 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 4 18:19:37.584650 systemd[1]: Stopped target basic.target - Basic System. Sep 4 18:19:37.595736 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 4 18:19:37.607017 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 4 18:19:37.619815 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 4 18:19:37.632645 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 4 18:19:37.644780 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 4 18:19:37.657400 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 4 18:19:37.670089 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 4 18:19:37.681691 systemd[1]: Stopped target swap.target - Swaps. Sep 4 18:19:37.691826 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 4 18:19:37.691896 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 4 18:19:37.707900 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 4 18:19:37.720065 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 4 18:19:37.732887 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 4 18:19:37.739156 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 4 18:19:37.746444 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 4 18:19:37.746518 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 4 18:19:37.765721 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 4 18:19:37.765774 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 4 18:19:37.778086 systemd[1]: ignition-files.service: Deactivated successfully. Sep 4 18:19:37.778139 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 4 18:19:37.790122 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Sep 4 18:19:37.790162 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Sep 4 18:19:37.821901 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 4 18:19:37.853693 ignition[1147]: INFO : Ignition 2.19.0 Sep 4 18:19:37.853693 ignition[1147]: INFO : Stage: umount Sep 4 18:19:37.853693 ignition[1147]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 4 18:19:37.853693 ignition[1147]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Sep 4 18:19:37.853693 ignition[1147]: INFO : umount: umount passed Sep 4 18:19:37.853693 ignition[1147]: INFO : Ignition finished successfully Sep 4 18:19:37.847931 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 4 18:19:37.857861 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 4 18:19:37.857922 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 4 18:19:37.874531 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 4 18:19:37.874577 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 4 18:19:37.889034 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 4 18:19:37.889492 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 4 18:19:37.889583 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 4 18:19:37.898322 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 4 18:19:37.898395 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 4 18:19:37.905714 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 4 18:19:37.905754 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 4 18:19:37.916508 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 4 18:19:37.916553 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 4 18:19:37.927610 systemd[1]: ignition-fetch.service: Deactivated successfully. Sep 4 18:19:37.927650 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Sep 4 18:19:37.939021 systemd[1]: Stopped target network.target - Network. Sep 4 18:19:37.950398 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 4 18:19:37.950445 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 4 18:19:37.962531 systemd[1]: Stopped target paths.target - Path Units. Sep 4 18:19:37.973563 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 4 18:19:37.978270 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 4 18:19:37.985592 systemd[1]: Stopped target slices.target - Slice Units. Sep 4 18:19:37.996171 systemd[1]: Stopped target sockets.target - Socket Units. Sep 4 18:19:38.006771 systemd[1]: iscsid.socket: Deactivated successfully. Sep 4 18:19:38.006807 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 4 18:19:38.017482 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 4 18:19:38.017522 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 4 18:19:38.028323 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 4 18:19:38.028373 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 4 18:19:38.039935 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 4 18:19:38.269244 kernel: hv_netvsc 0022487b-6c94-0022-487b-6c940022487b eth0: Data path switched from VF: enP25850s1 Sep 4 18:19:38.039976 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 4 18:19:38.051163 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 4 18:19:38.051202 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 4 18:19:38.064256 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 4 18:19:38.075324 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 4 18:19:38.086728 systemd-networkd[904]: eth0: DHCPv6 lease lost Sep 4 18:19:38.088182 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 4 18:19:38.088271 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 4 18:19:38.105075 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 4 18:19:38.105140 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 4 18:19:38.124989 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 4 18:19:38.135794 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 4 18:19:38.135851 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 4 18:19:38.143203 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 4 18:19:38.156462 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 4 18:19:38.156560 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 4 18:19:38.182732 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 4 18:19:38.182821 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 4 18:19:38.193593 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 4 18:19:38.193640 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 4 18:19:38.205541 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 4 18:19:38.205596 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 4 18:19:38.218232 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 4 18:19:38.218382 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 4 18:19:38.231036 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 4 18:19:38.231081 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 4 18:19:38.242226 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 4 18:19:38.242263 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 4 18:19:38.263591 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 4 18:19:38.263649 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 4 18:19:38.281114 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 4 18:19:38.281168 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 4 18:19:38.294338 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 4 18:19:38.294398 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 4 18:19:38.563928 systemd-journald[216]: Received SIGTERM from PID 1 (systemd). Sep 4 18:19:38.337931 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 4 18:19:38.350735 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 4 18:19:38.350795 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 4 18:19:38.365105 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Sep 4 18:19:38.365154 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 4 18:19:38.377953 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 4 18:19:38.377996 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 4 18:19:38.390696 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 4 18:19:38.390738 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 18:19:38.404704 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 4 18:19:38.404790 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 4 18:19:38.417488 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 4 18:19:38.417559 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 4 18:19:38.435978 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 4 18:19:38.470879 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 4 18:19:38.497426 systemd[1]: Switching root. Sep 4 18:19:38.590874 systemd-journald[216]: Journal stopped Sep 4 18:19:48.387239 kernel: SELinux: policy capability network_peer_controls=1 Sep 4 18:19:48.387260 kernel: SELinux: policy capability open_perms=1 Sep 4 18:19:48.387270 kernel: SELinux: policy capability extended_socket_class=1 Sep 4 18:19:48.387279 kernel: SELinux: policy capability always_check_network=0 Sep 4 18:19:48.387287 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 4 18:19:48.387295 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 4 18:19:48.387303 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 4 18:19:48.387311 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 4 18:19:48.387319 kernel: audit: type=1403 audit(1725473980.713:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 4 18:19:48.387329 systemd[1]: Successfully loaded SELinux policy in 223.218ms. Sep 4 18:19:48.387340 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 10.122ms. Sep 4 18:19:48.387350 systemd[1]: systemd 255 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Sep 4 18:19:48.387360 systemd[1]: Detected virtualization microsoft. Sep 4 18:19:48.387368 systemd[1]: Detected architecture arm64. Sep 4 18:19:48.387378 systemd[1]: Detected first boot. Sep 4 18:19:48.387388 systemd[1]: Hostname set to . Sep 4 18:19:48.387397 systemd[1]: Initializing machine ID from random generator. Sep 4 18:19:48.387408 zram_generator::config[1186]: No configuration found. Sep 4 18:19:48.387418 systemd[1]: Populated /etc with preset unit settings. Sep 4 18:19:48.387427 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 4 18:19:48.387436 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 4 18:19:48.387446 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 4 18:19:48.387456 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 4 18:19:48.387465 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 4 18:19:48.387475 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 4 18:19:48.387484 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 4 18:19:48.387493 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 4 18:19:48.387503 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 4 18:19:48.387514 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 4 18:19:48.387523 systemd[1]: Created slice user.slice - User and Session Slice. Sep 4 18:19:48.387532 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 4 18:19:48.387542 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 4 18:19:48.387551 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 4 18:19:48.387561 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 4 18:19:48.387570 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 4 18:19:48.387579 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 4 18:19:48.387588 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Sep 4 18:19:48.387599 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 4 18:19:48.387609 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 4 18:19:48.387624 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 4 18:19:48.387636 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 4 18:19:48.387646 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 4 18:19:48.387673 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 4 18:19:48.387685 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 4 18:19:48.387697 systemd[1]: Reached target slices.target - Slice Units. Sep 4 18:19:48.387706 systemd[1]: Reached target swap.target - Swaps. Sep 4 18:19:48.387715 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 4 18:19:48.387725 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 4 18:19:48.387734 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 4 18:19:48.387743 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 4 18:19:48.387755 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 4 18:19:48.387764 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 4 18:19:48.387774 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 4 18:19:48.387784 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 4 18:19:48.387793 systemd[1]: Mounting media.mount - External Media Directory... Sep 4 18:19:48.387802 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 4 18:19:48.387812 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 4 18:19:48.387823 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 4 18:19:48.387833 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). Sep 4 18:19:48.387845 systemd[1]: Reached target machines.target - Containers. Sep 4 18:19:48.387854 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 4 18:19:48.387864 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 18:19:48.387873 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 4 18:19:48.387883 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 4 18:19:48.387892 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 4 18:19:48.387902 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 4 18:19:48.387913 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 4 18:19:48.387922 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 4 18:19:48.387932 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 4 18:19:48.387941 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 4 18:19:48.387951 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 4 18:19:48.387961 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 4 18:19:48.387970 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 4 18:19:48.387980 systemd[1]: Stopped systemd-fsck-usr.service. Sep 4 18:19:48.387991 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 4 18:19:48.388000 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 4 18:19:48.388010 kernel: fuse: init (API version 7.39) Sep 4 18:19:48.388019 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 4 18:19:48.388028 kernel: loop: module loaded Sep 4 18:19:48.388037 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 4 18:19:48.388047 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 4 18:19:48.388071 systemd-journald[1274]: Collecting audit messages is disabled. Sep 4 18:19:48.388093 systemd-journald[1274]: Journal started Sep 4 18:19:48.388112 systemd-journald[1274]: Runtime Journal (/run/log/journal/ebabe4d1156b42d481ff5be012ead1a9) is 8.0M, max 78.6M, 70.6M free. Sep 4 18:19:46.773092 systemd[1]: Queued start job for default target multi-user.target. Sep 4 18:19:47.382313 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Sep 4 18:19:47.382680 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 4 18:19:47.383006 systemd[1]: systemd-journald.service: Consumed 3.092s CPU time. Sep 4 18:19:48.410632 systemd[1]: verity-setup.service: Deactivated successfully. Sep 4 18:19:48.410696 kernel: ACPI: bus type drm_connector registered Sep 4 18:19:48.410709 systemd[1]: Stopped verity-setup.service. Sep 4 18:19:48.433134 systemd[1]: Started systemd-journald.service - Journal Service. Sep 4 18:19:48.433925 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 4 18:19:48.440399 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 4 18:19:48.446946 systemd[1]: Mounted media.mount - External Media Directory. Sep 4 18:19:48.452739 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 4 18:19:48.459303 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 4 18:19:48.465762 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 4 18:19:48.472702 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 4 18:19:48.479983 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 4 18:19:48.487730 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 4 18:19:48.487862 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 4 18:19:48.496032 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 4 18:19:48.496171 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 4 18:19:48.503177 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 4 18:19:48.503312 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 4 18:19:48.509796 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 4 18:19:48.509929 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 4 18:19:48.517320 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 4 18:19:48.517455 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 4 18:19:48.524063 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 4 18:19:48.524195 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 4 18:19:48.530873 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 4 18:19:48.537562 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 4 18:19:48.547008 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 4 18:19:48.564449 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 4 18:19:48.575821 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 4 18:19:48.584763 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 4 18:19:48.591201 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 4 18:19:48.591235 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 4 18:19:48.598105 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Sep 4 18:19:48.609788 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 4 18:19:48.617196 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 4 18:19:48.622937 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 18:19:48.623884 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 4 18:19:48.633837 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 4 18:19:48.640371 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 4 18:19:48.641369 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 4 18:19:48.647802 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 4 18:19:48.648743 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 4 18:19:48.656853 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 4 18:19:48.669006 systemd-journald[1274]: Time spent on flushing to /var/log/journal/ebabe4d1156b42d481ff5be012ead1a9 is 13.073ms for 885 entries. Sep 4 18:19:48.669006 systemd-journald[1274]: System Journal (/var/log/journal/ebabe4d1156b42d481ff5be012ead1a9) is 8.0M, max 2.6G, 2.6G free. Sep 4 18:19:48.729705 systemd-journald[1274]: Received client request to flush runtime journal. Sep 4 18:19:48.678866 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 4 18:19:48.688622 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 4 18:19:48.697988 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 4 18:19:48.705139 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 4 18:19:48.712523 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 4 18:19:48.721693 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 4 18:19:48.731873 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 4 18:19:48.742163 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 4 18:19:48.755987 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Sep 4 18:19:48.763936 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Sep 4 18:19:48.777868 udevadm[1331]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Sep 4 18:19:48.806683 kernel: loop0: detected capacity change from 0 to 114288 Sep 4 18:19:48.840783 systemd-tmpfiles[1320]: ACLs are not supported, ignoring. Sep 4 18:19:48.840801 systemd-tmpfiles[1320]: ACLs are not supported, ignoring. Sep 4 18:19:48.844609 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 4 18:19:48.851488 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 4 18:19:48.863851 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 4 18:19:48.914083 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 4 18:19:48.916273 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Sep 4 18:19:48.938546 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 4 18:19:48.950920 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 4 18:19:48.964994 systemd-tmpfiles[1339]: ACLs are not supported, ignoring. Sep 4 18:19:48.965264 systemd-tmpfiles[1339]: ACLs are not supported, ignoring. Sep 4 18:19:48.968751 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 4 18:19:49.500688 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 4 18:19:49.559687 kernel: loop1: detected capacity change from 0 to 65520 Sep 4 18:19:49.668685 kernel: loop2: detected capacity change from 0 to 61752 Sep 4 18:19:49.818690 kernel: loop3: detected capacity change from 0 to 114288 Sep 4 18:19:49.827689 kernel: loop4: detected capacity change from 0 to 65520 Sep 4 18:19:49.835679 kernel: loop5: detected capacity change from 0 to 61752 Sep 4 18:19:49.837632 (sd-merge)[1346]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Sep 4 18:19:49.838024 (sd-merge)[1346]: Merged extensions into '/usr'. Sep 4 18:19:49.846726 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 4 18:19:49.860910 systemd[1]: Starting ensure-sysext.service... Sep 4 18:19:49.866091 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 4 18:19:49.883973 systemd[1]: Reloading requested from client PID 1348 ('systemctl') (unit ensure-sysext.service)... Sep 4 18:19:49.883986 systemd[1]: Reloading... Sep 4 18:19:49.890166 systemd-tmpfiles[1349]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 4 18:19:49.890424 systemd-tmpfiles[1349]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 4 18:19:49.891095 systemd-tmpfiles[1349]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 4 18:19:49.891316 systemd-tmpfiles[1349]: ACLs are not supported, ignoring. Sep 4 18:19:49.891360 systemd-tmpfiles[1349]: ACLs are not supported, ignoring. Sep 4 18:19:49.917250 systemd-tmpfiles[1349]: Detected autofs mount point /boot during canonicalization of boot. Sep 4 18:19:49.917263 systemd-tmpfiles[1349]: Skipping /boot Sep 4 18:19:49.924267 systemd-tmpfiles[1349]: Detected autofs mount point /boot during canonicalization of boot. Sep 4 18:19:49.924284 systemd-tmpfiles[1349]: Skipping /boot Sep 4 18:19:49.963681 zram_generator::config[1375]: No configuration found. Sep 4 18:19:50.076544 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. Sep 4 18:19:50.132443 systemd[1]: Reloading finished in 248 ms. Sep 4 18:19:50.165135 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 4 18:19:50.185268 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Sep 4 18:19:50.193865 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 4 18:19:50.204845 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 4 18:19:50.226810 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 4 18:19:50.233796 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 4 18:19:50.247244 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 18:19:50.248554 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 4 18:19:50.257309 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 4 18:19:50.265974 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 4 18:19:50.274024 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 18:19:50.275398 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 4 18:19:50.275548 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 4 18:19:50.282937 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 4 18:19:50.283069 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 4 18:19:50.290429 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 4 18:19:50.290559 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 4 18:19:50.299593 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 4 18:19:50.312581 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 18:19:50.316884 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 4 18:19:50.324748 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 4 18:19:50.336920 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 4 18:19:50.345233 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 18:19:50.346847 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 4 18:19:50.353348 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 4 18:19:50.354696 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 4 18:19:50.361843 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 4 18:19:50.361970 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 4 18:19:50.369486 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 4 18:19:50.369602 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 4 18:19:50.380972 systemd[1]: Expecting device dev-ptp_hyperv.device - /dev/ptp_hyperv... Sep 4 18:19:50.387320 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 4 18:19:50.394000 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 4 18:19:50.406127 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 4 18:19:50.419520 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 4 18:19:50.429917 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 4 18:19:50.435916 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 4 18:19:50.436096 systemd[1]: Reached target time-set.target - System Time Set. Sep 4 18:19:50.443022 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 4 18:19:50.444690 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 4 18:19:50.454606 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 4 18:19:50.467105 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 4 18:19:50.467386 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 4 18:19:50.475373 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 4 18:19:50.475692 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 4 18:19:50.485209 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 4 18:19:50.486708 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 4 18:19:50.494936 systemd[1]: Finished ensure-sysext.service. Sep 4 18:19:50.510160 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 4 18:19:50.510528 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 4 18:19:50.570743 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 4 18:19:50.618336 systemd-resolved[1442]: Positive Trust Anchors: Sep 4 18:19:50.618351 systemd-resolved[1442]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 4 18:19:50.618386 systemd-resolved[1442]: 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 Sep 4 18:19:50.726550 systemd-resolved[1442]: Using system hostname 'ci-4054.1.0-a-bc791940be'. Sep 4 18:19:50.728126 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 4 18:19:50.734736 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 4 18:19:50.769322 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 4 18:19:50.780849 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 4 18:19:50.809133 systemd-udevd[1482]: Using default interface naming scheme 'v255'. Sep 4 18:19:51.141805 augenrules[1483]: No rules Sep 4 18:19:51.142539 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Sep 4 18:19:51.201759 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 4 18:19:51.223857 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 4 18:19:51.295733 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Sep 4 18:19:51.337057 systemd[1]: Condition check resulted in dev-ptp_hyperv.device - /dev/ptp_hyperv being skipped. Sep 4 18:19:51.356713 kernel: BTRFS info: devid 1 device path /dev/mapper/usr changed to /dev/dm-0 scanned by (udev-worker) (1493) Sep 4 18:19:51.365686 kernel: BTRFS info: devid 1 device path /dev/dm-0 changed to /dev/mapper/usr scanned by (udev-worker) (1493) Sep 4 18:19:51.369913 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 18:19:51.387370 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 4 18:19:51.387595 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 18:19:51.399957 systemd-networkd[1503]: lo: Link UP Sep 4 18:19:51.399965 systemd-networkd[1503]: lo: Gained carrier Sep 4 18:19:51.402006 systemd-networkd[1503]: Enumeration completed Sep 4 18:19:51.403560 systemd-networkd[1503]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 18:19:51.403566 systemd-networkd[1503]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 4 18:19:51.404854 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 18:19:51.412628 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 4 18:19:51.419981 systemd[1]: Reached target network.target - Network. Sep 4 18:19:51.435097 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 4 18:19:51.459677 kernel: mlx5_core 64fa:00:02.0 enP25850s1: Link up Sep 4 18:19:51.486756 kernel: hv_netvsc 0022487b-6c94-0022-487b-6c940022487b eth0: Data path switched to VF: enP25850s1 Sep 4 18:19:51.486567 systemd-networkd[1503]: enP25850s1: Link UP Sep 4 18:19:51.486683 systemd-networkd[1503]: eth0: Link UP Sep 4 18:19:51.486686 systemd-networkd[1503]: eth0: Gained carrier Sep 4 18:19:51.486700 systemd-networkd[1503]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 18:19:51.491992 systemd-networkd[1503]: enP25850s1: Gained carrier Sep 4 18:19:51.505691 systemd-networkd[1503]: eth0: DHCPv4 address 10.200.20.47/24, gateway 10.200.20.1 acquired from 168.63.129.16 Sep 4 18:19:51.643674 kernel: mousedev: PS/2 mouse device common for all mice Sep 4 18:19:51.681818 kernel: hv_vmbus: registering driver hv_balloon Sep 4 18:19:51.688199 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 4 18:19:51.699436 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Sep 4 18:19:51.699516 kernel: hv_balloon: Memory hot add disabled on ARM64 Sep 4 18:19:51.702774 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). Sep 4 18:19:51.727717 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 42 scanned by (udev-worker) (1501) Sep 4 18:19:51.727809 kernel: hv_vmbus: registering driver hyperv_fb Sep 4 18:19:51.731882 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Sep 4 18:19:51.744056 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Sep 4 18:19:51.750810 kernel: Console: switching to colour dummy device 80x25 Sep 4 18:19:51.754675 kernel: Console: switching to colour frame buffer device 128x48 Sep 4 18:19:51.788517 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 4 18:19:51.788700 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 18:19:51.804437 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Sep 4 18:19:51.812054 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Sep 4 18:19:51.833841 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Sep 4 18:19:51.841363 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 4 18:19:51.848897 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 4 18:19:52.137767 lvm[1575]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 4 18:19:52.143369 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 4 18:19:52.171170 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Sep 4 18:19:52.181007 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 4 18:19:52.192860 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Sep 4 18:19:52.204477 lvm[1584]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Sep 4 18:19:52.210941 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 4 18:19:52.237740 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Sep 4 18:19:52.555785 systemd-networkd[1503]: eth0: Gained IPv6LL Sep 4 18:19:52.557636 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 4 18:19:52.565704 systemd[1]: Reached target network-online.target - Network is Online. Sep 4 18:19:53.387816 systemd-networkd[1503]: enP25850s1: Gained IPv6LL Sep 4 18:20:06.232678 ldconfig[1313]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 4 18:20:06.394900 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 4 18:20:06.407778 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 4 18:20:06.420631 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 4 18:20:06.427376 systemd[1]: Reached target sysinit.target - System Initialization. Sep 4 18:20:06.433555 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 4 18:20:06.440998 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Sep 4 18:20:06.448304 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 4 18:20:06.454517 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 4 18:20:06.461765 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 4 18:20:06.468928 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 4 18:20:06.468957 systemd[1]: Reached target paths.target - Path Units. Sep 4 18:20:06.474303 systemd[1]: Reached target timers.target - Timer Units. Sep 4 18:20:06.480318 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 4 18:20:06.487860 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 4 18:20:06.500100 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 4 18:20:06.506500 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 4 18:20:06.512716 systemd[1]: Reached target sockets.target - Socket Units. Sep 4 18:20:06.518131 systemd[1]: Reached target basic.target - Basic System. Sep 4 18:20:06.523667 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 4 18:20:06.523699 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 4 18:20:06.530780 systemd[1]: Starting chronyd.service - NTP client/server... Sep 4 18:20:06.539782 systemd[1]: Starting containerd.service - containerd container runtime... Sep 4 18:20:06.550816 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Sep 4 18:20:06.558047 (chronyd)[1594]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Sep 4 18:20:06.561897 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 4 18:20:06.568316 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 4 18:20:06.575376 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 4 18:20:06.581138 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). Sep 4 18:20:06.582880 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 4 18:20:06.592122 jq[1600]: false Sep 4 18:20:06.593127 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 4 18:20:06.599833 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 4 18:20:06.609829 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 4 18:20:06.617812 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 4 18:20:06.623832 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Sep 4 18:20:06.624187 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 4 18:20:06.624831 systemd[1]: Starting update-engine.service - Update Engine... Sep 4 18:20:06.635623 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 4 18:20:06.645978 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 4 18:20:06.648895 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 4 18:20:06.649216 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 4 18:20:06.649681 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 4 18:20:06.650553 jq[1614]: true Sep 4 18:20:06.674790 jq[1616]: true Sep 4 18:20:06.688745 chronyd[1625]: chronyd version 4.5 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Sep 4 18:20:06.739732 chronyd[1625]: Timezone right/UTC failed leap second check, ignoring Sep 4 18:20:06.739949 chronyd[1625]: Loaded seccomp filter (level 2) Sep 4 18:20:06.747845 extend-filesystems[1601]: Found loop3 Sep 4 18:20:06.768833 extend-filesystems[1601]: Found loop4 Sep 4 18:20:06.768833 extend-filesystems[1601]: Found loop5 Sep 4 18:20:06.768833 extend-filesystems[1601]: Found sda Sep 4 18:20:06.768833 extend-filesystems[1601]: Found sda1 Sep 4 18:20:06.768833 extend-filesystems[1601]: Found sda2 Sep 4 18:20:06.768833 extend-filesystems[1601]: Found sda3 Sep 4 18:20:06.768833 extend-filesystems[1601]: Found usr Sep 4 18:20:06.768833 extend-filesystems[1601]: Found sda4 Sep 4 18:20:06.768833 extend-filesystems[1601]: Found sda6 Sep 4 18:20:06.768833 extend-filesystems[1601]: Found sda7 Sep 4 18:20:06.768833 extend-filesystems[1601]: Found sda9 Sep 4 18:20:06.768833 extend-filesystems[1601]: Checking size of /dev/sda9 Sep 4 18:20:06.753985 (ntainerd)[1640]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 4 18:20:06.864181 update_engine[1612]: I0904 18:20:06.803509 1612 main.cc:92] Flatcar Update Engine starting Sep 4 18:20:06.754017 systemd[1]: Started chronyd.service - NTP client/server. Sep 4 18:20:06.762322 systemd[1]: motdgen.service: Deactivated successfully. Sep 4 18:20:06.762490 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 4 18:20:06.766472 systemd-logind[1609]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Sep 4 18:20:06.766908 systemd-logind[1609]: New seat seat0. Sep 4 18:20:06.778149 systemd[1]: Started systemd-logind.service - User Login Management. Sep 4 18:20:06.898758 extend-filesystems[1601]: Old size kept for /dev/sda9 Sep 4 18:20:06.898758 extend-filesystems[1601]: Found sr0 Sep 4 18:20:06.903784 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 4 18:20:06.903954 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 4 18:20:06.921987 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 4 18:20:06.934493 dbus-daemon[1597]: [system] SELinux support is enabled Sep 4 18:20:06.934884 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 4 18:20:06.946802 update_engine[1612]: I0904 18:20:06.946640 1612 update_check_scheduler.cc:74] Next update check in 8m54s Sep 4 18:20:06.952584 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). Sep 4 18:20:06.953219 dbus-daemon[1597]: [system] Successfully activated service 'org.freedesktop.systemd1' Sep 4 18:20:06.953353 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 4 18:20:06.969874 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). Sep 4 18:20:06.969897 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 4 18:20:06.978092 systemd[1]: Started update-engine.service - Update Engine. Sep 4 18:20:06.999680 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 42 scanned by (udev-worker) (1657) Sep 4 18:20:07.004072 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 4 18:20:07.233686 bash[1638]: Updated "/home/core/.ssh/authorized_keys" Sep 4 18:20:07.237046 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 4 18:20:07.249209 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Sep 4 18:20:07.283756 coreos-metadata[1596]: Sep 04 18:20:07.283 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Sep 4 18:20:07.286391 coreos-metadata[1596]: Sep 04 18:20:07.286 INFO Fetch successful Sep 4 18:20:07.287718 coreos-metadata[1596]: Sep 04 18:20:07.287 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Sep 4 18:20:07.291825 coreos-metadata[1596]: Sep 04 18:20:07.291 INFO Fetch successful Sep 4 18:20:07.292238 coreos-metadata[1596]: Sep 04 18:20:07.292 INFO Fetching http://168.63.129.16/machine/f6132886-f0ee-4974-b9df-23c2f53a595b/8bcf201d%2D9bb8%2D45b2%2D9ef9%2De47b50bd128e.%5Fci%2D4054.1.0%2Da%2Dbc791940be?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Sep 4 18:20:07.294392 coreos-metadata[1596]: Sep 04 18:20:07.294 INFO Fetch successful Sep 4 18:20:07.294392 coreos-metadata[1596]: Sep 04 18:20:07.294 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Sep 4 18:20:07.305207 coreos-metadata[1596]: Sep 04 18:20:07.305 INFO Fetch successful Sep 4 18:20:07.331954 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Sep 4 18:20:07.339380 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 4 18:20:07.841890 locksmithd[1676]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 4 18:20:08.093727 sshd_keygen[1613]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 4 18:20:08.113013 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 4 18:20:08.124856 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 4 18:20:08.141811 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Sep 4 18:20:08.148967 systemd[1]: issuegen.service: Deactivated successfully. Sep 4 18:20:08.150972 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 4 18:20:08.165303 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 4 18:20:08.174821 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Sep 4 18:20:08.186749 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 4 18:20:08.189234 containerd[1640]: time="2024-09-04T18:20:08.189167520Z" level=info msg="starting containerd" revision=8ccfc03e4e2b73c22899202ae09d0caf906d3863 version=v1.7.20 Sep 4 18:20:08.205998 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 4 18:20:08.220685 containerd[1640]: time="2024-09-04T18:20:08.220627080Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Sep 4 18:20:08.222955 containerd[1640]: time="2024-09-04T18:20:08.221817080Z" 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.48-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Sep 4 18:20:08.222955 containerd[1640]: time="2024-09-04T18:20:08.221848600Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Sep 4 18:20:08.222955 containerd[1640]: time="2024-09-04T18:20:08.221863800Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Sep 4 18:20:08.222955 containerd[1640]: time="2024-09-04T18:20:08.222031080Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Sep 4 18:20:08.222955 containerd[1640]: time="2024-09-04T18:20:08.222049720Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Sep 4 18:20:08.222955 containerd[1640]: time="2024-09-04T18:20:08.222106600Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Sep 4 18:20:08.222955 containerd[1640]: time="2024-09-04T18:20:08.222124280Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Sep 4 18:20:08.222955 containerd[1640]: time="2024-09-04T18:20:08.222274520Z" 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 Sep 4 18:20:08.222955 containerd[1640]: time="2024-09-04T18:20:08.222289960Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Sep 4 18:20:08.222955 containerd[1640]: time="2024-09-04T18:20:08.222302800Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Sep 4 18:20:08.222955 containerd[1640]: time="2024-09-04T18:20:08.222311920Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Sep 4 18:20:08.222083 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Sep 4 18:20:08.223271 containerd[1640]: time="2024-09-04T18:20:08.222375080Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Sep 4 18:20:08.223271 containerd[1640]: time="2024-09-04T18:20:08.222543320Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Sep 4 18:20:08.223271 containerd[1640]: time="2024-09-04T18:20:08.222627280Z" 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 Sep 4 18:20:08.223271 containerd[1640]: time="2024-09-04T18:20:08.222639880Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Sep 4 18:20:08.223271 containerd[1640]: time="2024-09-04T18:20:08.222740320Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Sep 4 18:20:08.223271 containerd[1640]: time="2024-09-04T18:20:08.222788320Z" level=info msg="metadata content store policy set" policy=shared Sep 4 18:20:08.229635 systemd[1]: Reached target getty.target - Login Prompts. Sep 4 18:20:08.242744 containerd[1640]: time="2024-09-04T18:20:08.242706240Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Sep 4 18:20:08.242822 containerd[1640]: time="2024-09-04T18:20:08.242766160Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Sep 4 18:20:08.242822 containerd[1640]: time="2024-09-04T18:20:08.242782800Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Sep 4 18:20:08.242822 containerd[1640]: time="2024-09-04T18:20:08.242798360Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Sep 4 18:20:08.242822 containerd[1640]: time="2024-09-04T18:20:08.242811960Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Sep 4 18:20:08.243025 containerd[1640]: time="2024-09-04T18:20:08.242999560Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Sep 4 18:20:08.243690 containerd[1640]: time="2024-09-04T18:20:08.243533440Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Sep 4 18:20:08.243755 containerd[1640]: time="2024-09-04T18:20:08.243708440Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Sep 4 18:20:08.243755 containerd[1640]: time="2024-09-04T18:20:08.243733800Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Sep 4 18:20:08.243755 containerd[1640]: time="2024-09-04T18:20:08.243751520Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Sep 4 18:20:08.243817 containerd[1640]: time="2024-09-04T18:20:08.243771400Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Sep 4 18:20:08.243817 containerd[1640]: time="2024-09-04T18:20:08.243788360Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Sep 4 18:20:08.243817 containerd[1640]: time="2024-09-04T18:20:08.243805000Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Sep 4 18:20:08.243869 containerd[1640]: time="2024-09-04T18:20:08.243822400Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Sep 4 18:20:08.243869 containerd[1640]: time="2024-09-04T18:20:08.243837560Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Sep 4 18:20:08.243869 containerd[1640]: time="2024-09-04T18:20:08.243854760Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Sep 4 18:20:08.243918 containerd[1640]: time="2024-09-04T18:20:08.243870920Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Sep 4 18:20:08.243918 containerd[1640]: time="2024-09-04T18:20:08.243886320Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Sep 4 18:20:08.243918 containerd[1640]: time="2024-09-04T18:20:08.243909960Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Sep 4 18:20:08.243967 containerd[1640]: time="2024-09-04T18:20:08.243927400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Sep 4 18:20:08.243967 containerd[1640]: time="2024-09-04T18:20:08.243943120Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Sep 4 18:20:08.243967 containerd[1640]: time="2024-09-04T18:20:08.243963960Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Sep 4 18:20:08.244060 containerd[1640]: time="2024-09-04T18:20:08.243978120Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Sep 4 18:20:08.244060 containerd[1640]: time="2024-09-04T18:20:08.243995720Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Sep 4 18:20:08.244060 containerd[1640]: time="2024-09-04T18:20:08.244013520Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Sep 4 18:20:08.244060 containerd[1640]: time="2024-09-04T18:20:08.244029360Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Sep 4 18:20:08.244060 containerd[1640]: time="2024-09-04T18:20:08.244047720Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Sep 4 18:20:08.244141 containerd[1640]: time="2024-09-04T18:20:08.244066240Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Sep 4 18:20:08.244141 containerd[1640]: time="2024-09-04T18:20:08.244081240Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Sep 4 18:20:08.244141 containerd[1640]: time="2024-09-04T18:20:08.244095160Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Sep 4 18:20:08.244141 containerd[1640]: time="2024-09-04T18:20:08.244110800Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Sep 4 18:20:08.244141 containerd[1640]: time="2024-09-04T18:20:08.244130360Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Sep 4 18:20:08.244222 containerd[1640]: time="2024-09-04T18:20:08.244154440Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Sep 4 18:20:08.244222 containerd[1640]: time="2024-09-04T18:20:08.244170560Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Sep 4 18:20:08.244222 containerd[1640]: time="2024-09-04T18:20:08.244184400Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Sep 4 18:20:08.244277 containerd[1640]: time="2024-09-04T18:20:08.244234960Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Sep 4 18:20:08.244277 containerd[1640]: time="2024-09-04T18:20:08.244257120Z" 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 Sep 4 18:20:08.244277 containerd[1640]: time="2024-09-04T18:20:08.244269320Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Sep 4 18:20:08.244327 containerd[1640]: time="2024-09-04T18:20:08.244285640Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Sep 4 18:20:08.244327 containerd[1640]: time="2024-09-04T18:20:08.244298520Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Sep 4 18:20:08.244327 containerd[1640]: time="2024-09-04T18:20:08.244318040Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Sep 4 18:20:08.244383 containerd[1640]: time="2024-09-04T18:20:08.244331200Z" level=info msg="NRI interface is disabled by configuration." Sep 4 18:20:08.244383 containerd[1640]: time="2024-09-04T18:20:08.244342000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Sep 4 18:20:08.245067 containerd[1640]: time="2024-09-04T18:20:08.244645440Z" 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}" Sep 4 18:20:08.245067 containerd[1640]: time="2024-09-04T18:20:08.244753520Z" level=info msg="Connect containerd service" Sep 4 18:20:08.245067 containerd[1640]: time="2024-09-04T18:20:08.244796280Z" level=info msg="using legacy CRI server" Sep 4 18:20:08.245067 containerd[1640]: time="2024-09-04T18:20:08.244804440Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 4 18:20:08.245067 containerd[1640]: time="2024-09-04T18:20:08.244903800Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Sep 4 18:20:08.246050 containerd[1640]: time="2024-09-04T18:20:08.245995600Z" 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" Sep 4 18:20:08.252429 containerd[1640]: time="2024-09-04T18:20:08.246161880Z" level=info msg="Start subscribing containerd event" Sep 4 18:20:08.252429 containerd[1640]: time="2024-09-04T18:20:08.246219480Z" level=info msg="Start recovering state" Sep 4 18:20:08.252429 containerd[1640]: time="2024-09-04T18:20:08.246244280Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 4 18:20:08.252429 containerd[1640]: time="2024-09-04T18:20:08.246277480Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 4 18:20:08.252429 containerd[1640]: time="2024-09-04T18:20:08.246279200Z" level=info msg="Start event monitor" Sep 4 18:20:08.252429 containerd[1640]: time="2024-09-04T18:20:08.246294840Z" level=info msg="Start snapshots syncer" Sep 4 18:20:08.252429 containerd[1640]: time="2024-09-04T18:20:08.246302920Z" level=info msg="Start cni network conf syncer for default" Sep 4 18:20:08.252429 containerd[1640]: time="2024-09-04T18:20:08.246309640Z" level=info msg="Start streaming server" Sep 4 18:20:08.246430 systemd[1]: Started containerd.service - containerd container runtime. Sep 4 18:20:08.254276 containerd[1640]: time="2024-09-04T18:20:08.253981760Z" level=info msg="containerd successfully booted in 0.065499s" Sep 4 18:20:08.254845 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 4 18:20:08.261044 systemd[1]: Startup finished in 668ms (kernel) + 15.713s (initrd) + 27.769s (userspace) = 44.152s. Sep 4 18:20:09.328871 login[1736]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Sep 4 18:20:09.329275 login[1735]: pam_unix(login:session): session opened for user core(uid=500) by core(uid=0) Sep 4 18:20:09.385885 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 4 18:20:09.392964 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 4 18:20:09.395181 systemd-logind[1609]: New session 1 of user core. Sep 4 18:20:09.433168 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 4 18:20:09.444025 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 4 18:20:09.516401 (systemd)[1745]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 4 18:20:09.733565 systemd[1745]: Queued start job for default target default.target. Sep 4 18:20:09.745448 systemd[1745]: Created slice app.slice - User Application Slice. Sep 4 18:20:09.745468 systemd[1745]: Reached target paths.target - Paths. Sep 4 18:20:09.745480 systemd[1745]: Reached target timers.target - Timers. Sep 4 18:20:09.746598 systemd[1745]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 4 18:20:09.756313 systemd[1745]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 4 18:20:09.756368 systemd[1745]: Reached target sockets.target - Sockets. Sep 4 18:20:09.756379 systemd[1745]: Reached target basic.target - Basic System. Sep 4 18:20:09.756416 systemd[1745]: Reached target default.target - Main User Target. Sep 4 18:20:09.756439 systemd[1745]: Startup finished in 235ms. Sep 4 18:20:09.756545 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 4 18:20:09.757673 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 4 18:20:10.330756 login[1736]: pam_unix(login:session): session opened for user core(uid=500) by core(uid=0) Sep 4 18:20:10.334800 systemd-logind[1609]: New session 2 of user core. Sep 4 18:20:10.340819 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 4 18:20:12.328682 waagent[1731]: 2024-09-04T18:20:12.328584Z INFO Daemon Daemon Azure Linux Agent Version: 2.9.1.1 Sep 4 18:20:12.335128 waagent[1731]: 2024-09-04T18:20:12.335066Z INFO Daemon Daemon OS: flatcar 4054.1.0 Sep 4 18:20:12.339797 waagent[1731]: 2024-09-04T18:20:12.339752Z INFO Daemon Daemon Python: 3.11.9 Sep 4 18:20:12.344253 waagent[1731]: 2024-09-04T18:20:12.344189Z INFO Daemon Daemon Run daemon Sep 4 18:20:12.348506 waagent[1731]: 2024-09-04T18:20:12.348460Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4054.1.0' Sep 4 18:20:12.357724 waagent[1731]: 2024-09-04T18:20:12.357680Z INFO Daemon Daemon Using waagent for provisioning Sep 4 18:20:12.363156 waagent[1731]: 2024-09-04T18:20:12.363115Z INFO Daemon Daemon Activate resource disk Sep 4 18:20:12.367923 waagent[1731]: 2024-09-04T18:20:12.367884Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Sep 4 18:20:12.379138 waagent[1731]: 2024-09-04T18:20:12.379094Z INFO Daemon Daemon Found device: None Sep 4 18:20:12.383978 waagent[1731]: 2024-09-04T18:20:12.383939Z ERROR Daemon Daemon Failed to mount resource disk [ResourceDiskError] unable to detect disk topology Sep 4 18:20:12.392739 waagent[1731]: 2024-09-04T18:20:12.392701Z ERROR Daemon Daemon Event: name=WALinuxAgent, op=ActivateResourceDisk, message=[ResourceDiskError] unable to detect disk topology, duration=0 Sep 4 18:20:12.405627 waagent[1731]: 2024-09-04T18:20:12.405577Z INFO Daemon Daemon Clean protocol and wireserver endpoint Sep 4 18:20:12.411694 waagent[1731]: 2024-09-04T18:20:12.411637Z INFO Daemon Daemon Running default provisioning handler Sep 4 18:20:12.423400 waagent[1731]: 2024-09-04T18:20:12.423327Z 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. Sep 4 18:20:12.437377 waagent[1731]: 2024-09-04T18:20:12.437324Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Sep 4 18:20:12.447293 waagent[1731]: 2024-09-04T18:20:12.447251Z INFO Daemon Daemon cloud-init is enabled: False Sep 4 18:20:12.452502 waagent[1731]: 2024-09-04T18:20:12.452462Z INFO Daemon Daemon Copying ovf-env.xml Sep 4 18:20:12.647222 waagent[1731]: 2024-09-04T18:20:12.646436Z INFO Daemon Daemon Successfully mounted dvd Sep 4 18:20:12.664528 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Sep 4 18:20:12.666452 waagent[1731]: 2024-09-04T18:20:12.666383Z INFO Daemon Daemon Detect protocol endpoint Sep 4 18:20:12.671809 waagent[1731]: 2024-09-04T18:20:12.671758Z INFO Daemon Daemon Clean protocol and wireserver endpoint Sep 4 18:20:12.678023 waagent[1731]: 2024-09-04T18:20:12.677975Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Sep 4 18:20:12.685087 waagent[1731]: 2024-09-04T18:20:12.685043Z INFO Daemon Daemon Test for route to 168.63.129.16 Sep 4 18:20:12.690644 waagent[1731]: 2024-09-04T18:20:12.690598Z INFO Daemon Daemon Route to 168.63.129.16 exists Sep 4 18:20:12.696137 waagent[1731]: 2024-09-04T18:20:12.696093Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Sep 4 18:20:12.772130 waagent[1731]: 2024-09-04T18:20:12.772079Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Sep 4 18:20:12.779528 waagent[1731]: 2024-09-04T18:20:12.779497Z INFO Daemon Daemon Wire protocol version:2012-11-30 Sep 4 18:20:12.785050 waagent[1731]: 2024-09-04T18:20:12.785003Z INFO Daemon Daemon Server preferred version:2015-04-05 Sep 4 18:20:13.384698 waagent[1731]: 2024-09-04T18:20:13.384024Z INFO Daemon Daemon Initializing goal state during protocol detection Sep 4 18:20:13.390964 waagent[1731]: 2024-09-04T18:20:13.390911Z INFO Daemon Daemon Forcing an update of the goal state. Sep 4 18:20:13.400141 waagent[1731]: 2024-09-04T18:20:13.400092Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Sep 4 18:20:13.443878 waagent[1731]: 2024-09-04T18:20:13.443833Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.154 Sep 4 18:20:13.449847 waagent[1731]: 2024-09-04T18:20:13.449801Z INFO Daemon Sep 4 18:20:13.452826 waagent[1731]: 2024-09-04T18:20:13.452785Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: ef7e939f-2b13-4a4c-a6a4-bd3b737fcd06 eTag: 11564711105595736120 source: Fabric] Sep 4 18:20:13.464562 waagent[1731]: 2024-09-04T18:20:13.464520Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Sep 4 18:20:13.471483 waagent[1731]: 2024-09-04T18:20:13.471439Z INFO Daemon Sep 4 18:20:13.474354 waagent[1731]: 2024-09-04T18:20:13.474314Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Sep 4 18:20:13.485317 waagent[1731]: 2024-09-04T18:20:13.485282Z INFO Daemon Daemon Downloading artifacts profile blob Sep 4 18:20:13.576238 waagent[1731]: 2024-09-04T18:20:13.576163Z INFO Daemon Downloaded certificate {'thumbprint': 'E321996C2463905FA537ADBAD5C16B765ECC8587', 'hasPrivateKey': False} Sep 4 18:20:13.586981 waagent[1731]: 2024-09-04T18:20:13.586933Z INFO Daemon Downloaded certificate {'thumbprint': '5A83218CACF1A37C73CA390AC78E687A38E9DAD7', 'hasPrivateKey': True} Sep 4 18:20:13.597125 waagent[1731]: 2024-09-04T18:20:13.597078Z INFO Daemon Fetch goal state completed Sep 4 18:20:13.611512 waagent[1731]: 2024-09-04T18:20:13.611446Z INFO Daemon Daemon Starting provisioning Sep 4 18:20:13.616608 waagent[1731]: 2024-09-04T18:20:13.616564Z INFO Daemon Daemon Handle ovf-env.xml. Sep 4 18:20:13.621336 waagent[1731]: 2024-09-04T18:20:13.621295Z INFO Daemon Daemon Set hostname [ci-4054.1.0-a-bc791940be] Sep 4 18:20:13.682676 waagent[1731]: 2024-09-04T18:20:13.677807Z INFO Daemon Daemon Publish hostname [ci-4054.1.0-a-bc791940be] Sep 4 18:20:13.684470 waagent[1731]: 2024-09-04T18:20:13.684418Z INFO Daemon Daemon Examine /proc/net/route for primary interface Sep 4 18:20:13.691264 waagent[1731]: 2024-09-04T18:20:13.691217Z INFO Daemon Daemon Primary interface is [eth0] Sep 4 18:20:13.707802 systemd-networkd[1503]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 4 18:20:13.708469 systemd-networkd[1503]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 4 18:20:13.708751 waagent[1731]: 2024-09-04T18:20:13.708512Z INFO Daemon Daemon Create user account if not exists Sep 4 18:20:13.708516 systemd-networkd[1503]: eth0: DHCP lease lost Sep 4 18:20:13.714428 waagent[1731]: 2024-09-04T18:20:13.714377Z INFO Daemon Daemon User core already exists, skip useradd Sep 4 18:20:13.720504 waagent[1731]: 2024-09-04T18:20:13.720455Z INFO Daemon Daemon Configure sudoer Sep 4 18:20:13.725230 waagent[1731]: 2024-09-04T18:20:13.725160Z INFO Daemon Daemon Configure sshd Sep 4 18:20:13.729811 waagent[1731]: 2024-09-04T18:20:13.729761Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Sep 4 18:20:13.730695 systemd-networkd[1503]: eth0: DHCPv6 lease lost Sep 4 18:20:13.743180 waagent[1731]: 2024-09-04T18:20:13.743117Z INFO Daemon Daemon Deploy ssh public key. Sep 4 18:20:13.755752 systemd-networkd[1503]: eth0: DHCPv4 address 10.200.20.47/24, gateway 10.200.20.1 acquired from 168.63.129.16 Sep 4 18:20:14.928684 waagent[1731]: 2024-09-04T18:20:14.928507Z INFO Daemon Daemon Provisioning complete Sep 4 18:20:14.948242 waagent[1731]: 2024-09-04T18:20:14.948196Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Sep 4 18:20:14.954505 waagent[1731]: 2024-09-04T18:20:14.954459Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Sep 4 18:20:14.964548 waagent[1731]: 2024-09-04T18:20:14.964507Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.9.1.1 is the most current agent Sep 4 18:20:15.093119 waagent[1800]: 2024-09-04T18:20:15.092584Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.9.1.1) Sep 4 18:20:15.093119 waagent[1800]: 2024-09-04T18:20:15.092763Z INFO ExtHandler ExtHandler OS: flatcar 4054.1.0 Sep 4 18:20:15.093119 waagent[1800]: 2024-09-04T18:20:15.092821Z INFO ExtHandler ExtHandler Python: 3.11.9 Sep 4 18:20:15.247030 waagent[1800]: 2024-09-04T18:20:15.246897Z INFO ExtHandler ExtHandler Distro: flatcar-4054.1.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.9; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Sep 4 18:20:15.247341 waagent[1800]: 2024-09-04T18:20:15.247301Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 4 18:20:15.247493 waagent[1800]: 2024-09-04T18:20:15.247457Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 4 18:20:15.261517 waagent[1800]: 2024-09-04T18:20:15.261458Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Sep 4 18:20:15.267113 waagent[1800]: 2024-09-04T18:20:15.267071Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.154 Sep 4 18:20:15.268685 waagent[1800]: 2024-09-04T18:20:15.267606Z INFO ExtHandler Sep 4 18:20:15.268685 waagent[1800]: 2024-09-04T18:20:15.267725Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 6d1aec06-4546-425e-9353-08d805166914 eTag: 11564711105595736120 source: Fabric] Sep 4 18:20:15.268685 waagent[1800]: 2024-09-04T18:20:15.268020Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Sep 4 18:20:15.268685 waagent[1800]: 2024-09-04T18:20:15.268565Z INFO ExtHandler Sep 4 18:20:15.268685 waagent[1800]: 2024-09-04T18:20:15.268637Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Sep 4 18:20:15.272524 waagent[1800]: 2024-09-04T18:20:15.272490Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Sep 4 18:20:15.364525 waagent[1800]: 2024-09-04T18:20:15.364463Z INFO ExtHandler Downloaded certificate {'thumbprint': 'E321996C2463905FA537ADBAD5C16B765ECC8587', 'hasPrivateKey': False} Sep 4 18:20:15.365085 waagent[1800]: 2024-09-04T18:20:15.365042Z INFO ExtHandler Downloaded certificate {'thumbprint': '5A83218CACF1A37C73CA390AC78E687A38E9DAD7', 'hasPrivateKey': True} Sep 4 18:20:15.365585 waagent[1800]: 2024-09-04T18:20:15.365543Z INFO ExtHandler Fetch goal state completed Sep 4 18:20:15.379275 waagent[1800]: 2024-09-04T18:20:15.379228Z INFO ExtHandler ExtHandler WALinuxAgent-2.9.1.1 running as process 1800 Sep 4 18:20:15.379511 waagent[1800]: 2024-09-04T18:20:15.379473Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Sep 4 18:20:15.381234 waagent[1800]: 2024-09-04T18:20:15.381187Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '4054.1.0', '', 'Flatcar Container Linux by Kinvolk'] Sep 4 18:20:15.381754 waagent[1800]: 2024-09-04T18:20:15.381712Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Sep 4 18:20:15.388575 waagent[1800]: 2024-09-04T18:20:15.388545Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Sep 4 18:20:15.388865 waagent[1800]: 2024-09-04T18:20:15.388823Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Sep 4 18:20:15.394982 waagent[1800]: 2024-09-04T18:20:15.394952Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Sep 4 18:20:15.400862 systemd[1]: Reloading requested from client PID 1815 ('systemctl') (unit waagent.service)... Sep 4 18:20:15.400876 systemd[1]: Reloading... Sep 4 18:20:15.473713 zram_generator::config[1847]: No configuration found. Sep 4 18:20:15.571187 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. Sep 4 18:20:15.647703 systemd[1]: Reloading finished in 246 ms. Sep 4 18:20:15.670156 waagent[1800]: 2024-09-04T18:20:15.669803Z INFO ExtHandler ExtHandler Executing systemctl daemon-reload for setting up waagent-network-setup.service Sep 4 18:20:15.676833 systemd[1]: Reloading requested from client PID 1900 ('systemctl') (unit waagent.service)... Sep 4 18:20:15.676849 systemd[1]: Reloading... Sep 4 18:20:15.743702 zram_generator::config[1928]: No configuration found. Sep 4 18:20:15.850066 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. Sep 4 18:20:15.927038 systemd[1]: Reloading finished in 249 ms. Sep 4 18:20:15.951366 waagent[1800]: 2024-09-04T18:20:15.950593Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Sep 4 18:20:15.951366 waagent[1800]: 2024-09-04T18:20:15.950781Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Sep 4 18:20:16.217997 waagent[1800]: 2024-09-04T18:20:16.217865Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Sep 4 18:20:16.218540 waagent[1800]: 2024-09-04T18:20:16.218485Z 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] Sep 4 18:20:16.219426 waagent[1800]: 2024-09-04T18:20:16.219336Z INFO ExtHandler ExtHandler Starting env monitor service. Sep 4 18:20:16.219559 waagent[1800]: 2024-09-04T18:20:16.219484Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 4 18:20:16.219986 waagent[1800]: 2024-09-04T18:20:16.219929Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Sep 4 18:20:16.220040 waagent[1800]: 2024-09-04T18:20:16.220000Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 4 18:20:16.220234 waagent[1800]: 2024-09-04T18:20:16.220193Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Sep 4 18:20:16.220611 waagent[1800]: 2024-09-04T18:20:16.220563Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Sep 4 18:20:16.220814 waagent[1800]: 2024-09-04T18:20:16.220776Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Sep 4 18:20:16.220966 waagent[1800]: 2024-09-04T18:20:16.220927Z INFO EnvHandler ExtHandler Configure routes Sep 4 18:20:16.221023 waagent[1800]: 2024-09-04T18:20:16.220995Z INFO EnvHandler ExtHandler Gateway:None Sep 4 18:20:16.221070 waagent[1800]: 2024-09-04T18:20:16.221044Z INFO EnvHandler ExtHandler Routes:None Sep 4 18:20:16.221852 waagent[1800]: 2024-09-04T18:20:16.221802Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Sep 4 18:20:16.222423 waagent[1800]: 2024-09-04T18:20:16.221986Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Sep 4 18:20:16.222423 waagent[1800]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Sep 4 18:20:16.222423 waagent[1800]: eth0 00000000 0114C80A 0003 0 0 1024 00000000 0 0 0 Sep 4 18:20:16.222423 waagent[1800]: eth0 0014C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Sep 4 18:20:16.222423 waagent[1800]: eth0 0114C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Sep 4 18:20:16.222423 waagent[1800]: eth0 10813FA8 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Sep 4 18:20:16.222423 waagent[1800]: eth0 FEA9FEA9 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Sep 4 18:20:16.222423 waagent[1800]: 2024-09-04T18:20:16.221591Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Sep 4 18:20:16.224262 waagent[1800]: 2024-09-04T18:20:16.223788Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Sep 4 18:20:16.224262 waagent[1800]: 2024-09-04T18:20:16.223869Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Sep 4 18:20:16.224379 waagent[1800]: 2024-09-04T18:20:16.224327Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Sep 4 18:20:16.231784 waagent[1800]: 2024-09-04T18:20:16.231743Z INFO ExtHandler ExtHandler Sep 4 18:20:16.231953 waagent[1800]: 2024-09-04T18:20:16.231918Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 6fdfcb9f-dc79-4d18-a0bd-4384df7cd539 correlation 55549087-8c5c-4716-8692-17c7d77c839c created: 2024-09-04T18:17:52.480026Z] Sep 4 18:20:16.232364 waagent[1800]: 2024-09-04T18:20:16.232323Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Sep 4 18:20:16.233057 waagent[1800]: 2024-09-04T18:20:16.233016Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 1 ms] Sep 4 18:20:16.267373 waagent[1800]: 2024-09-04T18:20:16.267312Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.9.1.1 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: DDDBBDA1-9E33-4AF6-A001-48162B3C5921;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 0] Sep 4 18:20:16.309494 waagent[1800]: 2024-09-04T18:20:16.309066Z INFO MonitorHandler ExtHandler Network interfaces: Sep 4 18:20:16.309494 waagent[1800]: Executing ['ip', '-a', '-o', 'link']: Sep 4 18:20:16.309494 waagent[1800]: 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 Sep 4 18:20:16.309494 waagent[1800]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:22:48:7b:6c:94 brd ff:ff:ff:ff:ff:ff Sep 4 18:20:16.309494 waagent[1800]: 3: enP25850s1: mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\ link/ether 00:22:48:7b:6c:94 brd ff:ff:ff:ff:ff:ff\ altname enP25850p0s2 Sep 4 18:20:16.309494 waagent[1800]: Executing ['ip', '-4', '-a', '-o', 'address']: Sep 4 18:20:16.309494 waagent[1800]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Sep 4 18:20:16.309494 waagent[1800]: 2: eth0 inet 10.200.20.47/24 metric 1024 brd 10.200.20.255 scope global eth0\ valid_lft forever preferred_lft forever Sep 4 18:20:16.309494 waagent[1800]: Executing ['ip', '-6', '-a', '-o', 'address']: Sep 4 18:20:16.309494 waagent[1800]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Sep 4 18:20:16.309494 waagent[1800]: 2: eth0 inet6 fe80::222:48ff:fe7b:6c94/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Sep 4 18:20:16.309494 waagent[1800]: 3: enP25850s1 inet6 fe80::222:48ff:fe7b:6c94/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Sep 4 18:20:16.400037 waagent[1800]: 2024-09-04T18:20:16.399093Z INFO EnvHandler ExtHandler Successfully added Azure fabric firewall rules. Current Firewall rules: Sep 4 18:20:16.400037 waagent[1800]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Sep 4 18:20:16.400037 waagent[1800]: pkts bytes target prot opt in out source destination Sep 4 18:20:16.400037 waagent[1800]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Sep 4 18:20:16.400037 waagent[1800]: pkts bytes target prot opt in out source destination Sep 4 18:20:16.400037 waagent[1800]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Sep 4 18:20:16.400037 waagent[1800]: pkts bytes target prot opt in out source destination Sep 4 18:20:16.400037 waagent[1800]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Sep 4 18:20:16.400037 waagent[1800]: 2 303 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Sep 4 18:20:16.400037 waagent[1800]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Sep 4 18:20:16.402983 waagent[1800]: 2024-09-04T18:20:16.402928Z INFO EnvHandler ExtHandler Current Firewall rules: Sep 4 18:20:16.402983 waagent[1800]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Sep 4 18:20:16.402983 waagent[1800]: pkts bytes target prot opt in out source destination Sep 4 18:20:16.402983 waagent[1800]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Sep 4 18:20:16.402983 waagent[1800]: pkts bytes target prot opt in out source destination Sep 4 18:20:16.402983 waagent[1800]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Sep 4 18:20:16.402983 waagent[1800]: pkts bytes target prot opt in out source destination Sep 4 18:20:16.402983 waagent[1800]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Sep 4 18:20:16.402983 waagent[1800]: 7 760 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Sep 4 18:20:16.402983 waagent[1800]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Sep 4 18:20:16.403461 waagent[1800]: 2024-09-04T18:20:16.403429Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Sep 4 18:20:30.535450 chronyd[1625]: Selected source PHC0 Sep 4 18:20:39.833782 kernel: hv_balloon: Max. dynamic memory size: 4096 MB Sep 4 18:20:52.140362 update_engine[1612]: I0904 18:20:52.139715 1612 update_attempter.cc:509] Updating boot flags... Sep 4 18:20:52.192714 kernel: BTRFS warning: duplicate device /dev/sda3 devid 1 generation 42 scanned by (udev-worker) (2034) Sep 4 18:20:59.782490 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 4 18:20:59.783563 systemd[1]: Started sshd@0-10.200.20.47:22-10.200.16.10:44666.service - OpenSSH per-connection server daemon (10.200.16.10:44666). Sep 4 18:21:00.247785 sshd[2062]: Accepted publickey for core from 10.200.16.10 port 44666 ssh2: RSA SHA256:1Gj1wCVnuCZigHSpj6e/Ns7sAOQfeYtBhY0CidOKtFA Sep 4 18:21:00.249047 sshd[2062]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 18:21:00.252682 systemd-logind[1609]: New session 3 of user core. Sep 4 18:21:00.260850 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 4 18:21:00.652892 systemd[1]: Started sshd@1-10.200.20.47:22-10.200.16.10:44678.service - OpenSSH per-connection server daemon (10.200.16.10:44678). Sep 4 18:21:01.092615 sshd[2067]: Accepted publickey for core from 10.200.16.10 port 44678 ssh2: RSA SHA256:1Gj1wCVnuCZigHSpj6e/Ns7sAOQfeYtBhY0CidOKtFA Sep 4 18:21:01.093844 sshd[2067]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 18:21:01.097360 systemd-logind[1609]: New session 4 of user core. Sep 4 18:21:01.104788 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 4 18:21:01.422240 sshd[2067]: pam_unix(sshd:session): session closed for user core Sep 4 18:21:01.425331 systemd[1]: sshd@1-10.200.20.47:22-10.200.16.10:44678.service: Deactivated successfully. Sep 4 18:21:01.426844 systemd[1]: session-4.scope: Deactivated successfully. Sep 4 18:21:01.427454 systemd-logind[1609]: Session 4 logged out. Waiting for processes to exit. Sep 4 18:21:01.428301 systemd-logind[1609]: Removed session 4. Sep 4 18:21:01.507004 systemd[1]: Started sshd@2-10.200.20.47:22-10.200.16.10:44686.service - OpenSSH per-connection server daemon (10.200.16.10:44686). Sep 4 18:21:01.977684 sshd[2074]: Accepted publickey for core from 10.200.16.10 port 44686 ssh2: RSA SHA256:1Gj1wCVnuCZigHSpj6e/Ns7sAOQfeYtBhY0CidOKtFA Sep 4 18:21:01.978965 sshd[2074]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 18:21:01.982524 systemd-logind[1609]: New session 5 of user core. Sep 4 18:21:01.989778 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 4 18:21:02.317992 sshd[2074]: pam_unix(sshd:session): session closed for user core Sep 4 18:21:02.321317 systemd[1]: sshd@2-10.200.20.47:22-10.200.16.10:44686.service: Deactivated successfully. Sep 4 18:21:02.322764 systemd[1]: session-5.scope: Deactivated successfully. Sep 4 18:21:02.323580 systemd-logind[1609]: Session 5 logged out. Waiting for processes to exit. Sep 4 18:21:02.324484 systemd-logind[1609]: Removed session 5. Sep 4 18:21:02.404728 systemd[1]: Started sshd@3-10.200.20.47:22-10.200.16.10:44702.service - OpenSSH per-connection server daemon (10.200.16.10:44702). Sep 4 18:21:02.883872 sshd[2081]: Accepted publickey for core from 10.200.16.10 port 44702 ssh2: RSA SHA256:1Gj1wCVnuCZigHSpj6e/Ns7sAOQfeYtBhY0CidOKtFA Sep 4 18:21:02.885103 sshd[2081]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 18:21:02.888742 systemd-logind[1609]: New session 6 of user core. Sep 4 18:21:02.896850 systemd[1]: Started session-6.scope - Session 6 of User core. Sep 4 18:21:03.243737 sshd[2081]: pam_unix(sshd:session): session closed for user core Sep 4 18:21:03.247315 systemd[1]: sshd@3-10.200.20.47:22-10.200.16.10:44702.service: Deactivated successfully. Sep 4 18:21:03.248801 systemd[1]: session-6.scope: Deactivated successfully. Sep 4 18:21:03.249407 systemd-logind[1609]: Session 6 logged out. Waiting for processes to exit. Sep 4 18:21:03.250202 systemd-logind[1609]: Removed session 6. Sep 4 18:21:03.328931 systemd[1]: Started sshd@4-10.200.20.47:22-10.200.16.10:44704.service - OpenSSH per-connection server daemon (10.200.16.10:44704). Sep 4 18:21:03.769185 sshd[2088]: Accepted publickey for core from 10.200.16.10 port 44704 ssh2: RSA SHA256:1Gj1wCVnuCZigHSpj6e/Ns7sAOQfeYtBhY0CidOKtFA Sep 4 18:21:03.770368 sshd[2088]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 18:21:03.775203 systemd-logind[1609]: New session 7 of user core. Sep 4 18:21:03.781871 systemd[1]: Started session-7.scope - Session 7 of User core. Sep 4 18:21:04.058008 sudo[2091]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Sep 4 18:21:04.058264 sudo[2091]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 4 18:21:04.078414 sudo[2091]: pam_unix(sudo:session): session closed for user root Sep 4 18:21:04.154124 sshd[2088]: pam_unix(sshd:session): session closed for user core Sep 4 18:21:04.157129 systemd[1]: sshd@4-10.200.20.47:22-10.200.16.10:44704.service: Deactivated successfully. Sep 4 18:21:04.159149 systemd[1]: session-7.scope: Deactivated successfully. Sep 4 18:21:04.160360 systemd-logind[1609]: Session 7 logged out. Waiting for processes to exit. Sep 4 18:21:04.161353 systemd-logind[1609]: Removed session 7. Sep 4 18:21:04.234104 systemd[1]: Started sshd@5-10.200.20.47:22-10.200.16.10:44714.service - OpenSSH per-connection server daemon (10.200.16.10:44714). Sep 4 18:21:04.674399 sshd[2096]: Accepted publickey for core from 10.200.16.10 port 44714 ssh2: RSA SHA256:1Gj1wCVnuCZigHSpj6e/Ns7sAOQfeYtBhY0CidOKtFA Sep 4 18:21:04.675715 sshd[2096]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 18:21:04.680328 systemd-logind[1609]: New session 8 of user core. Sep 4 18:21:04.685787 systemd[1]: Started session-8.scope - Session 8 of User core. Sep 4 18:21:04.927469 sudo[2100]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Sep 4 18:21:04.927897 sudo[2100]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 4 18:21:04.930831 sudo[2100]: pam_unix(sudo:session): session closed for user root Sep 4 18:21:04.934951 sudo[2099]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/systemctl restart audit-rules Sep 4 18:21:04.935192 sudo[2099]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 4 18:21:04.952192 systemd[1]: Stopping audit-rules.service - Load Security Auditing Rules... Sep 4 18:21:04.953106 auditctl[2103]: No rules Sep 4 18:21:04.954187 systemd[1]: audit-rules.service: Deactivated successfully. Sep 4 18:21:04.954364 systemd[1]: Stopped audit-rules.service - Load Security Auditing Rules. Sep 4 18:21:04.957914 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Sep 4 18:21:04.977197 augenrules[2121]: No rules Sep 4 18:21:04.978249 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Sep 4 18:21:04.979306 sudo[2099]: pam_unix(sudo:session): session closed for user root Sep 4 18:21:05.050870 sshd[2096]: pam_unix(sshd:session): session closed for user core Sep 4 18:21:05.053303 systemd-logind[1609]: Session 8 logged out. Waiting for processes to exit. Sep 4 18:21:05.054285 systemd[1]: sshd@5-10.200.20.47:22-10.200.16.10:44714.service: Deactivated successfully. Sep 4 18:21:05.055867 systemd[1]: session-8.scope: Deactivated successfully. Sep 4 18:21:05.057961 systemd-logind[1609]: Removed session 8. Sep 4 18:21:05.136541 systemd[1]: Started sshd@6-10.200.20.47:22-10.200.16.10:44726.service - OpenSSH per-connection server daemon (10.200.16.10:44726). Sep 4 18:21:05.611029 sshd[2129]: Accepted publickey for core from 10.200.16.10 port 44726 ssh2: RSA SHA256:1Gj1wCVnuCZigHSpj6e/Ns7sAOQfeYtBhY0CidOKtFA Sep 4 18:21:05.612257 sshd[2129]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 18:21:05.615834 systemd-logind[1609]: New session 9 of user core. Sep 4 18:21:05.623858 systemd[1]: Started session-9.scope - Session 9 of User core. Sep 4 18:21:05.881231 sudo[2132]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/cat /var/resource/http Sep 4 18:21:05.881493 sudo[2132]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 4 18:21:05.884444 sudo[2132]: pam_unix(sudo:session): session closed for user root Sep 4 18:21:05.971148 sshd[2129]: pam_unix(sshd:session): session closed for user core Sep 4 18:21:05.974521 systemd[1]: sshd@6-10.200.20.47:22-10.200.16.10:44726.service: Deactivated successfully. Sep 4 18:21:05.976980 systemd[1]: session-9.scope: Deactivated successfully. Sep 4 18:21:05.977746 systemd-logind[1609]: Session 9 logged out. Waiting for processes to exit. Sep 4 18:21:05.978743 systemd-logind[1609]: Removed session 9. Sep 4 18:21:06.051691 systemd[1]: Started sshd@7-10.200.20.47:22-10.200.16.10:44742.service - OpenSSH per-connection server daemon (10.200.16.10:44742). Sep 4 18:21:06.491355 sshd[2137]: Accepted publickey for core from 10.200.16.10 port 44742 ssh2: RSA SHA256:1Gj1wCVnuCZigHSpj6e/Ns7sAOQfeYtBhY0CidOKtFA Sep 4 18:21:06.492574 sshd[2137]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 18:21:06.495932 systemd-logind[1609]: New session 10 of user core. Sep 4 18:21:06.506791 systemd[1]: Started session-10.scope - Session 10 of User core. Sep 4 18:21:06.744387 sudo[2140]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/cat /var/resource/tftp Sep 4 18:21:06.744713 sudo[2140]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 4 18:21:06.747342 sudo[2140]: pam_unix(sudo:session): session closed for user root Sep 4 18:21:06.822616 sshd[2137]: pam_unix(sshd:session): session closed for user core Sep 4 18:21:06.825509 systemd[1]: sshd@7-10.200.20.47:22-10.200.16.10:44742.service: Deactivated successfully. Sep 4 18:21:06.826934 systemd[1]: session-10.scope: Deactivated successfully. Sep 4 18:21:06.827518 systemd-logind[1609]: Session 10 logged out. Waiting for processes to exit. Sep 4 18:21:06.828450 systemd-logind[1609]: Removed session 10. Sep 4 18:21:06.906757 systemd[1]: Started sshd@8-10.200.20.47:22-10.200.16.10:44748.service - OpenSSH per-connection server daemon (10.200.16.10:44748). Sep 4 18:21:07.377973 sshd[2145]: Accepted publickey for core from 10.200.16.10 port 44748 ssh2: RSA SHA256:1Gj1wCVnuCZigHSpj6e/Ns7sAOQfeYtBhY0CidOKtFA Sep 4 18:21:07.379152 sshd[2145]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 4 18:21:07.382838 systemd-logind[1609]: New session 11 of user core. Sep 4 18:21:07.392785 systemd[1]: Started session-11.scope - Session 11 of User core. Sep 4 18:21:07.646941 sudo[2148]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/cat /var/resource/data Sep 4 18:21:07.647221 sudo[2148]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 4 18:21:07.649913 sudo[2148]: pam_unix(sudo:session): session closed for user root Sep 4 18:21:07.725207 sshd[2145]: pam_unix(sshd:session): session closed for user core Sep 4 18:21:07.728364 systemd-logind[1609]: Session 11 logged out. Waiting for processes to exit. Sep 4 18:21:07.729034 systemd[1]: sshd@8-10.200.20.47:22-10.200.16.10:44748.service: Deactivated successfully. Sep 4 18:21:07.730886 systemd[1]: session-11.scope: Deactivated successfully. Sep 4 18:21:07.732008 systemd-logind[1609]: Removed session 11.