Jun 25 18:30:33.957612 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Jun 25 18:30:33.957634 kernel: Linux version 6.6.35-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 13.2.1_p20240210 p14) 13.2.1 20240210, GNU ld (Gentoo 2.41 p5) 2.41.0) #1 SMP PREEMPT Tue Jun 25 17:19:03 -00 2024 Jun 25 18:30:33.957645 kernel: KASLR enabled Jun 25 18:30:33.957650 kernel: efi: EFI v2.7 by EDK II Jun 25 18:30:33.957656 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb8fd018 ACPI 2.0=0xd9710018 RNG=0xd971e498 MEMRESERVE=0xd9b43d18 Jun 25 18:30:33.957662 kernel: random: crng init done Jun 25 18:30:33.957669 kernel: ACPI: Early table checksum verification disabled Jun 25 18:30:33.957675 kernel: ACPI: RSDP 0x00000000D9710018 000024 (v02 BOCHS ) Jun 25 18:30:33.957681 kernel: ACPI: XSDT 0x00000000D971FE98 000064 (v01 BOCHS BXPC 00000001 01000013) Jun 25 18:30:33.957689 kernel: ACPI: FACP 0x00000000D971FA98 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Jun 25 18:30:33.957696 kernel: ACPI: DSDT 0x00000000D9717518 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Jun 25 18:30:33.957703 kernel: ACPI: APIC 0x00000000D971FC18 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Jun 25 18:30:33.957709 kernel: ACPI: PPTT 0x00000000D971D898 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Jun 25 18:30:33.957715 kernel: ACPI: GTDT 0x00000000D971E818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Jun 25 18:30:33.957723 kernel: ACPI: MCFG 0x00000000D971E918 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Jun 25 18:30:33.957731 kernel: ACPI: SPCR 0x00000000D971FF98 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Jun 25 18:30:33.957737 kernel: ACPI: DBG2 0x00000000D971E418 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Jun 25 18:30:33.957744 kernel: ACPI: IORT 0x00000000D971E718 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Jun 25 18:30:33.957750 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Jun 25 18:30:33.957756 kernel: NUMA: Failed to initialise from firmware Jun 25 18:30:33.957763 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Jun 25 18:30:33.957769 kernel: NUMA: NODE_DATA [mem 0xdc957800-0xdc95cfff] Jun 25 18:30:33.957775 kernel: Zone ranges: Jun 25 18:30:33.957782 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Jun 25 18:30:33.957788 kernel: DMA32 empty Jun 25 18:30:33.957795 kernel: Normal empty Jun 25 18:30:33.957802 kernel: Movable zone start for each node Jun 25 18:30:33.957808 kernel: Early memory node ranges Jun 25 18:30:33.957815 kernel: node 0: [mem 0x0000000040000000-0x00000000d976ffff] Jun 25 18:30:33.957822 kernel: node 0: [mem 0x00000000d9770000-0x00000000d9b3ffff] Jun 25 18:30:33.957828 kernel: node 0: [mem 0x00000000d9b40000-0x00000000dce1ffff] Jun 25 18:30:33.957835 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Jun 25 18:30:33.957841 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Jun 25 18:30:33.957848 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Jun 25 18:30:33.957854 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Jun 25 18:30:33.957861 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Jun 25 18:30:33.957867 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Jun 25 18:30:33.957879 kernel: psci: probing for conduit method from ACPI. Jun 25 18:30:33.957886 kernel: psci: PSCIv1.1 detected in firmware. Jun 25 18:30:33.957892 kernel: psci: Using standard PSCI v0.2 function IDs Jun 25 18:30:33.957902 kernel: psci: Trusted OS migration not required Jun 25 18:30:33.957909 kernel: psci: SMC Calling Convention v1.1 Jun 25 18:30:33.957917 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Jun 25 18:30:33.957926 kernel: percpu: Embedded 31 pages/cpu s86632 r8192 d32152 u126976 Jun 25 18:30:33.957932 kernel: pcpu-alloc: s86632 r8192 d32152 u126976 alloc=31*4096 Jun 25 18:30:33.957940 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Jun 25 18:30:33.957947 kernel: Detected PIPT I-cache on CPU0 Jun 25 18:30:33.957954 kernel: CPU features: detected: GIC system register CPU interface Jun 25 18:30:33.957961 kernel: CPU features: detected: Hardware dirty bit management Jun 25 18:30:33.957968 kernel: CPU features: detected: Spectre-v4 Jun 25 18:30:33.957974 kernel: CPU features: detected: Spectre-BHB Jun 25 18:30:33.957982 kernel: CPU features: kernel page table isolation forced ON by KASLR Jun 25 18:30:33.957989 kernel: CPU features: detected: Kernel page table isolation (KPTI) Jun 25 18:30:33.957997 kernel: CPU features: detected: ARM erratum 1418040 Jun 25 18:30:33.958004 kernel: alternatives: applying boot alternatives Jun 25 18:30:33.958012 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=ttyS0,115200 flatcar.first_boot=detected acpi=force verity.usrhash=e6069a8408a0ca7e7bc40a0bde7fe3ef89df2f98c4bdd2e7e7f9f8f3f8ad207f Jun 25 18:30:33.958020 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Jun 25 18:30:33.958027 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Jun 25 18:30:33.958034 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Jun 25 18:30:33.958041 kernel: Fallback order for Node 0: 0 Jun 25 18:30:33.958049 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Jun 25 18:30:33.958056 kernel: Policy zone: DMA Jun 25 18:30:33.958092 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jun 25 18:30:33.958100 kernel: software IO TLB: area num 4. Jun 25 18:30:33.958109 kernel: software IO TLB: mapped [mem 0x00000000d2e00000-0x00000000d6e00000] (64MB) Jun 25 18:30:33.958117 kernel: Memory: 2386848K/2572288K available (10240K kernel code, 2182K rwdata, 8072K rodata, 39040K init, 897K bss, 185440K reserved, 0K cma-reserved) Jun 25 18:30:33.958139 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Jun 25 18:30:33.958146 kernel: trace event string verifier disabled Jun 25 18:30:33.958153 kernel: rcu: Preemptible hierarchical RCU implementation. Jun 25 18:30:33.958164 kernel: rcu: RCU event tracing is enabled. Jun 25 18:30:33.958175 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Jun 25 18:30:33.958185 kernel: Trampoline variant of Tasks RCU enabled. Jun 25 18:30:33.958195 kernel: Tracing variant of Tasks RCU enabled. Jun 25 18:30:33.958203 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Jun 25 18:30:33.958210 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Jun 25 18:30:33.958217 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Jun 25 18:30:33.958227 kernel: GICv3: 256 SPIs implemented Jun 25 18:30:33.958234 kernel: GICv3: 0 Extended SPIs implemented Jun 25 18:30:33.958241 kernel: Root IRQ handler: gic_handle_irq Jun 25 18:30:33.958248 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Jun 25 18:30:33.958255 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Jun 25 18:30:33.958262 kernel: ITS [mem 0x08080000-0x0809ffff] Jun 25 18:30:33.958269 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400d0000 (indirect, esz 8, psz 64K, shr 1) Jun 25 18:30:33.958277 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400e0000 (flat, esz 8, psz 64K, shr 1) Jun 25 18:30:33.958284 kernel: GICv3: using LPI property table @0x00000000400f0000 Jun 25 18:30:33.958291 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040100000 Jun 25 18:30:33.958302 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jun 25 18:30:33.958311 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jun 25 18:30:33.958319 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Jun 25 18:30:33.958326 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Jun 25 18:30:33.958333 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Jun 25 18:30:33.958340 kernel: arm-pv: using stolen time PV Jun 25 18:30:33.958347 kernel: Console: colour dummy device 80x25 Jun 25 18:30:33.958354 kernel: ACPI: Core revision 20230628 Jun 25 18:30:33.958362 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Jun 25 18:30:33.958369 kernel: pid_max: default: 32768 minimum: 301 Jun 25 18:30:33.958376 kernel: LSM: initializing lsm=lockdown,capability,selinux,integrity Jun 25 18:30:33.958385 kernel: SELinux: Initializing. Jun 25 18:30:33.958392 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Jun 25 18:30:33.958399 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Jun 25 18:30:33.958406 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Jun 25 18:30:33.958413 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Jun 25 18:30:33.958421 kernel: rcu: Hierarchical SRCU implementation. Jun 25 18:30:33.958435 kernel: rcu: Max phase no-delay instances is 400. Jun 25 18:30:33.958443 kernel: Platform MSI: ITS@0x8080000 domain created Jun 25 18:30:33.958450 kernel: PCI/MSI: ITS@0x8080000 domain created Jun 25 18:30:33.958459 kernel: Remapping and enabling EFI services. Jun 25 18:30:33.958467 kernel: smp: Bringing up secondary CPUs ... Jun 25 18:30:33.958474 kernel: Detected PIPT I-cache on CPU1 Jun 25 18:30:33.958481 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Jun 25 18:30:33.958489 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040110000 Jun 25 18:30:33.958496 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jun 25 18:30:33.958503 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Jun 25 18:30:33.958510 kernel: Detected PIPT I-cache on CPU2 Jun 25 18:30:33.958518 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Jun 25 18:30:33.958525 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040120000 Jun 25 18:30:33.958534 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jun 25 18:30:33.958541 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Jun 25 18:30:33.958553 kernel: Detected PIPT I-cache on CPU3 Jun 25 18:30:33.958562 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Jun 25 18:30:33.958570 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040130000 Jun 25 18:30:33.958577 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jun 25 18:30:33.958584 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Jun 25 18:30:33.958592 kernel: smp: Brought up 1 node, 4 CPUs Jun 25 18:30:33.958600 kernel: SMP: Total of 4 processors activated. Jun 25 18:30:33.958609 kernel: CPU features: detected: 32-bit EL0 Support Jun 25 18:30:33.958616 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Jun 25 18:30:33.958624 kernel: CPU features: detected: Common not Private translations Jun 25 18:30:33.958631 kernel: CPU features: detected: CRC32 instructions Jun 25 18:30:33.958639 kernel: CPU features: detected: Enhanced Virtualization Traps Jun 25 18:30:33.958646 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Jun 25 18:30:33.958654 kernel: CPU features: detected: LSE atomic instructions Jun 25 18:30:33.958661 kernel: CPU features: detected: Privileged Access Never Jun 25 18:30:33.958670 kernel: CPU features: detected: RAS Extension Support Jun 25 18:30:33.958678 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Jun 25 18:30:33.958685 kernel: CPU: All CPU(s) started at EL1 Jun 25 18:30:33.958693 kernel: alternatives: applying system-wide alternatives Jun 25 18:30:33.958700 kernel: devtmpfs: initialized Jun 25 18:30:33.958708 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Jun 25 18:30:33.958715 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Jun 25 18:30:33.958723 kernel: pinctrl core: initialized pinctrl subsystem Jun 25 18:30:33.958730 kernel: SMBIOS 3.0.0 present. Jun 25 18:30:33.958740 kernel: DMI: QEMU KVM Virtual Machine, BIOS edk2-20230524-3.fc38 05/24/2023 Jun 25 18:30:33.958747 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jun 25 18:30:33.958755 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Jun 25 18:30:33.958762 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Jun 25 18:30:33.958770 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Jun 25 18:30:33.958778 kernel: audit: initializing netlink subsys (disabled) Jun 25 18:30:33.958785 kernel: audit: type=2000 audit(0.024:1): state=initialized audit_enabled=0 res=1 Jun 25 18:30:33.958793 kernel: thermal_sys: Registered thermal governor 'step_wise' Jun 25 18:30:33.958801 kernel: cpuidle: using governor menu Jun 25 18:30:33.958811 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Jun 25 18:30:33.958818 kernel: ASID allocator initialised with 32768 entries Jun 25 18:30:33.958826 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jun 25 18:30:33.958834 kernel: Serial: AMBA PL011 UART driver Jun 25 18:30:33.958841 kernel: Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Jun 25 18:30:33.958849 kernel: Modules: 0 pages in range for non-PLT usage Jun 25 18:30:33.958857 kernel: Modules: 509120 pages in range for PLT usage Jun 25 18:30:33.958864 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Jun 25 18:30:33.958873 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Jun 25 18:30:33.958883 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Jun 25 18:30:33.958890 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Jun 25 18:30:33.958897 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Jun 25 18:30:33.958905 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Jun 25 18:30:33.958912 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Jun 25 18:30:33.958920 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Jun 25 18:30:33.958927 kernel: ACPI: Added _OSI(Module Device) Jun 25 18:30:33.958935 kernel: ACPI: Added _OSI(Processor Device) Jun 25 18:30:33.958942 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Jun 25 18:30:33.958951 kernel: ACPI: Added _OSI(Processor Aggregator Device) Jun 25 18:30:33.958958 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Jun 25 18:30:33.958966 kernel: ACPI: Interpreter enabled Jun 25 18:30:33.958974 kernel: ACPI: Using GIC for interrupt routing Jun 25 18:30:33.958982 kernel: ACPI: MCFG table detected, 1 entries Jun 25 18:30:33.958990 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Jun 25 18:30:33.958997 kernel: printk: console [ttyAMA0] enabled Jun 25 18:30:33.959005 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Jun 25 18:30:33.959160 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Jun 25 18:30:33.959241 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Jun 25 18:30:33.959311 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Jun 25 18:30:33.959380 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Jun 25 18:30:33.959458 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Jun 25 18:30:33.959468 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Jun 25 18:30:33.959476 kernel: PCI host bridge to bus 0000:00 Jun 25 18:30:33.959549 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Jun 25 18:30:33.959616 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Jun 25 18:30:33.959676 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Jun 25 18:30:33.959736 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Jun 25 18:30:33.959835 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Jun 25 18:30:33.959927 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Jun 25 18:30:33.960001 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Jun 25 18:30:33.960112 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Jun 25 18:30:33.960188 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Jun 25 18:30:33.960258 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Jun 25 18:30:33.960335 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Jun 25 18:30:33.960404 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Jun 25 18:30:33.960494 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Jun 25 18:30:33.960569 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Jun 25 18:30:33.960635 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Jun 25 18:30:33.960645 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Jun 25 18:30:33.960653 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Jun 25 18:30:33.960660 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Jun 25 18:30:33.960667 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Jun 25 18:30:33.960675 kernel: iommu: Default domain type: Translated Jun 25 18:30:33.960682 kernel: iommu: DMA domain TLB invalidation policy: strict mode Jun 25 18:30:33.960689 kernel: efivars: Registered efivars operations Jun 25 18:30:33.960697 kernel: vgaarb: loaded Jun 25 18:30:33.960707 kernel: clocksource: Switched to clocksource arch_sys_counter Jun 25 18:30:33.960714 kernel: VFS: Disk quotas dquot_6.6.0 Jun 25 18:30:33.960722 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jun 25 18:30:33.960730 kernel: pnp: PnP ACPI init Jun 25 18:30:33.960804 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Jun 25 18:30:33.960817 kernel: pnp: PnP ACPI: found 1 devices Jun 25 18:30:33.960825 kernel: NET: Registered PF_INET protocol family Jun 25 18:30:33.960832 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Jun 25 18:30:33.960842 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Jun 25 18:30:33.960850 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jun 25 18:30:33.960858 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Jun 25 18:30:33.960865 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Jun 25 18:30:33.960878 kernel: TCP: Hash tables configured (established 32768 bind 32768) Jun 25 18:30:33.960886 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Jun 25 18:30:33.960894 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Jun 25 18:30:33.960901 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jun 25 18:30:33.960909 kernel: PCI: CLS 0 bytes, default 64 Jun 25 18:30:33.960918 kernel: kvm [1]: HYP mode not available Jun 25 18:30:33.960926 kernel: Initialise system trusted keyrings Jun 25 18:30:33.960934 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Jun 25 18:30:33.960942 kernel: Key type asymmetric registered Jun 25 18:30:33.960949 kernel: Asymmetric key parser 'x509' registered Jun 25 18:30:33.960957 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Jun 25 18:30:33.960964 kernel: io scheduler mq-deadline registered Jun 25 18:30:33.960972 kernel: io scheduler kyber registered Jun 25 18:30:33.960979 kernel: io scheduler bfq registered Jun 25 18:30:33.960988 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Jun 25 18:30:33.960996 kernel: ACPI: button: Power Button [PWRB] Jun 25 18:30:33.961004 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Jun 25 18:30:33.961093 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Jun 25 18:30:33.961108 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Jun 25 18:30:33.961116 kernel: thunder_xcv, ver 1.0 Jun 25 18:30:33.961123 kernel: thunder_bgx, ver 1.0 Jun 25 18:30:33.961131 kernel: nicpf, ver 1.0 Jun 25 18:30:33.961138 kernel: nicvf, ver 1.0 Jun 25 18:30:33.961234 kernel: rtc-efi rtc-efi.0: registered as rtc0 Jun 25 18:30:33.961305 kernel: rtc-efi rtc-efi.0: setting system clock to 2024-06-25T18:30:33 UTC (1719340233) Jun 25 18:30:33.961315 kernel: hid: raw HID events driver (C) Jiri Kosina Jun 25 18:30:33.961324 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Jun 25 18:30:33.961331 kernel: watchdog: Delayed init of the lockup detector failed: -19 Jun 25 18:30:33.961339 kernel: watchdog: Hard watchdog permanently disabled Jun 25 18:30:33.961347 kernel: NET: Registered PF_INET6 protocol family Jun 25 18:30:33.961354 kernel: Segment Routing with IPv6 Jun 25 18:30:33.961365 kernel: In-situ OAM (IOAM) with IPv6 Jun 25 18:30:33.961372 kernel: NET: Registered PF_PACKET protocol family Jun 25 18:30:33.961394 kernel: Key type dns_resolver registered Jun 25 18:30:33.961402 kernel: registered taskstats version 1 Jun 25 18:30:33.961410 kernel: Loading compiled-in X.509 certificates Jun 25 18:30:33.961418 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.35-flatcar: 751918e575d02f96b0daadd44b8f442a8c39ecd3' Jun 25 18:30:33.961431 kernel: Key type .fscrypt registered Jun 25 18:30:33.961439 kernel: Key type fscrypt-provisioning registered Jun 25 18:30:33.961447 kernel: ima: No TPM chip found, activating TPM-bypass! Jun 25 18:30:33.961457 kernel: ima: Allocated hash algorithm: sha1 Jun 25 18:30:33.961464 kernel: ima: No architecture policies found Jun 25 18:30:33.961472 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Jun 25 18:30:33.961480 kernel: clk: Disabling unused clocks Jun 25 18:30:33.961487 kernel: Freeing unused kernel memory: 39040K Jun 25 18:30:33.961495 kernel: Run /init as init process Jun 25 18:30:33.961502 kernel: with arguments: Jun 25 18:30:33.961510 kernel: /init Jun 25 18:30:33.961517 kernel: with environment: Jun 25 18:30:33.961526 kernel: HOME=/ Jun 25 18:30:33.961533 kernel: TERM=linux Jun 25 18:30:33.961541 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Jun 25 18:30:33.961550 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) Jun 25 18:30:33.961560 systemd[1]: Detected virtualization kvm. Jun 25 18:30:33.961569 systemd[1]: Detected architecture arm64. Jun 25 18:30:33.961577 systemd[1]: Running in initrd. Jun 25 18:30:33.961584 systemd[1]: No hostname configured, using default hostname. Jun 25 18:30:33.961594 systemd[1]: Hostname set to . Jun 25 18:30:33.961602 systemd[1]: Initializing machine ID from VM UUID. Jun 25 18:30:33.961610 systemd[1]: Queued start job for default target initrd.target. Jun 25 18:30:33.961618 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jun 25 18:30:33.961626 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jun 25 18:30:33.961635 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Jun 25 18:30:33.961643 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jun 25 18:30:33.961652 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Jun 25 18:30:33.961662 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Jun 25 18:30:33.961672 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Jun 25 18:30:33.961680 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Jun 25 18:30:33.961688 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jun 25 18:30:33.961697 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jun 25 18:30:33.961705 systemd[1]: Reached target paths.target - Path Units. Jun 25 18:30:33.961715 systemd[1]: Reached target slices.target - Slice Units. Jun 25 18:30:33.961723 systemd[1]: Reached target swap.target - Swaps. Jun 25 18:30:33.961731 systemd[1]: Reached target timers.target - Timer Units. Jun 25 18:30:33.961739 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Jun 25 18:30:33.961747 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jun 25 18:30:33.961755 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Jun 25 18:30:33.961763 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Jun 25 18:30:33.961772 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jun 25 18:30:33.961780 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jun 25 18:30:33.961794 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jun 25 18:30:33.961803 systemd[1]: Reached target sockets.target - Socket Units. Jun 25 18:30:33.961811 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Jun 25 18:30:33.961820 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jun 25 18:30:33.961828 systemd[1]: Finished network-cleanup.service - Network Cleanup. Jun 25 18:30:33.961836 systemd[1]: Starting systemd-fsck-usr.service... Jun 25 18:30:33.961844 systemd[1]: Starting systemd-journald.service - Journal Service... Jun 25 18:30:33.961852 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jun 25 18:30:33.961861 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jun 25 18:30:33.961874 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Jun 25 18:30:33.961885 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jun 25 18:30:33.961893 systemd[1]: Finished systemd-fsck-usr.service. Jun 25 18:30:33.961902 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jun 25 18:30:33.961913 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jun 25 18:30:33.961922 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jun 25 18:30:33.961932 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jun 25 18:30:33.961962 systemd-journald[237]: Collecting audit messages is disabled. Jun 25 18:30:33.961984 systemd-journald[237]: Journal started Jun 25 18:30:33.962003 systemd-journald[237]: Runtime Journal (/run/log/journal/f390032797a641dead9f065d6dad49ae) is 5.9M, max 47.3M, 41.4M free. Jun 25 18:30:33.951776 systemd-modules-load[238]: Inserted module 'overlay' Jun 25 18:30:33.964771 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jun 25 18:30:33.966105 systemd[1]: Started systemd-journald.service - Journal Service. Jun 25 18:30:33.966154 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Jun 25 18:30:33.970090 kernel: Bridge firewalling registered Jun 25 18:30:33.968364 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jun 25 18:30:33.969912 systemd-modules-load[238]: Inserted module 'br_netfilter' Jun 25 18:30:33.972587 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jun 25 18:30:33.983390 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jun 25 18:30:33.985857 systemd[1]: Starting systemd-tmpfiles-setup.service - Create Volatile Files and Directories... Jun 25 18:30:33.987005 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jun 25 18:30:33.989740 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Jun 25 18:30:33.992086 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jun 25 18:30:33.997660 systemd[1]: Finished systemd-tmpfiles-setup.service - Create Volatile Files and Directories. Jun 25 18:30:34.000314 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jun 25 18:30:34.005076 dracut-cmdline[274]: dracut-dracut-053 Jun 25 18:30:34.009168 dracut-cmdline[274]: 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=ttyS0,115200 flatcar.first_boot=detected acpi=force verity.usrhash=e6069a8408a0ca7e7bc40a0bde7fe3ef89df2f98c4bdd2e7e7f9f8f3f8ad207f Jun 25 18:30:34.026151 systemd-resolved[281]: Positive Trust Anchors: Jun 25 18:30:34.026169 systemd-resolved[281]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jun 25 18:30:34.026201 systemd-resolved[281]: 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 corp home internal intranet lan local private test Jun 25 18:30:34.030828 systemd-resolved[281]: Defaulting to hostname 'linux'. Jun 25 18:30:34.033718 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jun 25 18:30:34.034818 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jun 25 18:30:34.081105 kernel: SCSI subsystem initialized Jun 25 18:30:34.087083 kernel: Loading iSCSI transport class v2.0-870. Jun 25 18:30:34.097123 kernel: iscsi: registered transport (tcp) Jun 25 18:30:34.110345 kernel: iscsi: registered transport (qla4xxx) Jun 25 18:30:34.110388 kernel: QLogic iSCSI HBA Driver Jun 25 18:30:34.161488 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Jun 25 18:30:34.175241 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Jun 25 18:30:34.204638 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Jun 25 18:30:34.204685 kernel: device-mapper: uevent: version 1.0.3 Jun 25 18:30:34.204696 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Jun 25 18:30:34.259113 kernel: raid6: neonx8 gen() 15566 MB/s Jun 25 18:30:34.276092 kernel: raid6: neonx4 gen() 15512 MB/s Jun 25 18:30:34.293100 kernel: raid6: neonx2 gen() 12685 MB/s Jun 25 18:30:34.310089 kernel: raid6: neonx1 gen() 10489 MB/s Jun 25 18:30:34.327087 kernel: raid6: int64x8 gen() 6958 MB/s Jun 25 18:30:34.344093 kernel: raid6: int64x4 gen() 7340 MB/s Jun 25 18:30:34.361089 kernel: raid6: int64x2 gen() 6133 MB/s Jun 25 18:30:34.378085 kernel: raid6: int64x1 gen() 5056 MB/s Jun 25 18:30:34.378123 kernel: raid6: using algorithm neonx8 gen() 15566 MB/s Jun 25 18:30:34.395479 kernel: raid6: .... xor() 11942 MB/s, rmw enabled Jun 25 18:30:34.395536 kernel: raid6: using neon recovery algorithm Jun 25 18:30:34.402098 kernel: xor: measuring software checksum speed Jun 25 18:30:34.403082 kernel: 8regs : 19854 MB/sec Jun 25 18:30:34.406724 kernel: 32regs : 19645 MB/sec Jun 25 18:30:34.406763 kernel: arm64_neon : 27143 MB/sec Jun 25 18:30:34.406774 kernel: xor: using function: arm64_neon (27143 MB/sec) Jun 25 18:30:34.460343 kernel: Btrfs loaded, zoned=no, fsverity=no Jun 25 18:30:34.471846 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Jun 25 18:30:34.491307 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jun 25 18:30:34.502855 systemd-udevd[459]: Using default interface naming scheme 'v255'. Jun 25 18:30:34.507678 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jun 25 18:30:34.511133 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Jun 25 18:30:34.528228 dracut-pre-trigger[468]: rd.md=0: removing MD RAID activation Jun 25 18:30:34.563081 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Jun 25 18:30:34.577321 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jun 25 18:30:34.627204 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jun 25 18:30:34.636254 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Jun 25 18:30:34.647414 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Jun 25 18:30:34.648821 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Jun 25 18:30:34.650534 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jun 25 18:30:34.652334 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jun 25 18:30:34.661506 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Jun 25 18:30:34.672849 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Jun 25 18:30:34.682950 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Jun 25 18:30:34.683058 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jun 25 18:30:34.673048 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Jun 25 18:30:34.683842 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jun 25 18:30:34.683949 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jun 25 18:30:34.686348 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jun 25 18:30:34.687393 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jun 25 18:30:34.687561 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jun 25 18:30:34.690492 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Jun 25 18:30:34.698323 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jun 25 18:30:34.711142 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jun 25 18:30:34.718969 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by (udev-worker) (514) Jun 25 18:30:34.719019 kernel: BTRFS: device fsid c80091a6-4bf3-4ad3-8e1c-e6eb918765f9 devid 1 transid 36 /dev/vda3 scanned by (udev-worker) (524) Jun 25 18:30:34.726570 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Jun 25 18:30:34.730775 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Jun 25 18:30:34.735012 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Jun 25 18:30:34.738618 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Jun 25 18:30:34.739693 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Jun 25 18:30:34.753270 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Jun 25 18:30:34.755350 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jun 25 18:30:34.765784 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jun 25 18:30:34.775662 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jun 25 18:30:35.778222 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jun 25 18:30:35.779093 disk-uuid[552]: The operation has completed successfully. Jun 25 18:30:35.816708 systemd[1]: disk-uuid.service: Deactivated successfully. Jun 25 18:30:35.816801 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Jun 25 18:30:35.837227 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Jun 25 18:30:35.841335 sh[575]: Success Jun 25 18:30:35.859093 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Jun 25 18:30:35.907361 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Jun 25 18:30:35.909669 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Jun 25 18:30:35.910564 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Jun 25 18:30:35.925459 kernel: BTRFS info (device dm-0): first mount of filesystem c80091a6-4bf3-4ad3-8e1c-e6eb918765f9 Jun 25 18:30:35.925508 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Jun 25 18:30:35.927105 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Jun 25 18:30:35.927152 kernel: BTRFS info (device dm-0): disabling log replay at mount time Jun 25 18:30:35.927164 kernel: BTRFS info (device dm-0): using free space tree Jun 25 18:30:35.935751 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Jun 25 18:30:35.937126 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Jun 25 18:30:35.937980 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Jun 25 18:30:35.940536 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Jun 25 18:30:35.959403 kernel: BTRFS info (device vda6): first mount of filesystem 0ee4f8d8-9b37-4f6c-84aa-681a87076704 Jun 25 18:30:35.959454 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Jun 25 18:30:35.959466 kernel: BTRFS info (device vda6): using free space tree Jun 25 18:30:35.967094 kernel: BTRFS info (device vda6): auto enabling async discard Jun 25 18:30:35.976142 systemd[1]: mnt-oem.mount: Deactivated successfully. Jun 25 18:30:35.978163 kernel: BTRFS info (device vda6): last unmount of filesystem 0ee4f8d8-9b37-4f6c-84aa-681a87076704 Jun 25 18:30:35.993837 systemd[1]: Finished ignition-setup.service - Ignition (setup). Jun 25 18:30:36.005210 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Jun 25 18:30:36.059980 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jun 25 18:30:36.077262 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jun 25 18:30:36.111790 systemd-networkd[763]: lo: Link UP Jun 25 18:30:36.111801 systemd-networkd[763]: lo: Gained carrier Jun 25 18:30:36.112798 systemd-networkd[763]: Enumeration completed Jun 25 18:30:36.113461 systemd-networkd[763]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jun 25 18:30:36.113465 systemd-networkd[763]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jun 25 18:30:36.115323 ignition[691]: Ignition 2.19.0 Jun 25 18:30:36.114584 systemd-networkd[763]: eth0: Link UP Jun 25 18:30:36.115330 ignition[691]: Stage: fetch-offline Jun 25 18:30:36.114587 systemd-networkd[763]: eth0: Gained carrier Jun 25 18:30:36.115367 ignition[691]: no configs at "/usr/lib/ignition/base.d" Jun 25 18:30:36.114594 systemd-networkd[763]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jun 25 18:30:36.115375 ignition[691]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jun 25 18:30:36.115906 systemd[1]: Started systemd-networkd.service - Network Configuration. Jun 25 18:30:36.115465 ignition[691]: parsed url from cmdline: "" Jun 25 18:30:36.118707 systemd[1]: Reached target network.target - Network. Jun 25 18:30:36.115469 ignition[691]: no config URL provided Jun 25 18:30:36.115473 ignition[691]: reading system config file "/usr/lib/ignition/user.ign" Jun 25 18:30:36.115480 ignition[691]: no config at "/usr/lib/ignition/user.ign" Jun 25 18:30:36.115502 ignition[691]: op(1): [started] loading QEMU firmware config module Jun 25 18:30:36.115506 ignition[691]: op(1): executing: "modprobe" "qemu_fw_cfg" Jun 25 18:30:36.136174 ignition[691]: op(1): [finished] loading QEMU firmware config module Jun 25 18:30:36.136202 ignition[691]: QEMU firmware config was not found. Ignoring... Jun 25 18:30:36.141377 ignition[691]: parsing config with SHA512: 89d48c1fb4a17ad4f8611de448a5bd05b694e38c08375fb38ca0b776d4376b816a922e08571023842b8e0228937962509565855eb92a2299d4b97204f230a65a Jun 25 18:30:36.148153 systemd-networkd[763]: eth0: DHCPv4 address 10.0.0.75/16, gateway 10.0.0.1 acquired from 10.0.0.1 Jun 25 18:30:36.148392 unknown[691]: fetched base config from "system" Jun 25 18:30:36.148645 ignition[691]: fetch-offline: fetch-offline passed Jun 25 18:30:36.148404 unknown[691]: fetched user config from "qemu" Jun 25 18:30:36.148724 ignition[691]: Ignition finished successfully Jun 25 18:30:36.149958 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Jun 25 18:30:36.152322 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Jun 25 18:30:36.156238 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Jun 25 18:30:36.165126 systemd-resolved[281]: Detected conflict on linux IN A 10.0.0.75 Jun 25 18:30:36.165138 systemd-resolved[281]: Hostname conflict, changing published hostname from 'linux' to 'linux5'. Jun 25 18:30:36.168921 ignition[775]: Ignition 2.19.0 Jun 25 18:30:36.168930 ignition[775]: Stage: kargs Jun 25 18:30:36.169176 ignition[775]: no configs at "/usr/lib/ignition/base.d" Jun 25 18:30:36.169185 ignition[775]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jun 25 18:30:36.169787 ignition[775]: kargs: kargs passed Jun 25 18:30:36.169829 ignition[775]: Ignition finished successfully Jun 25 18:30:36.174605 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Jun 25 18:30:36.184230 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Jun 25 18:30:36.195155 ignition[784]: Ignition 2.19.0 Jun 25 18:30:36.195164 ignition[784]: Stage: disks Jun 25 18:30:36.195332 ignition[784]: no configs at "/usr/lib/ignition/base.d" Jun 25 18:30:36.195342 ignition[784]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jun 25 18:30:36.195931 ignition[784]: disks: disks passed Jun 25 18:30:36.199501 systemd[1]: Finished ignition-disks.service - Ignition (disks). Jun 25 18:30:36.195974 ignition[784]: Ignition finished successfully Jun 25 18:30:36.200665 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Jun 25 18:30:36.202103 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Jun 25 18:30:36.203703 systemd[1]: Reached target local-fs.target - Local File Systems. Jun 25 18:30:36.205395 systemd[1]: Reached target sysinit.target - System Initialization. Jun 25 18:30:36.207335 systemd[1]: Reached target basic.target - Basic System. Jun 25 18:30:36.220209 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Jun 25 18:30:36.234171 systemd-fsck[794]: ROOT: clean, 14/553520 files, 52654/553472 blocks Jun 25 18:30:36.238298 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Jun 25 18:30:36.240918 systemd[1]: Mounting sysroot.mount - /sysroot... Jun 25 18:30:36.286941 kernel: EXT4-fs (vda9): mounted filesystem 91548e21-ce72-437e-94b9-d3fed380163a r/w with ordered data mode. Quota mode: none. Jun 25 18:30:36.287758 systemd[1]: Mounted sysroot.mount - /sysroot. Jun 25 18:30:36.289019 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Jun 25 18:30:36.305163 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jun 25 18:30:36.307349 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Jun 25 18:30:36.308386 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Jun 25 18:30:36.308437 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Jun 25 18:30:36.308460 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Jun 25 18:30:36.314020 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Jun 25 18:30:36.316200 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Jun 25 18:30:36.320132 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/vda6 scanned by mount (802) Jun 25 18:30:36.322513 kernel: BTRFS info (device vda6): first mount of filesystem 0ee4f8d8-9b37-4f6c-84aa-681a87076704 Jun 25 18:30:36.322545 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Jun 25 18:30:36.322556 kernel: BTRFS info (device vda6): using free space tree Jun 25 18:30:36.327075 kernel: BTRFS info (device vda6): auto enabling async discard Jun 25 18:30:36.327975 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jun 25 18:30:36.369423 initrd-setup-root[827]: cut: /sysroot/etc/passwd: No such file or directory Jun 25 18:30:36.373549 initrd-setup-root[834]: cut: /sysroot/etc/group: No such file or directory Jun 25 18:30:36.377849 initrd-setup-root[841]: cut: /sysroot/etc/shadow: No such file or directory Jun 25 18:30:36.382189 initrd-setup-root[848]: cut: /sysroot/etc/gshadow: No such file or directory Jun 25 18:30:36.457833 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Jun 25 18:30:36.470183 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Jun 25 18:30:36.472513 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Jun 25 18:30:36.477105 kernel: BTRFS info (device vda6): last unmount of filesystem 0ee4f8d8-9b37-4f6c-84aa-681a87076704 Jun 25 18:30:36.490651 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Jun 25 18:30:36.496603 ignition[915]: INFO : Ignition 2.19.0 Jun 25 18:30:36.496603 ignition[915]: INFO : Stage: mount Jun 25 18:30:36.498778 ignition[915]: INFO : no configs at "/usr/lib/ignition/base.d" Jun 25 18:30:36.498778 ignition[915]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jun 25 18:30:36.498778 ignition[915]: INFO : mount: mount passed Jun 25 18:30:36.498778 ignition[915]: INFO : Ignition finished successfully Jun 25 18:30:36.498799 systemd[1]: Finished ignition-mount.service - Ignition (mount). Jun 25 18:30:36.508190 systemd[1]: Starting ignition-files.service - Ignition (files)... Jun 25 18:30:36.924709 systemd[1]: sysroot-oem.mount: Deactivated successfully. Jun 25 18:30:36.937264 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jun 25 18:30:36.943822 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by mount (929) Jun 25 18:30:36.943873 kernel: BTRFS info (device vda6): first mount of filesystem 0ee4f8d8-9b37-4f6c-84aa-681a87076704 Jun 25 18:30:36.943884 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Jun 25 18:30:36.945141 kernel: BTRFS info (device vda6): using free space tree Jun 25 18:30:36.947089 kernel: BTRFS info (device vda6): auto enabling async discard Jun 25 18:30:36.948298 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jun 25 18:30:36.976658 ignition[946]: INFO : Ignition 2.19.0 Jun 25 18:30:36.976658 ignition[946]: INFO : Stage: files Jun 25 18:30:36.978213 ignition[946]: INFO : no configs at "/usr/lib/ignition/base.d" Jun 25 18:30:36.978213 ignition[946]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jun 25 18:30:36.978213 ignition[946]: DEBUG : files: compiled without relabeling support, skipping Jun 25 18:30:36.981679 ignition[946]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Jun 25 18:30:36.981679 ignition[946]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Jun 25 18:30:36.981679 ignition[946]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Jun 25 18:30:36.981679 ignition[946]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Jun 25 18:30:36.981679 ignition[946]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Jun 25 18:30:36.981189 unknown[946]: wrote ssh authorized keys file for user: core Jun 25 18:30:36.989374 ignition[946]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Jun 25 18:30:36.989374 ignition[946]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Jun 25 18:30:36.989374 ignition[946]: INFO : files: op(4): [started] processing unit "etcd-member.service" Jun 25 18:30:36.989374 ignition[946]: INFO : files: op(4): op(5): [started] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" Jun 25 18:30:36.989374 ignition[946]: INFO : files: op(4): op(5): [finished] writing systemd drop-in "20-clct-etcd-member.conf" at "/sysroot/etc/systemd/system/etcd-member.service.d/20-clct-etcd-member.conf" Jun 25 18:30:36.989374 ignition[946]: INFO : files: op(4): [finished] processing unit "etcd-member.service" Jun 25 18:30:36.989374 ignition[946]: INFO : files: op(6): [started] processing unit "coreos-metadata.service" Jun 25 18:30:36.989374 ignition[946]: INFO : files: op(6): op(7): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Jun 25 18:30:36.989374 ignition[946]: INFO : files: op(6): op(7): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Jun 25 18:30:36.989374 ignition[946]: INFO : files: op(6): [finished] processing unit "coreos-metadata.service" Jun 25 18:30:36.989374 ignition[946]: INFO : files: op(8): [started] setting preset to disabled for "coreos-metadata.service" Jun 25 18:30:37.008325 ignition[946]: INFO : files: op(8): op(9): [started] removing enablement symlink(s) for "coreos-metadata.service" Jun 25 18:30:37.009743 ignition[946]: INFO : files: op(8): op(9): [finished] removing enablement symlink(s) for "coreos-metadata.service" Jun 25 18:30:37.009743 ignition[946]: INFO : files: op(8): [finished] setting preset to disabled for "coreos-metadata.service" Jun 25 18:30:37.009743 ignition[946]: INFO : files: op(a): [started] setting preset to enabled for "etcd-member.service" Jun 25 18:30:37.009743 ignition[946]: INFO : files: op(a): [finished] setting preset to enabled for "etcd-member.service" Jun 25 18:30:37.009743 ignition[946]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Jun 25 18:30:37.009743 ignition[946]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Jun 25 18:30:37.009743 ignition[946]: INFO : files: files passed Jun 25 18:30:37.009743 ignition[946]: INFO : Ignition finished successfully Jun 25 18:30:37.011032 systemd[1]: Finished ignition-files.service - Ignition (files). Jun 25 18:30:37.019230 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Jun 25 18:30:37.020988 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Jun 25 18:30:37.023847 systemd[1]: ignition-quench.service: Deactivated successfully. Jun 25 18:30:37.023950 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Jun 25 18:30:37.028303 initrd-setup-root-after-ignition[975]: grep: /sysroot/oem/oem-release: No such file or directory Jun 25 18:30:37.030393 initrd-setup-root-after-ignition[977]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jun 25 18:30:37.030393 initrd-setup-root-after-ignition[977]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Jun 25 18:30:37.033341 initrd-setup-root-after-ignition[981]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jun 25 18:30:37.032693 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Jun 25 18:30:37.034448 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Jun 25 18:30:37.049302 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Jun 25 18:30:37.069349 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Jun 25 18:30:37.069481 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Jun 25 18:30:37.071304 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Jun 25 18:30:37.073034 systemd[1]: Reached target initrd.target - Initrd Default Target. Jun 25 18:30:37.074628 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Jun 25 18:30:37.075396 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Jun 25 18:30:37.090703 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jun 25 18:30:37.092974 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Jun 25 18:30:37.104013 systemd[1]: Stopped target network.target - Network. Jun 25 18:30:37.104905 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Jun 25 18:30:37.106531 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Jun 25 18:30:37.108366 systemd[1]: Stopped target timers.target - Timer Units. Jun 25 18:30:37.109903 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Jun 25 18:30:37.110024 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jun 25 18:30:37.112340 systemd[1]: Stopped target initrd.target - Initrd Default Target. Jun 25 18:30:37.114094 systemd[1]: Stopped target basic.target - Basic System. Jun 25 18:30:37.115555 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Jun 25 18:30:37.117149 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Jun 25 18:30:37.119026 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Jun 25 18:30:37.120854 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Jun 25 18:30:37.122445 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Jun 25 18:30:37.124125 systemd[1]: Stopped target sysinit.target - System Initialization. Jun 25 18:30:37.125961 systemd[1]: Stopped target local-fs.target - Local File Systems. Jun 25 18:30:37.127488 systemd[1]: Stopped target swap.target - Swaps. Jun 25 18:30:37.128833 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Jun 25 18:30:37.128956 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Jun 25 18:30:37.131001 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Jun 25 18:30:37.132719 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jun 25 18:30:37.134398 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Jun 25 18:30:37.134482 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jun 25 18:30:37.136424 systemd[1]: dracut-initqueue.service: Deactivated successfully. Jun 25 18:30:37.136535 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Jun 25 18:30:37.139082 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Jun 25 18:30:37.139198 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Jun 25 18:30:37.140966 systemd[1]: Stopped target paths.target - Path Units. Jun 25 18:30:37.142379 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Jun 25 18:30:37.146106 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jun 25 18:30:37.147134 systemd[1]: Stopped target slices.target - Slice Units. Jun 25 18:30:37.149013 systemd[1]: Stopped target sockets.target - Socket Units. Jun 25 18:30:37.150501 systemd[1]: iscsid.socket: Deactivated successfully. Jun 25 18:30:37.150588 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Jun 25 18:30:37.151912 systemd[1]: iscsiuio.socket: Deactivated successfully. Jun 25 18:30:37.151994 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jun 25 18:30:37.153313 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Jun 25 18:30:37.153414 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Jun 25 18:30:37.155142 systemd[1]: ignition-files.service: Deactivated successfully. Jun 25 18:30:37.155249 systemd[1]: Stopped ignition-files.service - Ignition (files). Jun 25 18:30:37.167232 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Jun 25 18:30:37.168649 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Jun 25 18:30:37.169676 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Jun 25 18:30:37.171439 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Jun 25 18:30:37.172828 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Jun 25 18:30:37.172961 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Jun 25 18:30:37.174777 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Jun 25 18:30:37.174876 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Jun 25 18:30:37.175116 systemd-networkd[763]: eth0: DHCPv6 lease lost Jun 25 18:30:37.179367 systemd[1]: systemd-networkd.service: Deactivated successfully. Jun 25 18:30:37.185276 ignition[1002]: INFO : Ignition 2.19.0 Jun 25 18:30:37.185276 ignition[1002]: INFO : Stage: umount Jun 25 18:30:37.185276 ignition[1002]: INFO : no configs at "/usr/lib/ignition/base.d" Jun 25 18:30:37.185276 ignition[1002]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jun 25 18:30:37.185276 ignition[1002]: INFO : umount: umount passed Jun 25 18:30:37.185276 ignition[1002]: INFO : Ignition finished successfully Jun 25 18:30:37.179481 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Jun 25 18:30:37.184865 systemd[1]: sysroot-boot.mount: Deactivated successfully. Jun 25 18:30:37.186588 systemd[1]: initrd-cleanup.service: Deactivated successfully. Jun 25 18:30:37.188124 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Jun 25 18:30:37.190233 systemd[1]: systemd-resolved.service: Deactivated successfully. Jun 25 18:30:37.190331 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Jun 25 18:30:37.192440 systemd[1]: ignition-mount.service: Deactivated successfully. Jun 25 18:30:37.192545 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Jun 25 18:30:37.194413 systemd[1]: sysroot-boot.service: Deactivated successfully. Jun 25 18:30:37.194495 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Jun 25 18:30:37.197916 systemd[1]: systemd-networkd.socket: Deactivated successfully. Jun 25 18:30:37.197966 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Jun 25 18:30:37.199255 systemd[1]: ignition-disks.service: Deactivated successfully. Jun 25 18:30:37.199308 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Jun 25 18:30:37.200970 systemd[1]: ignition-kargs.service: Deactivated successfully. Jun 25 18:30:37.201015 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Jun 25 18:30:37.202617 systemd[1]: ignition-setup.service: Deactivated successfully. Jun 25 18:30:37.202658 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Jun 25 18:30:37.204237 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Jun 25 18:30:37.204282 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Jun 25 18:30:37.205892 systemd[1]: initrd-setup-root.service: Deactivated successfully. Jun 25 18:30:37.205937 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Jun 25 18:30:37.217173 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Jun 25 18:30:37.218414 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Jun 25 18:30:37.218484 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jun 25 18:30:37.220410 systemd[1]: systemd-sysctl.service: Deactivated successfully. Jun 25 18:30:37.220467 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Jun 25 18:30:37.222137 systemd[1]: systemd-modules-load.service: Deactivated successfully. Jun 25 18:30:37.222182 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Jun 25 18:30:37.223830 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Jun 25 18:30:37.223872 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create Volatile Files and Directories. Jun 25 18:30:37.226031 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Jun 25 18:30:37.235527 systemd[1]: network-cleanup.service: Deactivated successfully. Jun 25 18:30:37.235635 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Jun 25 18:30:37.251801 systemd[1]: systemd-udevd.service: Deactivated successfully. Jun 25 18:30:37.251956 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Jun 25 18:30:37.254526 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Jun 25 18:30:37.254566 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Jun 25 18:30:37.256325 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Jun 25 18:30:37.256354 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Jun 25 18:30:37.257956 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Jun 25 18:30:37.258002 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Jun 25 18:30:37.260525 systemd[1]: dracut-cmdline.service: Deactivated successfully. Jun 25 18:30:37.260570 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Jun 25 18:30:37.263001 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jun 25 18:30:37.263046 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jun 25 18:30:37.274208 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Jun 25 18:30:37.275149 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Jun 25 18:30:37.275207 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jun 25 18:30:37.277243 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Jun 25 18:30:37.277289 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jun 25 18:30:37.278392 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Jun 25 18:30:37.278447 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Jun 25 18:30:37.280447 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jun 25 18:30:37.280492 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jun 25 18:30:37.282771 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Jun 25 18:30:37.284091 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Jun 25 18:30:37.286367 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Jun 25 18:30:37.288401 systemd[1]: Starting initrd-switch-root.service - Switch Root... Jun 25 18:30:37.297929 systemd[1]: Switching root. Jun 25 18:30:37.328315 systemd-journald[237]: Journal stopped Jun 25 18:30:37.922811 systemd-journald[237]: Received SIGTERM from PID 1 (systemd). Jun 25 18:30:37.922864 kernel: SELinux: policy capability network_peer_controls=1 Jun 25 18:30:37.922882 kernel: SELinux: policy capability open_perms=1 Jun 25 18:30:37.922892 kernel: SELinux: policy capability extended_socket_class=1 Jun 25 18:30:37.922902 kernel: SELinux: policy capability always_check_network=0 Jun 25 18:30:37.922912 kernel: SELinux: policy capability cgroup_seclabel=1 Jun 25 18:30:37.922921 kernel: SELinux: policy capability nnp_nosuid_transition=1 Jun 25 18:30:37.922931 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Jun 25 18:30:37.922941 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Jun 25 18:30:37.922950 kernel: audit: type=1403 audit(1719340237.395:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Jun 25 18:30:37.922962 systemd[1]: Successfully loaded SELinux policy in 33.128ms. Jun 25 18:30:37.922979 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 9.244ms. Jun 25 18:30:37.922993 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) Jun 25 18:30:37.923005 systemd[1]: Detected virtualization kvm. Jun 25 18:30:37.923015 systemd[1]: Detected architecture arm64. Jun 25 18:30:37.923026 systemd[1]: Detected first boot. Jun 25 18:30:37.923037 systemd[1]: Initializing machine ID from VM UUID. Jun 25 18:30:37.923047 zram_generator::config[1045]: No configuration found. Jun 25 18:30:37.923060 systemd[1]: Populated /etc with preset unit settings. Jun 25 18:30:37.923083 systemd[1]: initrd-switch-root.service: Deactivated successfully. Jun 25 18:30:37.923095 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Jun 25 18:30:37.923105 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Jun 25 18:30:37.923117 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Jun 25 18:30:37.923132 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Jun 25 18:30:37.923144 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Jun 25 18:30:37.923154 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Jun 25 18:30:37.923166 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Jun 25 18:30:37.923177 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Jun 25 18:30:37.923188 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Jun 25 18:30:37.923198 systemd[1]: Created slice user.slice - User and Session Slice. Jun 25 18:30:37.923209 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jun 25 18:30:37.923220 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jun 25 18:30:37.923233 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Jun 25 18:30:37.923243 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Jun 25 18:30:37.923254 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Jun 25 18:30:37.923265 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jun 25 18:30:37.923276 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Jun 25 18:30:37.923287 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jun 25 18:30:37.923298 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Jun 25 18:30:37.923309 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Jun 25 18:30:37.923319 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Jun 25 18:30:37.923332 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Jun 25 18:30:37.923342 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jun 25 18:30:37.923354 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jun 25 18:30:37.923366 systemd[1]: Reached target slices.target - Slice Units. Jun 25 18:30:37.923377 systemd[1]: Reached target swap.target - Swaps. Jun 25 18:30:37.923388 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Jun 25 18:30:37.923399 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Jun 25 18:30:37.923409 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jun 25 18:30:37.923428 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jun 25 18:30:37.923439 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jun 25 18:30:37.923450 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Jun 25 18:30:37.923461 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Jun 25 18:30:37.923472 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Jun 25 18:30:37.923483 systemd[1]: Mounting media.mount - External Media Directory... Jun 25 18:30:37.923493 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Jun 25 18:30:37.923504 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Jun 25 18:30:37.923514 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Jun 25 18:30:37.923527 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). Jun 25 18:30:37.923537 systemd[1]: Reached target machines.target - Containers. Jun 25 18:30:37.923549 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Jun 25 18:30:37.923560 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jun 25 18:30:37.923571 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jun 25 18:30:37.923581 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Jun 25 18:30:37.923592 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jun 25 18:30:37.923604 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jun 25 18:30:37.923616 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jun 25 18:30:37.923632 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Jun 25 18:30:37.923648 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jun 25 18:30:37.923662 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Jun 25 18:30:37.923673 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Jun 25 18:30:37.923684 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Jun 25 18:30:37.923694 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Jun 25 18:30:37.923705 systemd[1]: Stopped systemd-fsck-usr.service. Jun 25 18:30:37.923716 kernel: fuse: init (API version 7.39) Jun 25 18:30:37.923727 systemd[1]: Starting systemd-journald.service - Journal Service... Jun 25 18:30:37.923738 kernel: loop: module loaded Jun 25 18:30:37.923749 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jun 25 18:30:37.923760 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jun 25 18:30:37.923771 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Jun 25 18:30:37.923781 kernel: ACPI: bus type drm_connector registered Jun 25 18:30:37.923791 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jun 25 18:30:37.923819 systemd-journald[1115]: Collecting audit messages is disabled. Jun 25 18:30:37.923842 systemd[1]: verity-setup.service: Deactivated successfully. Jun 25 18:30:37.923854 systemd[1]: Stopped verity-setup.service. Jun 25 18:30:37.923865 systemd-journald[1115]: Journal started Jun 25 18:30:37.923887 systemd-journald[1115]: Runtime Journal (/run/log/journal/f390032797a641dead9f065d6dad49ae) is 5.9M, max 47.3M, 41.4M free. Jun 25 18:30:37.738562 systemd[1]: Queued start job for default target multi-user.target. Jun 25 18:30:37.761993 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Jun 25 18:30:37.762359 systemd[1]: systemd-journald.service: Deactivated successfully. Jun 25 18:30:37.927308 systemd[1]: Started systemd-journald.service - Journal Service. Jun 25 18:30:37.927948 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Jun 25 18:30:37.929326 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Jun 25 18:30:37.930588 systemd[1]: Mounted media.mount - External Media Directory. Jun 25 18:30:37.931772 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Jun 25 18:30:37.933169 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Jun 25 18:30:37.934396 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Jun 25 18:30:37.937103 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Jun 25 18:30:37.938471 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jun 25 18:30:37.940015 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jun 25 18:30:37.940206 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Jun 25 18:30:37.941719 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jun 25 18:30:37.941878 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jun 25 18:30:37.943211 systemd[1]: modprobe@drm.service: Deactivated successfully. Jun 25 18:30:37.943347 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jun 25 18:30:37.944643 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jun 25 18:30:37.946157 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jun 25 18:30:37.947599 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jun 25 18:30:37.947727 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Jun 25 18:30:37.949090 systemd[1]: modprobe@loop.service: Deactivated successfully. Jun 25 18:30:37.949229 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jun 25 18:30:37.950685 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jun 25 18:30:37.952147 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jun 25 18:30:37.953567 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Jun 25 18:30:37.966759 systemd[1]: Reached target network-pre.target - Preparation for Network. Jun 25 18:30:37.981212 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Jun 25 18:30:37.983442 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Jun 25 18:30:37.984505 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Jun 25 18:30:37.984547 systemd[1]: Reached target local-fs.target - Local File Systems. Jun 25 18:30:37.986461 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Jun 25 18:30:37.988664 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Jun 25 18:30:37.991038 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Jun 25 18:30:37.992168 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jun 25 18:30:37.993789 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Jun 25 18:30:37.996172 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Jun 25 18:30:37.997272 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jun 25 18:30:38.002917 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Jun 25 18:30:38.004142 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jun 25 18:30:38.007231 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jun 25 18:30:38.008945 systemd-journald[1115]: Time spent on flushing to /var/log/journal/f390032797a641dead9f065d6dad49ae is 27.224ms for 829 entries. Jun 25 18:30:38.008945 systemd-journald[1115]: System Journal (/var/log/journal/f390032797a641dead9f065d6dad49ae) is 8.0M, max 195.6M, 187.6M free. Jun 25 18:30:38.048533 systemd-journald[1115]: Received client request to flush runtime journal. Jun 25 18:30:38.048579 kernel: loop0: detected capacity change from 0 to 113712 Jun 25 18:30:38.048609 kernel: block loop0: the capability attribute has been deprecated. Jun 25 18:30:38.048783 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jun 25 18:30:38.010249 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Jun 25 18:30:38.014337 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jun 25 18:30:38.019199 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jun 25 18:30:38.020652 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Jun 25 18:30:38.022152 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Jun 25 18:30:38.025087 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Jun 25 18:30:38.026676 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Jun 25 18:30:38.032199 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Jun 25 18:30:38.041310 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Jun 25 18:30:38.047743 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Jun 25 18:30:38.052220 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Jun 25 18:30:38.063258 udevadm[1167]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Jun 25 18:30:38.066323 systemd-tmpfiles[1156]: ACLs are not supported, ignoring. Jun 25 18:30:38.066343 systemd-tmpfiles[1156]: ACLs are not supported, ignoring. Jun 25 18:30:38.067092 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jun 25 18:30:38.069820 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Jun 25 18:30:38.072104 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jun 25 18:30:38.073657 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Jun 25 18:30:38.079181 kernel: loop1: detected capacity change from 0 to 59688 Jun 25 18:30:38.084432 systemd[1]: Starting systemd-sysusers.service - Create System Users... Jun 25 18:30:38.109446 systemd[1]: Finished systemd-sysusers.service - Create System Users. Jun 25 18:30:38.110125 kernel: loop2: detected capacity change from 0 to 113712 Jun 25 18:30:38.115188 kernel: loop3: detected capacity change from 0 to 59688 Jun 25 18:30:38.117588 (sd-merge)[1178]: Using extensions 'containerd-flatcar', 'docker-flatcar'. Jun 25 18:30:38.117945 (sd-merge)[1178]: Merged extensions into '/usr'. Jun 25 18:30:38.123664 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jun 25 18:30:38.127119 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Jun 25 18:30:38.130323 systemd[1]: Starting ensure-sysext.service... Jun 25 18:30:38.133410 systemd[1]: Starting systemd-tmpfiles-setup.service - Create Volatile Files and Directories... Jun 25 18:30:38.141801 systemd-tmpfiles[1180]: ACLs are not supported, ignoring. Jun 25 18:30:38.141815 systemd-tmpfiles[1180]: ACLs are not supported, ignoring. Jun 25 18:30:38.150443 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jun 25 18:30:38.151857 systemd-tmpfiles[1184]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Jun 25 18:30:38.152367 systemd-tmpfiles[1184]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Jun 25 18:30:38.152962 systemd-tmpfiles[1184]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Jun 25 18:30:38.153457 systemd-tmpfiles[1184]: ACLs are not supported, ignoring. Jun 25 18:30:38.153566 systemd-tmpfiles[1184]: ACLs are not supported, ignoring. Jun 25 18:30:38.154559 systemd[1]: Reloading requested from client PID 1183 ('systemctl') (unit ensure-sysext.service)... Jun 25 18:30:38.154575 systemd[1]: Reloading... Jun 25 18:30:38.156294 systemd-tmpfiles[1184]: Detected autofs mount point /boot during canonicalization of boot. Jun 25 18:30:38.156402 systemd-tmpfiles[1184]: Skipping /boot Jun 25 18:30:38.164868 systemd-tmpfiles[1184]: Detected autofs mount point /boot during canonicalization of boot. Jun 25 18:30:38.165011 systemd-tmpfiles[1184]: Skipping /boot Jun 25 18:30:38.214097 zram_generator::config[1211]: No configuration found. Jun 25 18:30:38.293676 ldconfig[1150]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jun 25 18:30:38.305054 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. Jun 25 18:30:38.343301 systemd[1]: Reloading finished in 188 ms. Jun 25 18:30:38.377410 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Jun 25 18:30:38.387631 systemd[1]: Finished systemd-tmpfiles-setup.service - Create Volatile Files and Directories. Jun 25 18:30:38.397617 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Jun 25 18:30:38.400156 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Jun 25 18:30:38.403348 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Jun 25 18:30:38.410448 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jun 25 18:30:38.414355 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Jun 25 18:30:38.417661 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jun 25 18:30:38.421539 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jun 25 18:30:38.427341 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jun 25 18:30:38.430045 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jun 25 18:30:38.431173 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jun 25 18:30:38.433120 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jun 25 18:30:38.433304 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jun 25 18:30:38.435274 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jun 25 18:30:38.438072 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jun 25 18:30:38.439942 systemd[1]: modprobe@loop.service: Deactivated successfully. Jun 25 18:30:38.440092 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jun 25 18:30:38.441618 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Jun 25 18:30:38.447091 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jun 25 18:30:38.449347 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jun 25 18:30:38.457356 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jun 25 18:30:38.457468 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jun 25 18:30:38.457519 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). Jun 25 18:30:38.458094 systemd[1]: Finished ensure-sysext.service. Jun 25 18:30:38.459329 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Jun 25 18:30:38.460829 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jun 25 18:30:38.460959 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jun 25 18:30:38.462456 systemd[1]: modprobe@drm.service: Deactivated successfully. Jun 25 18:30:38.462569 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jun 25 18:30:38.469739 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jun 25 18:30:38.478397 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Jun 25 18:30:38.481226 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Jun 25 18:30:38.485763 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Jun 25 18:30:38.525821 systemd[1]: Started systemd-userdbd.service - User Database Manager. Jun 25 18:30:38.529923 augenrules[1287]: No rules Jun 25 18:30:38.538528 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Jun 25 18:30:38.554128 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Jun 25 18:30:38.565248 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jun 25 18:30:38.570297 systemd[1]: Starting systemd-update-done.service - Update is Completed... Jun 25 18:30:38.584169 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Jun 25 18:30:38.585554 systemd[1]: Reached target time-set.target - System Time Set. Jun 25 18:30:38.587349 systemd-resolved[1254]: Positive Trust Anchors: Jun 25 18:30:38.587611 systemd-resolved[1254]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jun 25 18:30:38.587687 systemd-resolved[1254]: 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 corp home internal intranet lan local private test Jun 25 18:30:38.591664 systemd[1]: Finished systemd-update-done.service - Update is Completed. Jun 25 18:30:38.591742 systemd-udevd[1295]: Using default interface naming scheme 'v255'. Jun 25 18:30:38.596022 systemd-resolved[1254]: Defaulting to hostname 'linux'. Jun 25 18:30:38.597610 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jun 25 18:30:38.598883 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jun 25 18:30:38.605159 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jun 25 18:30:38.614299 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jun 25 18:30:38.628283 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Jun 25 18:30:38.647098 kernel: BTRFS info: devid 1 device path /dev/mapper/usr changed to /dev/dm-0 scanned by (udev-worker) (1317) Jun 25 18:30:38.662393 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (1316) Jun 25 18:30:38.673842 systemd-networkd[1306]: lo: Link UP Jun 25 18:30:38.674179 systemd-networkd[1306]: lo: Gained carrier Jun 25 18:30:38.674995 systemd-networkd[1306]: Enumeration completed Jun 25 18:30:38.675166 systemd[1]: Started systemd-networkd.service - Network Configuration. Jun 25 18:30:38.676007 systemd-networkd[1306]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jun 25 18:30:38.676107 systemd-networkd[1306]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jun 25 18:30:38.677106 systemd[1]: Reached target network.target - Network. Jun 25 18:30:38.682458 systemd-networkd[1306]: eth0: Link UP Jun 25 18:30:38.682532 systemd-networkd[1306]: eth0: Gained carrier Jun 25 18:30:38.682589 systemd-networkd[1306]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jun 25 18:30:38.693361 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Jun 25 18:30:38.698425 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Jun 25 18:30:38.700753 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Jun 25 18:30:38.702429 systemd-networkd[1306]: eth0: DHCPv4 address 10.0.0.75/16, gateway 10.0.0.1 acquired from 10.0.0.1 Jun 25 18:30:38.704203 systemd-timesyncd[1273]: Network configuration changed, trying to establish connection. Jun 25 18:30:38.704252 systemd-networkd[1306]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jun 25 18:30:38.705021 systemd-timesyncd[1273]: Contacted time server 10.0.0.1:123 (10.0.0.1). Jun 25 18:30:38.705102 systemd-timesyncd[1273]: Initial clock synchronization to Tue 2024-06-25 18:30:38.550947 UTC. Jun 25 18:30:38.730348 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jun 25 18:30:38.731854 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Jun 25 18:30:38.743633 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Jun 25 18:30:38.753316 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Jun 25 18:30:38.777108 lvm[1336]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Jun 25 18:30:38.779199 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jun 25 18:30:38.817633 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Jun 25 18:30:38.819047 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jun 25 18:30:38.822116 systemd[1]: Reached target sysinit.target - System Initialization. Jun 25 18:30:38.823208 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Jun 25 18:30:38.824453 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Jun 25 18:30:38.825856 systemd[1]: Started logrotate.timer - Daily rotation of log files. Jun 25 18:30:38.827251 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Jun 25 18:30:38.828370 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Jun 25 18:30:38.829495 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Jun 25 18:30:38.829535 systemd[1]: Reached target paths.target - Path Units. Jun 25 18:30:38.830389 systemd[1]: Reached target timers.target - Timer Units. Jun 25 18:30:38.832420 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Jun 25 18:30:38.834823 systemd[1]: Starting docker.socket - Docker Socket for the API... Jun 25 18:30:38.840315 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Jun 25 18:30:38.842997 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Jun 25 18:30:38.844568 systemd[1]: Listening on docker.socket - Docker Socket for the API. Jun 25 18:30:38.845694 systemd[1]: Reached target sockets.target - Socket Units. Jun 25 18:30:38.846610 systemd[1]: Reached target basic.target - Basic System. Jun 25 18:30:38.847569 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Jun 25 18:30:38.847624 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Jun 25 18:30:38.848639 systemd[1]: Starting containerd.service - containerd container runtime... Jun 25 18:30:38.850492 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Jun 25 18:30:38.853187 lvm[1343]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Jun 25 18:30:38.854781 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Jun 25 18:30:38.857398 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Jun 25 18:30:38.859295 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). Jun 25 18:30:38.861249 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Jun 25 18:30:38.865337 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Jun 25 18:30:38.871536 jq[1346]: false Jun 25 18:30:38.873218 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Jun 25 18:30:38.879307 systemd[1]: Starting systemd-logind.service - User Login Management... Jun 25 18:30:38.886408 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Jun 25 18:30:38.886915 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Jun 25 18:30:38.887670 systemd[1]: Starting update-engine.service - Update Engine... Jun 25 18:30:38.890233 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Jun 25 18:30:38.891248 dbus-daemon[1345]: [system] SELinux support is enabled Jun 25 18:30:38.892644 systemd[1]: Started dbus.service - D-Bus System Message Bus. Jun 25 18:30:38.899122 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Jun 25 18:30:38.901448 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Jun 25 18:30:38.901618 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Jun 25 18:30:38.901902 systemd[1]: motdgen.service: Deactivated successfully. Jun 25 18:30:38.902050 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Jun 25 18:30:38.903210 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Jun 25 18:30:38.903371 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Jun 25 18:30:38.909561 extend-filesystems[1347]: Found loop2 Jun 25 18:30:38.909561 extend-filesystems[1347]: Found loop3 Jun 25 18:30:38.909561 extend-filesystems[1347]: Found vda Jun 25 18:30:38.909561 extend-filesystems[1347]: Found vda1 Jun 25 18:30:38.923008 extend-filesystems[1347]: Found vda2 Jun 25 18:30:38.923008 extend-filesystems[1347]: Found vda3 Jun 25 18:30:38.923008 extend-filesystems[1347]: Found usr Jun 25 18:30:38.923008 extend-filesystems[1347]: Found vda4 Jun 25 18:30:38.923008 extend-filesystems[1347]: Found vda6 Jun 25 18:30:38.923008 extend-filesystems[1347]: Found vda7 Jun 25 18:30:38.923008 extend-filesystems[1347]: Found vda9 Jun 25 18:30:38.923008 extend-filesystems[1347]: Checking size of /dev/vda9 Jun 25 18:30:38.912621 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). Jun 25 18:30:38.912658 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Jun 25 18:30:38.915289 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). Jun 25 18:30:38.915309 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Jun 25 18:30:38.935532 jq[1361]: true Jun 25 18:30:38.945260 (ntainerd)[1374]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Jun 25 18:30:38.947663 extend-filesystems[1347]: Old size kept for /dev/vda9 Jun 25 18:30:38.948787 systemd[1]: extend-filesystems.service: Deactivated successfully. Jun 25 18:30:38.948978 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Jun 25 18:30:38.958257 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (1313) Jun 25 18:30:38.968810 jq[1376]: true Jun 25 18:30:38.993010 systemd-logind[1351]: Watching system buttons on /dev/input/event0 (Power Button) Jun 25 18:30:38.996598 systemd-logind[1351]: New seat seat0. Jun 25 18:30:39.000526 update_engine[1360]: I0625 18:30:39.000205 1360 main.cc:92] Flatcar Update Engine starting Jun 25 18:30:39.003169 systemd[1]: Started systemd-logind.service - User Login Management. Jun 25 18:30:39.004834 update_engine[1360]: I0625 18:30:39.004791 1360 update_check_scheduler.cc:74] Next update check in 7m32s Jun 25 18:30:39.005454 systemd[1]: Started update-engine.service - Update Engine. Jun 25 18:30:39.014650 systemd[1]: Started locksmithd.service - Cluster reboot manager. Jun 25 18:30:39.072407 bash[1395]: Updated "/home/core/.ssh/authorized_keys" Jun 25 18:30:39.073996 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Jun 25 18:30:39.076059 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Jun 25 18:30:39.112745 locksmithd[1391]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Jun 25 18:30:39.205267 containerd[1374]: time="2024-06-25T18:30:39.205155250Z" level=info msg="starting containerd" revision=cd7148ac666309abf41fd4a49a8a5895b905e7f3 version=v1.7.18 Jun 25 18:30:39.228883 containerd[1374]: time="2024-06-25T18:30:39.228835072Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Jun 25 18:30:39.228883 containerd[1374]: time="2024-06-25T18:30:39.228882697Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Jun 25 18:30:39.230621 containerd[1374]: time="2024-06-25T18:30:39.230581438Z" 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.35-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Jun 25 18:30:39.230621 containerd[1374]: time="2024-06-25T18:30:39.230616745Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Jun 25 18:30:39.230845 containerd[1374]: time="2024-06-25T18:30:39.230814504Z" 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 Jun 25 18:30:39.230845 containerd[1374]: time="2024-06-25T18:30:39.230838473Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Jun 25 18:30:39.230935 containerd[1374]: time="2024-06-25T18:30:39.230920072Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Jun 25 18:30:39.230985 containerd[1374]: time="2024-06-25T18:30:39.230971463Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Jun 25 18:30:39.231004 containerd[1374]: time="2024-06-25T18:30:39.230986214Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Jun 25 18:30:39.231054 containerd[1374]: time="2024-06-25T18:30:39.231041646Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Jun 25 18:30:39.231277 containerd[1374]: time="2024-06-25T18:30:39.231250311Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Jun 25 18:30:39.231308 containerd[1374]: time="2024-06-25T18:30:39.231276006Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" Jun 25 18:30:39.231308 containerd[1374]: time="2024-06-25T18:30:39.231286520Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Jun 25 18:30:39.231390 containerd[1374]: time="2024-06-25T18:30:39.231374121Z" 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 Jun 25 18:30:39.231417 containerd[1374]: time="2024-06-25T18:30:39.231391382Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Jun 25 18:30:39.231456 containerd[1374]: time="2024-06-25T18:30:39.231442106Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" Jun 25 18:30:39.231475 containerd[1374]: time="2024-06-25T18:30:39.231457759Z" level=info msg="metadata content store policy set" policy=shared Jun 25 18:30:39.234559 containerd[1374]: time="2024-06-25T18:30:39.234533122Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Jun 25 18:30:39.234606 containerd[1374]: time="2024-06-25T18:30:39.234564153Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Jun 25 18:30:39.234606 containerd[1374]: time="2024-06-25T18:30:39.234576903Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Jun 25 18:30:39.234641 containerd[1374]: time="2024-06-25T18:30:39.234608562Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Jun 25 18:30:39.234641 containerd[1374]: time="2024-06-25T18:30:39.234622881Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Jun 25 18:30:39.234641 containerd[1374]: time="2024-06-25T18:30:39.234632767Z" level=info msg="NRI interface is disabled by configuration." Jun 25 18:30:39.234704 containerd[1374]: time="2024-06-25T18:30:39.234645124Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Jun 25 18:30:39.234796 containerd[1374]: time="2024-06-25T18:30:39.234777251Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Jun 25 18:30:39.234829 containerd[1374]: time="2024-06-25T18:30:39.234799573Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Jun 25 18:30:39.234829 containerd[1374]: time="2024-06-25T18:30:39.234821071Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Jun 25 18:30:39.234865 containerd[1374]: time="2024-06-25T18:30:39.234834762Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Jun 25 18:30:39.234865 containerd[1374]: time="2024-06-25T18:30:39.234847512Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Jun 25 18:30:39.234904 containerd[1374]: time="2024-06-25T18:30:39.234863753Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Jun 25 18:30:39.234904 containerd[1374]: time="2024-06-25T18:30:39.234876621Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Jun 25 18:30:39.234904 containerd[1374]: time="2024-06-25T18:30:39.234888233Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Jun 25 18:30:39.234904 containerd[1374]: time="2024-06-25T18:30:39.234901100Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Jun 25 18:30:39.234966 containerd[1374]: time="2024-06-25T18:30:39.234914556Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Jun 25 18:30:39.234966 containerd[1374]: time="2024-06-25T18:30:39.234926443Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Jun 25 18:30:39.234966 containerd[1374]: time="2024-06-25T18:30:39.234937035Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Jun 25 18:30:39.235071 containerd[1374]: time="2024-06-25T18:30:39.235024244Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Jun 25 18:30:39.235330 containerd[1374]: time="2024-06-25T18:30:39.235310976Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Jun 25 18:30:39.235359 containerd[1374]: time="2024-06-25T18:30:39.235345185Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Jun 25 18:30:39.235384 containerd[1374]: time="2024-06-25T18:30:39.235358798Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Jun 25 18:30:39.235384 containerd[1374]: time="2024-06-25T18:30:39.235380296Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Jun 25 18:30:39.235504 containerd[1374]: time="2024-06-25T18:30:39.235491278Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Jun 25 18:30:39.235529 containerd[1374]: time="2024-06-25T18:30:39.235508147Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Jun 25 18:30:39.235529 containerd[1374]: time="2024-06-25T18:30:39.235520857Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Jun 25 18:30:39.235563 containerd[1374]: time="2024-06-25T18:30:39.235532665Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Jun 25 18:30:39.235563 containerd[1374]: time="2024-06-25T18:30:39.235546317Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Jun 25 18:30:39.235596 containerd[1374]: time="2024-06-25T18:30:39.235573033Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Jun 25 18:30:39.235596 containerd[1374]: time="2024-06-25T18:30:39.235586214Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Jun 25 18:30:39.235632 containerd[1374]: time="2024-06-25T18:30:39.235598219Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Jun 25 18:30:39.235632 containerd[1374]: time="2024-06-25T18:30:39.235611361Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Jun 25 18:30:39.235753 containerd[1374]: time="2024-06-25T18:30:39.235736583Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Jun 25 18:30:39.235773 containerd[1374]: time="2024-06-25T18:30:39.235759258Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Jun 25 18:30:39.235791 containerd[1374]: time="2024-06-25T18:30:39.235775225Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Jun 25 18:30:39.235815 containerd[1374]: time="2024-06-25T18:30:39.235789073Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Jun 25 18:30:39.235815 containerd[1374]: time="2024-06-25T18:30:39.235810846Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Jun 25 18:30:39.235854 containerd[1374]: time="2024-06-25T18:30:39.235824263Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Jun 25 18:30:39.235854 containerd[1374]: time="2024-06-25T18:30:39.235836149Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Jun 25 18:30:39.235854 containerd[1374]: time="2024-06-25T18:30:39.235847722Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Jun 25 18:30:39.236195 containerd[1374]: time="2024-06-25T18:30:39.236144301Z" 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}" Jun 25 18:30:39.236407 containerd[1374]: time="2024-06-25T18:30:39.236202794Z" level=info msg="Connect containerd service" Jun 25 18:30:39.236407 containerd[1374]: time="2024-06-25T18:30:39.236228882Z" level=info msg="using legacy CRI server" Jun 25 18:30:39.236407 containerd[1374]: time="2024-06-25T18:30:39.236235511Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Jun 25 18:30:39.236407 containerd[1374]: time="2024-06-25T18:30:39.236366579Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Jun 25 18:30:39.237101 containerd[1374]: time="2024-06-25T18:30:39.237039885Z" 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" Jun 25 18:30:39.237130 containerd[1374]: time="2024-06-25T18:30:39.237115481Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Jun 25 18:30:39.237149 containerd[1374]: time="2024-06-25T18:30:39.237132978Z" 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 Jun 25 18:30:39.237149 containerd[1374]: time="2024-06-25T18:30:39.237142550Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Jun 25 18:30:39.237194 containerd[1374]: time="2024-06-25T18:30:39.237153456Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Jun 25 18:30:39.237292 containerd[1374]: time="2024-06-25T18:30:39.237188331Z" level=info msg="Start subscribing containerd event" Jun 25 18:30:39.237315 containerd[1374]: time="2024-06-25T18:30:39.237298921Z" level=info msg="Start recovering state" Jun 25 18:30:39.237377 containerd[1374]: time="2024-06-25T18:30:39.237358943Z" level=info msg="Start event monitor" Jun 25 18:30:39.237377 containerd[1374]: time="2024-06-25T18:30:39.237374047Z" level=info msg="Start snapshots syncer" Jun 25 18:30:39.237415 containerd[1374]: time="2024-06-25T18:30:39.237383187Z" level=info msg="Start cni network conf syncer for default" Jun 25 18:30:39.237415 containerd[1374]: time="2024-06-25T18:30:39.237392799Z" level=info msg="Start streaming server" Jun 25 18:30:39.237821 containerd[1374]: time="2024-06-25T18:30:39.237790160Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jun 25 18:30:39.237859 containerd[1374]: time="2024-06-25T18:30:39.237843944Z" level=info msg=serving... address=/run/containerd/containerd.sock Jun 25 18:30:39.237974 systemd[1]: Started containerd.service - containerd container runtime. Jun 25 18:30:39.240747 containerd[1374]: time="2024-06-25T18:30:39.239297497Z" level=info msg="containerd successfully booted in 0.035195s" Jun 25 18:30:39.470746 sshd_keygen[1362]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Jun 25 18:30:39.491110 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Jun 25 18:30:39.501281 systemd[1]: Starting issuegen.service - Generate /run/issue... Jun 25 18:30:39.506331 systemd[1]: issuegen.service: Deactivated successfully. Jun 25 18:30:39.507144 systemd[1]: Finished issuegen.service - Generate /run/issue. Jun 25 18:30:39.509333 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Jun 25 18:30:39.520196 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Jun 25 18:30:39.522749 systemd[1]: Started getty@tty1.service - Getty on tty1. Jun 25 18:30:39.524596 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Jun 25 18:30:39.525584 systemd[1]: Reached target getty.target - Login Prompts. Jun 25 18:30:40.334190 systemd-networkd[1306]: eth0: Gained IPv6LL Jun 25 18:30:40.335929 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Jun 25 18:30:40.338278 systemd[1]: Reached target network-online.target - Network is Online. Jun 25 18:30:40.353326 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Jun 25 18:30:40.355381 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Jun 25 18:30:40.369988 systemd[1]: coreos-metadata.service: Deactivated successfully. Jun 25 18:30:40.370268 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Jun 25 18:30:40.373095 systemd[1]: Starting etcd-member.service - etcd (System Application Container)... Jun 25 18:30:40.374170 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Jun 25 18:30:40.376896 (-wrapper)[1437]: etcd-member.service: Referenced but unset environment variable evaluates to an empty string: ETCD_OPTS Jun 25 18:30:40.377452 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Jun 25 18:30:40.521325 systemd[1]: Starting docker.service - Docker Application Container Engine... Jun 25 18:30:40.521399 (dockerd)[1452]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU Jun 25 18:30:40.772663 dockerd[1452]: time="2024-06-25T18:30:40.772243948Z" level=info msg="Starting up" Jun 25 18:30:40.802131 dockerd[1452]: time="2024-06-25T18:30:40.802085110Z" level=info msg="Loading containers: start." Jun 25 18:30:40.878097 kernel: Initializing XFRM netlink socket Jun 25 18:30:40.947307 systemd-networkd[1306]: docker0: Link UP Jun 25 18:30:40.955601 dockerd[1452]: time="2024-06-25T18:30:40.955556170Z" level=info msg="Loading containers: done." Jun 25 18:30:41.008213 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck3048952987-merged.mount: Deactivated successfully. Jun 25 18:30:41.008819 dockerd[1452]: time="2024-06-25T18:30:41.008409878Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 Jun 25 18:30:41.008819 dockerd[1452]: time="2024-06-25T18:30:41.008608231Z" level=info msg="Docker daemon" commit=fca702de7f71362c8d103073c7e4a1d0a467fadd graphdriver=overlay2 version=24.0.9 Jun 25 18:30:41.008819 dockerd[1452]: time="2024-06-25T18:30:41.008711172Z" level=info msg="Daemon has completed initialization" Jun 25 18:30:41.035082 dockerd[1452]: time="2024-06-25T18:30:41.034947152Z" level=info msg="API listen on /run/docker.sock" Jun 25 18:30:41.035114 systemd[1]: Started docker.service - Docker Application Container Engine. Jun 25 18:30:41.036985 etcd-wrapper[1445]: Error response from daemon: No such container: etcd-member Jun 25 18:30:41.048231 etcd-wrapper[1567]: Error response from daemon: No such container: etcd-member Jun 25 18:30:41.073267 etcd-wrapper[1584]: Unable to find image 'quay.io/coreos/etcd:v3.5.0-arm64' locally Jun 25 18:30:41.820410 etcd-wrapper[1584]: v3.5.0-arm64: Pulling from coreos/etcd Jun 25 18:30:41.820410 etcd-wrapper[1584]: 6022c6b01d76: Pulling fs layer Jun 25 18:30:41.820410 etcd-wrapper[1584]: 1d97b1ec77fc: Pulling fs layer Jun 25 18:30:41.820410 etcd-wrapper[1584]: 99ef4b690837: Pulling fs layer Jun 25 18:30:41.820410 etcd-wrapper[1584]: 435b7bf0967c: Pulling fs layer Jun 25 18:30:41.820410 etcd-wrapper[1584]: 17e4a5f7c1b5: Pulling fs layer Jun 25 18:30:41.820410 etcd-wrapper[1584]: 5b0292999756: Pulling fs layer Jun 25 18:30:41.820410 etcd-wrapper[1584]: 435b7bf0967c: Waiting Jun 25 18:30:41.820410 etcd-wrapper[1584]: 17e4a5f7c1b5: Waiting Jun 25 18:30:41.820410 etcd-wrapper[1584]: 5b0292999756: Waiting Jun 25 18:30:42.070085 etcd-wrapper[1584]: 1d97b1ec77fc: Verifying Checksum Jun 25 18:30:42.070318 etcd-wrapper[1584]: 1d97b1ec77fc: Download complete Jun 25 18:30:42.120606 etcd-wrapper[1584]: 99ef4b690837: Verifying Checksum Jun 25 18:30:42.120606 etcd-wrapper[1584]: 99ef4b690837: Download complete Jun 25 18:30:42.191039 etcd-wrapper[1584]: 6022c6b01d76: Verifying Checksum Jun 25 18:30:42.191512 etcd-wrapper[1584]: 6022c6b01d76: Download complete Jun 25 18:30:42.284165 etcd-wrapper[1584]: 17e4a5f7c1b5: Verifying Checksum Jun 25 18:30:42.284165 etcd-wrapper[1584]: 17e4a5f7c1b5: Download complete Jun 25 18:30:42.339044 etcd-wrapper[1584]: 435b7bf0967c: Verifying Checksum Jun 25 18:30:42.339220 etcd-wrapper[1584]: 435b7bf0967c: Download complete Jun 25 18:30:42.361716 etcd-wrapper[1584]: 5b0292999756: Verifying Checksum Jun 25 18:30:42.361716 etcd-wrapper[1584]: 5b0292999756: Download complete Jun 25 18:30:42.764682 etcd-wrapper[1584]: 6022c6b01d76: Pull complete Jun 25 18:30:42.859164 systemd[1]: var-lib-docker-overlay2-1284f0f277e1eb2f0cda7563d9103a0d95f1340f9f02a1a938f2c3d97f1583ce-merged.mount: Deactivated successfully. Jun 25 18:30:43.004469 etcd-wrapper[1584]: 1d97b1ec77fc: Pull complete Jun 25 18:30:43.086657 systemd[1]: var-lib-docker-overlay2-1f98574c74a867c2cf6c93a9d416500b825ffa217b6f3eba13d52621b7e62b0c-merged.mount: Deactivated successfully. Jun 25 18:30:43.142196 etcd-wrapper[1584]: 99ef4b690837: Pull complete Jun 25 18:30:43.247040 etcd-wrapper[1584]: 435b7bf0967c: Pull complete Jun 25 18:30:43.260569 etcd-wrapper[1584]: 17e4a5f7c1b5: Pull complete Jun 25 18:30:43.283331 etcd-wrapper[1584]: 5b0292999756: Pull complete Jun 25 18:30:43.289184 etcd-wrapper[1584]: Digest: sha256:96255d1ad67e7afcea46b4dc1b7e74024b9f6971193cb7316b093b3b5671efb0 Jun 25 18:30:43.291105 etcd-wrapper[1584]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.0-arm64 Jun 25 18:30:43.493515 containerd[1374]: time="2024-06-25T18:30:43.493298106Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jun 25 18:30:43.494036 containerd[1374]: time="2024-06-25T18:30:43.493392548Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jun 25 18:30:43.494507 containerd[1374]: time="2024-06-25T18:30:43.494382728Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jun 25 18:30:43.494507 containerd[1374]: time="2024-06-25T18:30:43.494405943Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jun 25 18:30:43.515219 systemd[1]: Started docker-cb2ba7918e483e3a00e3349531f5b73606cf09a7ab4a36d4bf6276594f6ce3cc.scope - libcontainer container cb2ba7918e483e3a00e3349531f5b73606cf09a7ab4a36d4bf6276594f6ce3cc. Jun 25 18:30:43.527474 systemd-resolved[1254]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Jun 25 18:30:43.588252 etcd-wrapper[1584]: {"level":"info","ts":1719340243.5879102,"caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} Jun 25 18:30:43.588252 etcd-wrapper[1584]: {"level":"info","ts":1719340243.588039,"caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"f390032797a641dead9f065d6dad49ae"} Jun 25 18:30:43.588252 etcd-wrapper[1584]: {"level":"warn","ts":1719340243.5880985,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} Jun 25 18:30:43.588252 etcd-wrapper[1584]: {"level":"warn","ts":1719340243.588121,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.0-arm64"} Jun 25 18:30:43.588252 etcd-wrapper[1584]: {"level":"warn","ts":1719340243.588139,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} Jun 25 18:30:43.588252 etcd-wrapper[1584]: {"level":"warn","ts":1719340243.5881639,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} Jun 25 18:30:43.588252 etcd-wrapper[1584]: {"level":"warn","ts":1719340243.5881824,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_UNSUPPORTED_ARCH=arm64"} Jun 25 18:30:43.588624 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.588Z","caller":"etcdmain/etcd.go:72","msg":"Running: ","args":["/usr/local/bin/etcd","--listen-peer-urls=http://10.0.0.75:2380","--listen-client-urls=http://0.0.0.0:2379","--initial-advertise-peer-urls=http://10.0.0.75:2380","--advertise-client-urls=http://10.0.0.75:2379","--discovery=http://10.0.0.1:46365/v2/keys/discovery/5231404102419581391"]} Jun 25 18:30:43.588681 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.588Z","caller":"embed/etcd.go:131","msg":"configuring peer listeners","listen-peer-urls":["http://10.0.0.75:2380"]} Jun 25 18:30:43.588819 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.588Z","caller":"embed/etcd.go:139","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} Jun 25 18:30:43.588900 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.588Z","caller":"embed/etcd.go:307","msg":"starting an etcd server","etcd-version":"3.5.0","git-sha":"946a5a6f2","go-version":"go1.16.3","go-os":"linux","go-arch":"arm64","max-cpu-set":4,"max-cpu-available":4,"member-initialized":false,"name":"f390032797a641dead9f065d6dad49ae","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":100000,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["http://10.0.0.75:2380"],"listen-peer-urls":["http://10.0.0.75:2380"],"advertise-client-urls":["http://10.0.0.75:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"f390032797a641dead9f065d6dad49ae=http://10.0.0.75:2380","initial-cluster-state":"new","initial-cluster-token":"http://10.0.0.1:46365/v2/keys/discovery/5231404102419581391","quota-size-bytes":2147483648,"pre-vote":true,"initial-corrupt-check":false,"corrupt-check-time-interval":"0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"http://10.0.0.1:46365/v2/keys/discovery/5231404102419581391","discovery-proxy":"","downgrade-check-interval":"5s"} Jun 25 18:30:43.590981 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.590Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"1.634429ms"} Jun 25 18:30:43.594361 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.594Z","caller":"v2discovery/discovery.go:342","msg":"found peer from discovery server","discovery-url":"http://10.0.0.1:46365","peer":"ff4a0dd2ce514f2a"} Jun 25 18:30:43.594361 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.594Z","caller":"v2discovery/discovery.go:336","msg":"found self from discovery server","discovery-url":"http://10.0.0.1:46365","self":"96418aefd2e734d5"} Jun 25 18:30:43.594361 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.594Z","caller":"v2discovery/discovery.go:352","msg":"found peers from discovery server; waiting for more","discovery-url":"http://10.0.0.1:46365","found-peers":2,"needed-peers":1} Jun 25 18:30:43.665677 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.665Z","caller":"v2discovery/discovery.go:371","msg":"found peer from discovery server","discovery-url":"http://10.0.0.1:46365","peer":"58cd8f08cec2d5ee"} Jun 25 18:30:43.665677 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.665Z","caller":"v2discovery/discovery.go:378","msg":"found all needed peers from discovery server","discovery-url":"http://10.0.0.1:46365","found-peers":3} Jun 25 18:30:43.669559 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.669Z","caller":"etcdserver/raft.go:448","msg":"starting local member","local-member-id":"96418aefd2e734d5","cluster-id":"cacced8c031a6437"} Jun 25 18:30:43.669767 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"96418aefd2e734d5 switched to configuration voters=()"} Jun 25 18:30:43.669767 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"96418aefd2e734d5 became follower at term 0"} Jun 25 18:30:43.669910 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 96418aefd2e734d5 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} Jun 25 18:30:43.669910 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"96418aefd2e734d5 became follower at term 1"} Jun 25 18:30:43.669910 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"96418aefd2e734d5 switched to configuration voters=(6398927913554728430)"} Jun 25 18:30:43.669910 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"96418aefd2e734d5 switched to configuration voters=(6398927913554728430 10827087741815567573)"} Jun 25 18:30:43.669910 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.669Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"96418aefd2e734d5 switched to configuration voters=(6398927913554728430 10827087741815567573 18395530827003940650)"} Jun 25 18:30:43.671612 etcd-wrapper[1584]: {"level":"warn","ts":"2024-06-25T18:30:43.671Z","caller":"auth/store.go:1220","msg":"simple token is not cryptographically signed"} Jun 25 18:30:43.672845 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.672Z","caller":"mvcc/kvstore.go:415","msg":"kvstore restored","current-rev":1} Jun 25 18:30:43.673592 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.673Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} Jun 25 18:30:43.674308 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.674Z","caller":"rafthttp/peer.go:133","msg":"starting remote peer","remote-peer-id":"58cd8f08cec2d5ee"} Jun 25 18:30:43.674416 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.674Z","caller":"rafthttp/pipeline.go:72","msg":"started HTTP pipelining with remote peer","local-member-id":"96418aefd2e734d5","remote-peer-id":"58cd8f08cec2d5ee"} Jun 25 18:30:43.674626 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.674Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"96418aefd2e734d5","remote-peer-id":"58cd8f08cec2d5ee"} Jun 25 18:30:43.674626 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.674Z","caller":"rafthttp/peer.go:137","msg":"started remote peer","remote-peer-id":"58cd8f08cec2d5ee"} Jun 25 18:30:43.674626 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.674Z","caller":"rafthttp/transport.go:317","msg":"added remote peer","local-member-id":"96418aefd2e734d5","remote-peer-id":"58cd8f08cec2d5ee","remote-peer-urls":["http://10.0.0.76:2380"]} Jun 25 18:30:43.674626 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.674Z","caller":"rafthttp/peer.go:133","msg":"starting remote peer","remote-peer-id":"ff4a0dd2ce514f2a"} Jun 25 18:30:43.674780 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.674Z","caller":"rafthttp/pipeline.go:72","msg":"started HTTP pipelining with remote peer","local-member-id":"96418aefd2e734d5","remote-peer-id":"ff4a0dd2ce514f2a"} Jun 25 18:30:43.675864 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.674Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"96418aefd2e734d5","remote-peer-id":"58cd8f08cec2d5ee"} Jun 25 18:30:43.675864 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.674Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"96418aefd2e734d5","remote-peer-id":"58cd8f08cec2d5ee"} Jun 25 18:30:43.675864 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.675Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream Message","local-member-id":"96418aefd2e734d5","remote-peer-id":"58cd8f08cec2d5ee"} Jun 25 18:30:43.676407 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.675Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"96418aefd2e734d5","remote-peer-id":"ff4a0dd2ce514f2a"} Jun 25 18:30:43.676858 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.676Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"96418aefd2e734d5","remote-peer-id":"ff4a0dd2ce514f2a"} Jun 25 18:30:43.677778 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.677Z","caller":"rafthttp/peer.go:137","msg":"started remote peer","remote-peer-id":"ff4a0dd2ce514f2a"} Jun 25 18:30:43.677949 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.677Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"96418aefd2e734d5","remote-peer-id":"ff4a0dd2ce514f2a"} Jun 25 18:30:43.678163 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.677Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream Message","local-member-id":"96418aefd2e734d5","remote-peer-id":"ff4a0dd2ce514f2a"} Jun 25 18:30:43.678163 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.677Z","caller":"rafthttp/transport.go:317","msg":"added remote peer","local-member-id":"96418aefd2e734d5","remote-peer-id":"ff4a0dd2ce514f2a","remote-peer-urls":["http://10.0.0.74:2380"]} Jun 25 18:30:43.678163 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.678Z","caller":"etcdserver/server.go:843","msg":"starting etcd server","local-member-id":"96418aefd2e734d5","local-server-version":"3.5.0","cluster-version":"to_be_decided"} Jun 25 18:30:43.678745 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.678Z","caller":"etcdserver/server.go:744","msg":"starting initial election tick advance","election-ticks":10} Jun 25 18:30:43.679226 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.678Z","caller":"rafthttp/peer_status.go:53","msg":"peer became active","peer-id":"ff4a0dd2ce514f2a"} Jun 25 18:30:43.679226 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.678Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"96418aefd2e734d5","remote-peer-id":"ff4a0dd2ce514f2a"} Jun 25 18:30:43.679226 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.679Z","caller":"rafthttp/peer_status.go:53","msg":"peer became active","peer-id":"58cd8f08cec2d5ee"} Jun 25 18:30:43.679226 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.679Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"96418aefd2e734d5","remote-peer-id":"58cd8f08cec2d5ee"} Jun 25 18:30:43.679444 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.679Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"96418aefd2e734d5","remote-peer-id":"ff4a0dd2ce514f2a"} Jun 25 18:30:43.679577 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.679Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"96418aefd2e734d5 switched to configuration voters=(6398927913554728430 10827087741815567573 18395530827003940650)"} Jun 25 18:30:43.679705 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.679Z","caller":"embed/etcd.go:276","msg":"now serving peer/client/metrics","local-member-id":"96418aefd2e734d5","initial-advertise-peer-urls":["http://10.0.0.75:2380"],"listen-peer-urls":["http://10.0.0.75:2380"],"advertise-client-urls":["http://10.0.0.75:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} Jun 25 18:30:43.679705 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.679Z","caller":"embed/etcd.go:580","msg":"serving peer traffic","address":"10.0.0.75:2380"} Jun 25 18:30:43.679705 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.679Z","caller":"embed/etcd.go:552","msg":"cmux::serve","address":"10.0.0.75:2380"} Jun 25 18:30:43.679867 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.679Z","caller":"membership/cluster.go:393","msg":"added member","cluster-id":"cacced8c031a6437","local-member-id":"96418aefd2e734d5","added-peer-id":"58cd8f08cec2d5ee","added-peer-peer-urls":["http://10.0.0.76:2380"]} Jun 25 18:30:43.680035 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.679Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"96418aefd2e734d5","remote-peer-id":"58cd8f08cec2d5ee"} Jun 25 18:30:43.680162 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.679Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"96418aefd2e734d5","to":"58cd8f08cec2d5ee","stream-type":"stream MsgApp v2"} Jun 25 18:30:43.680162 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.680Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","local-member-id":"96418aefd2e734d5","remote-peer-id":"58cd8f08cec2d5ee"} Jun 25 18:30:43.680724 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.680Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"96418aefd2e734d5","to":"ff4a0dd2ce514f2a","stream-type":"stream MsgApp v2"} Jun 25 18:30:43.680724 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.680Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","local-member-id":"96418aefd2e734d5","remote-peer-id":"ff4a0dd2ce514f2a"} Jun 25 18:30:43.680856 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.680Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"96418aefd2e734d5 switched to configuration voters=(6398927913554728430 10827087741815567573 18395530827003940650)"} Jun 25 18:30:43.682290 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.680Z","caller":"membership/cluster.go:393","msg":"added member","cluster-id":"cacced8c031a6437","local-member-id":"96418aefd2e734d5","added-peer-id":"96418aefd2e734d5","added-peer-peer-urls":["http://10.0.0.75:2380"]} Jun 25 18:30:43.682290 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.681Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"96418aefd2e734d5 switched to configuration voters=(6398927913554728430 10827087741815567573 18395530827003940650)"} Jun 25 18:30:43.682290 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.681Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"96418aefd2e734d5","to":"ff4a0dd2ce514f2a","stream-type":"stream Message"} Jun 25 18:30:43.682290 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.681Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream Message","local-member-id":"96418aefd2e734d5","remote-peer-id":"ff4a0dd2ce514f2a"} Jun 25 18:30:43.682290 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.680Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"96418aefd2e734d5","to":"58cd8f08cec2d5ee","stream-type":"stream Message"} Jun 25 18:30:43.682290 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.681Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream Message","local-member-id":"96418aefd2e734d5","remote-peer-id":"58cd8f08cec2d5ee"} Jun 25 18:30:43.682290 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.681Z","caller":"membership/cluster.go:393","msg":"added member","cluster-id":"cacced8c031a6437","local-member-id":"96418aefd2e734d5","added-peer-id":"ff4a0dd2ce514f2a","added-peer-peer-urls":["http://10.0.0.74:2380"]} Jun 25 18:30:43.729692 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.729Z","caller":"etcdserver/server.go:767","msg":"initialized peer connections; fast-forwarding election ticks","local-member-id":"96418aefd2e734d5","forward-ticks":8,"forward-duration":"800ms","election-ticks":10,"election-timeout":"1s","active-remote-members":2} Jun 25 18:30:43.778625 systemd[1]: var-lib-docker-overlay2-b4207afc0d0262f55ffb5333f5fbfe52ea1da7b45cabe6bbc268763b1213aa37-merged.mount: Deactivated successfully. Jun 25 18:30:43.870245 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.870Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"96418aefd2e734d5 is starting a new election at term 1"} Jun 25 18:30:43.870245 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.870Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"96418aefd2e734d5 became pre-candidate at term 1"} Jun 25 18:30:43.870496 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.870Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"96418aefd2e734d5 received MsgPreVoteResp from 96418aefd2e734d5 at term 1"} Jun 25 18:30:43.870496 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.870Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"96418aefd2e734d5 [logterm: 1, index: 3] sent MsgPreVote request to 58cd8f08cec2d5ee at term 1"} Jun 25 18:30:43.870496 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.870Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"96418aefd2e734d5 [logterm: 1, index: 3] sent MsgPreVote request to ff4a0dd2ce514f2a at term 1"} Jun 25 18:30:43.870970 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.870Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"96418aefd2e734d5 received MsgPreVoteResp from 58cd8f08cec2d5ee at term 1"} Jun 25 18:30:43.870970 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.870Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"96418aefd2e734d5 has received 2 MsgPreVoteResp votes and 0 vote rejections"} Jun 25 18:30:43.870970 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.870Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"96418aefd2e734d5 became candidate at term 2"} Jun 25 18:30:43.870970 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.870Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"96418aefd2e734d5 received MsgVoteResp from 96418aefd2e734d5 at term 2"} Jun 25 18:30:43.870970 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.870Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"96418aefd2e734d5 [logterm: 1, index: 3] sent MsgVote request to 58cd8f08cec2d5ee at term 2"} Jun 25 18:30:43.870970 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.870Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"96418aefd2e734d5 [logterm: 1, index: 3] sent MsgVote request to ff4a0dd2ce514f2a at term 2"} Jun 25 18:30:43.872205 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"96418aefd2e734d5 received MsgVoteResp from ff4a0dd2ce514f2a at term 2"} Jun 25 18:30:43.872205 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"96418aefd2e734d5 has received 2 MsgVoteResp votes and 0 vote rejections"} Jun 25 18:30:43.872205 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"96418aefd2e734d5 became leader at term 2"} Jun 25 18:30:43.872205 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 96418aefd2e734d5 elected leader 96418aefd2e734d5 at term 2"} Jun 25 18:30:43.874367 systemd[1]: Started etcd-member.service - etcd (System Application Container). Jun 25 18:30:43.875131 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.873Z","caller":"etcdserver/server.go:2027","msg":"published local member to cluster through raft","local-member-id":"96418aefd2e734d5","local-member-attributes":"{Name:f390032797a641dead9f065d6dad49ae ClientURLs:[http://10.0.0.75:2379]}","request-path":"/0/members/96418aefd2e734d5/attributes","cluster-id":"cacced8c031a6437","publish-timeout":"7s"} Jun 25 18:30:43.875131 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.873Z","caller":"embed/serve.go:98","msg":"ready to serve client requests"} Jun 25 18:30:43.875131 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.873Z","caller":"etcdmain/main.go:47","msg":"notifying init daemon"} Jun 25 18:30:43.875131 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.873Z","caller":"etcdmain/main.go:53","msg":"successfully notified init daemon"} Jun 25 18:30:43.875131 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.874Z","caller":"embed/serve.go:140","msg":"serving client traffic insecurely; this is strongly discouraged!","address":"[::]:2379"} Jun 25 18:30:43.875865 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.875Z","caller":"etcdserver/server.go:2476","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} Jun 25 18:30:43.876122 systemd[1]: Reached target multi-user.target - Multi-User System. Jun 25 18:30:43.877373 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.877Z","caller":"membership/cluster.go:531","msg":"set initial cluster version","cluster-id":"cacced8c031a6437","local-member-id":"96418aefd2e734d5","cluster-version":"3.5"} Jun 25 18:30:43.877693 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.877Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} Jun 25 18:30:43.877693 etcd-wrapper[1584]: {"level":"info","ts":"2024-06-25T18:30:43.877Z","caller":"etcdserver/server.go:2500","msg":"cluster version is updated","cluster-version":"3.5"} Jun 25 18:30:43.878122 systemd[1]: Startup finished in 575ms (kernel) + 3.650s (initrd) + 6.517s (userspace) = 10.743s. Jun 25 18:30:46.189707 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Jun 25 18:30:46.190770 systemd[1]: Started sshd@0-10.0.0.75:22-10.0.0.1:52538.service - OpenSSH per-connection server daemon (10.0.0.1:52538). Jun 25 18:30:46.239935 sshd[1678]: Accepted publickey for core from 10.0.0.1 port 52538 ssh2: RSA SHA256:PTHQXr0iRYYg3MbKKJZ6aC6iEkqmHU1AdffEoJcWF3A Jun 25 18:30:46.241513 sshd[1678]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jun 25 18:30:46.253024 systemd-logind[1351]: New session 1 of user core. Jun 25 18:30:46.254022 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Jun 25 18:30:46.261293 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Jun 25 18:30:46.274928 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Jun 25 18:30:46.277303 systemd[1]: Starting user@500.service - User Manager for UID 500... Jun 25 18:30:46.285309 (systemd)[1682]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Jun 25 18:30:46.368099 systemd[1682]: Queued start job for default target default.target. Jun 25 18:30:46.388135 systemd[1682]: Created slice app.slice - User Application Slice. Jun 25 18:30:46.388165 systemd[1682]: Reached target paths.target - Paths. Jun 25 18:30:46.388177 systemd[1682]: Reached target timers.target - Timers. Jun 25 18:30:46.389458 systemd[1682]: Starting dbus.socket - D-Bus User Message Bus Socket... Jun 25 18:30:46.399237 systemd[1682]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jun 25 18:30:46.399300 systemd[1682]: Reached target sockets.target - Sockets. Jun 25 18:30:46.399311 systemd[1682]: Reached target basic.target - Basic System. Jun 25 18:30:46.399347 systemd[1682]: Reached target default.target - Main User Target. Jun 25 18:30:46.399373 systemd[1682]: Startup finished in 108ms. Jun 25 18:30:46.399662 systemd[1]: Started user@500.service - User Manager for UID 500. Jun 25 18:30:46.400870 systemd[1]: Started session-1.scope - Session 1 of User core. Jun 25 18:30:46.462132 systemd[1]: Started sshd@1-10.0.0.75:22-10.0.0.1:52542.service - OpenSSH per-connection server daemon (10.0.0.1:52542). Jun 25 18:30:46.505615 sshd[1693]: Accepted publickey for core from 10.0.0.1 port 52542 ssh2: RSA SHA256:PTHQXr0iRYYg3MbKKJZ6aC6iEkqmHU1AdffEoJcWF3A Jun 25 18:30:46.506893 sshd[1693]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jun 25 18:30:46.510644 systemd-logind[1351]: New session 2 of user core. Jun 25 18:30:46.520268 systemd[1]: Started session-2.scope - Session 2 of User core. Jun 25 18:30:46.571925 sshd[1693]: pam_unix(sshd:session): session closed for user core Jun 25 18:30:46.582854 systemd[1]: sshd@1-10.0.0.75:22-10.0.0.1:52542.service: Deactivated successfully. Jun 25 18:30:46.584681 systemd[1]: session-2.scope: Deactivated successfully. Jun 25 18:30:46.585972 systemd-logind[1351]: Session 2 logged out. Waiting for processes to exit. Jun 25 18:30:46.592303 systemd[1]: Started sshd@2-10.0.0.75:22-10.0.0.1:52558.service - OpenSSH per-connection server daemon (10.0.0.1:52558). Jun 25 18:30:46.593061 systemd-logind[1351]: Removed session 2. Jun 25 18:30:46.624785 sshd[1700]: Accepted publickey for core from 10.0.0.1 port 52558 ssh2: RSA SHA256:PTHQXr0iRYYg3MbKKJZ6aC6iEkqmHU1AdffEoJcWF3A Jun 25 18:30:46.625921 sshd[1700]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jun 25 18:30:46.629892 systemd-logind[1351]: New session 3 of user core. Jun 25 18:30:46.638257 systemd[1]: Started session-3.scope - Session 3 of User core. Jun 25 18:30:46.686012 sshd[1700]: pam_unix(sshd:session): session closed for user core Jun 25 18:30:46.694330 systemd[1]: sshd@2-10.0.0.75:22-10.0.0.1:52558.service: Deactivated successfully. Jun 25 18:30:46.695778 systemd[1]: session-3.scope: Deactivated successfully. Jun 25 18:30:46.697005 systemd-logind[1351]: Session 3 logged out. Waiting for processes to exit. Jun 25 18:30:46.698135 systemd[1]: Started sshd@3-10.0.0.75:22-10.0.0.1:52562.service - OpenSSH per-connection server daemon (10.0.0.1:52562). Jun 25 18:30:46.698922 systemd-logind[1351]: Removed session 3. Jun 25 18:30:46.734465 sshd[1707]: Accepted publickey for core from 10.0.0.1 port 52562 ssh2: RSA SHA256:PTHQXr0iRYYg3MbKKJZ6aC6iEkqmHU1AdffEoJcWF3A Jun 25 18:30:46.735598 sshd[1707]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jun 25 18:30:46.740043 systemd-logind[1351]: New session 4 of user core. Jun 25 18:30:46.756216 systemd[1]: Started session-4.scope - Session 4 of User core. Jun 25 18:30:46.808149 sshd[1707]: pam_unix(sshd:session): session closed for user core Jun 25 18:30:46.816355 systemd[1]: sshd@3-10.0.0.75:22-10.0.0.1:52562.service: Deactivated successfully. Jun 25 18:30:46.817784 systemd[1]: session-4.scope: Deactivated successfully. Jun 25 18:30:46.818408 systemd-logind[1351]: Session 4 logged out. Waiting for processes to exit. Jun 25 18:30:46.820084 systemd[1]: Started sshd@4-10.0.0.75:22-10.0.0.1:52576.service - OpenSSH per-connection server daemon (10.0.0.1:52576). Jun 25 18:30:46.820814 systemd-logind[1351]: Removed session 4. Jun 25 18:30:46.856493 sshd[1714]: Accepted publickey for core from 10.0.0.1 port 52576 ssh2: RSA SHA256:PTHQXr0iRYYg3MbKKJZ6aC6iEkqmHU1AdffEoJcWF3A Jun 25 18:30:46.857569 sshd[1714]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jun 25 18:30:46.861152 systemd-logind[1351]: New session 5 of user core. Jun 25 18:30:46.864195 systemd[1]: Started session-5.scope - Session 5 of User core. Jun 25 18:30:46.927979 sudo[1717]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Jun 25 18:30:46.928233 sudo[1717]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Jun 25 18:30:46.939771 sudo[1717]: pam_unix(sudo:session): session closed for user root Jun 25 18:30:46.941429 sshd[1714]: pam_unix(sshd:session): session closed for user core Jun 25 18:30:46.955410 systemd[1]: sshd@4-10.0.0.75:22-10.0.0.1:52576.service: Deactivated successfully. Jun 25 18:30:46.956871 systemd[1]: session-5.scope: Deactivated successfully. Jun 25 18:30:46.958115 systemd-logind[1351]: Session 5 logged out. Waiting for processes to exit. Jun 25 18:30:46.959254 systemd[1]: Started sshd@5-10.0.0.75:22-10.0.0.1:52588.service - OpenSSH per-connection server daemon (10.0.0.1:52588). Jun 25 18:30:46.959883 systemd-logind[1351]: Removed session 5. Jun 25 18:30:46.995596 sshd[1722]: Accepted publickey for core from 10.0.0.1 port 52588 ssh2: RSA SHA256:PTHQXr0iRYYg3MbKKJZ6aC6iEkqmHU1AdffEoJcWF3A Jun 25 18:30:46.997148 sshd[1722]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jun 25 18:30:47.000567 systemd-logind[1351]: New session 6 of user core. Jun 25 18:30:47.013216 systemd[1]: Started session-6.scope - Session 6 of User core. Jun 25 18:30:47.063997 sudo[1726]: 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 Jun 25 18:30:47.064272 sudo[1726]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Jun 25 18:30:47.067243 sudo[1726]: pam_unix(sudo:session): session closed for user root Jun 25 18:30:47.072280 sudo[1725]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/systemctl restart audit-rules Jun 25 18:30:47.072813 sudo[1725]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Jun 25 18:30:47.089320 systemd[1]: Stopping audit-rules.service - Load Security Auditing Rules... Jun 25 18:30:47.090575 auditctl[1729]: No rules Jun 25 18:30:47.091423 systemd[1]: audit-rules.service: Deactivated successfully. Jun 25 18:30:47.092181 systemd[1]: Stopped audit-rules.service - Load Security Auditing Rules. Jun 25 18:30:47.093816 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Jun 25 18:30:47.116149 augenrules[1747]: No rules Jun 25 18:30:47.118122 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Jun 25 18:30:47.119826 sudo[1725]: pam_unix(sudo:session): session closed for user root Jun 25 18:30:47.121393 sshd[1722]: pam_unix(sshd:session): session closed for user core Jun 25 18:30:47.123654 systemd[1]: sshd@5-10.0.0.75:22-10.0.0.1:52588.service: Deactivated successfully. Jun 25 18:30:47.125106 systemd[1]: session-6.scope: Deactivated successfully. Jun 25 18:30:47.127309 systemd-logind[1351]: Session 6 logged out. Waiting for processes to exit. Jun 25 18:30:47.128121 systemd-logind[1351]: Removed session 6.