Jul 2 00:46:47.747221 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Jul 2 00:46:47.747240 kernel: Linux version 5.15.161-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 11.3.1_p20221209 p3) 11.3.1 20221209, GNU ld (Gentoo 2.39 p5) 2.39.0) #1 SMP PREEMPT Mon Jul 1 23:37:37 -00 2024 Jul 2 00:46:47.747248 kernel: efi: EFI v2.70 by EDK II Jul 2 00:46:47.747254 kernel: efi: SMBIOS 3.0=0xd9260000 ACPI 2.0=0xd9240000 MEMATTR=0xda32b018 RNG=0xd9220018 MEMRESERVE=0xd9521c18 Jul 2 00:46:47.747259 kernel: random: crng init done Jul 2 00:46:47.747264 kernel: ACPI: Early table checksum verification disabled Jul 2 00:46:47.747270 kernel: ACPI: RSDP 0x00000000D9240000 000024 (v02 BOCHS ) Jul 2 00:46:47.747276 kernel: ACPI: XSDT 0x00000000D9230000 000064 (v01 BOCHS BXPC 00000001 01000013) Jul 2 00:46:47.747282 kernel: ACPI: FACP 0x00000000D91E0000 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Jul 2 00:46:47.747287 kernel: ACPI: DSDT 0x00000000D91F0000 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 2 00:46:47.747293 kernel: ACPI: APIC 0x00000000D91D0000 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Jul 2 00:46:47.747298 kernel: ACPI: PPTT 0x00000000D91C0000 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 2 00:46:47.747303 kernel: ACPI: GTDT 0x00000000D91B0000 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 2 00:46:47.747309 kernel: ACPI: MCFG 0x00000000D91A0000 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Jul 2 00:46:47.747316 kernel: ACPI: SPCR 0x00000000D9190000 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Jul 2 00:46:47.747322 kernel: ACPI: DBG2 0x00000000D9180000 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Jul 2 00:46:47.747328 kernel: ACPI: IORT 0x00000000D9170000 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Jul 2 00:46:47.747333 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Jul 2 00:46:47.747339 kernel: NUMA: Failed to initialise from firmware Jul 2 00:46:47.747345 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Jul 2 00:46:47.747354 kernel: NUMA: NODE_DATA [mem 0xdcb0b900-0xdcb10fff] Jul 2 00:46:47.747360 kernel: Zone ranges: Jul 2 00:46:47.747366 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Jul 2 00:46:47.747374 kernel: DMA32 empty Jul 2 00:46:47.747380 kernel: Normal empty Jul 2 00:46:47.747386 kernel: Movable zone start for each node Jul 2 00:46:47.747392 kernel: Early memory node ranges Jul 2 00:46:47.747398 kernel: node 0: [mem 0x0000000040000000-0x00000000d924ffff] Jul 2 00:46:47.747404 kernel: node 0: [mem 0x00000000d9250000-0x00000000d951ffff] Jul 2 00:46:47.747409 kernel: node 0: [mem 0x00000000d9520000-0x00000000dc7fffff] Jul 2 00:46:47.747415 kernel: node 0: [mem 0x00000000dc800000-0x00000000dc88ffff] Jul 2 00:46:47.747420 kernel: node 0: [mem 0x00000000dc890000-0x00000000dc89ffff] Jul 2 00:46:47.747426 kernel: node 0: [mem 0x00000000dc8a0000-0x00000000dc9bffff] Jul 2 00:46:47.747432 kernel: node 0: [mem 0x00000000dc9c0000-0x00000000dcffffff] Jul 2 00:46:47.747438 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Jul 2 00:46:47.747445 kernel: On node 0, zone DMA: 12288 pages in unavailable ranges Jul 2 00:46:47.747451 kernel: psci: probing for conduit method from ACPI. Jul 2 00:46:47.747457 kernel: psci: PSCIv1.1 detected in firmware. Jul 2 00:46:47.747462 kernel: psci: Using standard PSCI v0.2 function IDs Jul 2 00:46:47.747468 kernel: psci: Trusted OS migration not required Jul 2 00:46:47.747476 kernel: psci: SMC Calling Convention v1.1 Jul 2 00:46:47.747482 kernel: smccc: KVM: hypervisor services detected (0x00000000 0x00000000 0x00000000 0x00000003) Jul 2 00:46:47.747489 kernel: ACPI: SRAT not present Jul 2 00:46:47.747496 kernel: percpu: Embedded 30 pages/cpu s83032 r8192 d31656 u122880 Jul 2 00:46:47.747502 kernel: pcpu-alloc: s83032 r8192 d31656 u122880 alloc=30*4096 Jul 2 00:46:47.747508 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Jul 2 00:46:47.747514 kernel: Detected PIPT I-cache on CPU0 Jul 2 00:46:47.747520 kernel: CPU features: detected: GIC system register CPU interface Jul 2 00:46:47.747526 kernel: CPU features: detected: Hardware dirty bit management Jul 2 00:46:47.747532 kernel: CPU features: detected: Spectre-v4 Jul 2 00:46:47.747538 kernel: CPU features: detected: Spectre-BHB Jul 2 00:46:47.747546 kernel: CPU features: kernel page table isolation forced ON by KASLR Jul 2 00:46:47.747568 kernel: CPU features: detected: Kernel page table isolation (KPTI) Jul 2 00:46:47.747574 kernel: CPU features: detected: ARM erratum 1418040 Jul 2 00:46:47.747580 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Jul 2 00:46:47.747586 kernel: Policy zone: DMA Jul 2 00:46:47.747596 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=7b86ecfcd4701bdf4668db795601b20c118ac0b117c34a9b3836e0a5236b73b0 Jul 2 00:46:47.747603 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Jul 2 00:46:47.747610 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Jul 2 00:46:47.747617 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Jul 2 00:46:47.747623 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jul 2 00:46:47.747629 kernel: Memory: 2457468K/2572288K available (9792K kernel code, 2092K rwdata, 7572K rodata, 36352K init, 777K bss, 114820K reserved, 0K cma-reserved) Jul 2 00:46:47.747637 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Jul 2 00:46:47.747643 kernel: trace event string verifier disabled Jul 2 00:46:47.747650 kernel: rcu: Preemptible hierarchical RCU implementation. Jul 2 00:46:47.747657 kernel: rcu: RCU event tracing is enabled. Jul 2 00:46:47.747671 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Jul 2 00:46:47.747678 kernel: Trampoline variant of Tasks RCU enabled. Jul 2 00:46:47.747684 kernel: Tracing variant of Tasks RCU enabled. Jul 2 00:46:47.747690 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Jul 2 00:46:47.747696 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Jul 2 00:46:47.747702 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Jul 2 00:46:47.747708 kernel: GICv3: 256 SPIs implemented Jul 2 00:46:47.747716 kernel: GICv3: 0 Extended SPIs implemented Jul 2 00:46:47.747722 kernel: GICv3: Distributor has no Range Selector support Jul 2 00:46:47.747728 kernel: Root IRQ handler: gic_handle_irq Jul 2 00:46:47.747734 kernel: GICv3: 16 PPIs implemented Jul 2 00:46:47.747740 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Jul 2 00:46:47.747746 kernel: ACPI: SRAT not present Jul 2 00:46:47.747752 kernel: ITS [mem 0x08080000-0x0809ffff] Jul 2 00:46:47.747758 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Jul 2 00:46:47.747765 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Jul 2 00:46:47.747771 kernel: GICv3: using LPI property table @0x00000000400d0000 Jul 2 00:46:47.747777 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Jul 2 00:46:47.747784 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 2 00:46:47.747791 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Jul 2 00:46:47.747798 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Jul 2 00:46:47.747804 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Jul 2 00:46:47.747810 kernel: arm-pv: using stolen time PV Jul 2 00:46:47.747817 kernel: Console: colour dummy device 80x25 Jul 2 00:46:47.747823 kernel: ACPI: Core revision 20210730 Jul 2 00:46:47.747830 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Jul 2 00:46:47.747836 kernel: pid_max: default: 32768 minimum: 301 Jul 2 00:46:47.747842 kernel: LSM: Security Framework initializing Jul 2 00:46:47.747848 kernel: SELinux: Initializing. Jul 2 00:46:47.747856 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Jul 2 00:46:47.747863 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Jul 2 00:46:47.747869 kernel: rcu: Hierarchical SRCU implementation. Jul 2 00:46:47.747875 kernel: Platform MSI: ITS@0x8080000 domain created Jul 2 00:46:47.747881 kernel: PCI/MSI: ITS@0x8080000 domain created Jul 2 00:46:47.747887 kernel: Remapping and enabling EFI services. Jul 2 00:46:47.747893 kernel: smp: Bringing up secondary CPUs ... Jul 2 00:46:47.747899 kernel: Detected PIPT I-cache on CPU1 Jul 2 00:46:47.747905 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Jul 2 00:46:47.747913 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Jul 2 00:46:47.747919 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 2 00:46:47.747925 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Jul 2 00:46:47.747931 kernel: Detected PIPT I-cache on CPU2 Jul 2 00:46:47.747938 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Jul 2 00:46:47.747944 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Jul 2 00:46:47.747951 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 2 00:46:47.747957 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Jul 2 00:46:47.747963 kernel: Detected PIPT I-cache on CPU3 Jul 2 00:46:47.747969 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Jul 2 00:46:47.747977 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Jul 2 00:46:47.747983 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Jul 2 00:46:47.747989 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Jul 2 00:46:47.747995 kernel: smp: Brought up 1 node, 4 CPUs Jul 2 00:46:47.748006 kernel: SMP: Total of 4 processors activated. Jul 2 00:46:47.748014 kernel: CPU features: detected: 32-bit EL0 Support Jul 2 00:46:47.748021 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Jul 2 00:46:47.748027 kernel: CPU features: detected: Common not Private translations Jul 2 00:46:47.748033 kernel: CPU features: detected: CRC32 instructions Jul 2 00:46:47.748040 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Jul 2 00:46:47.748046 kernel: CPU features: detected: LSE atomic instructions Jul 2 00:46:47.748053 kernel: CPU features: detected: Privileged Access Never Jul 2 00:46:47.748061 kernel: CPU features: detected: RAS Extension Support Jul 2 00:46:47.748067 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Jul 2 00:46:47.748073 kernel: CPU: All CPU(s) started at EL1 Jul 2 00:46:47.748080 kernel: alternatives: patching kernel code Jul 2 00:46:47.748087 kernel: devtmpfs: initialized Jul 2 00:46:47.748094 kernel: KASLR enabled Jul 2 00:46:47.748100 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Jul 2 00:46:47.748107 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Jul 2 00:46:47.748114 kernel: pinctrl core: initialized pinctrl subsystem Jul 2 00:46:47.748120 kernel: SMBIOS 3.0.0 present. Jul 2 00:46:47.748127 kernel: DMI: QEMU KVM Virtual Machine, BIOS 0.0.0 02/06/2015 Jul 2 00:46:47.748134 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jul 2 00:46:47.748140 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Jul 2 00:46:47.748147 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Jul 2 00:46:47.748155 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Jul 2 00:46:47.748162 kernel: audit: initializing netlink subsys (disabled) Jul 2 00:46:47.748168 kernel: audit: type=2000 audit(0.033:1): state=initialized audit_enabled=0 res=1 Jul 2 00:46:47.748174 kernel: thermal_sys: Registered thermal governor 'step_wise' Jul 2 00:46:47.748181 kernel: cpuidle: using governor menu Jul 2 00:46:47.748187 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Jul 2 00:46:47.748194 kernel: ASID allocator initialised with 32768 entries Jul 2 00:46:47.748200 kernel: ACPI: bus type PCI registered Jul 2 00:46:47.748207 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jul 2 00:46:47.748215 kernel: Serial: AMBA PL011 UART driver Jul 2 00:46:47.748222 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Jul 2 00:46:47.748228 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Jul 2 00:46:47.748235 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Jul 2 00:46:47.748241 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Jul 2 00:46:47.748248 kernel: cryptd: max_cpu_qlen set to 1000 Jul 2 00:46:47.748255 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Jul 2 00:46:47.748261 kernel: ACPI: Added _OSI(Module Device) Jul 2 00:46:47.748268 kernel: ACPI: Added _OSI(Processor Device) Jul 2 00:46:47.748275 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Jul 2 00:46:47.748282 kernel: ACPI: Added _OSI(Processor Aggregator Device) Jul 2 00:46:47.748288 kernel: ACPI: Added _OSI(Linux-Dell-Video) Jul 2 00:46:47.748295 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Jul 2 00:46:47.748301 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Jul 2 00:46:47.748308 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Jul 2 00:46:47.748314 kernel: ACPI: Interpreter enabled Jul 2 00:46:47.748320 kernel: ACPI: Using GIC for interrupt routing Jul 2 00:46:47.748327 kernel: ACPI: MCFG table detected, 1 entries Jul 2 00:46:47.748335 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Jul 2 00:46:47.748341 kernel: printk: console [ttyAMA0] enabled Jul 2 00:46:47.748348 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Jul 2 00:46:47.748470 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Jul 2 00:46:47.748536 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Jul 2 00:46:47.748645 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Jul 2 00:46:47.748719 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Jul 2 00:46:47.748784 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Jul 2 00:46:47.748793 kernel: ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Jul 2 00:46:47.748799 kernel: PCI host bridge to bus 0000:00 Jul 2 00:46:47.748870 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Jul 2 00:46:47.748924 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Jul 2 00:46:47.748976 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Jul 2 00:46:47.749028 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Jul 2 00:46:47.749101 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Jul 2 00:46:47.749175 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Jul 2 00:46:47.749236 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Jul 2 00:46:47.749296 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Jul 2 00:46:47.749355 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Jul 2 00:46:47.749414 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Jul 2 00:46:47.749474 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Jul 2 00:46:47.749536 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Jul 2 00:46:47.749606 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Jul 2 00:46:47.749669 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Jul 2 00:46:47.749726 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Jul 2 00:46:47.749735 kernel: ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 Jul 2 00:46:47.749741 kernel: ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 Jul 2 00:46:47.749748 kernel: ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 Jul 2 00:46:47.749757 kernel: ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 Jul 2 00:46:47.749764 kernel: iommu: Default domain type: Translated Jul 2 00:46:47.749770 kernel: iommu: DMA domain TLB invalidation policy: strict mode Jul 2 00:46:47.749777 kernel: vgaarb: loaded Jul 2 00:46:47.749783 kernel: pps_core: LinuxPPS API ver. 1 registered Jul 2 00:46:47.749790 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Jul 2 00:46:47.749796 kernel: PTP clock support registered Jul 2 00:46:47.749803 kernel: Registered efivars operations Jul 2 00:46:47.749809 kernel: clocksource: Switched to clocksource arch_sys_counter Jul 2 00:46:47.749815 kernel: VFS: Disk quotas dquot_6.6.0 Jul 2 00:46:47.749824 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jul 2 00:46:47.749831 kernel: pnp: PnP ACPI init Jul 2 00:46:47.749895 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Jul 2 00:46:47.749905 kernel: pnp: PnP ACPI: found 1 devices Jul 2 00:46:47.749911 kernel: NET: Registered PF_INET protocol family Jul 2 00:46:47.749918 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Jul 2 00:46:47.749924 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Jul 2 00:46:47.749931 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jul 2 00:46:47.749939 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Jul 2 00:46:47.749946 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Jul 2 00:46:47.749953 kernel: TCP: Hash tables configured (established 32768 bind 32768) Jul 2 00:46:47.749959 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Jul 2 00:46:47.749966 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Jul 2 00:46:47.749972 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jul 2 00:46:47.749980 kernel: PCI: CLS 0 bytes, default 64 Jul 2 00:46:47.749986 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Jul 2 00:46:47.749994 kernel: kvm [1]: HYP mode not available Jul 2 00:46:47.750001 kernel: Initialise system trusted keyrings Jul 2 00:46:47.750007 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Jul 2 00:46:47.750014 kernel: Key type asymmetric registered Jul 2 00:46:47.750020 kernel: Asymmetric key parser 'x509' registered Jul 2 00:46:47.750027 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Jul 2 00:46:47.750034 kernel: io scheduler mq-deadline registered Jul 2 00:46:47.750040 kernel: io scheduler kyber registered Jul 2 00:46:47.750047 kernel: io scheduler bfq registered Jul 2 00:46:47.750054 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Jul 2 00:46:47.750061 kernel: ACPI: button: Power Button [PWRB] Jul 2 00:46:47.750068 kernel: ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 Jul 2 00:46:47.750130 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Jul 2 00:46:47.750138 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Jul 2 00:46:47.750145 kernel: thunder_xcv, ver 1.0 Jul 2 00:46:47.750151 kernel: thunder_bgx, ver 1.0 Jul 2 00:46:47.750158 kernel: nicpf, ver 1.0 Jul 2 00:46:47.750164 kernel: nicvf, ver 1.0 Jul 2 00:46:47.750232 kernel: rtc-efi rtc-efi.0: registered as rtc0 Jul 2 00:46:47.750290 kernel: rtc-efi rtc-efi.0: setting system clock to 2024-07-02T00:46:47 UTC (1719881207) Jul 2 00:46:47.750298 kernel: hid: raw HID events driver (C) Jiri Kosina Jul 2 00:46:47.750305 kernel: NET: Registered PF_INET6 protocol family Jul 2 00:46:47.750312 kernel: Segment Routing with IPv6 Jul 2 00:46:47.750318 kernel: In-situ OAM (IOAM) with IPv6 Jul 2 00:46:47.750324 kernel: NET: Registered PF_PACKET protocol family Jul 2 00:46:47.750331 kernel: Key type dns_resolver registered Jul 2 00:46:47.750337 kernel: registered taskstats version 1 Jul 2 00:46:47.750345 kernel: Loading compiled-in X.509 certificates Jul 2 00:46:47.750352 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.15.161-flatcar: c418313b450e4055b23e41c11cb6dc415de0265d' Jul 2 00:46:47.750358 kernel: Key type .fscrypt registered Jul 2 00:46:47.750365 kernel: Key type fscrypt-provisioning registered Jul 2 00:46:47.750372 kernel: ima: No TPM chip found, activating TPM-bypass! Jul 2 00:46:47.750378 kernel: ima: Allocated hash algorithm: sha1 Jul 2 00:46:47.750384 kernel: ima: No architecture policies found Jul 2 00:46:47.750391 kernel: clk: Disabling unused clocks Jul 2 00:46:47.750397 kernel: Freeing unused kernel memory: 36352K Jul 2 00:46:47.750405 kernel: Run /init as init process Jul 2 00:46:47.750412 kernel: with arguments: Jul 2 00:46:47.750418 kernel: /init Jul 2 00:46:47.750425 kernel: with environment: Jul 2 00:46:47.750431 kernel: HOME=/ Jul 2 00:46:47.750438 kernel: TERM=linux Jul 2 00:46:47.750444 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Jul 2 00:46:47.750452 systemd[1]: systemd 252 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) Jul 2 00:46:47.750462 systemd[1]: Detected virtualization kvm. Jul 2 00:46:47.750469 systemd[1]: Detected architecture arm64. Jul 2 00:46:47.750476 systemd[1]: Running in initrd. Jul 2 00:46:47.750483 systemd[1]: No hostname configured, using default hostname. Jul 2 00:46:47.750490 systemd[1]: Hostname set to . Jul 2 00:46:47.750497 systemd[1]: Initializing machine ID from VM UUID. Jul 2 00:46:47.750504 systemd[1]: Queued start job for default target initrd.target. Jul 2 00:46:47.750511 systemd[1]: Started systemd-ask-password-console.path. Jul 2 00:46:47.750519 systemd[1]: Reached target cryptsetup.target. Jul 2 00:46:47.750526 systemd[1]: Reached target paths.target. Jul 2 00:46:47.750533 systemd[1]: Reached target slices.target. Jul 2 00:46:47.750539 systemd[1]: Reached target swap.target. Jul 2 00:46:47.750546 systemd[1]: Reached target timers.target. Jul 2 00:46:47.750563 systemd[1]: Listening on iscsid.socket. Jul 2 00:46:47.750570 systemd[1]: Listening on iscsiuio.socket. Jul 2 00:46:47.750578 systemd[1]: Listening on systemd-journald-audit.socket. Jul 2 00:46:47.750593 systemd[1]: Listening on systemd-journald-dev-log.socket. Jul 2 00:46:47.750604 systemd[1]: Listening on systemd-journald.socket. Jul 2 00:46:47.750612 systemd[1]: Listening on systemd-networkd.socket. Jul 2 00:46:47.750619 systemd[1]: Listening on systemd-udevd-control.socket. Jul 2 00:46:47.750626 systemd[1]: Listening on systemd-udevd-kernel.socket. Jul 2 00:46:47.750632 systemd[1]: Reached target sockets.target. Jul 2 00:46:47.750639 systemd[1]: Starting kmod-static-nodes.service... Jul 2 00:46:47.750646 systemd[1]: Finished network-cleanup.service. Jul 2 00:46:47.750655 systemd[1]: Starting systemd-fsck-usr.service... Jul 2 00:46:47.750667 systemd[1]: Starting systemd-journald.service... Jul 2 00:46:47.750675 systemd[1]: Starting systemd-modules-load.service... Jul 2 00:46:47.750683 systemd[1]: Starting systemd-resolved.service... Jul 2 00:46:47.750691 systemd[1]: Starting systemd-vconsole-setup.service... Jul 2 00:46:47.750699 systemd[1]: Finished kmod-static-nodes.service. Jul 2 00:46:47.750706 systemd[1]: Finished systemd-fsck-usr.service. Jul 2 00:46:47.750713 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... Jul 2 00:46:47.750720 systemd[1]: Finished systemd-vconsole-setup.service. Jul 2 00:46:47.750729 systemd[1]: Starting dracut-cmdline-ask.service... Jul 2 00:46:47.750739 systemd-journald[290]: Journal started Jul 2 00:46:47.750780 systemd-journald[290]: Runtime Journal (/run/log/journal/84e426444bae42ed84c3b4aeac44ba0d) is 6.0M, max 48.7M, 42.6M free. Jul 2 00:46:47.741363 systemd-modules-load[291]: Inserted module 'overlay' Jul 2 00:46:47.752070 systemd[1]: Started systemd-journald.service. Jul 2 00:46:47.752000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:47.756602 kernel: audit: type=1130 audit(1719881207.752:2): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:47.756739 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. Jul 2 00:46:47.756000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:47.760592 kernel: audit: type=1130 audit(1719881207.756:3): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:47.764577 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Jul 2 00:46:47.765983 systemd-resolved[292]: Positive Trust Anchors: Jul 2 00:46:47.765998 systemd-resolved[292]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 2 00:46:47.766026 systemd-resolved[292]: 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 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Jul 2 00:46:47.767975 systemd[1]: Finished dracut-cmdline-ask.service. Jul 2 00:46:47.774797 kernel: Bridge firewalling registered Jul 2 00:46:47.774818 kernel: audit: type=1130 audit(1719881207.771:4): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:47.771000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:47.770681 systemd-resolved[292]: Defaulting to hostname 'linux'. Jul 2 00:46:47.771912 systemd-modules-load[291]: Inserted module 'br_netfilter' Jul 2 00:46:47.778612 kernel: audit: type=1130 audit(1719881207.776:5): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:47.776000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:47.774888 systemd[1]: Started systemd-resolved.service. Jul 2 00:46:47.776225 systemd[1]: Reached target nss-lookup.target. Jul 2 00:46:47.780035 systemd[1]: Starting dracut-cmdline.service... Jul 2 00:46:47.784577 kernel: SCSI subsystem initialized Jul 2 00:46:47.789053 dracut-cmdline[309]: dracut-dracut-053 Jul 2 00:46:47.791341 dracut-cmdline[309]: 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=7b86ecfcd4701bdf4668db795601b20c118ac0b117c34a9b3836e0a5236b73b0 Jul 2 00:46:47.796120 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Jul 2 00:46:47.796141 kernel: device-mapper: uevent: version 1.0.3 Jul 2 00:46:47.796150 kernel: device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com Jul 2 00:46:47.795995 systemd-modules-load[291]: Inserted module 'dm_multipath' Jul 2 00:46:47.796806 systemd[1]: Finished systemd-modules-load.service. Jul 2 00:46:47.798000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:47.800022 systemd[1]: Starting systemd-sysctl.service... Jul 2 00:46:47.803077 kernel: audit: type=1130 audit(1719881207.798:6): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:47.807427 systemd[1]: Finished systemd-sysctl.service. Jul 2 00:46:47.807000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:47.810579 kernel: audit: type=1130 audit(1719881207.807:7): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:47.859581 kernel: Loading iSCSI transport class v2.0-870. Jul 2 00:46:47.873589 kernel: iscsi: registered transport (tcp) Jul 2 00:46:47.889707 kernel: iscsi: registered transport (qla4xxx) Jul 2 00:46:47.889744 kernel: QLogic iSCSI HBA Driver Jul 2 00:46:47.927708 systemd[1]: Finished dracut-cmdline.service. Jul 2 00:46:47.930637 kernel: audit: type=1130 audit(1719881207.927:8): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:47.927000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:47.929251 systemd[1]: Starting dracut-pre-udev.service... Jul 2 00:46:47.978579 kernel: raid6: neonx8 gen() 13706 MB/s Jul 2 00:46:47.995563 kernel: raid6: neonx8 xor() 10827 MB/s Jul 2 00:46:48.012560 kernel: raid6: neonx4 gen() 13526 MB/s Jul 2 00:46:48.029562 kernel: raid6: neonx4 xor() 11137 MB/s Jul 2 00:46:48.046559 kernel: raid6: neonx2 gen() 12953 MB/s Jul 2 00:46:48.063564 kernel: raid6: neonx2 xor() 10303 MB/s Jul 2 00:46:48.080562 kernel: raid6: neonx1 gen() 10501 MB/s Jul 2 00:46:48.097565 kernel: raid6: neonx1 xor() 8644 MB/s Jul 2 00:46:48.114561 kernel: raid6: int64x8 gen() 6260 MB/s Jul 2 00:46:48.131566 kernel: raid6: int64x8 xor() 3541 MB/s Jul 2 00:46:48.148565 kernel: raid6: int64x4 gen() 7233 MB/s Jul 2 00:46:48.165566 kernel: raid6: int64x4 xor() 3823 MB/s Jul 2 00:46:48.182565 kernel: raid6: int64x2 gen() 6115 MB/s Jul 2 00:46:48.199566 kernel: raid6: int64x2 xor() 3315 MB/s Jul 2 00:46:48.216574 kernel: raid6: int64x1 gen() 4913 MB/s Jul 2 00:46:48.233916 kernel: raid6: int64x1 xor() 2603 MB/s Jul 2 00:46:48.233928 kernel: raid6: using algorithm neonx8 gen() 13706 MB/s Jul 2 00:46:48.233937 kernel: raid6: .... xor() 10827 MB/s, rmw enabled Jul 2 00:46:48.233948 kernel: raid6: using neon recovery algorithm Jul 2 00:46:48.246682 kernel: xor: measuring software checksum speed Jul 2 00:46:48.246701 kernel: 8regs : 17293 MB/sec Jul 2 00:46:48.247565 kernel: 32regs : 20749 MB/sec Jul 2 00:46:48.248769 kernel: arm64_neon : 27939 MB/sec Jul 2 00:46:48.248781 kernel: xor: using function: arm64_neon (27939 MB/sec) Jul 2 00:46:48.305569 kernel: Btrfs loaded, crc32c=crc32c-generic, zoned=no, fsverity=no Jul 2 00:46:48.315348 systemd[1]: Finished dracut-pre-udev.service. Jul 2 00:46:48.315000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:48.317000 audit: BPF prog-id=7 op=LOAD Jul 2 00:46:48.318816 kernel: audit: type=1130 audit(1719881208.315:9): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:48.318839 kernel: audit: type=1334 audit(1719881208.317:10): prog-id=7 op=LOAD Jul 2 00:46:48.318000 audit: BPF prog-id=8 op=LOAD Jul 2 00:46:48.319273 systemd[1]: Starting systemd-udevd.service... Jul 2 00:46:48.331280 systemd-udevd[493]: Using default interface naming scheme 'v252'. Jul 2 00:46:48.334601 systemd[1]: Started systemd-udevd.service. Jul 2 00:46:48.334000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:48.335984 systemd[1]: Starting dracut-pre-trigger.service... Jul 2 00:46:48.347453 dracut-pre-trigger[499]: rd.md=0: removing MD RAID activation Jul 2 00:46:48.374376 systemd[1]: Finished dracut-pre-trigger.service. Jul 2 00:46:48.374000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:48.375764 systemd[1]: Starting systemd-udev-trigger.service... Jul 2 00:46:48.413632 systemd[1]: Finished systemd-udev-trigger.service. Jul 2 00:46:48.413000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:48.444748 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Jul 2 00:46:48.449576 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jul 2 00:46:48.467464 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device. Jul 2 00:46:48.470086 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by (udev-worker) (554) Jul 2 00:46:48.473580 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device. Jul 2 00:46:48.479561 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Jul 2 00:46:48.484145 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device. Jul 2 00:46:48.485091 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device. Jul 2 00:46:48.487515 systemd[1]: Starting disk-uuid.service... Jul 2 00:46:48.526825 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jul 2 00:46:49.538363 disk-uuid[566]: The operation has completed successfully. Jul 2 00:46:49.539261 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Jul 2 00:46:49.571865 systemd[1]: disk-uuid.service: Deactivated successfully. Jul 2 00:46:49.572000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=disk-uuid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:49.572000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=disk-uuid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:49.571955 systemd[1]: Finished disk-uuid.service. Jul 2 00:46:49.575374 systemd[1]: Starting verity-setup.service... Jul 2 00:46:49.593572 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Jul 2 00:46:49.616449 systemd[1]: Found device dev-mapper-usr.device. Jul 2 00:46:49.618048 systemd[1]: Mounting sysusr-usr.mount... Jul 2 00:46:49.618696 systemd[1]: Finished verity-setup.service. Jul 2 00:46:49.618000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=verity-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:49.667177 systemd[1]: Mounted sysusr-usr.mount. Jul 2 00:46:49.668179 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: norecovery. Quota mode: none. Jul 2 00:46:49.667838 systemd[1]: afterburn-network-kargs.service was skipped because no trigger condition checks were met. Jul 2 00:46:49.668517 systemd[1]: Starting ignition-setup.service... Jul 2 00:46:49.670148 systemd[1]: Starting parse-ip-for-networkd.service... Jul 2 00:46:49.677625 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Jul 2 00:46:49.677670 kernel: BTRFS info (device vda6): using free space tree Jul 2 00:46:49.677685 kernel: BTRFS info (device vda6): has skinny extents Jul 2 00:46:49.686163 systemd[1]: mnt-oem.mount: Deactivated successfully. Jul 2 00:46:49.693000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:49.693483 systemd[1]: Finished ignition-setup.service. Jul 2 00:46:49.694857 systemd[1]: Starting ignition-fetch-offline.service... Jul 2 00:46:49.753005 systemd[1]: Finished parse-ip-for-networkd.service. Jul 2 00:46:49.753000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=parse-ip-for-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:49.754000 audit: BPF prog-id=9 op=LOAD Jul 2 00:46:49.755324 systemd[1]: Starting systemd-networkd.service... Jul 2 00:46:49.780944 ignition[657]: Ignition 2.14.0 Jul 2 00:46:49.781707 ignition[657]: Stage: fetch-offline Jul 2 00:46:49.782261 ignition[657]: no configs at "/usr/lib/ignition/base.d" Jul 2 00:46:49.782936 ignition[657]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 2 00:46:49.783830 ignition[657]: parsed url from cmdline: "" Jul 2 00:46:49.783889 ignition[657]: no config URL provided Jul 2 00:46:49.784403 ignition[657]: reading system config file "/usr/lib/ignition/user.ign" Jul 2 00:46:49.785174 ignition[657]: no config at "/usr/lib/ignition/user.ign" Jul 2 00:46:49.785847 ignition[657]: op(1): [started] loading QEMU firmware config module Jul 2 00:46:49.786582 ignition[657]: op(1): executing: "modprobe" "qemu_fw_cfg" Jul 2 00:46:49.788234 systemd-networkd[742]: lo: Link UP Jul 2 00:46:49.788248 systemd-networkd[742]: lo: Gained carrier Jul 2 00:46:49.788892 systemd-networkd[742]: Enumeration completed Jul 2 00:46:49.790000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:49.788980 systemd[1]: Started systemd-networkd.service. Jul 2 00:46:49.790236 systemd[1]: Reached target network.target. Jul 2 00:46:49.791815 systemd[1]: Starting iscsiuio.service... Jul 2 00:46:49.792955 systemd-networkd[742]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 2 00:46:49.794140 ignition[657]: op(1): [finished] loading QEMU firmware config module Jul 2 00:46:49.794454 systemd-networkd[742]: eth0: Link UP Jul 2 00:46:49.794458 systemd-networkd[742]: eth0: Gained carrier Jul 2 00:46:49.798813 ignition[657]: parsing config with SHA512: 679b04b36a6ac7d6ffbf78edfe5a78d817e0de3648c4ed812181542db8da8168897456d774bb0ca627d1733e329803313c5b85a7ac9d6c7e67c78967d5e040e2 Jul 2 00:46:49.801000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsiuio comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:49.800972 systemd[1]: Started iscsiuio.service. Jul 2 00:46:49.802468 systemd[1]: Starting iscsid.service... Jul 2 00:46:49.805974 iscsid[749]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Jul 2 00:46:49.805974 iscsid[749]: iscsid: Warning: InitiatorName file /etc/iscsi/initiatorname.iscsi does not exist or does not contain a properly formatted InitiatorName. If using software iscsi (iscsi_tcp or ib_iser) or partial offload (bnx2i or cxgbi iscsi), you may not be able to log into or discover targets. Please create a file /etc/iscsi/initiatorname.iscsi that contains a sting with the format: InitiatorName=iqn.yyyy-mm.[:identifier]. Jul 2 00:46:49.805974 iscsid[749]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Jul 2 00:46:49.805974 iscsid[749]: If using hardware iscsi like qla4xxx this message can be ignored. Jul 2 00:46:49.805974 iscsid[749]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Jul 2 00:46:49.805974 iscsid[749]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Jul 2 00:46:49.811000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:49.811121 systemd[1]: Started iscsid.service. Jul 2 00:46:49.813330 systemd[1]: Starting dracut-initqueue.service... Jul 2 00:46:49.814630 systemd-networkd[742]: eth0: DHCPv4 address 10.0.0.62/16, gateway 10.0.0.1 acquired from 10.0.0.1 Jul 2 00:46:49.818306 unknown[657]: fetched base config from "system" Jul 2 00:46:49.818501 ignition[657]: fetch-offline: fetch-offline passed Jul 2 00:46:49.818314 unknown[657]: fetched user config from "qemu" Jul 2 00:46:49.818700 ignition[657]: Ignition finished successfully Jul 2 00:46:49.820000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch-offline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:49.819646 systemd[1]: Finished ignition-fetch-offline.service. Jul 2 00:46:49.820774 systemd[1]: ignition-fetch.service was skipped because of an unmet condition check (ConditionPathExists=!/run/ignition.json). Jul 2 00:46:49.821507 systemd[1]: Starting ignition-kargs.service... Jul 2 00:46:49.823982 systemd[1]: Finished dracut-initqueue.service. Jul 2 00:46:49.825000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-initqueue comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:49.826190 systemd[1]: Reached target remote-fs-pre.target. Jul 2 00:46:49.826845 systemd[1]: Reached target remote-cryptsetup.target. Jul 2 00:46:49.827455 systemd[1]: Reached target remote-fs.target. Jul 2 00:46:49.828770 systemd[1]: Starting dracut-pre-mount.service... Jul 2 00:46:49.832231 ignition[756]: Ignition 2.14.0 Jul 2 00:46:49.832239 ignition[756]: Stage: kargs Jul 2 00:46:49.832330 ignition[756]: no configs at "/usr/lib/ignition/base.d" Jul 2 00:46:49.834268 systemd[1]: Finished ignition-kargs.service. Jul 2 00:46:49.834000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:49.832340 ignition[756]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 2 00:46:49.832936 ignition[756]: kargs: kargs passed Jul 2 00:46:49.836200 systemd[1]: Starting ignition-disks.service... Jul 2 00:46:49.837000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:49.832977 ignition[756]: Ignition finished successfully Jul 2 00:46:49.837459 systemd[1]: Finished dracut-pre-mount.service. Jul 2 00:46:49.842092 ignition[769]: Ignition 2.14.0 Jul 2 00:46:49.842102 ignition[769]: Stage: disks Jul 2 00:46:49.842183 ignition[769]: no configs at "/usr/lib/ignition/base.d" Jul 2 00:46:49.843827 systemd[1]: Finished ignition-disks.service. Jul 2 00:46:49.844000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-disks comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:49.842192 ignition[769]: no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 2 00:46:49.845025 systemd[1]: Reached target initrd-root-device.target. Jul 2 00:46:49.842798 ignition[769]: disks: disks passed Jul 2 00:46:49.845963 systemd[1]: Reached target local-fs-pre.target. Jul 2 00:46:49.842835 ignition[769]: Ignition finished successfully Jul 2 00:46:49.847114 systemd[1]: Reached target local-fs.target. Jul 2 00:46:49.848106 systemd[1]: Reached target sysinit.target. Jul 2 00:46:49.848974 systemd[1]: Reached target basic.target. Jul 2 00:46:49.850732 systemd[1]: Starting systemd-fsck-root.service... Jul 2 00:46:49.861107 systemd-fsck[777]: ROOT: clean, 614/553520 files, 56019/553472 blocks Jul 2 00:46:49.864422 systemd[1]: Finished systemd-fsck-root.service. Jul 2 00:46:49.864000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:49.865806 systemd[1]: Mounting sysroot.mount... Jul 2 00:46:49.871368 systemd[1]: Mounted sysroot.mount. Jul 2 00:46:49.872377 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none. Jul 2 00:46:49.872804 systemd[1]: Reached target initrd-root-fs.target. Jul 2 00:46:49.874856 systemd[1]: Mounting sysroot-usr.mount... Jul 2 00:46:49.875756 systemd[1]: flatcar-metadata-hostname.service was skipped because no trigger condition checks were met. Jul 2 00:46:49.875794 systemd[1]: ignition-remount-sysroot.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Jul 2 00:46:49.875818 systemd[1]: Reached target ignition-diskful.target. Jul 2 00:46:49.877386 systemd[1]: Mounted sysroot-usr.mount. Jul 2 00:46:49.878629 systemd[1]: Starting initrd-setup-root.service... Jul 2 00:46:49.882702 initrd-setup-root[787]: cut: /sysroot/etc/passwd: No such file or directory Jul 2 00:46:49.886858 initrd-setup-root[795]: cut: /sysroot/etc/group: No such file or directory Jul 2 00:46:49.890182 initrd-setup-root[803]: cut: /sysroot/etc/shadow: No such file or directory Jul 2 00:46:49.893882 initrd-setup-root[811]: cut: /sysroot/etc/gshadow: No such file or directory Jul 2 00:46:49.920072 systemd[1]: Finished initrd-setup-root.service. Jul 2 00:46:49.920000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:49.921375 systemd[1]: Starting ignition-mount.service... Jul 2 00:46:49.922509 systemd[1]: Starting sysroot-boot.service... Jul 2 00:46:49.926606 bash[828]: umount: /sysroot/usr/share/oem: not mounted. Jul 2 00:46:49.934689 ignition[830]: INFO : Ignition 2.14.0 Jul 2 00:46:49.934689 ignition[830]: INFO : Stage: mount Jul 2 00:46:49.935860 ignition[830]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 2 00:46:49.935860 ignition[830]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 2 00:46:49.935860 ignition[830]: INFO : mount: mount passed Jul 2 00:46:49.935860 ignition[830]: INFO : Ignition finished successfully Jul 2 00:46:49.938144 systemd[1]: Finished ignition-mount.service. Jul 2 00:46:49.938000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:49.939513 systemd[1]: Finished sysroot-boot.service. Jul 2 00:46:49.939000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:50.627540 systemd[1]: Mounting sysroot-usr-share-oem.mount... Jul 2 00:46:50.634220 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/vda6 scanned by mount (838) Jul 2 00:46:50.634250 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Jul 2 00:46:50.634260 kernel: BTRFS info (device vda6): using free space tree Jul 2 00:46:50.634763 kernel: BTRFS info (device vda6): has skinny extents Jul 2 00:46:50.638062 systemd[1]: Mounted sysroot-usr-share-oem.mount. Jul 2 00:46:50.639458 systemd[1]: Starting ignition-files.service... Jul 2 00:46:50.653036 ignition[858]: INFO : Ignition 2.14.0 Jul 2 00:46:50.653036 ignition[858]: INFO : Stage: files Jul 2 00:46:50.654617 ignition[858]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 2 00:46:50.654617 ignition[858]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 2 00:46:50.654617 ignition[858]: DEBUG : files: compiled without relabeling support, skipping Jul 2 00:46:50.666077 ignition[858]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Jul 2 00:46:50.666077 ignition[858]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Jul 2 00:46:50.668218 ignition[858]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Jul 2 00:46:50.669274 ignition[858]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Jul 2 00:46:50.669274 ignition[858]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Jul 2 00:46:50.668939 unknown[858]: wrote ssh authorized keys file for user: core Jul 2 00:46:50.672532 ignition[858]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Jul 2 00:46:50.672532 ignition[858]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Jul 2 00:46:50.672532 ignition[858]: INFO : files: op(4): [started] processing unit "etcd-member.service" Jul 2 00:46:50.672532 ignition[858]: 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" Jul 2 00:46:50.672532 ignition[858]: 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" Jul 2 00:46:50.672532 ignition[858]: INFO : files: op(4): [finished] processing unit "etcd-member.service" Jul 2 00:46:50.672532 ignition[858]: INFO : files: op(6): [started] processing unit "coreos-metadata.service" Jul 2 00:46:50.672532 ignition[858]: INFO : files: op(6): op(7): [started] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Jul 2 00:46:50.672532 ignition[858]: INFO : files: op(6): op(7): [finished] writing unit "coreos-metadata.service" at "/sysroot/etc/systemd/system/coreos-metadata.service" Jul 2 00:46:50.672532 ignition[858]: INFO : files: op(6): [finished] processing unit "coreos-metadata.service" Jul 2 00:46:50.672532 ignition[858]: INFO : files: op(8): [started] setting preset to enabled for "etcd-member.service" Jul 2 00:46:50.672532 ignition[858]: INFO : files: op(8): [finished] setting preset to enabled for "etcd-member.service" Jul 2 00:46:50.672532 ignition[858]: INFO : files: op(9): [started] setting preset to disabled for "coreos-metadata.service" Jul 2 00:46:50.672532 ignition[858]: INFO : files: op(9): op(a): [started] removing enablement symlink(s) for "coreos-metadata.service" Jul 2 00:46:50.709956 ignition[858]: INFO : files: op(9): op(a): [finished] removing enablement symlink(s) for "coreos-metadata.service" Jul 2 00:46:50.711841 ignition[858]: INFO : files: op(9): [finished] setting preset to disabled for "coreos-metadata.service" Jul 2 00:46:50.711841 ignition[858]: INFO : files: createResultFile: createFiles: op(b): [started] writing file "/sysroot/etc/.ignition-result.json" Jul 2 00:46:50.711841 ignition[858]: INFO : files: createResultFile: createFiles: op(b): [finished] writing file "/sysroot/etc/.ignition-result.json" Jul 2 00:46:50.711841 ignition[858]: INFO : files: files passed Jul 2 00:46:50.711841 ignition[858]: INFO : Ignition finished successfully Jul 2 00:46:50.712000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:50.712016 systemd[1]: Finished ignition-files.service. Jul 2 00:46:50.714230 systemd[1]: Starting initrd-setup-root-after-ignition.service... Jul 2 00:46:50.719000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:50.719000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:50.715256 systemd[1]: torcx-profile-populate.service was skipped because of an unmet condition check (ConditionPathExists=/sysroot/etc/torcx/next-profile). Jul 2 00:46:50.722289 initrd-setup-root-after-ignition[882]: grep: /sysroot/usr/share/oem/oem-release: No such file or directory Jul 2 00:46:50.715960 systemd[1]: Starting ignition-quench.service... Jul 2 00:46:50.722000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root-after-ignition comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:50.725123 initrd-setup-root-after-ignition[884]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jul 2 00:46:50.718640 systemd[1]: ignition-quench.service: Deactivated successfully. Jul 2 00:46:50.718734 systemd[1]: Finished ignition-quench.service. Jul 2 00:46:50.721650 systemd[1]: Finished initrd-setup-root-after-ignition.service. Jul 2 00:46:50.723116 systemd[1]: Reached target ignition-complete.target. Jul 2 00:46:50.725301 systemd[1]: Starting initrd-parse-etc.service... Jul 2 00:46:50.738388 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Jul 2 00:46:50.738482 systemd[1]: Finished initrd-parse-etc.service. Jul 2 00:46:50.739000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:50.739000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:50.740027 systemd[1]: Reached target initrd-fs.target. Jul 2 00:46:50.740988 systemd[1]: Reached target initrd.target. Jul 2 00:46:50.742107 systemd[1]: dracut-mount.service was skipped because no trigger condition checks were met. Jul 2 00:46:50.742890 systemd[1]: Starting dracut-pre-pivot.service... Jul 2 00:46:50.755588 systemd[1]: Finished dracut-pre-pivot.service. Jul 2 00:46:50.755000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-pivot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:50.756963 systemd[1]: Starting initrd-cleanup.service... Jul 2 00:46:50.764954 systemd[1]: Stopped target network.target. Jul 2 00:46:50.765603 systemd[1]: Stopped target nss-lookup.target. Jul 2 00:46:50.766613 systemd[1]: Stopped target remote-cryptsetup.target. Jul 2 00:46:50.767749 systemd[1]: Stopped target timers.target. Jul 2 00:46:50.768857 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Jul 2 00:46:50.769000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-pivot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:50.768975 systemd[1]: Stopped dracut-pre-pivot.service. Jul 2 00:46:50.769937 systemd[1]: Stopped target initrd.target. Jul 2 00:46:50.770923 systemd[1]: Stopped target basic.target. Jul 2 00:46:50.771876 systemd[1]: Stopped target ignition-complete.target. Jul 2 00:46:50.772946 systemd[1]: Stopped target ignition-diskful.target. Jul 2 00:46:50.774066 systemd[1]: Stopped target initrd-root-device.target. Jul 2 00:46:50.775173 systemd[1]: Stopped target remote-fs.target. Jul 2 00:46:50.776269 systemd[1]: Stopped target remote-fs-pre.target. Jul 2 00:46:50.777394 systemd[1]: Stopped target sysinit.target. Jul 2 00:46:50.778322 systemd[1]: Stopped target local-fs.target. Jul 2 00:46:50.779286 systemd[1]: Stopped target local-fs-pre.target. Jul 2 00:46:50.780240 systemd[1]: Stopped target swap.target. Jul 2 00:46:50.781000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:50.781108 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Jul 2 00:46:50.781215 systemd[1]: Stopped dracut-pre-mount.service. Jul 2 00:46:50.783000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-initqueue comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:50.782214 systemd[1]: Stopped target cryptsetup.target. Jul 2 00:46:50.784000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch-offline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:50.783073 systemd[1]: dracut-initqueue.service: Deactivated successfully. Jul 2 00:46:50.783167 systemd[1]: Stopped dracut-initqueue.service. Jul 2 00:46:50.784259 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Jul 2 00:46:50.784350 systemd[1]: Stopped ignition-fetch-offline.service. Jul 2 00:46:50.785284 systemd[1]: Stopped target paths.target. Jul 2 00:46:50.786117 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Jul 2 00:46:50.789594 systemd[1]: Stopped systemd-ask-password-console.path. Jul 2 00:46:50.790311 systemd[1]: Stopped target slices.target. Jul 2 00:46:50.791304 systemd[1]: Stopped target sockets.target. Jul 2 00:46:50.792213 systemd[1]: iscsid.socket: Deactivated successfully. Jul 2 00:46:50.792282 systemd[1]: Closed iscsid.socket. Jul 2 00:46:50.793112 systemd[1]: iscsiuio.socket: Deactivated successfully. Jul 2 00:46:50.795000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root-after-ignition comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:50.793174 systemd[1]: Closed iscsiuio.socket. Jul 2 00:46:50.795000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:50.794083 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Jul 2 00:46:50.794175 systemd[1]: Stopped initrd-setup-root-after-ignition.service. Jul 2 00:46:50.795170 systemd[1]: ignition-files.service: Deactivated successfully. Jul 2 00:46:50.798000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:50.795257 systemd[1]: Stopped ignition-files.service. Jul 2 00:46:50.797225 systemd[1]: Stopping ignition-mount.service... Jul 2 00:46:50.797890 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Jul 2 00:46:50.798006 systemd[1]: Stopped kmod-static-nodes.service. Jul 2 00:46:50.799783 systemd[1]: Stopping sysroot-boot.service... Jul 2 00:46:50.803000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:50.800826 systemd[1]: Stopping systemd-networkd.service... Jul 2 00:46:50.804000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:50.802127 systemd[1]: Stopping systemd-resolved.service... Jul 2 00:46:50.803129 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Jul 2 00:46:50.803226 systemd[1]: Stopped systemd-udev-trigger.service. Jul 2 00:46:50.804281 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Jul 2 00:46:50.804369 systemd[1]: Stopped dracut-pre-trigger.service. Jul 2 00:46:50.809239 ignition[897]: INFO : Ignition 2.14.0 Jul 2 00:46:50.809239 ignition[897]: INFO : Stage: umount Jul 2 00:46:50.809239 ignition[897]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 2 00:46:50.809239 ignition[897]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/qemu" Jul 2 00:46:50.809000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:50.809000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:50.810000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:50.812000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:50.814000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-disks comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:50.807602 systemd-networkd[742]: eth0: DHCPv6 lease lost Jul 2 00:46:50.817000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:50.817941 ignition[897]: INFO : umount: umount passed Jul 2 00:46:50.817941 ignition[897]: INFO : Ignition finished successfully Jul 2 00:46:50.818000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:50.808999 systemd[1]: initrd-cleanup.service: Deactivated successfully. Jul 2 00:46:50.809094 systemd[1]: Finished initrd-cleanup.service. Jul 2 00:46:50.820000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=parse-ip-for-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:50.820000 audit: BPF prog-id=9 op=UNLOAD Jul 2 00:46:50.810215 systemd[1]: systemd-networkd.service: Deactivated successfully. Jul 2 00:46:50.821000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:50.810318 systemd[1]: Stopped systemd-networkd.service. Jul 2 00:46:50.811393 systemd[1]: ignition-mount.service: Deactivated successfully. Jul 2 00:46:50.811459 systemd[1]: Stopped ignition-mount.service. Jul 2 00:46:50.824000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:50.812490 systemd[1]: systemd-networkd.socket: Deactivated successfully. Jul 2 00:46:50.812520 systemd[1]: Closed systemd-networkd.socket. Jul 2 00:46:50.813330 systemd[1]: ignition-disks.service: Deactivated successfully. Jul 2 00:46:50.813367 systemd[1]: Stopped ignition-disks.service. Jul 2 00:46:50.830000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:50.815612 systemd[1]: ignition-kargs.service: Deactivated successfully. Jul 2 00:46:50.831000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:50.815654 systemd[1]: Stopped ignition-kargs.service. Jul 2 00:46:50.817422 systemd[1]: ignition-setup.service: Deactivated successfully. Jul 2 00:46:50.833000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:50.817462 systemd[1]: Stopped ignition-setup.service. Jul 2 00:46:50.835000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:50.819359 systemd[1]: Stopping network-cleanup.service... Jul 2 00:46:50.836000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=network-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:50.820249 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Jul 2 00:46:50.837000 audit: BPF prog-id=6 op=UNLOAD Jul 2 00:46:50.820308 systemd[1]: Stopped parse-ip-for-networkd.service. Jul 2 00:46:50.821501 systemd[1]: systemd-sysctl.service: Deactivated successfully. Jul 2 00:46:50.840000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:50.821542 systemd[1]: Stopped systemd-sysctl.service. Jul 2 00:46:50.841000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:50.823702 systemd[1]: systemd-modules-load.service: Deactivated successfully. Jul 2 00:46:50.823744 systemd[1]: Stopped systemd-modules-load.service. Jul 2 00:46:50.825211 systemd[1]: Stopping systemd-udevd.service... Jul 2 00:46:50.829507 systemd[1]: sysroot-boot.mount: Deactivated successfully. Jul 2 00:46:50.845000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:50.829592 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Jul 2 00:46:50.830132 systemd[1]: systemd-resolved.service: Deactivated successfully. Jul 2 00:46:50.830214 systemd[1]: Stopped systemd-resolved.service. Jul 2 00:46:50.847000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:50.831267 systemd[1]: sysroot-boot.service: Deactivated successfully. Jul 2 00:46:50.831340 systemd[1]: Stopped sysroot-boot.service. Jul 2 00:46:50.832667 systemd[1]: initrd-setup-root.service: Deactivated successfully. Jul 2 00:46:50.832723 systemd[1]: Stopped initrd-setup-root.service. Jul 2 00:46:50.834851 systemd[1]: systemd-udevd.service: Deactivated successfully. Jul 2 00:46:50.835007 systemd[1]: Stopped systemd-udevd.service. Jul 2 00:46:50.852000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-udevadm-cleanup-db comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:50.852000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-udevadm-cleanup-db comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:50.836023 systemd[1]: network-cleanup.service: Deactivated successfully. Jul 2 00:46:50.836101 systemd[1]: Stopped network-cleanup.service. Jul 2 00:46:50.836863 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Jul 2 00:46:50.836894 systemd[1]: Closed systemd-udevd-control.socket. Jul 2 00:46:50.838219 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Jul 2 00:46:50.838250 systemd[1]: Closed systemd-udevd-kernel.socket. Jul 2 00:46:50.840074 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Jul 2 00:46:50.840115 systemd[1]: Stopped dracut-pre-udev.service. Jul 2 00:46:50.841103 systemd[1]: dracut-cmdline.service: Deactivated successfully. Jul 2 00:46:50.841135 systemd[1]: Stopped dracut-cmdline.service. Jul 2 00:46:50.842299 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jul 2 00:46:50.842334 systemd[1]: Stopped dracut-cmdline-ask.service. Jul 2 00:46:50.846057 systemd[1]: Starting initrd-udevadm-cleanup-db.service... Jul 2 00:46:50.847336 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 2 00:46:50.847390 systemd[1]: Stopped systemd-vconsole-setup.service. Jul 2 00:46:50.851283 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Jul 2 00:46:50.851361 systemd[1]: Finished initrd-udevadm-cleanup-db.service. Jul 2 00:46:50.852969 systemd[1]: Reached target initrd-switch-root.target. Jul 2 00:46:50.854533 systemd[1]: Starting initrd-switch-root.service... Jul 2 00:46:50.860752 systemd[1]: Switching root. Jul 2 00:46:50.883350 iscsid[749]: iscsid shutting down. Jul 2 00:46:50.883977 systemd-journald[290]: Received SIGTERM from PID 1 (systemd). Jul 2 00:46:50.884027 systemd-journald[290]: Journal stopped Jul 2 00:46:52.878786 kernel: SELinux: Class mctp_socket not defined in policy. Jul 2 00:46:52.878839 kernel: SELinux: Class anon_inode not defined in policy. Jul 2 00:46:52.878851 kernel: SELinux: the above unknown classes and permissions will be allowed Jul 2 00:46:52.878863 kernel: SELinux: policy capability network_peer_controls=1 Jul 2 00:46:52.878873 kernel: SELinux: policy capability open_perms=1 Jul 2 00:46:52.878887 kernel: SELinux: policy capability extended_socket_class=1 Jul 2 00:46:52.878896 kernel: SELinux: policy capability always_check_network=0 Jul 2 00:46:52.878906 kernel: SELinux: policy capability cgroup_seclabel=1 Jul 2 00:46:52.878916 kernel: SELinux: policy capability nnp_nosuid_transition=1 Jul 2 00:46:52.878926 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Jul 2 00:46:52.878938 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Jul 2 00:46:52.878948 systemd[1]: Successfully loaded SELinux policy in 47.945ms. Jul 2 00:46:52.878969 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 7.573ms. Jul 2 00:46:52.878981 systemd[1]: systemd 252 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) Jul 2 00:46:52.878992 systemd[1]: Detected virtualization kvm. Jul 2 00:46:52.879002 systemd[1]: Detected architecture arm64. Jul 2 00:46:52.879012 systemd[1]: Detected first boot. Jul 2 00:46:52.879023 systemd[1]: Initializing machine ID from VM UUID. Jul 2 00:46:52.879033 kernel: SELinux: Context system_u:object_r:container_file_t:s0:c1022,c1023 is not valid (left unmapped). Jul 2 00:46:52.879045 systemd[1]: Populated /etc with preset unit settings. Jul 2 00:46:52.879056 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Jul 2 00:46:52.879067 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Jul 2 00:46:52.879079 systemd[1]: /run/systemd/system/docker.socket:8: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Jul 2 00:46:52.879089 kernel: kauditd_printk_skb: 77 callbacks suppressed Jul 2 00:46:52.879099 kernel: audit: type=1334 audit(1719881212.749:81): prog-id=12 op=LOAD Jul 2 00:46:52.879109 kernel: audit: type=1334 audit(1719881212.749:82): prog-id=3 op=UNLOAD Jul 2 00:46:52.879118 kernel: audit: type=1334 audit(1719881212.749:83): prog-id=13 op=LOAD Jul 2 00:46:52.879129 kernel: audit: type=1334 audit(1719881212.749:84): prog-id=14 op=LOAD Jul 2 00:46:52.879140 kernel: audit: type=1334 audit(1719881212.749:85): prog-id=4 op=UNLOAD Jul 2 00:46:52.879149 kernel: audit: type=1334 audit(1719881212.749:86): prog-id=5 op=UNLOAD Jul 2 00:46:52.879159 kernel: audit: type=1334 audit(1719881212.750:87): prog-id=15 op=LOAD Jul 2 00:46:52.879170 kernel: audit: type=1334 audit(1719881212.750:88): prog-id=12 op=UNLOAD Jul 2 00:46:52.879180 systemd[1]: iscsiuio.service: Deactivated successfully. Jul 2 00:46:52.879190 kernel: audit: type=1334 audit(1719881212.751:89): prog-id=16 op=LOAD Jul 2 00:46:52.879199 kernel: audit: type=1334 audit(1719881212.751:90): prog-id=17 op=LOAD Jul 2 00:46:52.879209 systemd[1]: Stopped iscsiuio.service. Jul 2 00:46:52.879221 systemd[1]: iscsid.service: Deactivated successfully. Jul 2 00:46:52.879235 systemd[1]: Stopped iscsid.service. Jul 2 00:46:52.879245 systemd[1]: initrd-switch-root.service: Deactivated successfully. Jul 2 00:46:52.879255 systemd[1]: Stopped initrd-switch-root.service. Jul 2 00:46:52.879266 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Jul 2 00:46:52.879280 systemd[1]: Created slice system-addon\x2dconfig.slice. Jul 2 00:46:52.879293 systemd[1]: Created slice system-addon\x2drun.slice. Jul 2 00:46:52.879303 systemd[1]: Created slice system-getty.slice. Jul 2 00:46:52.879313 systemd[1]: Created slice system-modprobe.slice. Jul 2 00:46:52.879323 systemd[1]: Created slice system-serial\x2dgetty.slice. Jul 2 00:46:52.879334 systemd[1]: Created slice system-system\x2dcloudinit.slice. Jul 2 00:46:52.879345 systemd[1]: Created slice system-systemd\x2dfsck.slice. Jul 2 00:46:52.879355 systemd[1]: Created slice user.slice. Jul 2 00:46:52.879366 systemd[1]: Started systemd-ask-password-console.path. Jul 2 00:46:52.879376 systemd[1]: Started systemd-ask-password-wall.path. Jul 2 00:46:52.879388 systemd[1]: Set up automount boot.automount. Jul 2 00:46:52.879400 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount. Jul 2 00:46:52.879410 systemd[1]: Stopped target initrd-switch-root.target. Jul 2 00:46:52.879420 systemd[1]: Stopped target initrd-fs.target. Jul 2 00:46:52.879430 systemd[1]: Stopped target initrd-root-fs.target. Jul 2 00:46:52.879441 systemd[1]: Reached target integritysetup.target. Jul 2 00:46:52.879451 systemd[1]: Reached target remote-cryptsetup.target. Jul 2 00:46:52.879461 systemd[1]: Reached target remote-fs.target. Jul 2 00:46:52.879471 systemd[1]: Reached target slices.target. Jul 2 00:46:52.879481 systemd[1]: Reached target swap.target. Jul 2 00:46:52.879493 systemd[1]: Reached target torcx.target. Jul 2 00:46:52.879504 systemd[1]: Reached target veritysetup.target. Jul 2 00:46:52.879516 systemd[1]: Listening on systemd-coredump.socket. Jul 2 00:46:52.879526 systemd[1]: Listening on systemd-initctl.socket. Jul 2 00:46:52.879536 systemd[1]: Listening on systemd-networkd.socket. Jul 2 00:46:52.879557 systemd[1]: Listening on systemd-udevd-control.socket. Jul 2 00:46:52.879569 systemd[1]: Listening on systemd-udevd-kernel.socket. Jul 2 00:46:52.879579 systemd[1]: Listening on systemd-userdbd.socket. Jul 2 00:46:52.879591 systemd[1]: Mounting dev-hugepages.mount... Jul 2 00:46:52.879601 systemd[1]: Mounting dev-mqueue.mount... Jul 2 00:46:52.879611 systemd[1]: Mounting media.mount... Jul 2 00:46:52.879621 systemd[1]: Mounting sys-kernel-debug.mount... Jul 2 00:46:52.879633 systemd[1]: Mounting sys-kernel-tracing.mount... Jul 2 00:46:52.879643 systemd[1]: Mounting tmp.mount... Jul 2 00:46:52.879653 systemd[1]: Starting flatcar-tmpfiles.service... Jul 2 00:46:52.879671 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Jul 2 00:46:52.879682 systemd[1]: Starting kmod-static-nodes.service... Jul 2 00:46:52.879692 systemd[1]: Starting modprobe@configfs.service... Jul 2 00:46:52.879702 systemd[1]: Starting modprobe@dm_mod.service... Jul 2 00:46:52.879712 systemd[1]: Starting modprobe@drm.service... Jul 2 00:46:52.879723 systemd[1]: Starting modprobe@efi_pstore.service... Jul 2 00:46:52.879733 systemd[1]: Starting modprobe@fuse.service... Jul 2 00:46:52.879743 systemd[1]: Starting modprobe@loop.service... Jul 2 00:46:52.879754 systemd[1]: setup-nsswitch.service was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Jul 2 00:46:52.879766 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Jul 2 00:46:52.879776 systemd[1]: Stopped systemd-fsck-root.service. Jul 2 00:46:52.879787 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Jul 2 00:46:52.879797 systemd[1]: Stopped systemd-fsck-usr.service. Jul 2 00:46:52.879807 systemd[1]: Stopped systemd-journald.service. Jul 2 00:46:52.879817 kernel: loop: module loaded Jul 2 00:46:52.879827 kernel: fuse: init (API version 7.34) Jul 2 00:46:52.879837 systemd[1]: Starting systemd-journald.service... Jul 2 00:46:52.879848 systemd[1]: Starting systemd-modules-load.service... Jul 2 00:46:52.879861 systemd[1]: Starting systemd-network-generator.service... Jul 2 00:46:52.879871 systemd[1]: Starting systemd-remount-fs.service... Jul 2 00:46:52.879881 systemd[1]: Starting systemd-udev-trigger.service... Jul 2 00:46:52.879892 systemd[1]: verity-setup.service: Deactivated successfully. Jul 2 00:46:52.879903 systemd[1]: Stopped verity-setup.service. Jul 2 00:46:52.879913 systemd[1]: Mounted dev-hugepages.mount. Jul 2 00:46:52.879924 systemd[1]: Mounted dev-mqueue.mount. Jul 2 00:46:52.879934 systemd[1]: Mounted media.mount. Jul 2 00:46:52.879945 systemd[1]: Mounted sys-kernel-debug.mount. Jul 2 00:46:52.879956 systemd[1]: Mounted sys-kernel-tracing.mount. Jul 2 00:46:52.879967 systemd[1]: Mounted tmp.mount. Jul 2 00:46:52.879977 systemd[1]: Finished kmod-static-nodes.service. Jul 2 00:46:52.879987 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jul 2 00:46:52.879997 systemd[1]: Finished modprobe@configfs.service. Jul 2 00:46:52.880010 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 2 00:46:52.880020 systemd[1]: Finished modprobe@dm_mod.service. Jul 2 00:46:52.880030 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 2 00:46:52.880041 systemd[1]: Finished modprobe@drm.service. Jul 2 00:46:52.880051 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 2 00:46:52.880064 systemd-journald[992]: Journal started Jul 2 00:46:52.880104 systemd-journald[992]: Runtime Journal (/run/log/journal/84e426444bae42ed84c3b4aeac44ba0d) is 6.0M, max 48.7M, 42.6M free. Jul 2 00:46:50.958000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Jul 2 00:46:50.992000 audit[1]: AVC avc: denied { bpf } for pid=1 comm="systemd" capability=39 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Jul 2 00:46:50.992000 audit[1]: AVC avc: denied { perfmon } for pid=1 comm="systemd" capability=38 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Jul 2 00:46:50.992000 audit: BPF prog-id=10 op=LOAD Jul 2 00:46:50.993000 audit: BPF prog-id=10 op=UNLOAD Jul 2 00:46:50.993000 audit: BPF prog-id=11 op=LOAD Jul 2 00:46:50.993000 audit: BPF prog-id=11 op=UNLOAD Jul 2 00:46:51.032000 audit[930]: AVC avc: denied { associate } for pid=930 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 srawcon="system_u:object_r:container_file_t:s0:c1022,c1023" Jul 2 00:46:51.032000 audit[930]: SYSCALL arch=c00000b7 syscall=5 success=yes exit=0 a0=4000022314 a1=40000283d8 a2=4000026840 a3=32 items=0 ppid=913 pid=930 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="torcx-generator" exe="/usr/lib/systemd/system-generators/torcx-generator" subj=system_u:system_r:kernel_t:s0 key=(null) Jul 2 00:46:51.032000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Jul 2 00:46:51.033000 audit[930]: AVC avc: denied { associate } for pid=930 comm="torcx-generator" name="lib" scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Jul 2 00:46:51.033000 audit[930]: SYSCALL arch=c00000b7 syscall=34 success=yes exit=0 a0=ffffffffffffff9c a1=40000223f9 a2=1ed a3=0 items=2 ppid=913 pid=930 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="torcx-generator" exe="/usr/lib/systemd/system-generators/torcx-generator" subj=system_u:system_r:kernel_t:s0 key=(null) Jul 2 00:46:51.033000 audit: CWD cwd="/" Jul 2 00:46:51.033000 audit: PATH item=0 name=(null) inode=2 dev=00:1c mode=040755 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:unlabeled_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Jul 2 00:46:51.033000 audit: PATH item=1 name=(null) inode=3 dev=00:1c mode=040755 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:unlabeled_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Jul 2 00:46:51.033000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Jul 2 00:46:52.749000 audit: BPF prog-id=12 op=LOAD Jul 2 00:46:52.749000 audit: BPF prog-id=3 op=UNLOAD Jul 2 00:46:52.749000 audit: BPF prog-id=13 op=LOAD Jul 2 00:46:52.749000 audit: BPF prog-id=14 op=LOAD Jul 2 00:46:52.749000 audit: BPF prog-id=4 op=UNLOAD Jul 2 00:46:52.749000 audit: BPF prog-id=5 op=UNLOAD Jul 2 00:46:52.750000 audit: BPF prog-id=15 op=LOAD Jul 2 00:46:52.750000 audit: BPF prog-id=12 op=UNLOAD Jul 2 00:46:52.880671 systemd[1]: Finished modprobe@efi_pstore.service. Jul 2 00:46:52.751000 audit: BPF prog-id=16 op=LOAD Jul 2 00:46:52.751000 audit: BPF prog-id=17 op=LOAD Jul 2 00:46:52.751000 audit: BPF prog-id=13 op=UNLOAD Jul 2 00:46:52.751000 audit: BPF prog-id=14 op=UNLOAD Jul 2 00:46:52.752000 audit: BPF prog-id=18 op=LOAD Jul 2 00:46:52.752000 audit: BPF prog-id=15 op=UNLOAD Jul 2 00:46:52.753000 audit: BPF prog-id=19 op=LOAD Jul 2 00:46:52.754000 audit: BPF prog-id=20 op=LOAD Jul 2 00:46:52.754000 audit: BPF prog-id=16 op=UNLOAD Jul 2 00:46:52.754000 audit: BPF prog-id=17 op=UNLOAD Jul 2 00:46:52.754000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:52.758000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=iscsiuio comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:52.760000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:52.762000 audit: BPF prog-id=18 op=UNLOAD Jul 2 00:46:52.762000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:52.762000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:52.837000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:52.838000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck-usr comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:52.840000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:52.840000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:52.842000 audit: BPF prog-id=21 op=LOAD Jul 2 00:46:52.842000 audit: BPF prog-id=22 op=LOAD Jul 2 00:46:52.842000 audit: BPF prog-id=23 op=LOAD Jul 2 00:46:52.842000 audit: BPF prog-id=19 op=UNLOAD Jul 2 00:46:52.842000 audit: BPF prog-id=20 op=UNLOAD Jul 2 00:46:52.862000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=verity-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:52.872000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:52.873000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:52.873000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:52.876000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:52.876000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:52.877000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Jul 2 00:46:52.877000 audit[992]: SYSCALL arch=c00000b7 syscall=211 success=yes exit=60 a0=4 a1=ffffc7544d70 a2=4000 a3=1 items=0 ppid=1 pid=992 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="systemd-journal" exe="/usr/lib/systemd/systemd-journald" subj=system_u:system_r:kernel_t:s0 key=(null) Jul 2 00:46:52.877000 audit: PROCTITLE proctitle="/usr/lib/systemd/systemd-journald" Jul 2 00:46:52.878000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:52.878000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:52.880000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:52.880000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:51.030612 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2024-07-02T00:46:51Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3510.3.5 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.5 /var/lib/torcx/store]" Jul 2 00:46:52.747865 systemd[1]: Queued start job for default target multi-user.target. Jul 2 00:46:51.030913 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2024-07-02T00:46:51Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Jul 2 00:46:52.747878 systemd[1]: Unnecessary job was removed for dev-vda6.device. Jul 2 00:46:51.030931 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2024-07-02T00:46:51Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Jul 2 00:46:52.754882 systemd[1]: systemd-journald.service: Deactivated successfully. Jul 2 00:46:51.030961 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2024-07-02T00:46:51Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Jul 2 00:46:52.881865 systemd[1]: Started systemd-journald.service. Jul 2 00:46:51.030971 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2024-07-02T00:46:51Z" level=debug msg="skipped missing lower profile" missing profile=oem Jul 2 00:46:51.031000 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2024-07-02T00:46:51Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Jul 2 00:46:51.031011 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2024-07-02T00:46:51Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Jul 2 00:46:51.031196 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2024-07-02T00:46:51Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Jul 2 00:46:51.031243 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2024-07-02T00:46:51Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Jul 2 00:46:51.031255 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2024-07-02T00:46:51Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Jul 2 00:46:51.032155 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2024-07-02T00:46:51Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:20.10.torcx.tgz" reference=20.10 Jul 2 00:46:51.032487 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2024-07-02T00:46:51Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:com.coreos.cl.torcx.tgz" reference=com.coreos.cl Jul 2 00:46:52.881000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:51.032520 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2024-07-02T00:46:51Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3510.3.5: no such file or directory" path=/usr/share/oem/torcx/store/3510.3.5 Jul 2 00:46:51.032534 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2024-07-02T00:46:51Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Jul 2 00:46:51.032570 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2024-07-02T00:46:51Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3510.3.5: no such file or directory" path=/var/lib/torcx/store/3510.3.5 Jul 2 00:46:51.032584 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2024-07-02T00:46:51Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Jul 2 00:46:52.882917 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jul 2 00:46:52.470531 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2024-07-02T00:46:52Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Jul 2 00:46:52.470810 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2024-07-02T00:46:52Z" level=debug msg="binaries propagated" assets="[/bin/containerd /bin/containerd-shim /bin/ctr /bin/docker /bin/docker-containerd /bin/docker-containerd-shim /bin/docker-init /bin/docker-proxy /bin/docker-runc /bin/dockerd /bin/runc /bin/tini]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Jul 2 00:46:52.883079 systemd[1]: Finished modprobe@fuse.service. Jul 2 00:46:52.470915 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2024-07-02T00:46:52Z" level=debug msg="networkd units propagated" assets="[/lib/systemd/network/50-docker.network /lib/systemd/network/90-docker-veth.network]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Jul 2 00:46:52.471080 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2024-07-02T00:46:52Z" level=debug msg="systemd units propagated" assets="[/lib/systemd/system/containerd.service /lib/systemd/system/docker.service /lib/systemd/system/docker.socket /lib/systemd/system/sockets.target.wants /lib/systemd/system/multi-user.target.wants]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Jul 2 00:46:52.471130 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2024-07-02T00:46:52Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Jul 2 00:46:52.471182 /usr/lib/systemd/system-generators/torcx-generator[930]: time="2024-07-02T00:46:52Z" level=debug msg="system state sealed" content="[TORCX_LOWER_PROFILES=\"vendor\" TORCX_UPPER_PROFILE=\"\" TORCX_PROFILE_PATH=\"/run/torcx/profile.json\" TORCX_BINDIR=\"/run/torcx/bin\" TORCX_UNPACKDIR=\"/run/torcx/unpack\"]" path=/run/metadata/torcx Jul 2 00:46:52.883000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@fuse comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:52.883000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@fuse comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:52.884077 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 2 00:46:52.884228 systemd[1]: Finished modprobe@loop.service. Jul 2 00:46:52.884000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:52.884000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:52.885151 systemd[1]: Finished systemd-modules-load.service. Jul 2 00:46:52.885000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:52.886059 systemd[1]: Finished systemd-network-generator.service. Jul 2 00:46:52.886000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-network-generator comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:52.887336 systemd[1]: Finished systemd-remount-fs.service. Jul 2 00:46:52.887000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-remount-fs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:52.888407 systemd[1]: Reached target network-pre.target. Jul 2 00:46:52.890124 systemd[1]: Mounting sys-fs-fuse-connections.mount... Jul 2 00:46:52.891911 systemd[1]: Mounting sys-kernel-config.mount... Jul 2 00:46:52.892478 systemd[1]: remount-root.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Jul 2 00:46:52.894029 systemd[1]: Starting systemd-hwdb-update.service... Jul 2 00:46:52.895717 systemd[1]: Starting systemd-journal-flush.service... Jul 2 00:46:52.896461 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 2 00:46:52.897414 systemd[1]: Starting systemd-random-seed.service... Jul 2 00:46:52.898179 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Jul 2 00:46:52.899218 systemd[1]: Starting systemd-sysctl.service... Jul 2 00:46:52.902781 systemd[1]: Finished flatcar-tmpfiles.service. Jul 2 00:46:52.903000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=flatcar-tmpfiles comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:52.903728 systemd[1]: Mounted sys-fs-fuse-connections.mount. Jul 2 00:46:52.904526 systemd[1]: Mounted sys-kernel-config.mount. Jul 2 00:46:52.906842 systemd-journald[992]: Time spent on flushing to /var/log/journal/84e426444bae42ed84c3b4aeac44ba0d is 11.823ms for 969 entries. Jul 2 00:46:52.906842 systemd-journald[992]: System Journal (/var/log/journal/84e426444bae42ed84c3b4aeac44ba0d) is 8.0M, max 195.6M, 187.6M free. Jul 2 00:46:52.930176 systemd-journald[992]: Received client request to flush runtime journal. Jul 2 00:46:52.912000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:52.916000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-random-seed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:52.919000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:52.928000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysusers comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:52.906226 systemd[1]: Starting systemd-sysusers.service... Jul 2 00:46:52.912854 systemd[1]: Finished systemd-udev-trigger.service. Jul 2 00:46:52.914512 systemd[1]: Starting systemd-udev-settle.service... Jul 2 00:46:52.930836 udevadm[1031]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Jul 2 00:46:52.915388 systemd[1]: Finished systemd-random-seed.service. Jul 2 00:46:52.916190 systemd[1]: Reached target first-boot-complete.target. Jul 2 00:46:52.919864 systemd[1]: Finished systemd-sysctl.service. Jul 2 00:46:52.928831 systemd[1]: Finished systemd-sysusers.service. Jul 2 00:46:52.931019 systemd[1]: Finished systemd-journal-flush.service. Jul 2 00:46:52.931000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journal-flush comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:53.270935 systemd[1]: Finished systemd-hwdb-update.service. Jul 2 00:46:53.271000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-hwdb-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:53.271000 audit: BPF prog-id=24 op=LOAD Jul 2 00:46:53.271000 audit: BPF prog-id=25 op=LOAD Jul 2 00:46:53.271000 audit: BPF prog-id=7 op=UNLOAD Jul 2 00:46:53.271000 audit: BPF prog-id=8 op=UNLOAD Jul 2 00:46:53.273010 systemd[1]: Starting systemd-udevd.service... Jul 2 00:46:53.288649 systemd-udevd[1033]: Using default interface naming scheme 'v252'. Jul 2 00:46:53.300522 systemd[1]: Started systemd-udevd.service. Jul 2 00:46:53.300000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:53.301000 audit: BPF prog-id=26 op=LOAD Jul 2 00:46:53.302651 systemd[1]: Starting systemd-networkd.service... Jul 2 00:46:53.314000 audit: BPF prog-id=27 op=LOAD Jul 2 00:46:53.314000 audit: BPF prog-id=28 op=LOAD Jul 2 00:46:53.314000 audit: BPF prog-id=29 op=LOAD Jul 2 00:46:53.316151 systemd[1]: Starting systemd-userdbd.service... Jul 2 00:46:53.336857 systemd[1]: Condition check resulted in dev-ttyAMA0.device being skipped. Jul 2 00:46:53.350082 systemd[1]: Started systemd-userdbd.service. Jul 2 00:46:53.350000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-userdbd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:53.388617 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Jul 2 00:46:53.403235 systemd-networkd[1039]: lo: Link UP Jul 2 00:46:53.403486 systemd-networkd[1039]: lo: Gained carrier Jul 2 00:46:53.403921 systemd-networkd[1039]: Enumeration completed Jul 2 00:46:53.404103 systemd[1]: Started systemd-networkd.service. Jul 2 00:46:53.404198 systemd-networkd[1039]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 2 00:46:53.404000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:53.405439 systemd-networkd[1039]: eth0: Link UP Jul 2 00:46:53.405514 systemd-networkd[1039]: eth0: Gained carrier Jul 2 00:46:53.405985 systemd[1]: Starting systemd-networkd-wait-online.service... Jul 2 00:46:53.411905 systemd[1]: Finished systemd-udev-settle.service. Jul 2 00:46:53.412000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udev-settle comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:53.413623 systemd[1]: Starting lvm2-activation-early.service... Jul 2 00:46:53.424691 systemd-networkd[1039]: eth0: DHCPv4 address 10.0.0.62/16, gateway 10.0.0.1 acquired from 10.0.0.1 Jul 2 00:46:53.431163 lvm[1067]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Jul 2 00:46:53.456312 systemd[1]: Finished lvm2-activation-early.service. Jul 2 00:46:53.456000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=lvm2-activation-early comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:53.457169 systemd[1]: Reached target cryptsetup.target. Jul 2 00:46:53.458824 systemd[1]: Starting lvm2-activation.service... Jul 2 00:46:53.462177 lvm[1068]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Jul 2 00:46:53.493481 systemd[1]: Finished lvm2-activation.service. Jul 2 00:46:53.493000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=lvm2-activation comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:53.494487 systemd[1]: Reached target local-fs-pre.target. Jul 2 00:46:53.495205 systemd[1]: var-lib-machines.mount was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Jul 2 00:46:53.495234 systemd[1]: Reached target local-fs.target. Jul 2 00:46:53.495864 systemd[1]: Reached target machines.target. Jul 2 00:46:53.497498 systemd[1]: Starting ldconfig.service... Jul 2 00:46:53.498443 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Jul 2 00:46:53.498495 systemd[1]: systemd-boot-system-token.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/LoaderFeatures-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Jul 2 00:46:53.499461 systemd[1]: Starting systemd-boot-update.service... Jul 2 00:46:53.501344 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service... Jul 2 00:46:53.503314 systemd[1]: Starting systemd-machine-id-commit.service... Jul 2 00:46:53.504920 systemd[1]: systemd-sysext.service was skipped because no trigger condition checks were met. Jul 2 00:46:53.504970 systemd[1]: ensure-sysext.service was skipped because no trigger condition checks were met. Jul 2 00:46:53.505953 systemd[1]: Starting systemd-tmpfiles-setup.service... Jul 2 00:46:53.510915 systemd[1]: boot.automount: Got automount request for /boot, triggered by 1070 (bootctl) Jul 2 00:46:53.512235 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service... Jul 2 00:46:53.522530 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Jul 2 00:46:53.523116 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service. Jul 2 00:46:53.523000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck@dev-disk-by\x2dlabel-OEM comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:53.524340 systemd[1]: Finished systemd-machine-id-commit.service. Jul 2 00:46:53.524000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-machine-id-commit comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:53.527721 systemd-tmpfiles[1073]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Jul 2 00:46:53.529363 systemd-tmpfiles[1073]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Jul 2 00:46:53.532039 systemd-tmpfiles[1073]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Jul 2 00:46:53.543852 systemd-fsck[1078]: fsck.fat 4.2 (2021-01-31) Jul 2 00:46:53.543852 systemd-fsck[1078]: /dev/vda1: 236 files, 117047/258078 clusters Jul 2 00:46:53.546433 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service. Jul 2 00:46:53.547000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:53.549010 systemd[1]: Mounting boot.mount... Jul 2 00:46:53.556827 systemd[1]: Mounted boot.mount. Jul 2 00:46:53.563639 systemd[1]: Finished systemd-boot-update.service. Jul 2 00:46:53.564000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-boot-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:53.633060 systemd[1]: Finished systemd-tmpfiles-setup.service. Jul 2 00:46:53.633000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:53.635490 systemd[1]: Starting audit-rules.service... Jul 2 00:46:53.637141 systemd[1]: Starting clean-ca-certificates.service... Jul 2 00:46:53.638929 systemd[1]: Starting systemd-journal-catalog-update.service... Jul 2 00:46:53.641887 ldconfig[1069]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jul 2 00:46:53.643000 audit: BPF prog-id=30 op=LOAD Jul 2 00:46:53.649000 audit: BPF prog-id=31 op=LOAD Jul 2 00:46:53.649140 systemd[1]: Starting systemd-resolved.service... Jul 2 00:46:53.651375 systemd[1]: Starting systemd-timesyncd.service... Jul 2 00:46:53.653825 systemd[1]: Starting systemd-update-utmp.service... Jul 2 00:46:53.655345 systemd[1]: Finished clean-ca-certificates.service. Jul 2 00:46:53.656493 systemd[1]: Finished ldconfig.service. Jul 2 00:46:53.655000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=clean-ca-certificates comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:53.656000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=ldconfig comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:53.657754 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Jul 2 00:46:53.660000 audit[1092]: SYSTEM_BOOT pid=1092 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg=' comm="systemd-update-utmp" exe="/usr/lib/systemd/systemd-update-utmp" hostname=? addr=? terminal=? res=success' Jul 2 00:46:53.664037 systemd[1]: Finished systemd-update-utmp.service. Jul 2 00:46:53.664000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-update-utmp comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:53.666992 systemd[1]: Finished systemd-journal-catalog-update.service. Jul 2 00:46:53.667000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journal-catalog-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:53.669148 systemd[1]: Starting systemd-update-done.service... Jul 2 00:46:53.678000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-update-done comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Jul 2 00:46:53.678323 systemd[1]: Finished systemd-update-done.service. Jul 2 00:46:53.695000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Jul 2 00:46:53.695000 audit[1103]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffd728c390 a2=420 a3=0 items=0 ppid=1081 pid=1103 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditctl" exe="/usr/sbin/auditctl" subj=system_u:system_r:kernel_t:s0 key=(null) Jul 2 00:46:53.695000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573 Jul 2 00:46:53.696196 augenrules[1103]: No rules Jul 2 00:46:53.697159 systemd[1]: Finished audit-rules.service. Jul 2 00:46:53.705582 systemd-resolved[1090]: Positive Trust Anchors: Jul 2 00:46:53.705595 systemd-resolved[1090]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 2 00:46:53.705622 systemd-resolved[1090]: 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 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Jul 2 00:46:53.714440 systemd[1]: Started systemd-timesyncd.service. Jul 2 00:46:53.715589 systemd-timesyncd[1091]: Contacted time server 10.0.0.1:123 (10.0.0.1). Jul 2 00:46:53.715644 systemd[1]: Reached target time-set.target. Jul 2 00:46:53.715648 systemd-timesyncd[1091]: Initial clock synchronization to Tue 2024-07-02 00:46:53.475977 UTC. Jul 2 00:46:53.717890 systemd-resolved[1090]: Defaulting to hostname 'linux'. Jul 2 00:46:53.719248 systemd[1]: Started systemd-resolved.service. Jul 2 00:46:53.719910 systemd[1]: Reached target network.target. Jul 2 00:46:53.720463 systemd[1]: Reached target nss-lookup.target. Jul 2 00:46:53.721085 systemd[1]: Reached target sysinit.target. Jul 2 00:46:53.721717 systemd[1]: Started motdgen.path. Jul 2 00:46:53.722232 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path. Jul 2 00:46:53.723195 systemd[1]: Started logrotate.timer. Jul 2 00:46:53.723861 systemd[1]: Started mdadm.timer. Jul 2 00:46:53.724393 systemd[1]: Started systemd-tmpfiles-clean.timer. Jul 2 00:46:53.725059 systemd[1]: update-engine-stub.timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Jul 2 00:46:53.725086 systemd[1]: Reached target paths.target. Jul 2 00:46:53.725623 systemd[1]: Reached target timers.target. Jul 2 00:46:53.726458 systemd[1]: Listening on dbus.socket. Jul 2 00:46:53.728211 systemd[1]: Starting docker.socket... Jul 2 00:46:53.731535 systemd[1]: Listening on sshd.socket. Jul 2 00:46:53.732225 systemd[1]: systemd-pcrphase-sysinit.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Jul 2 00:46:53.732814 systemd[1]: Listening on docker.socket. Jul 2 00:46:53.733467 systemd[1]: Reached target sockets.target. Jul 2 00:46:53.734095 systemd[1]: Reached target basic.target. Jul 2 00:46:53.734741 systemd[1]: addon-config@usr-share-oem.service was skipped because no trigger condition checks were met. Jul 2 00:46:53.734774 systemd[1]: addon-run@usr-share-oem.service was skipped because no trigger condition checks were met. Jul 2 00:46:53.736185 systemd[1]: Starting containerd.service... Jul 2 00:46:53.737762 systemd[1]: Starting dbus.service... Jul 2 00:46:53.739624 systemd[1]: Starting enable-oem-cloudinit.service... Jul 2 00:46:53.741587 systemd[1]: Starting extend-filesystems.service... Jul 2 00:46:53.742375 systemd[1]: flatcar-setup-environment.service was skipped because of an unmet condition check (ConditionPathExists=/usr/share/oem/bin/flatcar-setup-environment). Jul 2 00:46:53.743815 systemd[1]: Starting motdgen.service... Jul 2 00:46:53.746456 systemd[1]: Starting ssh-key-proc-cmdline.service... Jul 2 00:46:53.750357 systemd[1]: Starting sshd-keygen.service... Jul 2 00:46:53.753073 jq[1113]: false Jul 2 00:46:53.753176 systemd[1]: Starting systemd-logind.service... Jul 2 00:46:53.753935 systemd[1]: systemd-pcrphase.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Jul 2 00:46:53.753995 systemd[1]: tcsd.service was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Jul 2 00:46:53.754986 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Jul 2 00:46:53.755677 systemd[1]: Starting update-engine.service... Jul 2 00:46:53.757505 systemd[1]: Starting update-ssh-keys-after-ignition.service... Jul 2 00:46:53.759792 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Jul 2 00:46:53.759951 systemd[1]: Condition check resulted in enable-oem-cloudinit.service being skipped. Jul 2 00:46:53.760237 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Jul 2 00:46:53.760366 systemd[1]: Finished ssh-key-proc-cmdline.service. Jul 2 00:46:53.761187 jq[1127]: true Jul 2 00:46:53.768853 systemd[1]: motdgen.service: Deactivated successfully. Jul 2 00:46:53.769018 systemd[1]: Finished motdgen.service. Jul 2 00:46:53.777022 dbus-daemon[1112]: [system] SELinux support is enabled Jul 2 00:46:53.777169 systemd[1]: Started dbus.service. Jul 2 00:46:53.778149 extend-filesystems[1114]: Found vda Jul 2 00:46:53.778149 extend-filesystems[1114]: Found vda1 Jul 2 00:46:53.778149 extend-filesystems[1114]: Found vda2 Jul 2 00:46:53.778149 extend-filesystems[1114]: Found vda3 Jul 2 00:46:53.778149 extend-filesystems[1114]: Found usr Jul 2 00:46:53.778149 extend-filesystems[1114]: Found vda4 Jul 2 00:46:53.778149 extend-filesystems[1114]: Found vda6 Jul 2 00:46:53.778149 extend-filesystems[1114]: Found vda7 Jul 2 00:46:53.778149 extend-filesystems[1114]: Found vda9 Jul 2 00:46:53.778149 extend-filesystems[1114]: Checking size of /dev/vda9 Jul 2 00:46:53.779517 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Jul 2 00:46:53.795289 jq[1129]: true Jul 2 00:46:53.779542 systemd[1]: Reached target system-config.target. Jul 2 00:46:53.780276 systemd[1]: user-cloudinit-proc-cmdline.service was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Jul 2 00:46:53.780291 systemd[1]: Reached target user-config.target. Jul 2 00:46:53.802036 extend-filesystems[1114]: Old size kept for /dev/vda9 Jul 2 00:46:53.802276 systemd[1]: extend-filesystems.service: Deactivated successfully. Jul 2 00:46:53.802438 systemd[1]: Finished extend-filesystems.service. Jul 2 00:46:53.832074 bash[1159]: Updated "/home/core/.ssh/authorized_keys" Jul 2 00:46:53.832913 systemd[1]: Finished update-ssh-keys-after-ignition.service. Jul 2 00:46:53.836207 systemd-logind[1123]: Watching system buttons on /dev/input/event0 (Power Button) Jul 2 00:46:53.837803 systemd-logind[1123]: New seat seat0. Jul 2 00:46:53.847076 systemd[1]: Started systemd-logind.service. Jul 2 00:46:53.848477 env[1130]: time="2024-07-02T00:46:53.847893160Z" level=info msg="starting containerd" revision=92b3a9d6f1b3bcc6dc74875cfdea653fe39f09c2 version=1.6.16 Jul 2 00:46:53.853016 update_engine[1126]: I0702 00:46:53.852804 1126 main.cc:92] Flatcar Update Engine starting Jul 2 00:46:53.856792 systemd[1]: Started update-engine.service. Jul 2 00:46:53.856891 update_engine[1126]: I0702 00:46:53.856840 1126 update_check_scheduler.cc:74] Next update check in 2m19s Jul 2 00:46:53.859166 systemd[1]: Started locksmithd.service. Jul 2 00:46:53.867861 env[1130]: time="2024-07-02T00:46:53.867818680Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Jul 2 00:46:53.868227 env[1130]: time="2024-07-02T00:46:53.868207160Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Jul 2 00:46:53.869738 env[1130]: time="2024-07-02T00:46:53.869701000Z" 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/5.15.161-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Jul 2 00:46:53.869738 env[1130]: time="2024-07-02T00:46:53.869732240Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Jul 2 00:46:53.869990 env[1130]: time="2024-07-02T00:46:53.869958200Z" 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 Jul 2 00:46:53.869990 env[1130]: time="2024-07-02T00:46:53.869980200Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Jul 2 00:46:53.870041 env[1130]: time="2024-07-02T00:46:53.869994640Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" Jul 2 00:46:53.870041 env[1130]: time="2024-07-02T00:46:53.870004400Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Jul 2 00:46:53.870087 env[1130]: time="2024-07-02T00:46:53.870074000Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Jul 2 00:46:53.870374 env[1130]: time="2024-07-02T00:46:53.870336720Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Jul 2 00:46:53.870476 env[1130]: time="2024-07-02T00:46:53.870458280Z" 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 Jul 2 00:46:53.870504 env[1130]: time="2024-07-02T00:46:53.870476560Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Jul 2 00:46:53.870541 env[1130]: time="2024-07-02T00:46:53.870526360Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" Jul 2 00:46:53.870585 env[1130]: time="2024-07-02T00:46:53.870543000Z" level=info msg="metadata content store policy set" policy=shared Jul 2 00:46:53.897251 locksmithd[1163]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Jul 2 00:46:53.914956 env[1130]: time="2024-07-02T00:46:53.914908680Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Jul 2 00:46:53.914956 env[1130]: time="2024-07-02T00:46:53.914957480Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Jul 2 00:46:53.915035 env[1130]: time="2024-07-02T00:46:53.914971800Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Jul 2 00:46:53.915035 env[1130]: time="2024-07-02T00:46:53.915009320Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Jul 2 00:46:53.915035 env[1130]: time="2024-07-02T00:46:53.915027400Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Jul 2 00:46:53.915100 env[1130]: time="2024-07-02T00:46:53.915041480Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Jul 2 00:46:53.915100 env[1130]: time="2024-07-02T00:46:53.915056320Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Jul 2 00:46:53.915438 env[1130]: time="2024-07-02T00:46:53.915410960Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Jul 2 00:46:53.915464 env[1130]: time="2024-07-02T00:46:53.915438120Z" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1 Jul 2 00:46:53.915464 env[1130]: time="2024-07-02T00:46:53.915453000Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Jul 2 00:46:53.915500 env[1130]: time="2024-07-02T00:46:53.915466520Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Jul 2 00:46:53.915500 env[1130]: time="2024-07-02T00:46:53.915482200Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Jul 2 00:46:53.915661 env[1130]: time="2024-07-02T00:46:53.915629040Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Jul 2 00:46:53.915751 env[1130]: time="2024-07-02T00:46:53.915729320Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Jul 2 00:46:53.915981 env[1130]: time="2024-07-02T00:46:53.915961880Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Jul 2 00:46:53.916006 env[1130]: time="2024-07-02T00:46:53.915991840Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Jul 2 00:46:53.916027 env[1130]: time="2024-07-02T00:46:53.916005800Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Jul 2 00:46:53.916179 env[1130]: time="2024-07-02T00:46:53.916163360Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Jul 2 00:46:53.916207 env[1130]: time="2024-07-02T00:46:53.916179520Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Jul 2 00:46:53.916207 env[1130]: time="2024-07-02T00:46:53.916192280Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Jul 2 00:46:53.916252 env[1130]: time="2024-07-02T00:46:53.916206480Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Jul 2 00:46:53.916252 env[1130]: time="2024-07-02T00:46:53.916223400Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Jul 2 00:46:53.916252 env[1130]: time="2024-07-02T00:46:53.916237240Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Jul 2 00:46:53.916252 env[1130]: time="2024-07-02T00:46:53.916249320Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Jul 2 00:46:53.916324 env[1130]: time="2024-07-02T00:46:53.916262880Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Jul 2 00:46:53.916324 env[1130]: time="2024-07-02T00:46:53.916276480Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Jul 2 00:46:53.916405 env[1130]: time="2024-07-02T00:46:53.916396200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Jul 2 00:46:53.916426 env[1130]: time="2024-07-02T00:46:53.916413000Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Jul 2 00:46:53.916447 env[1130]: time="2024-07-02T00:46:53.916425920Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Jul 2 00:46:53.916447 env[1130]: time="2024-07-02T00:46:53.916438880Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Jul 2 00:46:53.916483 env[1130]: time="2024-07-02T00:46:53.916453760Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1 Jul 2 00:46:53.916483 env[1130]: time="2024-07-02T00:46:53.916464920Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Jul 2 00:46:53.916522 env[1130]: time="2024-07-02T00:46:53.916482720Z" level=error msg="failed to initialize a tracing processor \"otlp\"" error="no OpenTelemetry endpoint: skip plugin" Jul 2 00:46:53.916522 env[1130]: time="2024-07-02T00:46:53.916518480Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Jul 2 00:46:53.916807 env[1130]: time="2024-07-02T00:46:53.916745720Z" 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 BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 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.6 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} 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}" Jul 2 00:46:53.921117 env[1130]: time="2024-07-02T00:46:53.916811360Z" level=info msg="Connect containerd service" Jul 2 00:46:53.921117 env[1130]: time="2024-07-02T00:46:53.916849840Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Jul 2 00:46:53.921117 env[1130]: time="2024-07-02T00:46:53.917746880Z" 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" Jul 2 00:46:53.921117 env[1130]: time="2024-07-02T00:46:53.918213040Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jul 2 00:46:53.921117 env[1130]: time="2024-07-02T00:46:53.918252520Z" level=info msg=serving... address=/run/containerd/containerd.sock Jul 2 00:46:53.921117 env[1130]: time="2024-07-02T00:46:53.919582320Z" level=info msg="containerd successfully booted in 0.072429s" Jul 2 00:46:53.921117 env[1130]: time="2024-07-02T00:46:53.919595520Z" level=info msg="Start subscribing containerd event" Jul 2 00:46:53.921117 env[1130]: time="2024-07-02T00:46:53.919678720Z" level=info msg="Start recovering state" Jul 2 00:46:53.921117 env[1130]: time="2024-07-02T00:46:53.919754920Z" level=info msg="Start event monitor" Jul 2 00:46:53.921117 env[1130]: time="2024-07-02T00:46:53.919784880Z" level=info msg="Start snapshots syncer" Jul 2 00:46:53.921117 env[1130]: time="2024-07-02T00:46:53.919797440Z" level=info msg="Start cni network conf syncer for default" Jul 2 00:46:53.921117 env[1130]: time="2024-07-02T00:46:53.919805760Z" level=info msg="Start streaming server" Jul 2 00:46:53.918388 systemd[1]: Started containerd.service. Jul 2 00:46:54.850747 systemd-networkd[1039]: eth0: Gained IPv6LL Jul 2 00:46:54.852798 systemd[1]: Finished systemd-networkd-wait-online.service. Jul 2 00:46:54.853751 systemd[1]: Reached target network-online.target. Jul 2 00:46:54.855675 systemd[1]: Starting coreos-metadata.service... Jul 2 00:46:54.861843 systemd[1]: coreos-metadata.service: Deactivated successfully. Jul 2 00:46:54.861990 systemd[1]: Finished coreos-metadata.service. Jul 2 00:46:54.863813 systemd[1]: Starting etcd-member.service... Jul 2 00:46:54.939103 systemd[1]: Starting docker.service... Jul 2 00:46:55.018711 env[1185]: time="2024-07-02T00:46:55.018657427Z" level=info msg="Starting up" Jul 2 00:46:55.020002 env[1185]: time="2024-07-02T00:46:55.019980710Z" level=info msg="parsed scheme: \"unix\"" module=grpc Jul 2 00:46:55.020002 env[1185]: time="2024-07-02T00:46:55.020000926Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc Jul 2 00:46:55.020094 env[1185]: time="2024-07-02T00:46:55.020021259Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/libcontainerd/docker-containerd.sock 0 }] }" module=grpc Jul 2 00:46:55.020094 env[1185]: time="2024-07-02T00:46:55.020033101Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc Jul 2 00:46:55.022272 env[1185]: time="2024-07-02T00:46:55.022250025Z" level=info msg="parsed scheme: \"unix\"" module=grpc Jul 2 00:46:55.022441 env[1185]: time="2024-07-02T00:46:55.022428620Z" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc Jul 2 00:46:55.022538 env[1185]: time="2024-07-02T00:46:55.022523313Z" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///var/run/docker/libcontainerd/docker-containerd.sock 0 }] }" module=grpc Jul 2 00:46:55.022600 env[1185]: time="2024-07-02T00:46:55.022587974Z" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc Jul 2 00:46:55.026806 systemd[1]: var-lib-docker-check\x2doverlayfs\x2dsupport357832331-merged.mount: Deactivated successfully. Jul 2 00:46:55.103899 env[1185]: time="2024-07-02T00:46:55.103822345Z" level=info msg="Loading containers: start." Jul 2 00:46:55.202564 kernel: Initializing XFRM netlink socket Jul 2 00:46:55.223447 env[1185]: time="2024-07-02T00:46:55.223418337Z" level=info msg="Default bridge (docker0) is assigned with an IP address 172.17.0.0/16. Daemon option --bip can be used to set a preferred IP address" Jul 2 00:46:55.266656 systemd-networkd[1039]: docker0: Link UP Jul 2 00:46:55.274741 env[1185]: time="2024-07-02T00:46:55.274714417Z" level=info msg="Loading containers: done." Jul 2 00:46:55.286372 sshd_keygen[1134]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Jul 2 00:46:55.291507 systemd[1]: var-lib-docker-overlay2-opaque\x2dbug\x2dcheck176302199-merged.mount: Deactivated successfully. Jul 2 00:46:55.295474 env[1185]: time="2024-07-02T00:46:55.295439895Z" 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 Jul 2 00:46:55.295761 env[1185]: time="2024-07-02T00:46:55.295742982Z" level=info msg="Docker daemon" commit=112bdf3343 graphdriver(s)=overlay2 version=20.10.23 Jul 2 00:46:55.295934 env[1185]: time="2024-07-02T00:46:55.295918111Z" level=info msg="Daemon has completed initialization" Jul 2 00:46:55.306465 systemd[1]: Finished sshd-keygen.service. Jul 2 00:46:55.309281 systemd[1]: Starting issuegen.service... Jul 2 00:46:55.313770 systemd[1]: Started docker.service. Jul 2 00:46:55.314839 systemd[1]: issuegen.service: Deactivated successfully. Jul 2 00:46:55.314986 systemd[1]: Finished issuegen.service. Jul 2 00:46:55.316886 systemd[1]: Starting systemd-user-sessions.service... Jul 2 00:46:55.321244 env[1185]: time="2024-07-02T00:46:55.321134113Z" level=info msg="API listen on /run/docker.sock" Jul 2 00:46:55.322538 systemd[1]: Finished systemd-user-sessions.service. Jul 2 00:46:55.322892 etcd-wrapper[1177]: Error response from daemon: No such container: etcd-member Jul 2 00:46:55.324868 systemd[1]: Started getty@tty1.service. Jul 2 00:46:55.326690 systemd[1]: Started serial-getty@ttyAMA0.service. Jul 2 00:46:55.327504 systemd[1]: Reached target getty.target. Jul 2 00:46:55.343522 etcd-wrapper[1310]: Error: No such container: etcd-member Jul 2 00:46:55.378035 etcd-wrapper[1329]: Unable to find image 'quay.io/coreos/etcd:v3.5.0-arm64' locally Jul 2 00:46:56.130873 etcd-wrapper[1329]: v3.5.0-arm64: Pulling from coreos/etcd Jul 2 00:46:56.131024 etcd-wrapper[1329]: 6022c6b01d76: Pulling fs layer Jul 2 00:46:56.131095 etcd-wrapper[1329]: 1d97b1ec77fc: Pulling fs layer Jul 2 00:46:56.131154 etcd-wrapper[1329]: 99ef4b690837: Pulling fs layer Jul 2 00:46:56.131214 etcd-wrapper[1329]: 435b7bf0967c: Pulling fs layer Jul 2 00:46:56.131271 etcd-wrapper[1329]: 17e4a5f7c1b5: Pulling fs layer Jul 2 00:46:56.131328 etcd-wrapper[1329]: 5b0292999756: Pulling fs layer Jul 2 00:46:56.131392 etcd-wrapper[1329]: 435b7bf0967c: Waiting Jul 2 00:46:56.131453 etcd-wrapper[1329]: 17e4a5f7c1b5: Waiting Jul 2 00:46:56.131510 etcd-wrapper[1329]: 5b0292999756: Waiting Jul 2 00:46:56.362237 etcd-wrapper[1329]: 99ef4b690837: Verifying Checksum Jul 2 00:46:56.362237 etcd-wrapper[1329]: 99ef4b690837: Download complete Jul 2 00:46:56.390087 etcd-wrapper[1329]: 1d97b1ec77fc: Verifying Checksum Jul 2 00:46:56.390087 etcd-wrapper[1329]: 1d97b1ec77fc: Download complete Jul 2 00:46:56.564221 etcd-wrapper[1329]: 435b7bf0967c: Verifying Checksum Jul 2 00:46:56.564221 etcd-wrapper[1329]: 435b7bf0967c: Download complete Jul 2 00:46:56.564628 etcd-wrapper[1329]: 17e4a5f7c1b5: Verifying Checksum Jul 2 00:46:56.564628 etcd-wrapper[1329]: 17e4a5f7c1b5: Download complete Jul 2 00:46:56.641228 etcd-wrapper[1329]: 6022c6b01d76: Download complete Jul 2 00:46:56.744446 etcd-wrapper[1329]: 5b0292999756: Verifying Checksum Jul 2 00:46:56.744446 etcd-wrapper[1329]: 5b0292999756: Download complete Jul 2 00:46:57.420377 etcd-wrapper[1329]: 6022c6b01d76: Pull complete Jul 2 00:46:57.659014 systemd[1]: var-lib-docker-overlay2-e3b8dd87352c01ead7b81024ad286f4f1f3f5f49791a0112275c506a758c1d80-merged.mount: Deactivated successfully. Jul 2 00:46:57.964087 etcd-wrapper[1329]: 1d97b1ec77fc: Pull complete Jul 2 00:46:58.153304 systemd[1]: var-lib-docker-overlay2-90c8c47c3955be8b7b55932e2d7f4d802ecc9ea17e267306139683803542db83-merged.mount: Deactivated successfully. Jul 2 00:46:58.185330 etcd-wrapper[1329]: 99ef4b690837: Pull complete Jul 2 00:46:58.391363 etcd-wrapper[1329]: 435b7bf0967c: Pull complete Jul 2 00:46:58.424991 systemd[1]: var-lib-docker-overlay2-b915ce7ba181228dbac1865eb074af3ba84727839c1f4b40aee7abfc9aa6734e-merged.mount: Deactivated successfully. Jul 2 00:46:58.431112 systemd[1]: var-lib-docker-overlay2-1633edcf17f63497d8b0c0f32b6e737543acc028791abf7793661d1d10832be5-merged.mount: Deactivated successfully. Jul 2 00:46:58.452005 etcd-wrapper[1329]: 17e4a5f7c1b5: Pull complete Jul 2 00:46:58.487920 systemd[1]: var-lib-docker-overlay2-07fe850d70a8c4f7386b577c0279fd0e169e0d8cec581fc5758a477e6523b931-merged.mount: Deactivated successfully. Jul 2 00:46:58.495492 etcd-wrapper[1329]: 5b0292999756: Pull complete Jul 2 00:46:58.500601 etcd-wrapper[1329]: Digest: sha256:96255d1ad67e7afcea46b4dc1b7e74024b9f6971193cb7316b093b3b5671efb0 Jul 2 00:46:58.503198 etcd-wrapper[1329]: Status: Downloaded newer image for quay.io/coreos/etcd:v3.5.0-arm64 Jul 2 00:46:58.616027 env[1130]: time="2024-07-02T00:46:58.615929515Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Jul 2 00:46:58.616027 env[1130]: time="2024-07-02T00:46:58.615978441Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Jul 2 00:46:58.616027 env[1130]: time="2024-07-02T00:46:58.615989523Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Jul 2 00:46:58.616460 env[1130]: time="2024-07-02T00:46:58.616242013Z" level=info msg="starting signal loop" namespace=moby path=/run/containerd/io.containerd.runtime.v2.task/moby/fe910270407395f6d089eb47db6b66201a91ef03465e78afd4901956aa3d355b pid=1419 runtime=io.containerd.runc.v2 Jul 2 00:46:58.628470 systemd[1]: Started docker-fe910270407395f6d089eb47db6b66201a91ef03465e78afd4901956aa3d355b.scope. Jul 2 00:46:58.710138 systemd-resolved[1090]: Failed to determine the local hostname and LLMNR/mDNS names, ignoring: No such device or address Jul 2 00:46:58.760845 etcd-wrapper[1329]: {"level":"info","ts":1719881218.7605631,"caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_DATA_DIR","variable-value":"/var/lib/etcd"} Jul 2 00:46:58.761000 etcd-wrapper[1329]: {"level":"info","ts":1719881218.7606397,"caller":"flags/flag.go:113","msg":"recognized and used environment variable","variable-name":"ETCD_NAME","variable-value":"84e426444bae42ed84c3b4aeac44ba0d"} Jul 2 00:46:58.761000 etcd-wrapper[1329]: {"level":"warn","ts":1719881218.7606795,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_URL=quay.io/coreos/etcd"} Jul 2 00:46:58.761000 etcd-wrapper[1329]: {"level":"warn","ts":1719881218.7606862,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_IMAGE_TAG=v3.5.0-arm64"} Jul 2 00:46:58.761000 etcd-wrapper[1329]: {"level":"warn","ts":1719881218.7606916,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_USER=etcd"} Jul 2 00:46:58.761000 etcd-wrapper[1329]: {"level":"warn","ts":1719881218.7606957,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_SSL_DIR=/etc/ssl/certs"} Jul 2 00:46:58.761000 etcd-wrapper[1329]: {"level":"warn","ts":1719881218.760701,"caller":"flags/flag.go:93","msg":"unrecognized environment variable","environment-variable":"ETCD_UNSUPPORTED_ARCH=arm64"} Jul 2 00:46:58.761000 etcd-wrapper[1329]: {"level":"info","ts":"2024-07-02T00:46:58.760Z","caller":"etcdmain/etcd.go:72","msg":"Running: ","args":["/usr/local/bin/etcd","--listen-client-urls=http://0.0.0.0:2379","--advertise-client-urls=http://10.0.0.62:2379"]} Jul 2 00:46:58.761278 etcd-wrapper[1329]: {"level":"info","ts":"2024-07-02T00:46:58.760Z","caller":"embed/etcd.go:131","msg":"configuring peer listeners","listen-peer-urls":["http://localhost:2380"]} Jul 2 00:46:58.761423 etcd-wrapper[1329]: {"level":"info","ts":"2024-07-02T00:46:58.761Z","caller":"embed/etcd.go:139","msg":"configuring client listeners","listen-client-urls":["http://0.0.0.0:2379"]} Jul 2 00:46:58.761582 etcd-wrapper[1329]: {"level":"info","ts":"2024-07-02T00:46:58.761Z","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":"84e426444bae42ed84c3b4aeac44ba0d","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://localhost:2380"],"listen-peer-urls":["http://localhost:2380"],"advertise-client-urls":["http://10.0.0.62:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"84e426444bae42ed84c3b4aeac44ba0d=http://localhost:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","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":"","discovery-proxy":"","downgrade-check-interval":"5s"} Jul 2 00:46:58.763537 etcd-wrapper[1329]: {"level":"info","ts":"2024-07-02T00:46:58.763Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"1.617311ms"} Jul 2 00:46:58.767763 etcd-wrapper[1329]: {"level":"info","ts":"2024-07-02T00:46:58.767Z","caller":"etcdserver/raft.go:448","msg":"starting local member","local-member-id":"8e9e05c52164694d","cluster-id":"cdf818194e3a8c32"} Jul 2 00:46:58.767763 etcd-wrapper[1329]: {"level":"info","ts":"2024-07-02T00:46:58.767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=()"} Jul 2 00:46:58.767763 etcd-wrapper[1329]: {"level":"info","ts":"2024-07-02T00:46:58.767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 0"} Jul 2 00:46:58.767763 etcd-wrapper[1329]: {"level":"info","ts":"2024-07-02T00:46:58.767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8e9e05c52164694d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} Jul 2 00:46:58.767763 etcd-wrapper[1329]: {"level":"info","ts":"2024-07-02T00:46:58.767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became follower at term 1"} Jul 2 00:46:58.767926 etcd-wrapper[1329]: {"level":"info","ts":"2024-07-02T00:46:58.767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Jul 2 00:46:58.769367 etcd-wrapper[1329]: {"level":"warn","ts":"2024-07-02T00:46:58.769Z","caller":"auth/store.go:1220","msg":"simple token is not cryptographically signed"} Jul 2 00:46:58.770737 etcd-wrapper[1329]: {"level":"info","ts":"2024-07-02T00:46:58.770Z","caller":"mvcc/kvstore.go:415","msg":"kvstore restored","current-rev":1} Jul 2 00:46:58.771829 etcd-wrapper[1329]: {"level":"info","ts":"2024-07-02T00:46:58.771Z","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"} Jul 2 00:46:58.773276 etcd-wrapper[1329]: {"level":"info","ts":"2024-07-02T00:46:58.772Z","caller":"etcdserver/server.go:843","msg":"starting etcd server","local-member-id":"8e9e05c52164694d","local-server-version":"3.5.0","cluster-version":"to_be_decided"} Jul 2 00:46:58.774322 etcd-wrapper[1329]: {"level":"info","ts":"2024-07-02T00:46:58.773Z","caller":"etcdserver/server.go:728","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8e9e05c52164694d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} Jul 2 00:46:58.777250 etcd-wrapper[1329]: {"level":"info","ts":"2024-07-02T00:46:58.774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d switched to configuration voters=(10276657743932975437)"} Jul 2 00:46:58.777250 etcd-wrapper[1329]: {"level":"info","ts":"2024-07-02T00:46:58.774Z","caller":"membership/cluster.go:393","msg":"added member","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","added-peer-id":"8e9e05c52164694d","added-peer-peer-urls":["http://localhost:2380"]} Jul 2 00:46:58.777250 etcd-wrapper[1329]: {"level":"info","ts":"2024-07-02T00:46:58.775Z","caller":"embed/etcd.go:276","msg":"now serving peer/client/metrics","local-member-id":"8e9e05c52164694d","initial-advertise-peer-urls":["http://localhost:2380"],"listen-peer-urls":["http://localhost:2380"],"advertise-client-urls":["http://10.0.0.62:2379"],"listen-client-urls":["http://0.0.0.0:2379"],"listen-metrics-urls":[]} Jul 2 00:46:58.777250 etcd-wrapper[1329]: {"level":"info","ts":"2024-07-02T00:46:58.775Z","caller":"embed/etcd.go:580","msg":"serving peer traffic","address":"127.0.0.1:2380"} Jul 2 00:46:58.777250 etcd-wrapper[1329]: {"level":"info","ts":"2024-07-02T00:46:58.775Z","caller":"embed/etcd.go:552","msg":"cmux::serve","address":"127.0.0.1:2380"} Jul 2 00:46:59.069050 etcd-wrapper[1329]: {"level":"info","ts":"2024-07-02T00:46:59.068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d is starting a new election at term 1"} Jul 2 00:46:59.069050 etcd-wrapper[1329]: {"level":"info","ts":"2024-07-02T00:46:59.068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became pre-candidate at term 1"} Jul 2 00:46:59.069050 etcd-wrapper[1329]: {"level":"info","ts":"2024-07-02T00:46:59.068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgPreVoteResp from 8e9e05c52164694d at term 1"} Jul 2 00:46:59.069050 etcd-wrapper[1329]: {"level":"info","ts":"2024-07-02T00:46:59.068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became candidate at term 2"} Jul 2 00:46:59.069050 etcd-wrapper[1329]: {"level":"info","ts":"2024-07-02T00:46:59.068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d received MsgVoteResp from 8e9e05c52164694d at term 2"} Jul 2 00:46:59.069050 etcd-wrapper[1329]: {"level":"info","ts":"2024-07-02T00:46:59.068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8e9e05c52164694d became leader at term 2"} Jul 2 00:46:59.069050 etcd-wrapper[1329]: {"level":"info","ts":"2024-07-02T00:46:59.068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8e9e05c52164694d elected leader 8e9e05c52164694d at term 2"} Jul 2 00:46:59.069598 etcd-wrapper[1329]: {"level":"info","ts":"2024-07-02T00:46:59.069Z","caller":"etcdserver/server.go:2476","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} Jul 2 00:46:59.069598 etcd-wrapper[1329]: {"level":"info","ts":"2024-07-02T00:46:59.069Z","caller":"etcdserver/server.go:2027","msg":"published local member to cluster through raft","local-member-id":"8e9e05c52164694d","local-member-attributes":"{Name:84e426444bae42ed84c3b4aeac44ba0d ClientURLs:[http://10.0.0.62:2379]}","request-path":"/0/members/8e9e05c52164694d/attributes","cluster-id":"cdf818194e3a8c32","publish-timeout":"7s"} Jul 2 00:46:59.069697 etcd-wrapper[1329]: {"level":"info","ts":"2024-07-02T00:46:59.069Z","caller":"embed/serve.go:98","msg":"ready to serve client requests"} Jul 2 00:46:59.070301 etcd-wrapper[1329]: {"level":"info","ts":"2024-07-02T00:46:59.069Z","caller":"etcdmain/main.go:47","msg":"notifying init daemon"} Jul 2 00:46:59.070301 etcd-wrapper[1329]: {"level":"info","ts":"2024-07-02T00:46:59.069Z","caller":"etcdmain/main.go:53","msg":"successfully notified init daemon"} Jul 2 00:46:59.070075 systemd[1]: Started etcd-member.service. Jul 2 00:46:59.070610 etcd-wrapper[1329]: {"level":"info","ts":"2024-07-02T00:46:59.070Z","caller":"embed/serve.go:140","msg":"serving client traffic insecurely; this is strongly discouraged!","address":"[::]:2379"} Jul 2 00:46:59.070945 systemd[1]: Reached target multi-user.target. Jul 2 00:46:59.073021 systemd[1]: Starting systemd-update-utmp-runlevel.service... Jul 2 00:46:59.079299 etcd-wrapper[1329]: {"level":"info","ts":"2024-07-02T00:46:59.079Z","caller":"membership/cluster.go:531","msg":"set initial cluster version","cluster-id":"cdf818194e3a8c32","local-member-id":"8e9e05c52164694d","cluster-version":"3.5"} Jul 2 00:46:59.079299 etcd-wrapper[1329]: {"level":"info","ts":"2024-07-02T00:46:59.079Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} Jul 2 00:46:59.079433 etcd-wrapper[1329]: {"level":"info","ts":"2024-07-02T00:46:59.079Z","caller":"etcdserver/server.go:2500","msg":"cluster version is updated","cluster-version":"3.5"} Jul 2 00:46:59.080618 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Jul 2 00:46:59.080823 systemd[1]: Finished systemd-update-utmp-runlevel.service. Jul 2 00:46:59.081722 systemd[1]: Startup finished in 631ms (kernel) + 3.330s (initrd) + 8.172s (userspace) = 12.134s. Jul 2 00:46:59.425656 systemd[1]: var-lib-docker-overlay2-4118c3a40379a60736c7544ae9db39c484bd99086ba9d3662798b8b9b5cb120e\x2dinit-merged.mount: Deactivated successfully. Jul 2 00:47:00.334788 systemd[1]: Created slice system-sshd.slice. Jul 2 00:47:00.336190 systemd[1]: Started sshd@0-10.0.0.62:22-10.0.0.1:43930.service. Jul 2 00:47:00.407840 sshd[1462]: Accepted publickey for core from 10.0.0.1 port 43930 ssh2: RSA SHA256:p8Y1IuTS6TxJ481HRtC9DuXWW9Af2DGdhQUd1gde29c Jul 2 00:47:00.414624 sshd[1462]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 2 00:47:00.431815 systemd-logind[1123]: New session 1 of user core. Jul 2 00:47:00.432816 systemd[1]: Created slice user-500.slice. Jul 2 00:47:00.434093 systemd[1]: Starting user-runtime-dir@500.service... Jul 2 00:47:00.442328 systemd[1]: Finished user-runtime-dir@500.service. Jul 2 00:47:00.443728 systemd[1]: Starting user@500.service... Jul 2 00:47:00.446429 (systemd)[1465]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Jul 2 00:47:00.510506 systemd[1465]: Queued start job for default target default.target. Jul 2 00:47:00.511023 systemd[1465]: Reached target paths.target. Jul 2 00:47:00.511042 systemd[1465]: Reached target sockets.target. Jul 2 00:47:00.511052 systemd[1465]: Reached target timers.target. Jul 2 00:47:00.511062 systemd[1465]: Reached target basic.target. Jul 2 00:47:00.511097 systemd[1465]: Reached target default.target. Jul 2 00:47:00.511121 systemd[1465]: Startup finished in 59ms. Jul 2 00:47:00.511238 systemd[1]: Started user@500.service. Jul 2 00:47:00.512511 systemd[1]: Started session-1.scope. Jul 2 00:47:00.562515 systemd[1]: Started sshd@1-10.0.0.62:22-10.0.0.1:36544.service. Jul 2 00:47:00.594379 sshd[1474]: Accepted publickey for core from 10.0.0.1 port 36544 ssh2: RSA SHA256:p8Y1IuTS6TxJ481HRtC9DuXWW9Af2DGdhQUd1gde29c Jul 2 00:47:00.595949 sshd[1474]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 2 00:47:00.600449 systemd[1]: Started session-2.scope. Jul 2 00:47:00.600641 systemd-logind[1123]: New session 2 of user core. Jul 2 00:47:00.658568 sshd[1474]: pam_unix(sshd:session): session closed for user core Jul 2 00:47:00.662436 systemd[1]: sshd@1-10.0.0.62:22-10.0.0.1:36544.service: Deactivated successfully. Jul 2 00:47:00.663009 systemd[1]: session-2.scope: Deactivated successfully. Jul 2 00:47:00.663470 systemd-logind[1123]: Session 2 logged out. Waiting for processes to exit. Jul 2 00:47:00.664444 systemd[1]: Started sshd@2-10.0.0.62:22-10.0.0.1:36552.service. Jul 2 00:47:00.665034 systemd-logind[1123]: Removed session 2. Jul 2 00:47:00.696745 sshd[1480]: Accepted publickey for core from 10.0.0.1 port 36552 ssh2: RSA SHA256:p8Y1IuTS6TxJ481HRtC9DuXWW9Af2DGdhQUd1gde29c Jul 2 00:47:00.698005 sshd[1480]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 2 00:47:00.701474 systemd-logind[1123]: New session 3 of user core. Jul 2 00:47:00.702274 systemd[1]: Started session-3.scope. Jul 2 00:47:00.749652 sshd[1480]: pam_unix(sshd:session): session closed for user core Jul 2 00:47:00.752310 systemd[1]: sshd@2-10.0.0.62:22-10.0.0.1:36552.service: Deactivated successfully. Jul 2 00:47:00.752859 systemd[1]: session-3.scope: Deactivated successfully. Jul 2 00:47:00.753327 systemd-logind[1123]: Session 3 logged out. Waiting for processes to exit. Jul 2 00:47:00.754269 systemd[1]: Started sshd@3-10.0.0.62:22-10.0.0.1:36554.service. Jul 2 00:47:00.754922 systemd-logind[1123]: Removed session 3. Jul 2 00:47:00.785922 sshd[1486]: Accepted publickey for core from 10.0.0.1 port 36554 ssh2: RSA SHA256:p8Y1IuTS6TxJ481HRtC9DuXWW9Af2DGdhQUd1gde29c Jul 2 00:47:00.787440 sshd[1486]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 2 00:47:00.790886 systemd-logind[1123]: New session 4 of user core. Jul 2 00:47:00.791791 systemd[1]: Started session-4.scope. Jul 2 00:47:00.849284 sshd[1486]: pam_unix(sshd:session): session closed for user core Jul 2 00:47:00.852821 systemd[1]: Started sshd@4-10.0.0.62:22-10.0.0.1:36556.service. Jul 2 00:47:00.853328 systemd[1]: sshd@3-10.0.0.62:22-10.0.0.1:36554.service: Deactivated successfully. Jul 2 00:47:00.853992 systemd[1]: session-4.scope: Deactivated successfully. Jul 2 00:47:00.854614 systemd-logind[1123]: Session 4 logged out. Waiting for processes to exit. Jul 2 00:47:00.855375 systemd-logind[1123]: Removed session 4. Jul 2 00:47:00.885766 sshd[1491]: Accepted publickey for core from 10.0.0.1 port 36556 ssh2: RSA SHA256:p8Y1IuTS6TxJ481HRtC9DuXWW9Af2DGdhQUd1gde29c Jul 2 00:47:00.886854 sshd[1491]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 2 00:47:00.889897 systemd-logind[1123]: New session 5 of user core. Jul 2 00:47:00.890738 systemd[1]: Started session-5.scope. Jul 2 00:47:00.938790 sshd[1491]: pam_unix(sshd:session): session closed for user core Jul 2 00:47:00.941489 systemd[1]: sshd@4-10.0.0.62:22-10.0.0.1:36556.service: Deactivated successfully. Jul 2 00:47:00.942072 systemd[1]: session-5.scope: Deactivated successfully. Jul 2 00:47:00.942617 systemd-logind[1123]: Session 5 logged out. Waiting for processes to exit. Jul 2 00:47:00.943670 systemd[1]: Started sshd@5-10.0.0.62:22-10.0.0.1:36566.service. Jul 2 00:47:00.944341 systemd-logind[1123]: Removed session 5. Jul 2 00:47:00.975542 sshd[1500]: Accepted publickey for core from 10.0.0.1 port 36566 ssh2: RSA SHA256:p8Y1IuTS6TxJ481HRtC9DuXWW9Af2DGdhQUd1gde29c Jul 2 00:47:00.976607 sshd[1500]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Jul 2 00:47:00.979886 systemd-logind[1123]: New session 6 of user core. Jul 2 00:47:00.980687 systemd[1]: Started session-6.scope. Jul 2 00:47:01.134772 sshd[1500]: pam_unix(sshd:session): session closed for user core Jul 2 00:47:01.137280 systemd[1]: sshd@5-10.0.0.62:22-10.0.0.1:36566.service: Deactivated successfully. Jul 2 00:47:01.137969 systemd[1]: session-6.scope: Deactivated successfully. Jul 2 00:47:01.138515 systemd-logind[1123]: Session 6 logged out. Waiting for processes to exit. Jul 2 00:47:01.139300 systemd-logind[1123]: Removed session 6. Jul 2 00:47:38.985736 update_engine[1126]: I0702 00:47:38.985673 1126 update_attempter.cc:509] Updating boot flags...