Aug 5 21:43:47.908779 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Aug 5 21:43:47.908802 kernel: Linux version 6.6.43-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 Mon Aug 5 20:24:20 -00 2024 Aug 5 21:43:47.908812 kernel: KASLR enabled Aug 5 21:43:47.908818 kernel: efi: EFI v2.7 by EDK II Aug 5 21:43:47.908823 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb8fd018 ACPI 2.0=0xd9710018 RNG=0xd971e498 MEMRESERVE=0xd9b43d18 Aug 5 21:43:47.908829 kernel: random: crng init done Aug 5 21:43:47.908836 kernel: ACPI: Early table checksum verification disabled Aug 5 21:43:47.908842 kernel: ACPI: RSDP 0x00000000D9710018 000024 (v02 BOCHS ) Aug 5 21:43:47.908848 kernel: ACPI: XSDT 0x00000000D971FE98 000064 (v01 BOCHS BXPC 00000001 01000013) Aug 5 21:43:47.908856 kernel: ACPI: FACP 0x00000000D971FA98 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Aug 5 21:43:47.908862 kernel: ACPI: DSDT 0x00000000D9717518 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Aug 5 21:43:47.908868 kernel: ACPI: APIC 0x00000000D971FC18 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Aug 5 21:43:47.908874 kernel: ACPI: PPTT 0x00000000D971D898 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Aug 5 21:43:47.908880 kernel: ACPI: GTDT 0x00000000D971E818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Aug 5 21:43:47.908887 kernel: ACPI: MCFG 0x00000000D971E918 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Aug 5 21:43:47.908895 kernel: ACPI: SPCR 0x00000000D971FF98 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Aug 5 21:43:47.908901 kernel: ACPI: DBG2 0x00000000D971E418 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Aug 5 21:43:47.908907 kernel: ACPI: IORT 0x00000000D971E718 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Aug 5 21:43:47.908914 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Aug 5 21:43:47.908920 kernel: NUMA: Failed to initialise from firmware Aug 5 21:43:47.908926 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Aug 5 21:43:47.908933 kernel: NUMA: NODE_DATA [mem 0xdc958800-0xdc95dfff] Aug 5 21:43:47.908939 kernel: Zone ranges: Aug 5 21:43:47.908945 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Aug 5 21:43:47.908951 kernel: DMA32 empty Aug 5 21:43:47.908959 kernel: Normal empty Aug 5 21:43:47.908965 kernel: Movable zone start for each node Aug 5 21:43:47.908971 kernel: Early memory node ranges Aug 5 21:43:47.908978 kernel: node 0: [mem 0x0000000040000000-0x00000000d976ffff] Aug 5 21:43:47.908984 kernel: node 0: [mem 0x00000000d9770000-0x00000000d9b3ffff] Aug 5 21:43:47.908991 kernel: node 0: [mem 0x00000000d9b40000-0x00000000dce1ffff] Aug 5 21:43:47.908997 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Aug 5 21:43:47.909003 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Aug 5 21:43:47.909009 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Aug 5 21:43:47.909016 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Aug 5 21:43:47.909022 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Aug 5 21:43:47.909028 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Aug 5 21:43:47.909036 kernel: psci: probing for conduit method from ACPI. Aug 5 21:43:47.909042 kernel: psci: PSCIv1.1 detected in firmware. Aug 5 21:43:47.909048 kernel: psci: Using standard PSCI v0.2 function IDs Aug 5 21:43:47.909063 kernel: psci: Trusted OS migration not required Aug 5 21:43:47.909071 kernel: psci: SMC Calling Convention v1.1 Aug 5 21:43:47.909077 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Aug 5 21:43:47.909085 kernel: percpu: Embedded 31 pages/cpu s86632 r8192 d32152 u126976 Aug 5 21:43:47.909092 kernel: pcpu-alloc: s86632 r8192 d32152 u126976 alloc=31*4096 Aug 5 21:43:47.909099 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Aug 5 21:43:47.909106 kernel: Detected PIPT I-cache on CPU0 Aug 5 21:43:47.909112 kernel: CPU features: detected: GIC system register CPU interface Aug 5 21:43:47.909119 kernel: CPU features: detected: Hardware dirty bit management Aug 5 21:43:47.909125 kernel: CPU features: detected: Spectre-v4 Aug 5 21:43:47.909132 kernel: CPU features: detected: Spectre-BHB Aug 5 21:43:47.909139 kernel: CPU features: kernel page table isolation forced ON by KASLR Aug 5 21:43:47.909145 kernel: CPU features: detected: Kernel page table isolation (KPTI) Aug 5 21:43:47.909153 kernel: CPU features: detected: ARM erratum 1418040 Aug 5 21:43:47.909160 kernel: alternatives: applying boot alternatives Aug 5 21:43:47.909168 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=bb6c4f94d40caa6d83ad7b7b3f8907e11ce677871c150228b9a5377ddab3341e Aug 5 21:43:47.909175 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Aug 5 21:43:47.909182 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Aug 5 21:43:47.909188 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Aug 5 21:43:47.909195 kernel: Fallback order for Node 0: 0 Aug 5 21:43:47.909202 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Aug 5 21:43:47.909208 kernel: Policy zone: DMA Aug 5 21:43:47.909215 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Aug 5 21:43:47.909221 kernel: software IO TLB: area num 4. Aug 5 21:43:47.909229 kernel: software IO TLB: mapped [mem 0x00000000d2e00000-0x00000000d6e00000] (64MB) Aug 5 21:43:47.909236 kernel: Memory: 2386852K/2572288K available (10240K kernel code, 2182K rwdata, 8072K rodata, 39040K init, 897K bss, 185436K reserved, 0K cma-reserved) Aug 5 21:43:47.909243 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Aug 5 21:43:47.909250 kernel: trace event string verifier disabled Aug 5 21:43:47.909256 kernel: rcu: Preemptible hierarchical RCU implementation. Aug 5 21:43:47.909264 kernel: rcu: RCU event tracing is enabled. Aug 5 21:43:47.909275 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Aug 5 21:43:47.909282 kernel: Trampoline variant of Tasks RCU enabled. Aug 5 21:43:47.909288 kernel: Tracing variant of Tasks RCU enabled. Aug 5 21:43:47.909295 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Aug 5 21:43:47.909302 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Aug 5 21:43:47.909308 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Aug 5 21:43:47.909316 kernel: GICv3: 256 SPIs implemented Aug 5 21:43:47.909323 kernel: GICv3: 0 Extended SPIs implemented Aug 5 21:43:47.909329 kernel: Root IRQ handler: gic_handle_irq Aug 5 21:43:47.909336 kernel: GICv3: GICv3 features: 16 PPIs, DirectLPI Aug 5 21:43:47.909343 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Aug 5 21:43:47.909349 kernel: ITS [mem 0x08080000-0x0809ffff] Aug 5 21:43:47.909356 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400d0000 (indirect, esz 8, psz 64K, shr 1) Aug 5 21:43:47.909363 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400e0000 (flat, esz 8, psz 64K, shr 1) Aug 5 21:43:47.909369 kernel: GICv3: using LPI property table @0x00000000400f0000 Aug 5 21:43:47.909376 kernel: GICv3: CPU0: using allocated LPI pending table @0x0000000040100000 Aug 5 21:43:47.909383 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Aug 5 21:43:47.909391 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Aug 5 21:43:47.909397 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Aug 5 21:43:47.909404 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Aug 5 21:43:47.909411 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Aug 5 21:43:47.909418 kernel: arm-pv: using stolen time PV Aug 5 21:43:47.909425 kernel: Console: colour dummy device 80x25 Aug 5 21:43:47.909431 kernel: ACPI: Core revision 20230628 Aug 5 21:43:47.909438 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Aug 5 21:43:47.909445 kernel: pid_max: default: 32768 minimum: 301 Aug 5 21:43:47.909452 kernel: LSM: initializing lsm=lockdown,capability,selinux,integrity Aug 5 21:43:47.909460 kernel: SELinux: Initializing. Aug 5 21:43:47.909467 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Aug 5 21:43:47.909474 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Aug 5 21:43:47.909481 kernel: RCU Tasks: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Aug 5 21:43:47.909488 kernel: RCU Tasks Trace: Setting shift to 2 and lim to 1 rcu_task_cb_adjust=1. Aug 5 21:43:47.909495 kernel: rcu: Hierarchical SRCU implementation. Aug 5 21:43:47.909502 kernel: rcu: Max phase no-delay instances is 400. Aug 5 21:43:47.909509 kernel: Platform MSI: ITS@0x8080000 domain created Aug 5 21:43:47.909516 kernel: PCI/MSI: ITS@0x8080000 domain created Aug 5 21:43:47.909524 kernel: Remapping and enabling EFI services. Aug 5 21:43:47.909531 kernel: smp: Bringing up secondary CPUs ... Aug 5 21:43:47.909540 kernel: Detected PIPT I-cache on CPU1 Aug 5 21:43:47.909548 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Aug 5 21:43:47.909555 kernel: GICv3: CPU1: using allocated LPI pending table @0x0000000040110000 Aug 5 21:43:47.909562 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Aug 5 21:43:47.909569 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Aug 5 21:43:47.909576 kernel: Detected PIPT I-cache on CPU2 Aug 5 21:43:47.909599 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Aug 5 21:43:47.909607 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040120000 Aug 5 21:43:47.909616 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Aug 5 21:43:47.909623 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Aug 5 21:43:47.909634 kernel: Detected PIPT I-cache on CPU3 Aug 5 21:43:47.909647 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Aug 5 21:43:47.909656 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040130000 Aug 5 21:43:47.909663 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Aug 5 21:43:47.909670 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Aug 5 21:43:47.909677 kernel: smp: Brought up 1 node, 4 CPUs Aug 5 21:43:47.909684 kernel: SMP: Total of 4 processors activated. Aug 5 21:43:47.909693 kernel: CPU features: detected: 32-bit EL0 Support Aug 5 21:43:47.909700 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Aug 5 21:43:47.909708 kernel: CPU features: detected: Common not Private translations Aug 5 21:43:47.909715 kernel: CPU features: detected: CRC32 instructions Aug 5 21:43:47.909722 kernel: CPU features: detected: Enhanced Virtualization Traps Aug 5 21:43:47.909729 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Aug 5 21:43:47.909736 kernel: CPU features: detected: LSE atomic instructions Aug 5 21:43:47.909744 kernel: CPU features: detected: Privileged Access Never Aug 5 21:43:47.909753 kernel: CPU features: detected: RAS Extension Support Aug 5 21:43:47.909760 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Aug 5 21:43:47.909767 kernel: CPU: All CPU(s) started at EL1 Aug 5 21:43:47.909774 kernel: alternatives: applying system-wide alternatives Aug 5 21:43:47.909781 kernel: devtmpfs: initialized Aug 5 21:43:47.909789 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Aug 5 21:43:47.909796 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Aug 5 21:43:47.909803 kernel: pinctrl core: initialized pinctrl subsystem Aug 5 21:43:47.909811 kernel: SMBIOS 3.0.0 present. Aug 5 21:43:47.909820 kernel: DMI: QEMU KVM Virtual Machine, BIOS edk2-20230524-3.fc38 05/24/2023 Aug 5 21:43:47.909827 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Aug 5 21:43:47.909834 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Aug 5 21:43:47.909842 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Aug 5 21:43:47.909849 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Aug 5 21:43:47.909856 kernel: audit: initializing netlink subsys (disabled) Aug 5 21:43:47.909864 kernel: audit: type=2000 audit(0.026:1): state=initialized audit_enabled=0 res=1 Aug 5 21:43:47.909871 kernel: thermal_sys: Registered thermal governor 'step_wise' Aug 5 21:43:47.909878 kernel: cpuidle: using governor menu Aug 5 21:43:47.909887 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Aug 5 21:43:47.909894 kernel: ASID allocator initialised with 32768 entries Aug 5 21:43:47.909902 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Aug 5 21:43:47.909909 kernel: Serial: AMBA PL011 UART driver Aug 5 21:43:47.909916 kernel: Modules: 2G module region forced by RANDOMIZE_MODULE_REGION_FULL Aug 5 21:43:47.909923 kernel: Modules: 0 pages in range for non-PLT usage Aug 5 21:43:47.909930 kernel: Modules: 509120 pages in range for PLT usage Aug 5 21:43:47.909938 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Aug 5 21:43:47.909945 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page Aug 5 21:43:47.909954 kernel: HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages Aug 5 21:43:47.909961 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page Aug 5 21:43:47.909968 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Aug 5 21:43:47.909976 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page Aug 5 21:43:47.909983 kernel: HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages Aug 5 21:43:47.909990 kernel: HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page Aug 5 21:43:47.909997 kernel: ACPI: Added _OSI(Module Device) Aug 5 21:43:47.910004 kernel: ACPI: Added _OSI(Processor Device) Aug 5 21:43:47.910012 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Aug 5 21:43:47.910020 kernel: ACPI: Added _OSI(Processor Aggregator Device) Aug 5 21:43:47.910027 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Aug 5 21:43:47.910035 kernel: ACPI: Interpreter enabled Aug 5 21:43:47.910042 kernel: ACPI: Using GIC for interrupt routing Aug 5 21:43:47.910049 kernel: ACPI: MCFG table detected, 1 entries Aug 5 21:43:47.910056 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Aug 5 21:43:47.910063 kernel: printk: console [ttyAMA0] enabled Aug 5 21:43:47.910071 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Aug 5 21:43:47.910203 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Aug 5 21:43:47.910282 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Aug 5 21:43:47.910351 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Aug 5 21:43:47.910418 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Aug 5 21:43:47.910483 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Aug 5 21:43:47.910493 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Aug 5 21:43:47.910500 kernel: PCI host bridge to bus 0000:00 Aug 5 21:43:47.910573 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Aug 5 21:43:47.910699 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Aug 5 21:43:47.910764 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Aug 5 21:43:47.910824 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Aug 5 21:43:47.910905 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Aug 5 21:43:47.910985 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Aug 5 21:43:47.911057 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Aug 5 21:43:47.911132 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Aug 5 21:43:47.911202 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Aug 5 21:43:47.911271 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Aug 5 21:43:47.911339 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Aug 5 21:43:47.911408 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Aug 5 21:43:47.911469 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Aug 5 21:43:47.911530 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Aug 5 21:43:47.911604 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Aug 5 21:43:47.911615 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Aug 5 21:43:47.911622 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Aug 5 21:43:47.911629 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Aug 5 21:43:47.911637 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Aug 5 21:43:47.911651 kernel: iommu: Default domain type: Translated Aug 5 21:43:47.911659 kernel: iommu: DMA domain TLB invalidation policy: strict mode Aug 5 21:43:47.911667 kernel: efivars: Registered efivars operations Aug 5 21:43:47.911674 kernel: vgaarb: loaded Aug 5 21:43:47.911684 kernel: clocksource: Switched to clocksource arch_sys_counter Aug 5 21:43:47.911691 kernel: VFS: Disk quotas dquot_6.6.0 Aug 5 21:43:47.911699 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Aug 5 21:43:47.911706 kernel: pnp: PnP ACPI init Aug 5 21:43:47.911794 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Aug 5 21:43:47.911805 kernel: pnp: PnP ACPI: found 1 devices Aug 5 21:43:47.911812 kernel: NET: Registered PF_INET protocol family Aug 5 21:43:47.911820 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Aug 5 21:43:47.911830 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Aug 5 21:43:47.911837 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Aug 5 21:43:47.911845 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Aug 5 21:43:47.911852 kernel: TCP bind hash table entries: 32768 (order: 8, 1048576 bytes, linear) Aug 5 21:43:47.911859 kernel: TCP: Hash tables configured (established 32768 bind 32768) Aug 5 21:43:47.911867 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Aug 5 21:43:47.911874 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Aug 5 21:43:47.911881 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Aug 5 21:43:47.911888 kernel: PCI: CLS 0 bytes, default 64 Aug 5 21:43:47.911897 kernel: kvm [1]: HYP mode not available Aug 5 21:43:47.911904 kernel: Initialise system trusted keyrings Aug 5 21:43:47.911911 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Aug 5 21:43:47.911919 kernel: Key type asymmetric registered Aug 5 21:43:47.911926 kernel: Asymmetric key parser 'x509' registered Aug 5 21:43:47.911934 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Aug 5 21:43:47.911941 kernel: io scheduler mq-deadline registered Aug 5 21:43:47.911948 kernel: io scheduler kyber registered Aug 5 21:43:47.911955 kernel: io scheduler bfq registered Aug 5 21:43:47.911964 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Aug 5 21:43:47.911971 kernel: ACPI: button: Power Button [PWRB] Aug 5 21:43:47.911979 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Aug 5 21:43:47.912049 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Aug 5 21:43:47.912064 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Aug 5 21:43:47.912071 kernel: thunder_xcv, ver 1.0 Aug 5 21:43:47.912079 kernel: thunder_bgx, ver 1.0 Aug 5 21:43:47.912086 kernel: nicpf, ver 1.0 Aug 5 21:43:47.912093 kernel: nicvf, ver 1.0 Aug 5 21:43:47.912173 kernel: rtc-efi rtc-efi.0: registered as rtc0 Aug 5 21:43:47.912241 kernel: rtc-efi rtc-efi.0: setting system clock to 2024-08-05T21:43:47 UTC (1722894227) Aug 5 21:43:47.912251 kernel: hid: raw HID events driver (C) Jiri Kosina Aug 5 21:43:47.912259 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Aug 5 21:43:47.912266 kernel: watchdog: Delayed init of the lockup detector failed: -19 Aug 5 21:43:47.912273 kernel: watchdog: Hard watchdog permanently disabled Aug 5 21:43:47.912281 kernel: NET: Registered PF_INET6 protocol family Aug 5 21:43:47.912288 kernel: Segment Routing with IPv6 Aug 5 21:43:47.912297 kernel: In-situ OAM (IOAM) with IPv6 Aug 5 21:43:47.912305 kernel: NET: Registered PF_PACKET protocol family Aug 5 21:43:47.912312 kernel: Key type dns_resolver registered Aug 5 21:43:47.912320 kernel: registered taskstats version 1 Aug 5 21:43:47.912327 kernel: Loading compiled-in X.509 certificates Aug 5 21:43:47.912335 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.43-flatcar: 7b6de7a842f23ac7c1bb6bedfb9546933daaea09' Aug 5 21:43:47.912342 kernel: Key type .fscrypt registered Aug 5 21:43:47.912349 kernel: Key type fscrypt-provisioning registered Aug 5 21:43:47.912356 kernel: ima: No TPM chip found, activating TPM-bypass! Aug 5 21:43:47.912365 kernel: ima: Allocated hash algorithm: sha1 Aug 5 21:43:47.912372 kernel: ima: No architecture policies found Aug 5 21:43:47.912380 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Aug 5 21:43:47.912387 kernel: clk: Disabling unused clocks Aug 5 21:43:47.912394 kernel: Freeing unused kernel memory: 39040K Aug 5 21:43:47.912402 kernel: Run /init as init process Aug 5 21:43:47.912409 kernel: with arguments: Aug 5 21:43:47.912416 kernel: /init Aug 5 21:43:47.912423 kernel: with environment: Aug 5 21:43:47.912432 kernel: HOME=/ Aug 5 21:43:47.912439 kernel: TERM=linux Aug 5 21:43:47.912446 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Aug 5 21:43:47.912455 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) Aug 5 21:43:47.912464 systemd[1]: Detected virtualization kvm. Aug 5 21:43:47.912472 systemd[1]: Detected architecture arm64. Aug 5 21:43:47.912479 systemd[1]: Running in initrd. Aug 5 21:43:47.912489 systemd[1]: No hostname configured, using default hostname. Aug 5 21:43:47.912497 systemd[1]: Hostname set to . Aug 5 21:43:47.912507 systemd[1]: Initializing machine ID from VM UUID. Aug 5 21:43:47.912515 systemd[1]: Queued start job for default target initrd.target. Aug 5 21:43:47.912523 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 5 21:43:47.912531 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 5 21:43:47.912541 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Aug 5 21:43:47.912549 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Aug 5 21:43:47.912559 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Aug 5 21:43:47.912567 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Aug 5 21:43:47.912576 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Aug 5 21:43:47.912594 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Aug 5 21:43:47.912603 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 5 21:43:47.912610 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Aug 5 21:43:47.912618 systemd[1]: Reached target paths.target - Path Units. Aug 5 21:43:47.912628 systemd[1]: Reached target slices.target - Slice Units. Aug 5 21:43:47.912636 systemd[1]: Reached target swap.target - Swaps. Aug 5 21:43:47.912649 systemd[1]: Reached target timers.target - Timer Units. Aug 5 21:43:47.912657 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Aug 5 21:43:47.912665 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Aug 5 21:43:47.912673 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Aug 5 21:43:47.912681 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Aug 5 21:43:47.912688 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Aug 5 21:43:47.912696 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Aug 5 21:43:47.912706 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Aug 5 21:43:47.912714 systemd[1]: Reached target sockets.target - Socket Units. Aug 5 21:43:47.912722 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Aug 5 21:43:47.912729 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Aug 5 21:43:47.912737 systemd[1]: Finished network-cleanup.service - Network Cleanup. Aug 5 21:43:47.912745 systemd[1]: Starting systemd-fsck-usr.service... Aug 5 21:43:47.912752 systemd[1]: Starting systemd-journald.service - Journal Service... Aug 5 21:43:47.912760 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Aug 5 21:43:47.912769 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 5 21:43:47.912777 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Aug 5 21:43:47.912785 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Aug 5 21:43:47.912792 systemd[1]: Finished systemd-fsck-usr.service. Aug 5 21:43:47.912801 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Aug 5 21:43:47.912826 systemd-journald[238]: Collecting audit messages is disabled. Aug 5 21:43:47.912845 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 5 21:43:47.912854 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Aug 5 21:43:47.912862 systemd-journald[238]: Journal started Aug 5 21:43:47.912882 systemd-journald[238]: Runtime Journal (/run/log/journal/8ec3a2822927461fbf2657c77bc9ea99) is 5.9M, max 47.3M, 41.4M free. Aug 5 21:43:47.905047 systemd-modules-load[239]: Inserted module 'overlay' Aug 5 21:43:47.918185 systemd[1]: Started systemd-journald.service - Journal Service. Aug 5 21:43:47.920610 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Aug 5 21:43:47.921790 systemd-modules-load[239]: Inserted module 'br_netfilter' Aug 5 21:43:47.922608 kernel: Bridge firewalling registered Aug 5 21:43:47.930780 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 5 21:43:47.932438 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Aug 5 21:43:47.935773 systemd[1]: Starting systemd-tmpfiles-setup.service - Create Volatile Files and Directories... Aug 5 21:43:47.936992 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Aug 5 21:43:47.940009 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Aug 5 21:43:47.944973 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 5 21:43:47.950486 systemd[1]: Finished systemd-tmpfiles-setup.service - Create Volatile Files and Directories. Aug 5 21:43:47.953810 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Aug 5 21:43:47.955856 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 5 21:43:47.967728 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Aug 5 21:43:47.970033 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Aug 5 21:43:47.979786 dracut-cmdline[279]: dracut-dracut-053 Aug 5 21:43:47.982397 dracut-cmdline[279]: 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=bb6c4f94d40caa6d83ad7b7b3f8907e11ce677871c150228b9a5377ddab3341e Aug 5 21:43:47.998015 systemd-resolved[282]: Positive Trust Anchors: Aug 5 21:43:47.998034 systemd-resolved[282]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Aug 5 21:43:47.998064 systemd-resolved[282]: 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 Aug 5 21:43:48.005008 systemd-resolved[282]: Defaulting to hostname 'linux'. Aug 5 21:43:48.005984 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Aug 5 21:43:48.007078 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Aug 5 21:43:48.052618 kernel: SCSI subsystem initialized Aug 5 21:43:48.057605 kernel: Loading iSCSI transport class v2.0-870. Aug 5 21:43:48.064608 kernel: iscsi: registered transport (tcp) Aug 5 21:43:48.078618 kernel: iscsi: registered transport (qla4xxx) Aug 5 21:43:48.078676 kernel: QLogic iSCSI HBA Driver Aug 5 21:43:48.122946 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Aug 5 21:43:48.136768 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Aug 5 21:43:48.153521 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Aug 5 21:43:48.153595 kernel: device-mapper: uevent: version 1.0.3 Aug 5 21:43:48.154987 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Aug 5 21:43:48.209620 kernel: raid6: neonx8 gen() 15742 MB/s Aug 5 21:43:48.226612 kernel: raid6: neonx4 gen() 15543 MB/s Aug 5 21:43:48.243607 kernel: raid6: neonx2 gen() 13037 MB/s Aug 5 21:43:48.260603 kernel: raid6: neonx1 gen() 10374 MB/s Aug 5 21:43:48.277603 kernel: raid6: int64x8 gen() 6924 MB/s Aug 5 21:43:48.294605 kernel: raid6: int64x4 gen() 7293 MB/s Aug 5 21:43:48.311604 kernel: raid6: int64x2 gen() 6093 MB/s Aug 5 21:43:48.328606 kernel: raid6: int64x1 gen() 5027 MB/s Aug 5 21:43:48.328624 kernel: raid6: using algorithm neonx8 gen() 15742 MB/s Aug 5 21:43:48.345604 kernel: raid6: .... xor() 11986 MB/s, rmw enabled Aug 5 21:43:48.345621 kernel: raid6: using neon recovery algorithm Aug 5 21:43:48.351990 kernel: xor: measuring software checksum speed Aug 5 21:43:48.352014 kernel: 8regs : 19859 MB/sec Aug 5 21:43:48.352984 kernel: 32regs : 19697 MB/sec Aug 5 21:43:48.353801 kernel: arm64_neon : 27197 MB/sec Aug 5 21:43:48.354604 kernel: xor: using function: arm64_neon (27197 MB/sec) Aug 5 21:43:48.406629 kernel: Btrfs loaded, zoned=no, fsverity=no Aug 5 21:43:48.418183 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Aug 5 21:43:48.434759 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 5 21:43:48.448217 systemd-udevd[466]: Using default interface naming scheme 'v255'. Aug 5 21:43:48.451547 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 5 21:43:48.460010 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Aug 5 21:43:48.472794 dracut-pre-trigger[473]: rd.md=0: removing MD RAID activation Aug 5 21:43:48.505650 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Aug 5 21:43:48.516750 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Aug 5 21:43:48.558737 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Aug 5 21:43:48.565737 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Aug 5 21:43:48.578758 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Aug 5 21:43:48.581558 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Aug 5 21:43:48.583954 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 5 21:43:48.585168 systemd[1]: Reached target remote-fs.target - Remote File Systems. Aug 5 21:43:48.593805 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Aug 5 21:43:48.608526 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Aug 5 21:43:48.613949 kernel: virtio_blk virtio1: 1/0/0 default/read/poll queues Aug 5 21:43:48.625106 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Aug 5 21:43:48.625233 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Aug 5 21:43:48.621999 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Aug 5 21:43:48.622109 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 5 21:43:48.623466 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 5 21:43:48.624542 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Aug 5 21:43:48.624659 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Aug 5 21:43:48.625706 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Aug 5 21:43:48.636752 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 5 21:43:48.641946 kernel: BTRFS: device fsid 8a9ab799-ab52-4671-9234-72d7c6e57b99 devid 1 transid 38 /dev/vda3 scanned by (udev-worker) (515) Aug 5 21:43:48.644062 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by (udev-worker) (529) Aug 5 21:43:48.653235 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Aug 5 21:43:48.654590 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 5 21:43:48.662775 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Aug 5 21:43:48.666567 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Aug 5 21:43:48.667728 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Aug 5 21:43:48.672876 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Aug 5 21:43:48.684745 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Aug 5 21:43:48.686879 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Aug 5 21:43:48.695627 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Aug 5 21:43:48.705119 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 5 21:43:49.726469 disk-uuid[556]: The operation has completed successfully. Aug 5 21:43:49.727594 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Aug 5 21:43:49.762011 systemd[1]: disk-uuid.service: Deactivated successfully. Aug 5 21:43:49.762114 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Aug 5 21:43:49.780783 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Aug 5 21:43:49.784969 sh[577]: Success Aug 5 21:43:49.808611 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Aug 5 21:43:49.846707 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Aug 5 21:43:49.859141 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Aug 5 21:43:49.860727 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Aug 5 21:43:49.874651 kernel: BTRFS info (device dm-0): first mount of filesystem 8a9ab799-ab52-4671-9234-72d7c6e57b99 Aug 5 21:43:49.874700 kernel: BTRFS info (device dm-0): using crc32c (crc32c-generic) checksum algorithm Aug 5 21:43:49.874721 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Aug 5 21:43:49.875254 kernel: BTRFS info (device dm-0): disabling log replay at mount time Aug 5 21:43:49.876060 kernel: BTRFS info (device dm-0): using free space tree Aug 5 21:43:49.880558 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Aug 5 21:43:49.882839 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Aug 5 21:43:49.897774 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Aug 5 21:43:49.899567 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Aug 5 21:43:49.907713 kernel: BTRFS info (device vda6): first mount of filesystem 2fbfcd26-f9be-477f-9b31-7e91608e027d Aug 5 21:43:49.907763 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Aug 5 21:43:49.907775 kernel: BTRFS info (device vda6): using free space tree Aug 5 21:43:49.910773 kernel: BTRFS info (device vda6): auto enabling async discard Aug 5 21:43:49.920653 kernel: BTRFS info (device vda6): last unmount of filesystem 2fbfcd26-f9be-477f-9b31-7e91608e027d Aug 5 21:43:49.919844 systemd[1]: mnt-oem.mount: Deactivated successfully. Aug 5 21:43:49.928197 systemd[1]: Finished ignition-setup.service - Ignition (setup). Aug 5 21:43:49.934780 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Aug 5 21:43:50.002763 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Aug 5 21:43:50.018793 systemd[1]: Starting systemd-networkd.service - Network Configuration... Aug 5 21:43:50.054729 systemd-networkd[769]: lo: Link UP Aug 5 21:43:50.054740 systemd-networkd[769]: lo: Gained carrier Aug 5 21:43:50.055429 systemd-networkd[769]: Enumeration completed Aug 5 21:43:50.055722 systemd[1]: Started systemd-networkd.service - Network Configuration. Aug 5 21:43:50.057386 systemd-networkd[769]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 5 21:43:50.057389 systemd-networkd[769]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Aug 5 21:43:50.062218 ignition[668]: Ignition 2.19.0 Aug 5 21:43:50.057519 systemd[1]: Reached target network.target - Network. Aug 5 21:43:50.062226 ignition[668]: Stage: fetch-offline Aug 5 21:43:50.058430 systemd-networkd[769]: eth0: Link UP Aug 5 21:43:50.062265 ignition[668]: no configs at "/usr/lib/ignition/base.d" Aug 5 21:43:50.058434 systemd-networkd[769]: eth0: Gained carrier Aug 5 21:43:50.062273 ignition[668]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Aug 5 21:43:50.058441 systemd-networkd[769]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 5 21:43:50.062364 ignition[668]: parsed url from cmdline: "" Aug 5 21:43:50.069628 systemd-networkd[769]: eth0: DHCPv4 address 10.0.0.66/16, gateway 10.0.0.1 acquired from 10.0.0.1 Aug 5 21:43:50.062368 ignition[668]: no config URL provided Aug 5 21:43:50.062372 ignition[668]: reading system config file "/usr/lib/ignition/user.ign" Aug 5 21:43:50.062379 ignition[668]: no config at "/usr/lib/ignition/user.ign" Aug 5 21:43:50.062406 ignition[668]: op(1): [started] loading QEMU firmware config module Aug 5 21:43:50.062410 ignition[668]: op(1): executing: "modprobe" "qemu_fw_cfg" Aug 5 21:43:50.080891 ignition[668]: op(1): [finished] loading QEMU firmware config module Aug 5 21:43:50.085344 ignition[668]: parsing config with SHA512: 1561875fc36782d991328b12ae57d74cfb9ddcfab73cd18d742b04ac615e25cda8e41e24a4c08271fe6c6309d6baba82bd903e01da06efd9b1b19d6aceb94b85 Aug 5 21:43:50.092261 unknown[668]: fetched base config from "system" Aug 5 21:43:50.092272 unknown[668]: fetched user config from "qemu" Aug 5 21:43:50.092462 ignition[668]: fetch-offline: fetch-offline passed Aug 5 21:43:50.092546 ignition[668]: Ignition finished successfully Aug 5 21:43:50.094451 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Aug 5 21:43:50.095787 systemd[1]: ignition-fetch.service - Ignition (fetch) was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Aug 5 21:43:50.105761 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Aug 5 21:43:50.116990 ignition[778]: Ignition 2.19.0 Aug 5 21:43:50.117000 ignition[778]: Stage: kargs Aug 5 21:43:50.117189 ignition[778]: no configs at "/usr/lib/ignition/base.d" Aug 5 21:43:50.117199 ignition[778]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Aug 5 21:43:50.117822 ignition[778]: kargs: kargs passed Aug 5 21:43:50.117871 ignition[778]: Ignition finished successfully Aug 5 21:43:50.121647 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Aug 5 21:43:50.134757 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Aug 5 21:43:50.145447 ignition[787]: Ignition 2.19.0 Aug 5 21:43:50.145458 ignition[787]: Stage: disks Aug 5 21:43:50.145667 ignition[787]: no configs at "/usr/lib/ignition/base.d" Aug 5 21:43:50.145677 ignition[787]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Aug 5 21:43:50.147812 systemd[1]: Finished ignition-disks.service - Ignition (disks). Aug 5 21:43:50.146270 ignition[787]: disks: disks passed Aug 5 21:43:50.150009 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Aug 5 21:43:50.146319 ignition[787]: Ignition finished successfully Aug 5 21:43:50.151465 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Aug 5 21:43:50.152993 systemd[1]: Reached target local-fs.target - Local File Systems. Aug 5 21:43:50.154801 systemd[1]: Reached target sysinit.target - System Initialization. Aug 5 21:43:50.156391 systemd[1]: Reached target basic.target - Basic System. Aug 5 21:43:50.162773 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Aug 5 21:43:50.181900 systemd-resolved[282]: Detected conflict on linux IN A 10.0.0.66 Aug 5 21:43:50.181913 systemd-resolved[282]: Hostname conflict, changing published hostname from 'linux' to 'linux10'. Aug 5 21:43:50.185812 systemd-fsck[799]: ROOT: clean, 14/553520 files, 52654/553472 blocks Aug 5 21:43:50.187385 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Aug 5 21:43:50.197788 systemd[1]: Mounting sysroot.mount - /sysroot... Aug 5 21:43:50.240591 kernel: EXT4-fs (vda9): mounted filesystem ec701988-3dff-4e7d-a2a2-79d78965de5d r/w with ordered data mode. Quota mode: none. Aug 5 21:43:50.241054 systemd[1]: Mounted sysroot.mount - /sysroot. Aug 5 21:43:50.242273 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Aug 5 21:43:50.261694 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Aug 5 21:43:50.263503 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Aug 5 21:43:50.264777 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Aug 5 21:43:50.264834 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Aug 5 21:43:50.264911 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Aug 5 21:43:50.275532 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/vda6 scanned by mount (807) Aug 5 21:43:50.272066 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Aug 5 21:43:50.279979 kernel: BTRFS info (device vda6): first mount of filesystem 2fbfcd26-f9be-477f-9b31-7e91608e027d Aug 5 21:43:50.280014 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Aug 5 21:43:50.280025 kernel: BTRFS info (device vda6): using free space tree Aug 5 21:43:50.274214 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Aug 5 21:43:50.284625 kernel: BTRFS info (device vda6): auto enabling async discard Aug 5 21:43:50.285846 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Aug 5 21:43:50.345844 initrd-setup-root[831]: cut: /sysroot/etc/passwd: No such file or directory Aug 5 21:43:50.350372 initrd-setup-root[838]: cut: /sysroot/etc/group: No such file or directory Aug 5 21:43:50.353981 initrd-setup-root[845]: cut: /sysroot/etc/shadow: No such file or directory Aug 5 21:43:50.357282 initrd-setup-root[852]: cut: /sysroot/etc/gshadow: No such file or directory Aug 5 21:43:50.431452 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Aug 5 21:43:50.439733 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Aug 5 21:43:50.442887 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Aug 5 21:43:50.449021 kernel: BTRFS info (device vda6): last unmount of filesystem 2fbfcd26-f9be-477f-9b31-7e91608e027d Aug 5 21:43:50.463958 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Aug 5 21:43:50.469113 ignition[921]: INFO : Ignition 2.19.0 Aug 5 21:43:50.469113 ignition[921]: INFO : Stage: mount Aug 5 21:43:50.469113 ignition[921]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 5 21:43:50.469113 ignition[921]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Aug 5 21:43:50.473604 ignition[921]: INFO : mount: mount passed Aug 5 21:43:50.473604 ignition[921]: INFO : Ignition finished successfully Aug 5 21:43:50.473627 systemd[1]: Finished ignition-mount.service - Ignition (mount). Aug 5 21:43:50.486731 systemd[1]: Starting ignition-files.service - Ignition (files)... Aug 5 21:43:50.870958 systemd[1]: sysroot-oem.mount: Deactivated successfully. Aug 5 21:43:50.879795 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Aug 5 21:43:50.885604 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by mount (934) Aug 5 21:43:50.887925 kernel: BTRFS info (device vda6): first mount of filesystem 2fbfcd26-f9be-477f-9b31-7e91608e027d Aug 5 21:43:50.887961 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Aug 5 21:43:50.887972 kernel: BTRFS info (device vda6): using free space tree Aug 5 21:43:50.890601 kernel: BTRFS info (device vda6): auto enabling async discard Aug 5 21:43:50.891845 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Aug 5 21:43:50.910093 ignition[951]: INFO : Ignition 2.19.0 Aug 5 21:43:50.910093 ignition[951]: INFO : Stage: files Aug 5 21:43:50.911796 ignition[951]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 5 21:43:50.911796 ignition[951]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Aug 5 21:43:50.911796 ignition[951]: DEBUG : files: compiled without relabeling support, skipping Aug 5 21:43:50.915464 ignition[951]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Aug 5 21:43:50.915464 ignition[951]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Aug 5 21:43:50.915464 ignition[951]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Aug 5 21:43:50.915464 ignition[951]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Aug 5 21:43:50.915464 ignition[951]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Aug 5 21:43:50.914614 unknown[951]: wrote ssh authorized keys file for user: core Aug 5 21:43:50.923910 ignition[951]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Aug 5 21:43:50.923910 ignition[951]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Aug 5 21:43:50.923910 ignition[951]: INFO : files: op(4): [started] processing unit "etcd-member.service" Aug 5 21:43:50.923910 ignition[951]: 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" Aug 5 21:43:50.923910 ignition[951]: 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" Aug 5 21:43:50.923910 ignition[951]: INFO : files: op(4): [finished] processing unit "etcd-member.service" Aug 5 21:43:50.923910 ignition[951]: INFO : files: op(6): [started] processing unit "coreos-metadata.service" Aug 5 21:43:50.923910 ignition[951]: INFO : files: op(6): op(7): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Aug 5 21:43:50.923910 ignition[951]: INFO : files: op(6): op(7): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Aug 5 21:43:50.923910 ignition[951]: INFO : files: op(6): [finished] processing unit "coreos-metadata.service" Aug 5 21:43:50.923910 ignition[951]: INFO : files: op(8): [started] setting preset to disabled for "coreos-metadata.service" Aug 5 21:43:50.943595 ignition[951]: INFO : files: op(8): op(9): [started] removing enablement symlink(s) for "coreos-metadata.service" Aug 5 21:43:50.946270 ignition[951]: INFO : files: op(8): op(9): [finished] removing enablement symlink(s) for "coreos-metadata.service" Aug 5 21:43:50.947662 ignition[951]: INFO : files: op(8): [finished] setting preset to disabled for "coreos-metadata.service" Aug 5 21:43:50.947662 ignition[951]: INFO : files: op(a): [started] setting preset to enabled for "etcd-member.service" Aug 5 21:43:50.947662 ignition[951]: INFO : files: op(a): [finished] setting preset to enabled for "etcd-member.service" Aug 5 21:43:50.947662 ignition[951]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Aug 5 21:43:50.947662 ignition[951]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Aug 5 21:43:50.947662 ignition[951]: INFO : files: files passed Aug 5 21:43:50.947662 ignition[951]: INFO : Ignition finished successfully Aug 5 21:43:50.949141 systemd[1]: Finished ignition-files.service - Ignition (files). Aug 5 21:43:50.962755 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Aug 5 21:43:50.964599 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Aug 5 21:43:50.966323 systemd[1]: ignition-quench.service: Deactivated successfully. Aug 5 21:43:50.966413 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Aug 5 21:43:50.973204 initrd-setup-root-after-ignition[980]: grep: /sysroot/oem/oem-release: No such file or directory Aug 5 21:43:50.976796 initrd-setup-root-after-ignition[982]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Aug 5 21:43:50.976796 initrd-setup-root-after-ignition[982]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Aug 5 21:43:50.980271 initrd-setup-root-after-ignition[986]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Aug 5 21:43:50.981331 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Aug 5 21:43:50.982969 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Aug 5 21:43:51.001777 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Aug 5 21:43:51.028349 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Aug 5 21:43:51.028486 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Aug 5 21:43:51.030792 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Aug 5 21:43:51.032550 systemd[1]: Reached target initrd.target - Initrd Default Target. Aug 5 21:43:51.034449 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Aug 5 21:43:51.035313 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Aug 5 21:43:51.055740 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Aug 5 21:43:51.070793 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Aug 5 21:43:51.079173 systemd[1]: Stopped target network.target - Network. Aug 5 21:43:51.080102 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Aug 5 21:43:51.081754 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 5 21:43:51.083751 systemd[1]: Stopped target timers.target - Timer Units. Aug 5 21:43:51.085572 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Aug 5 21:43:51.085718 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Aug 5 21:43:51.088219 systemd[1]: Stopped target initrd.target - Initrd Default Target. Aug 5 21:43:51.090013 systemd[1]: Stopped target basic.target - Basic System. Aug 5 21:43:51.091558 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Aug 5 21:43:51.093207 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Aug 5 21:43:51.095155 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Aug 5 21:43:51.096951 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Aug 5 21:43:51.098756 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Aug 5 21:43:51.100467 systemd[1]: Stopped target sysinit.target - System Initialization. Aug 5 21:43:51.102319 systemd[1]: Stopped target local-fs.target - Local File Systems. Aug 5 21:43:51.103997 systemd[1]: Stopped target swap.target - Swaps. Aug 5 21:43:51.105370 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Aug 5 21:43:51.105495 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Aug 5 21:43:51.107744 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Aug 5 21:43:51.109433 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 5 21:43:51.111287 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Aug 5 21:43:51.114636 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 5 21:43:51.115744 systemd[1]: dracut-initqueue.service: Deactivated successfully. Aug 5 21:43:51.115868 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Aug 5 21:43:51.118263 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Aug 5 21:43:51.118427 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Aug 5 21:43:51.120393 systemd[1]: Stopped target paths.target - Path Units. Aug 5 21:43:51.121858 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Aug 5 21:43:51.126640 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 5 21:43:51.127858 systemd[1]: Stopped target slices.target - Slice Units. Aug 5 21:43:51.129868 systemd[1]: Stopped target sockets.target - Socket Units. Aug 5 21:43:51.131316 systemd[1]: iscsid.socket: Deactivated successfully. Aug 5 21:43:51.131403 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Aug 5 21:43:51.132777 systemd[1]: iscsiuio.socket: Deactivated successfully. Aug 5 21:43:51.132860 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Aug 5 21:43:51.134291 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Aug 5 21:43:51.134400 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Aug 5 21:43:51.136114 systemd[1]: ignition-files.service: Deactivated successfully. Aug 5 21:43:51.136220 systemd[1]: Stopped ignition-files.service - Ignition (files). Aug 5 21:43:51.148772 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Aug 5 21:43:51.150329 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Aug 5 21:43:51.151327 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Aug 5 21:43:51.153020 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Aug 5 21:43:51.154788 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Aug 5 21:43:51.154917 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Aug 5 21:43:51.156620 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Aug 5 21:43:51.156639 systemd-networkd[769]: eth0: DHCPv6 lease lost Aug 5 21:43:51.156728 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Aug 5 21:43:51.161064 systemd[1]: systemd-networkd.service: Deactivated successfully. Aug 5 21:43:51.161159 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Aug 5 21:43:51.163787 systemd[1]: systemd-networkd.socket: Deactivated successfully. Aug 5 21:43:51.163881 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Aug 5 21:43:51.169951 ignition[1006]: INFO : Ignition 2.19.0 Aug 5 21:43:51.180331 ignition[1006]: INFO : Stage: umount Aug 5 21:43:51.180331 ignition[1006]: INFO : no configs at "/usr/lib/ignition/base.d" Aug 5 21:43:51.180331 ignition[1006]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Aug 5 21:43:51.180331 ignition[1006]: INFO : umount: umount passed Aug 5 21:43:51.180331 ignition[1006]: INFO : Ignition finished successfully Aug 5 21:43:51.177736 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Aug 5 21:43:51.178544 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Aug 5 21:43:51.178622 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Aug 5 21:43:51.180677 systemd[1]: sysroot-boot.mount: Deactivated successfully. Aug 5 21:43:51.181242 systemd[1]: systemd-resolved.service: Deactivated successfully. Aug 5 21:43:51.182094 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Aug 5 21:43:51.185529 systemd[1]: initrd-cleanup.service: Deactivated successfully. Aug 5 21:43:51.185661 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Aug 5 21:43:51.188338 systemd[1]: ignition-mount.service: Deactivated successfully. Aug 5 21:43:51.188430 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Aug 5 21:43:51.190105 systemd[1]: sysroot-boot.service: Deactivated successfully. Aug 5 21:43:51.190206 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Aug 5 21:43:51.195895 systemd[1]: ignition-disks.service: Deactivated successfully. Aug 5 21:43:51.195963 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Aug 5 21:43:51.197404 systemd[1]: ignition-kargs.service: Deactivated successfully. Aug 5 21:43:51.197451 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Aug 5 21:43:51.199151 systemd[1]: ignition-setup.service: Deactivated successfully. Aug 5 21:43:51.199197 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Aug 5 21:43:51.200964 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Aug 5 21:43:51.201010 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Aug 5 21:43:51.202676 systemd[1]: initrd-setup-root.service: Deactivated successfully. Aug 5 21:43:51.202723 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Aug 5 21:43:51.204470 systemd[1]: systemd-sysctl.service: Deactivated successfully. Aug 5 21:43:51.204519 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Aug 5 21:43:51.205962 systemd[1]: systemd-modules-load.service: Deactivated successfully. Aug 5 21:43:51.206004 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Aug 5 21:43:51.207590 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Aug 5 21:43:51.207645 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create Volatile Files and Directories. Aug 5 21:43:51.209402 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 5 21:43:51.213551 systemd[1]: network-cleanup.service: Deactivated successfully. Aug 5 21:43:51.213666 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Aug 5 21:43:51.219254 systemd[1]: systemd-udevd.service: Deactivated successfully. Aug 5 21:43:51.219419 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 5 21:43:51.221062 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Aug 5 21:43:51.221103 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Aug 5 21:43:51.222550 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Aug 5 21:43:51.222609 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Aug 5 21:43:51.224457 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Aug 5 21:43:51.224511 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Aug 5 21:43:51.227328 systemd[1]: dracut-cmdline.service: Deactivated successfully. Aug 5 21:43:51.227378 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Aug 5 21:43:51.230111 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Aug 5 21:43:51.230162 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Aug 5 21:43:51.240741 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Aug 5 21:43:51.242072 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Aug 5 21:43:51.242135 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 5 21:43:51.243812 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Aug 5 21:43:51.243857 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Aug 5 21:43:51.245951 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Aug 5 21:43:51.246004 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Aug 5 21:43:51.247976 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Aug 5 21:43:51.248024 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Aug 5 21:43:51.250099 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Aug 5 21:43:51.251642 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Aug 5 21:43:51.253225 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Aug 5 21:43:51.255900 systemd[1]: Starting initrd-switch-root.service - Switch Root... Aug 5 21:43:51.268351 systemd[1]: Switching root. Aug 5 21:43:51.305117 systemd-journald[238]: Journal stopped Aug 5 21:43:52.030895 systemd-journald[238]: Received SIGTERM from PID 1 (systemd). Aug 5 21:43:52.030945 kernel: SELinux: policy capability network_peer_controls=1 Aug 5 21:43:52.030962 kernel: SELinux: policy capability open_perms=1 Aug 5 21:43:52.030973 kernel: SELinux: policy capability extended_socket_class=1 Aug 5 21:43:52.030982 kernel: SELinux: policy capability always_check_network=0 Aug 5 21:43:52.030992 kernel: SELinux: policy capability cgroup_seclabel=1 Aug 5 21:43:52.031009 kernel: SELinux: policy capability nnp_nosuid_transition=1 Aug 5 21:43:52.031018 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Aug 5 21:43:52.031028 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Aug 5 21:43:52.031038 kernel: audit: type=1403 audit(1722894231.376:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Aug 5 21:43:52.031051 systemd[1]: Successfully loaded SELinux policy in 34.565ms. Aug 5 21:43:52.031064 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 12.898ms. Aug 5 21:43:52.031076 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) Aug 5 21:43:52.031089 systemd[1]: Detected virtualization kvm. Aug 5 21:43:52.031099 systemd[1]: Detected architecture arm64. Aug 5 21:43:52.031110 systemd[1]: Detected first boot. Aug 5 21:43:52.031120 systemd[1]: Initializing machine ID from VM UUID. Aug 5 21:43:52.031133 zram_generator::config[1050]: No configuration found. Aug 5 21:43:52.031145 systemd[1]: Populated /etc with preset unit settings. Aug 5 21:43:52.031159 systemd[1]: initrd-switch-root.service: Deactivated successfully. Aug 5 21:43:52.031169 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Aug 5 21:43:52.031180 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Aug 5 21:43:52.031191 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Aug 5 21:43:52.031203 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Aug 5 21:43:52.031216 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Aug 5 21:43:52.031226 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Aug 5 21:43:52.031237 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Aug 5 21:43:52.031248 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Aug 5 21:43:52.031259 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Aug 5 21:43:52.031269 systemd[1]: Created slice user.slice - User and Session Slice. Aug 5 21:43:52.031280 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Aug 5 21:43:52.031291 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Aug 5 21:43:52.031302 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Aug 5 21:43:52.031314 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Aug 5 21:43:52.031325 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Aug 5 21:43:52.031335 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Aug 5 21:43:52.031346 systemd[1]: Expecting device dev-ttyAMA0.device - /dev/ttyAMA0... Aug 5 21:43:52.031358 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Aug 5 21:43:52.031374 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Aug 5 21:43:52.031385 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Aug 5 21:43:52.031395 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Aug 5 21:43:52.031407 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Aug 5 21:43:52.031418 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Aug 5 21:43:52.031429 systemd[1]: Reached target remote-fs.target - Remote File Systems. Aug 5 21:43:52.031439 systemd[1]: Reached target slices.target - Slice Units. Aug 5 21:43:52.031450 systemd[1]: Reached target swap.target - Swaps. Aug 5 21:43:52.031460 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Aug 5 21:43:52.031471 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Aug 5 21:43:52.031485 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Aug 5 21:43:52.031497 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Aug 5 21:43:52.031508 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Aug 5 21:43:52.031519 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Aug 5 21:43:52.031530 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Aug 5 21:43:52.031540 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Aug 5 21:43:52.031551 systemd[1]: Mounting media.mount - External Media Directory... Aug 5 21:43:52.031561 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Aug 5 21:43:52.031572 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Aug 5 21:43:52.031590 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Aug 5 21:43:52.031604 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). Aug 5 21:43:52.031615 systemd[1]: Reached target machines.target - Containers. Aug 5 21:43:52.031631 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Aug 5 21:43:52.031645 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 5 21:43:52.031656 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Aug 5 21:43:52.031667 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Aug 5 21:43:52.031678 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 5 21:43:52.031688 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Aug 5 21:43:52.031701 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 5 21:43:52.031712 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Aug 5 21:43:52.031722 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 5 21:43:52.031733 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Aug 5 21:43:52.031744 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Aug 5 21:43:52.031754 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Aug 5 21:43:52.031764 kernel: fuse: init (API version 7.39) Aug 5 21:43:52.031774 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Aug 5 21:43:52.031785 systemd[1]: Stopped systemd-fsck-usr.service. Aug 5 21:43:52.031796 kernel: loop: module loaded Aug 5 21:43:52.031806 systemd[1]: Starting systemd-journald.service - Journal Service... Aug 5 21:43:52.031817 kernel: ACPI: bus type drm_connector registered Aug 5 21:43:52.031827 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Aug 5 21:43:52.031838 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Aug 5 21:43:52.031849 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Aug 5 21:43:52.031860 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Aug 5 21:43:52.031871 systemd[1]: verity-setup.service: Deactivated successfully. Aug 5 21:43:52.031881 systemd[1]: Stopped verity-setup.service. Aug 5 21:43:52.031915 systemd-journald[1116]: Collecting audit messages is disabled. Aug 5 21:43:52.031938 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Aug 5 21:43:52.031950 systemd-journald[1116]: Journal started Aug 5 21:43:52.031971 systemd-journald[1116]: Runtime Journal (/run/log/journal/8ec3a2822927461fbf2657c77bc9ea99) is 5.9M, max 47.3M, 41.4M free. Aug 5 21:43:51.800803 systemd[1]: Queued start job for default target multi-user.target. Aug 5 21:43:51.816315 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Aug 5 21:43:51.816731 systemd[1]: systemd-journald.service: Deactivated successfully. Aug 5 21:43:52.033778 systemd[1]: Started systemd-journald.service - Journal Service. Aug 5 21:43:52.034478 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Aug 5 21:43:52.035720 systemd[1]: Mounted media.mount - External Media Directory. Aug 5 21:43:52.036757 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Aug 5 21:43:52.037899 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Aug 5 21:43:52.039041 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Aug 5 21:43:52.041622 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Aug 5 21:43:52.042991 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Aug 5 21:43:52.044550 systemd[1]: modprobe@configfs.service: Deactivated successfully. Aug 5 21:43:52.044743 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Aug 5 21:43:52.046210 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 5 21:43:52.046364 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 5 21:43:52.049888 systemd[1]: modprobe@drm.service: Deactivated successfully. Aug 5 21:43:52.050040 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Aug 5 21:43:52.051578 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 5 21:43:52.051755 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 5 21:43:52.053216 systemd[1]: modprobe@fuse.service: Deactivated successfully. Aug 5 21:43:52.053353 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Aug 5 21:43:52.054831 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 5 21:43:52.054982 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 5 21:43:52.056647 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Aug 5 21:43:52.058130 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Aug 5 21:43:52.059765 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Aug 5 21:43:52.074661 systemd[1]: Reached target network-pre.target - Preparation for Network. Aug 5 21:43:52.088753 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Aug 5 21:43:52.091120 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Aug 5 21:43:52.092269 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Aug 5 21:43:52.092312 systemd[1]: Reached target local-fs.target - Local File Systems. Aug 5 21:43:52.094642 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Aug 5 21:43:52.096939 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Aug 5 21:43:52.099429 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Aug 5 21:43:52.100667 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 5 21:43:52.102385 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Aug 5 21:43:52.104802 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Aug 5 21:43:52.106099 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Aug 5 21:43:52.110484 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Aug 5 21:43:52.114719 systemd-journald[1116]: Time spent on flushing to /var/log/journal/8ec3a2822927461fbf2657c77bc9ea99 is 26ms for 826 entries. Aug 5 21:43:52.114719 systemd-journald[1116]: System Journal (/var/log/journal/8ec3a2822927461fbf2657c77bc9ea99) is 8.0M, max 195.6M, 187.6M free. Aug 5 21:43:52.147672 systemd-journald[1116]: Received client request to flush runtime journal. Aug 5 21:43:52.147734 kernel: loop0: detected capacity change from 0 to 59688 Aug 5 21:43:52.147748 kernel: block loop0: the capability attribute has been deprecated. Aug 5 21:43:52.111718 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Aug 5 21:43:52.114554 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Aug 5 21:43:52.117870 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Aug 5 21:43:52.123698 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Aug 5 21:43:52.126527 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Aug 5 21:43:52.128063 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Aug 5 21:43:52.129687 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Aug 5 21:43:52.131368 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Aug 5 21:43:52.135716 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Aug 5 21:43:52.143033 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Aug 5 21:43:52.154603 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Aug 5 21:43:52.155324 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Aug 5 21:43:52.160814 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Aug 5 21:43:52.162551 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Aug 5 21:43:52.165142 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Aug 5 21:43:52.177798 systemd-tmpfiles[1161]: ACLs are not supported, ignoring. Aug 5 21:43:52.178190 systemd-tmpfiles[1161]: ACLs are not supported, ignoring. Aug 5 21:43:52.178439 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Aug 5 21:43:52.181916 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Aug 5 21:43:52.183672 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Aug 5 21:43:52.186614 kernel: loop1: detected capacity change from 0 to 113712 Aug 5 21:43:52.191928 systemd[1]: Starting systemd-sysusers.service - Create System Users... Aug 5 21:43:52.193781 udevadm[1173]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Aug 5 21:43:52.213759 systemd[1]: Finished systemd-sysusers.service - Create System Users. Aug 5 21:43:52.220846 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Aug 5 21:43:52.224604 kernel: loop2: detected capacity change from 0 to 59688 Aug 5 21:43:52.232679 kernel: loop3: detected capacity change from 0 to 113712 Aug 5 21:43:52.234558 (sd-merge)[1185]: Using extensions 'containerd-flatcar', 'docker-flatcar'. Aug 5 21:43:52.234941 (sd-merge)[1185]: Merged extensions into '/usr'. Aug 5 21:43:52.240699 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Aug 5 21:43:52.244495 systemd-tmpfiles[1184]: ACLs are not supported, ignoring. Aug 5 21:43:52.244511 systemd-tmpfiles[1184]: ACLs are not supported, ignoring. Aug 5 21:43:52.255956 systemd[1]: Starting ensure-sysext.service... Aug 5 21:43:52.260879 systemd[1]: Starting systemd-tmpfiles-setup.service - Create Volatile Files and Directories... Aug 5 21:43:52.264262 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Aug 5 21:43:52.278679 systemd[1]: Reloading requested from client PID 1188 ('systemctl') (unit ensure-sysext.service)... Aug 5 21:43:52.278702 systemd[1]: Reloading... Aug 5 21:43:52.296941 systemd-tmpfiles[1189]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Aug 5 21:43:52.297148 systemd-tmpfiles[1189]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Aug 5 21:43:52.298199 systemd-tmpfiles[1189]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Aug 5 21:43:52.298523 systemd-tmpfiles[1189]: ACLs are not supported, ignoring. Aug 5 21:43:52.298678 systemd-tmpfiles[1189]: ACLs are not supported, ignoring. Aug 5 21:43:52.301109 systemd-tmpfiles[1189]: Detected autofs mount point /boot during canonicalization of boot. Aug 5 21:43:52.301237 systemd-tmpfiles[1189]: Skipping /boot Aug 5 21:43:52.307822 systemd-tmpfiles[1189]: Detected autofs mount point /boot during canonicalization of boot. Aug 5 21:43:52.307833 systemd-tmpfiles[1189]: Skipping /boot Aug 5 21:43:52.341616 zram_generator::config[1218]: No configuration found. Aug 5 21:43:52.399736 ldconfig[1155]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Aug 5 21:43:52.444236 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. Aug 5 21:43:52.483007 systemd[1]: Reloading finished in 203 ms. Aug 5 21:43:52.508548 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Aug 5 21:43:52.510749 systemd[1]: Finished systemd-tmpfiles-setup.service - Create Volatile Files and Directories. Aug 5 21:43:52.516777 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Aug 5 21:43:52.520754 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Aug 5 21:43:52.523875 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Aug 5 21:43:52.529817 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Aug 5 21:43:52.534767 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Aug 5 21:43:52.547963 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 5 21:43:52.556274 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 5 21:43:52.558882 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 5 21:43:52.566087 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 5 21:43:52.567229 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 5 21:43:52.570864 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Aug 5 21:43:52.573377 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 5 21:43:52.576092 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 5 21:43:52.577964 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Aug 5 21:43:52.584864 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 5 21:43:52.585054 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 5 21:43:52.587565 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 5 21:43:52.587758 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 5 21:43:52.593757 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 5 21:43:52.610923 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 5 21:43:52.613136 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 5 21:43:52.615249 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 5 21:43:52.616276 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 5 21:43:52.617220 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Aug 5 21:43:52.619130 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Aug 5 21:43:52.620901 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Aug 5 21:43:52.622779 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 5 21:43:52.622908 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 5 21:43:52.624415 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 5 21:43:52.624537 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 5 21:43:52.626262 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 5 21:43:52.626387 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 5 21:43:52.636479 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Aug 5 21:43:52.653195 augenrules[1288]: No rules Aug 5 21:43:52.654944 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Aug 5 21:43:52.660009 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Aug 5 21:43:52.662221 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Aug 5 21:43:52.666288 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Aug 5 21:43:52.667476 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Aug 5 21:43:52.669119 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Aug 5 21:43:52.671463 systemd[1]: Starting systemd-update-done.service - Update is Completed... Aug 5 21:43:52.672597 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). Aug 5 21:43:52.673571 systemd[1]: Started systemd-userdbd.service - User Database Manager. Aug 5 21:43:52.675521 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Aug 5 21:43:52.677107 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Aug 5 21:43:52.677244 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Aug 5 21:43:52.678963 systemd[1]: modprobe@drm.service: Deactivated successfully. Aug 5 21:43:52.679100 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Aug 5 21:43:52.682211 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Aug 5 21:43:52.682348 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Aug 5 21:43:52.684096 systemd[1]: modprobe@loop.service: Deactivated successfully. Aug 5 21:43:52.684222 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Aug 5 21:43:52.692623 systemd[1]: Finished ensure-sysext.service. Aug 5 21:43:52.693796 systemd[1]: Finished systemd-update-done.service - Update is Completed. Aug 5 21:43:52.695949 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Aug 5 21:43:52.696090 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Aug 5 21:43:52.698652 systemd-udevd[1302]: Using default interface naming scheme 'v255'. Aug 5 21:43:52.708777 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Aug 5 21:43:52.715801 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Aug 5 21:43:52.720906 systemd-resolved[1256]: Positive Trust Anchors: Aug 5 21:43:52.720919 systemd-resolved[1256]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Aug 5 21:43:52.720950 systemd-resolved[1256]: 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 Aug 5 21:43:52.729857 systemd-resolved[1256]: Defaulting to hostname 'linux'. Aug 5 21:43:52.745799 systemd[1]: Starting systemd-networkd.service - Network Configuration... Aug 5 21:43:52.747023 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Aug 5 21:43:52.750602 kernel: BTRFS info: devid 1 device path /dev/mapper/usr changed to /dev/dm-0 scanned by (udev-worker) (1315) Aug 5 21:43:52.751121 systemd[1]: Condition check resulted in dev-ttyAMA0.device - /dev/ttyAMA0 being skipped. Aug 5 21:43:52.751613 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Aug 5 21:43:52.778264 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Aug 5 21:43:52.784120 systemd[1]: Reached target time-set.target - System Time Set. Aug 5 21:43:52.789655 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 38 scanned by (udev-worker) (1319) Aug 5 21:43:52.815136 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Aug 5 21:43:52.831223 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Aug 5 21:43:52.855969 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Aug 5 21:43:52.857342 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Aug 5 21:43:52.862073 systemd-networkd[1320]: lo: Link UP Aug 5 21:43:52.862084 systemd-networkd[1320]: lo: Gained carrier Aug 5 21:43:52.868768 systemd-networkd[1320]: Enumeration completed Aug 5 21:43:52.868886 systemd[1]: Started systemd-networkd.service - Network Configuration. Aug 5 21:43:52.870135 systemd[1]: Reached target network.target - Network. Aug 5 21:43:52.873861 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Aug 5 21:43:52.881957 systemd-networkd[1320]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 5 21:43:52.881968 systemd-networkd[1320]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Aug 5 21:43:52.882952 systemd-networkd[1320]: eth0: Link UP Aug 5 21:43:52.882955 systemd-networkd[1320]: eth0: Gained carrier Aug 5 21:43:52.882972 systemd-networkd[1320]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Aug 5 21:43:52.895030 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Aug 5 21:43:52.911649 systemd-networkd[1320]: eth0: DHCPv4 address 10.0.0.66/16, gateway 10.0.0.1 acquired from 10.0.0.1 Aug 5 21:43:52.912513 systemd-timesyncd[1311]: Network configuration changed, trying to establish connection. Aug 5 21:43:52.913107 systemd-timesyncd[1311]: Contacted time server 10.0.0.1:123 (10.0.0.1). Aug 5 21:43:52.913153 systemd-timesyncd[1311]: Initial clock synchronization to Mon 2024-08-05 21:43:52.514927 UTC. Aug 5 21:43:52.914212 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Aug 5 21:43:52.952378 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Aug 5 21:43:52.954904 lvm[1350]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Aug 5 21:43:52.996116 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Aug 5 21:43:52.997565 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Aug 5 21:43:52.998635 systemd[1]: Reached target sysinit.target - System Initialization. Aug 5 21:43:52.999732 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Aug 5 21:43:53.000829 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Aug 5 21:43:53.001960 systemd[1]: Started logrotate.timer - Daily rotation of log files. Aug 5 21:43:53.003083 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Aug 5 21:43:53.004266 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Aug 5 21:43:53.005463 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Aug 5 21:43:53.005495 systemd[1]: Reached target paths.target - Path Units. Aug 5 21:43:53.006356 systemd[1]: Reached target timers.target - Timer Units. Aug 5 21:43:53.008010 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Aug 5 21:43:53.010275 systemd[1]: Starting docker.socket - Docker Socket for the API... Aug 5 21:43:53.018430 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Aug 5 21:43:53.020710 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Aug 5 21:43:53.022354 systemd[1]: Listening on docker.socket - Docker Socket for the API. Aug 5 21:43:53.023334 systemd[1]: Reached target sockets.target - Socket Units. Aug 5 21:43:53.024245 systemd[1]: Reached target basic.target - Basic System. Aug 5 21:43:53.025042 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Aug 5 21:43:53.025069 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Aug 5 21:43:53.025938 systemd[1]: Starting containerd.service - containerd container runtime... Aug 5 21:43:53.027773 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Aug 5 21:43:53.029032 lvm[1357]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Aug 5 21:43:53.031214 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Aug 5 21:43:53.034366 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Aug 5 21:43:53.035914 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). Aug 5 21:43:53.040572 jq[1360]: false Aug 5 21:43:53.039558 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Aug 5 21:43:53.041888 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Aug 5 21:43:53.047734 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Aug 5 21:43:53.050210 extend-filesystems[1361]: Found loop2 Aug 5 21:43:53.052645 extend-filesystems[1361]: Found loop3 Aug 5 21:43:53.052645 extend-filesystems[1361]: Found vda Aug 5 21:43:53.052645 extend-filesystems[1361]: Found vda1 Aug 5 21:43:53.052645 extend-filesystems[1361]: Found vda2 Aug 5 21:43:53.052645 extend-filesystems[1361]: Found vda3 Aug 5 21:43:53.052645 extend-filesystems[1361]: Found usr Aug 5 21:43:53.052645 extend-filesystems[1361]: Found vda4 Aug 5 21:43:53.052645 extend-filesystems[1361]: Found vda6 Aug 5 21:43:53.052645 extend-filesystems[1361]: Found vda7 Aug 5 21:43:53.052645 extend-filesystems[1361]: Found vda9 Aug 5 21:43:53.052645 extend-filesystems[1361]: Checking size of /dev/vda9 Aug 5 21:43:53.070103 dbus-daemon[1359]: [system] SELinux support is enabled Aug 5 21:43:53.053821 systemd[1]: Starting systemd-logind.service - User Login Management... Aug 5 21:43:53.057198 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Aug 5 21:43:53.058651 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Aug 5 21:43:53.059276 systemd[1]: Starting update-engine.service - Update Engine... Aug 5 21:43:53.074673 jq[1375]: true Aug 5 21:43:53.061704 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Aug 5 21:43:53.066321 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Aug 5 21:43:53.073788 systemd[1]: Started dbus.service - D-Bus System Message Bus. Aug 5 21:43:53.076527 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Aug 5 21:43:53.077045 extend-filesystems[1361]: Old size kept for /dev/vda9 Aug 5 21:43:53.077077 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Aug 5 21:43:53.077326 systemd[1]: motdgen.service: Deactivated successfully. Aug 5 21:43:53.079627 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Aug 5 21:43:53.081206 systemd[1]: extend-filesystems.service: Deactivated successfully. Aug 5 21:43:53.081341 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Aug 5 21:43:53.082609 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Aug 5 21:43:53.082744 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Aug 5 21:43:53.089619 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 38 scanned by (udev-worker) (1319) Aug 5 21:43:53.098062 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). Aug 5 21:43:53.098092 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Aug 5 21:43:53.100918 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). Aug 5 21:43:53.100936 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Aug 5 21:43:53.107103 update_engine[1373]: I0805 21:43:53.106918 1373 main.cc:92] Flatcar Update Engine starting Aug 5 21:43:53.108527 update_engine[1373]: I0805 21:43:53.108497 1373 update_check_scheduler.cc:74] Next update check in 2m51s Aug 5 21:43:53.111459 systemd-logind[1372]: Watching system buttons on /dev/input/event0 (Power Button) Aug 5 21:43:53.113121 systemd-logind[1372]: New seat seat0. Aug 5 21:43:53.115968 systemd[1]: Started systemd-logind.service - User Login Management. Aug 5 21:43:53.118148 systemd[1]: Started update-engine.service - Update Engine. Aug 5 21:43:53.119046 jq[1382]: true Aug 5 21:43:53.122318 (ntainerd)[1392]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Aug 5 21:43:53.128791 systemd[1]: Started locksmithd.service - Cluster reboot manager. Aug 5 21:43:53.166663 bash[1409]: Updated "/home/core/.ssh/authorized_keys" Aug 5 21:43:53.168996 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Aug 5 21:43:53.170711 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Aug 5 21:43:53.177854 locksmithd[1399]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="etcd-lock" Aug 5 21:43:53.301225 containerd[1392]: time="2024-08-05T21:43:53.301151114Z" level=info msg="starting containerd" revision=cd7148ac666309abf41fd4a49a8a5895b905e7f3 version=v1.7.18 Aug 5 21:43:53.325084 containerd[1392]: time="2024-08-05T21:43:53.325028744Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Aug 5 21:43:53.325183 containerd[1392]: time="2024-08-05T21:43:53.325164248Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Aug 5 21:43:53.326427 containerd[1392]: time="2024-08-05T21:43:53.326388727Z" 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.43-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Aug 5 21:43:53.326427 containerd[1392]: time="2024-08-05T21:43:53.326420465Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Aug 5 21:43:53.326697 containerd[1392]: time="2024-08-05T21:43:53.326663726Z" 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 Aug 5 21:43:53.326782 containerd[1392]: time="2024-08-05T21:43:53.326762209Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Aug 5 21:43:53.326899 containerd[1392]: time="2024-08-05T21:43:53.326875174Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Aug 5 21:43:53.326940 containerd[1392]: time="2024-08-05T21:43:53.326925727Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Aug 5 21:43:53.326960 containerd[1392]: time="2024-08-05T21:43:53.326940892Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Aug 5 21:43:53.327007 containerd[1392]: time="2024-08-05T21:43:53.326995322Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Aug 5 21:43:53.327195 containerd[1392]: time="2024-08-05T21:43:53.327170128Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Aug 5 21:43:53.327215 containerd[1392]: time="2024-08-05T21:43:53.327196127Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" Aug 5 21:43:53.327215 containerd[1392]: time="2024-08-05T21:43:53.327207074Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Aug 5 21:43:53.327331 containerd[1392]: time="2024-08-05T21:43:53.327314071Z" 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 Aug 5 21:43:53.327352 containerd[1392]: time="2024-08-05T21:43:53.327332125Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Aug 5 21:43:53.327407 containerd[1392]: time="2024-08-05T21:43:53.327381310Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" Aug 5 21:43:53.327407 containerd[1392]: time="2024-08-05T21:43:53.327392751Z" level=info msg="metadata content store policy set" policy=shared Aug 5 21:43:53.330282 containerd[1392]: time="2024-08-05T21:43:53.330253011Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Aug 5 21:43:53.330319 containerd[1392]: time="2024-08-05T21:43:53.330284901Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Aug 5 21:43:53.330319 containerd[1392]: time="2024-08-05T21:43:53.330298736Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Aug 5 21:43:53.330352 containerd[1392]: time="2024-08-05T21:43:53.330328308Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Aug 5 21:43:53.330352 containerd[1392]: time="2024-08-05T21:43:53.330342371Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Aug 5 21:43:53.330409 containerd[1392]: time="2024-08-05T21:43:53.330352368Z" level=info msg="NRI interface is disabled by configuration." Aug 5 21:43:53.330409 containerd[1392]: time="2024-08-05T21:43:53.330371107Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Aug 5 21:43:53.330521 containerd[1392]: time="2024-08-05T21:43:53.330502544Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Aug 5 21:43:53.330542 containerd[1392]: time="2024-08-05T21:43:53.330526376Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Aug 5 21:43:53.330559 containerd[1392]: time="2024-08-05T21:43:53.330546787Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Aug 5 21:43:53.330579 containerd[1392]: time="2024-08-05T21:43:53.330560280Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Aug 5 21:43:53.330610 containerd[1392]: time="2024-08-05T21:43:53.330573546Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Aug 5 21:43:53.330610 containerd[1392]: time="2024-08-05T21:43:53.330603117Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Aug 5 21:43:53.330646 containerd[1392]: time="2024-08-05T21:43:53.330616953Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Aug 5 21:43:53.330646 containerd[1392]: time="2024-08-05T21:43:53.330629382Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Aug 5 21:43:53.330678 containerd[1392]: time="2024-08-05T21:43:53.330642609Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Aug 5 21:43:53.330678 containerd[1392]: time="2024-08-05T21:43:53.330672713Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Aug 5 21:43:53.330712 containerd[1392]: time="2024-08-05T21:43:53.330684306Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Aug 5 21:43:53.330712 containerd[1392]: time="2024-08-05T21:43:53.330695671Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Aug 5 21:43:53.330808 containerd[1392]: time="2024-08-05T21:43:53.330791265Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Aug 5 21:43:53.331087 containerd[1392]: time="2024-08-05T21:43:53.331073562Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Aug 5 21:43:53.331110 containerd[1392]: time="2024-08-05T21:43:53.331100777Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Aug 5 21:43:53.331131 containerd[1392]: time="2024-08-05T21:43:53.331114460Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Aug 5 21:43:53.331153 containerd[1392]: time="2024-08-05T21:43:53.331138064Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Aug 5 21:43:53.331263 containerd[1392]: time="2024-08-05T21:43:53.331249965Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Aug 5 21:43:53.331283 containerd[1392]: time="2024-08-05T21:43:53.331267449Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Aug 5 21:43:53.331301 containerd[1392]: time="2024-08-05T21:43:53.331280562Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Aug 5 21:43:53.331301 containerd[1392]: time="2024-08-05T21:43:53.331292763Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Aug 5 21:43:53.331339 containerd[1392]: time="2024-08-05T21:43:53.331312110Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Aug 5 21:43:53.331339 containerd[1392]: time="2024-08-05T21:43:53.331326288Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Aug 5 21:43:53.331339 containerd[1392]: time="2024-08-05T21:43:53.331337653Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Aug 5 21:43:53.331391 containerd[1392]: time="2024-08-05T21:43:53.331349246Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Aug 5 21:43:53.331391 containerd[1392]: time="2024-08-05T21:43:53.331361751Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Aug 5 21:43:53.331508 containerd[1392]: time="2024-08-05T21:43:53.331490983Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Aug 5 21:43:53.331528 containerd[1392]: time="2024-08-05T21:43:53.331519833Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Aug 5 21:43:53.331553 containerd[1392]: time="2024-08-05T21:43:53.331542334Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Aug 5 21:43:53.331699 containerd[1392]: time="2024-08-05T21:43:53.331685859Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Aug 5 21:43:53.331719 containerd[1392]: time="2024-08-05T21:43:53.331706042Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Aug 5 21:43:53.331743 containerd[1392]: time="2024-08-05T21:43:53.331720789Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Aug 5 21:43:53.331743 containerd[1392]: time="2024-08-05T21:43:53.331732838Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Aug 5 21:43:53.331776 containerd[1392]: time="2024-08-05T21:43:53.331742949Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Aug 5 21:43:53.332074 containerd[1392]: time="2024-08-05T21:43:53.332022472Z" 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}" Aug 5 21:43:53.332174 containerd[1392]: time="2024-08-05T21:43:53.332083781Z" level=info msg="Connect containerd service" Aug 5 21:43:53.332174 containerd[1392]: time="2024-08-05T21:43:53.332110806Z" level=info msg="using legacy CRI server" Aug 5 21:43:53.332174 containerd[1392]: time="2024-08-05T21:43:53.332117306Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Aug 5 21:43:53.332266 containerd[1392]: time="2024-08-05T21:43:53.332246424Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Aug 5 21:43:53.332878 containerd[1392]: time="2024-08-05T21:43:53.332842491Z" 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" Aug 5 21:43:53.332902 containerd[1392]: time="2024-08-05T21:43:53.332894032Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Aug 5 21:43:53.332929 containerd[1392]: time="2024-08-05T21:43:53.332911440Z" 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 Aug 5 21:43:53.332929 containerd[1392]: time="2024-08-05T21:43:53.332921741Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Aug 5 21:43:53.332964 containerd[1392]: time="2024-08-05T21:43:53.332933486Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Aug 5 21:43:53.333091 containerd[1392]: time="2024-08-05T21:43:53.332981112Z" level=info msg="Start subscribing containerd event" Aug 5 21:43:53.333113 containerd[1392]: time="2024-08-05T21:43:53.333099398Z" level=info msg="Start recovering state" Aug 5 21:43:53.333280 containerd[1392]: time="2024-08-05T21:43:53.333252272Z" level=info msg="Start event monitor" Aug 5 21:43:53.333280 containerd[1392]: time="2024-08-05T21:43:53.333270517Z" level=info msg="Start snapshots syncer" Aug 5 21:43:53.333280 containerd[1392]: time="2024-08-05T21:43:53.333280323Z" level=info msg="Start cni network conf syncer for default" Aug 5 21:43:53.333349 containerd[1392]: time="2024-08-05T21:43:53.333289294Z" level=info msg="Start streaming server" Aug 5 21:43:53.334751 containerd[1392]: time="2024-08-05T21:43:53.333396367Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Aug 5 21:43:53.334751 containerd[1392]: time="2024-08-05T21:43:53.333442396Z" level=info msg=serving... address=/run/containerd/containerd.sock Aug 5 21:43:53.334751 containerd[1392]: time="2024-08-05T21:43:53.333491543Z" level=info msg="containerd successfully booted in 0.035136s" Aug 5 21:43:53.333600 systemd[1]: Started containerd.service - containerd container runtime. Aug 5 21:43:53.885909 sshd_keygen[1381]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Aug 5 21:43:53.907651 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Aug 5 21:43:53.915859 systemd[1]: Starting issuegen.service - Generate /run/issue... Aug 5 21:43:53.922764 systemd[1]: issuegen.service: Deactivated successfully. Aug 5 21:43:53.923667 systemd[1]: Finished issuegen.service - Generate /run/issue. Aug 5 21:43:53.926275 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Aug 5 21:43:53.940030 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Aug 5 21:43:53.950892 systemd[1]: Started getty@tty1.service - Getty on tty1. Aug 5 21:43:53.953083 systemd[1]: Started serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0. Aug 5 21:43:53.954463 systemd[1]: Reached target getty.target - Login Prompts. Aug 5 21:43:54.506726 systemd-networkd[1320]: eth0: Gained IPv6LL Aug 5 21:43:54.509118 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Aug 5 21:43:54.511189 systemd[1]: Reached target network-online.target - Network is Online. Aug 5 21:43:54.528081 systemd[1]: Starting coreos-metadata.service - QEMU metadata agent... Aug 5 21:43:54.530194 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Aug 5 21:43:54.550750 systemd[1]: coreos-metadata.service: Deactivated successfully. Aug 5 21:43:54.551088 systemd[1]: Finished coreos-metadata.service - QEMU metadata agent. Aug 5 21:43:54.553033 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Aug 5 21:43:54.578362 (-wrapper)[1455]: etcd-member.service: Referenced but unset environment variable evaluates to an empty string: ETCD_OPTS Aug 5 21:43:54.581694 systemd[1]: Starting etcd-member.service - etcd (System Application Container)... Aug 5 21:43:54.582916 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Aug 5 21:43:54.720799 systemd[1]: Starting docker.service - Docker Application Container Engine... Aug 5 21:43:54.720968 (dockerd)[1465]: 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 Aug 5 21:43:54.973003 dockerd[1465]: time="2024-08-05T21:43:54.972893202Z" level=info msg="Starting up" Aug 5 21:43:55.003937 dockerd[1465]: time="2024-08-05T21:43:55.003897749Z" level=info msg="Loading containers: start." Aug 5 21:43:55.079606 kernel: Initializing XFRM netlink socket Aug 5 21:43:55.140925 systemd-networkd[1320]: docker0: Link UP Aug 5 21:43:55.156965 dockerd[1465]: time="2024-08-05T21:43:55.156912345Z" level=info msg="Loading containers: done." Aug 5 21:43:55.210956 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck207095910-merged.mount: Deactivated successfully. Aug 5 21:43:55.211576 dockerd[1465]: time="2024-08-05T21:43:55.211192501Z" 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 Aug 5 21:43:55.211576 dockerd[1465]: time="2024-08-05T21:43:55.211405313Z" level=info msg="Docker daemon" commit=fca702de7f71362c8d103073c7e4a1d0a467fadd graphdriver=overlay2 version=24.0.9 Aug 5 21:43:55.211576 dockerd[1465]: time="2024-08-05T21:43:55.211521972Z" level=info msg="Daemon has completed initialization" Aug 5 21:43:55.235844 dockerd[1465]: time="2024-08-05T21:43:55.235653335Z" level=info msg="API listen on /run/docker.sock" Aug 5 21:43:55.235834 systemd[1]: Started docker.service - Docker Application Container Engine. Aug 5 21:43:55.237381 etcd-wrapper[1459]: Error response from daemon: No such container: etcd-member Aug 5 21:43:55.249176 etcd-wrapper[1579]: Error response from daemon: No such container: etcd-member Aug 5 21:43:55.276069 etcd-wrapper[1596]: Unable to find image 'quay.io/coreos/etcd:v3.5.0' locally Aug 5 21:43:55.976149 etcd-wrapper[1596]: v3.5.0: Pulling from coreos/etcd Aug 5 21:43:56.154622 etcd-wrapper[1596]: 6022c6b01d76: Pulling fs layer Aug 5 21:43:56.154622 etcd-wrapper[1596]: 1d97b1ec77fc: Pulling fs layer Aug 5 21:43:56.154622 etcd-wrapper[1596]: 99ef4b690837: Pulling fs layer Aug 5 21:43:56.154622 etcd-wrapper[1596]: 435b7bf0967c: Pulling fs layer Aug 5 21:43:56.154622 etcd-wrapper[1596]: 17e4a5f7c1b5: Pulling fs layer Aug 5 21:43:56.154835 etcd-wrapper[1596]: 5b0292999756: Pulling fs layer Aug 5 21:43:56.154835 etcd-wrapper[1596]: 435b7bf0967c: Waiting Aug 5 21:43:56.154835 etcd-wrapper[1596]: 17e4a5f7c1b5: Waiting Aug 5 21:43:56.154835 etcd-wrapper[1596]: 5b0292999756: Waiting Aug 5 21:43:56.374384 etcd-wrapper[1596]: 1d97b1ec77fc: Verifying Checksum Aug 5 21:43:56.374384 etcd-wrapper[1596]: 1d97b1ec77fc: Download complete Aug 5 21:43:56.414792 etcd-wrapper[1596]: 99ef4b690837: Verifying Checksum Aug 5 21:43:56.414792 etcd-wrapper[1596]: 99ef4b690837: Download complete Aug 5 21:43:56.563827 etcd-wrapper[1596]: 6022c6b01d76: Download complete Aug 5 21:43:56.589179 etcd-wrapper[1596]: 435b7bf0967c: Verifying Checksum Aug 5 21:43:56.589179 etcd-wrapper[1596]: 435b7bf0967c: Download complete Aug 5 21:43:56.628116 etcd-wrapper[1596]: 17e4a5f7c1b5: Verifying Checksum Aug 5 21:43:56.628116 etcd-wrapper[1596]: 17e4a5f7c1b5: Download complete Aug 5 21:43:56.766841 etcd-wrapper[1596]: 5b0292999756: Verifying Checksum Aug 5 21:43:56.766989 etcd-wrapper[1596]: 5b0292999756: Download complete Aug 5 21:43:57.090670 etcd-wrapper[1596]: 6022c6b01d76: Pull complete Aug 5 21:43:57.174134 systemd[1]: var-lib-docker-overlay2-b7740318a2d1cf99bd446c77b7dcf4256281bc01baf12b04370f35da364fb6a3-merged.mount: Deactivated successfully. Aug 5 21:43:57.305017 etcd-wrapper[1596]: 1d97b1ec77fc: Pull complete Aug 5 21:43:57.378642 systemd[1]: var-lib-docker-overlay2-40554afbb214f888479f1b99a8950778c87d45785a2fc712ad690a98079a2048-merged.mount: Deactivated successfully. Aug 5 21:43:57.407269 etcd-wrapper[1596]: 99ef4b690837: Pull complete Aug 5 21:43:57.498906 etcd-wrapper[1596]: 435b7bf0967c: Pull complete Aug 5 21:43:57.511229 etcd-wrapper[1596]: 17e4a5f7c1b5: Pull complete Aug 5 21:43:57.525498 etcd-wrapper[1596]: 5b0292999756: Pull complete Aug 5 21:43:57.530967 etcd-wrapper[1596]: Digest: sha256:28759af54acd6924b2191dc1a1d096e2fa2e219717a21b9d8edf89717db3631b Aug 5 21:43:57.533241 etcd-wrapper[1596]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.0 Aug 5 21:43:57.653251 containerd[1392]: time="2024-08-05T21:43:57.652719374Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Aug 5 21:43:57.653251 containerd[1392]: time="2024-08-05T21:43:57.653161025Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 5 21:43:57.653843 containerd[1392]: time="2024-08-05T21:43:57.653653392Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Aug 5 21:43:57.653843 containerd[1392]: time="2024-08-05T21:43:57.653678672Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Aug 5 21:43:57.672740 systemd[1]: Started docker-e2475d852d8494403289d6ad45062b8f59475796f78318665036dd4ccc67226a.scope - libcontainer container e2475d852d8494403289d6ad45062b8f59475796f78318665036dd4ccc67226a. Aug 5 21:43:57.683742 systemd-resolved[1256]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Aug 5 21:43:57.732088 etcd-wrapper[1596]: {"level":"info","ts":1722894237.7317963,"caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} Aug 5 21:43:57.732088 etcd-wrapper[1596]: {"level":"info","ts":1722894237.7318673,"caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"8ec3a2822927461fbf2657c77bc9ea99"} Aug 5 21:43:57.732088 etcd-wrapper[1596]: {"level":"warn","ts":1722894237.7318933,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} Aug 5 21:43:57.732088 etcd-wrapper[1596]: {"level":"warn","ts":1722894237.7319016,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.0"} Aug 5 21:43:57.732088 etcd-wrapper[1596]: {"level":"warn","ts":1722894237.731907,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} Aug 5 21:43:57.732088 etcd-wrapper[1596]: {"level":"warn","ts":1722894237.731911,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} Aug 5 21:43:57.732088 etcd-wrapper[1596]: {"level":"warn","ts":1722894237.7319787,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_UNSUPPORTED_ARCH=arm64"} Aug 5 21:43:57.732402 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.732Z","caller":"etcdmain/etcd.go:72","msg":"Running: ","args":["/usr/local/bin/etcd","--listen-peer-urls=http://10.0.0.66:2380","--listen-client-urls=http://0.0.0.0:2379","--initial-advertise-peer-urls=http://10.0.0.66:2380","--advertise-client-urls=http://10.0.0.66:2379","--discovery=http://10.0.0.1:35281/v2/keys/discovery/6406398761264087772","--enable-v2=true"]} Aug 5 21:43:57.732402 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.732Z","caller":"embed/etcd.go:131","msg":"configuring peer listeners","listen-peer-urls":["http://10.0.0.66:2380"]} Aug 5 21:43:57.732402 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.732Z","caller":"embed/etcd.go:139","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} Aug 5 21:43:57.732541 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.732Z","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":"8ec3a2822927461fbf2657c77bc9ea99","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.66:2380"],"listen-peer-urls":["http://10.0.0.66:2380"],"advertise-client-urls":["http://10.0.0.66:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"8ec3a2822927461fbf2657c77bc9ea99=http://10.0.0.66:2380","initial-cluster-state":"new","initial-cluster-token":"http://10.0.0.1:35281/v2/keys/discovery/6406398761264087772","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:35281/v2/keys/discovery/6406398761264087772","discovery-proxy":"","downgrade-check-interval":"5s"} Aug 5 21:43:57.734458 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.734Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"1.537137ms"} Aug 5 21:43:57.737564 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.737Z","caller":"v2discovery/discovery.go:342","msg":"found peer from discovery server","discovery-url":"http://10.0.0.1:35281","peer":"53de09afdd35ed69"} Aug 5 21:43:57.737564 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.737Z","caller":"v2discovery/discovery.go:342","msg":"found peer from discovery server","discovery-url":"http://10.0.0.1:35281","peer":"2b7c7906cc6724c2"} Aug 5 21:43:57.737564 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.737Z","caller":"v2discovery/discovery.go:336","msg":"found self from discovery server","discovery-url":"http://10.0.0.1:35281","self":"6549516508a93b94"} Aug 5 21:43:57.737564 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.737Z","caller":"v2discovery/discovery.go:378","msg":"found all needed peers from discovery server","discovery-url":"http://10.0.0.1:35281","found-peers":3} Aug 5 21:43:57.740111 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.739Z","caller":"etcdserver/raft.go:448","msg":"starting local member","local-member-id":"6549516508a93b94","cluster-id":"1fc0cec0a44bf954"} Aug 5 21:43:57.740269 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.740Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6549516508a93b94 switched to configuration voters=()"} Aug 5 21:43:57.740269 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.740Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6549516508a93b94 became follower at term 0"} Aug 5 21:43:57.740269 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.740Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 6549516508a93b94 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} Aug 5 21:43:57.740269 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.740Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6549516508a93b94 became follower at term 1"} Aug 5 21:43:57.740269 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.740Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6549516508a93b94 switched to configuration voters=(3133512510849098946)"} Aug 5 21:43:57.740269 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.740Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6549516508a93b94 switched to configuration voters=(3133512510849098946 6043278400913010025)"} Aug 5 21:43:57.740513 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.740Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6549516508a93b94 switched to configuration voters=(3133512510849098946 6043278400913010025 7298454165509454740)"} Aug 5 21:43:57.741638 etcd-wrapper[1596]: {"level":"warn","ts":"2024-08-05T21:43:57.741Z","caller":"auth/store.go:1220","msg":"simple token is not cryptographically signed"} Aug 5 21:43:57.742824 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.742Z","caller":"mvcc/kvstore.go:415","msg":"kvstore restored","current-rev":1} Aug 5 21:43:57.743361 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.743Z","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"} Aug 5 21:43:57.743779 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.743Z","caller":"rafthttp/peer.go:133","msg":"starting remote peer","remote-peer-id":"2b7c7906cc6724c2"} Aug 5 21:43:57.743908 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.743Z","caller":"rafthttp/pipeline.go:72","msg":"started HTTP pipelining with remote peer","local-member-id":"6549516508a93b94","remote-peer-id":"2b7c7906cc6724c2"} Aug 5 21:43:57.744738 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.744Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"6549516508a93b94","remote-peer-id":"2b7c7906cc6724c2"} Aug 5 21:43:57.745378 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.745Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"6549516508a93b94","remote-peer-id":"2b7c7906cc6724c2"} Aug 5 21:43:57.745513 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.745Z","caller":"rafthttp/peer.go:137","msg":"started remote peer","remote-peer-id":"2b7c7906cc6724c2"} Aug 5 21:43:57.745513 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.745Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"6549516508a93b94","remote-peer-id":"2b7c7906cc6724c2"} Aug 5 21:43:57.745513 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.745Z","caller":"rafthttp/transport.go:317","msg":"added remote peer","local-member-id":"6549516508a93b94","remote-peer-id":"2b7c7906cc6724c2","remote-peer-urls":["http://10.0.0.65:2380"]} Aug 5 21:43:57.745513 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.745Z","caller":"rafthttp/peer.go:133","msg":"starting remote peer","remote-peer-id":"53de09afdd35ed69"} Aug 5 21:43:57.745513 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.745Z","caller":"rafthttp/pipeline.go:72","msg":"started HTTP pipelining with remote peer","local-member-id":"6549516508a93b94","remote-peer-id":"53de09afdd35ed69"} Aug 5 21:43:57.746769 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.745Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream Message","local-member-id":"6549516508a93b94","remote-peer-id":"2b7c7906cc6724c2"} Aug 5 21:43:57.746769 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.746Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"6549516508a93b94","remote-peer-id":"53de09afdd35ed69"} Aug 5 21:43:57.746887 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.746Z","caller":"rafthttp/stream.go:169","msg":"started stream writer with remote peer","local-member-id":"6549516508a93b94","remote-peer-id":"53de09afdd35ed69"} Aug 5 21:43:57.747356 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.747Z","caller":"rafthttp/peer.go:137","msg":"started remote peer","remote-peer-id":"53de09afdd35ed69"} Aug 5 21:43:57.747356 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.747Z","caller":"rafthttp/transport.go:317","msg":"added remote peer","local-member-id":"6549516508a93b94","remote-peer-id":"53de09afdd35ed69","remote-peer-urls":["http://10.0.0.67:2380"]} Aug 5 21:43:57.747356 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.747Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream Message","local-member-id":"6549516508a93b94","remote-peer-id":"53de09afdd35ed69"} Aug 5 21:43:57.747356 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.747Z","caller":"rafthttp/stream.go:395","msg":"started stream reader with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"6549516508a93b94","remote-peer-id":"53de09afdd35ed69"} Aug 5 21:43:57.747502 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.747Z","caller":"etcdserver/server.go:843","msg":"starting etcd server","local-member-id":"6549516508a93b94","local-server-version":"3.5.0","cluster-version":"to_be_decided"} Aug 5 21:43:57.748169 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.747Z","caller":"etcdserver/server.go:744","msg":"starting initial election tick advance","election-ticks":10} Aug 5 21:43:57.748169 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6549516508a93b94 switched to configuration voters=(3133512510849098946 6043278400913010025 7298454165509454740)"} Aug 5 21:43:57.748277 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.748Z","caller":"membership/cluster.go:393","msg":"added member","cluster-id":"1fc0cec0a44bf954","local-member-id":"6549516508a93b94","added-peer-id":"2b7c7906cc6724c2","added-peer-peer-urls":["http://10.0.0.65:2380"]} Aug 5 21:43:57.748277 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6549516508a93b94 switched to configuration voters=(3133512510849098946 6043278400913010025 7298454165509454740)"} Aug 5 21:43:57.748535 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.748Z","caller":"membership/cluster.go:393","msg":"added member","cluster-id":"1fc0cec0a44bf954","local-member-id":"6549516508a93b94","added-peer-id":"53de09afdd35ed69","added-peer-peer-urls":["http://10.0.0.67:2380"]} Aug 5 21:43:57.749237 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.748Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6549516508a93b94 switched to configuration voters=(3133512510849098946 6043278400913010025 7298454165509454740)"} Aug 5 21:43:57.749237 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.748Z","caller":"membership/cluster.go:393","msg":"added member","cluster-id":"1fc0cec0a44bf954","local-member-id":"6549516508a93b94","added-peer-id":"6549516508a93b94","added-peer-peer-urls":["http://10.0.0.66:2380"]} Aug 5 21:43:57.749237 etcd-wrapper[1596]: {"level":"warn","ts":"2024-08-05T21:43:57.749Z","caller":"embed/etcd.go:700","msg":"Flag `enable-v2` is deprecated and will get removed in etcd 3.6."} Aug 5 21:43:57.749467 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.749Z","caller":"embed/etcd.go:276","msg":"now serving peer/client/metrics","local-member-id":"6549516508a93b94","initial-advertise-peer-urls":["http://10.0.0.66:2380"],"listen-peer-urls":["http://10.0.0.66:2380"],"advertise-client-urls":["http://10.0.0.66:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} Aug 5 21:43:57.749467 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.749Z","caller":"embed/etcd.go:580","msg":"serving peer traffic","address":"10.0.0.66:2380"} Aug 5 21:43:57.749467 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.749Z","caller":"embed/etcd.go:552","msg":"cmux::serve","address":"10.0.0.66:2380"} Aug 5 21:43:57.750018 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.749Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"6549516508a93b94","to":"2b7c7906cc6724c2","stream-type":"stream Message"} Aug 5 21:43:57.750018 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.749Z","caller":"rafthttp/peer_status.go:53","msg":"peer became active","peer-id":"2b7c7906cc6724c2"} Aug 5 21:43:57.750018 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.749Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream Message","local-member-id":"6549516508a93b94","remote-peer-id":"2b7c7906cc6724c2"} Aug 5 21:43:57.750362 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.750Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"6549516508a93b94","to":"53de09afdd35ed69","stream-type":"stream MsgApp v2"} Aug 5 21:43:57.750362 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.750Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"6549516508a93b94","to":"53de09afdd35ed69","stream-type":"stream Message"} Aug 5 21:43:57.750362 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.750Z","caller":"rafthttp/peer_status.go:53","msg":"peer became active","peer-id":"53de09afdd35ed69"} Aug 5 21:43:57.750362 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.750Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","local-member-id":"6549516508a93b94","remote-peer-id":"53de09afdd35ed69"} Aug 5 21:43:57.750362 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.750Z","caller":"rafthttp/stream.go:249","msg":"set message encoder","from":"6549516508a93b94","to":"2b7c7906cc6724c2","stream-type":"stream MsgApp v2"} Aug 5 21:43:57.750362 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.750Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream Message","local-member-id":"6549516508a93b94","remote-peer-id":"53de09afdd35ed69"} Aug 5 21:43:57.750362 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.750Z","caller":"rafthttp/stream.go:274","msg":"established TCP streaming connection with remote peer","stream-writer-type":"stream MsgApp v2","local-member-id":"6549516508a93b94","remote-peer-id":"2b7c7906cc6724c2"} Aug 5 21:43:57.751389 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.751Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"6549516508a93b94","remote-peer-id":"2b7c7906cc6724c2"} Aug 5 21:43:57.751389 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.751Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"6549516508a93b94","remote-peer-id":"2b7c7906cc6724c2"} Aug 5 21:43:57.751389 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.751Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream MsgApp v2","local-member-id":"6549516508a93b94","remote-peer-id":"53de09afdd35ed69"} Aug 5 21:43:57.753772 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.751Z","caller":"rafthttp/stream.go:412","msg":"established TCP streaming connection with remote peer","stream-reader-type":"stream Message","local-member-id":"6549516508a93b94","remote-peer-id":"53de09afdd35ed69"} Aug 5 21:43:57.798074 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.797Z","caller":"etcdserver/server.go:767","msg":"initialized peer connections; fast-forwarding election ticks","local-member-id":"6549516508a93b94","forward-ticks":8,"forward-duration":"800ms","election-ticks":10,"election-timeout":"1s","active-remote-members":2} Aug 5 21:43:57.931908 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6549516508a93b94 [logterm: 1, index: 3, vote: 0] cast MsgPreVote for 2b7c7906cc6724c2 [logterm: 1, index: 3] at term 1"} Aug 5 21:43:57.932681 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6549516508a93b94 [term: 1] received a MsgVote message with higher term from 2b7c7906cc6724c2 [term: 2]"} Aug 5 21:43:57.932681 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6549516508a93b94 became follower at term 2"} Aug 5 21:43:57.932681 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.932Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"6549516508a93b94 [logterm: 1, index: 3, vote: 0] cast MsgVote for 2b7c7906cc6724c2 [logterm: 1, index: 3] at term 2"} Aug 5 21:43:57.933417 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 6549516508a93b94 elected leader 2b7c7906cc6724c2 at term 2"} Aug 5 21:43:57.935157 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.934Z","caller":"etcdserver/server.go:2027","msg":"published local member to cluster through raft","local-member-id":"6549516508a93b94","local-member-attributes":"{Name:8ec3a2822927461fbf2657c77bc9ea99 ClientURLs:[http://10.0.0.66:2379]}","request-path":"/0/members/6549516508a93b94/attributes","cluster-id":"1fc0cec0a44bf954","publish-timeout":"7s"} Aug 5 21:43:57.935157 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.934Z","caller":"embed/serve.go:98","msg":"ready to serve client requests"} Aug 5 21:43:57.935386 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.935Z","caller":"etcdmain/main.go:47","msg":"notifying init daemon"} Aug 5 21:43:57.935386 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.935Z","caller":"etcdmain/main.go:53","msg":"successfully notified init daemon"} Aug 5 21:43:57.935544 systemd[1]: Started etcd-member.service - etcd (System Application Container). Aug 5 21:43:57.937087 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.936Z","caller":"embed/serve.go:140","msg":"serving client traffic insecurely; this is strongly discouraged!","address":"[::]:2379"} Aug 5 21:43:57.937087 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.936Z","caller":"membership/cluster.go:531","msg":"set initial cluster version","cluster-id":"1fc0cec0a44bf954","local-member-id":"6549516508a93b94","cluster-version":"3.5"} Aug 5 21:43:57.937087 etcd-wrapper[1596]: {"level":"info","ts":"2024-08-05T21:43:57.936Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} Aug 5 21:43:57.937413 systemd[1]: Reached target multi-user.target - Multi-User System. Aug 5 21:43:57.939178 systemd[1]: Startup finished in 555ms (kernel) + 3.667s (initrd) + 6.600s (userspace) = 10.823s. Aug 5 21:43:58.098968 systemd[1]: var-lib-docker-overlay2-dcac11cf3bd2a349ca35e3bd13b8873cb12dcf52f7b9211bf527cc37629fa646-merged.mount: Deactivated successfully. Aug 5 21:44:00.524237 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Aug 5 21:44:00.525308 systemd[1]: Started sshd@0-10.0.0.66:22-10.0.0.1:59878.service - OpenSSH per-connection server daemon (10.0.0.1:59878). Aug 5 21:44:00.580074 sshd[1692]: Accepted publickey for core from 10.0.0.1 port 59878 ssh2: RSA SHA256:vLb6+tqq+AN0xHsfmostacJPBc0ER3TTPjmoL0pNEEc Aug 5 21:44:00.581889 sshd[1692]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 21:44:00.600628 systemd-logind[1372]: New session 1 of user core. Aug 5 21:44:00.601628 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Aug 5 21:44:00.614818 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Aug 5 21:44:00.624642 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Aug 5 21:44:00.626798 systemd[1]: Starting user@500.service - User Manager for UID 500... Aug 5 21:44:00.633298 (systemd)[1696]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Aug 5 21:44:00.711856 systemd[1696]: Queued start job for default target default.target. Aug 5 21:44:00.724455 systemd[1696]: Created slice app.slice - User Application Slice. Aug 5 21:44:00.724484 systemd[1696]: Reached target paths.target - Paths. Aug 5 21:44:00.724495 systemd[1696]: Reached target timers.target - Timers. Aug 5 21:44:00.725641 systemd[1696]: Starting dbus.socket - D-Bus User Message Bus Socket... Aug 5 21:44:00.734518 systemd[1696]: Listening on dbus.socket - D-Bus User Message Bus Socket. Aug 5 21:44:00.734609 systemd[1696]: Reached target sockets.target - Sockets. Aug 5 21:44:00.734623 systemd[1696]: Reached target basic.target - Basic System. Aug 5 21:44:00.734666 systemd[1696]: Reached target default.target - Main User Target. Aug 5 21:44:00.734691 systemd[1696]: Startup finished in 96ms. Aug 5 21:44:00.734937 systemd[1]: Started user@500.service - User Manager for UID 500. Aug 5 21:44:00.736411 systemd[1]: Started session-1.scope - Session 1 of User core. Aug 5 21:44:00.793034 systemd[1]: Started sshd@1-10.0.0.66:22-10.0.0.1:59888.service - OpenSSH per-connection server daemon (10.0.0.1:59888). Aug 5 21:44:00.826812 sshd[1707]: Accepted publickey for core from 10.0.0.1 port 59888 ssh2: RSA SHA256:vLb6+tqq+AN0xHsfmostacJPBc0ER3TTPjmoL0pNEEc Aug 5 21:44:00.828125 sshd[1707]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 21:44:00.832328 systemd-logind[1372]: New session 2 of user core. Aug 5 21:44:00.839746 systemd[1]: Started session-2.scope - Session 2 of User core. Aug 5 21:44:00.891061 sshd[1707]: pam_unix(sshd:session): session closed for user core Aug 5 21:44:00.904978 systemd[1]: sshd@1-10.0.0.66:22-10.0.0.1:59888.service: Deactivated successfully. Aug 5 21:44:00.906401 systemd[1]: session-2.scope: Deactivated successfully. Aug 5 21:44:00.907635 systemd-logind[1372]: Session 2 logged out. Waiting for processes to exit. Aug 5 21:44:00.908696 systemd[1]: Started sshd@2-10.0.0.66:22-10.0.0.1:59890.service - OpenSSH per-connection server daemon (10.0.0.1:59890). Aug 5 21:44:00.909367 systemd-logind[1372]: Removed session 2. Aug 5 21:44:00.942909 sshd[1714]: Accepted publickey for core from 10.0.0.1 port 59890 ssh2: RSA SHA256:vLb6+tqq+AN0xHsfmostacJPBc0ER3TTPjmoL0pNEEc Aug 5 21:44:00.944031 sshd[1714]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 21:44:00.947464 systemd-logind[1372]: New session 3 of user core. Aug 5 21:44:00.959747 systemd[1]: Started session-3.scope - Session 3 of User core. Aug 5 21:44:01.005842 sshd[1714]: pam_unix(sshd:session): session closed for user core Aug 5 21:44:01.015857 systemd[1]: sshd@2-10.0.0.66:22-10.0.0.1:59890.service: Deactivated successfully. Aug 5 21:44:01.017261 systemd[1]: session-3.scope: Deactivated successfully. Aug 5 21:44:01.018599 systemd-logind[1372]: Session 3 logged out. Waiting for processes to exit. Aug 5 21:44:01.019657 systemd[1]: Started sshd@3-10.0.0.66:22-10.0.0.1:59906.service - OpenSSH per-connection server daemon (10.0.0.1:59906). Aug 5 21:44:01.020490 systemd-logind[1372]: Removed session 3. Aug 5 21:44:01.054406 sshd[1721]: Accepted publickey for core from 10.0.0.1 port 59906 ssh2: RSA SHA256:vLb6+tqq+AN0xHsfmostacJPBc0ER3TTPjmoL0pNEEc Aug 5 21:44:01.054795 sshd[1721]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 21:44:01.058645 systemd-logind[1372]: New session 4 of user core. Aug 5 21:44:01.068758 systemd[1]: Started session-4.scope - Session 4 of User core. Aug 5 21:44:01.118995 sshd[1721]: pam_unix(sshd:session): session closed for user core Aug 5 21:44:01.127797 systemd[1]: sshd@3-10.0.0.66:22-10.0.0.1:59906.service: Deactivated successfully. Aug 5 21:44:01.129152 systemd[1]: session-4.scope: Deactivated successfully. Aug 5 21:44:01.131683 systemd-logind[1372]: Session 4 logged out. Waiting for processes to exit. Aug 5 21:44:01.132758 systemd[1]: Started sshd@4-10.0.0.66:22-10.0.0.1:59914.service - OpenSSH per-connection server daemon (10.0.0.1:59914). Aug 5 21:44:01.133449 systemd-logind[1372]: Removed session 4. Aug 5 21:44:01.166443 sshd[1728]: Accepted publickey for core from 10.0.0.1 port 59914 ssh2: RSA SHA256:vLb6+tqq+AN0xHsfmostacJPBc0ER3TTPjmoL0pNEEc Aug 5 21:44:01.167570 sshd[1728]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 21:44:01.171479 systemd-logind[1372]: New session 5 of user core. Aug 5 21:44:01.180739 systemd[1]: Started session-5.scope - Session 5 of User core. Aug 5 21:44:01.239561 sudo[1731]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Aug 5 21:44:01.239847 sudo[1731]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Aug 5 21:44:01.254288 sudo[1731]: pam_unix(sudo:session): session closed for user root Aug 5 21:44:01.255840 sshd[1728]: pam_unix(sshd:session): session closed for user core Aug 5 21:44:01.269981 systemd[1]: sshd@4-10.0.0.66:22-10.0.0.1:59914.service: Deactivated successfully. Aug 5 21:44:01.271406 systemd[1]: session-5.scope: Deactivated successfully. Aug 5 21:44:01.272782 systemd-logind[1372]: Session 5 logged out. Waiting for processes to exit. Aug 5 21:44:01.285820 systemd[1]: Started sshd@5-10.0.0.66:22-10.0.0.1:59928.service - OpenSSH per-connection server daemon (10.0.0.1:59928). Aug 5 21:44:01.286725 systemd-logind[1372]: Removed session 5. Aug 5 21:44:01.316793 sshd[1736]: Accepted publickey for core from 10.0.0.1 port 59928 ssh2: RSA SHA256:vLb6+tqq+AN0xHsfmostacJPBc0ER3TTPjmoL0pNEEc Aug 5 21:44:01.318168 sshd[1736]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 21:44:01.322192 systemd-logind[1372]: New session 6 of user core. Aug 5 21:44:01.331742 systemd[1]: Started session-6.scope - Session 6 of User core. Aug 5 21:44:01.381316 sudo[1740]: 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 Aug 5 21:44:01.381552 sudo[1740]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Aug 5 21:44:01.384460 sudo[1740]: pam_unix(sudo:session): session closed for user root Aug 5 21:44:01.388784 sudo[1739]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/systemctl restart audit-rules Aug 5 21:44:01.388999 sudo[1739]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Aug 5 21:44:01.406828 systemd[1]: Stopping audit-rules.service - Load Security Auditing Rules... Aug 5 21:44:01.408362 auditctl[1743]: No rules Aug 5 21:44:01.408712 systemd[1]: audit-rules.service: Deactivated successfully. Aug 5 21:44:01.408925 systemd[1]: Stopped audit-rules.service - Load Security Auditing Rules. Aug 5 21:44:01.411295 systemd[1]: Starting audit-rules.service - Load Security Auditing Rules... Aug 5 21:44:01.433617 augenrules[1761]: No rules Aug 5 21:44:01.434891 systemd[1]: Finished audit-rules.service - Load Security Auditing Rules. Aug 5 21:44:01.435787 sudo[1739]: pam_unix(sudo:session): session closed for user root Aug 5 21:44:01.437254 sshd[1736]: pam_unix(sshd:session): session closed for user core Aug 5 21:44:01.440124 systemd[1]: sshd@5-10.0.0.66:22-10.0.0.1:59928.service: Deactivated successfully. Aug 5 21:44:01.441530 systemd[1]: session-6.scope: Deactivated successfully. Aug 5 21:44:01.442141 systemd-logind[1372]: Session 6 logged out. Waiting for processes to exit. Aug 5 21:44:01.443145 systemd-logind[1372]: Removed session 6. Aug 5 21:44:01.900940 systemd[1]: Started sshd@6-10.0.0.66:22-10.0.0.1:59932.service - OpenSSH per-connection server daemon (10.0.0.1:59932). Aug 5 21:44:01.935310 sshd[1769]: Accepted publickey for core from 10.0.0.1 port 59932 ssh2: RSA SHA256:vLb6+tqq+AN0xHsfmostacJPBc0ER3TTPjmoL0pNEEc Aug 5 21:44:01.936486 sshd[1769]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Aug 5 21:44:01.940307 systemd-logind[1372]: New session 7 of user core. Aug 5 21:44:01.946731 systemd[1]: Started session-7.scope - Session 7 of User core. Aug 5 21:44:01.995422 sudo[1773]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/systemctl stop sshd.socket Aug 5 21:44:01.995956 sudo[1773]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Aug 5 21:44:02.002769 systemd[1]: sshd.socket: Deactivated successfully. Aug 5 21:44:02.003077 systemd[1]: Closed sshd.socket - OpenSSH Server Socket. Aug 5 21:44:02.004222 sudo[1773]: pam_unix(sudo:session): session closed for user root Aug 5 21:44:02.008432 sudo[1772]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/locksmithctl send-need-reboot Aug 5 21:44:02.008690 sudo[1772]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Aug 5 21:44:02.013536 locksmithd[1399]: LastCheckedTime=0 Progress=0 CurrentOperation="UPDATE_STATUS_UPDATED_NEED_REBOOT" NewVersion= NewSize=0 Aug 5 21:44:02.013482 locksmithd[1399]: No configured reboot window Aug 5 21:44:02.017490 locksmithd[1399]: Logins detected, delaying reboot for 5 minutes. Aug 5 21:44:03.015046 sudo[1772]: pam_unix(sudo:session): session closed for user root Aug 5 21:44:03.016719 sshd[1769]: pam_unix(sshd:session): session closed for user core Aug 5 21:44:03.020214 systemd[1]: sshd@6-10.0.0.66:22-10.0.0.1:59932.service: Deactivated successfully. Aug 5 21:44:03.021715 systemd[1]: session-7.scope: Deactivated successfully. Aug 5 21:44:03.022246 systemd-logind[1372]: Session 7 logged out. Waiting for processes to exit. Aug 5 21:44:03.023168 systemd-logind[1372]: Removed session 7. Aug 5 21:44:38.151869 update_engine[1373]: I0805 21:44:38.151767 1373 update_attempter.cc:509] Updating boot flags... Aug 5 21:44:38.172617 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 38 scanned by (udev-worker) (1793) Aug 5 21:44:38.193679 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 38 scanned by (udev-worker) (1791) Aug 5 21:44:38.220043 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 38 scanned by (udev-worker) (1791) Aug 5 21:46:44.118300 update_engine[1373]: I0805 21:46:44.118218 1373 prefs.cc:52] certificate-report-to-send-update not present in /var/lib/update_engine/prefs Aug 5 21:46:44.118300 update_engine[1373]: I0805 21:46:44.118264 1373 prefs.cc:52] certificate-report-to-send-download not present in /var/lib/update_engine/prefs Aug 5 21:46:44.118827 update_engine[1373]: I0805 21:46:44.118578 1373 prefs.cc:52] aleph-version not present in /var/lib/update_engine/prefs Aug 5 21:46:44.119249 update_engine[1373]: I0805 21:46:44.118999 1373 omaha_request_params.cc:62] Current group set to beta Aug 5 21:46:44.119249 update_engine[1373]: I0805 21:46:44.119094 1373 update_attempter.cc:499] Already updated boot flags. Skipping. Aug 5 21:46:44.119249 update_engine[1373]: I0805 21:46:44.119098 1373 update_attempter.cc:643] Scheduling an action processor start. Aug 5 21:46:44.119249 update_engine[1373]: I0805 21:46:44.119109 1373 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Aug 5 21:46:44.119249 update_engine[1373]: I0805 21:46:44.119132 1373 prefs.cc:52] previous-version not present in /var/lib/update_engine/prefs Aug 5 21:46:44.119249 update_engine[1373]: I0805 21:46:44.119184 1373 omaha_request_action.cc:271] Posting an Omaha request to disabled Aug 5 21:46:44.119249 update_engine[1373]: I0805 21:46:44.119189 1373 omaha_request_action.cc:272] Request: Aug 5 21:46:44.119249 update_engine[1373]: Aug 5 21:46:44.119249 update_engine[1373]: Aug 5 21:46:44.119249 update_engine[1373]: Aug 5 21:46:44.119249 update_engine[1373]: Aug 5 21:46:44.119249 update_engine[1373]: Aug 5 21:46:44.119249 update_engine[1373]: Aug 5 21:46:44.119249 update_engine[1373]: Aug 5 21:46:44.119249 update_engine[1373]: Aug 5 21:46:44.119249 update_engine[1373]: I0805 21:46:44.119191 1373 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Aug 5 21:46:44.121275 update_engine[1373]: I0805 21:46:44.121249 1373 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Aug 5 21:46:44.121514 update_engine[1373]: I0805 21:46:44.121490 1373 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Aug 5 21:46:44.156811 update_engine[1373]: E0805 21:46:44.156776 1373 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Aug 5 21:46:44.156868 update_engine[1373]: I0805 21:46:44.156833 1373 libcurl_http_fetcher.cc:283] No HTTP response, retry 1 Aug 5 21:46:54.126013 update_engine[1373]: I0805 21:46:54.125865 1373 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Aug 5 21:46:54.126385 update_engine[1373]: I0805 21:46:54.126142 1373 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Aug 5 21:46:54.126385 update_engine[1373]: I0805 21:46:54.126286 1373 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Aug 5 21:46:54.203457 update_engine[1373]: E0805 21:46:54.203413 1373 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Aug 5 21:46:54.203520 update_engine[1373]: I0805 21:46:54.203464 1373 libcurl_http_fetcher.cc:283] No HTTP response, retry 2 Aug 5 21:47:04.126516 update_engine[1373]: I0805 21:47:04.126439 1373 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Aug 5 21:47:04.126931 update_engine[1373]: I0805 21:47:04.126721 1373 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Aug 5 21:47:04.126931 update_engine[1373]: I0805 21:47:04.126879 1373 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Aug 5 21:47:04.194451 update_engine[1373]: E0805 21:47:04.194392 1373 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Aug 5 21:47:04.194569 update_engine[1373]: I0805 21:47:04.194478 1373 libcurl_http_fetcher.cc:283] No HTTP response, retry 3 Aug 5 21:47:14.117399 update_engine[1373]: I0805 21:47:14.117324 1373 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Aug 5 21:47:14.117805 update_engine[1373]: I0805 21:47:14.117548 1373 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Aug 5 21:47:14.117805 update_engine[1373]: I0805 21:47:14.117722 1373 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Aug 5 21:47:14.189919 update_engine[1373]: E0805 21:47:14.189837 1373 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Aug 5 21:47:14.189919 update_engine[1373]: I0805 21:47:14.189909 1373 libcurl_http_fetcher.cc:297] Transfer resulted in an error (0), 0 bytes downloaded Aug 5 21:47:14.189919 update_engine[1373]: I0805 21:47:14.189914 1373 omaha_request_action.cc:617] Omaha request response: Aug 5 21:47:14.190117 update_engine[1373]: E0805 21:47:14.190004 1373 omaha_request_action.cc:636] Omaha request network transfer failed. Aug 5 21:47:14.190117 update_engine[1373]: I0805 21:47:14.190019 1373 action_processor.cc:68] ActionProcessor::ActionComplete: OmahaRequestAction action failed. Aborting processing. Aug 5 21:47:14.190117 update_engine[1373]: I0805 21:47:14.190022 1373 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Aug 5 21:47:14.190117 update_engine[1373]: I0805 21:47:14.190024 1373 update_attempter.cc:306] Processing Done. Aug 5 21:47:14.190117 update_engine[1373]: E0805 21:47:14.190037 1373 update_attempter.cc:619] Update failed. Aug 5 21:47:14.190117 update_engine[1373]: I0805 21:47:14.190040 1373 utils.cc:600] Converting error code 2000 to kActionCodeOmahaErrorInHTTPResponse Aug 5 21:47:14.190117 update_engine[1373]: I0805 21:47:14.190042 1373 payload_state.cc:97] Updating payload state for error code: 37 (kActionCodeOmahaErrorInHTTPResponse) Aug 5 21:47:14.190117 update_engine[1373]: I0805 21:47:14.190045 1373 payload_state.cc:103] Ignoring failures until we get a valid Omaha response. Aug 5 21:47:14.190117 update_engine[1373]: I0805 21:47:14.190110 1373 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Aug 5 21:47:14.190289 update_engine[1373]: I0805 21:47:14.190127 1373 omaha_request_action.cc:271] Posting an Omaha request to disabled Aug 5 21:47:14.190289 update_engine[1373]: I0805 21:47:14.190130 1373 omaha_request_action.cc:272] Request: Aug 5 21:47:14.190289 update_engine[1373]: Aug 5 21:47:14.190289 update_engine[1373]: Aug 5 21:47:14.190289 update_engine[1373]: Aug 5 21:47:14.190289 update_engine[1373]: Aug 5 21:47:14.190289 update_engine[1373]: Aug 5 21:47:14.190289 update_engine[1373]: Aug 5 21:47:14.190289 update_engine[1373]: I0805 21:47:14.190133 1373 libcurl_http_fetcher.cc:47] Starting/Resuming transfer Aug 5 21:47:14.190289 update_engine[1373]: I0805 21:47:14.190268 1373 libcurl_http_fetcher.cc:151] Setting up curl options for HTTP Aug 5 21:47:14.190462 update_engine[1373]: I0805 21:47:14.190390 1373 libcurl_http_fetcher.cc:449] Setting up timeout source: 1 seconds. Aug 5 21:47:14.197647 update_engine[1373]: E0805 21:47:14.197505 1373 libcurl_http_fetcher.cc:266] Unable to get http response code: Could not resolve host: disabled Aug 5 21:47:14.197647 update_engine[1373]: I0805 21:47:14.197567 1373 libcurl_http_fetcher.cc:297] Transfer resulted in an error (0), 0 bytes downloaded Aug 5 21:47:14.197647 update_engine[1373]: I0805 21:47:14.197572 1373 omaha_request_action.cc:617] Omaha request response: Aug 5 21:47:14.197647 update_engine[1373]: I0805 21:47:14.197575 1373 action_processor.cc:65] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Aug 5 21:47:14.197647 update_engine[1373]: I0805 21:47:14.197578 1373 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Aug 5 21:47:14.197647 update_engine[1373]: I0805 21:47:14.197597 1373 update_attempter.cc:306] Processing Done. Aug 5 21:47:14.197647 update_engine[1373]: I0805 21:47:14.197602 1373 update_attempter.cc:310] Error event sent. Aug 5 21:47:14.197647 update_engine[1373]: I0805 21:47:14.197610 1373 update_check_scheduler.cc:74] Next update check in 48m2s