Oct 2 18:41:50.713921 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Oct 2 18:41:50.713938 kernel: Linux version 5.10.193-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo 9.3.0-r1 p3) 9.3.0, GNU ld (Gentoo 2.36.1 p5) 2.36.1) #1 SMP PREEMPT Fri Sep 1 15:04:28 -00 2023 Oct 2 18:41:50.713946 kernel: efi: EFI v2.70 by EDK II Oct 2 18:41:50.713952 kernel: efi: SMBIOS 3.0=0xd9260000 ACPI 2.0=0xd9240000 MEMATTR=0xda32b018 RNG=0xd9220018 MEMRESERVE=0xd9521c18 Oct 2 18:41:50.713957 kernel: random: crng init done Oct 2 18:41:50.713962 kernel: ACPI: Early table checksum verification disabled Oct 2 18:41:50.713967 kernel: ACPI: RSDP 0x00000000D9240000 000024 (v02 BOCHS ) Oct 2 18:41:50.713973 kernel: ACPI: XSDT 0x00000000D9230000 000064 (v01 BOCHS BXPC 00000001 01000013) Oct 2 18:41:50.713978 kernel: ACPI: FACP 0x00000000D91E0000 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Oct 2 18:41:50.713984 kernel: ACPI: DSDT 0x00000000D91F0000 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 2 18:41:50.713990 kernel: ACPI: APIC 0x00000000D91D0000 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Oct 2 18:41:50.713995 kernel: ACPI: PPTT 0x00000000D91C0000 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 2 18:41:50.714000 kernel: ACPI: GTDT 0x00000000D91B0000 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 2 18:41:50.714005 kernel: ACPI: MCFG 0x00000000D91A0000 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 2 18:41:50.714012 kernel: ACPI: SPCR 0x00000000D9190000 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 2 18:41:50.714017 kernel: ACPI: DBG2 0x00000000D9180000 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Oct 2 18:41:50.714024 kernel: ACPI: IORT 0x00000000D9170000 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 2 18:41:50.714030 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Oct 2 18:41:50.714035 kernel: ACPI: NUMA: Failed to initialise from firmware Oct 2 18:41:50.714040 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Oct 2 18:41:50.714046 kernel: NUMA: NODE_DATA [mem 0xdcb0a900-0xdcb0ffff] Oct 2 18:41:50.714051 kernel: Zone ranges: Oct 2 18:41:50.714056 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Oct 2 18:41:50.714062 kernel: DMA32 empty Oct 2 18:41:50.714067 kernel: Normal empty Oct 2 18:41:50.714072 kernel: Movable zone start for each node Oct 2 18:41:50.714077 kernel: Early memory node ranges Oct 2 18:41:50.714083 kernel: node 0: [mem 0x0000000040000000-0x00000000d924ffff] Oct 2 18:41:50.714089 kernel: node 0: [mem 0x00000000d9250000-0x00000000d951ffff] Oct 2 18:41:50.714094 kernel: node 0: [mem 0x00000000d9520000-0x00000000dc7fffff] Oct 2 18:41:50.714100 kernel: node 0: [mem 0x00000000dc800000-0x00000000dc88ffff] Oct 2 18:41:50.714105 kernel: node 0: [mem 0x00000000dc890000-0x00000000dc89ffff] Oct 2 18:41:50.714110 kernel: node 0: [mem 0x00000000dc8a0000-0x00000000dc9bffff] Oct 2 18:41:50.714115 kernel: node 0: [mem 0x00000000dc9c0000-0x00000000dcffffff] Oct 2 18:41:50.714121 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Oct 2 18:41:50.714126 kernel: On node 0 totalpages: 643072 Oct 2 18:41:50.714131 kernel: DMA zone: 10048 pages used for memmap Oct 2 18:41:50.714137 kernel: DMA zone: 0 pages reserved Oct 2 18:41:50.714142 kernel: DMA zone: 643072 pages, LIFO batch:63 Oct 2 18:41:50.714148 kernel: psci: probing for conduit method from ACPI. Oct 2 18:41:50.714160 kernel: psci: PSCIv1.1 detected in firmware. Oct 2 18:41:50.714166 kernel: psci: Using standard PSCI v0.2 function IDs Oct 2 18:41:50.714171 kernel: psci: Trusted OS migration not required Oct 2 18:41:50.714177 kernel: psci: SMC Calling Convention v1.1 Oct 2 18:41:50.714182 kernel: ACPI: SRAT not present Oct 2 18:41:50.714188 kernel: percpu: Embedded 32 pages/cpu s94040 r8192 d28840 u131072 Oct 2 18:41:50.714194 kernel: pcpu-alloc: s94040 r8192 d28840 u131072 alloc=32*4096 Oct 2 18:41:50.714200 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 2 18:41:50.714207 kernel: Detected PIPT I-cache on CPU0 Oct 2 18:41:50.714213 kernel: CPU features: detected: GIC system register CPU interface Oct 2 18:41:50.714218 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 2 18:41:50.714224 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 2 18:41:50.714229 kernel: CPU features: detected: Hardware dirty bit management Oct 2 18:41:50.714235 kernel: CPU features: detected: Spectre-v4 Oct 2 18:41:50.714241 kernel: CPU features: detected: ARM erratum 1418040 Oct 2 18:41:50.714246 kernel: CPU features: detected: Spectre-BHB Oct 2 18:41:50.714252 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Oct 2 18:41:50.714257 kernel: Policy zone: DMA Oct 2 18:41:50.714264 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=cf44c671bad59cbfeb9c66e77c1486bd4dc3d19b46370ded7b14f13f1283486e Oct 2 18:41:50.714271 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 2 18:41:50.714277 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 2 18:41:50.714283 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 2 18:41:50.714289 kernel: Memory: 2452432K/2572288K available (9344K kernel code, 2266K rwdata, 6828K rodata, 34176K init, 769K bss, 119856K reserved, 0K cma-reserved) Oct 2 18:41:50.714294 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 2 18:41:50.714300 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 2 18:41:50.714306 kernel: rcu: RCU event tracing is enabled. Oct 2 18:41:50.714312 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 2 18:41:50.714317 kernel: Trampoline variant of Tasks RCU enabled. Oct 2 18:41:50.714323 kernel: Tracing variant of Tasks RCU enabled. Oct 2 18:41:50.714329 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 2 18:41:50.714336 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 2 18:41:50.714342 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 2 18:41:50.714352 kernel: GICv3: 256 SPIs implemented Oct 2 18:41:50.714358 kernel: GICv3: 0 Extended SPIs implemented Oct 2 18:41:50.714364 kernel: GICv3: Distributor has no Range Selector support Oct 2 18:41:50.714369 kernel: GICv3: 16 PPIs implemented Oct 2 18:41:50.714375 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Oct 2 18:41:50.714381 kernel: ACPI: SRAT not present Oct 2 18:41:50.714386 kernel: ITS [mem 0x08080000-0x0809ffff] Oct 2 18:41:50.714392 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Oct 2 18:41:50.714398 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Oct 2 18:41:50.714404 kernel: GICv3: using LPI property table @0x00000000400d0000 Oct 2 18:41:50.714411 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Oct 2 18:41:50.714416 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 2 18:41:50.714422 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Oct 2 18:41:50.714428 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Oct 2 18:41:50.714434 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Oct 2 18:41:50.714440 kernel: arm-pv: using stolen time PV Oct 2 18:41:50.714447 kernel: Console: colour dummy device 80x25 Oct 2 18:41:50.714452 kernel: ACPI: Core revision 20200925 Oct 2 18:41:50.714459 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Oct 2 18:41:50.714465 kernel: pid_max: default: 32768 minimum: 301 Oct 2 18:41:50.714472 kernel: LSM: Security Framework initializing Oct 2 18:41:50.714478 kernel: SELinux: Initializing. Oct 2 18:41:50.714484 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 2 18:41:50.714490 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 2 18:41:50.714496 kernel: rcu: Hierarchical SRCU implementation. Oct 2 18:41:50.714502 kernel: Platform MSI: ITS@0x8080000 domain created Oct 2 18:41:50.714507 kernel: PCI/MSI: ITS@0x8080000 domain created Oct 2 18:41:50.714513 kernel: Remapping and enabling EFI services. Oct 2 18:41:50.714519 kernel: smp: Bringing up secondary CPUs ... Oct 2 18:41:50.714525 kernel: Detected PIPT I-cache on CPU1 Oct 2 18:41:50.714533 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Oct 2 18:41:50.714539 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Oct 2 18:41:50.714545 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 2 18:41:50.714551 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Oct 2 18:41:50.714580 kernel: Detected PIPT I-cache on CPU2 Oct 2 18:41:50.714586 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Oct 2 18:41:50.714593 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Oct 2 18:41:50.714599 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 2 18:41:50.714605 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Oct 2 18:41:50.714610 kernel: Detected PIPT I-cache on CPU3 Oct 2 18:41:50.714618 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Oct 2 18:41:50.714624 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Oct 2 18:41:50.714631 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 2 18:41:50.714637 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Oct 2 18:41:50.714643 kernel: smp: Brought up 1 node, 4 CPUs Oct 2 18:41:50.714649 kernel: SMP: Total of 4 processors activated. Oct 2 18:41:50.714664 kernel: CPU features: detected: Privileged Access Never Oct 2 18:41:50.714672 kernel: CPU features: detected: LSE atomic instructions Oct 2 18:41:50.714679 kernel: CPU features: detected: User Access Override Oct 2 18:41:50.714685 kernel: CPU features: detected: 32-bit EL0 Support Oct 2 18:41:50.714691 kernel: CPU features: detected: Common not Private translations Oct 2 18:41:50.714703 kernel: CPU features: detected: RAS Extension Support Oct 2 18:41:50.714710 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 2 18:41:50.714716 kernel: CPU features: detected: CRC32 instructions Oct 2 18:41:50.714723 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 2 18:41:50.714729 kernel: CPU: All CPU(s) started at EL1 Oct 2 18:41:50.714735 kernel: alternatives: patching kernel code Oct 2 18:41:50.714743 kernel: devtmpfs: initialized Oct 2 18:41:50.714749 kernel: KASLR enabled Oct 2 18:41:50.714755 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 2 18:41:50.714762 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 2 18:41:50.714768 kernel: pinctrl core: initialized pinctrl subsystem Oct 2 18:41:50.714775 kernel: SMBIOS 3.0.0 present. Oct 2 18:41:50.714781 kernel: DMI: QEMU KVM Virtual Machine, BIOS 0.0.0 02/06/2015 Oct 2 18:41:50.714787 kernel: NET: Registered protocol family 16 Oct 2 18:41:50.714794 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 2 18:41:50.714800 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 2 18:41:50.714807 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 2 18:41:50.714813 kernel: audit: initializing netlink subsys (disabled) Oct 2 18:41:50.714819 kernel: audit: type=2000 audit(0.031:1): state=initialized audit_enabled=0 res=1 Oct 2 18:41:50.714825 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 2 18:41:50.714833 kernel: cpuidle: using governor menu Oct 2 18:41:50.714839 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 2 18:41:50.714845 kernel: ASID allocator initialised with 32768 entries Oct 2 18:41:50.714851 kernel: ACPI: bus type PCI registered Oct 2 18:41:50.714857 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 2 18:41:50.714864 kernel: Serial: AMBA PL011 UART driver Oct 2 18:41:50.714870 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Oct 2 18:41:50.714876 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Oct 2 18:41:50.714882 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Oct 2 18:41:50.714889 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Oct 2 18:41:50.714896 kernel: cryptd: max_cpu_qlen set to 1000 Oct 2 18:41:50.714902 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Oct 2 18:41:50.714908 kernel: ACPI: Added _OSI(Module Device) Oct 2 18:41:50.714914 kernel: ACPI: Added _OSI(Processor Device) Oct 2 18:41:50.714920 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Oct 2 18:41:50.714926 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 2 18:41:50.714932 kernel: ACPI: Added _OSI(Linux-Dell-Video) Oct 2 18:41:50.714938 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Oct 2 18:41:50.714944 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Oct 2 18:41:50.714951 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 2 18:41:50.714958 kernel: ACPI: Interpreter enabled Oct 2 18:41:50.714964 kernel: ACPI: Using GIC for interrupt routing Oct 2 18:41:50.714970 kernel: ACPI: MCFG table detected, 1 entries Oct 2 18:41:50.714976 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Oct 2 18:41:50.714982 kernel: printk: console [ttyAMA0] enabled Oct 2 18:41:50.714989 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 2 18:41:50.715097 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 2 18:41:50.715159 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 2 18:41:50.715212 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 2 18:41:50.715266 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Oct 2 18:41:50.715318 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Oct 2 18:41:50.715327 kernel: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Oct 2 18:41:50.715333 kernel: PCI host bridge to bus 0000:00 Oct 2 18:41:50.715400 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Oct 2 18:41:50.715451 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 2 18:41:50.715501 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Oct 2 18:41:50.715550 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 2 18:41:50.715636 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Oct 2 18:41:50.715699 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Oct 2 18:41:50.715757 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Oct 2 18:41:50.715813 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Oct 2 18:41:50.715872 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Oct 2 18:41:50.715927 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Oct 2 18:41:50.715982 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Oct 2 18:41:50.716037 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Oct 2 18:41:50.716087 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Oct 2 18:41:50.716135 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 2 18:41:50.716186 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Oct 2 18:41:50.716194 kernel: ACPI: PCI Interrupt Link [GSI0] (IRQs *35) Oct 2 18:41:50.716202 kernel: ACPI: PCI Interrupt Link [GSI1] (IRQs *36) Oct 2 18:41:50.716209 kernel: ACPI: PCI Interrupt Link [GSI2] (IRQs *37) Oct 2 18:41:50.716215 kernel: ACPI: PCI Interrupt Link [GSI3] (IRQs *38) Oct 2 18:41:50.716221 kernel: vgaarb: loaded Oct 2 18:41:50.716227 kernel: Registered efivars operations Oct 2 18:41:50.716233 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 2 18:41:50.716239 kernel: VFS: Disk quotas dquot_6.6.0 Oct 2 18:41:50.716245 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 2 18:41:50.716251 kernel: pnp: PnP ACPI init Oct 2 18:41:50.716385 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Oct 2 18:41:50.716440 kernel: system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active) Oct 2 18:41:50.716448 kernel: pnp: PnP ACPI: found 1 devices Oct 2 18:41:50.716454 kernel: NET: Registered protocol family 2 Oct 2 18:41:50.716461 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 2 18:41:50.716467 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 2 18:41:50.716474 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 2 18:41:50.716480 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Oct 2 18:41:50.716490 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 2 18:41:50.716496 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 2 18:41:50.716502 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 2 18:41:50.716509 kernel: NET: Registered protocol family 1 Oct 2 18:41:50.716515 kernel: PCI: CLS 0 bytes, default 64 Oct 2 18:41:50.716521 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Oct 2 18:41:50.716527 kernel: kvm [1]: HYP mode not available Oct 2 18:41:50.716533 kernel: Initialise system trusted keyrings Oct 2 18:41:50.716540 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 2 18:41:50.716548 kernel: Key type asymmetric registered Oct 2 18:41:50.716585 kernel: Asymmetric key parser 'x509' registered Oct 2 18:41:50.716592 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Oct 2 18:41:50.716598 kernel: io scheduler mq-deadline registered Oct 2 18:41:50.716604 kernel: io scheduler kyber registered Oct 2 18:41:50.716611 kernel: io scheduler bfq registered Oct 2 18:41:50.716617 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 2 18:41:50.716623 kernel: ACPI: Power Button [PWRB] Oct 2 18:41:50.716629 kernel: PCI Interrupt Link [GSI1] enabled at IRQ 36 Oct 2 18:41:50.716688 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Oct 2 18:41:50.716699 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 2 18:41:50.716705 kernel: msm_serial: driver initialized Oct 2 18:41:50.716711 kernel: wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information. Oct 2 18:41:50.716718 kernel: wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld . All Rights Reserved. Oct 2 18:41:50.716724 kernel: thunder_xcv, ver 1.0 Oct 2 18:41:50.716730 kernel: thunder_bgx, ver 1.0 Oct 2 18:41:50.716736 kernel: nicpf, ver 1.0 Oct 2 18:41:50.716742 kernel: nicvf, ver 1.0 Oct 2 18:41:50.716804 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 2 18:41:50.716856 kernel: rtc-efi rtc-efi.0: setting system clock to 2023-10-02T18:41:50 UTC (1696272110) Oct 2 18:41:50.716865 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 2 18:41:50.716871 kernel: NET: Registered protocol family 10 Oct 2 18:41:50.716877 kernel: Segment Routing with IPv6 Oct 2 18:41:50.716883 kernel: NET: Registered protocol family 17 Oct 2 18:41:50.716889 kernel: Key type dns_resolver registered Oct 2 18:41:50.716895 kernel: registered taskstats version 1 Oct 2 18:41:50.716902 kernel: Loading compiled-in X.509 certificates Oct 2 18:41:50.716908 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.10.193-flatcar: 87e8d5da4c02aed0c92b4048454c634afd40ab13' Oct 2 18:41:50.716916 kernel: Key type .fscrypt registered Oct 2 18:41:50.716922 kernel: Key type fscrypt-provisioning registered Oct 2 18:41:50.716928 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 2 18:41:50.716934 kernel: ima: Allocated hash algorithm: sha1 Oct 2 18:41:50.716940 kernel: ima: No architecture policies found Oct 2 18:41:50.716946 kernel: Freeing unused kernel memory: 34176K Oct 2 18:41:50.716953 kernel: Run /init as init process Oct 2 18:41:50.716959 kernel: with arguments: Oct 2 18:41:50.716965 kernel: /init Oct 2 18:41:50.716972 kernel: with environment: Oct 2 18:41:50.716978 kernel: HOME=/ Oct 2 18:41:50.716984 kernel: TERM=linux Oct 2 18:41:50.716990 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 2 18:41:50.716998 systemd[1]: systemd 249 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 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Oct 2 18:41:50.717006 systemd[1]: Detected virtualization kvm. Oct 2 18:41:50.717013 systemd[1]: Detected architecture arm64. Oct 2 18:41:50.717020 systemd[1]: Running in initial RAM disk. Oct 2 18:41:50.717028 systemd[1]: No hostname configured, using default hostname. Oct 2 18:41:50.717035 systemd[1]: Hostname set to . Oct 2 18:41:50.717041 systemd[1]: Initializing machine ID from VM UUID. Oct 2 18:41:50.717048 systemd[1]: Queued start job for default target Initrd Default Target. Oct 2 18:41:50.717055 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 2 18:41:50.717061 systemd[1]: Reached target Local Encrypted Volumes. Oct 2 18:41:50.717068 systemd[1]: Reached target Path Units. Oct 2 18:41:50.717074 systemd[1]: Reached target Slice Units. Oct 2 18:41:50.717081 systemd[1]: Reached target Swaps. Oct 2 18:41:50.717089 systemd[1]: Reached target Timer Units. Oct 2 18:41:50.717096 systemd[1]: Listening on Open-iSCSI iscsid Socket. Oct 2 18:41:50.717102 systemd[1]: Listening on Open-iSCSI iscsiuio Socket. Oct 2 18:41:50.717109 systemd[1]: Listening on Journal Audit Socket. Oct 2 18:41:50.717115 systemd[1]: Listening on Journal Socket (/dev/log). Oct 2 18:41:50.717122 systemd[1]: Listening on Journal Socket. Oct 2 18:41:50.717128 systemd[1]: Listening on Network Service Netlink Socket. Oct 2 18:41:50.717135 systemd[1]: Listening on udev Control Socket. Oct 2 18:41:50.717143 systemd[1]: Listening on udev Kernel Socket. Oct 2 18:41:50.717150 systemd[1]: Reached target Socket Units. Oct 2 18:41:50.717156 systemd[1]: Starting Create List of Static Device Nodes... Oct 2 18:41:50.717163 systemd[1]: Finished Network Cleanup. Oct 2 18:41:50.717170 systemd[1]: Started Hardware RNG Entropy Gatherer Daemon. Oct 2 18:41:50.717176 systemd[1]: Starting Journal Service... Oct 2 18:41:50.717183 systemd[1]: Starting Load Kernel Modules... Oct 2 18:41:50.717189 systemd[1]: Starting Setup Virtual Console... Oct 2 18:41:50.717196 systemd[1]: Finished Create List of Static Device Nodes. Oct 2 18:41:50.717204 systemd[1]: Finished Setup Virtual Console. Oct 2 18:41:50.717211 systemd[1]: Starting dracut ask for additional cmdline parameters... Oct 2 18:41:50.717218 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 2 18:41:50.717224 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 2 18:41:50.717233 systemd-journald[280]: Journal started Oct 2 18:41:50.717272 systemd-journald[280]: Runtime Journal (/run/log/journal/a18247ffc07548c98908c4626118c15c) is 6.0M, max 48.5M, 42.4M free. Oct 2 18:41:50.710311 systemd-modules-load[281]: Inserted module 'overlay' Oct 2 18:41:50.719407 systemd[1]: Started Journal Service. Oct 2 18:41:50.726592 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Oct 2 18:41:50.729752 systemd[1]: Finished dracut ask for additional cmdline parameters. Oct 2 18:41:50.731056 kernel: Bridge firewalling registered Oct 2 18:41:50.730132 systemd-modules-load[281]: Inserted module 'br_netfilter' Oct 2 18:41:50.733122 systemd[1]: Finished Load Kernel Modules. Oct 2 18:41:50.734658 systemd[1]: Starting dracut cmdline hook... Oct 2 18:41:50.735887 systemd[1]: Starting Apply Kernel Variables... Oct 2 18:41:50.740243 systemd[1]: Finished Apply Kernel Variables. Oct 2 18:41:50.743833 dracut-cmdline[297]: dracut-dracut-053 Oct 2 18:41:50.746124 dracut-cmdline[297]: 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=cf44c671bad59cbfeb9c66e77c1486bd4dc3d19b46370ded7b14f13f1283486e Oct 2 18:41:50.805584 kernel: SCSI subsystem initialized Oct 2 18:41:50.809574 kernel: Loading iSCSI transport class v2.0-870. Oct 2 18:41:50.816582 kernel: iscsi: registered transport (tcp) Oct 2 18:41:50.828591 kernel: iscsi: registered transport (qla4xxx) Oct 2 18:41:50.828638 kernel: QLogic iSCSI HBA Driver Oct 2 18:41:50.838723 systemd[1]: Finished dracut cmdline hook. Oct 2 18:41:50.840238 systemd[1]: Starting dracut pre-udev hook... Oct 2 18:41:50.853506 kernel: device-mapper: uevent: version 1.0.3 Oct 2 18:41:50.853542 kernel: device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com Oct 2 18:41:50.888587 kernel: raid6: neonx8 gen() 13693 MB/s Oct 2 18:41:50.905573 kernel: raid6: neonx8 xor() 10737 MB/s Oct 2 18:41:50.922570 kernel: raid6: neonx4 gen() 13546 MB/s Oct 2 18:41:50.939577 kernel: raid6: neonx4 xor() 11074 MB/s Oct 2 18:41:50.956569 kernel: raid6: neonx2 gen() 13018 MB/s Oct 2 18:41:50.973569 kernel: raid6: neonx2 xor() 9946 MB/s Oct 2 18:41:50.990568 kernel: raid6: neonx1 gen() 10803 MB/s Oct 2 18:41:51.007575 kernel: raid6: neonx1 xor() 8845 MB/s Oct 2 18:41:51.024573 kernel: raid6: int64x8 gen() 6833 MB/s Oct 2 18:41:51.041576 kernel: raid6: int64x8 xor() 3538 MB/s Oct 2 18:41:51.058571 kernel: raid6: int64x4 gen() 6895 MB/s Oct 2 18:41:51.075567 kernel: raid6: int64x4 xor() 3829 MB/s Oct 2 18:41:51.092572 kernel: raid6: int64x2 gen() 6183 MB/s Oct 2 18:41:51.109575 kernel: raid6: int64x2 xor() 3312 MB/s Oct 2 18:41:51.126570 kernel: raid6: int64x1 gen() 5009 MB/s Oct 2 18:41:51.143686 kernel: raid6: int64x1 xor() 2595 MB/s Oct 2 18:41:51.143698 kernel: raid6: using algorithm neonx8 gen() 13693 MB/s Oct 2 18:41:51.143706 kernel: raid6: .... xor() 10737 MB/s, rmw enabled Oct 2 18:41:51.144776 kernel: raid6: using neon recovery algorithm Oct 2 18:41:51.154571 kernel: xor: measuring software checksum speed Oct 2 18:41:51.155575 kernel: 8regs : 17120 MB/sec Oct 2 18:41:51.155592 kernel: 32regs : 20733 MB/sec Oct 2 18:41:51.156948 kernel: arm64_neon : 28824 MB/sec Oct 2 18:41:51.156959 kernel: xor: using function: arm64_neon (28824 MB/sec) Oct 2 18:41:51.203580 kernel: Btrfs loaded, crc32c=crc32c-generic Oct 2 18:41:51.213745 systemd[1]: Finished dracut pre-udev hook. Oct 2 18:41:51.214000 audit: BPF prog-id=6 op=LOAD Oct 2 18:41:51.215000 audit: BPF prog-id=7 op=LOAD Oct 2 18:41:51.216729 kernel: audit: type=1334 audit(1696272111.214:2): prog-id=6 op=LOAD Oct 2 18:41:51.216750 kernel: audit: type=1334 audit(1696272111.215:3): prog-id=7 op=LOAD Oct 2 18:41:51.216758 kernel: audit: type=1334 audit(1696272111.216:4): prog-id=8 op=LOAD Oct 2 18:41:51.216000 audit: BPF prog-id=8 op=LOAD Oct 2 18:41:51.217101 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 2 18:41:51.229041 systemd-udevd[418]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 2 18:41:51.230739 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 2 18:41:51.232433 systemd[1]: Starting dracut pre-trigger hook... Oct 2 18:41:51.235905 kernel: audit: type=1334 audit(1696272111.233:5): prog-id=9 op=LOAD Oct 2 18:41:51.233000 audit: BPF prog-id=9 op=LOAD Oct 2 18:41:51.235110 systemd[1]: Starting Network Configuration... Oct 2 18:41:51.247890 dracut-pre-trigger[425]: rd.md=0: removing MD RAID activation Oct 2 18:41:51.253785 systemd-networkd[432]: lo: Link UP Oct 2 18:41:51.253793 systemd-networkd[432]: lo: Gained carrier Oct 2 18:41:51.254134 systemd-networkd[432]: Enumeration completed Oct 2 18:41:51.256186 systemd[1]: Started Network Configuration. Oct 2 18:41:51.257000 audit: BPF prog-id=10 op=LOAD Oct 2 18:41:51.259636 kernel: audit: type=1334 audit(1696272111.257:6): prog-id=10 op=LOAD Oct 2 18:41:51.258111 systemd[1]: Starting Network Name Resolution... Oct 2 18:41:51.277447 systemd[1]: Finished dracut pre-trigger hook. Oct 2 18:41:51.279043 systemd[1]: Starting Coldplug All udev Devices... Oct 2 18:41:51.289352 systemd-udevd[418]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 2 18:41:51.289860 systemd-resolved[452]: Positive Trust Anchors: Oct 2 18:41:51.289867 systemd-resolved[452]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 2 18:41:51.289897 systemd-resolved[452]: 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 Oct 2 18:41:51.290216 systemd-resolved[452]: Defaulting to hostname 'linux'. Oct 2 18:41:51.291145 systemd[1]: Started Network Name Resolution. Oct 2 18:41:51.292140 systemd[1]: Reached target Network. Oct 2 18:41:51.294570 systemd[1]: Reached target Host and Network Name Lookups. Oct 2 18:41:51.298499 systemd[1]: Starting iSCSI UserSpace I/O driver... Oct 2 18:41:51.309567 systemd[1]: Finished Coldplug All udev Devices. Oct 2 18:41:51.314152 systemd[1]: Started iSCSI UserSpace I/O driver. Oct 2 18:41:51.315768 systemd[1]: Starting Open-iSCSI... Oct 2 18:41:51.318182 iscsid[490]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Oct 2 18:41:51.318182 iscsid[490]: 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]. Oct 2 18:41:51.318182 iscsid[490]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Oct 2 18:41:51.318182 iscsid[490]: If using hardware iscsi like qla4xxx this message can be ignored. Oct 2 18:41:51.318182 iscsid[490]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Oct 2 18:41:51.328695 iscsid[490]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Oct 2 18:41:51.321510 systemd[1]: Started Open-iSCSI. Oct 2 18:41:51.325768 systemd[1]: Starting dracut initqueue hook... Oct 2 18:41:51.348982 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Oct 2 18:41:51.349143 kernel: vda: detected capacity change from 0 to 4756340736 Oct 2 18:41:51.370488 systemd-udevd[483]: Using default interface naming scheme 'v249'. Oct 2 18:41:51.373575 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 2 18:41:51.375573 systemd-networkd[432]: eth0: Link UP Oct 2 18:41:51.397586 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 scanned by systemd-udevd (478) Oct 2 18:41:51.398985 systemd[1]: Found device /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Oct 2 18:41:51.400041 systemd[1]: Found device /dev/disk/by-partlabel/USR-A. Oct 2 18:41:51.405121 systemd[1]: Found device /dev/disk/by-label/EFI-SYSTEM. Oct 2 18:41:51.407880 systemd[1]: Found device /dev/disk/by-label/ROOT. Oct 2 18:41:51.412874 systemd[1]: Found device /dev/disk/by-label/OEM. Oct 2 18:41:51.413920 systemd[1]: Finished dracut initqueue hook. Oct 2 18:41:51.415104 systemd[1]: Reached target Initrd Root Device. Oct 2 18:41:51.416299 systemd[1]: Reached target Preparation for Remote File Systems. Oct 2 18:41:51.417805 systemd[1]: Reached target Remote Encrypted Volumes. Oct 2 18:41:51.419351 systemd[1]: Reached target Remote File Systems. Oct 2 18:41:51.421401 systemd[1]: Starting Generate new UUID for disk GPT if necessary... Oct 2 18:41:51.422988 systemd[1]: Starting dracut pre-mount hook... Oct 2 18:41:51.429579 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 2 18:41:51.435253 systemd[1]: Finished dracut pre-mount hook. Oct 2 18:41:52.422629 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Oct 2 18:41:52.422671 systemd-networkd[432]: eth0: Gained carrier Oct 2 18:41:52.433618 systemd-networkd[432]: eth0: DHCPv4 address 10.0.0.2/16 via 10.0.0.1 Oct 2 18:41:52.443277 disk-uuid[512]: The operation has completed successfully. Oct 2 18:41:52.444354 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 2 18:41:52.465509 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 2 18:41:52.465652 systemd[1]: Finished Generate new UUID for disk GPT if necessary. Oct 2 18:41:52.467338 systemd[1]: Starting Ignition (setup)... Oct 2 18:41:52.475171 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Oct 2 18:41:52.475199 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 2 18:41:52.476047 kernel: BTRFS info (device vda6): has skinny extents Oct 2 18:41:52.481502 systemd[1]: mnt-oem.mount: Deactivated successfully. Oct 2 18:41:52.494320 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 2 18:41:52.494448 systemd[1]: Finished Ignition (setup). Oct 2 18:41:52.496591 systemd[1]: Starting Ignition (disks)... Oct 2 18:41:52.510065 ignition[547]: Ignition v0.36.2 Oct 2 18:41:52.510082 ignition[547]: Stage: disks Oct 2 18:41:52.510092 ignition[547]: reading system config file "/usr/lib/ignition/base.ign" Oct 2 18:41:52.510102 ignition[547]: no config at "/usr/lib/ignition/base.ign" Oct 2 18:41:52.510144 ignition[547]: parsed url from cmdline: "" Oct 2 18:41:52.510147 ignition[547]: no config URL provided Oct 2 18:41:52.510152 ignition[547]: reading system config file "/usr/lib/ignition/user.ign" Oct 2 18:41:52.510159 ignition[547]: no config at "/usr/lib/ignition/user.ign" Oct 2 18:41:52.510178 ignition[547]: op(1): [started] loading QEMU firmware config module Oct 2 18:41:52.510182 ignition[547]: op(1): executing: "modprobe" "qemu_fw_cfg" Oct 2 18:41:52.512642 ignition[547]: op(1): [finished] loading QEMU firmware config module Oct 2 18:41:52.522221 ignition[547]: parsing config with SHA512: 8318df19c499ae6c6bf5219a1796710ce6cd86fe1ab72bf926012ff726f6d11b010d887ade68d6c8e7dd0fc4a7c30a2510f14bc2365ab3bf6c7d20d358b4e864 Oct 2 18:41:52.523853 ignition[547]: disks: disks passed Oct 2 18:41:52.523862 ignition[547]: Ignition finished successfully Oct 2 18:41:52.524467 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 2 18:41:52.524589 systemd[1]: Finished Ignition (disks). Oct 2 18:41:52.525964 systemd[1]: Reached target Preparation for Local File Systems. Oct 2 18:41:52.528124 systemd[1]: Starting File System Check on /dev/disk/by-label/ROOT... Oct 2 18:41:52.530401 systemd[1]: Starting Verity Setup for /dev/mapper/usr... Oct 2 18:41:52.537296 systemd-fsck[560]: ROOT: clean, 566/553520 files, 55047/553472 blocks Oct 2 18:41:52.542252 systemd[1]: Finished File System Check on /dev/disk/by-label/ROOT. Oct 2 18:41:52.546850 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Oct 2 18:41:52.544493 systemd[1]: Mounting /sysroot... Oct 2 18:41:52.564943 systemd[1]: Found device /dev/mapper/usr. Oct 2 18:41:52.566290 systemd[1]: Starting File System Check on /dev/mapper/usr... Oct 2 18:41:52.567348 systemd[1]: Finished Verity Setup for /dev/mapper/usr. Oct 2 18:41:52.575234 systemd-fsck[574]: fsck.ext4: Operation not permitted while trying to open /dev/mapper/usr Oct 2 18:41:52.575234 systemd-fsck[574]: You must have r/w access to the filesystem or be root Oct 2 18:41:52.575704 systemd-fsck[572]: fsck failed with exit status 8. Oct 2 18:41:52.575707 systemd-fsck[572]: Ignoring error. Oct 2 18:41:52.576795 systemd[1]: Finished File System Check on /dev/mapper/usr. Oct 2 18:41:52.579137 systemd[1]: Mounting /sysusr/usr... Oct 2 18:41:52.595309 systemd[1]: Mounted /sysroot. Oct 2 18:41:52.596452 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null) Oct 2 18:41:52.596041 systemd[1]: Reached target Initrd Root File System. Oct 2 18:41:52.607568 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: (null) Oct 2 18:41:52.607649 systemd[1]: Mounted /sysusr/usr. Oct 2 18:41:52.608337 systemd[1]: Reached target Local File Systems. Oct 2 18:41:52.609497 systemd[1]: Reached target System Initialization. Oct 2 18:41:52.610920 systemd[1]: Reached target Basic System. Oct 2 18:41:52.613665 systemd[1]: Mounting /sysroot/usr... Oct 2 18:41:52.615764 systemd[1]: Mounted /sysroot/usr. Oct 2 18:41:52.617039 systemd[1]: Starting Root filesystem setup... Oct 2 18:41:52.647662 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 2 18:41:52.647779 systemd[1]: Finished Root filesystem setup. Oct 2 18:41:52.649949 systemd[1]: Starting Ignition (files)... Oct 2 18:41:52.651122 systemd[1]: Starting /sysroot/boot... Oct 2 18:41:52.653292 ignition[591]: Ignition v0.36.2 Oct 2 18:41:52.653307 ignition[591]: Stage: files Oct 2 18:41:52.653318 ignition[591]: reading system config file "/usr/lib/ignition/base.ign" Oct 2 18:41:52.653328 ignition[591]: no config at "/usr/lib/ignition/base.ign" Oct 2 18:41:52.653831 ignition[591]: files: compiled without relabeling support, skipping Oct 2 18:41:52.677220 systemd[1]: Finished /sysroot/boot. Oct 2 18:41:52.708939 ignition[591]: files: createUsers: op(1): [started] creating or modifying user "core" Oct 2 18:41:52.708951 ignition[591]: files: createUsers: op(1): executing: "/usr/sbin/usermod" "--root" "/sysroot" "core" Oct 2 18:41:52.710875 ignition[591]: files: createUsers: op(1): [finished] creating or modifying user "core" Oct 2 18:41:52.710885 ignition[591]: files: createUsers: op(2): [started] adding ssh keys to user "core" Oct 2 18:41:52.713670 ignition[591]: files: createUsers: op(2): [finished] adding ssh keys to user "core" Oct 2 18:41:52.713685 ignition[591]: files: op(3): [started] processing unit "coreos-metadata.service" Oct 2 18:41:52.713777 ignition[591]: files: op(3): op(4): [started] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Oct 2 18:41:52.716097 ignition[591]: files: op(3): op(4): [finished] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Oct 2 18:41:52.716673 systemd[1]: ignition-files.service: Deactivated successfully. Oct 2 18:41:52.716102 ignition[591]: files: op(3): [finished] processing unit "coreos-metadata.service" Oct 2 18:41:52.716773 systemd[1]: Finished Ignition (files). Oct 2 18:41:52.716107 ignition[591]: files: files passed Oct 2 18:41:52.718366 systemd[1]: Condition check resulted in Populate torcx store to satisfy profile being skipped. Oct 2 18:41:52.716111 ignition[591]: Ignition finished successfully Oct 2 18:41:52.729034 kernel: audit: type=1334 audit(1696272112.726:7): prog-id=10 op=UNLOAD Oct 2 18:41:52.729060 kernel: audit: type=1334 audit(1696272112.726:8): prog-id=9 op=UNLOAD Oct 2 18:41:52.729069 kernel: audit: type=1334 audit(1696272112.726:9): prog-id=6 op=UNLOAD Oct 2 18:41:52.726000 audit: BPF prog-id=10 op=UNLOAD Oct 2 18:41:52.726000 audit: BPF prog-id=9 op=UNLOAD Oct 2 18:41:52.726000 audit: BPF prog-id=6 op=UNLOAD Oct 2 18:41:52.718955 systemd[1]: Starting Ignition (record completion)... Oct 2 18:41:52.731299 kernel: audit: type=1334 audit(1696272112.728:10): prog-id=3 op=UNLOAD Oct 2 18:41:52.728000 audit: BPF prog-id=3 op=UNLOAD Oct 2 18:41:52.721069 systemd[1]: Starting Reload Configuration from the Real Root... Oct 2 18:41:52.722889 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 2 18:41:52.722983 systemd[1]: Finished Ignition (record completion). Oct 2 18:41:52.725308 systemd[1]: Reloading. Oct 2 18:41:52.808000 audit: BPF prog-id=11 op=LOAD Oct 2 18:41:52.808000 audit: BPF prog-id=12 op=LOAD Oct 2 18:41:52.809000 audit: BPF prog-id=13 op=LOAD Oct 2 18:41:52.809000 audit: BPF prog-id=14 op=LOAD Oct 2 18:41:52.809000 audit: BPF prog-id=15 op=LOAD Oct 2 18:41:52.809000 audit: BPF prog-id=7 op=UNLOAD Oct 2 18:41:52.809000 audit: BPF prog-id=8 op=UNLOAD Oct 2 18:41:52.809000 audit: BPF prog-id=16 op=LOAD Oct 2 18:41:52.809000 audit: BPF prog-id=17 op=LOAD Oct 2 18:41:52.809000 audit: BPF prog-id=18 op=LOAD Oct 2 18:41:52.809000 audit: BPF prog-id=4 op=UNLOAD Oct 2 18:41:52.809000 audit: BPF prog-id=5 op=UNLOAD Oct 2 18:41:52.823460 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 2 18:41:52.823624 systemd[1]: Finished Reload Configuration from the Real Root. Oct 2 18:41:52.825480 systemd[1]: Reached target Initrd File Systems. Oct 2 18:41:52.826964 systemd[1]: Reached target Initrd Default Target. Oct 2 18:41:52.828428 systemd[1]: Condition check resulted in dracut mount hook being skipped. Oct 2 18:41:52.829052 systemd[1]: Starting dracut pre-pivot and cleanup hook... Oct 2 18:41:52.838985 systemd[1]: Finished dracut pre-pivot and cleanup hook. Oct 2 18:41:52.840475 systemd[1]: Starting Cleaning Up and Shutting Down Daemons... Oct 2 18:41:52.846122 systemd[1]: Stopped target Host and Network Name Lookups. Oct 2 18:41:52.847127 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 2 18:41:52.848717 systemd[1]: Stopped target Timer Units. Oct 2 18:41:52.850044 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 2 18:41:52.850142 systemd[1]: Stopped dracut pre-pivot and cleanup hook. Oct 2 18:41:52.851498 systemd[1]: Stopped target Initrd Default Target. Oct 2 18:41:52.852986 systemd[1]: Stopped target Basic System. Oct 2 18:41:52.854314 systemd[1]: Stopped target Initrd Root Device. Oct 2 18:41:52.855684 systemd[1]: Stopped target Path Units. Oct 2 18:41:52.856858 systemd[1]: Stopped target Remote File Systems. Oct 2 18:41:52.858186 systemd[1]: Stopped target Preparation for Remote File Systems. Oct 2 18:41:52.859582 systemd[1]: Stopped target Slice Units. Oct 2 18:41:52.860861 systemd[1]: Stopped target Socket Units. Oct 2 18:41:52.862322 systemd[1]: Stopped target System Initialization. Oct 2 18:41:52.863643 systemd[1]: Stopped target Local File Systems. Oct 2 18:41:52.864924 systemd[1]: Stopped target Preparation for Local File Systems. Oct 2 18:41:52.866439 systemd[1]: Stopped target Swaps. Oct 2 18:41:52.867635 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 2 18:41:52.867733 systemd[1]: Stopped dracut pre-mount hook. Oct 2 18:41:52.869158 systemd[1]: Stopped target Local Encrypted Volumes. Oct 2 18:41:52.870357 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 2 18:41:52.874612 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 2 18:41:52.875797 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 2 18:41:52.875888 systemd[1]: Stopped dracut initqueue hook. Oct 2 18:41:52.881117 iscsid[490]: iscsid shutting down. Oct 2 18:41:52.877454 systemd[1]: Stopping Open-iSCSI... Oct 2 18:41:52.881051 systemd[1]: Stopping /sysroot/boot... Oct 2 18:41:52.881649 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 2 18:41:52.881769 systemd[1]: Stopped Coldplug All udev Devices. Oct 2 18:41:52.882963 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 2 18:41:52.883052 systemd[1]: Stopped dracut pre-trigger hook. Oct 2 18:41:52.885047 systemd[1]: iscsid.service: Deactivated successfully. Oct 2 18:41:52.885152 systemd[1]: Stopped Open-iSCSI. Oct 2 18:41:52.886770 systemd[1]: iscsid.socket: Deactivated successfully. Oct 2 18:41:52.886830 systemd[1]: Closed Open-iSCSI iscsid Socket. Oct 2 18:41:52.888397 systemd[1]: Stopping iSCSI UserSpace I/O driver... Oct 2 18:41:52.891068 systemd[1]: iscsiuio.service: Deactivated successfully. Oct 2 18:41:52.891167 systemd[1]: Stopped iSCSI UserSpace I/O driver. Oct 2 18:41:52.892510 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 2 18:41:52.892619 systemd[1]: Finished Cleaning Up and Shutting Down Daemons. Oct 2 18:41:52.894091 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 2 18:41:52.894191 systemd[1]: Stopped /sysroot/boot. Oct 2 18:41:52.895636 systemd[1]: Stopped target Network. Oct 2 18:41:52.896314 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 2 18:41:52.896381 systemd[1]: Closed Open-iSCSI iscsiuio Socket. Oct 2 18:41:52.897901 systemd[1]: Stopping Network Name Resolution... Oct 2 18:41:52.903458 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 2 18:41:52.903566 systemd[1]: Stopped Network Name Resolution. Oct 2 18:41:52.904824 systemd[1]: Stopping Network Configuration... Oct 2 18:41:52.909651 systemd-networkd[432]: eth0: DHCP lease lost Oct 2 18:41:52.911000 audit: BPF prog-id=11 op=UNLOAD Oct 2 18:41:52.917587 systemd-networkd[432]: eth0: DHCPv6 lease lost Oct 2 18:41:52.918505 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 2 18:41:52.918621 systemd[1]: Stopped Network Configuration. Oct 2 18:41:52.920200 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 2 18:41:52.920227 systemd[1]: Closed Network Service Netlink Socket. Oct 2 18:41:52.921939 systemd[1]: Stopping Network Cleanup... Oct 2 18:41:52.922752 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 2 18:41:52.922817 systemd[1]: Stopped Apply Kernel Variables. Oct 2 18:41:52.924140 systemd[1]: systemd-modules-load.service: Deactivated successfully. Oct 2 18:41:52.929000 audit: BPF prog-id=12 op=UNLOAD Oct 2 18:41:52.924182 systemd[1]: Stopped Load Kernel Modules. Oct 2 18:41:52.925384 systemd[1]: Stopping Rule-based Manager for Device Events and Files... Oct 2 18:41:52.930417 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 2 18:41:52.930521 systemd[1]: Stopped Network Cleanup. Oct 2 18:41:52.934170 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 2 18:41:52.934283 systemd[1]: Stopped Rule-based Manager for Device Events and Files. Oct 2 18:41:52.936070 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 2 18:41:52.936105 systemd[1]: Closed udev Control Socket. Oct 2 18:41:52.937458 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 2 18:41:52.937488 systemd[1]: Closed udev Kernel Socket. Oct 2 18:41:52.938907 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 2 18:41:52.938947 systemd[1]: Stopped dracut pre-udev hook. Oct 2 18:41:52.940111 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 2 18:41:52.940149 systemd[1]: Stopped dracut cmdline hook. Oct 2 18:41:52.947000 audit: BPF prog-id=13 op=UNLOAD Oct 2 18:41:52.941541 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 2 18:41:52.941595 systemd[1]: Stopped dracut ask for additional cmdline parameters. Oct 2 18:41:52.943543 systemd[1]: Starting Cleanup udev Database... Oct 2 18:41:52.944423 systemd[1]: Stopping Hardware RNG Entropy Gatherer Daemon... Oct 2 18:41:52.945770 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 2 18:41:52.945822 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 2 18:41:52.947354 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 2 18:41:52.947395 systemd[1]: Stopped Create List of Static Device Nodes. Oct 2 18:41:52.948749 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 2 18:41:52.948792 systemd[1]: Stopped Setup Virtual Console. Oct 2 18:41:52.950418 systemd[1]: rngd.service: Deactivated successfully. Oct 2 18:41:52.950519 systemd[1]: Stopped Hardware RNG Entropy Gatherer Daemon. Oct 2 18:41:52.960000 audit: BPF prog-id=16 op=UNLOAD Oct 2 18:41:52.952010 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 2 18:41:52.952104 systemd[1]: Finished Cleanup udev Database. Oct 2 18:41:52.953226 systemd[1]: Reached target Switch Root. Oct 2 18:41:52.955083 systemd[1]: Starting Switch Root... Oct 2 18:41:52.959877 systemd[1]: Switching root. Oct 2 18:41:52.972768 systemd-journald[280]: Journal stopped Oct 2 18:41:55.260614 systemd-journald[280]: Received SIGTERM from PID 1 (systemd). Oct 2 18:41:55.260665 kernel: SELinux: policy capability network_peer_controls=1 Oct 2 18:41:55.260677 kernel: SELinux: policy capability open_perms=1 Oct 2 18:41:55.260690 kernel: SELinux: policy capability extended_socket_class=1 Oct 2 18:41:55.260699 kernel: SELinux: policy capability always_check_network=0 Oct 2 18:41:55.260708 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 2 18:41:55.260718 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 2 18:41:55.260727 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 2 18:41:55.260736 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Oct 2 18:41:55.260745 systemd[1]: Successfully loaded SELinux policy in 33.143ms. Oct 2 18:41:55.260766 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 6.381ms. Oct 2 18:41:55.260779 systemd[1]: systemd 249 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 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Oct 2 18:41:55.260791 systemd[1]: Detected virtualization kvm. Oct 2 18:41:55.260801 systemd[1]: Detected architecture arm64. Oct 2 18:41:55.260811 systemd[1]: Detected first boot. Oct 2 18:41:55.260821 systemd[1]: Initializing machine ID from VM UUID. Oct 2 18:41:55.260830 systemd[1]: Populated /etc with preset unit settings. Oct 2 18:41:55.260841 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. Oct 2 18:41:55.260851 systemd[1]: initrd-switch-root.service: Current command vanished from the unit file, execution of the command list won't be resumed. Oct 2 18:41:55.260860 kernel: kauditd_printk_skb: 23 callbacks suppressed Oct 2 18:41:55.260869 kernel: audit: type=1334 audit(1696272115.147:34): prog-id=21 op=LOAD Oct 2 18:41:55.260879 kernel: audit: type=1334 audit(1696272115.148:35): prog-id=22 op=LOAD Oct 2 18:41:55.260888 kernel: audit: type=1334 audit(1696272115.149:36): prog-id=23 op=LOAD Oct 2 18:41:55.260897 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 2 18:41:55.260906 kernel: audit: type=1334 audit(1696272115.149:37): prog-id=17 op=UNLOAD Oct 2 18:41:55.260916 systemd[1]: Stopped Switch Root. Oct 2 18:41:55.260925 kernel: audit: type=1334 audit(1696272115.149:38): prog-id=18 op=UNLOAD Oct 2 18:41:55.260934 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 2 18:41:55.260944 systemd[1]: Created slice Slice /system/addon-config. Oct 2 18:41:55.260954 systemd[1]: Created slice Slice /system/addon-run. Oct 2 18:41:55.260964 kernel: audit: type=1334 audit(1696272115.160:39): prog-id=21 op=UNLOAD Oct 2 18:41:55.260973 systemd[1]: Created slice Slice /system/getty. Oct 2 18:41:55.260983 systemd[1]: Created slice Slice /system/modprobe. Oct 2 18:41:55.260992 systemd[1]: Created slice Slice /system/serial-getty. Oct 2 18:41:55.261002 systemd[1]: Created slice Slice /system/system-cloudinit. Oct 2 18:41:55.261011 systemd[1]: Created slice Slice /system/systemd-fsck. Oct 2 18:41:55.261020 systemd[1]: Created slice User and Session Slice. Oct 2 18:41:55.261030 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 2 18:41:55.261040 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Oct 2 18:41:55.261051 systemd[1]: Set up automount Boot partition Automount Point. Oct 2 18:41:55.261061 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Oct 2 18:41:55.261070 systemd[1]: Stopped target Switch Root. Oct 2 18:41:55.261079 systemd[1]: Stopped target Initrd File Systems. Oct 2 18:41:55.261089 systemd[1]: Stopped target Initrd Root File System. Oct 2 18:41:55.261098 systemd[1]: Reached target Remote Encrypted Volumes. Oct 2 18:41:55.261108 systemd[1]: Reached target Remote File Systems. Oct 2 18:41:55.261117 systemd[1]: Reached target Slice Units. Oct 2 18:41:55.261127 systemd[1]: Reached target Swaps. Oct 2 18:41:55.261136 systemd[1]: Reached target Verify torcx succeeded. Oct 2 18:41:55.261147 systemd[1]: Reached target Local Verity Protected Volumes. Oct 2 18:41:55.261160 systemd[1]: Listening on Process Core Dump Socket. Oct 2 18:41:55.261169 systemd[1]: Listening on initctl Compatibility Named Pipe. Oct 2 18:41:55.261178 systemd[1]: Listening on Network Service Netlink Socket. Oct 2 18:41:55.261188 systemd[1]: Listening on udev Control Socket. Oct 2 18:41:55.261197 systemd[1]: Listening on udev Kernel Socket. Oct 2 18:41:55.261206 systemd[1]: Mounting Huge Pages File System... Oct 2 18:41:55.261216 systemd[1]: Mounting POSIX Message Queue File System... Oct 2 18:41:55.261226 systemd[1]: Mounting External Media Directory... Oct 2 18:41:55.261237 systemd[1]: Mounting Kernel Debug File System... Oct 2 18:41:55.261246 systemd[1]: Mounting Kernel Trace File System... Oct 2 18:41:55.261273 systemd[1]: Mounting Temporary Directory /tmp... Oct 2 18:41:55.261284 systemd[1]: Starting Create missing system files... Oct 2 18:41:55.261294 systemd[1]: Starting Create List of Static Device Nodes... Oct 2 18:41:55.261303 systemd[1]: Starting Load Kernel Module configfs... Oct 2 18:41:55.261313 systemd[1]: Starting Load Kernel Module drm... Oct 2 18:41:55.261323 systemd[1]: Starting Load Kernel Module fuse... Oct 2 18:41:55.261338 systemd[1]: Condition check resulted in Create /etc/nsswitch.conf being skipped. Oct 2 18:41:55.261350 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 2 18:41:55.261361 systemd[1]: Stopped File System Check on Root Device. Oct 2 18:41:55.261372 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 2 18:41:55.261381 systemd[1]: Stopped systemd-fsck-usr.service. Oct 2 18:41:55.261390 systemd[1]: Stopped Journal Service. Oct 2 18:41:55.261400 kernel: audit: type=1334 audit(1696272115.241:40): prog-id=24 op=LOAD Oct 2 18:41:55.261409 kernel: audit: type=1334 audit(1696272115.241:41): prog-id=25 op=LOAD Oct 2 18:41:55.261419 kernel: audit: type=1334 audit(1696272115.242:42): prog-id=26 op=LOAD Oct 2 18:41:55.261428 systemd[1]: Starting Journal Service... Oct 2 18:41:55.261438 kernel: audit: type=1334 audit(1696272115.242:43): prog-id=22 op=UNLOAD Oct 2 18:41:55.261448 systemd[1]: Starting Load Kernel Modules... Oct 2 18:41:55.261459 systemd[1]: Starting Remount Root and Kernel File Systems... Oct 2 18:41:55.261469 kernel: fuse: init (API version 7.32) Oct 2 18:41:55.261478 systemd[1]: Starting Coldplug All udev Devices... Oct 2 18:41:55.261488 systemd[1]: verity-setup.service: Deactivated successfully. Oct 2 18:41:55.261498 systemd[1]: Stopped verity-setup.service. Oct 2 18:41:55.261507 systemd[1]: Mounted Huge Pages File System. Oct 2 18:41:55.261517 systemd[1]: Mounted POSIX Message Queue File System. Oct 2 18:41:55.261527 systemd-journald[805]: Journal started Oct 2 18:41:55.261584 systemd-journald[805]: Runtime Journal (/run/log/journal/a18247ffc07548c98908c4626118c15c) is 6.0M, max 48.5M, 42.4M free. Oct 2 18:41:53.059000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 2 18:41:53.104000 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 Oct 2 18:41:53.104000 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 Oct 2 18:41:53.104000 audit: BPF prog-id=19 op=LOAD Oct 2 18:41:53.104000 audit: BPF prog-id=19 op=UNLOAD Oct 2 18:41:53.104000 audit: BPF prog-id=20 op=LOAD Oct 2 18:41:53.104000 audit: BPF prog-id=20 op=UNLOAD Oct 2 18:41:55.147000 audit: BPF prog-id=21 op=LOAD Oct 2 18:41:55.148000 audit: BPF prog-id=22 op=LOAD Oct 2 18:41:55.149000 audit: BPF prog-id=23 op=LOAD Oct 2 18:41:55.149000 audit: BPF prog-id=17 op=UNLOAD Oct 2 18:41:55.149000 audit: BPF prog-id=18 op=UNLOAD Oct 2 18:41:55.160000 audit: BPF prog-id=21 op=UNLOAD Oct 2 18:41:55.241000 audit: BPF prog-id=24 op=LOAD Oct 2 18:41:55.241000 audit: BPF prog-id=25 op=LOAD Oct 2 18:41:55.242000 audit: BPF prog-id=26 op=LOAD Oct 2 18:41:55.242000 audit: BPF prog-id=22 op=UNLOAD Oct 2 18:41:55.242000 audit: BPF prog-id=23 op=UNLOAD Oct 2 18:41:55.259000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Oct 2 18:41:53.169513 /usr/lib/systemd/system-generators/torcx-generator[755]: time="2023-10-02T18:41:53Z" 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/3033.3.17 /usr/share/oem/torcx/store /var/lib/torcx/store/3033.3.17 /var/lib/torcx/store]" Oct 2 18:41:55.146804 systemd[1]: Queued start job for default target Multi-User System. Oct 2 18:41:53.170030 /usr/lib/systemd/system-generators/torcx-generator[755]: time="2023-10-02T18:41:53Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 2 18:41:55.150531 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 2 18:41:53.170051 /usr/lib/systemd/system-generators/torcx-generator[755]: time="2023-10-02T18:41:53Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 2 18:41:53.170372 /usr/lib/systemd/system-generators/torcx-generator[755]: time="2023-10-02T18:41:53Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Oct 2 18:41:53.170384 /usr/lib/systemd/system-generators/torcx-generator[755]: time="2023-10-02T18:41:53Z" level=debug msg="skipped missing lower profile" missing profile=oem Oct 2 18:41:53.170413 /usr/lib/systemd/system-generators/torcx-generator[755]: time="2023-10-02T18:41:53Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Oct 2 18:41:53.170427 /usr/lib/systemd/system-generators/torcx-generator[755]: time="2023-10-02T18:41:53Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Oct 2 18:41:53.170648 /usr/lib/systemd/system-generators/torcx-generator[755]: time="2023-10-02T18:41:53Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Oct 2 18:41:53.170684 /usr/lib/systemd/system-generators/torcx-generator[755]: time="2023-10-02T18:41:53Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 2 18:41:53.170702 /usr/lib/systemd/system-generators/torcx-generator[755]: time="2023-10-02T18:41:53Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 2 18:41:53.171033 /usr/lib/systemd/system-generators/torcx-generator[755]: time="2023-10-02T18:41:53Z" 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 Oct 2 18:41:53.171062 /usr/lib/systemd/system-generators/torcx-generator[755]: time="2023-10-02T18:41:53Z" 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 Oct 2 18:41:53.171081 /usr/lib/systemd/system-generators/torcx-generator[755]: time="2023-10-02T18:41:53Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3033.3.17: no such file or directory" path=/usr/share/oem/torcx/store/3033.3.17 Oct 2 18:41:53.171096 /usr/lib/systemd/system-generators/torcx-generator[755]: time="2023-10-02T18:41:53Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Oct 2 18:41:53.171113 /usr/lib/systemd/system-generators/torcx-generator[755]: time="2023-10-02T18:41:53Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3033.3.17: no such file or directory" path=/var/lib/torcx/store/3033.3.17 Oct 2 18:41:53.171126 /usr/lib/systemd/system-generators/torcx-generator[755]: time="2023-10-02T18:41:53Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Oct 2 18:41:54.942967 /usr/lib/systemd/system-generators/torcx-generator[755]: time="2023-10-02T18:41:54Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 2 18:41:54.943233 /usr/lib/systemd/system-generators/torcx-generator[755]: time="2023-10-02T18:41:54Z" 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 Oct 2 18:41:54.943354 /usr/lib/systemd/system-generators/torcx-generator[755]: time="2023-10-02T18:41:54Z" 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 Oct 2 18:41:54.943528 /usr/lib/systemd/system-generators/torcx-generator[755]: time="2023-10-02T18:41:54Z" 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 Oct 2 18:41:54.943615 /usr/lib/systemd/system-generators/torcx-generator[755]: time="2023-10-02T18:41:54Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Oct 2 18:41:54.943678 /usr/lib/systemd/system-generators/torcx-generator[755]: time="2023-10-02T18:41:54Z" 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 Oct 2 18:41:55.263653 systemd[1]: Started Journal Service. Oct 2 18:41:55.264135 systemd[1]: Mounted External Media Directory. Oct 2 18:41:55.265061 systemd[1]: Mounted Kernel Debug File System. Oct 2 18:41:55.265982 systemd[1]: Mounted Kernel Trace File System. Oct 2 18:41:55.266959 systemd[1]: Mounted Temporary Directory /tmp. Oct 2 18:41:55.268125 systemd[1]: Finished Create missing system files. Oct 2 18:41:55.269294 systemd[1]: Finished Create List of Static Device Nodes. Oct 2 18:41:55.270445 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 2 18:41:55.270662 systemd[1]: Finished Load Kernel Module configfs. Oct 2 18:41:55.271721 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 2 18:41:55.271921 systemd[1]: Finished Load Kernel Module drm. Oct 2 18:41:55.272983 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 2 18:41:55.273172 systemd[1]: Finished Load Kernel Module fuse. Oct 2 18:41:55.274477 systemd[1]: Finished Load Kernel Modules. Oct 2 18:41:55.275602 systemd[1]: Finished Remount Root and Kernel File Systems. Oct 2 18:41:55.278728 systemd[1]: Mounting FUSE Control File System... Oct 2 18:41:55.280512 systemd[1]: Mounting Kernel Configuration File System... Oct 2 18:41:55.281492 systemd[1]: Condition check resulted in Remount Root File System being skipped. Oct 2 18:41:55.284022 systemd[1]: Starting Rebuild Hardware Database... Oct 2 18:41:55.285793 systemd[1]: Starting Flush Journal to Persistent Storage... Oct 2 18:41:55.286723 systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. Oct 2 18:41:55.291234 systemd-journald[805]: Time spent on flushing to /var/log/journal/a18247ffc07548c98908c4626118c15c is 8.442ms for 766 entries. Oct 2 18:41:55.291234 systemd-journald[805]: System Journal (/var/log/journal/a18247ffc07548c98908c4626118c15c) is 8.0M, max 195.6M, 187.6M free. Oct 2 18:41:55.287653 systemd[1]: Starting Load/Save Random Seed... Oct 2 18:41:55.289474 systemd[1]: Starting Apply Kernel Variables... Oct 2 18:41:55.291775 systemd[1]: Starting Create System Users... Oct 2 18:41:55.294688 systemd[1]: Mounted FUSE Control File System. Oct 2 18:41:55.295765 systemd[1]: Mounted Kernel Configuration File System. Oct 2 18:41:55.297218 systemd[1]: Finished Load/Save Random Seed. Oct 2 18:41:55.298162 systemd[1]: Reached target First Boot Complete. Oct 2 18:41:55.302051 systemd[1]: Finished Coldplug All udev Devices. Oct 2 18:41:55.303883 systemd[1]: Starting Wait for udev To Complete Device Initialization... Oct 2 18:41:55.305364 systemd[1]: Finished Apply Kernel Variables. Oct 2 18:41:55.319026 systemd-sysusers[816]: Creating group sgx with gid 999. Oct 2 18:41:55.320225 systemd[1]: Finished Flush Journal to Persistent Storage. Oct 2 18:41:55.321775 systemd-sysusers[816]: Creating group systemd-oom with gid 998. Oct 2 18:41:55.322223 systemd-sysusers[816]: Creating user systemd-oom (systemd Userspace OOM Killer) with uid 998 and gid 998. Oct 2 18:41:55.322826 systemd-sysusers[816]: Creating group systemd-timesync with gid 997. Oct 2 18:41:55.323254 systemd-sysusers[816]: Creating user systemd-timesync (systemd Time Synchronization) with uid 997 and gid 997. Oct 2 18:41:55.323958 systemd-sysusers[816]: Creating group systemd-coredump with gid 996. Oct 2 18:41:55.324391 systemd-sysusers[816]: Creating user systemd-coredump (systemd Core Dumper) with uid 996 and gid 996. Oct 2 18:41:55.332397 systemd[1]: Finished Create System Users. Oct 2 18:41:55.334294 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 2 18:41:55.353261 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 2 18:41:55.540497 systemd[1]: Finished Rebuild Hardware Database. Oct 2 18:41:55.541000 audit: BPF prog-id=27 op=LOAD Oct 2 18:41:55.541000 audit: BPF prog-id=28 op=LOAD Oct 2 18:41:55.541000 audit: BPF prog-id=29 op=LOAD Oct 2 18:41:55.541000 audit: BPF prog-id=14 op=UNLOAD Oct 2 18:41:55.541000 audit: BPF prog-id=15 op=UNLOAD Oct 2 18:41:55.542971 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 2 18:41:55.581683 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 2 18:41:55.583000 audit: BPF prog-id=30 op=LOAD Oct 2 18:41:55.586069 systemd[1]: Starting Network Configuration... Oct 2 18:41:55.621768 systemd-udevd[834]: Using default interface naming scheme 'v249'. Oct 2 18:41:55.635596 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by systemd-udevd (837) Oct 2 18:41:55.644397 udevadm[819]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Oct 2 18:41:55.645948 systemd-networkd[832]: lo: Link UP Oct 2 18:41:55.645958 systemd-networkd[832]: lo: Gained carrier Oct 2 18:41:55.646454 systemd-networkd[832]: Enumeration completed Oct 2 18:41:55.646542 systemd[1]: Started Network Configuration. Oct 2 18:41:55.649190 systemd-networkd[832]: eth0: Link UP Oct 2 18:41:55.655603 systemd-networkd[832]: eth0: Gained carrier Oct 2 18:41:55.659902 systemd[1]: Finished Wait for udev To Complete Device Initialization. Oct 2 18:41:55.662083 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 2 18:41:55.663222 systemd-networkd[832]: eth0: DHCPv4 address 10.0.0.2/16 via 10.0.0.1 Oct 2 18:41:55.679311 lvm[850]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 2 18:41:55.708610 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 2 18:41:55.709643 systemd[1]: Reached target Local Encrypted Volumes. Oct 2 18:41:55.711570 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 2 18:41:55.714908 lvm[851]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 2 18:41:55.743471 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 2 18:41:55.744525 systemd[1]: Reached target Preparation for Local File Systems. Oct 2 18:41:55.745514 systemd[1]: Condition check resulted in Virtual Machine and Container Storage (Compatibility) being skipped. Oct 2 18:41:55.745541 systemd[1]: Reached target Containers. Oct 2 18:41:55.747242 systemd[1]: Starting File System Check on /dev/disk/by-label/OEM... Oct 2 18:41:55.759634 systemd[1]: Finished File System Check on /dev/disk/by-label/OEM. Oct 2 18:41:55.761762 systemd[1]: Mounting /usr/share/oem... Oct 2 18:41:55.767819 kernel: BTRFS info (device vda6): using crc32c (crc32c-generic) checksum algorithm Oct 2 18:41:55.767877 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 2 18:41:55.767895 kernel: BTRFS info (device vda6): has skinny extents Oct 2 18:41:55.770595 systemd[1]: Mounted /usr/share/oem. Oct 2 18:41:55.771366 systemd[1]: Reached target Local File Systems. Oct 2 18:41:55.773188 systemd[1]: Starting Rebuild Dynamic Linker Cache... Oct 2 18:41:55.774589 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Oct 2 18:41:55.774633 systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Oct 2 18:41:55.775789 systemd[1]: Starting Commit a transient machine-id on disk... Oct 2 18:41:55.777899 systemd[1]: Starting Create Volatile Files and Directories... Oct 2 18:41:55.785355 systemd-tmpfiles[876]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Oct 2 18:41:55.786378 systemd-tmpfiles[876]: /usr/lib/tmpfiles.d/systemd.conf:33: Duplicate line for path "/var/lib/systemd", ignoring. Oct 2 18:41:55.797979 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 2 18:41:55.798589 systemd[1]: Finished Commit a transient machine-id on disk. Oct 2 18:41:55.830314 systemd-tmpfiles[876]: Detected autofs mount point /boot during canonicalization of /boot. Oct 2 18:41:55.830325 systemd-tmpfiles[876]: Skipping /boot Oct 2 18:41:55.834887 systemd-tmpfiles[876]: Detected autofs mount point /boot during canonicalization of /boot. Oct 2 18:41:55.834903 systemd-tmpfiles[876]: Skipping /boot Oct 2 18:41:55.873251 systemd[1]: Finished Create Volatile Files and Directories. Oct 2 18:41:55.875383 systemd[1]: Starting Load Security Auditing Rules... Oct 2 18:41:55.877285 systemd[1]: Starting Clean up broken links in /etc/ssl/certs... Oct 2 18:41:55.879461 systemd[1]: Starting Rebuild Journal Catalog... Oct 2 18:41:55.882000 audit: BPF prog-id=31 op=LOAD Oct 2 18:41:55.883013 systemd[1]: Starting Network Name Resolution... Oct 2 18:41:55.884000 audit: BPF prog-id=32 op=LOAD Oct 2 18:41:55.885117 systemd[1]: Starting Network Time Synchronization... Oct 2 18:41:55.888182 systemd[1]: Starting Record System Boot/Shutdown in UTMP... Oct 2 18:41:55.889726 systemd[1]: Finished Clean up broken links in /etc/ssl/certs. Oct 2 18:41:55.890963 systemd[1]: Condition check resulted in Update CA bundle at /etc/ssl/certs/ca-certificates.crt being skipped. Oct 2 18:41:55.898262 systemd[1]: Finished Record System Boot/Shutdown in UTMP. Oct 2 18:41:55.910041 systemd[1]: Finished Rebuild Journal Catalog. Oct 2 18:41:55.929228 systemd[1]: Started Network Time Synchronization. Oct 2 18:41:55.929743 systemd-timesyncd[883]: Initial synchronization to time server 10.0.0.1:123 (10.0.0.1). Oct 2 18:41:55.930411 systemd[1]: Reached target System Time Set. Oct 2 18:41:55.934121 augenrules[898]: No rules Oct 2 18:41:55.933000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Oct 2 18:41:55.935177 systemd[1]: Finished Load Security Auditing Rules. Oct 2 18:41:55.938978 systemd-resolved[882]: Positive Trust Anchors: Oct 2 18:41:55.939013 systemd-resolved[882]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 2 18:41:55.939060 systemd-resolved[882]: 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 Oct 2 18:41:55.940155 systemd-resolved[882]: Defaulting to hostname 'linux'. Oct 2 18:41:55.941918 systemd[1]: Started Network Name Resolution. Oct 2 18:41:55.942861 systemd[1]: Reached target Network. Oct 2 18:41:55.943640 systemd[1]: Reached target Host and Network Name Lookups. Oct 2 18:41:55.971291 systemd[1]: Finished Rebuild Dynamic Linker Cache. Oct 2 18:41:55.973429 systemd[1]: Starting Update is Completed... Oct 2 18:41:55.978188 systemd[1]: Finished Update is Completed. Oct 2 18:41:55.979041 systemd[1]: Reached target System Initialization. Oct 2 18:41:55.979968 systemd[1]: Started Watch for update engine configuration changes. Oct 2 18:41:55.981016 systemd[1]: Started Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 2 18:41:55.982213 systemd[1]: Started Daily Log Rotation. Oct 2 18:41:55.983211 systemd[1]: Started Weekly check for MD array's redundancy information.. Oct 2 18:41:55.984286 systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 2 18:41:55.985224 systemd[1]: Condition check resulted in Update Engine Stub Timer being skipped. Oct 2 18:41:55.985257 systemd[1]: Reached target Path Units. Oct 2 18:41:55.985972 systemd[1]: Reached target Timer Units. Oct 2 18:41:55.987021 systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 2 18:41:55.988964 systemd[1]: Starting Docker Socket for the API... Oct 2 18:41:55.992189 systemd[1]: Listening on OpenSSH Server Socket. Oct 2 18:41:55.993446 systemd[1]: Listening on Docker Socket for the API. Oct 2 18:41:55.994375 systemd[1]: Reached target Socket Units. Oct 2 18:41:55.995127 systemd[1]: Reached target Basic System. Oct 2 18:41:55.995948 systemd[1]: Condition check resulted in Configure Addon /usr/share/oem being skipped. Oct 2 18:41:55.995978 systemd[1]: Condition check resulted in Run Addon /usr/share/oem being skipped. Oct 2 18:41:55.996877 systemd[1]: Starting containerd container runtime... Oct 2 18:41:55.998549 systemd[1]: Started D-Bus System Message Bus. Oct 2 18:41:56.001652 systemd[1]: Starting Extend Filesystems... Oct 2 18:41:56.002389 systemd[1]: Condition check resulted in Modifies /etc/environment for CoreOS being skipped. Oct 2 18:41:56.003453 systemd[1]: Starting Generate /run/flatcar/motd... Oct 2 18:41:56.005288 systemd[1]: Starting Install an ssh key from /proc/cmdline... Oct 2 18:41:56.009510 systemd[1]: Starting Generate sshd host keys... Oct 2 18:41:56.010362 systemd[1]: Condition check resulted in Load cloud-config from /usr/share/oem/cloud-config.yml being skipped. Oct 2 18:41:56.010396 systemd[1]: Reached target Load system-provided cloud configs. Oct 2 18:41:56.013172 systemd[1]: Starting User Login Management... Oct 2 18:41:56.014580 extend-filesystems[909]: Found vda Oct 2 18:41:56.014580 extend-filesystems[909]: Found vda1 Oct 2 18:41:56.014580 extend-filesystems[909]: Found vda2 Oct 2 18:41:56.014580 extend-filesystems[909]: Found vda3 Oct 2 18:41:56.014580 extend-filesystems[909]: Found usr Oct 2 18:41:56.014580 extend-filesystems[909]: Found vda4 Oct 2 18:41:56.014580 extend-filesystems[909]: Found vda6 Oct 2 18:41:56.014580 extend-filesystems[909]: Found vda7 Oct 2 18:41:56.014580 extend-filesystems[909]: Found vda9 Oct 2 18:41:56.014580 extend-filesystems[909]: Checking size of /dev/vda9 Oct 2 18:41:56.014285 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 2 18:41:56.014989 systemd[1]: Starting Update Engine... Oct 2 18:41:56.017526 systemd[1]: Condition check resulted in Load cloud-config from url defined in /proc/cmdline being skipped. Oct 2 18:41:56.017550 systemd[1]: Reached target Load user-provided cloud configs. Oct 2 18:41:56.022751 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 2 18:41:56.022971 systemd[1]: Finished Install an ssh key from /proc/cmdline. Oct 2 18:41:56.024971 systemd[1]: motdgen.service: Deactivated successfully. Oct 2 18:41:56.025190 systemd[1]: Finished Generate /run/flatcar/motd. Oct 2 18:41:56.041469 extend-filesystems[909]: Old size kept for /dev/vda9 Oct 2 18:41:56.040449 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 2 18:41:56.040681 systemd[1]: Finished Extend Filesystems. Oct 2 18:41:56.073009 systemd-logind[919]: Watching system buttons on /dev/input/event0 (Power Button) Oct 2 18:41:56.073356 systemd-logind[919]: New seat seat0. Oct 2 18:41:56.074833 systemd[1]: Started User Login Management. Oct 2 18:41:56.099626 update_engine[925]: I1002 18:41:56.099349 925 main.cc:89] Flatcar Update Engine starting Oct 2 18:41:56.100576 env[929]: time="2023-10-02T18:41:56.100517076Z" level=info msg="starting containerd" revision=d0d56c1a4ace8bae8c7c98d28ba98f0537ebe704 version=1.5.13 Oct 2 18:41:56.101678 update_engine[925]: I1002 18:41:56.101637 925 update_check_scheduler.cc:74] Next update check in 7m1s Oct 2 18:41:56.103740 systemd[1]: Started Update Engine. Oct 2 18:41:56.106058 systemd[1]: Started Cluster reboot manager. Oct 2 18:41:56.125310 env[929]: time="2023-10-02T18:41:56.125256334Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Oct 2 18:41:56.125435 env[929]: time="2023-10-02T18:41:56.125415337Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Oct 2 18:41:56.127338 env[929]: time="2023-10-02T18:41:56.126469628Z" 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.10.193-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Oct 2 18:41:56.127338 env[929]: time="2023-10-02T18:41:56.126501598Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Oct 2 18:41:56.127338 env[929]: time="2023-10-02T18:41:56.126707398Z" 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 Oct 2 18:41:56.127338 env[929]: time="2023-10-02T18:41:56.126724889Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Oct 2 18:41:56.127338 env[929]: time="2023-10-02T18:41:56.126747477Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" Oct 2 18:41:56.127338 env[929]: time="2023-10-02T18:41:56.126757362Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Oct 2 18:41:56.127338 env[929]: time="2023-10-02T18:41:56.126837867Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Oct 2 18:41:56.127338 env[929]: time="2023-10-02T18:41:56.126994090Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Oct 2 18:41:56.127338 env[929]: time="2023-10-02T18:41:56.127109732Z" 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 Oct 2 18:41:56.127338 env[929]: time="2023-10-02T18:41:56.127124211Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Oct 2 18:41:56.127803 env[929]: time="2023-10-02T18:41:56.127170429Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" Oct 2 18:41:56.127803 env[929]: time="2023-10-02T18:41:56.127182129Z" level=info msg="metadata content store policy set" policy=shared Oct 2 18:41:56.133707 env[929]: time="2023-10-02T18:41:56.133651704Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Oct 2 18:41:56.133707 env[929]: time="2023-10-02T18:41:56.133687960Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Oct 2 18:41:56.133789 env[929]: time="2023-10-02T18:41:56.133724217Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Oct 2 18:41:56.133789 env[929]: time="2023-10-02T18:41:56.133760396Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Oct 2 18:41:56.133789 env[929]: time="2023-10-02T18:41:56.133774258Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Oct 2 18:41:56.133789 env[929]: time="2023-10-02T18:41:56.133786497Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Oct 2 18:41:56.133869 env[929]: time="2023-10-02T18:41:56.133800552Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Oct 2 18:41:56.133869 env[929]: time="2023-10-02T18:41:56.133812638Z" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1 Oct 2 18:41:56.133869 env[929]: time="2023-10-02T18:41:56.133825264Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Oct 2 18:41:56.133869 env[929]: time="2023-10-02T18:41:56.133837967Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Oct 2 18:41:56.133869 env[929]: time="2023-10-02T18:41:56.133851326Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Oct 2 18:41:56.133978 env[929]: time="2023-10-02T18:41:56.133956813Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Oct 2 18:41:56.134125 env[929]: time="2023-10-02T18:41:56.134044578Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Oct 2 18:41:56.134318 env[929]: time="2023-10-02T18:41:56.134295090Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Oct 2 18:41:56.134353 env[929]: time="2023-10-02T18:41:56.134322118Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Oct 2 18:41:56.134453 env[929]: time="2023-10-02T18:41:56.134435289Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Oct 2 18:41:56.134453 env[929]: time="2023-10-02T18:41:56.134451931Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Oct 2 18:41:56.134573 env[929]: time="2023-10-02T18:41:56.134463669Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Oct 2 18:41:56.134573 env[929]: time="2023-10-02T18:41:56.134474518Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Oct 2 18:41:56.134573 env[929]: time="2023-10-02T18:41:56.134485175Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Oct 2 18:41:56.134573 env[929]: time="2023-10-02T18:41:56.134496720Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Oct 2 18:41:56.134573 env[929]: time="2023-10-02T18:41:56.134509423Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Oct 2 18:41:56.134573 env[929]: time="2023-10-02T18:41:56.134531046Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Oct 2 18:41:56.134573 env[929]: time="2023-10-02T18:41:56.134542282Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Oct 2 18:41:56.134702 env[929]: time="2023-10-02T18:41:56.134677732Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Oct 2 18:41:56.134702 env[929]: time="2023-10-02T18:41:56.134694875Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Oct 2 18:41:56.134742 env[929]: time="2023-10-02T18:41:56.134706381Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Oct 2 18:41:56.134742 env[929]: time="2023-10-02T18:41:56.134719316Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Oct 2 18:41:56.134958 env[929]: time="2023-10-02T18:41:56.134901525Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false BaseRuntimeSpec:} UntrustedWorkloadRuntime:{Type: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false BaseRuntimeSpec:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false BaseRuntimeSpec:}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginConfTemplate:} 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:false SelinuxCategoryRange:1024 SandboxImage:k8s.gcr.io/pause:3.5 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 IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir: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}" Oct 2 18:41:56.136293 env[929]: time="2023-10-02T18:41:56.134968323Z" level=info msg="Connect containerd service" Oct 2 18:41:56.136293 env[929]: time="2023-10-02T18:41:56.135009058Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Oct 2 18:41:56.136293 env[929]: time="2023-10-02T18:41:56.135709744Z" 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" Oct 2 18:41:56.136293 env[929]: time="2023-10-02T18:41:56.135770364Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Oct 2 18:41:56.136293 env[929]: time="2023-10-02T18:41:56.135825038Z" level=info msg="Start subscribing containerd event" Oct 2 18:41:56.136293 env[929]: time="2023-10-02T18:41:56.135939406Z" level=info msg="Start recovering state" Oct 2 18:41:56.136293 env[929]: time="2023-10-02T18:41:56.135998327Z" level=info msg="Start event monitor" Oct 2 18:41:56.136293 env[929]: time="2023-10-02T18:41:56.136091768Z" level=info msg="Start snapshots syncer" Oct 2 18:41:56.136293 env[929]: time="2023-10-02T18:41:56.136099683Z" level=info msg="Start cni network conf syncer" Oct 2 18:41:56.136293 env[929]: time="2023-10-02T18:41:56.136105243Z" level=info msg="Start streaming server" Oct 2 18:41:56.136611 env[929]: time="2023-10-02T18:41:56.136592175Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Oct 2 18:41:56.136681 env[929]: time="2023-10-02T18:41:56.136633296Z" level=info msg=serving... address=/run/containerd/containerd.sock Oct 2 18:41:56.136743 systemd[1]: Started containerd container runtime. Oct 2 18:41:56.138482 env[929]: time="2023-10-02T18:41:56.138248537Z" level=info msg="containerd successfully booted in 0.038278s" Oct 2 18:41:56.166089 locksmithd[942]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 2 18:41:56.507705 sshd_keygen[927]: ssh-keygen: generating new host keys: RSA DSA ECDSA ED25519 Oct 2 18:41:56.524948 systemd[1]: Finished Generate sshd host keys. Oct 2 18:41:56.527055 systemd[1]: Starting Generate /run/issue... Oct 2 18:41:56.531101 systemd[1]: issuegen.service: Deactivated successfully. Oct 2 18:41:56.531310 systemd[1]: Finished Generate /run/issue. Oct 2 18:41:56.533314 systemd[1]: Starting Permit User Sessions... Oct 2 18:41:56.537476 systemd[1]: Finished Permit User Sessions. Oct 2 18:41:56.539337 systemd[1]: Started Getty on tty1. Oct 2 18:41:56.541183 systemd[1]: Started Serial Getty on ttyAMA0. Oct 2 18:41:56.542156 systemd[1]: Reached target Login Prompts. Oct 2 18:41:56.542975 systemd[1]: Reached target Multi-User System. Oct 2 18:41:56.544851 systemd[1]: Starting Record Runlevel Change in UTMP... Oct 2 18:41:56.549163 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 2 18:41:56.549365 systemd[1]: Finished Record Runlevel Change in UTMP. Oct 2 18:41:56.550346 systemd[1]: Startup finished in 548ms (kernel) + 2.450s (initrd) + 3.528s (userspace) = 6.527s. Oct 2 18:41:57.605771 systemd-networkd[832]: eth0: Gained IPv6LL Oct 2 18:42:05.725518 systemd[1]: Created slice Slice /system/sshd. Oct 2 18:42:05.726550 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:52614). Oct 2 18:42:06.050269 sshd[965]: Accepted publickey for core from 10.0.0.1 port 52614 ssh2: RSA SHA256:NktSY6Bc6bn5o44ywwTbDBj/CEQn71u67t6NWiM/TgY Oct 2 18:42:06.059339 sshd[965]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 2 18:42:06.083697 systemd[1]: Created slice User Slice of UID 500. Oct 2 18:42:06.084802 systemd[1]: Starting User Runtime Directory /run/user/500... Oct 2 18:42:06.086602 systemd-logind[919]: New session 1 of user core. Oct 2 18:42:06.093385 systemd[1]: Finished User Runtime Directory /run/user/500. Oct 2 18:42:06.094670 systemd[1]: Starting User Manager for UID 500... Oct 2 18:42:06.097008 systemd[968]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 2 18:42:06.178037 systemd[968]: Queued start job for default target Main User Target. Oct 2 18:42:06.178207 systemd[968]: Reached target Paths. Oct 2 18:42:06.178220 systemd[968]: Reached target Sockets. Oct 2 18:42:06.178231 systemd[968]: Reached target Timers. Oct 2 18:42:06.178240 systemd[968]: Reached target Basic System. Oct 2 18:42:06.178281 systemd[968]: Reached target Main User Target. Oct 2 18:42:06.178295 systemd[968]: Startup finished in 67ms. Oct 2 18:42:06.178596 systemd[1]: Started User Manager for UID 500. Oct 2 18:42:06.179429 systemd[1]: Started Session 1 of User core. Oct 2 18:42:06.245265 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:52626). Oct 2 18:42:06.293491 sshd[977]: Accepted publickey for core from 10.0.0.1 port 52626 ssh2: RSA SHA256:NktSY6Bc6bn5o44ywwTbDBj/CEQn71u67t6NWiM/TgY Oct 2 18:42:06.294844 sshd[977]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 2 18:42:06.298473 systemd-logind[919]: New session 2 of user core. Oct 2 18:42:06.309906 systemd[1]: Started Session 2 of User core. Oct 2 18:42:06.380632 sshd[977]: pam_unix(sshd:session): session closed for user core Oct 2 18:42:06.384150 systemd[1]: sshd@1-10.0.0.2:22-10.0.0.1:52626.service: Deactivated successfully. Oct 2 18:42:06.384799 systemd[1]: session-2.scope: Deactivated successfully. Oct 2 18:42:06.385805 systemd-logind[919]: Session 2 logged out. Waiting for processes to exit. Oct 2 18:42:06.406018 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:52640). Oct 2 18:42:06.406881 systemd-logind[919]: Removed session 2. Oct 2 18:42:06.446021 sshd[983]: Accepted publickey for core from 10.0.0.1 port 52640 ssh2: RSA SHA256:NktSY6Bc6bn5o44ywwTbDBj/CEQn71u67t6NWiM/TgY Oct 2 18:42:06.447214 sshd[983]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 2 18:42:06.450156 systemd[1]: Started Session 3 of User core. Oct 2 18:42:06.450399 systemd-logind[919]: New session 3 of user core. Oct 2 18:42:06.499621 sshd[983]: pam_unix(sshd:session): session closed for user core Oct 2 18:42:06.502358 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:52644). Oct 2 18:42:06.502713 systemd[1]: sshd@2-10.0.0.2:22-10.0.0.1:52640.service: Deactivated successfully. Oct 2 18:42:06.508566 systemd[1]: session-3.scope: Deactivated successfully. Oct 2 18:42:06.509241 systemd-logind[919]: Session 3 logged out. Waiting for processes to exit. Oct 2 18:42:06.509933 systemd-logind[919]: Removed session 3. Oct 2 18:42:06.548496 sshd[989]: Accepted publickey for core from 10.0.0.1 port 52644 ssh2: RSA SHA256:NktSY6Bc6bn5o44ywwTbDBj/CEQn71u67t6NWiM/TgY Oct 2 18:42:06.549731 sshd[989]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 2 18:42:06.552139 systemd-logind[919]: New session 4 of user core. Oct 2 18:42:06.552885 systemd[1]: Started Session 4 of User core. Oct 2 18:42:06.612343 sshd[989]: pam_unix(sshd:session): session closed for user core Oct 2 18:42:06.615477 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:52658). Oct 2 18:42:06.616011 systemd[1]: sshd@3-10.0.0.2:22-10.0.0.1:52644.service: Deactivated successfully. Oct 2 18:42:06.616599 systemd[1]: session-4.scope: Deactivated successfully. Oct 2 18:42:06.624091 systemd-logind[919]: Session 4 logged out. Waiting for processes to exit. Oct 2 18:42:06.624901 systemd-logind[919]: Removed session 4. Oct 2 18:42:06.656886 sshd[995]: Accepted publickey for core from 10.0.0.1 port 52658 ssh2: RSA SHA256:NktSY6Bc6bn5o44ywwTbDBj/CEQn71u67t6NWiM/TgY Oct 2 18:42:06.658040 sshd[995]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 2 18:42:06.661050 systemd-logind[919]: New session 5 of user core. Oct 2 18:42:06.661867 systemd[1]: Started Session 5 of User core. Oct 2 18:42:06.908164 sudo[999]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/setenforce 1 Oct 2 18:42:06.908379 sudo[999]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 2 18:42:06.925817 sudo[999]: pam_unix(sudo:session): session closed for user root Oct 2 18:42:06.929954 dbus-daemon[908]: [system] Reloaded configuration Oct 2 18:42:06.931409 sshd[995]: pam_unix(sshd:session): session closed for user core Oct 2 18:42:06.935797 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:52666). Oct 2 18:42:06.937953 systemd[1]: sshd@4-10.0.0.2:22-10.0.0.1:52658.service: Deactivated successfully. Oct 2 18:42:06.938690 systemd[1]: session-5.scope: Deactivated successfully. Oct 2 18:42:06.939361 systemd-logind[919]: Session 5 logged out. Waiting for processes to exit. Oct 2 18:42:06.940049 systemd-logind[919]: Removed session 5. Oct 2 18:42:06.979889 sshd[1002]: Accepted publickey for core from 10.0.0.1 port 52666 ssh2: RSA SHA256:NktSY6Bc6bn5o44ywwTbDBj/CEQn71u67t6NWiM/TgY Oct 2 18:42:06.981698 sshd[1002]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 2 18:42:06.985396 systemd-logind[919]: New session 6 of user core. Oct 2 18:42:06.985878 systemd[1]: Started Session 6 of User core. Oct 2 18:42:07.037441 sudo[1007]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Oct 2 18:42:07.037676 sudo[1007]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 2 18:42:07.041102 sudo[1007]: pam_unix(sudo:session): session closed for user root Oct 2 18:42:07.044742 sudo[1006]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl restart audit-rules Oct 2 18:42:07.044943 sudo[1006]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 2 18:42:07.052172 systemd[1]: Stopping Load Security Auditing Rules... Oct 2 18:42:07.054098 auditctl[1010]: No rules Oct 2 18:42:07.052000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Oct 2 18:42:07.056041 kernel: kauditd_printk_skb: 11 callbacks suppressed Oct 2 18:42:07.056153 kernel: audit: type=1305 audit(1696272127.052:55): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Oct 2 18:42:07.056248 systemd[1]: audit-rules.service: Deactivated successfully. Oct 2 18:42:07.056495 systemd[1]: Stopped Load Security Auditing Rules. Oct 2 18:42:07.057946 systemd[1]: Starting Load Security Auditing Rules... Oct 2 18:42:07.074255 augenrules[1027]: No rules Oct 2 18:42:07.075158 systemd[1]: Finished Load Security Auditing Rules. Oct 2 18:42:07.075982 sudo[1006]: pam_unix(sudo:session): session closed for user root Oct 2 18:42:07.077190 sshd[1002]: pam_unix(sshd:session): session closed for user core Oct 2 18:42:07.081440 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:52674). Oct 2 18:42:07.083354 systemd[1]: sshd@5-10.0.0.2:22-10.0.0.1:52666.service: Deactivated successfully. Oct 2 18:42:07.083952 systemd[1]: session-6.scope: Deactivated successfully. Oct 2 18:42:07.086170 systemd-logind[919]: Session 6 logged out. Waiting for processes to exit. Oct 2 18:42:07.087688 systemd-logind[919]: Removed session 6. Oct 2 18:42:07.124801 sshd[1032]: Accepted publickey for core from 10.0.0.1 port 52674 ssh2: RSA SHA256:NktSY6Bc6bn5o44ywwTbDBj/CEQn71u67t6NWiM/TgY Oct 2 18:42:07.126181 sshd[1032]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 2 18:42:07.128887 kernel: audit: type=1006 audit(1696272127.125:56): pid=1032 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=7 res=1 Oct 2 18:42:07.133427 systemd-logind[919]: New session 7 of user core. Oct 2 18:42:07.133450 systemd[1]: Started Session 7 of User core. Oct 2 18:42:07.188109 sudo[1036]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/mkdir -p . Oct 2 18:42:07.189048 sudo[1036]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 2 18:42:07.192199 sudo[1036]: pam_unix(sudo:session): session closed for user root Oct 2 18:42:07.193388 sshd[1032]: pam_unix(sshd:session): session closed for user core Oct 2 18:42:07.197080 systemd[1]: sshd@6-10.0.0.2:22-10.0.0.1:52674.service: Deactivated successfully. Oct 2 18:42:07.197656 systemd[1]: session-7.scope: Deactivated successfully. Oct 2 18:42:07.200070 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:52688). Oct 2 18:42:07.200527 systemd-logind[919]: Session 7 logged out. Waiting for processes to exit. Oct 2 18:42:07.202061 systemd-logind[919]: Removed session 7. Oct 2 18:42:07.248947 sshd[1040]: Accepted publickey for core from 10.0.0.1 port 52688 ssh2: RSA SHA256:NktSY6Bc6bn5o44ywwTbDBj/CEQn71u67t6NWiM/TgY Oct 2 18:42:07.250079 sshd[1040]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 2 18:42:07.252893 kernel: audit: type=1006 audit(1696272127.249:57): pid=1040 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=8 res=1 Oct 2 18:42:07.254439 systemd-logind[919]: New session 8 of user core. Oct 2 18:42:07.254943 systemd[1]: Started Session 8 of User core. Oct 2 18:42:07.320731 sudo[1043]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/install -m 0755 /dev/stdin kolet Oct 2 18:42:07.320960 sudo[1043]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 2 18:42:07.650230 sudo[1043]: pam_unix(sudo:session): session closed for user root Oct 2 18:42:07.656129 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:52694). Oct 2 18:42:07.659380 sshd[1040]: pam_unix(sshd:session): session closed for user core Oct 2 18:42:07.665780 systemd[1]: sshd@7-10.0.0.2:22-10.0.0.1:52688.service: Deactivated successfully. Oct 2 18:42:07.666371 systemd[1]: session-8.scope: Deactivated successfully. Oct 2 18:42:07.667254 systemd-logind[919]: Session 8 logged out. Waiting for processes to exit. Oct 2 18:42:07.667924 systemd-logind[919]: Removed session 8. Oct 2 18:42:07.702072 sshd[1046]: Accepted publickey for core from 10.0.0.1 port 52694 ssh2: RSA SHA256:NktSY6Bc6bn5o44ywwTbDBj/CEQn71u67t6NWiM/TgY Oct 2 18:42:07.703408 sshd[1046]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 2 18:42:07.707336 kernel: audit: type=1006 audit(1696272127.702:58): pid=1046 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=9 res=1 Oct 2 18:42:07.709436 systemd-logind[919]: New session 9 of user core. Oct 2 18:42:07.709969 systemd[1]: Started Session 9 of User core. Oct 2 18:42:07.761543 sudo[1050]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/mkdir -p /updates Oct 2 18:42:07.761764 sudo[1050]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 2 18:42:07.764920 sudo[1050]: pam_unix(sudo:session): session closed for user root Oct 2 18:42:07.766267 sshd[1046]: pam_unix(sshd:session): session closed for user core Oct 2 18:42:07.770488 systemd[1]: sshd@8-10.0.0.2:22-10.0.0.1:52694.service: Deactivated successfully. Oct 2 18:42:07.771067 systemd[1]: session-9.scope: Deactivated successfully. Oct 2 18:42:07.771796 systemd-logind[919]: Session 9 logged out. Waiting for processes to exit. Oct 2 18:42:07.772794 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:52708). Oct 2 18:42:07.773488 systemd-logind[919]: Removed session 9. Oct 2 18:42:07.825074 sshd[1054]: Accepted publickey for core from 10.0.0.1 port 52708 ssh2: RSA SHA256:NktSY6Bc6bn5o44ywwTbDBj/CEQn71u67t6NWiM/TgY Oct 2 18:42:07.826679 sshd[1054]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 2 18:42:07.830107 kernel: audit: type=1006 audit(1696272127.825:59): pid=1054 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=10 res=1 Oct 2 18:42:07.834371 systemd-logind[919]: New session 10 of user core. Oct 2 18:42:07.834916 systemd[1]: Started Session 10 of User core. Oct 2 18:42:07.891420 sudo[1057]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/install -m 0755 /dev/stdin /updates/update.gz Oct 2 18:42:07.891672 sudo[1057]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 2 18:42:09.352069 sudo[1057]: pam_unix(sudo:session): session closed for user root Oct 2 18:42:09.354429 sshd[1054]: pam_unix(sshd:session): session closed for user core Oct 2 18:42:09.358046 systemd[1]: sshd@9-10.0.0.2:22-10.0.0.1:52708.service: Deactivated successfully. Oct 2 18:42:09.358606 systemd[1]: session-10.scope: Deactivated successfully. Oct 2 18:42:09.362663 systemd-logind[919]: Session 10 logged out. Waiting for processes to exit. Oct 2 18:42:09.363419 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:52722). Oct 2 18:42:09.365699 systemd-logind[919]: Removed session 10. Oct 2 18:42:09.412365 sshd[1061]: Accepted publickey for core from 10.0.0.1 port 52722 ssh2: RSA SHA256:NktSY6Bc6bn5o44ywwTbDBj/CEQn71u67t6NWiM/TgY Oct 2 18:42:09.414880 sshd[1061]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 2 18:42:09.417573 kernel: audit: type=1006 audit(1696272129.414:60): pid=1061 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=11 res=1 Oct 2 18:42:09.420346 systemd[1]: Started Session 11 of User core. Oct 2 18:42:09.420514 systemd-logind[919]: New session 11 of user core. Oct 2 18:42:09.476080 sudo[1064]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemd-run --quiet ./kolet run cl.update.payload Omaha Oct 2 18:42:09.476284 sudo[1064]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 2 18:42:09.490681 systemd[1]: Started /home/core/./kolet run cl.update.payload Omaha. Oct 2 18:42:09.491812 sudo[1064]: pam_unix(sudo:session): session closed for user root Oct 2 18:42:09.493041 sshd[1061]: pam_unix(sshd:session): session closed for user core Oct 2 18:42:09.495386 systemd[1]: sshd@10-10.0.0.2:22-10.0.0.1:52722.service: Deactivated successfully. Oct 2 18:42:09.496087 systemd[1]: session-11.scope: Deactivated successfully. Oct 2 18:42:09.496721 systemd-logind[919]: Session 11 logged out. Waiting for processes to exit. Oct 2 18:42:09.497431 systemd-logind[919]: Removed session 11. Oct 2 18:42:14.474546 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:58340). Oct 2 18:42:14.517023 sshd[1075]: Accepted publickey for core from 10.0.0.1 port 58340 ssh2: RSA SHA256:NktSY6Bc6bn5o44ywwTbDBj/CEQn71u67t6NWiM/TgY Oct 2 18:42:14.518486 sshd[1075]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 2 18:42:14.521620 kernel: audit: type=1006 audit(1696272134.517:61): pid=1075 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=12 res=1 Oct 2 18:42:14.524734 systemd-logind[919]: New session 12 of user core. Oct 2 18:42:14.525180 systemd[1]: Started Session 12 of User core. Oct 2 18:42:14.591951 sshd[1075]: pam_unix(sshd:session): session closed for user core Oct 2 18:42:14.594976 systemd[1]: sshd@11-10.0.0.2:22-10.0.0.1:58340.service: Deactivated successfully. Oct 2 18:42:14.595634 systemd[1]: session-12.scope: Deactivated successfully. Oct 2 18:42:14.596256 systemd-logind[919]: Session 12 logged out. Waiting for processes to exit. Oct 2 18:42:14.597334 systemd-logind[919]: Removed session 12. Oct 2 18:42:41.050205 update_engine[925]: I1002 18:42:41.050143 925 update_attempter.cc:505] Updating boot flags...