Nov 3 20:17:38.679019 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Nov 3 20:17:38.679035 kernel: Linux version 5.10.107-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 Tue Mar 22 19:39:09 -00 2022 Nov 3 20:17:38.679044 kernel: efi: EFI v2.70 by EDK II Nov 3 20:17:38.679049 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb82c018 ACPI 2.0=0xdbfd0018 RNG=0xdcfdd318 MEMRESERVE=0xdb834c18 Nov 3 20:17:38.679054 kernel: efi: seeding entropy pool Nov 3 20:17:38.679059 kernel: efi: memattr: Unexpected EFI Memory Attributes table version 2 Nov 3 20:17:38.679065 kernel: ACPI: Early table checksum verification disabled Nov 3 20:17:38.679070 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Nov 3 20:17:38.679075 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Nov 3 20:17:38.679080 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Nov 3 20:17:38.679086 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 3 20:17:38.679091 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Nov 3 20:17:38.679096 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 3 20:17:38.679101 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 3 20:17:38.679107 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 3 20:17:38.679112 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 3 20:17:38.679119 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Nov 3 20:17:38.679125 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Nov 3 20:17:38.679130 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Nov 3 20:17:38.679135 kernel: ACPI: NUMA: Failed to initialise from firmware Nov 3 20:17:38.679141 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Nov 3 20:17:38.679146 kernel: NUMA: NODE_DATA [mem 0xdc95a900-0xdc95ffff] Nov 3 20:17:38.679151 kernel: Zone ranges: Nov 3 20:17:38.679157 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Nov 3 20:17:38.679162 kernel: DMA32 empty Nov 3 20:17:38.679167 kernel: Normal empty Nov 3 20:17:38.679172 kernel: Movable zone start for each node Nov 3 20:17:38.679178 kernel: Early memory node ranges Nov 3 20:17:38.679183 kernel: node 0: [mem 0x0000000040000000-0x00000000dc09ffff] Nov 3 20:17:38.679189 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Nov 3 20:17:38.679194 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Nov 3 20:17:38.679199 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Nov 3 20:17:38.679204 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Nov 3 20:17:38.679209 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Nov 3 20:17:38.679215 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Nov 3 20:17:38.679220 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Nov 3 20:17:38.679225 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Nov 3 20:17:38.679230 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Nov 3 20:17:38.679235 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Nov 3 20:17:38.679241 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Nov 3 20:17:38.679249 kernel: On node 0 totalpages: 643072 Nov 3 20:17:38.679255 kernel: DMA zone: 10048 pages used for memmap Nov 3 20:17:38.679260 kernel: DMA zone: 0 pages reserved Nov 3 20:17:38.679265 kernel: DMA zone: 643072 pages, LIFO batch:63 Nov 3 20:17:38.679271 kernel: psci: probing for conduit method from ACPI. Nov 3 20:17:38.679276 kernel: psci: PSCIv1.1 detected in firmware. Nov 3 20:17:38.679282 kernel: psci: Using standard PSCI v0.2 function IDs Nov 3 20:17:38.679292 kernel: psci: Trusted OS migration not required Nov 3 20:17:38.679299 kernel: psci: SMC Calling Convention v1.1 Nov 3 20:17:38.679305 kernel: ACPI: SRAT not present Nov 3 20:17:38.679310 kernel: percpu: Embedded 32 pages/cpu s93720 r8192 d29160 u131072 Nov 3 20:17:38.679316 kernel: pcpu-alloc: s93720 r8192 d29160 u131072 alloc=32*4096 Nov 3 20:17:38.679321 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Nov 3 20:17:38.679327 kernel: Detected PIPT I-cache on CPU0 Nov 3 20:17:38.679332 kernel: CPU features: detected: GIC system register CPU interface Nov 3 20:17:38.679338 kernel: CPU features: kernel page table isolation forced ON by KASLR Nov 3 20:17:38.679343 kernel: CPU features: detected: Kernel page table isolation (KPTI) Nov 3 20:17:38.679349 kernel: CPU features: detected: Hardware dirty bit management Nov 3 20:17:38.679354 kernel: CPU features: detected: Spectre-v4 Nov 3 20:17:38.679361 kernel: CPU features: detected: ARM erratum 1418040 Nov 3 20:17:38.679366 kernel: CPU features: detected: Spectre-BHB Nov 3 20:17:38.679372 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Nov 3 20:17:38.679377 kernel: Policy zone: DMA Nov 3 20:17:38.679384 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=add5f6239860d533a5bdd88b1ecacfa095fc20febf51ab00ef758dd688c5dc20 Nov 3 20:17:38.679390 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Nov 3 20:17:38.679395 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Nov 3 20:17:38.679401 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 3 20:17:38.679407 kernel: Memory: 2452100K/2572288K available (9344K kernel code, 2274K rwdata, 6844K rodata, 34240K init, 769K bss, 120188K reserved, 0K cma-reserved) Nov 3 20:17:38.679412 kernel: random: get_random_u64 called from __kmem_cache_create+0x28/0x4a0 with crng_init=0 Nov 3 20:17:38.679418 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Nov 3 20:17:38.679424 kernel: rcu: Preemptible hierarchical RCU implementation. Nov 3 20:17:38.679430 kernel: rcu: RCU event tracing is enabled. Nov 3 20:17:38.679436 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Nov 3 20:17:38.679442 kernel: Trampoline variant of Tasks RCU enabled. Nov 3 20:17:38.679447 kernel: Tracing variant of Tasks RCU enabled. Nov 3 20:17:38.679453 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Nov 3 20:17:38.679458 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Nov 3 20:17:38.679464 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Nov 3 20:17:38.679469 kernel: GICv3: 256 SPIs implemented Nov 3 20:17:38.679475 kernel: GICv3: 0 Extended SPIs implemented Nov 3 20:17:38.679480 kernel: GICv3: Distributor has no Range Selector support Nov 3 20:17:38.679486 kernel: GICv3: 16 PPIs implemented Nov 3 20:17:38.679492 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Nov 3 20:17:38.679498 kernel: ACPI: SRAT not present Nov 3 20:17:38.679503 kernel: ITS [mem 0x08080000-0x0809ffff] Nov 3 20:17:38.679508 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Nov 3 20:17:38.679514 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Nov 3 20:17:38.679520 kernel: GICv3: using LPI property table @0x00000000400d0000 Nov 3 20:17:38.679525 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Nov 3 20:17:38.679531 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 3 20:17:38.679536 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Nov 3 20:17:38.679542 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Nov 3 20:17:38.679548 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Nov 3 20:17:38.679554 kernel: arm-pv: using stolen time PV Nov 3 20:17:38.679560 kernel: Console: colour dummy device 80x25 Nov 3 20:17:38.679566 kernel: ACPI: Core revision 20200925 Nov 3 20:17:38.679571 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Nov 3 20:17:38.679577 kernel: pid_max: default: 32768 minimum: 301 Nov 3 20:17:38.679583 kernel: LSM: Security Framework initializing Nov 3 20:17:38.679589 kernel: SELinux: Initializing. Nov 3 20:17:38.679594 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 3 20:17:38.679600 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 3 20:17:38.679606 kernel: rcu: Hierarchical SRCU implementation. Nov 3 20:17:38.679612 kernel: Platform MSI: ITS@0x8080000 domain created Nov 3 20:17:38.679618 kernel: PCI/MSI: ITS@0x8080000 domain created Nov 3 20:17:38.679624 kernel: Remapping and enabling EFI services. Nov 3 20:17:38.679629 kernel: smp: Bringing up secondary CPUs ... Nov 3 20:17:38.679635 kernel: Detected PIPT I-cache on CPU1 Nov 3 20:17:38.679641 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Nov 3 20:17:38.679647 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Nov 3 20:17:38.679652 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 3 20:17:38.679658 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Nov 3 20:17:38.679664 kernel: Detected PIPT I-cache on CPU2 Nov 3 20:17:38.679670 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Nov 3 20:17:38.679676 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Nov 3 20:17:38.679682 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 3 20:17:38.679688 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Nov 3 20:17:38.679694 kernel: Detected PIPT I-cache on CPU3 Nov 3 20:17:38.679704 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Nov 3 20:17:38.679710 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Nov 3 20:17:38.679717 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 3 20:17:38.679723 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Nov 3 20:17:38.679729 kernel: smp: Brought up 1 node, 4 CPUs Nov 3 20:17:38.679735 kernel: SMP: Total of 4 processors activated. Nov 3 20:17:38.679740 kernel: CPU features: detected: Privileged Access Never Nov 3 20:17:38.679746 kernel: CPU features: detected: LSE atomic instructions Nov 3 20:17:38.679752 kernel: CPU features: detected: User Access Override Nov 3 20:17:38.679758 kernel: CPU features: detected: 32-bit EL0 Support Nov 3 20:17:38.679764 kernel: CPU features: detected: Common not Private translations Nov 3 20:17:38.679770 kernel: CPU features: detected: RAS Extension Support Nov 3 20:17:38.679777 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Nov 3 20:17:38.679784 kernel: CPU features: detected: CRC32 instructions Nov 3 20:17:38.679790 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Nov 3 20:17:38.679796 kernel: CPU: All CPU(s) started at EL1 Nov 3 20:17:38.679802 kernel: alternatives: patching kernel code Nov 3 20:17:38.679809 kernel: devtmpfs: initialized Nov 3 20:17:38.679815 kernel: KASLR enabled Nov 3 20:17:38.679821 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Nov 3 20:17:38.679827 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Nov 3 20:17:38.679833 kernel: pinctrl core: initialized pinctrl subsystem Nov 3 20:17:38.679839 kernel: SMBIOS 3.0.0 present. Nov 3 20:17:38.679845 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Nov 3 20:17:38.679851 kernel: NET: Registered protocol family 16 Nov 3 20:17:38.679857 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Nov 3 20:17:38.679863 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Nov 3 20:17:38.679870 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Nov 3 20:17:38.679876 kernel: audit: initializing netlink subsys (disabled) Nov 3 20:17:38.679882 kernel: audit: type=2000 audit(0.030:1): state=initialized audit_enabled=0 res=1 Nov 3 20:17:38.679888 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 3 20:17:38.679894 kernel: cpuidle: using governor menu Nov 3 20:17:38.679900 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Nov 3 20:17:38.679906 kernel: ASID allocator initialised with 32768 entries Nov 3 20:17:38.679912 kernel: ACPI: bus type PCI registered Nov 3 20:17:38.679918 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 3 20:17:38.679925 kernel: Serial: AMBA PL011 UART driver Nov 3 20:17:38.679931 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Nov 3 20:17:38.679943 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Nov 3 20:17:38.679949 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Nov 3 20:17:38.679955 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Nov 3 20:17:38.679961 kernel: cryptd: max_cpu_qlen set to 1000 Nov 3 20:17:38.679967 kernel: ACPI: Added _OSI(Module Device) Nov 3 20:17:38.679973 kernel: ACPI: Added _OSI(Processor Device) Nov 3 20:17:38.679979 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Nov 3 20:17:38.679986 kernel: ACPI: Added _OSI(Processor Aggregator Device) Nov 3 20:17:38.679992 kernel: ACPI: Added _OSI(Linux-Dell-Video) Nov 3 20:17:38.679998 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Nov 3 20:17:38.680004 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Nov 3 20:17:38.680010 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Nov 3 20:17:38.680016 kernel: ACPI: Interpreter enabled Nov 3 20:17:38.680022 kernel: ACPI: Using GIC for interrupt routing Nov 3 20:17:38.680028 kernel: ACPI: MCFG table detected, 1 entries Nov 3 20:17:38.680034 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Nov 3 20:17:38.680040 kernel: printk: console [ttyAMA0] enabled Nov 3 20:17:38.680047 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Nov 3 20:17:38.680143 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Nov 3 20:17:38.680198 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Nov 3 20:17:38.680249 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Nov 3 20:17:38.680306 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Nov 3 20:17:38.680359 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Nov 3 20:17:38.680367 kernel: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Nov 3 20:17:38.680376 kernel: PCI host bridge to bus 0000:00 Nov 3 20:17:38.680434 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Nov 3 20:17:38.680484 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Nov 3 20:17:38.680533 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Nov 3 20:17:38.680582 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Nov 3 20:17:38.680647 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Nov 3 20:17:38.680714 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Nov 3 20:17:38.680774 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Nov 3 20:17:38.680830 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Nov 3 20:17:38.680885 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Nov 3 20:17:38.680954 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Nov 3 20:17:38.681016 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Nov 3 20:17:38.681072 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Nov 3 20:17:38.681122 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Nov 3 20:17:38.681171 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Nov 3 20:17:38.681219 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Nov 3 20:17:38.681227 kernel: ACPI: PCI Interrupt Link [GSI0] (IRQs *35) Nov 3 20:17:38.681233 kernel: ACPI: PCI Interrupt Link [GSI1] (IRQs *36) Nov 3 20:17:38.681239 kernel: ACPI: PCI Interrupt Link [GSI2] (IRQs *37) Nov 3 20:17:38.681245 kernel: ACPI: PCI Interrupt Link [GSI3] (IRQs *38) Nov 3 20:17:38.681251 kernel: vgaarb: loaded Nov 3 20:17:38.681257 kernel: Registered efivars operations Nov 3 20:17:38.681263 kernel: clocksource: Switched to clocksource arch_sys_counter Nov 3 20:17:38.681271 kernel: VFS: Disk quotas dquot_6.6.0 Nov 3 20:17:38.681277 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 3 20:17:38.681283 kernel: pnp: PnP ACPI init Nov 3 20:17:38.681355 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Nov 3 20:17:38.681405 kernel: system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active) Nov 3 20:17:38.681413 kernel: pnp: PnP ACPI: found 1 devices Nov 3 20:17:38.681419 kernel: NET: Registered protocol family 2 Nov 3 20:17:38.681426 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Nov 3 20:17:38.681434 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Nov 3 20:17:38.681440 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Nov 3 20:17:38.681446 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Nov 3 20:17:38.681452 kernel: TCP: Hash tables configured (established 32768 bind 32768) Nov 3 20:17:38.681459 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 3 20:17:38.681465 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 3 20:17:38.681471 kernel: NET: Registered protocol family 1 Nov 3 20:17:38.681477 kernel: PCI: CLS 0 bytes, default 64 Nov 3 20:17:38.681483 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Nov 3 20:17:38.681490 kernel: kvm [1]: HYP mode not available Nov 3 20:17:38.681496 kernel: Initialise system trusted keyrings Nov 3 20:17:38.681502 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Nov 3 20:17:38.681508 kernel: Key type asymmetric registered Nov 3 20:17:38.681514 kernel: Asymmetric key parser 'x509' registered Nov 3 20:17:38.681520 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Nov 3 20:17:38.681526 kernel: io scheduler mq-deadline registered Nov 3 20:17:38.681532 kernel: io scheduler kyber registered Nov 3 20:17:38.681538 kernel: io scheduler bfq registered Nov 3 20:17:38.681545 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Nov 3 20:17:38.681551 kernel: ACPI: Power Button [PWRB] Nov 3 20:17:38.681557 kernel: PCI Interrupt Link [GSI1] enabled at IRQ 36 Nov 3 20:17:38.681611 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Nov 3 20:17:38.681619 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Nov 3 20:17:38.681625 kernel: msm_serial: driver initialized Nov 3 20:17:38.681631 kernel: random: fast init done Nov 3 20:17:38.681637 kernel: wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information. Nov 3 20:17:38.681643 kernel: random: crng init done Nov 3 20:17:38.681649 kernel: wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld . All Rights Reserved. Nov 3 20:17:38.681656 kernel: thunder_xcv, ver 1.0 Nov 3 20:17:38.681662 kernel: thunder_bgx, ver 1.0 Nov 3 20:17:38.681668 kernel: nicpf, ver 1.0 Nov 3 20:17:38.681674 kernel: nicvf, ver 1.0 Nov 3 20:17:38.681733 kernel: rtc-efi rtc-efi.0: registered as rtc0 Nov 3 20:17:38.681782 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-11-03T20:17:38 UTC (1762201058) Nov 3 20:17:38.681790 kernel: hid: raw HID events driver (C) Jiri Kosina Nov 3 20:17:38.681796 kernel: NET: Registered protocol family 10 Nov 3 20:17:38.681804 kernel: Segment Routing with IPv6 Nov 3 20:17:38.681810 kernel: NET: Registered protocol family 17 Nov 3 20:17:38.681816 kernel: Key type dns_resolver registered Nov 3 20:17:38.681822 kernel: registered taskstats version 1 Nov 3 20:17:38.681828 kernel: Loading compiled-in X.509 certificates Nov 3 20:17:38.681834 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.10.107-flatcar: acb58ed5bddf18757940d1f794ad1dcb34e85378' Nov 3 20:17:38.681841 kernel: Key type ._fscrypt registered Nov 3 20:17:38.681846 kernel: Key type .fscrypt registered Nov 3 20:17:38.681852 kernel: Key type fscrypt-provisioning registered Nov 3 20:17:38.681859 kernel: ima: No TPM chip found, activating TPM-bypass! Nov 3 20:17:38.681865 kernel: ima: Allocated hash algorithm: sha1 Nov 3 20:17:38.681871 kernel: ima: No architecture policies found Nov 3 20:17:38.681877 kernel: Freeing unused kernel memory: 34240K Nov 3 20:17:38.681883 kernel: Run /init as init process Nov 3 20:17:38.681889 kernel: with arguments: Nov 3 20:17:38.681895 kernel: /init Nov 3 20:17:38.681901 kernel: with environment: Nov 3 20:17:38.681907 kernel: HOME=/ Nov 3 20:17:38.681912 kernel: TERM=linux Nov 3 20:17:38.681919 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Nov 3 20:17:38.681927 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) Nov 3 20:17:38.681943 systemd[1]: Detected virtualization kvm. Nov 3 20:17:38.681950 systemd[1]: Detected architecture arm64. Nov 3 20:17:38.681956 systemd[1]: Running in initial RAM disk. Nov 3 20:17:38.681962 systemd[1]: No hostname configured, using default hostname. Nov 3 20:17:38.681969 systemd[1]: Hostname set to . Nov 3 20:17:38.681977 systemd[1]: Initializing machine ID from VM UUID. Nov 3 20:17:38.681984 systemd[1]: Queued start job for default target Initrd Default Target. Nov 3 20:17:38.682000 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Nov 3 20:17:38.682006 systemd[1]: Reached target Local Encrypted Volumes. Nov 3 20:17:38.682013 systemd[1]: Reached target Path Units. Nov 3 20:17:38.682019 systemd[1]: Reached target Slice Units. Nov 3 20:17:38.682025 systemd[1]: Reached target Swaps. Nov 3 20:17:38.682032 systemd[1]: Reached target Timer Units. Nov 3 20:17:38.682040 systemd[1]: Listening on Open-iSCSI iscsid Socket. Nov 3 20:17:38.682047 systemd[1]: Listening on Open-iSCSI iscsiuio Socket. Nov 3 20:17:38.682053 systemd[1]: Listening on Journal Audit Socket. Nov 3 20:17:38.682060 systemd[1]: Listening on Journal Socket (/dev/log). Nov 3 20:17:38.682066 systemd[1]: Listening on Journal Socket. Nov 3 20:17:38.682072 systemd[1]: Listening on Network Service Netlink Socket. Nov 3 20:17:38.682079 systemd[1]: Listening on udev Control Socket. Nov 3 20:17:38.682085 systemd[1]: Listening on udev Kernel Socket. Nov 3 20:17:38.682091 systemd[1]: Reached target Socket Units. Nov 3 20:17:38.682099 systemd[1]: Starting Create List of Static Device Nodes... Nov 3 20:17:38.682105 systemd[1]: Finished Network Cleanup. Nov 3 20:17:38.682112 systemd[1]: Started Hardware RNG Entropy Gatherer Daemon. Nov 3 20:17:38.682118 systemd[1]: Starting Journal Service... Nov 3 20:17:38.682125 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Nov 3 20:17:38.682131 systemd[1]: Starting Apply Kernel Variables... Nov 3 20:17:38.682138 systemd[1]: Starting Setup Virtual Console... Nov 3 20:17:38.682144 systemd[1]: Finished Create List of Static Device Nodes. Nov 3 20:17:38.682151 systemd[1]: Finished Apply Kernel Variables. Nov 3 20:17:38.682158 systemd[1]: Finished Setup Virtual Console. Nov 3 20:17:38.682165 systemd[1]: Starting dracut ask for additional cmdline parameters... Nov 3 20:17:38.682174 systemd-journald[279]: Journal started Nov 3 20:17:38.682208 systemd-journald[279]: Runtime Journal (/run/log/journal/6083db5279854a16a861cc12fe8ac4d7) is 6.0M, max 48.5M, 42.4M free. Nov 3 20:17:38.684973 systemd[1]: Starting Create Static Device Nodes in /dev... Nov 3 20:17:38.686270 systemd[1]: Started Journal Service. Nov 3 20:17:38.686626 systemd[1]: Finished Create Static Device Nodes in /dev. Nov 3 20:17:38.697504 systemd[1]: Finished dracut ask for additional cmdline parameters. Nov 3 20:17:38.699170 systemd[1]: Starting dracut cmdline hook... Nov 3 20:17:38.707585 dracut-cmdline[296]: dracut-dracut-053 Nov 3 20:17:38.709775 dracut-cmdline[296]: 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=add5f6239860d533a5bdd88b1ecacfa095fc20febf51ab00ef758dd688c5dc20 Nov 3 20:17:38.766963 kernel: SCSI subsystem initialized Nov 3 20:17:38.770949 kernel: Loading iSCSI transport class v2.0-870. Nov 3 20:17:38.776970 kernel: iscsi: registered transport (tcp) Nov 3 20:17:38.787963 kernel: iscsi: registered transport (qla4xxx) Nov 3 20:17:38.787978 kernel: QLogic iSCSI HBA Driver Nov 3 20:17:38.797830 systemd[1]: Finished dracut cmdline hook. Nov 3 20:17:38.799366 systemd[1]: Starting dracut pre-udev hook... Nov 3 20:17:38.812697 kernel: device-mapper: uevent: version 1.0.3 Nov 3 20:17:38.812724 kernel: device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com Nov 3 20:17:38.847971 kernel: raid6: neonx8 gen() 13770 MB/s Nov 3 20:17:38.864965 kernel: raid6: neonx8 xor() 10730 MB/s Nov 3 20:17:38.881969 kernel: raid6: neonx4 gen() 13574 MB/s Nov 3 20:17:38.898958 kernel: raid6: neonx4 xor() 11548 MB/s Nov 3 20:17:38.915968 kernel: raid6: neonx2 gen() 12924 MB/s Nov 3 20:17:38.932967 kernel: raid6: neonx2 xor() 9875 MB/s Nov 3 20:17:38.949969 kernel: raid6: neonx1 gen() 10951 MB/s Nov 3 20:17:38.966968 kernel: raid6: neonx1 xor() 8814 MB/s Nov 3 20:17:38.983966 kernel: raid6: int64x8 gen() 6817 MB/s Nov 3 20:17:39.000968 kernel: raid6: int64x8 xor() 3791 MB/s Nov 3 20:17:39.017966 kernel: raid6: int64x4 gen() 6883 MB/s Nov 3 20:17:39.034967 kernel: raid6: int64x4 xor() 3831 MB/s Nov 3 20:17:39.051968 kernel: raid6: int64x2 gen() 6175 MB/s Nov 3 20:17:39.068967 kernel: raid6: int64x2 xor() 3308 MB/s Nov 3 20:17:39.085967 kernel: raid6: int64x1 gen() 5006 MB/s Nov 3 20:17:39.103139 kernel: raid6: int64x1 xor() 2563 MB/s Nov 3 20:17:39.103161 kernel: raid6: using algorithm neonx8 gen() 13770 MB/s Nov 3 20:17:39.103176 kernel: raid6: .... xor() 10730 MB/s, rmw enabled Nov 3 20:17:39.104298 kernel: raid6: using neon recovery algorithm Nov 3 20:17:39.113962 kernel: xor: measuring software checksum speed Nov 3 20:17:39.114961 kernel: 8regs : 16694 MB/sec Nov 3 20:17:39.115959 kernel: 32regs : 20755 MB/sec Nov 3 20:17:39.117395 kernel: arm64_neon : 29173 MB/sec Nov 3 20:17:39.117407 kernel: xor: using function: arm64_neon (29173 MB/sec) Nov 3 20:17:39.161959 kernel: Btrfs loaded, crc32c=crc32c-generic Nov 3 20:17:39.172194 systemd[1]: Finished dracut pre-udev hook. Nov 3 20:17:39.172000 audit: BPF prog-id=6 op=LOAD Nov 3 20:17:39.173000 audit: BPF prog-id=7 op=LOAD Nov 3 20:17:39.175354 kernel: audit: type=1334 audit(1762201059.172:2): prog-id=6 op=LOAD Nov 3 20:17:39.175375 kernel: audit: type=1334 audit(1762201059.173:3): prog-id=7 op=LOAD Nov 3 20:17:39.175384 kernel: audit: type=1334 audit(1762201059.174:4): prog-id=8 op=LOAD Nov 3 20:17:39.174000 audit: BPF prog-id=8 op=LOAD Nov 3 20:17:39.175705 systemd[1]: Starting Rule-based Manager for Device Events and Files... Nov 3 20:17:39.187228 systemd-udevd[415]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Nov 3 20:17:39.190416 systemd[1]: Started Rule-based Manager for Device Events and Files. Nov 3 20:17:39.192131 systemd[1]: Starting dracut pre-trigger hook... Nov 3 20:17:39.192000 audit: BPF prog-id=9 op=LOAD Nov 3 20:17:39.195191 systemd[1]: Starting Network Configuration... Nov 3 20:17:39.197128 kernel: audit: type=1334 audit(1762201059.192:5): prog-id=9 op=LOAD Nov 3 20:17:39.206568 dracut-pre-trigger[420]: rd.md=0: removing MD RAID activation Nov 3 20:17:39.212739 systemd-networkd[427]: lo: Link UP Nov 3 20:17:39.212746 systemd-networkd[427]: lo: Gained carrier Nov 3 20:17:39.214000 audit: BPF prog-id=10 op=LOAD Nov 3 20:17:39.213067 systemd-networkd[427]: Enumeration completed Nov 3 20:17:39.216610 kernel: audit: type=1334 audit(1762201059.214:6): prog-id=10 op=LOAD Nov 3 20:17:39.213164 systemd[1]: Started Network Configuration. Nov 3 20:17:39.216092 systemd[1]: Starting Network Name Resolution... Nov 3 20:17:39.233754 systemd[1]: Finished dracut pre-trigger hook. Nov 3 20:17:39.235350 systemd[1]: Starting Coldplug All udev Devices... Nov 3 20:17:39.243993 systemd-resolved[442]: Positive Trust Anchors: Nov 3 20:17:39.244008 systemd-resolved[442]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 3 20:17:39.244036 systemd-resolved[442]: 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 Nov 3 20:17:39.244316 systemd-resolved[442]: Defaulting to hostname 'linux'. Nov 3 20:17:39.245651 systemd[1]: Started Network Name Resolution. Nov 3 20:17:39.245654 systemd-udevd[415]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Nov 3 20:17:39.247535 systemd[1]: Reached target Network. Nov 3 20:17:39.258763 systemd[1]: Reached target Host and Network Name Lookups. Nov 3 20:17:39.262689 systemd[1]: Starting iSCSI UserSpace I/O driver... Nov 3 20:17:39.270653 systemd[1]: Started iSCSI UserSpace I/O driver. Nov 3 20:17:39.271818 systemd[1]: Finished Coldplug All udev Devices. Nov 3 20:17:39.273726 systemd[1]: Starting Open-iSCSI... Nov 3 20:17:39.276819 iscsid[482]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Nov 3 20:17:39.276819 iscsid[482]: 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]. Nov 3 20:17:39.276819 iscsid[482]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Nov 3 20:17:39.276819 iscsid[482]: If using hardware iscsi like qla4xxx this message can be ignored. Nov 3 20:17:39.276819 iscsid[482]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Nov 3 20:17:39.289044 iscsid[482]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Nov 3 20:17:39.280216 systemd[1]: Started Open-iSCSI. Nov 3 20:17:39.284888 systemd[1]: Starting dracut initqueue hook... Nov 3 20:17:39.307953 systemd-udevd[478]: Using default interface naming scheme 'v249'. Nov 3 20:17:39.310854 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Nov 3 20:17:39.310997 kernel: vda: detected capacity change from 0 to 4756340736 Nov 3 20:17:39.312869 systemd-networkd[427]: eth0: Link UP Nov 3 20:17:39.320954 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 3 20:17:39.343495 systemd[1]: Found device /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Nov 3 20:17:39.344735 systemd[1]: Found device /dev/disk/by-partlabel/USR-A. Nov 3 20:17:39.347962 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/vda6 scanned by systemd-udevd (464) Nov 3 20:17:39.348485 systemd[1]: Found device /dev/disk/by-label/EFI-SYSTEM. Nov 3 20:17:39.352714 systemd[1]: Found device /dev/disk/by-label/ROOT. Nov 3 20:17:39.357765 systemd[1]: Finished dracut initqueue hook. Nov 3 20:17:39.360481 systemd[1]: Found device /dev/disk/by-label/OEM. Nov 3 20:17:39.361397 systemd[1]: Reached target Initrd Root Device. Nov 3 20:17:39.362749 systemd[1]: Reached target Preparation for Remote File Systems. Nov 3 20:17:39.364391 systemd[1]: Reached target Remote Encrypted Volumes. Nov 3 20:17:39.366025 systemd[1]: Reached target Remote File Systems. Nov 3 20:17:39.368193 systemd[1]: Starting Generate new UUID for disk GPT if necessary... Nov 3 20:17:39.369739 systemd[1]: Starting dracut pre-mount hook... Nov 3 20:17:39.378161 systemd[1]: Finished dracut pre-mount hook. Nov 3 20:17:39.379888 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 3 20:17:39.381955 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 3 20:17:39.384959 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 3 20:17:40.329960 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Nov 3 20:17:40.330004 systemd-networkd[427]: eth0: Gained carrier Nov 3 20:17:40.336011 systemd-networkd[427]: eth0: DHCPv4 address 10.0.0.8/16 via 10.0.0.1 Nov 3 20:17:40.386762 disk-uuid[503]: The operation has completed successfully. Nov 3 20:17:40.387894 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 3 20:17:40.411874 systemd[1]: disk-uuid.service: Deactivated successfully. Nov 3 20:17:40.412022 systemd[1]: Finished Generate new UUID for disk GPT if necessary. Nov 3 20:17:40.414650 systemd[1]: Starting Ignition (setup)... Nov 3 20:17:40.422454 kernel: BTRFS info (device vda6): disk space caching is enabled Nov 3 20:17:40.422482 kernel: BTRFS info (device vda6): has skinny extents Nov 3 20:17:40.428219 systemd[1]: mnt-oem.mount: Deactivated successfully. Nov 3 20:17:40.433299 systemd[1]: ignition-setup.service: Deactivated successfully. Nov 3 20:17:40.433423 systemd[1]: Finished Ignition (setup). Nov 3 20:17:40.435597 systemd[1]: Starting Ignition (disks)... Nov 3 20:17:40.444991 ignition[540]: Ignition v0.36.1-15-gde4e6cc9 Nov 3 20:17:40.445008 ignition[540]: Stage: disks Nov 3 20:17:40.445017 ignition[540]: reading system config file "/usr/lib/ignition/base.ign" Nov 3 20:17:40.445027 ignition[540]: no config at "/usr/lib/ignition/base.ign" Nov 3 20:17:40.445069 ignition[540]: parsed url from cmdline: "" Nov 3 20:17:40.445072 ignition[540]: no config URL provided Nov 3 20:17:40.445077 ignition[540]: reading system config file "/usr/lib/ignition/user.ign" Nov 3 20:17:40.445084 ignition[540]: no config at "/usr/lib/ignition/user.ign" Nov 3 20:17:40.445104 ignition[540]: op(1): [started] loading QEMU firmware config module Nov 3 20:17:40.445109 ignition[540]: op(1): executing: "modprobe" "qemu_fw_cfg" Nov 3 20:17:40.446077 ignition[540]: op(1): [finished] loading QEMU firmware config module Nov 3 20:17:40.459571 ignition[540]: parsing config with SHA512: 61bdf9e26e101ebf96d8587b4aae38860bc1923bb5e446e1987aafe1319d82e1d5e9edfa8fd0fc6ed1f7c00fda21bf6da0f96a4a61712236dc510fe4352da72c Nov 3 20:17:40.461464 ignition[540]: disks: disks passed Nov 3 20:17:40.461472 ignition[540]: Ignition finished successfully Nov 3 20:17:40.462738 systemd[1]: ignition-disks.service: Deactivated successfully. Nov 3 20:17:40.462858 systemd[1]: Finished Ignition (disks). Nov 3 20:17:40.464234 systemd[1]: Reached target Preparation for Local File Systems. Nov 3 20:17:40.466577 systemd[1]: Starting File System Check on /dev/disk/by-label/ROOT... Nov 3 20:17:40.469043 systemd[1]: Starting Verity Setup for /dev/mapper/usr... Nov 3 20:17:40.479903 systemd-fsck[554]: ROOT: clean, 528/553792 files, 37752/553472 blocks Nov 3 20:17:40.481302 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Nov 3 20:17:40.483031 systemd[1]: Finished File System Check on /dev/disk/by-label/ROOT. Nov 3 20:17:40.485041 systemd[1]: Mounting /sysroot... Nov 3 20:17:40.501654 systemd[1]: Found device /dev/mapper/usr. Nov 3 20:17:40.503135 systemd[1]: Starting File System Check on /dev/mapper/usr... Nov 3 20:17:40.504207 systemd[1]: Finished Verity Setup for /dev/mapper/usr. Nov 3 20:17:40.512963 systemd-fsck[569]: fsck.ext4: Operation not permitted while trying to open /dev/mapper/usr Nov 3 20:17:40.512963 systemd-fsck[569]: You must have r/w access to the filesystem or be root Nov 3 20:17:40.513404 systemd-fsck[567]: fsck failed with exit status 8. Nov 3 20:17:40.513407 systemd-fsck[567]: Ignoring error. Nov 3 20:17:40.515253 systemd[1]: Finished File System Check on /dev/mapper/usr. Nov 3 20:17:40.518407 systemd[1]: Mounting /sysusr/usr... Nov 3 20:17:40.528287 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null) Nov 3 20:17:40.528329 kernel: ext4 filesystem being mounted at /sysroot supports timestamps until 2038 (0x7fffffff) Nov 3 20:17:40.529152 systemd[1]: Mounted /sysroot. Nov 3 20:17:40.529841 systemd[1]: Reached target Initrd Root File System. Nov 3 20:17:40.547959 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: (null) Nov 3 20:17:40.548050 systemd[1]: Mounted /sysusr/usr. Nov 3 20:17:40.548776 systemd[1]: Reached target Local File Systems. Nov 3 20:17:40.550019 systemd[1]: Reached target System Initialization. Nov 3 20:17:40.551473 systemd[1]: Reached target Basic System. Nov 3 20:17:40.553787 systemd[1]: Mounting /sysroot/usr... Nov 3 20:17:40.555770 systemd[1]: Mounted /sysroot/usr. Nov 3 20:17:40.557131 systemd[1]: Starting Root filesystem setup... Nov 3 20:17:40.579517 systemd[1]: initrd-setup-root.service: Deactivated successfully. Nov 3 20:17:40.579648 systemd[1]: Finished Root filesystem setup. Nov 3 20:17:40.581911 systemd[1]: Starting Ignition (files)... Nov 3 20:17:40.583131 systemd[1]: Starting /sysroot/boot... Nov 3 20:17:40.584377 ignition[585]: Ignition v0.36.1-15-gde4e6cc9 Nov 3 20:17:40.584383 ignition[585]: Stage: files Nov 3 20:17:40.584392 ignition[585]: reading system config file "/usr/lib/ignition/base.ign" Nov 3 20:17:40.584400 ignition[585]: no config at "/usr/lib/ignition/base.ign" Nov 3 20:17:40.584870 ignition[585]: files: compiled without relabeling support, skipping Nov 3 20:17:40.598461 systemd[1]: Finished /sysroot/boot. Nov 3 20:17:40.602876 ignition[585]: files: createUsers: op(1): [started] creating or modifying user "core" Nov 3 20:17:40.602888 ignition[585]: files: createUsers: op(1): executing: "/usr/sbin/usermod" "--root" "/sysroot" "core" Nov 3 20:17:40.604634 ignition[585]: files: createUsers: op(1): [finished] creating or modifying user "core" Nov 3 20:17:40.604646 ignition[585]: files: createUsers: op(2): [started] adding ssh keys to user "core" Nov 3 20:17:40.605771 ignition[585]: files: createUsers: op(2): [finished] adding ssh keys to user "core" Nov 3 20:17:40.607091 systemd[1]: ignition-files.service: Deactivated successfully. Nov 3 20:17:40.605790 ignition[585]: files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/etc/flatcar/update.conf" Nov 3 20:17:40.607193 systemd[1]: Finished Ignition (files). Nov 3 20:17:40.606062 ignition[585]: files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/etc/flatcar/update.conf" Nov 3 20:17:40.608021 systemd[1]: Condition check resulted in Populate torcx store to satisfy profile being skipped. Nov 3 20:17:40.615000 audit: BPF prog-id=9 op=UNLOAD Nov 3 20:17:40.606069 ignition[585]: files: op(4): [started] processing unit "coreos-metadata.service" Nov 3 20:17:40.620169 kernel: audit: type=1334 audit(1762201060.615:7): prog-id=9 op=UNLOAD Nov 3 20:17:40.620190 kernel: audit: type=1334 audit(1762201060.617:8): prog-id=10 op=UNLOAD Nov 3 20:17:40.617000 audit: BPF prog-id=10 op=UNLOAD Nov 3 20:17:40.608630 systemd[1]: Starting Ignition (record completion)... Nov 3 20:17:40.606154 ignition[585]: files: op(4): op(5): [started] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Nov 3 20:17:40.610263 systemd[1]: Starting Reload Configuration from the Real Root... Nov 3 20:17:40.606539 ignition[585]: files: op(4): op(5): [finished] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Nov 3 20:17:40.611780 systemd[1]: ignition-quench.service: Deactivated successfully. Nov 3 20:17:40.631011 kernel: audit: type=1334 audit(1762201060.624:9): prog-id=3 op=UNLOAD Nov 3 20:17:40.631031 kernel: audit: type=1334 audit(1762201060.624:10): prog-id=6 op=UNLOAD Nov 3 20:17:40.624000 audit: BPF prog-id=3 op=UNLOAD Nov 3 20:17:40.624000 audit: BPF prog-id=6 op=UNLOAD Nov 3 20:17:40.606545 ignition[585]: files: op(4): [finished] processing unit "coreos-metadata.service" Nov 3 20:17:40.611887 systemd[1]: Finished Ignition (record completion). Nov 3 20:17:40.606550 ignition[585]: files: files passed Nov 3 20:17:40.615139 systemd[1]: Reloading. Nov 3 20:17:40.606553 ignition[585]: Ignition finished successfully Nov 3 20:17:40.700000 audit: BPF prog-id=11 op=LOAD Nov 3 20:17:40.701000 audit: BPF prog-id=12 op=LOAD Nov 3 20:17:40.701000 audit: BPF prog-id=13 op=LOAD Nov 3 20:17:40.702000 audit: BPF prog-id=14 op=LOAD Nov 3 20:17:40.702000 audit: BPF prog-id=15 op=LOAD Nov 3 20:17:40.702000 audit: BPF prog-id=4 op=UNLOAD Nov 3 20:17:40.702000 audit: BPF prog-id=5 op=UNLOAD Nov 3 20:17:40.702000 audit: BPF prog-id=16 op=LOAD Nov 3 20:17:40.702000 audit: BPF prog-id=17 op=LOAD Nov 3 20:17:40.702000 audit: BPF prog-id=18 op=LOAD Nov 3 20:17:40.702000 audit: BPF prog-id=7 op=UNLOAD Nov 3 20:17:40.702000 audit: BPF prog-id=8 op=UNLOAD Nov 3 20:17:40.716160 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Nov 3 20:17:40.716271 systemd[1]: Finished Reload Configuration from the Real Root. Nov 3 20:17:40.718067 systemd[1]: Reached target Initrd File Systems. Nov 3 20:17:40.719650 systemd[1]: Reached target Initrd Default Target. Nov 3 20:17:40.721121 systemd[1]: Condition check resulted in dracut mount hook being skipped. Nov 3 20:17:40.721736 systemd[1]: Starting dracut pre-pivot and cleanup hook... Nov 3 20:17:40.731546 systemd[1]: Finished dracut pre-pivot and cleanup hook. Nov 3 20:17:40.733101 systemd[1]: Starting Cleaning Up and Shutting Down Daemons... Nov 3 20:17:40.738757 systemd[1]: Stopped target Host and Network Name Lookups. Nov 3 20:17:40.739797 systemd[1]: Stopped target Remote Encrypted Volumes. Nov 3 20:17:40.741523 systemd[1]: Stopped target Timer Units. Nov 3 20:17:40.742898 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Nov 3 20:17:40.743018 systemd[1]: Stopped dracut pre-pivot and cleanup hook. Nov 3 20:17:40.744392 systemd[1]: Stopped target Initrd Default Target. Nov 3 20:17:40.745906 systemd[1]: Stopped target Basic System. Nov 3 20:17:40.747367 systemd[1]: Stopped target Initrd Root Device. Nov 3 20:17:40.748817 systemd[1]: Stopped target Path Units. Nov 3 20:17:40.750104 systemd[1]: Stopped target Remote File Systems. Nov 3 20:17:40.751505 systemd[1]: Stopped target Preparation for Remote File Systems. Nov 3 20:17:40.752976 systemd[1]: Stopped target Slice Units. Nov 3 20:17:40.754397 systemd[1]: Stopped target Socket Units. Nov 3 20:17:40.755882 systemd[1]: Stopped target System Initialization. Nov 3 20:17:40.757253 systemd[1]: Stopped target Local File Systems. Nov 3 20:17:40.758676 systemd[1]: Stopped target Preparation for Local File Systems. Nov 3 20:17:40.760252 systemd[1]: Stopped target Swaps. Nov 3 20:17:40.761491 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Nov 3 20:17:40.761587 systemd[1]: Stopped dracut pre-mount hook. Nov 3 20:17:40.763088 systemd[1]: Stopped target Local Encrypted Volumes. Nov 3 20:17:40.764349 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 3 20:17:40.767979 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Nov 3 20:17:40.769070 systemd[1]: dracut-initqueue.service: Deactivated successfully. Nov 3 20:17:40.769161 systemd[1]: Stopped dracut initqueue hook. Nov 3 20:17:40.772403 iscsid[482]: iscsid shutting down. Nov 3 20:17:40.772718 systemd[1]: Stopping Open-iSCSI... Nov 3 20:17:40.774228 systemd[1]: Stopping /sysroot/boot... Nov 3 20:17:40.774840 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Nov 3 20:17:40.774988 systemd[1]: Stopped Coldplug All udev Devices. Nov 3 20:17:40.776264 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Nov 3 20:17:40.776356 systemd[1]: Stopped dracut pre-trigger hook. Nov 3 20:17:40.778742 systemd[1]: iscsid.service: Deactivated successfully. Nov 3 20:17:40.778845 systemd[1]: Stopped Open-iSCSI. Nov 3 20:17:40.780719 systemd[1]: iscsid.socket: Deactivated successfully. Nov 3 20:17:40.780764 systemd[1]: Closed Open-iSCSI iscsid Socket. Nov 3 20:17:40.783005 systemd[1]: Stopping iSCSI UserSpace I/O driver... Nov 3 20:17:40.784197 systemd[1]: iscsiuio.service: Deactivated successfully. Nov 3 20:17:40.784315 systemd[1]: Stopped iSCSI UserSpace I/O driver. Nov 3 20:17:40.785820 systemd[1]: initrd-cleanup.service: Deactivated successfully. Nov 3 20:17:40.785925 systemd[1]: Finished Cleaning Up and Shutting Down Daemons. Nov 3 20:17:40.787318 systemd[1]: sysroot-boot.service: Deactivated successfully. Nov 3 20:17:40.787423 systemd[1]: Stopped /sysroot/boot. Nov 3 20:17:40.789094 systemd[1]: Stopped target Network. Nov 3 20:17:40.789866 systemd[1]: iscsiuio.socket: Deactivated successfully. Nov 3 20:17:40.789930 systemd[1]: Closed Open-iSCSI iscsiuio Socket. Nov 3 20:17:40.791381 systemd[1]: Stopping Network Name Resolution... Nov 3 20:17:40.796957 systemd[1]: systemd-resolved.service: Deactivated successfully. Nov 3 20:17:40.797070 systemd[1]: Stopped Network Name Resolution. Nov 3 20:17:40.798517 systemd[1]: Stopping Network Configuration... Nov 3 20:17:40.803024 systemd-networkd[427]: eth0: DHCP lease lost Nov 3 20:17:40.803000 audit: BPF prog-id=12 op=UNLOAD Nov 3 20:17:40.821544 systemd-networkd[427]: eth0: DHCPv6 lease lost Nov 3 20:17:40.823809 systemd[1]: systemd-networkd.service: Deactivated successfully. Nov 3 20:17:40.824011 systemd[1]: Stopped Network Configuration. Nov 3 20:17:40.825965 systemd[1]: systemd-networkd.socket: Deactivated successfully. Nov 3 20:17:40.826000 systemd[1]: Closed Network Service Netlink Socket. Nov 3 20:17:40.828000 audit: BPF prog-id=11 op=UNLOAD Nov 3 20:17:40.827863 systemd[1]: Stopping Network Cleanup... Nov 3 20:17:40.828776 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 3 20:17:40.828837 systemd[1]: Stopped Apply Kernel Variables. Nov 3 20:17:40.830374 systemd[1]: Stopping Rule-based Manager for Device Events and Files... Nov 3 20:17:40.836052 systemd[1]: network-cleanup.service: Deactivated successfully. Nov 3 20:17:40.836163 systemd[1]: Stopped Network Cleanup. Nov 3 20:17:40.838639 systemd[1]: systemd-udevd.service: Deactivated successfully. Nov 3 20:17:40.838752 systemd[1]: Stopped Rule-based Manager for Device Events and Files. Nov 3 20:17:40.840134 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Nov 3 20:17:40.840170 systemd[1]: Closed udev Control Socket. Nov 3 20:17:40.841598 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Nov 3 20:17:40.841638 systemd[1]: Closed udev Kernel Socket. Nov 3 20:17:40.843108 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Nov 3 20:17:40.843148 systemd[1]: Stopped dracut pre-udev hook. Nov 3 20:17:40.844676 systemd[1]: dracut-cmdline.service: Deactivated successfully. Nov 3 20:17:40.852000 audit: BPF prog-id=16 op=UNLOAD Nov 3 20:17:40.844716 systemd[1]: Stopped dracut cmdline hook. Nov 3 20:17:40.845930 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 3 20:17:40.845995 systemd[1]: Stopped dracut ask for additional cmdline parameters. Nov 3 20:17:40.848431 systemd[1]: Starting Cleanup udev Database... Nov 3 20:17:40.849179 systemd[1]: Stopping Hardware RNG Entropy Gatherer Daemon... Nov 3 20:17:40.850717 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 3 20:17:40.850771 systemd[1]: Stopped Create Static Device Nodes in /dev. Nov 3 20:17:40.852358 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Nov 3 20:17:40.852401 systemd[1]: Stopped Create List of Static Device Nodes. Nov 3 20:17:40.854018 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 3 20:17:40.854062 systemd[1]: Stopped Setup Virtual Console. Nov 3 20:17:40.855515 systemd[1]: rngd.service: Deactivated successfully. Nov 3 20:17:40.866000 audit: BPF prog-id=13 op=UNLOAD Nov 3 20:17:40.855614 systemd[1]: Stopped Hardware RNG Entropy Gatherer Daemon. Nov 3 20:17:40.857122 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Nov 3 20:17:40.857217 systemd[1]: Finished Cleanup udev Database. Nov 3 20:17:40.858743 systemd[1]: Reached target Switch Root. Nov 3 20:17:40.860616 systemd[1]: Starting Switch Root... Nov 3 20:17:40.865331 systemd[1]: Switching root. Nov 3 20:17:40.886258 systemd-journald[279]: Journal stopped Nov 3 20:17:43.010828 systemd-journald[279]: Received SIGTERM from PID 1 (systemd). Nov 3 20:17:43.010877 kernel: SELinux: policy capability network_peer_controls=1 Nov 3 20:17:43.010889 kernel: SELinux: policy capability open_perms=1 Nov 3 20:17:43.010898 kernel: SELinux: policy capability extended_socket_class=1 Nov 3 20:17:43.010911 kernel: SELinux: policy capability always_check_network=0 Nov 3 20:17:43.010920 kernel: SELinux: policy capability cgroup_seclabel=1 Nov 3 20:17:43.010929 kernel: SELinux: policy capability nnp_nosuid_transition=1 Nov 3 20:17:43.010969 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Nov 3 20:17:43.010980 systemd[1]: Successfully loaded SELinux policy in 29.884ms. Nov 3 20:17:43.010995 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 6.151ms. Nov 3 20:17:43.011005 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) Nov 3 20:17:43.011015 systemd[1]: Detected virtualization kvm. Nov 3 20:17:43.011025 systemd[1]: Detected architecture arm64. Nov 3 20:17:43.011034 systemd[1]: Detected first boot. Nov 3 20:17:43.011044 systemd[1]: Initializing machine ID from VM UUID. Nov 3 20:17:43.011053 systemd[1]: Populated /etc with preset unit settings. Nov 3 20:17:43.011066 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. Nov 3 20:17:43.011076 systemd[1]: initrd-switch-root.service: Current command vanished from the unit file, execution of the command list won't be resumed. Nov 3 20:17:43.011086 systemd[1]: initrd-switch-root.service: Deactivated successfully. Nov 3 20:17:43.011096 systemd[1]: Stopped Switch Root. Nov 3 20:17:43.011105 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Nov 3 20:17:43.011115 systemd[1]: Created slice Slice /system/addon-config. Nov 3 20:17:43.011125 systemd[1]: Created slice Slice /system/addon-run. Nov 3 20:17:43.011134 systemd[1]: Created slice Slice /system/getty. Nov 3 20:17:43.011145 systemd[1]: Created slice Slice /system/modprobe. Nov 3 20:17:43.011155 systemd[1]: Created slice Slice /system/serial-getty. Nov 3 20:17:43.011164 systemd[1]: Created slice Slice /system/system-cloudinit. Nov 3 20:17:43.011174 kernel: kauditd_printk_skb: 41 callbacks suppressed Nov 3 20:17:43.011182 kernel: audit: type=1334 audit(1762201062.910:52): prog-id=27 op=UNLOAD Nov 3 20:17:43.011192 systemd[1]: Created slice Slice /system/systemd-fsck. Nov 3 20:17:43.011204 systemd[1]: Created slice User and Session Slice. Nov 3 20:17:43.011214 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Nov 3 20:17:43.011224 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Nov 3 20:17:43.011235 systemd[1]: Set up automount Boot partition Automount Point. Nov 3 20:17:43.011244 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Nov 3 20:17:43.011254 systemd[1]: Stopped target Switch Root. Nov 3 20:17:43.011263 systemd[1]: Stopped target Initrd File Systems. Nov 3 20:17:43.011273 systemd[1]: Stopped target Initrd Root File System. Nov 3 20:17:43.011283 systemd[1]: Reached target Remote Encrypted Volumes. Nov 3 20:17:43.011300 systemd[1]: Reached target Remote File Systems. Nov 3 20:17:43.011310 systemd[1]: Reached target Slice Units. Nov 3 20:17:43.011319 systemd[1]: Reached target Swaps. Nov 3 20:17:43.011329 systemd[1]: Reached target Verify torcx succeeded. Nov 3 20:17:43.011340 systemd[1]: Reached target Local Verity Protected Volumes. Nov 3 20:17:43.011350 systemd[1]: Listening on Process Core Dump Socket. Nov 3 20:17:43.011360 systemd[1]: Listening on initctl Compatibility Named Pipe. Nov 3 20:17:43.011369 systemd[1]: Listening on Network Service Netlink Socket. Nov 3 20:17:43.011378 systemd[1]: Listening on udev Control Socket. Nov 3 20:17:43.011388 systemd[1]: Listening on udev Kernel Socket. Nov 3 20:17:43.011397 systemd[1]: Mounting Huge Pages File System... Nov 3 20:17:43.011407 systemd[1]: Mounting POSIX Message Queue File System... Nov 3 20:17:43.011416 systemd[1]: Mounting External Media Directory... Nov 3 20:17:43.011425 systemd[1]: Mounting Kernel Debug File System... Nov 3 20:17:43.011436 systemd[1]: Mounting Kernel Trace File System... Nov 3 20:17:43.011446 systemd[1]: Mounting Temporary Directory /tmp... Nov 3 20:17:43.011455 systemd[1]: Starting Create missing system files... Nov 3 20:17:43.011465 systemd[1]: Starting Create List of Static Device Nodes... Nov 3 20:17:43.011475 systemd[1]: Starting Load Kernel Module configfs... Nov 3 20:17:43.011484 systemd[1]: Starting Load Kernel Module drm... Nov 3 20:17:43.011494 systemd[1]: Starting Load Kernel Module fuse... Nov 3 20:17:43.011504 systemd[1]: Condition check resulted in Create /etc/nsswitch.conf being skipped. Nov 3 20:17:43.011513 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Nov 3 20:17:43.011524 systemd[1]: Stopped File System Check on Root Device. Nov 3 20:17:43.011533 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Nov 3 20:17:43.011542 systemd[1]: Stopped systemd-fsck-usr.service. Nov 3 20:17:43.011552 systemd[1]: Stopped Journal Service. Nov 3 20:17:43.011561 kernel: fuse: init (API version 7.32) Nov 3 20:17:43.011570 kernel: audit: type=1334 audit(1762201062.994:53): prog-id=30 op=LOAD Nov 3 20:17:43.011578 kernel: audit: type=1334 audit(1762201062.995:54): prog-id=31 op=LOAD Nov 3 20:17:43.011587 kernel: audit: type=1334 audit(1762201062.996:55): prog-id=32 op=LOAD Nov 3 20:17:43.011596 systemd[1]: Starting Journal Service... Nov 3 20:17:43.011605 kernel: audit: type=1334 audit(1762201062.996:56): prog-id=28 op=UNLOAD Nov 3 20:17:43.011615 kernel: audit: type=1334 audit(1762201062.996:57): prog-id=29 op=UNLOAD Nov 3 20:17:43.011624 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Nov 3 20:17:43.011634 systemd[1]: Starting Remount Root and Kernel File Systems... Nov 3 20:17:43.011643 systemd[1]: Starting Apply Kernel Variables... Nov 3 20:17:43.011653 systemd[1]: Starting Coldplug All udev Devices... Nov 3 20:17:43.011662 kernel: audit: type=1305 audit(1762201063.009:58): op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Nov 3 20:17:43.011673 systemd-journald[800]: Journal started Nov 3 20:17:43.011707 systemd-journald[800]: Runtime Journal (/run/log/journal/6083db5279854a16a861cc12fe8ac4d7) is 6.0M, max 48.5M, 42.4M free. Nov 3 20:17:40.945000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Nov 3 20:17:40.974000 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 Nov 3 20:17:40.974000 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 Nov 3 20:17:40.974000 audit: BPF prog-id=19 op=LOAD Nov 3 20:17:40.974000 audit: BPF prog-id=19 op=UNLOAD Nov 3 20:17:40.974000 audit: BPF prog-id=20 op=LOAD Nov 3 20:17:40.974000 audit: BPF prog-id=20 op=UNLOAD Nov 3 20:17:41.003000 audit[750]: AVC avc: denied { associate } for pid=750 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Nov 3 20:17:42.882000 audit: BPF prog-id=21 op=LOAD Nov 3 20:17:42.882000 audit: BPF prog-id=22 op=LOAD Nov 3 20:17:42.882000 audit: BPF prog-id=23 op=LOAD Nov 3 20:17:42.882000 audit: BPF prog-id=14 op=UNLOAD Nov 3 20:17:42.882000 audit: BPF prog-id=15 op=UNLOAD Nov 3 20:17:42.893000 audit: BPF prog-id=24 op=LOAD Nov 3 20:17:42.893000 audit: BPF prog-id=21 op=UNLOAD Nov 3 20:17:42.893000 audit: BPF prog-id=25 op=LOAD Nov 3 20:17:42.893000 audit: BPF prog-id=26 op=LOAD Nov 3 20:17:42.893000 audit: BPF prog-id=22 op=UNLOAD Nov 3 20:17:42.893000 audit: BPF prog-id=23 op=UNLOAD Nov 3 20:17:42.893000 audit: BPF prog-id=27 op=LOAD Nov 3 20:17:42.893000 audit: BPF prog-id=24 op=UNLOAD Nov 3 20:17:42.893000 audit: BPF prog-id=28 op=LOAD Nov 3 20:17:42.893000 audit: BPF prog-id=29 op=LOAD Nov 3 20:17:42.893000 audit: BPF prog-id=25 op=UNLOAD Nov 3 20:17:42.893000 audit: BPF prog-id=26 op=UNLOAD Nov 3 20:17:42.910000 audit: BPF prog-id=27 op=UNLOAD Nov 3 20:17:42.994000 audit: BPF prog-id=30 op=LOAD Nov 3 20:17:42.995000 audit: BPF prog-id=31 op=LOAD Nov 3 20:17:42.996000 audit: BPF prog-id=32 op=LOAD Nov 3 20:17:42.996000 audit: BPF prog-id=28 op=UNLOAD Nov 3 20:17:42.996000 audit: BPF prog-id=29 op=UNLOAD Nov 3 20:17:43.009000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Nov 3 20:17:42.882672 systemd[1]: Queued start job for default target Multi-User System. Nov 3 20:17:41.002660 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-03T20:17:41Z" 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.2.4 /usr/share/oem/torcx/store /var/lib/torcx/store/3033.2.4 /var/lib/torcx/store]" Nov 3 20:17:42.895094 systemd[1]: systemd-journald.service: Deactivated successfully. Nov 3 20:17:41.002905 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-03T20:17:41Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Nov 3 20:17:41.002923 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-03T20:17:41Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Nov 3 20:17:41.003043 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-03T20:17:41Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Nov 3 20:17:41.003054 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-03T20:17:41Z" level=debug msg="skipped missing lower profile" missing profile=oem Nov 3 20:17:41.003081 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-03T20:17:41Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Nov 3 20:17:41.003094 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-03T20:17:41Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Nov 3 20:17:43.012954 systemd[1]: verity-setup.service: Deactivated successfully. Nov 3 20:17:41.003279 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-03T20:17:41Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Nov 3 20:17:41.003326 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-03T20:17:41Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Nov 3 20:17:41.003341 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-03T20:17:41Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Nov 3 20:17:41.003667 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-03T20:17:41Z" 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 Nov 3 20:17:41.003697 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-03T20:17:41Z" 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 Nov 3 20:17:41.003717 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-03T20:17:41Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3033.2.4: no such file or directory" path=/usr/share/oem/torcx/store/3033.2.4 Nov 3 20:17:41.003732 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-03T20:17:41Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Nov 3 20:17:41.003750 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-03T20:17:41Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3033.2.4: no such file or directory" path=/var/lib/torcx/store/3033.2.4 Nov 3 20:17:41.003763 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-03T20:17:41Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Nov 3 20:17:42.710778 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-03T20:17:42Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Nov 3 20:17:42.711068 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-03T20:17:42Z" 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 Nov 3 20:17:42.711161 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-03T20:17:42Z" 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 Nov 3 20:17:42.711289 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-03T20:17:42Z" 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]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Nov 3 20:17:42.711355 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-03T20:17:42Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Nov 3 20:17:42.711417 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-11-03T20:17:42Z" 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 Nov 3 20:17:43.014223 systemd[1]: Stopped verity-setup.service. Nov 3 20:17:43.017954 systemd[1]: Started Journal Service. Nov 3 20:17:43.018345 systemd[1]: Mounted Huge Pages File System. Nov 3 20:17:43.019259 systemd[1]: Mounted POSIX Message Queue File System. Nov 3 20:17:43.020224 systemd[1]: Mounted External Media Directory. Nov 3 20:17:43.021108 systemd[1]: Mounted Kernel Debug File System. Nov 3 20:17:43.022010 systemd[1]: Mounted Kernel Trace File System. Nov 3 20:17:43.022902 systemd[1]: Mounted Temporary Directory /tmp. Nov 3 20:17:43.024093 systemd[1]: Finished Create missing system files. Nov 3 20:17:43.025272 systemd[1]: Finished Create List of Static Device Nodes. Nov 3 20:17:43.026465 systemd[1]: modprobe@configfs.service: Deactivated successfully. Nov 3 20:17:43.026649 systemd[1]: Finished Load Kernel Module configfs. Nov 3 20:17:43.027727 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 3 20:17:43.027910 systemd[1]: Finished Load Kernel Module drm. Nov 3 20:17:43.028969 systemd[1]: modprobe@fuse.service: Deactivated successfully. Nov 3 20:17:43.029151 systemd[1]: Finished Load Kernel Module fuse. Nov 3 20:17:43.030310 systemd[1]: Finished Remount Root and Kernel File Systems. Nov 3 20:17:43.031577 systemd[1]: Finished Apply Kernel Variables. Nov 3 20:17:43.041734 systemd[1]: Mounting FUSE Control File System... Nov 3 20:17:43.043509 systemd[1]: Mounting Kernel Configuration File System... Nov 3 20:17:43.044413 systemd[1]: Condition check resulted in Remount Root File System being skipped. Nov 3 20:17:43.045779 systemd[1]: Starting Rebuild Hardware Database... Nov 3 20:17:43.047617 systemd[1]: Starting Flush Journal to Persistent Storage... Nov 3 20:17:43.048674 systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. Nov 3 20:17:43.049601 systemd[1]: Starting Load/Save Random Seed... Nov 3 20:17:43.050853 systemd-journald[800]: Time spent on flushing to /var/log/journal/6083db5279854a16a861cc12fe8ac4d7 is 8.658ms for 782 entries. Nov 3 20:17:43.050853 systemd-journald[800]: System Journal (/var/log/journal/6083db5279854a16a861cc12fe8ac4d7) is 8.0M, max 203.0M, 195.0M free. Nov 3 20:17:43.051384 systemd[1]: Starting Create System Users... Nov 3 20:17:43.056092 systemd[1]: Mounted FUSE Control File System. Nov 3 20:17:43.057124 systemd[1]: Mounted Kernel Configuration File System. Nov 3 20:17:43.058357 systemd[1]: Finished Load/Save Random Seed. Nov 3 20:17:43.059356 systemd[1]: Reached target First Boot Complete. Nov 3 20:17:43.063777 systemd[1]: Finished Coldplug All udev Devices. Nov 3 20:17:43.064926 systemd-sysusers[812]: Creating group sgx with gid 999. Nov 3 20:17:43.069804 systemd[1]: Starting Wait for udev To Complete Device Initialization... Nov 3 20:17:43.071279 systemd[1]: Finished Flush Journal to Persistent Storage. Nov 3 20:17:43.073634 systemd-sysusers[812]: Creating group systemd-oom with gid 998. Nov 3 20:17:43.074149 systemd-sysusers[812]: Creating user systemd-oom (systemd Userspace OOM Killer) with uid 998 and gid 998. Nov 3 20:17:43.075061 systemd-sysusers[812]: Creating group systemd-timesync with gid 997. Nov 3 20:17:43.075604 systemd-sysusers[812]: Creating user systemd-timesync (systemd Time Synchronization) with uid 997 and gid 997. Nov 3 20:17:43.076254 systemd-sysusers[812]: Creating group systemd-coredump with gid 996. Nov 3 20:17:43.076777 systemd-sysusers[812]: Creating user systemd-coredump (systemd Core Dumper) with uid 996 and gid 996. Nov 3 20:17:43.085510 systemd[1]: Finished Create System Users. Nov 3 20:17:43.087645 systemd[1]: Starting Create Static Device Nodes in /dev... Nov 3 20:17:43.100182 systemd[1]: Finished Create Static Device Nodes in /dev. Nov 3 20:17:43.298157 systemd[1]: Finished Rebuild Hardware Database. Nov 3 20:17:43.309000 audit: BPF prog-id=33 op=LOAD Nov 3 20:17:43.310000 audit: BPF prog-id=34 op=LOAD Nov 3 20:17:43.312180 systemd[1]: Starting Rule-based Manager for Device Events and Files... Nov 3 20:17:43.312410 kernel: audit: type=1334 audit(1762201063.309:59): prog-id=33 op=LOAD Nov 3 20:17:43.312448 kernel: audit: type=1334 audit(1762201063.310:60): prog-id=34 op=LOAD Nov 3 20:17:43.312462 kernel: audit: type=1334 audit(1762201063.310:61): prog-id=35 op=LOAD Nov 3 20:17:43.310000 audit: BPF prog-id=35 op=LOAD Nov 3 20:17:43.310000 audit: BPF prog-id=17 op=UNLOAD Nov 3 20:17:43.310000 audit: BPF prog-id=18 op=UNLOAD Nov 3 20:17:43.334865 systemd[1]: Started Rule-based Manager for Device Events and Files. Nov 3 20:17:43.342000 audit: BPF prog-id=36 op=LOAD Nov 3 20:17:43.344140 systemd[1]: Starting Network Configuration... Nov 3 20:17:43.364924 systemd-udevd[836]: Using default interface naming scheme 'v249'. Nov 3 20:17:43.377623 systemd-networkd[837]: lo: Link UP Nov 3 20:17:43.377632 systemd-networkd[837]: lo: Gained carrier Nov 3 20:17:43.377959 systemd-networkd[837]: Enumeration completed Nov 3 20:17:43.378072 systemd[1]: Started Network Configuration. Nov 3 20:17:43.379585 systemd-networkd[837]: eth0: Link UP Nov 3 20:17:43.391068 systemd-networkd[837]: eth0: Gained carrier Nov 3 20:17:43.396120 systemd-networkd[837]: eth0: DHCPv4 address 10.0.0.8/16 via 10.0.0.1 Nov 3 20:17:43.396312 udevadm[815]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Nov 3 20:17:43.408134 systemd[1]: Finished Wait for udev To Complete Device Initialization. Nov 3 20:17:43.419971 systemd[1]: Starting Activation of LVM2 logical volumes... Nov 3 20:17:43.429003 lvm[845]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Nov 3 20:17:43.447914 systemd[1]: Finished Activation of LVM2 logical volumes. Nov 3 20:17:43.448990 systemd[1]: Reached target Local Encrypted Volumes. Nov 3 20:17:43.463986 systemd[1]: Starting Activation of LVM2 logical volumes... Nov 3 20:17:43.467598 lvm[846]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Nov 3 20:17:43.487965 systemd[1]: Finished Activation of LVM2 logical volumes. Nov 3 20:17:43.489011 systemd[1]: Reached target Preparation for Local File Systems. Nov 3 20:17:43.490022 systemd[1]: Condition check resulted in Virtual Machine and Container Storage (Compatibility) being skipped. Nov 3 20:17:43.490046 systemd[1]: Reached target Containers. Nov 3 20:17:43.499190 systemd[1]: Starting File System Check on /dev/disk/by-label/OEM... Nov 3 20:17:43.511067 systemd[1]: Finished File System Check on /dev/disk/by-label/OEM. Nov 3 20:17:43.513238 systemd[1]: Mounting /usr/share/oem... Nov 3 20:17:43.519260 kernel: BTRFS info (device vda6): disk space caching is enabled Nov 3 20:17:43.519320 kernel: BTRFS info (device vda6): has skinny extents Nov 3 20:17:43.521988 systemd[1]: Mounted /usr/share/oem. Nov 3 20:17:43.522764 systemd[1]: Reached target Local File Systems. Nov 3 20:17:43.524650 systemd[1]: Starting Rebuild Dynamic Linker Cache... Nov 3 20:17:43.525907 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Nov 3 20:17:43.525960 systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Nov 3 20:17:43.526999 systemd[1]: Starting Commit a transient machine-id on disk... Nov 3 20:17:43.529100 systemd[1]: Starting Create Volatile Files and Directories... Nov 3 20:17:43.536508 systemd-tmpfiles[871]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Nov 3 20:17:43.537996 systemd-tmpfiles[871]: /usr/lib/tmpfiles.d/systemd.conf:33: Duplicate line for path "/var/lib/systemd", ignoring. Nov 3 20:17:43.544064 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Nov 3 20:17:43.544639 systemd[1]: Finished Commit a transient machine-id on disk. Nov 3 20:17:43.585631 systemd-tmpfiles[871]: Detected autofs mount point /boot during canonicalization of /boot. Nov 3 20:17:43.585704 systemd-tmpfiles[871]: Skipping /boot Nov 3 20:17:43.592391 systemd-tmpfiles[871]: Detected autofs mount point /boot during canonicalization of /boot. Nov 3 20:17:43.592405 systemd-tmpfiles[871]: Skipping /boot Nov 3 20:17:43.609289 systemd[1]: Finished Rebuild Dynamic Linker Cache. Nov 3 20:17:43.630547 systemd[1]: Finished Create Volatile Files and Directories. Nov 3 20:17:43.632727 systemd[1]: Starting Load Security Auditing Rules... Nov 3 20:17:43.634497 systemd[1]: Starting Clean up broken links in /etc/ssl/certs... Nov 3 20:17:43.636430 systemd[1]: Starting Rebuild Journal Catalog... Nov 3 20:17:43.639000 audit: BPF prog-id=37 op=LOAD Nov 3 20:17:43.640708 systemd[1]: Starting Network Name Resolution... Nov 3 20:17:43.641000 audit: BPF prog-id=38 op=LOAD Nov 3 20:17:43.642905 systemd[1]: Starting Network Time Synchronization... Nov 3 20:17:43.644762 systemd[1]: Starting Record System Boot/Shutdown in UTMP... Nov 3 20:17:43.646400 systemd[1]: Finished Clean up broken links in /etc/ssl/certs. Nov 3 20:17:43.647729 systemd[1]: Condition check resulted in Update CA bundle at /etc/ssl/certs/ca-certificates.crt being skipped. Nov 3 20:17:43.649505 systemd[1]: Finished Rebuild Journal Catalog. Nov 3 20:17:43.651646 systemd[1]: Starting Update is Completed... Nov 3 20:17:43.657474 systemd[1]: Finished Record System Boot/Shutdown in UTMP. Nov 3 20:17:43.658876 systemd[1]: Finished Update is Completed. Nov 3 20:17:43.661000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Nov 3 20:17:43.662444 augenrules[894]: No rules Nov 3 20:17:43.671353 systemd[1]: Finished Load Security Auditing Rules. Nov 3 20:17:43.684773 systemd[1]: Started Network Time Synchronization. Nov 3 20:17:43.685440 systemd-timesyncd[882]: Initial synchronization to time server 10.0.0.1:123 (10.0.0.1). Nov 3 20:17:43.686012 systemd[1]: Reached target System Initialization. Nov 3 20:17:43.686853 systemd-resolved[881]: Positive Trust Anchors: Nov 3 20:17:43.687022 systemd[1]: Started Watch for update engine configuration changes. Nov 3 20:17:43.687166 systemd-resolved[881]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 3 20:17:43.687249 systemd-resolved[881]: 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 Nov 3 20:17:43.688254 systemd-resolved[881]: Defaulting to hostname 'linux'. Nov 3 20:17:43.688308 systemd[1]: Started Watch for a cloud-config at /var/lib/flatcar-install/user_data. Nov 3 20:17:43.689418 systemd[1]: Started Daily Cleanup of Temporary Directories. Nov 3 20:17:43.690444 systemd[1]: Condition check resulted in Update Engine Stub Timer being skipped. Nov 3 20:17:43.690477 systemd[1]: Reached target Path Units. Nov 3 20:17:43.691216 systemd[1]: Reached target System Time Set. Nov 3 20:17:43.692161 systemd[1]: Started Daily Log Rotation. Nov 3 20:17:43.693133 systemd[1]: Started Weekly check for MD array's redundancy information.. Nov 3 20:17:43.694255 systemd[1]: Reached target Timer Units. Nov 3 20:17:43.695384 systemd[1]: Listening on D-Bus System Message Bus Socket. Nov 3 20:17:43.700708 systemd[1]: Starting Docker Socket for the API... Nov 3 20:17:43.703777 systemd[1]: Listening on OpenSSH Server Socket. Nov 3 20:17:43.705123 systemd[1]: Started Network Name Resolution. Nov 3 20:17:43.706062 systemd[1]: Listening on Docker Socket for the API. Nov 3 20:17:43.706995 systemd[1]: Reached target Network. Nov 3 20:17:43.707690 systemd[1]: Reached target Host and Network Name Lookups. Nov 3 20:17:43.708694 systemd[1]: Reached target Socket Units. Nov 3 20:17:43.709507 systemd[1]: Reached target Basic System. Nov 3 20:17:43.710374 systemd[1]: Condition check resulted in Configure Addon /usr/share/oem being skipped. Nov 3 20:17:43.710405 systemd[1]: Condition check resulted in Run Addon /usr/share/oem being skipped. Nov 3 20:17:43.711426 systemd[1]: Started D-Bus System Message Bus. Nov 3 20:17:43.714714 systemd[1]: Starting Extend Filesystems... Nov 3 20:17:43.715598 systemd[1]: Condition check resulted in Modifies /etc/environment for CoreOS being skipped. Nov 3 20:17:43.716559 systemd[1]: Starting Generate /run/flatcar/motd... Nov 3 20:17:43.718451 systemd[1]: Starting Install an ssh key from /proc/cmdline... Nov 3 20:17:43.720391 systemd[1]: Starting Generate sshd host keys... Nov 3 20:17:43.721241 systemd[1]: Condition check resulted in Load cloud-config from /usr/share/oem/cloud-config.yml being skipped. Nov 3 20:17:43.721304 systemd[1]: Reached target Load system-provided cloud configs. Nov 3 20:17:43.723958 extend-filesystems[905]: Found vda Nov 3 20:17:43.723958 extend-filesystems[905]: Found vda1 Nov 3 20:17:43.723958 extend-filesystems[905]: Found vda2 Nov 3 20:17:43.723958 extend-filesystems[905]: Found vda3 Nov 3 20:17:43.723958 extend-filesystems[905]: Found usr Nov 3 20:17:43.723958 extend-filesystems[905]: Found vda4 Nov 3 20:17:43.723958 extend-filesystems[905]: Found vda6 Nov 3 20:17:43.723958 extend-filesystems[905]: Found vda7 Nov 3 20:17:43.723958 extend-filesystems[905]: Found vda9 Nov 3 20:17:43.723958 extend-filesystems[905]: Checking size of /dev/vda9 Nov 3 20:17:43.750216 extend-filesystems[905]: Old size kept for /dev/vda9 Nov 3 20:17:43.724474 systemd[1]: Starting User Login Management... Nov 3 20:17:43.726142 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Nov 3 20:17:43.727537 systemd[1]: Starting Update Engine... Nov 3 20:17:43.728266 systemd[1]: Condition check resulted in Load cloud-config from url defined in /proc/cmdline being skipped. Nov 3 20:17:43.728314 systemd[1]: Reached target Load user-provided cloud configs. Nov 3 20:17:43.730994 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Nov 3 20:17:43.731213 systemd[1]: Finished Install an ssh key from /proc/cmdline. Nov 3 20:17:43.734700 systemd[1]: extend-filesystems.service: Deactivated successfully. Nov 3 20:17:43.734923 systemd[1]: Finished Extend Filesystems. Nov 3 20:17:43.746496 systemd[1]: motdgen.service: Deactivated successfully. Nov 3 20:17:43.746738 systemd[1]: Finished Generate /run/flatcar/motd. Nov 3 20:17:43.760796 update_engine[917]: I1103 20:17:43.759681 917 main.cc:89] Flatcar Update Engine starting Nov 3 20:17:43.761064 systemd-logind[914]: Watching system buttons on /dev/input/event0 (Power Button) Nov 3 20:17:43.761245 systemd-logind[914]: New seat seat0. Nov 3 20:17:43.766576 systemd[1]: Started User Login Management. Nov 3 20:17:43.769793 systemd[1]: Started Update Engine. Nov 3 20:17:43.771617 update_engine[917]: I1103 20:17:43.771577 917 update_check_scheduler.cc:74] Next update check in 8m45s Nov 3 20:17:43.777254 systemd[1]: Started Cluster reboot manager. Nov 3 20:17:43.804146 locksmithd[929]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Nov 3 20:17:44.399210 sshd_keygen[923]: ssh-keygen: generating new host keys: RSA DSA ECDSA ED25519 Nov 3 20:17:44.417329 systemd[1]: Finished Generate sshd host keys. Nov 3 20:17:44.434144 systemd[1]: Starting Generate /run/issue... Nov 3 20:17:44.438482 systemd[1]: issuegen.service: Deactivated successfully. Nov 3 20:17:44.438714 systemd[1]: Finished Generate /run/issue. Nov 3 20:17:44.440884 systemd[1]: Starting Permit User Sessions... Nov 3 20:17:44.445297 systemd[1]: Finished Permit User Sessions. Nov 3 20:17:44.447432 systemd[1]: Started Getty on tty1. Nov 3 20:17:44.449372 systemd[1]: Started Serial Getty on ttyAMA0. Nov 3 20:17:44.450417 systemd[1]: Reached target Login Prompts. Nov 3 20:17:44.451373 systemd[1]: Reached target Multi-User System. Nov 3 20:17:44.453346 systemd[1]: Starting Record Runlevel Change in UTMP... Nov 3 20:17:44.458412 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Nov 3 20:17:44.458642 systemd[1]: Finished Record Runlevel Change in UTMP. Nov 3 20:17:44.459717 systemd[1]: Startup finished in 694ms (kernel) + 2.354s (initrd) + 3.545s (userspace) = 6.593s. Nov 3 20:17:45.131570 systemd-networkd[837]: eth0: Gained IPv6LL Nov 3 20:17:50.511877 systemd[1]: Created slice Slice /system/sshd. Nov 3 20:17:50.512892 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:47414). Nov 3 20:17:50.558483 sshd[950]: Accepted publickey for core from 10.0.0.1 port 47414 ssh2: RSA SHA256:692sSxTeBYXCSoeByc7zvUD/VRPArpPZwEkxc0ZTfNg Nov 3 20:17:50.560105 sshd[950]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 3 20:17:50.580466 systemd[1]: Created slice User Slice of UID 500. Nov 3 20:17:50.581355 systemd[1]: Starting User Runtime Directory /run/user/500... Nov 3 20:17:50.583914 systemd-logind[914]: New session 1 of user core. Nov 3 20:17:50.587118 systemd[1]: Finished User Runtime Directory /run/user/500. Nov 3 20:17:50.588142 systemd[1]: Starting User Manager for UID 500... Nov 3 20:17:50.592424 systemd[953]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Nov 3 20:17:50.637868 systemd[953]: Queued start job for default target Main User Target. Nov 3 20:17:50.638019 systemd[953]: Reached target Paths. Nov 3 20:17:50.638033 systemd[953]: Reached target Sockets. Nov 3 20:17:50.638044 systemd[953]: Reached target Timers. Nov 3 20:17:50.638054 systemd[953]: Reached target Basic System. Nov 3 20:17:50.638093 systemd[953]: Reached target Main User Target. Nov 3 20:17:50.638106 systemd[953]: Startup finished in 42ms. Nov 3 20:17:50.638609 systemd[1]: Started User Manager for UID 500. Nov 3 20:17:50.645004 systemd[1]: Started Session 1 of User core. Nov 3 20:17:50.713024 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:47416). Nov 3 20:17:50.753395 sshd[962]: Accepted publickey for core from 10.0.0.1 port 47416 ssh2: RSA SHA256:692sSxTeBYXCSoeByc7zvUD/VRPArpPZwEkxc0ZTfNg Nov 3 20:17:50.754667 sshd[962]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 3 20:17:50.758445 systemd-logind[914]: New session 2 of user core. Nov 3 20:17:50.770368 systemd[1]: Started Session 2 of User core. Nov 3 20:17:50.826514 sshd[962]: pam_unix(sshd:session): session closed for user core Nov 3 20:17:50.835620 systemd[1]: sshd@1-10.0.0.8:22-10.0.0.1:47416.service: Deactivated successfully. Nov 3 20:17:50.836322 systemd[1]: session-2.scope: Deactivated successfully. Nov 3 20:17:50.837007 systemd-logind[914]: Session 2 logged out. Waiting for processes to exit. Nov 3 20:17:50.839269 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:47432). Nov 3 20:17:50.847783 systemd-logind[914]: Removed session 2. Nov 3 20:17:50.876817 sshd[968]: Accepted publickey for core from 10.0.0.1 port 47432 ssh2: RSA SHA256:692sSxTeBYXCSoeByc7zvUD/VRPArpPZwEkxc0ZTfNg Nov 3 20:17:50.877652 sshd[968]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 3 20:17:50.880250 systemd-logind[914]: New session 3 of user core. Nov 3 20:17:50.891106 systemd[1]: Started Session 3 of User core. Nov 3 20:17:50.940044 sshd[968]: pam_unix(sshd:session): session closed for user core Nov 3 20:17:50.953430 systemd[1]: sshd@2-10.0.0.8:22-10.0.0.1:47432.service: Deactivated successfully. Nov 3 20:17:50.953909 systemd[1]: session-3.scope: Deactivated successfully. Nov 3 20:17:50.954583 systemd-logind[914]: Session 3 logged out. Waiting for processes to exit. Nov 3 20:17:50.955284 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:47448). Nov 3 20:17:50.956262 systemd-logind[914]: Removed session 3. Nov 3 20:17:50.992225 sshd[974]: Accepted publickey for core from 10.0.0.1 port 47448 ssh2: RSA SHA256:692sSxTeBYXCSoeByc7zvUD/VRPArpPZwEkxc0ZTfNg Nov 3 20:17:50.993249 sshd[974]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 3 20:17:50.995828 systemd-logind[914]: New session 4 of user core. Nov 3 20:17:51.004072 systemd[1]: Started Session 4 of User core. Nov 3 20:17:51.056966 sshd[974]: pam_unix(sshd:session): session closed for user core Nov 3 20:17:51.065218 systemd[1]: sshd@3-10.0.0.8:22-10.0.0.1:47448.service: Deactivated successfully. Nov 3 20:17:51.065706 systemd[1]: session-4.scope: Deactivated successfully. Nov 3 20:17:51.066408 systemd-logind[914]: Session 4 logged out. Waiting for processes to exit. Nov 3 20:17:51.067045 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:47464). Nov 3 20:17:51.068145 systemd-logind[914]: Removed session 4. Nov 3 20:17:51.107311 sshd[980]: Accepted publickey for core from 10.0.0.1 port 47464 ssh2: RSA SHA256:692sSxTeBYXCSoeByc7zvUD/VRPArpPZwEkxc0ZTfNg Nov 3 20:17:51.108166 sshd[980]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 3 20:17:51.110877 systemd-logind[914]: New session 5 of user core. Nov 3 20:17:51.117147 systemd[1]: Started Session 5 of User core. Nov 3 20:17:51.173244 sudo[983]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/setenforce 1 Nov 3 20:17:51.173445 sudo[983]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Nov 3 20:17:51.185433 sudo[983]: pam_unix(sudo:session): session closed for user root Nov 3 20:17:51.187478 sshd[980]: pam_unix(sshd:session): session closed for user core Nov 3 20:17:51.206551 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:47472). Nov 3 20:17:51.211416 dbus-daemon[904]: [system] Reloaded configuration Nov 3 20:17:51.224766 systemd[1]: sshd@4-10.0.0.8:22-10.0.0.1:47464.service: Deactivated successfully. Nov 3 20:17:51.225383 systemd[1]: session-5.scope: Deactivated successfully. Nov 3 20:17:51.226175 systemd-logind[914]: Session 5 logged out. Waiting for processes to exit. Nov 3 20:17:51.227304 systemd-logind[914]: Removed session 5. Nov 3 20:17:51.253616 sshd[986]: Accepted publickey for core from 10.0.0.1 port 47472 ssh2: RSA SHA256:692sSxTeBYXCSoeByc7zvUD/VRPArpPZwEkxc0ZTfNg Nov 3 20:17:51.254712 sshd[986]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 3 20:17:51.257565 systemd-logind[914]: New session 6 of user core. Nov 3 20:17:51.263073 systemd[1]: Started Session 6 of User core. Nov 3 20:17:51.315675 sudo[991]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Nov 3 20:17:51.315886 sudo[991]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Nov 3 20:17:51.319938 sudo[991]: pam_unix(sudo:session): session closed for user root Nov 3 20:17:51.323332 sudo[990]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl restart audit-rules Nov 3 20:17:51.323529 sudo[990]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Nov 3 20:17:51.340854 systemd[1]: Stopping Load Security Auditing Rules... Nov 3 20:17:51.341000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Nov 3 20:17:51.343331 auditctl[994]: No rules Nov 3 20:17:51.343589 systemd[1]: audit-rules.service: Deactivated successfully. Nov 3 20:17:51.343813 systemd[1]: Stopped Load Security Auditing Rules. Nov 3 20:17:51.345868 systemd[1]: Starting Load Security Auditing Rules... Nov 3 20:17:51.347203 kernel: kauditd_printk_skb: 6 callbacks suppressed Nov 3 20:17:51.347247 kernel: audit: type=1305 audit(1762201071.341:68): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Nov 3 20:17:51.363846 augenrules[1011]: No rules Nov 3 20:17:51.364878 systemd[1]: Finished Load Security Auditing Rules. Nov 3 20:17:51.365961 sudo[990]: pam_unix(sudo:session): session closed for user root Nov 3 20:17:51.367166 sshd[986]: pam_unix(sshd:session): session closed for user core Nov 3 20:17:51.382590 systemd[1]: sshd@5-10.0.0.8:22-10.0.0.1:47472.service: Deactivated successfully. Nov 3 20:17:51.383124 systemd[1]: session-6.scope: Deactivated successfully. Nov 3 20:17:51.383679 systemd-logind[914]: Session 6 logged out. Waiting for processes to exit. Nov 3 20:17:51.384667 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:47480). Nov 3 20:17:51.387420 systemd-logind[914]: Removed session 6. Nov 3 20:17:51.422794 sshd[1017]: Accepted publickey for core from 10.0.0.1 port 47480 ssh2: RSA SHA256:692sSxTeBYXCSoeByc7zvUD/VRPArpPZwEkxc0ZTfNg Nov 3 20:17:51.423788 sshd[1017]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 3 20:17:51.427779 kernel: audit: type=1006 audit(1762201071.422:69): pid=1017 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=7 res=1 Nov 3 20:17:51.430077 systemd-logind[914]: New session 7 of user core. Nov 3 20:17:51.437112 systemd[1]: Started Session 7 of User core. Nov 3 20:17:51.488487 sudo[1020]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/bash -c cat >/etc/coreos/update.conf.new </etc/coreos/update-payload-key.pub.pem </etc/coreos/update.conf.new </etc/coreos/update-payload-key.pub.pem < Nov 3 20:17:54.297078 update_engine[1267]: Nov 3 20:17:54.297078 update_engine[1267]: Nov 3 20:17:54.297078 update_engine[1267]: Nov 3 20:17:54.297078 update_engine[1267]: Nov 3 20:17:54.297078 update_engine[1267]: Nov 3 20:17:54.297078 update_engine[1267]: Nov 3 20:17:54.297078 update_engine[1267]: Nov 3 20:17:54.297078 update_engine[1267]: Nov 3 20:17:54.297078 update_engine[1267]: I1103 20:17:54.297200 1267 libcurl_http_fetcher.cc:48] Starting/Resuming transfer Nov 3 20:17:54.299291 update_engine[1267]: I1103 20:17:54.299255 1267 libcurl_http_fetcher.cc:152] Setting up curl options for HTTP Nov 3 20:17:54.299523 update_engine[1267]: I1103 20:17:54.299497 1267 libcurl_http_fetcher.cc:435] Setting up timeout source: 1 seconds. Nov 3 20:17:54.300820 update_engine[1267]: I1103 20:17:54.300788 1267 libcurl_http_fetcher.cc:248] HTTP response code: 200 Nov 3 20:17:54.300902 update_engine[1267]: I1103 20:17:54.300863 1267 libcurl_http_fetcher.cc:305] Transfer completed (200), 791 bytes downloaded Nov 3 20:17:54.300902 update_engine[1267]: I1103 20:17:54.300874 1267 omaha_request_action.cc:619] Omaha request response: Nov 3 20:17:54.300902 update_engine[1267]: Nov 3 20:17:54.300902 update_engine[1267]: Nov 3 20:17:54.300902 update_engine[1267]: Nov 3 20:17:54.300902 update_engine[1267]: Nov 3 20:17:54.300902 update_engine[1267]: Nov 3 20:17:54.300902 update_engine[1267]: Nov 3 20:17:54.300902 update_engine[1267]: Nov 3 20:17:54.300902 update_engine[1267]: Nov 3 20:17:54.300902 update_engine[1267]: Nov 3 20:17:54.300902 update_engine[1267]: Nov 3 20:17:54.300902 update_engine[1267]: Nov 3 20:17:54.300902 update_engine[1267]: Nov 3 20:17:54.300902 update_engine[1267]: Nov 3 20:17:54.300902 update_engine[1267]: Nov 3 20:17:54.300902 update_engine[1267]: Nov 3 20:17:54.300902 update_engine[1267]: Nov 3 20:17:54.300902 update_engine[1267]: Nov 3 20:17:54.300902 update_engine[1267]: Nov 3 20:17:54.300902 update_engine[1267]: Nov 3 20:17:54.300902 update_engine[1267]: Nov 3 20:17:54.302829 update_engine[1267]: I1103 20:17:54.302802 1267 omaha_request_action.cc:447] Omaha Response manifest version = Nov 3 20:17:54.302829 update_engine[1267]: I1103 20:17:54.302830 1267 omaha_request_action.cc:470] Found 1 url(s) Nov 3 20:17:54.302906 update_engine[1267]: I1103 20:17:54.302843 1267 omaha_request_action.cc:506] Processing first of 1 package(s) Nov 3 20:17:54.302906 update_engine[1267]: I1103 20:17:54.302847 1267 omaha_request_action.cc:513] Omaha Response package name = update.gz Nov 3 20:17:54.302906 update_engine[1267]: I1103 20:17:54.302850 1267 omaha_request_action.cc:529] Url0: http://10.0.0.7:34567/packages/update.gz Nov 3 20:17:54.302906 update_engine[1267]: I1103 20:17:54.302858 1267 omaha_request_action.cc:541] Payload size = 433878941 bytes Nov 3 20:17:54.302906 update_engine[1267]: I1103 20:17:54.302873 1267 omaha_request_action.cc:565] Found 1 action(s). Processing the postinstall action. Nov 3 20:17:54.302906 update_engine[1267]: I1103 20:17:54.302886 1267 payload_state.cc:51] Resetting all persisted state as this is a new response Nov 3 20:17:54.302906 update_engine[1267]: I1103 20:17:54.302891 1267 payload_state.cc:360] Current Response Signature = Nov 3 20:17:54.302906 update_engine[1267]: NumURLs = 1 Nov 3 20:17:54.302906 update_engine[1267]: Url0 = http://10.0.0.7:34567/packages/update.gz Nov 3 20:17:54.302906 update_engine[1267]: Payload Size = 433878941 Nov 3 20:17:54.302906 update_engine[1267]: Payload Sha256 Hash = lVdxUTtdTOsZmTszVwz4Xs2gAniQzDt5nUhkgQrAo2w= Nov 3 20:17:54.302906 update_engine[1267]: Is Delta Payload = 0 Nov 3 20:17:54.302906 update_engine[1267]: Max Failure Count Per Url = 10 Nov 3 20:17:54.302906 update_engine[1267]: Disable Payload Backoff = 1 Nov 3 20:17:54.303147 update_engine[1267]: I1103 20:17:54.302976 1267 payload_state.cc:381] Payload Attempt Number = 0 Nov 3 20:17:54.303147 update_engine[1267]: I1103 20:17:54.303010 1267 payload_state.cc:404] Current URL Index = 0 Nov 3 20:17:54.303147 update_engine[1267]: I1103 20:17:54.303037 1267 payload_state.cc:425] Current URL (Url0)'s Failure Count = 0 Nov 3 20:17:54.303147 update_engine[1267]: I1103 20:17:54.303062 1267 payload_state.cc:287] Resetting backoff expiry time as payload backoff is disabled Nov 3 20:17:54.303147 update_engine[1267]: I1103 20:17:54.303066 1267 payload_state.cc:452] Backoff Expiry Time = 01/01/70 00:00:00 UTC Nov 3 20:17:54.303147 update_engine[1267]: I1103 20:17:54.303095 1267 payload_state.cc:203] Payload backoff logic is disabled. Can proceed with the download Nov 3 20:17:54.303147 update_engine[1267]: I1103 20:17:54.303112 1267 action_processor.cc:82] ActionProcessor::ActionComplete: finished OmahaRequestAction, starting OmahaResponseHandlerAction Nov 3 20:17:54.303147 update_engine[1267]: I1103 20:17:54.303115 1267 omaha_response_handler_action.cc:43] Using Url0 as the download url this time Nov 3 20:17:54.303147 update_engine[1267]: I1103 20:17:54.303128 1267 prefs.cc:51] update-state-next-operation not present in /var/lib/update_engine/prefs Nov 3 20:17:54.303147 update_engine[1267]: E1103 20:17:54.303133 1267 payload_processor.cc:488] prefs->GetInt64(kPrefsUpdateStateNextOperation, &next_operation) && next_operation != kUpdateStateOperationInvalid && next_operation > 0 failed. Nov 3 20:17:54.303596 update_engine[1267]: I1103 20:17:54.303573 1267 omaha_response_handler_action.cc:85] Using this install plan: Nov 3 20:17:54.303596 update_engine[1267]: I1103 20:17:54.303588 1267 install_plan.cc:53] InstallPlan: , new_update, url: http://10.0.0.7:34567/packages/update.gz, payload size: 433878941, payload hash: lVdxUTtdTOsZmTszVwz4Xs2gAniQzDt5nUhkgQrAo2w=, partition_path: /dev/vda4, kernel_path: /boot/flatcar/vmlinuz-b, pcr_policy_path: /var/lib/update_engine/pcrs-b.zip, old_partition_path: /dev/vda3, old_kernel_path: /boot/flatcar/vmlinuz-a Nov 3 20:17:54.303699 update_engine[1267]: I1103 20:17:54.303663 1267 action_processor.cc:82] ActionProcessor::ActionComplete: finished OmahaResponseHandlerAction, starting FilesystemCopierAction Nov 3 20:17:54.304660 update_engine[1267]: I1103 20:17:54.304636 1267 filesystem_copier_action.cc:296] Filesystem size: 1073741824 Nov 3 20:17:54.348489 sshd[1280]: pam_unix(sshd:session): session closed for user core Nov 3 20:17:54.353449 systemd[1]: sshd@24-10.0.0.8:22-10.0.0.1:47666.service: Deactivated successfully. Nov 3 20:17:54.354069 systemd[1]: session-25.scope: Deactivated successfully. Nov 3 20:17:54.354629 systemd-logind[914]: Session 25 logged out. Waiting for processes to exit. Nov 3 20:17:54.355477 systemd-logind[914]: Removed session 25. Nov 3 20:17:56.056163 update_engine[1267]: I1103 20:17:56.056123 1267 filesystem_copier_action.cc:274] Hash: MqVcpLuSy9a3pkT1U79KicfT+9MRnGj6QbL170vealU= Nov 3 20:17:56.056163 update_engine[1267]: I1103 20:17:56.056164 1267 action_processor.cc:82] ActionProcessor::ActionComplete: finished FilesystemCopierAction, starting KernelCopierAction Nov 3 20:17:56.118679 update_engine[1267]: E1103 20:17:56.118621 1267 kernel_copier_action.cc:60] Failed to copy kernel from /boot/flatcar/vmlinuz-a to /boot/flatcar/vmlinuz-b Nov 3 20:17:56.118679 update_engine[1267]: I1103 20:17:56.118665 1267 action_processor.cc:68] ActionProcessor::ActionComplete: KernelCopierAction action failed. Aborting processing. Nov 3 20:17:56.118679 update_engine[1267]: I1103 20:17:56.118669 1267 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type KernelCopierAction Nov 3 20:17:56.118679 update_engine[1267]: I1103 20:17:56.118673 1267 update_attempter.cc:302] Processing Done. Nov 3 20:17:56.118917 update_engine[1267]: E1103 20:17:56.118721 1267 update_attempter.cc:615] Update failed. Nov 3 20:17:56.118917 update_engine[1267]: I1103 20:17:56.118726 1267 payload_state.cc:97] Updating payload state for error code: 1 (kActionCodeError) Nov 3 20:17:56.118917 update_engine[1267]: I1103 20:17:56.118729 1267 payload_state.cc:276] Incrementing the URL failure count Nov 3 20:17:56.118917 update_engine[1267]: I1103 20:17:56.118733 1267 payload_state.cc:425] Current URL (Url0)'s Failure Count = 1 Nov 3 20:17:56.118917 update_engine[1267]: I1103 20:17:56.118911 1267 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Nov 3 20:17:56.119072 update_engine[1267]: I1103 20:17:56.118929 1267 omaha_request_action.cc:268] Posting an Omaha request to http://10.0.0.7:34567/v1/update Nov 3 20:17:56.119072 update_engine[1267]: I1103 20:17:56.118934 1267 omaha_request_action.cc:269] Request: Nov 3 20:17:56.119072 update_engine[1267]: Nov 3 20:17:56.119072 update_engine[1267]: Nov 3 20:17:56.119072 update_engine[1267]: Nov 3 20:17:56.119072 update_engine[1267]: Nov 3 20:17:56.119072 update_engine[1267]: Nov 3 20:17:56.119072 update_engine[1267]: Nov 3 20:17:56.119072 update_engine[1267]: I1103 20:17:56.118952 1267 libcurl_http_fetcher.cc:48] Starting/Resuming transfer Nov 3 20:17:56.119072 update_engine[1267]: I1103 20:17:56.119020 1267 libcurl_http_fetcher.cc:152] Setting up curl options for HTTP Nov 3 20:17:56.119291 update_engine[1267]: I1103 20:17:56.119124 1267 libcurl_http_fetcher.cc:435] Setting up timeout source: 1 seconds. Nov 3 20:17:56.230862 update_engine[1267]: I1103 20:17:56.230822 1267 libcurl_http_fetcher.cc:248] HTTP response code: 200 Nov 3 20:17:56.231005 update_engine[1267]: I1103 20:17:56.230934 1267 libcurl_http_fetcher.cc:305] Transfer completed (200), 241 bytes downloaded Nov 3 20:17:56.231005 update_engine[1267]: I1103 20:17:56.230986 1267 omaha_request_action.cc:619] Omaha request response: Nov 3 20:17:56.231005 update_engine[1267]: Nov 3 20:17:56.231005 update_engine[1267]: Nov 3 20:17:56.231005 update_engine[1267]: Nov 3 20:17:56.231005 update_engine[1267]: Nov 3 20:17:56.231005 update_engine[1267]: Nov 3 20:17:56.231005 update_engine[1267]: Nov 3 20:17:56.231005 update_engine[1267]: E1103 20:17:56.231000 1267 omaha_request_action.cc:626] HTTP reported success but Omaha reports an error. Nov 3 20:17:56.231005 update_engine[1267]: I1103 20:17:56.231005 1267 action_processor.cc:65] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Nov 3 20:17:56.231005 update_engine[1267]: I1103 20:17:56.231009 1267 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Nov 3 20:17:56.231439 update_engine[1267]: I1103 20:17:56.231012 1267 update_attempter.cc:302] Processing Done. Nov 3 20:17:56.231439 update_engine[1267]: I1103 20:17:56.231017 1267 update_attempter.cc:306] Error event sent. Nov 3 20:18:04.401536 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:41356). Nov 3 20:18:04.443465 sshd[1302]: Accepted publickey for core from 10.0.0.1 port 41356 ssh2: RSA SHA256:692sSxTeBYXCSoeByc7zvUD/VRPArpPZwEkxc0ZTfNg Nov 3 20:18:04.444785 sshd[1302]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 3 20:18:04.445153 kernel: kauditd_printk_skb: 287 callbacks suppressed Nov 3 20:18:04.445210 kernel: audit: type=1006 audit(1762201084.443:365): pid=1302 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=26 res=1 Nov 3 20:18:04.447469 systemd-logind[914]: New session 26 of user core. Nov 3 20:18:04.456070 systemd[1]: Started Session 26 of User core. Nov 3 20:18:04.512357 sshd[1302]: pam_unix(sshd:session): session closed for user core Nov 3 20:18:04.524857 systemd[1]: sshd@25-10.0.0.8:22-10.0.0.1:41356.service: Deactivated successfully. Nov 3 20:18:04.525418 systemd[1]: session-26.scope: Deactivated successfully. Nov 3 20:18:04.526036 systemd-logind[914]: Session 26 logged out. Waiting for processes to exit. Nov 3 20:18:04.527034 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:41372). Nov 3 20:18:04.527707 systemd-logind[914]: Removed session 26. Nov 3 20:18:04.563584 sshd[1309]: Accepted publickey for core from 10.0.0.1 port 41372 ssh2: RSA SHA256:692sSxTeBYXCSoeByc7zvUD/VRPArpPZwEkxc0ZTfNg Nov 3 20:18:04.564687 sshd[1309]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 3 20:18:04.566958 kernel: audit: type=1006 audit(1762201084.563:366): pid=1309 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=27 res=1 Nov 3 20:18:04.568526 systemd-logind[914]: New session 27 of user core. Nov 3 20:18:04.576133 systemd[1]: Started Session 27 of User core. Nov 3 20:18:04.632494 sshd[1309]: pam_unix(sshd:session): session closed for user core Nov 3 20:18:04.644868 systemd[1]: sshd@26-10.0.0.8:22-10.0.0.1:41372.service: Deactivated successfully. Nov 3 20:18:04.645427 systemd[1]: session-27.scope: Deactivated successfully. Nov 3 20:18:04.646018 systemd-logind[914]: Session 27 logged out. Waiting for processes to exit. Nov 3 20:18:04.647017 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:41382). Nov 3 20:18:04.647695 systemd-logind[914]: Removed session 27. Nov 3 20:18:04.684893 sshd[1317]: Accepted publickey for core from 10.0.0.1 port 41382 ssh2: RSA SHA256:692sSxTeBYXCSoeByc7zvUD/VRPArpPZwEkxc0ZTfNg Nov 3 20:18:04.686268 sshd[1317]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 3 20:18:04.687958 kernel: audit: type=1006 audit(1762201084.684:367): pid=1317 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=28 res=1 Nov 3 20:18:04.689299 systemd-logind[914]: New session 28 of user core. Nov 3 20:18:04.701096 systemd[1]: Started Session 28 of User core. Nov 3 20:18:04.749685 sshd[1317]: pam_unix(sshd:session): session closed for user core Nov 3 20:18:04.764983 systemd[1]: sshd@27-10.0.0.8:22-10.0.0.1:41382.service: Deactivated successfully. Nov 3 20:18:04.765645 systemd[1]: session-28.scope: Deactivated successfully. Nov 3 20:18:04.766358 systemd-logind[914]: Session 28 logged out. Waiting for processes to exit. Nov 3 20:18:04.767583 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:41392). Nov 3 20:18:04.768315 systemd-logind[914]: Removed session 28. Nov 3 20:18:04.804090 sshd[1323]: Accepted publickey for core from 10.0.0.1 port 41392 ssh2: RSA SHA256:692sSxTeBYXCSoeByc7zvUD/VRPArpPZwEkxc0ZTfNg Nov 3 20:18:04.805051 sshd[1323]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 3 20:18:04.806954 kernel: audit: type=1006 audit(1762201084.803:368): pid=1323 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=29 res=1 Nov 3 20:18:04.809069 systemd-logind[914]: New session 29 of user core. Nov 3 20:18:04.818084 systemd[1]: Started Session 29 of User core. Nov 3 20:18:04.866789 sshd[1323]: pam_unix(sshd:session): session closed for user core Nov 3 20:18:04.879956 systemd[1]: sshd@28-10.0.0.8:22-10.0.0.1:41392.service: Deactivated successfully. Nov 3 20:18:04.880631 systemd[1]: session-29.scope: Deactivated successfully. Nov 3 20:18:04.881335 systemd-logind[914]: Session 29 logged out. Waiting for processes to exit. Nov 3 20:18:04.882612 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:41400). Nov 3 20:18:04.883392 systemd-logind[914]: Removed session 29. Nov 3 20:18:04.920898 sshd[1329]: Accepted publickey for core from 10.0.0.1 port 41400 ssh2: RSA SHA256:692sSxTeBYXCSoeByc7zvUD/VRPArpPZwEkxc0ZTfNg Nov 3 20:18:04.921737 sshd[1329]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 3 20:18:04.923958 kernel: audit: type=1006 audit(1762201084.920:369): pid=1329 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=30 res=1 Nov 3 20:18:04.925627 systemd-logind[914]: New session 30 of user core. Nov 3 20:18:04.939077 systemd[1]: Started Session 30 of User core. Nov 3 20:18:04.992423 sudo[1333]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl stop sshd.socket Nov 3 20:18:04.992903 sudo[1333]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Nov 3 20:18:04.998526 systemd[1]: sshd.socket: Deactivated successfully. Nov 3 20:18:04.998879 systemd[1]: Closed OpenSSH Server Socket. Nov 3 20:18:04.999749 sudo[1333]: pam_unix(sudo:session): session closed for user root Nov 3 20:18:05.003369 sudo[1332]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/reboot Nov 3 20:18:05.003573 sudo[1332]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Nov 3 20:18:05.018243 sshd[950]: pam_unix(sshd:session): session closed for user core Nov 3 20:18:05.019908 sshd[1329]: pam_unix(sshd:session): session closed for user core Nov 3 20:18:05.018528 systemd[1]: Stopping Session 1 of User core... Nov 3 20:18:05.021490 sudo[1332]: pam_unix(sudo:session): session closed for user root Nov 3 20:18:05.019728 systemd[1]: Stopping Session 30 of User core... Nov 3 20:18:05.022414 systemd[1]: Removed slice Slice /system/addon-config. Nov 3 20:18:05.023660 systemd[1]: Removed slice Slice /system/addon-run. Nov 3 20:18:05.024883 systemd[1]: Removed slice Slice /system/modprobe. Nov 3 20:18:05.026102 systemd[1]: Removed slice Slice /system/system-cloudinit. Nov 3 20:18:05.027214 systemd[1]: Stopped target Multi-User System. Nov 3 20:18:05.028062 systemd[1]: Stopped target Login Prompts. Nov 3 20:18:05.028835 systemd[1]: Stopped target Containers. Nov 3 20:18:05.029806 systemd[1]: Stopped target Host and Network Name Lookups. Nov 3 20:18:05.030901 systemd[1]: Stopped target Remote Encrypted Volumes. Nov 3 20:18:05.031884 systemd[1]: Stopped target Timer Units. Nov 3 20:18:05.032744 systemd[1]: logrotate.timer: Deactivated successfully. Nov 3 20:18:05.032789 systemd[1]: Stopped Daily Log Rotation. Nov 3 20:18:05.033583 systemd[1]: mdadm.timer: Deactivated successfully. Nov 3 20:18:05.033622 systemd[1]: Stopped Weekly check for MD array's redundancy information.. Nov 3 20:18:05.034791 systemd[1]: systemd-tmpfiles-clean.timer: Deactivated successfully. Nov 3 20:18:05.034840 systemd[1]: Stopped Daily Cleanup of Temporary Directories. Nov 3 20:18:05.035876 systemd[1]: Stopped target System Time Set. Nov 3 20:18:05.036738 systemd[1]: Stopped target Load user-provided cloud configs. Nov 3 20:18:05.037763 systemd[1]: Stopped target Load system-provided cloud configs. Nov 3 20:18:05.038834 systemd[1]: systemd-coredump.socket: Deactivated successfully. Nov 3 20:18:05.038924 systemd[1]: Closed Process Core Dump Socket. Nov 3 20:18:05.051245 systemd[1]: Unmounting Boot partition... Nov 3 20:18:05.052216 systemd[1]: Stopping Getty on tty1... Nov 3 20:18:05.053032 systemd[1]: Stopping Serial Getty on ttyAMA0... Nov 3 20:18:05.053963 systemd[1]: Stopping OpenSSH per-connection server daemon... Nov 3 20:18:05.054881 systemd[1]: Stopping OpenSSH per-connection server daemon (10.0.0.1:41400)... Nov 3 20:18:05.055879 systemd[1]: systemd-machine-id-commit.service: Deactivated successfully. Nov 3 20:18:05.056035 systemd[1]: Stopped Commit a transient machine-id on disk. Nov 3 20:18:05.057082 systemd[1]: Stopped target First Boot Complete. Nov 3 20:18:05.058894 systemd[1]: Stopping Load/Save Random Seed... Nov 3 20:18:05.059905 systemd[1]: Stopping Update Engine... Nov 3 20:18:05.063075 systemd[1]: getty@tty1.service: Deactivated successfully. Nov 3 20:18:05.063276 systemd[1]: Stopped Getty on tty1. Nov 3 20:18:05.064383 systemd[1]: serial-getty@ttyAMA0.service: Deactivated successfully. Nov 3 20:18:05.064576 systemd[1]: Stopped Serial Getty on ttyAMA0. Nov 3 20:18:05.065842 systemd[1]: sshd@0-10.0.0.8:22-10.0.0.1:47414.service: Deactivated successfully. Nov 3 20:18:05.066051 systemd[1]: Stopped OpenSSH per-connection server daemon. Nov 3 20:18:05.067417 systemd[1]: update-engine.service: Main process exited, code=exited, status=1/FAILURE Nov 3 20:18:05.067488 systemd[1]: update-engine.service: Failed with result 'exit-code'. Nov 3 20:18:05.067727 systemd[1]: Stopped Update Engine. Nov 3 20:18:05.068609 systemd[1]: update-engine.service: Consumed 2.342s CPU time. Nov 3 20:18:05.068833 systemd[1]: sshd@29-10.0.0.8:22-10.0.0.1:41400.service: Deactivated successfully. Nov 3 20:18:05.069042 systemd[1]: Stopped OpenSSH per-connection server daemon (10.0.0.1:41400). Nov 3 20:18:05.089590 systemd[1]: boot.mount: Deactivated successfully. Nov 3 20:18:05.089863 systemd[1]: Unmounted Boot partition. Nov 3 20:18:05.091064 systemd[1]: systemd-random-seed.service: Deactivated successfully. Nov 3 20:18:05.091279 systemd[1]: Stopped Load/Save Random Seed. Nov 3 20:18:05.092313 systemd[1]: session-1.scope: Deactivated successfully. Nov 3 20:18:05.092541 systemd[1]: Stopped Session 1 of User core. Nov 3 20:18:05.093547 systemd[1]: session-30.scope: Deactivated successfully. Nov 3 20:18:05.093771 systemd[1]: Stopped Session 30 of User core. Nov 3 20:18:05.095726 systemd-logind[914]: Session 30 logged out. Waiting for processes to exit. Nov 3 20:18:05.096074 systemd[1]: Removed slice Slice /system/getty. Nov 3 20:18:05.097231 systemd[1]: Removed slice Slice /system/serial-getty. Nov 3 20:18:05.098419 systemd[1]: Removed slice Slice /system/sshd. Nov 3 20:18:05.099306 systemd[1]: sshd-keygen.service: Deactivated successfully. Nov 3 20:18:05.099410 systemd[1]: Stopped Generate sshd host keys. Nov 3 20:18:05.100302 systemd[1]: systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service: Deactivated successfully. Nov 3 20:18:05.100424 systemd[1]: Stopped File System Check on /dev/disk/by-label/EFI-SYSTEM. Nov 3 20:18:05.102095 systemd[1]: Stopping User Login Management... Nov 3 20:18:05.102862 systemd[1]: Stopping User Manager for UID 500... Nov 3 20:18:05.104199 systemd[953]: Stopped target Main User Target. Nov 3 20:18:05.104217 systemd[953]: Stopped target Basic System. Nov 3 20:18:05.104225 systemd[953]: Stopped target Paths. Nov 3 20:18:05.104233 systemd[953]: Stopped target Sockets. Nov 3 20:18:05.104248 systemd[953]: Reached target Shutdown. Nov 3 20:18:05.104255 systemd[953]: Stopped target Timers. Nov 3 20:18:05.104354 systemd[953]: Finished Exit the Session. Nov 3 20:18:05.104376 systemd[953]: Reached target Exit the Session. Nov 3 20:18:05.104995 systemd-logind[914]: Session 1 logged out. Waiting for processes to exit. Nov 3 20:18:05.106725 systemd[1]: user@500.service: Deactivated successfully. Nov 3 20:18:05.106899 systemd[1]: Stopped User Manager for UID 500. Nov 3 20:18:05.108738 systemd[1]: Stopping User Runtime Directory /run/user/500... Nov 3 20:18:05.113985 systemd[1]: run-user-500.mount: Deactivated successfully. Nov 3 20:18:05.114054 systemd[1]: Unmounted /run/user/500. Nov 3 20:18:05.115155 systemd[1]: user-runtime-dir@500.service: Deactivated successfully. Nov 3 20:18:05.115377 systemd[1]: Stopped User Runtime Directory /run/user/500. Nov 3 20:18:05.117075 systemd[1]: Removed slice User Slice of UID 500. Nov 3 20:18:05.119405 systemd[1]: Stopping D-Bus System Message Bus... Nov 3 20:18:05.124643 systemd[1]: Stopping Permit User Sessions... Nov 3 20:18:05.125882 systemd[1]: dbus.service: Deactivated successfully. Nov 3 20:18:05.126149 systemd[1]: Stopped D-Bus System Message Bus. Nov 3 20:18:05.127139 systemd[1]: systemd-logind.service: Deactivated successfully. Nov 3 20:18:05.127378 systemd[1]: Stopped User Login Management. Nov 3 20:18:05.128899 systemd[1]: systemd-user-sessions.service: Deactivated successfully. Nov 3 20:18:05.129135 systemd[1]: Stopped Permit User Sessions. Nov 3 20:18:05.129947 systemd[1]: Stopped target Basic System. Nov 3 20:18:05.130669 systemd[1]: Stopped target Network. Nov 3 20:18:05.131374 systemd[1]: Stopped target Path Units. Nov 3 20:18:05.132063 systemd[1]: motdgen.path: Deactivated successfully. Nov 3 20:18:05.133000 audit: BPF prog-id=60 op=UNLOAD Nov 3 20:18:05.134248 systemd[1]: Stopped Watch for update engine configuration changes. Nov 3 20:18:05.135974 kernel: audit: type=1334 audit(1762201085.133:370): prog-id=60 op=UNLOAD Nov 3 20:18:05.136161 systemd[1]: user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path: Deactivated successfully. Nov 3 20:18:05.137985 systemd[1]: Stopped Watch for a cloud-config at /var/lib/flatcar-install/user_data. Nov 3 20:18:05.139025 systemd[1]: Stopped target Remote File Systems. Nov 3 20:18:05.139799 systemd[1]: Stopped target Slice Units. Nov 3 20:18:05.140852 systemd[1]: Removed slice User and Session Slice. Nov 3 20:18:05.141755 systemd[1]: Stopped target Socket Units. Nov 3 20:18:05.142576 systemd[1]: dbus.socket: Deactivated successfully. Nov 3 20:18:05.142630 systemd[1]: Closed D-Bus System Message Bus Socket. Nov 3 20:18:05.143614 systemd[1]: docker.socket: Deactivated successfully. Nov 3 20:18:05.143851 systemd[1]: Closed Docker Socket for the API. Nov 3 20:18:05.144810 systemd[1]: Stopped target System Initialization. Nov 3 20:18:05.145707 systemd[1]: proc-sys-fs-binfmt_misc.automount: Deactivated successfully. Nov 3 20:18:05.145834 systemd[1]: Unset automount Arbitrary Executable File Formats File System Automount Point. Nov 3 20:18:05.146963 systemd[1]: Stopped target Local Verity Protected Volumes. Nov 3 20:18:05.148691 systemd[1]: Stopping Load Security Auditing Rules... Nov 3 20:18:05.150485 systemd[1]: Stopping Network Name Resolution... Nov 3 20:18:05.151386 systemd[1]: Stopping Network Time Synchronization... Nov 3 20:18:05.151610 auditctl[1347]: No rules Nov 3 20:18:05.152151 systemd[1]: systemd-update-done.service: Deactivated successfully. Nov 3 20:18:05.152224 systemd[1]: Stopped Update is Completed. Nov 3 20:18:05.152964 systemd[1]: ldconfig.service: Deactivated successfully. Nov 3 20:18:05.153030 systemd[1]: Stopped Rebuild Dynamic Linker Cache. Nov 3 20:18:05.153841 systemd[1]: systemd-hwdb-update.service: Deactivated successfully. Nov 3 20:18:05.153900 systemd[1]: Stopped Rebuild Hardware Database. Nov 3 20:18:05.154799 systemd[1]: systemd-journal-catalog-update.service: Deactivated successfully. Nov 3 20:18:05.154860 systemd[1]: Stopped Rebuild Journal Catalog. Nov 3 20:18:05.156485 systemd[1]: Stopping Record System Boot/Shutdown in UTMP... Nov 3 20:18:05.159053 systemd[1]: systemd-resolved.service: Deactivated successfully. Nov 3 20:18:05.159300 systemd[1]: Stopped Network Name Resolution. Nov 3 20:18:05.160395 systemd[1]: systemd-timesyncd.service: Deactivated successfully. Nov 3 20:18:05.160627 systemd[1]: Stopped Network Time Synchronization. Nov 3 20:18:05.161783 systemd[1]: audit-rules.service: Deactivated successfully. Nov 3 20:18:05.162029 systemd[1]: Stopped Load Security Auditing Rules. Nov 3 20:18:05.164476 systemd[1]: Stopping Network Configuration... Nov 3 20:18:05.174000 systemd-networkd[837]: eth0: DHCPv6 lease lost Nov 3 20:18:05.173000 audit: BPF prog-id=67 op=UNLOAD Nov 3 20:18:05.175193 systemd[1]: systemd-networkd.service: Deactivated successfully. Nov 3 20:18:05.175439 systemd[1]: Stopped Network Configuration. Nov 3 20:18:05.173000 audit: BPF prog-id=56 op=UNLOAD Nov 3 20:18:05.176974 kernel: audit: type=1334 audit(1762201085.173:371): prog-id=67 op=UNLOAD Nov 3 20:18:05.177050 kernel: audit: type=1334 audit(1762201085.173:372): prog-id=56 op=UNLOAD Nov 3 20:18:05.177765 systemd[1]: systemd-update-utmp.service: Deactivated successfully. Nov 3 20:18:05.178014 systemd[1]: Stopped Record System Boot/Shutdown in UTMP. Nov 3 20:18:05.179693 systemd[1]: systemd-networkd.socket: Deactivated successfully. Nov 3 20:18:05.179779 systemd[1]: Closed Network Service Netlink Socket. Nov 3 20:18:05.180706 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 3 20:18:05.180764 systemd[1]: Stopped Apply Kernel Variables. Nov 3 20:18:05.181585 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Nov 3 20:18:05.181641 systemd[1]: Stopped Create Volatile Files and Directories. Nov 3 20:18:05.182611 systemd[1]: Stopped target Local File Systems. Nov 3 20:18:05.184362 systemd[1]: Unmounting /etc/flatcar/update-payload-key.pub.pem... Nov 3 20:18:05.185857 systemd[1]: Unmounting External Media Directory... Nov 3 20:18:05.187549 systemd[1]: Unmounting /run/credentials/systemd-sysusers.service... Nov 3 20:18:05.187000 audit: BPF prog-id=66 op=UNLOAD Nov 3 20:18:05.189967 kernel: audit: type=1334 audit(1762201085.187:373): prog-id=66 op=UNLOAD Nov 3 20:18:05.190444 systemd[1]: Unmounting /run/torcx/unpack... Nov 3 20:18:05.191693 systemd[1]: Unmounting Temporary Directory /tmp... Nov 3 20:18:05.193054 systemd[1]: Unmounting /usr/share/oem... Nov 3 20:18:05.194634 systemd[1]: Unmounting /usr/share/update_engine/update-payload-key.pub.pem... Nov 3 20:18:05.197387 systemd[1]: etc-flatcar-update\x2dpayload\x2dkey.pub.pem.mount: Deactivated successfully. Nov 3 20:18:05.197642 systemd[1]: Unmounted /etc/flatcar/update-payload-key.pub.pem. Nov 3 20:18:05.199130 systemd[1]: media.mount: Deactivated successfully. Nov 3 20:18:05.199381 systemd[1]: Unmounted External Media Directory. Nov 3 20:18:05.200515 systemd[1]: run-credentials-systemd\x2dsysusers.service.mount: Deactivated successfully. Nov 3 20:18:05.200755 systemd[1]: Unmounted /run/credentials/systemd-sysusers.service. Nov 3 20:18:05.202106 systemd[1]: tmp.mount: Deactivated successfully. Nov 3 20:18:05.202355 systemd[1]: Unmounted Temporary Directory /tmp. Nov 3 20:18:05.203358 systemd[1]: usr-share-oem.mount: Deactivated successfully. Nov 3 20:18:05.203614 systemd[1]: Unmounted /usr/share/oem. Nov 3 20:18:05.204567 systemd[1]: usr-share-update_engine-update\x2dpayload\x2dkey.pub.pem.mount: Deactivated successfully. Nov 3 20:18:05.204806 systemd[1]: Unmounted /usr/share/update_engine/update-payload-key.pub.pem. Nov 3 20:18:05.206262 systemd[1]: systemd-fsck@dev-disk-by\x2dlabel-OEM.service: Deactivated successfully. Nov 3 20:18:05.206338 systemd[1]: Stopped File System Check on /dev/disk/by-label/OEM. Nov 3 20:18:05.207752 systemd[1]: Removed slice Slice /system/systemd-fsck. Nov 3 20:18:05.211732 systemd[1]: run-torcx-unpack.mount: Deactivated successfully. Nov 3 20:18:05.211995 systemd[1]: Unmounted /run/torcx/unpack. Nov 3 20:18:05.212983 systemd[1]: Stopped target Preparation for Local File Systems. Nov 3 20:18:05.213992 systemd[1]: Stopped target Swaps. Nov 3 20:18:05.214677 systemd[1]: Reached target Unmount All Filesystems. Nov 3 20:18:05.215717 systemd[1]: lvm2-activation.service: Deactivated successfully. Nov 3 20:18:05.215798 systemd[1]: Stopped Activation of LVM2 logical volumes. Nov 3 20:18:05.216798 systemd[1]: Stopped target Local Encrypted Volumes. Nov 3 20:18:05.217732 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 3 20:18:05.217793 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Nov 3 20:18:05.218950 systemd[1]: systemd-ask-password-wall.path: Deactivated successfully. Nov 3 20:18:05.222996 systemd[1]: Stopped Forward Password Requests to Wall Directory Watch. Nov 3 20:18:05.225042 systemd[1]: lvm2-activation-early.service: Deactivated successfully. Nov 3 20:18:05.225136 systemd[1]: Stopped Activation of LVM2 logical volumes. Nov 3 20:18:05.226076 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 3 20:18:05.226152 systemd[1]: Stopped Create Static Device Nodes in /dev. Nov 3 20:18:05.227095 systemd[1]: systemd-sysusers.service: Deactivated successfully. Nov 3 20:18:05.227163 systemd[1]: Stopped Create System Users. Nov 3 20:18:05.227958 systemd[1]: systemd-remount-fs.service: Deactivated successfully. Nov 3 20:18:05.228015 systemd[1]: Stopped Remount Root and Kernel File Systems. Nov 3 20:18:05.228965 systemd[1]: Reached target System Shutdown. Nov 3 20:18:05.229740 systemd[1]: Reached target Late Shutdown Services. Nov 3 20:18:05.230720 systemd[1]: systemd-reboot.service: Deactivated successfully. Nov 3 20:18:05.230765 systemd[1]: Finished System Reboot. Nov 3 20:18:05.231527 systemd[1]: Reached target System Reboot. Nov 3 20:18:05.232305 systemd[1]: Shutting down. Nov 3 20:18:05.233000 audit: BPF prog-id=59 op=UNLOAD Nov 3 20:18:05.234000 audit: BPF prog-id=58 op=UNLOAD Nov 3 20:18:05.234000 audit: BPF prog-id=57 op=UNLOAD Nov 3 20:18:05.235955 kernel: audit: type=1334 audit(1762201085.233:374): prog-id=59 op=UNLOAD Nov 3 20:18:05.236000 audit: BPF prog-id=62 op=UNLOAD Nov 3 20:18:05.236000 audit: BPF prog-id=61 op=UNLOAD Nov 3 20:18:05.238000 audit: BPF prog-id=65 op=UNLOAD Nov 3 20:18:05.238000 audit: BPF prog-id=64 op=UNLOAD Nov 3 20:18:05.238000 audit: BPF prog-id=63 op=UNLOAD Nov 3 20:18:05.269976 systemd-shutdown[1]: Syncing filesystems and block devices. Nov 3 20:18:05.278962 systemd-shutdown[1]: Sending SIGTERM to remaining processes... Nov 3 20:18:05.280499 systemd-journald[800]: Journal stopped -- Reboot -- Nov 3 20:18:13.675165 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Nov 3 20:18:13.675181 kernel: Linux version 5.10.107-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 Tue Mar 22 19:39:09 -00 2022 Nov 3 20:18:13.675190 kernel: efi: EFI v2.70 by EDK II Nov 3 20:18:13.675195 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb82c018 ACPI 2.0=0xdbfd0018 RNG=0xdcfdd318 MEMRESERVE=0xdb834c18 Nov 3 20:18:13.675199 kernel: efi: seeding entropy pool Nov 3 20:18:13.675204 kernel: efi: memattr: Unexpected EFI Memory Attributes table version 2 Nov 3 20:18:13.675210 kernel: ACPI: Early table checksum verification disabled Nov 3 20:18:13.675215 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Nov 3 20:18:13.675220 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Nov 3 20:18:13.675225 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Nov 3 20:18:13.675231 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 3 20:18:13.675236 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Nov 3 20:18:13.675241 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 3 20:18:13.675246 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 3 20:18:13.675252 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 3 20:18:13.675257 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 3 20:18:13.675264 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Nov 3 20:18:13.675269 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Nov 3 20:18:13.675274 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Nov 3 20:18:13.675280 kernel: ACPI: NUMA: Failed to initialise from firmware Nov 3 20:18:13.675285 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Nov 3 20:18:13.675294 kernel: NUMA: NODE_DATA [mem 0xdc95a900-0xdc95ffff] Nov 3 20:18:13.675300 kernel: Zone ranges: Nov 3 20:18:13.675305 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Nov 3 20:18:13.675310 kernel: DMA32 empty Nov 3 20:18:13.675322 kernel: Normal empty Nov 3 20:18:13.675327 kernel: Movable zone start for each node Nov 3 20:18:13.675335 kernel: Early memory node ranges Nov 3 20:18:13.675340 kernel: node 0: [mem 0x0000000040000000-0x00000000dc09ffff] Nov 3 20:18:13.675345 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Nov 3 20:18:13.675350 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Nov 3 20:18:13.675356 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Nov 3 20:18:13.675361 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Nov 3 20:18:13.675366 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Nov 3 20:18:13.675372 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Nov 3 20:18:13.675377 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Nov 3 20:18:13.675382 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Nov 3 20:18:13.675387 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Nov 3 20:18:13.675393 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Nov 3 20:18:13.675398 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Nov 3 20:18:13.675406 kernel: On node 0 totalpages: 643072 Nov 3 20:18:13.675412 kernel: DMA zone: 10048 pages used for memmap Nov 3 20:18:13.675418 kernel: DMA zone: 0 pages reserved Nov 3 20:18:13.675423 kernel: DMA zone: 643072 pages, LIFO batch:63 Nov 3 20:18:13.675428 kernel: psci: probing for conduit method from ACPI. Nov 3 20:18:13.675437 kernel: psci: PSCIv1.1 detected in firmware. Nov 3 20:18:13.675443 kernel: psci: Using standard PSCI v0.2 function IDs Nov 3 20:18:13.675449 kernel: psci: Trusted OS migration not required Nov 3 20:18:13.675455 kernel: psci: SMC Calling Convention v1.1 Nov 3 20:18:13.675460 kernel: ACPI: SRAT not present Nov 3 20:18:13.675466 kernel: percpu: Embedded 32 pages/cpu s93720 r8192 d29160 u131072 Nov 3 20:18:13.675472 kernel: pcpu-alloc: s93720 r8192 d29160 u131072 alloc=32*4096 Nov 3 20:18:13.675481 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Nov 3 20:18:13.675487 kernel: Detected PIPT I-cache on CPU0 Nov 3 20:18:13.675493 kernel: CPU features: detected: GIC system register CPU interface Nov 3 20:18:13.675499 kernel: CPU features: kernel page table isolation forced ON by KASLR Nov 3 20:18:13.675506 kernel: CPU features: detected: Kernel page table isolation (KPTI) Nov 3 20:18:13.675512 kernel: CPU features: detected: Hardware dirty bit management Nov 3 20:18:13.675517 kernel: CPU features: detected: Spectre-v4 Nov 3 20:18:13.675524 kernel: CPU features: detected: ARM erratum 1418040 Nov 3 20:18:13.675552 kernel: CPU features: detected: Spectre-BHB Nov 3 20:18:13.675558 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Nov 3 20:18:13.675563 kernel: Policy zone: DMA Nov 3 20:18:13.675570 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 acpi=force verity.usrhash=add5f6239860d533a5bdd88b1ecacfa095fc20febf51ab00ef758dd688c5dc20 Nov 3 20:18:13.675576 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Nov 3 20:18:13.675581 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Nov 3 20:18:13.675587 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 3 20:18:13.675593 kernel: Memory: 2452100K/2572288K available (9344K kernel code, 2274K rwdata, 6844K rodata, 34240K init, 769K bss, 120188K reserved, 0K cma-reserved) Nov 3 20:18:13.675599 kernel: random: get_random_u64 called from __kmem_cache_create+0x28/0x4a0 with crng_init=0 Nov 3 20:18:13.675604 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Nov 3 20:18:13.675611 kernel: rcu: Preemptible hierarchical RCU implementation. Nov 3 20:18:13.675617 kernel: rcu: RCU event tracing is enabled. Nov 3 20:18:13.675623 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Nov 3 20:18:13.675629 kernel: Trampoline variant of Tasks RCU enabled. Nov 3 20:18:13.675635 kernel: Tracing variant of Tasks RCU enabled. Nov 3 20:18:13.675641 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Nov 3 20:18:13.675646 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Nov 3 20:18:13.675652 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Nov 3 20:18:13.675657 kernel: GICv3: 256 SPIs implemented Nov 3 20:18:13.675663 kernel: GICv3: 0 Extended SPIs implemented Nov 3 20:18:13.675668 kernel: GICv3: Distributor has no Range Selector support Nov 3 20:18:13.675674 kernel: GICv3: 16 PPIs implemented Nov 3 20:18:13.675681 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Nov 3 20:18:13.675686 kernel: ACPI: SRAT not present Nov 3 20:18:13.675691 kernel: ITS [mem 0x08080000-0x0809ffff] Nov 3 20:18:13.675697 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Nov 3 20:18:13.675703 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Nov 3 20:18:13.675709 kernel: GICv3: using LPI property table @0x00000000400d0000 Nov 3 20:18:13.675714 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Nov 3 20:18:13.675720 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 3 20:18:13.675725 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Nov 3 20:18:13.675731 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Nov 3 20:18:13.675737 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Nov 3 20:18:13.675743 kernel: arm-pv: using stolen time PV Nov 3 20:18:13.675749 kernel: Console: colour dummy device 80x25 Nov 3 20:18:13.675755 kernel: ACPI: Core revision 20200925 Nov 3 20:18:13.675761 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Nov 3 20:18:13.675767 kernel: pid_max: default: 32768 minimum: 301 Nov 3 20:18:13.675772 kernel: LSM: Security Framework initializing Nov 3 20:18:13.675778 kernel: SELinux: Initializing. Nov 3 20:18:13.675784 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 3 20:18:13.675790 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 3 20:18:13.675795 kernel: rcu: Hierarchical SRCU implementation. Nov 3 20:18:13.675802 kernel: Platform MSI: ITS@0x8080000 domain created Nov 3 20:18:13.675808 kernel: PCI/MSI: ITS@0x8080000 domain created Nov 3 20:18:13.675813 kernel: Remapping and enabling EFI services. Nov 3 20:18:13.675819 kernel: smp: Bringing up secondary CPUs ... Nov 3 20:18:13.675825 kernel: Detected PIPT I-cache on CPU1 Nov 3 20:18:13.675830 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Nov 3 20:18:13.675836 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Nov 3 20:18:13.675842 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 3 20:18:13.675847 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Nov 3 20:18:13.675853 kernel: Detected PIPT I-cache on CPU2 Nov 3 20:18:13.675860 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Nov 3 20:18:13.675866 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Nov 3 20:18:13.675872 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 3 20:18:13.675878 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Nov 3 20:18:13.675883 kernel: Detected PIPT I-cache on CPU3 Nov 3 20:18:13.675893 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Nov 3 20:18:13.675899 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Nov 3 20:18:13.675906 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 3 20:18:13.675912 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Nov 3 20:18:13.675918 kernel: smp: Brought up 1 node, 4 CPUs Nov 3 20:18:13.675923 kernel: SMP: Total of 4 processors activated. Nov 3 20:18:13.675929 kernel: CPU features: detected: Privileged Access Never Nov 3 20:18:13.675935 kernel: CPU features: detected: LSE atomic instructions Nov 3 20:18:13.675941 kernel: CPU features: detected: User Access Override Nov 3 20:18:13.675947 kernel: CPU features: detected: 32-bit EL0 Support Nov 3 20:18:13.675953 kernel: CPU features: detected: Common not Private translations Nov 3 20:18:13.675959 kernel: CPU features: detected: RAS Extension Support Nov 3 20:18:13.675966 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Nov 3 20:18:13.675972 kernel: CPU features: detected: CRC32 instructions Nov 3 20:18:13.675978 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Nov 3 20:18:13.675984 kernel: CPU: All CPU(s) started at EL1 Nov 3 20:18:13.675990 kernel: alternatives: patching kernel code Nov 3 20:18:13.675997 kernel: devtmpfs: initialized Nov 3 20:18:13.676003 kernel: KASLR enabled Nov 3 20:18:13.676009 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Nov 3 20:18:13.676015 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Nov 3 20:18:13.676021 kernel: pinctrl core: initialized pinctrl subsystem Nov 3 20:18:13.676027 kernel: SMBIOS 3.0.0 present. Nov 3 20:18:13.676033 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Nov 3 20:18:13.676038 kernel: NET: Registered protocol family 16 Nov 3 20:18:13.676045 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Nov 3 20:18:13.676051 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Nov 3 20:18:13.676058 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Nov 3 20:18:13.676064 kernel: audit: initializing netlink subsys (disabled) Nov 3 20:18:13.676070 kernel: audit: type=2000 audit(0.030:1): state=initialized audit_enabled=0 res=1 Nov 3 20:18:13.676076 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 3 20:18:13.676082 kernel: cpuidle: using governor menu Nov 3 20:18:13.676088 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Nov 3 20:18:13.676094 kernel: ASID allocator initialised with 32768 entries Nov 3 20:18:13.676100 kernel: ACPI: bus type PCI registered Nov 3 20:18:13.676106 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 3 20:18:13.676113 kernel: Serial: AMBA PL011 UART driver Nov 3 20:18:13.676119 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Nov 3 20:18:13.676125 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Nov 3 20:18:13.676131 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Nov 3 20:18:13.676137 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Nov 3 20:18:13.676143 kernel: cryptd: max_cpu_qlen set to 1000 Nov 3 20:18:13.676149 kernel: ACPI: Added _OSI(Module Device) Nov 3 20:18:13.676155 kernel: ACPI: Added _OSI(Processor Device) Nov 3 20:18:13.676161 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Nov 3 20:18:13.676168 kernel: ACPI: Added _OSI(Processor Aggregator Device) Nov 3 20:18:13.676174 kernel: ACPI: Added _OSI(Linux-Dell-Video) Nov 3 20:18:13.676180 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Nov 3 20:18:13.676186 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Nov 3 20:18:13.676192 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Nov 3 20:18:13.676197 kernel: ACPI: Interpreter enabled Nov 3 20:18:13.676203 kernel: ACPI: Using GIC for interrupt routing Nov 3 20:18:13.676209 kernel: ACPI: MCFG table detected, 1 entries Nov 3 20:18:13.676215 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Nov 3 20:18:13.676221 kernel: printk: console [ttyAMA0] enabled Nov 3 20:18:13.676229 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Nov 3 20:18:13.676411 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Nov 3 20:18:13.676490 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Nov 3 20:18:13.676559 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Nov 3 20:18:13.676614 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Nov 3 20:18:13.676665 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Nov 3 20:18:13.676673 kernel: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Nov 3 20:18:13.676682 kernel: PCI host bridge to bus 0000:00 Nov 3 20:18:13.676742 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Nov 3 20:18:13.676794 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Nov 3 20:18:13.676867 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Nov 3 20:18:13.677486 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Nov 3 20:18:13.677599 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Nov 3 20:18:13.677664 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Nov 3 20:18:13.677724 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Nov 3 20:18:13.677779 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Nov 3 20:18:13.677833 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Nov 3 20:18:13.677886 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Nov 3 20:18:13.677939 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Nov 3 20:18:13.677993 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Nov 3 20:18:13.678042 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Nov 3 20:18:13.678091 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Nov 3 20:18:13.678138 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Nov 3 20:18:13.678146 kernel: ACPI: PCI Interrupt Link [GSI0] (IRQs *35) Nov 3 20:18:13.678152 kernel: ACPI: PCI Interrupt Link [GSI1] (IRQs *36) Nov 3 20:18:13.678159 kernel: ACPI: PCI Interrupt Link [GSI2] (IRQs *37) Nov 3 20:18:13.678165 kernel: ACPI: PCI Interrupt Link [GSI3] (IRQs *38) Nov 3 20:18:13.678171 kernel: vgaarb: loaded Nov 3 20:18:13.678177 kernel: Registered efivars operations Nov 3 20:18:13.678183 kernel: clocksource: Switched to clocksource arch_sys_counter Nov 3 20:18:13.678190 kernel: VFS: Disk quotas dquot_6.6.0 Nov 3 20:18:13.678197 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 3 20:18:13.678203 kernel: pnp: PnP ACPI init Nov 3 20:18:13.678264 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Nov 3 20:18:13.678321 kernel: system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active) Nov 3 20:18:13.678331 kernel: pnp: PnP ACPI: found 1 devices Nov 3 20:18:13.678337 kernel: NET: Registered protocol family 2 Nov 3 20:18:13.678343 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Nov 3 20:18:13.678352 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Nov 3 20:18:13.678358 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Nov 3 20:18:13.678365 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Nov 3 20:18:13.678371 kernel: TCP: Hash tables configured (established 32768 bind 32768) Nov 3 20:18:13.678377 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 3 20:18:13.678383 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 3 20:18:13.678389 kernel: NET: Registered protocol family 1 Nov 3 20:18:13.678395 kernel: PCI: CLS 0 bytes, default 64 Nov 3 20:18:13.678402 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Nov 3 20:18:13.678409 kernel: kvm [1]: HYP mode not available Nov 3 20:18:13.678415 kernel: Initialise system trusted keyrings Nov 3 20:18:13.678421 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Nov 3 20:18:13.678427 kernel: Key type asymmetric registered Nov 3 20:18:13.678433 kernel: Asymmetric key parser 'x509' registered Nov 3 20:18:13.678439 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Nov 3 20:18:13.678445 kernel: io scheduler mq-deadline registered Nov 3 20:18:13.678451 kernel: io scheduler kyber registered Nov 3 20:18:13.678457 kernel: io scheduler bfq registered Nov 3 20:18:13.678464 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Nov 3 20:18:13.678470 kernel: ACPI: Power Button [PWRB] Nov 3 20:18:13.678476 kernel: PCI Interrupt Link [GSI1] enabled at IRQ 36 Nov 3 20:18:13.678545 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Nov 3 20:18:13.678563 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Nov 3 20:18:13.678570 kernel: msm_serial: driver initialized Nov 3 20:18:13.678576 kernel: random: fast init done Nov 3 20:18:13.678582 kernel: wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information. Nov 3 20:18:13.678588 kernel: random: crng init done Nov 3 20:18:13.678594 kernel: wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld . All Rights Reserved. Nov 3 20:18:13.678602 kernel: thunder_xcv, ver 1.0 Nov 3 20:18:13.678608 kernel: thunder_bgx, ver 1.0 Nov 3 20:18:13.678614 kernel: nicpf, ver 1.0 Nov 3 20:18:13.678620 kernel: nicvf, ver 1.0 Nov 3 20:18:13.678689 kernel: rtc-efi rtc-efi.0: registered as rtc0 Nov 3 20:18:13.678740 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-11-03T20:18:13 UTC (1762201093) Nov 3 20:18:13.678748 kernel: hid: raw HID events driver (C) Jiri Kosina Nov 3 20:18:13.678755 kernel: NET: Registered protocol family 10 Nov 3 20:18:13.678762 kernel: Segment Routing with IPv6 Nov 3 20:18:13.678768 kernel: NET: Registered protocol family 17 Nov 3 20:18:13.678774 kernel: Key type dns_resolver registered Nov 3 20:18:13.678780 kernel: registered taskstats version 1 Nov 3 20:18:13.678786 kernel: Loading compiled-in X.509 certificates Nov 3 20:18:13.678792 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.10.107-flatcar: acb58ed5bddf18757940d1f794ad1dcb34e85378' Nov 3 20:18:13.678798 kernel: Key type ._fscrypt registered Nov 3 20:18:13.678804 kernel: Key type .fscrypt registered Nov 3 20:18:13.678810 kernel: Key type fscrypt-provisioning registered Nov 3 20:18:13.678818 kernel: ima: No TPM chip found, activating TPM-bypass! Nov 3 20:18:13.678824 kernel: ima: Allocated hash algorithm: sha1 Nov 3 20:18:13.678830 kernel: ima: No architecture policies found Nov 3 20:18:13.678837 kernel: Freeing unused kernel memory: 34240K Nov 3 20:18:13.678843 kernel: Run /init as init process Nov 3 20:18:13.678849 kernel: with arguments: Nov 3 20:18:13.678855 kernel: /init Nov 3 20:18:13.678861 kernel: with environment: Nov 3 20:18:13.678866 kernel: HOME=/ Nov 3 20:18:13.678872 kernel: TERM=linux Nov 3 20:18:13.678879 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Nov 3 20:18:13.678888 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) Nov 3 20:18:13.678896 systemd[1]: Detected virtualization kvm. Nov 3 20:18:13.678903 systemd[1]: Detected architecture arm64. Nov 3 20:18:13.678909 systemd[1]: Running in initial RAM disk. Nov 3 20:18:13.678916 systemd[1]: No hostname configured, using default hostname. Nov 3 20:18:13.678922 systemd[1]: Hostname set to . Nov 3 20:18:13.678930 systemd[1]: Initializing machine ID from VM UUID. Nov 3 20:18:13.678938 systemd[1]: Queued start job for default target Initrd Default Target. Nov 3 20:18:13.678945 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Nov 3 20:18:13.678952 systemd[1]: Reached target Local Encrypted Volumes. Nov 3 20:18:13.678958 systemd[1]: Reached target Path Units. Nov 3 20:18:13.678965 systemd[1]: Reached target Slice Units. Nov 3 20:18:13.678971 systemd[1]: Reached target Swaps. Nov 3 20:18:13.678977 systemd[1]: Reached target Timer Units. Nov 3 20:18:13.678985 systemd[1]: Listening on Open-iSCSI iscsid Socket. Nov 3 20:18:13.678992 systemd[1]: Listening on Open-iSCSI iscsiuio Socket. Nov 3 20:18:13.678999 systemd[1]: Listening on Journal Audit Socket. Nov 3 20:18:13.679005 systemd[1]: Listening on Journal Socket (/dev/log). Nov 3 20:18:13.679011 systemd[1]: Listening on Journal Socket. Nov 3 20:18:13.679018 systemd[1]: Listening on udev Control Socket. Nov 3 20:18:13.679025 systemd[1]: Listening on udev Kernel Socket. Nov 3 20:18:13.679031 systemd[1]: Reached target Socket Units. Nov 3 20:18:13.679037 systemd[1]: Starting iSCSI UserSpace I/O driver... Nov 3 20:18:13.679045 systemd[1]: Starting Create List of Static Device Nodes... Nov 3 20:18:13.679051 systemd[1]: Started Hardware RNG Entropy Gatherer Daemon. Nov 3 20:18:13.679058 systemd[1]: Starting Journal Service... Nov 3 20:18:13.679064 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Nov 3 20:18:13.679071 systemd[1]: Starting Apply Kernel Variables... Nov 3 20:18:13.679077 systemd[1]: Starting Setup Virtual Console... Nov 3 20:18:13.679083 kernel: SCSI subsystem initialized Nov 3 20:18:13.679089 systemd[1]: Started iSCSI UserSpace I/O driver. Nov 3 20:18:13.679096 systemd[1]: Finished Create List of Static Device Nodes. Nov 3 20:18:13.679102 kernel: Loading iSCSI transport class v2.0-870. Nov 3 20:18:13.679110 systemd[1]: Finished Setup Virtual Console. Nov 3 20:18:13.679116 systemd[1]: Starting dracut ask for additional cmdline parameters... Nov 3 20:18:13.679124 systemd-journald[270]: Journal started Nov 3 20:18:13.679159 systemd-journald[270]: Runtime Journal (/run/log/journal/6083db5279854a16a861cc12fe8ac4d7) is 6.0M, max 48.5M, 42.4M free. Nov 3 20:18:13.682842 systemd[1]: Starting Create Static Device Nodes in /dev... Nov 3 20:18:13.684590 systemd[1]: Started Journal Service. Nov 3 20:18:13.684650 systemd[1]: Finished Apply Kernel Variables. Nov 3 20:18:13.685573 systemd[1]: Finished Create Static Device Nodes in /dev. Nov 3 20:18:13.695878 systemd[1]: Finished dracut ask for additional cmdline parameters. Nov 3 20:18:13.697509 systemd[1]: Starting dracut cmdline hook... Nov 3 20:18:13.705950 dracut-cmdline[292]: dracut-dracut-053 Nov 3 20:18:13.708175 dracut-cmdline[292]: 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 acpi=force verity.usrhash=add5f6239860d533a5bdd88b1ecacfa095fc20febf51ab00ef758dd688c5dc20 Nov 3 20:18:13.764606 kernel: iscsi: registered transport (tcp) Nov 3 20:18:13.775891 kernel: iscsi: registered transport (qla4xxx) Nov 3 20:18:13.775945 kernel: QLogic iSCSI HBA Driver Nov 3 20:18:13.785603 systemd[1]: Finished dracut cmdline hook. Nov 3 20:18:13.787080 systemd[1]: Starting dracut pre-udev hook... Nov 3 20:18:13.788292 systemd[1]: Starting Open-iSCSI... Nov 3 20:18:13.790596 iscsid[378]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Nov 3 20:18:13.790596 iscsid[378]: 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]. Nov 3 20:18:13.790596 iscsid[378]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Nov 3 20:18:13.790596 iscsid[378]: If using hardware iscsi like qla4xxx this message can be ignored. Nov 3 20:18:13.800388 iscsid[378]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Nov 3 20:18:13.800388 iscsid[378]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Nov 3 20:18:13.804045 kernel: device-mapper: uevent: version 1.0.3 Nov 3 20:18:13.804068 kernel: device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com Nov 3 20:18:13.794124 systemd[1]: Started Open-iSCSI. Nov 3 20:18:13.836557 kernel: raid6: neonx8 gen() 13797 MB/s Nov 3 20:18:13.853554 kernel: raid6: neonx8 xor() 10768 MB/s Nov 3 20:18:13.870551 kernel: raid6: neonx4 gen() 13601 MB/s Nov 3 20:18:13.887552 kernel: raid6: neonx4 xor() 11591 MB/s Nov 3 20:18:13.904548 kernel: raid6: neonx2 gen() 13020 MB/s Nov 3 20:18:13.921553 kernel: raid6: neonx2 xor() 9895 MB/s Nov 3 20:18:13.938554 kernel: raid6: neonx1 gen() 10980 MB/s Nov 3 20:18:13.955564 kernel: raid6: neonx1 xor() 8841 MB/s Nov 3 20:18:13.972563 kernel: raid6: int64x8 gen() 6834 MB/s Nov 3 20:18:13.989554 kernel: raid6: int64x8 xor() 3799 MB/s Nov 3 20:18:14.006553 kernel: raid6: int64x4 gen() 6902 MB/s Nov 3 20:18:14.023552 kernel: raid6: int64x4 xor() 3835 MB/s Nov 3 20:18:14.040554 kernel: raid6: int64x2 gen() 6191 MB/s Nov 3 20:18:14.057554 kernel: raid6: int64x2 xor() 3324 MB/s Nov 3 20:18:14.074584 kernel: raid6: int64x1 gen() 5011 MB/s Nov 3 20:18:14.091698 kernel: raid6: int64x1 xor() 2600 MB/s Nov 3 20:18:14.091725 kernel: raid6: using algorithm neonx8 gen() 13797 MB/s Nov 3 20:18:14.091741 kernel: raid6: .... xor() 10768 MB/s, rmw enabled Nov 3 20:18:14.092827 kernel: raid6: using neon recovery algorithm Nov 3 20:18:14.102550 kernel: xor: measuring software checksum speed Nov 3 20:18:14.103552 kernel: 8regs : 17163 MB/sec Nov 3 20:18:14.104552 kernel: 32regs : 20770 MB/sec Nov 3 20:18:14.105796 kernel: arm64_neon : 28375 MB/sec Nov 3 20:18:14.105806 kernel: xor: using function: arm64_neon (28375 MB/sec) Nov 3 20:18:14.150562 kernel: Btrfs loaded, crc32c=crc32c-generic Nov 3 20:18:14.160443 systemd[1]: Finished dracut pre-udev hook. Nov 3 20:18:14.161000 audit: BPF prog-id=6 op=LOAD Nov 3 20:18:14.161000 audit: BPF prog-id=7 op=LOAD Nov 3 20:18:14.161000 audit: BPF prog-id=8 op=LOAD Nov 3 20:18:14.162517 systemd[1]: Starting Rule-based Manager for Device Events and Files... Nov 3 20:18:14.165522 kernel: audit: type=1334 audit(1762201094.161:2): prog-id=6 op=LOAD Nov 3 20:18:14.165556 kernel: audit: type=1334 audit(1762201094.161:3): prog-id=7 op=LOAD Nov 3 20:18:14.165570 kernel: audit: type=1334 audit(1762201094.161:4): prog-id=8 op=LOAD Nov 3 20:18:14.175694 systemd-udevd[410]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Nov 3 20:18:14.177347 systemd[1]: Started Rule-based Manager for Device Events and Files. Nov 3 20:18:14.179116 systemd[1]: Starting dracut pre-trigger hook... Nov 3 20:18:14.190129 dracut-pre-trigger[418]: rd.md=0: removing MD RAID activation Nov 3 20:18:14.211628 systemd[1]: Finished dracut pre-trigger hook. Nov 3 20:18:14.213036 systemd[1]: Starting Coldplug All udev Devices... Nov 3 20:18:14.222096 systemd-udevd[410]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Nov 3 20:18:14.242219 systemd[1]: Finished Coldplug All udev Devices. Nov 3 20:18:14.243769 systemd[1]: Starting dracut initqueue hook... Nov 3 20:18:14.255284 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Nov 3 20:18:14.255433 kernel: vda: detected capacity change from 0 to 4756340736 Nov 3 20:18:14.264553 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 3 20:18:14.272898 systemd-udevd[468]: Using default interface naming scheme 'v249'. Nov 3 20:18:14.284546 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by systemd-udevd (459) Nov 3 20:18:14.284626 systemd[1]: Found device /dev/disk/by-partlabel/USR-A. Nov 3 20:18:14.286558 systemd[1]: Found device /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Nov 3 20:18:14.289410 systemd[1]: Found device /dev/disk/by-label/ROOT. Nov 3 20:18:14.297974 systemd[1]: Finished dracut initqueue hook. Nov 3 20:18:14.298834 systemd[1]: Reached target Initrd Root Device. Nov 3 20:18:14.300176 systemd[1]: Reached target Preparation for Remote File Systems. Nov 3 20:18:14.301763 systemd[1]: Reached target Remote Encrypted Volumes. Nov 3 20:18:14.303349 systemd[1]: Reached target Remote File Systems. Nov 3 20:18:14.305461 systemd[1]: Starting Generate new UUID for disk GPT if necessary... Nov 3 20:18:14.307089 systemd[1]: Starting dracut pre-mount hook... Nov 3 20:18:14.308297 systemd[1]: disk-uuid.service: Deactivated successfully. Nov 3 20:18:14.308418 systemd[1]: Finished Generate new UUID for disk GPT if necessary. Nov 3 20:18:14.309770 systemd[1]: Reached target Preparation for Local File Systems. Nov 3 20:18:14.312024 systemd[1]: Starting Verity Setup for /dev/mapper/usr... Nov 3 20:18:14.315903 systemd[1]: Finished dracut pre-mount hook. Nov 3 20:18:14.317354 systemd[1]: Starting File System Check on /dev/disk/by-label/ROOT... Nov 3 20:18:14.323991 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Nov 3 20:18:14.326659 systemd-fsck[505]: ROOT: clean, 640/553792 files, 39961/553472 blocks Nov 3 20:18:14.329599 systemd[1]: Finished File System Check on /dev/disk/by-label/ROOT. Nov 3 20:18:14.331663 systemd[1]: Mounting /sysroot... Nov 3 20:18:14.340775 systemd[1]: Found device /dev/mapper/usr. Nov 3 20:18:14.342634 systemd[1]: Starting File System Check on /dev/mapper/usr... Nov 3 20:18:14.344786 systemd[1]: Finished Verity Setup for /dev/mapper/usr. Nov 3 20:18:14.352042 systemd-fsck[516]: fsck.ext4: Operation not permitted while trying to open /dev/mapper/usr Nov 3 20:18:14.352042 systemd-fsck[516]: You must have r/w access to the filesystem or be root Nov 3 20:18:14.352240 systemd-fsck[512]: fsck failed with exit status 8. Nov 3 20:18:14.352244 systemd-fsck[512]: Ignoring error. Nov 3 20:18:14.352789 systemd[1]: Finished File System Check on /dev/mapper/usr. Nov 3 20:18:14.354887 systemd[1]: Mounting /sysusr/usr... Nov 3 20:18:14.373899 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null) Nov 3 20:18:14.373930 kernel: ext4 filesystem being mounted at /sysroot supports timestamps until 2038 (0x7fffffff) Nov 3 20:18:14.375015 systemd[1]: Mounted /sysroot. Nov 3 20:18:14.375737 systemd[1]: Reached target Initrd Root File System. Nov 3 20:18:14.377507 systemd[1]: Starting Reload Configuration from the Real Root... Nov 3 20:18:14.381593 systemd[1]: Reloading. Nov 3 20:18:14.385540 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: (null) Nov 3 20:18:14.388000 audit: BPF prog-id=6 op=UNLOAD Nov 3 20:18:14.388000 audit: BPF prog-id=3 op=UNLOAD Nov 3 20:18:14.391285 kernel: audit: type=1334 audit(1762201094.388:5): prog-id=6 op=UNLOAD Nov 3 20:18:14.391322 kernel: audit: type=1334 audit(1762201094.388:6): prog-id=3 op=UNLOAD Nov 3 20:18:14.463000 audit: BPF prog-id=9 op=LOAD Nov 3 20:18:14.464000 audit: BPF prog-id=10 op=LOAD Nov 3 20:18:14.465788 kernel: audit: type=1334 audit(1762201094.463:7): prog-id=9 op=LOAD Nov 3 20:18:14.465808 kernel: audit: type=1334 audit(1762201094.464:8): prog-id=10 op=LOAD Nov 3 20:18:14.465000 audit: BPF prog-id=11 op=LOAD Nov 3 20:18:14.466687 kernel: audit: type=1334 audit(1762201094.465:9): prog-id=11 op=LOAD Nov 3 20:18:14.466000 audit: BPF prog-id=7 op=UNLOAD Nov 3 20:18:14.467596 kernel: audit: type=1334 audit(1762201094.466:10): prog-id=7 op=UNLOAD Nov 3 20:18:14.466000 audit: BPF prog-id=8 op=UNLOAD Nov 3 20:18:14.467000 audit: BPF prog-id=12 op=LOAD Nov 3 20:18:14.467000 audit: BPF prog-id=13 op=LOAD Nov 3 20:18:14.467000 audit: BPF prog-id=14 op=LOAD Nov 3 20:18:14.467000 audit: BPF prog-id=4 op=UNLOAD Nov 3 20:18:14.467000 audit: BPF prog-id=5 op=UNLOAD Nov 3 20:18:14.469566 systemd[1]: Mounted /sysusr/usr. Nov 3 20:18:14.472455 systemd[1]: Reached target Local File Systems. Nov 3 20:18:14.474210 systemd[1]: Reached target System Initialization. Nov 3 20:18:14.475077 systemd[1]: Reached target Basic System. Nov 3 20:18:14.477748 systemd[1]: Mounting /sysroot/usr... Nov 3 20:18:14.480195 systemd[1]: Mounted /sysroot/usr. Nov 3 20:18:14.488845 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Nov 3 20:18:14.488958 systemd[1]: Finished Reload Configuration from the Real Root. Nov 3 20:18:14.490849 systemd[1]: Reached target Initrd File Systems. Nov 3 20:18:14.492597 systemd[1]: Reached target Initrd Default Target. Nov 3 20:18:14.494392 systemd[1]: Condition check resulted in dracut mount hook being skipped. Nov 3 20:18:14.495048 systemd[1]: Starting dracut pre-pivot and cleanup hook... Nov 3 20:18:14.505072 systemd[1]: Finished dracut pre-pivot and cleanup hook. Nov 3 20:18:14.506710 systemd[1]: Starting Cleaning Up and Shutting Down Daemons... Nov 3 20:18:14.514257 systemd[1]: Stopped target Remote Encrypted Volumes. Nov 3 20:18:14.515371 systemd[1]: Stopped target Timer Units. Nov 3 20:18:14.516242 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Nov 3 20:18:14.516370 systemd[1]: Stopped dracut pre-pivot and cleanup hook. Nov 3 20:18:14.517462 systemd[1]: Stopped target Initrd Default Target. Nov 3 20:18:14.518966 systemd[1]: Stopped target Basic System. Nov 3 20:18:14.520252 systemd[1]: Stopped target Initrd Root Device. Nov 3 20:18:14.521897 systemd[1]: Stopped target Path Units. Nov 3 20:18:14.523360 systemd[1]: Stopped target Remote File Systems. Nov 3 20:18:14.524873 systemd[1]: Stopped target Preparation for Remote File Systems. Nov 3 20:18:14.526409 systemd[1]: Stopped target Slice Units. Nov 3 20:18:14.527749 systemd[1]: Stopped target Socket Units. Nov 3 20:18:14.529000 systemd[1]: Stopped target System Initialization. Nov 3 20:18:14.530413 systemd[1]: Stopped target Local File Systems. Nov 3 20:18:14.532038 systemd[1]: Stopped target Preparation for Local File Systems. Nov 3 20:18:14.533568 systemd[1]: Stopped target Swaps. Nov 3 20:18:14.534811 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Nov 3 20:18:14.534919 systemd[1]: Stopped dracut pre-mount hook. Nov 3 20:18:14.536288 systemd[1]: Stopped target Local Encrypted Volumes. Nov 3 20:18:14.537676 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 3 20:18:14.541571 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Nov 3 20:18:14.542899 systemd[1]: dracut-initqueue.service: Deactivated successfully. Nov 3 20:18:14.546932 iscsid[378]: iscsid shutting down. Nov 3 20:18:14.543002 systemd[1]: Stopped dracut initqueue hook. Nov 3 20:18:14.544685 systemd[1]: Stopping Open-iSCSI... Nov 3 20:18:14.546049 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 3 20:18:14.546161 systemd[1]: Stopped Apply Kernel Variables. Nov 3 20:18:14.547709 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Nov 3 20:18:14.547797 systemd[1]: Stopped Coldplug All udev Devices. Nov 3 20:18:14.548974 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Nov 3 20:18:14.549060 systemd[1]: Stopped dracut pre-trigger hook. Nov 3 20:18:14.550444 systemd[1]: Stopping Rule-based Manager for Device Events and Files... Nov 3 20:18:14.555507 systemd[1]: iscsid.service: Deactivated successfully. Nov 3 20:18:14.555985 systemd[1]: Stopped Open-iSCSI. Nov 3 20:18:14.570000 audit: BPF prog-id=9 op=UNLOAD Nov 3 20:18:14.558092 systemd[1]: systemd-udevd.service: Deactivated successfully. Nov 3 20:18:14.558208 systemd[1]: Stopped Rule-based Manager for Device Events and Files. Nov 3 20:18:14.560162 systemd[1]: iscsid.socket: Deactivated successfully. Nov 3 20:18:14.560282 systemd[1]: Closed Open-iSCSI iscsid Socket. Nov 3 20:18:14.561680 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Nov 3 20:18:14.561718 systemd[1]: Closed udev Control Socket. Nov 3 20:18:14.564583 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Nov 3 20:18:14.564616 systemd[1]: Closed udev Kernel Socket. Nov 3 20:18:14.565608 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Nov 3 20:18:14.565694 systemd[1]: Stopped dracut pre-udev hook. Nov 3 20:18:14.566998 systemd[1]: dracut-cmdline.service: Deactivated successfully. Nov 3 20:18:14.567037 systemd[1]: Stopped dracut cmdline hook. Nov 3 20:18:14.568760 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 3 20:18:14.568799 systemd[1]: Stopped dracut ask for additional cmdline parameters. Nov 3 20:18:14.571814 systemd[1]: Starting Cleanup udev Database... Nov 3 20:18:14.574019 systemd[1]: Stopping iSCSI UserSpace I/O driver... Nov 3 20:18:14.576553 systemd[1]: Stopping Hardware RNG Entropy Gatherer Daemon... Nov 3 20:18:14.578177 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 3 20:18:14.578286 systemd[1]: Stopped Create Static Device Nodes in /dev. Nov 3 20:18:14.579800 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Nov 3 20:18:14.579846 systemd[1]: Stopped Create List of Static Device Nodes. Nov 3 20:18:14.581565 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 3 20:18:14.603000 audit: BPF prog-id=12 op=UNLOAD Nov 3 20:18:14.581611 systemd[1]: Stopped Setup Virtual Console. Nov 3 20:18:14.583863 systemd[1]: iscsiuio.service: Deactivated successfully. Nov 3 20:18:14.584042 systemd[1]: Stopped iSCSI UserSpace I/O driver. Nov 3 20:18:14.585728 systemd[1]: rngd.service: Deactivated successfully. Nov 3 20:18:14.585830 systemd[1]: Stopped Hardware RNG Entropy Gatherer Daemon. Nov 3 20:18:14.587709 systemd[1]: initrd-cleanup.service: Deactivated successfully. Nov 3 20:18:14.587921 systemd[1]: Finished Cleaning Up and Shutting Down Daemons. Nov 3 20:18:14.589538 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Nov 3 20:18:14.589639 systemd[1]: Finished Cleanup udev Database. Nov 3 20:18:14.592200 systemd[1]: Reached target Switch Root. Nov 3 20:18:14.593888 systemd[1]: iscsiuio.socket: Deactivated successfully. Nov 3 20:18:14.594079 systemd[1]: Closed Open-iSCSI iscsiuio Socket. Nov 3 20:18:14.596913 systemd[1]: Starting Switch Root... Nov 3 20:18:14.602898 systemd[1]: Switching root. Nov 3 20:18:14.620022 systemd-journald[270]: Journal stopped Nov 3 20:18:16.664953 systemd-journald[270]: Received SIGTERM from PID 1 (systemd). Nov 3 20:18:16.664996 kernel: SELinux: policy capability network_peer_controls=1 Nov 3 20:18:16.665008 kernel: SELinux: policy capability open_perms=1 Nov 3 20:18:16.665017 kernel: SELinux: policy capability extended_socket_class=1 Nov 3 20:18:16.665026 kernel: SELinux: policy capability always_check_network=0 Nov 3 20:18:16.665035 kernel: SELinux: policy capability cgroup_seclabel=1 Nov 3 20:18:16.665044 kernel: SELinux: policy capability nnp_nosuid_transition=1 Nov 3 20:18:16.665053 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Nov 3 20:18:16.665063 systemd[1]: Successfully loaded SELinux policy in 30.632ms. Nov 3 20:18:16.665077 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 6.096ms. Nov 3 20:18:16.665089 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) Nov 3 20:18:16.665099 systemd[1]: Detected virtualization kvm. Nov 3 20:18:16.665109 systemd[1]: Detected architecture arm64. Nov 3 20:18:16.665120 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. Nov 3 20:18:16.665130 systemd[1]: initrd-switch-root.service: Current command vanished from the unit file, execution of the command list won't be resumed. Nov 3 20:18:16.665141 systemd[1]: initrd-switch-root.service: Deactivated successfully. Nov 3 20:18:16.665151 systemd[1]: Stopped Switch Root. Nov 3 20:18:16.665160 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Nov 3 20:18:16.665169 systemd[1]: Created slice Slice /system/addon-config. Nov 3 20:18:16.665179 systemd[1]: Created slice Slice /system/addon-run. Nov 3 20:18:16.665192 systemd[1]: Created slice Slice /system/getty. Nov 3 20:18:16.665202 systemd[1]: Created slice Slice /system/modprobe. Nov 3 20:18:16.665211 systemd[1]: Created slice Slice /system/serial-getty. Nov 3 20:18:16.665222 systemd[1]: Created slice Slice /system/system-cloudinit. Nov 3 20:18:16.665232 systemd[1]: Created slice Slice /system/systemd-fsck. Nov 3 20:18:16.665241 systemd[1]: Created slice User and Session Slice. Nov 3 20:18:16.665250 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Nov 3 20:18:16.665260 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Nov 3 20:18:16.665269 systemd[1]: Set up automount Boot partition Automount Point. Nov 3 20:18:16.665279 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Nov 3 20:18:16.665288 systemd[1]: Stopped target Switch Root. Nov 3 20:18:16.665299 systemd[1]: Stopped target Initrd File Systems. Nov 3 20:18:16.665308 systemd[1]: Stopped target Initrd Root File System. Nov 3 20:18:16.665326 systemd[1]: Reached target Remote Encrypted Volumes. Nov 3 20:18:16.665337 systemd[1]: Reached target Remote File Systems. Nov 3 20:18:16.665346 systemd[1]: Reached target Slice Units. Nov 3 20:18:16.665355 systemd[1]: Reached target Swaps. Nov 3 20:18:16.665365 systemd[1]: Reached target Verify torcx succeeded. Nov 3 20:18:16.665374 systemd[1]: Reached target Local Verity Protected Volumes. Nov 3 20:18:16.665384 systemd[1]: Listening on Process Core Dump Socket. Nov 3 20:18:16.665393 systemd[1]: Listening on initctl Compatibility Named Pipe. Nov 3 20:18:16.665403 systemd[1]: Listening on Network Service Netlink Socket. Nov 3 20:18:16.665414 systemd[1]: Listening on udev Control Socket. Nov 3 20:18:16.665424 systemd[1]: Listening on udev Kernel Socket. Nov 3 20:18:16.665433 systemd[1]: Mounting Huge Pages File System... Nov 3 20:18:16.665442 systemd[1]: Mounting POSIX Message Queue File System... Nov 3 20:18:16.665452 systemd[1]: Mounting External Media Directory... Nov 3 20:18:16.665461 systemd[1]: Mounting Kernel Debug File System... Nov 3 20:18:16.665470 systemd[1]: Mounting Kernel Trace File System... Nov 3 20:18:16.665480 systemd[1]: Mounting Temporary Directory /tmp... Nov 3 20:18:16.665490 systemd[1]: Starting Create missing system files... Nov 3 20:18:16.665499 systemd[1]: Starting Create List of Static Device Nodes... Nov 3 20:18:16.665510 systemd[1]: Starting Load Kernel Module configfs... Nov 3 20:18:16.665520 systemd[1]: Starting Load Kernel Module drm... Nov 3 20:18:16.665546 systemd[1]: Starting Load Kernel Module fuse... Nov 3 20:18:16.665556 systemd[1]: Condition check resulted in Create /etc/nsswitch.conf being skipped. Nov 3 20:18:16.665566 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Nov 3 20:18:16.665575 systemd[1]: Stopped File System Check on Root Device. Nov 3 20:18:16.665585 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Nov 3 20:18:16.665594 systemd[1]: Stopped systemd-fsck-usr.service. Nov 3 20:18:16.665604 systemd[1]: Stopped Journal Service. Nov 3 20:18:16.665614 kernel: fuse: init (API version 7.32) Nov 3 20:18:16.665623 systemd[1]: Starting Journal Service... Nov 3 20:18:16.665633 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Nov 3 20:18:16.665643 systemd[1]: Starting Remount Root and Kernel File Systems... Nov 3 20:18:16.665652 systemd[1]: Starting Apply Kernel Variables... Nov 3 20:18:16.665661 systemd[1]: Starting Coldplug All udev Devices... Nov 3 20:18:16.665671 systemd[1]: verity-setup.service: Deactivated successfully. Nov 3 20:18:16.665680 systemd[1]: Stopped verity-setup.service. Nov 3 20:18:16.665691 systemd-journald[692]: Journal started Nov 3 20:18:16.665726 systemd-journald[692]: Runtime Journal (/run/log/journal/6083db5279854a16a861cc12fe8ac4d7) is 6.0M, max 48.5M, 42.4M free. Nov 3 20:18:14.693000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Nov 3 20:18:14.720000 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 Nov 3 20:18:14.720000 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 Nov 3 20:18:14.720000 audit: BPF prog-id=15 op=LOAD Nov 3 20:18:14.720000 audit: BPF prog-id=15 op=UNLOAD Nov 3 20:18:14.720000 audit: BPF prog-id=16 op=LOAD Nov 3 20:18:14.720000 audit: BPF prog-id=16 op=UNLOAD Nov 3 20:18:14.754000 audit[645]: AVC avc: denied { associate } for pid=645 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Nov 3 20:18:16.529000 audit: BPF prog-id=17 op=LOAD Nov 3 20:18:16.529000 audit: BPF prog-id=18 op=LOAD Nov 3 20:18:16.529000 audit: BPF prog-id=19 op=LOAD Nov 3 20:18:16.529000 audit: BPF prog-id=13 op=UNLOAD Nov 3 20:18:16.529000 audit: BPF prog-id=14 op=UNLOAD Nov 3 20:18:16.538000 audit: BPF prog-id=20 op=LOAD Nov 3 20:18:16.539000 audit: BPF prog-id=17 op=UNLOAD Nov 3 20:18:16.539000 audit: BPF prog-id=21 op=LOAD Nov 3 20:18:16.539000 audit: BPF prog-id=22 op=LOAD Nov 3 20:18:16.539000 audit: BPF prog-id=18 op=UNLOAD Nov 3 20:18:16.539000 audit: BPF prog-id=19 op=UNLOAD Nov 3 20:18:16.539000 audit: BPF prog-id=23 op=LOAD Nov 3 20:18:16.539000 audit: BPF prog-id=20 op=UNLOAD Nov 3 20:18:16.539000 audit: BPF prog-id=24 op=LOAD Nov 3 20:18:16.539000 audit: BPF prog-id=25 op=LOAD Nov 3 20:18:16.539000 audit: BPF prog-id=21 op=UNLOAD Nov 3 20:18:16.539000 audit: BPF prog-id=22 op=UNLOAD Nov 3 20:18:16.551000 audit: BPF prog-id=23 op=UNLOAD Nov 3 20:18:16.651000 audit: BPF prog-id=26 op=LOAD Nov 3 20:18:16.651000 audit: BPF prog-id=27 op=LOAD Nov 3 20:18:16.651000 audit: BPF prog-id=28 op=LOAD Nov 3 20:18:16.651000 audit: BPF prog-id=24 op=UNLOAD Nov 3 20:18:16.651000 audit: BPF prog-id=25 op=UNLOAD Nov 3 20:18:16.663000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Nov 3 20:18:16.529320 systemd[1]: Queued start job for default target Multi-User System. Nov 3 20:18:14.752351 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-03T20:18:14Z" 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.2.4 /usr/share/oem/torcx/store /var/lib/torcx/store/3033.2.4 /var/lib/torcx/store]" Nov 3 20:18:16.540739 systemd[1]: systemd-journald.service: Deactivated successfully. Nov 3 20:18:14.752652 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-03T20:18:14Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Nov 3 20:18:14.752672 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-03T20:18:14Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Nov 3 20:18:14.752989 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-03T20:18:14Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Nov 3 20:18:14.753002 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-03T20:18:14Z" level=debug msg="skipped missing lower profile" missing profile=oem Nov 3 20:18:14.753037 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-03T20:18:14Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Nov 3 20:18:14.753050 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-03T20:18:14Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Nov 3 20:18:14.753391 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-03T20:18:14Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Nov 3 20:18:14.753430 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-03T20:18:14Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Nov 3 20:18:14.753450 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-03T20:18:14Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Nov 3 20:18:14.753865 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-03T20:18:14Z" 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 Nov 3 20:18:14.753896 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-03T20:18:14Z" 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 Nov 3 20:18:14.753917 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-03T20:18:14Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3033.2.4: no such file or directory" path=/usr/share/oem/torcx/store/3033.2.4 Nov 3 20:18:14.753933 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-03T20:18:14Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Nov 3 20:18:14.754044 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-03T20:18:14Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3033.2.4: no such file or directory" path=/var/lib/torcx/store/3033.2.4 Nov 3 20:18:14.754061 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-03T20:18:14Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Nov 3 20:18:16.444923 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-03T20:18:16Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Nov 3 20:18:16.445187 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-03T20:18:16Z" 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 Nov 3 20:18:16.445280 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-03T20:18:16Z" 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 Nov 3 20:18:16.445410 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-03T20:18:16Z" 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]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Nov 3 20:18:16.445502 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-03T20:18:16Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Nov 3 20:18:16.445583 /usr/lib/systemd/system-generators/torcx-generator[645]: time="2025-11-03T20:18:16Z" 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 Nov 3 20:18:16.668542 systemd[1]: Started Journal Service. Nov 3 20:18:16.668906 systemd[1]: Mounted Huge Pages File System. Nov 3 20:18:16.669790 systemd[1]: Mounted POSIX Message Queue File System. Nov 3 20:18:16.670773 systemd[1]: Mounted External Media Directory. Nov 3 20:18:16.671670 systemd[1]: Mounted Kernel Debug File System. Nov 3 20:18:16.672588 systemd[1]: Mounted Kernel Trace File System. Nov 3 20:18:16.673469 systemd[1]: Mounted Temporary Directory /tmp. Nov 3 20:18:16.674659 systemd[1]: Finished Create missing system files. Nov 3 20:18:16.675803 systemd[1]: Finished Create List of Static Device Nodes. Nov 3 20:18:16.676980 systemd[1]: modprobe@configfs.service: Deactivated successfully. Nov 3 20:18:16.677150 systemd[1]: Finished Load Kernel Module configfs. Nov 3 20:18:16.678210 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 3 20:18:16.678391 systemd[1]: Finished Load Kernel Module drm. Nov 3 20:18:16.679455 systemd[1]: modprobe@fuse.service: Deactivated successfully. Nov 3 20:18:16.679626 systemd[1]: Finished Load Kernel Module fuse. Nov 3 20:18:16.680699 systemd[1]: Finished Remount Root and Kernel File Systems. Nov 3 20:18:16.681905 systemd[1]: Finished Apply Kernel Variables. Nov 3 20:18:16.692513 systemd[1]: Mounting FUSE Control File System... Nov 3 20:18:16.694198 systemd[1]: Mounting Kernel Configuration File System... Nov 3 20:18:16.695067 systemd[1]: Condition check resulted in Remount Root File System being skipped. Nov 3 20:18:16.695954 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Nov 3 20:18:16.696853 systemd[1]: Starting Flush Journal to Persistent Storage... Nov 3 20:18:16.697829 systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. Nov 3 20:18:16.698921 systemd[1]: Starting Load/Save Random Seed... Nov 3 20:18:16.699771 systemd[1]: Condition check resulted in Create System Users being skipped. Nov 3 20:18:16.701069 systemd-journald[692]: Time spent on flushing to /var/log/journal/6083db5279854a16a861cc12fe8ac4d7 is 10.799ms for 667 entries. Nov 3 20:18:16.701069 systemd-journald[692]: System Journal (/var/log/journal/6083db5279854a16a861cc12fe8ac4d7) is 8.0M, max 203.0M, 195.0M free. Nov 3 20:18:16.700675 systemd[1]: Starting Create Static Device Nodes in /dev... Nov 3 20:18:16.703835 systemd[1]: Mounted FUSE Control File System. Nov 3 20:18:16.704911 systemd[1]: Mounted Kernel Configuration File System. Nov 3 20:18:16.706492 systemd[1]: Finished Load/Save Random Seed. Nov 3 20:18:16.707825 systemd[1]: Condition check resulted in First Boot Complete being skipped. Nov 3 20:18:16.725000 audit: BPF prog-id=29 op=LOAD Nov 3 20:18:16.725000 audit: BPF prog-id=30 op=LOAD Nov 3 20:18:16.725000 audit: BPF prog-id=31 op=LOAD Nov 3 20:18:16.725000 audit: BPF prog-id=10 op=UNLOAD Nov 3 20:18:16.725000 audit: BPF prog-id=11 op=UNLOAD Nov 3 20:18:16.719848 systemd[1]: Finished Coldplug All udev Devices. Nov 3 20:18:16.722029 systemd[1]: Finished Create Static Device Nodes in /dev. Nov 3 20:18:16.724001 systemd[1]: Starting Wait for udev To Complete Device Initialization... Nov 3 20:18:16.726468 systemd[1]: Starting Rule-based Manager for Device Events and Files... Nov 3 20:18:16.728219 systemd[1]: Finished Flush Journal to Persistent Storage. Nov 3 20:18:16.750393 systemd[1]: Started Rule-based Manager for Device Events and Files. Nov 3 20:18:16.759000 audit: BPF prog-id=32 op=LOAD Nov 3 20:18:16.760647 systemd[1]: Starting Network Configuration... Nov 3 20:18:16.787612 systemd-networkd[725]: lo: Link UP Nov 3 20:18:16.787622 systemd-networkd[725]: lo: Gained carrier Nov 3 20:18:16.787915 systemd-networkd[725]: Enumeration completed Nov 3 20:18:16.788012 systemd[1]: Started Network Configuration. Nov 3 20:18:16.790186 systemd-udevd[708]: Using default interface naming scheme 'v249'. Nov 3 20:18:16.809722 udevadm[705]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Nov 3 20:18:16.820898 systemd-networkd[725]: eth0: Link UP Nov 3 20:18:16.825566 systemd-networkd[725]: eth0: Gained carrier Nov 3 20:18:16.833645 systemd-networkd[725]: eth0: DHCPv4 address 10.0.0.8/16 via 10.0.0.1 Nov 3 20:18:16.835646 systemd[1]: Finished Wait for udev To Complete Device Initialization. Nov 3 20:18:16.846349 systemd[1]: Starting Activation of LVM2 logical volumes... Nov 3 20:18:16.854058 lvm[733]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Nov 3 20:18:16.885395 systemd[1]: Finished Activation of LVM2 logical volumes. Nov 3 20:18:16.886482 systemd[1]: Reached target Local Encrypted Volumes. Nov 3 20:18:16.899351 systemd[1]: Starting Activation of LVM2 logical volumes... Nov 3 20:18:16.902475 lvm[734]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Nov 3 20:18:16.929497 systemd[1]: Finished Activation of LVM2 logical volumes. Nov 3 20:18:16.930636 systemd[1]: Reached target Preparation for Local File Systems. Nov 3 20:18:16.931663 systemd[1]: Condition check resulted in Virtual Machine and Container Storage (Compatibility) being skipped. Nov 3 20:18:16.931687 systemd[1]: Reached target Containers. Nov 3 20:18:16.941787 systemd[1]: Starting File System Check on /dev/disk/by-label/OEM... Nov 3 20:18:16.953644 systemd[1]: Finished File System Check on /dev/disk/by-label/OEM. Nov 3 20:18:16.955800 systemd[1]: Mounting /usr/share/oem... Nov 3 20:18:16.962061 kernel: BTRFS info (device vda6): disk space caching is enabled Nov 3 20:18:16.962125 kernel: BTRFS info (device vda6): has skinny extents Nov 3 20:18:16.964223 systemd[1]: Mounted /usr/share/oem. Nov 3 20:18:16.965022 systemd[1]: Reached target Local File Systems. Nov 3 20:18:16.965988 systemd[1]: Condition check resulted in Rebuild Dynamic Linker Cache being skipped. Nov 3 20:18:16.966373 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Nov 3 20:18:16.966409 systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Nov 3 20:18:16.966440 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Nov 3 20:18:16.977392 systemd[1]: Starting Create Volatile Files and Directories... Nov 3 20:18:16.983150 systemd-tmpfiles[757]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Nov 3 20:18:16.984197 systemd-tmpfiles[757]: /usr/lib/tmpfiles.d/systemd.conf:33: Duplicate line for path "/var/lib/systemd", ignoring. Nov 3 20:18:17.016610 systemd-tmpfiles[757]: Detected autofs mount point /boot during canonicalization of /boot. Nov 3 20:18:17.016623 systemd-tmpfiles[757]: Skipping /boot Nov 3 20:18:17.022380 systemd-tmpfiles[757]: Detected autofs mount point /boot during canonicalization of /boot. Nov 3 20:18:17.022395 systemd-tmpfiles[757]: Skipping /boot Nov 3 20:18:17.053406 systemd[1]: Finished Create Volatile Files and Directories. Nov 3 20:18:17.063470 systemd[1]: Starting Load Security Auditing Rules... Nov 3 20:18:17.065293 systemd[1]: Starting Clean up broken links in /etc/ssl/certs... Nov 3 20:18:17.066435 systemd[1]: Condition check resulted in Rebuild Journal Catalog being skipped. Nov 3 20:18:17.066000 audit: BPF prog-id=33 op=LOAD Nov 3 20:18:17.067830 systemd[1]: Starting Network Name Resolution... Nov 3 20:18:17.068000 audit: BPF prog-id=34 op=LOAD Nov 3 20:18:17.069944 systemd[1]: Starting Network Time Synchronization... Nov 3 20:18:17.071287 systemd[1]: Condition check resulted in Update is Completed being skipped. Nov 3 20:18:17.072172 systemd[1]: Starting Record System Boot/Shutdown in UTMP... Nov 3 20:18:17.074072 systemd[1]: Finished Clean up broken links in /etc/ssl/certs. Nov 3 20:18:17.075652 systemd[1]: Condition check resulted in Update CA bundle at /etc/ssl/certs/ca-certificates.crt being skipped. Nov 3 20:18:17.078829 systemd[1]: Finished Record System Boot/Shutdown in UTMP. Nov 3 20:18:17.089000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Nov 3 20:18:17.090519 augenrules[778]: No rules Nov 3 20:18:17.091391 systemd[1]: Finished Load Security Auditing Rules. Nov 3 20:18:17.105323 systemd[1]: Started Network Time Synchronization. Nov 3 20:18:16.210524 systemd-timesyncd[762]: Initial synchronization to time server 10.0.0.1:123 (10.0.0.1). Nov 3 20:18:16.210548 systemd-resolved[761]: Positive Trust Anchors: Nov 3 20:18:16.210555 systemd-resolved[761]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 3 20:18:16.210582 systemd-resolved[761]: 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 Nov 3 20:18:16.210703 systemd[1]: Reached target System Initialization. Nov 3 20:18:16.211348 systemd-resolved[761]: Defaulting to hostname 'linux'. Nov 3 20:18:16.211969 systemd[1]: Started Watch for update engine configuration changes. Nov 3 20:18:16.213196 systemd[1]: Started Watch for a cloud-config at /var/lib/flatcar-install/user_data. Nov 3 20:18:16.214401 systemd[1]: Started Daily Cleanup of Temporary Directories. Nov 3 20:18:16.215644 systemd[1]: Condition check resulted in Update Engine Stub Timer being skipped. Nov 3 20:18:16.215668 systemd[1]: Reached target Path Units. Nov 3 20:18:16.216534 systemd[1]: Reached target System Time Set. Nov 3 20:18:16.217659 systemd[1]: Started Daily Log Rotation. Nov 3 20:18:16.220068 systemd[1]: Started Weekly check for MD array's redundancy information.. Nov 3 20:18:16.221350 systemd[1]: Reached target Timer Units. Nov 3 20:18:16.222479 systemd[1]: Listening on D-Bus System Message Bus Socket. Nov 3 20:18:16.236313 systemd[1]: Starting Docker Socket for the API... Nov 3 20:18:16.239341 systemd[1]: Listening on OpenSSH Server Socket. Nov 3 20:18:16.240607 systemd[1]: Started Network Name Resolution. Nov 3 20:18:16.241575 systemd[1]: Listening on Docker Socket for the API. Nov 3 20:18:16.242584 systemd[1]: Reached target Network. Nov 3 20:18:16.243347 systemd[1]: Reached target Host and Network Name Lookups. Nov 3 20:18:16.244335 systemd[1]: Reached target Socket Units. Nov 3 20:18:16.245125 systemd[1]: Reached target Basic System. Nov 3 20:18:16.245897 systemd[1]: Condition check resulted in Configure Addon /usr/share/oem being skipped. Nov 3 20:18:16.245925 systemd[1]: Condition check resulted in Run Addon /usr/share/oem being skipped. Nov 3 20:18:16.246826 systemd[1]: Started D-Bus System Message Bus. Nov 3 20:18:16.249826 systemd[1]: Starting Extend Filesystems... Nov 3 20:18:16.250637 systemd[1]: Condition check resulted in Modifies /etc/environment for CoreOS being skipped. Nov 3 20:18:16.251557 systemd[1]: Starting Generate /run/flatcar/motd... Nov 3 20:18:16.253645 systemd[1]: Starting Install an ssh key from /proc/cmdline... Nov 3 20:18:16.255483 systemd[1]: Starting Generate sshd host keys... Nov 3 20:18:16.256400 systemd[1]: Condition check resulted in Load cloud-config from /usr/share/oem/cloud-config.yml being skipped. Nov 3 20:18:16.256427 systemd[1]: Reached target Load system-provided cloud configs. Nov 3 20:18:16.259750 systemd[1]: Starting User Login Management... Nov 3 20:18:16.261284 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Nov 3 20:18:16.261882 systemd[1]: Starting Update Engine... Nov 3 20:18:16.263566 extend-filesystems[788]: Found vda Nov 3 20:18:16.263566 extend-filesystems[788]: Found vda1 Nov 3 20:18:16.263566 extend-filesystems[788]: Found vda2 Nov 3 20:18:16.263566 extend-filesystems[788]: Found vda3 Nov 3 20:18:16.263566 extend-filesystems[788]: Found usr Nov 3 20:18:16.263566 extend-filesystems[788]: Found vda4 Nov 3 20:18:16.263566 extend-filesystems[788]: Found vda6 Nov 3 20:18:16.263566 extend-filesystems[788]: Found vda7 Nov 3 20:18:16.263566 extend-filesystems[788]: Found vda9 Nov 3 20:18:16.263566 extend-filesystems[788]: Checking size of /dev/vda9 Nov 3 20:18:16.263881 systemd[1]: Condition check resulted in Load cloud-config from url defined in /proc/cmdline being skipped. Nov 3 20:18:16.263931 systemd[1]: Reached target Load user-provided cloud configs. Nov 3 20:18:16.266373 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Nov 3 20:18:16.266595 systemd[1]: Finished Install an ssh key from /proc/cmdline. Nov 3 20:18:16.268838 systemd[1]: motdgen.service: Deactivated successfully. Nov 3 20:18:16.269037 systemd[1]: Finished Generate /run/flatcar/motd. Nov 3 20:18:16.278370 extend-filesystems[788]: Old size kept for /dev/vda9 Nov 3 20:18:16.282425 systemd[1]: extend-filesystems.service: Deactivated successfully. Nov 3 20:18:16.282646 systemd[1]: Finished Extend Filesystems. Nov 3 20:18:16.296965 systemd-logind[799]: Watching system buttons on /dev/input/event0 (Power Button) Nov 3 20:18:16.299380 systemd-logind[799]: New seat seat0. Nov 3 20:18:16.306700 systemd[1]: Started User Login Management. Nov 3 20:18:16.314536 update_engine[801]: I1103 20:18:16.314211 801 main.cc:89] Flatcar Update Engine starting Nov 3 20:18:16.315043 update_engine[801]: I1103 20:18:16.315023 801 payload_state.cc:360] Current Response Signature = Nov 3 20:18:16.315043 update_engine[801]: NumURLs = 1 Nov 3 20:18:16.315043 update_engine[801]: Url0 = http://10.0.0.7:34567/packages/update.gz Nov 3 20:18:16.315043 update_engine[801]: Payload Size = 433878941 Nov 3 20:18:16.315043 update_engine[801]: Payload Sha256 Hash = lVdxUTtdTOsZmTszVwz4Xs2gAniQzDt5nUhkgQrAo2w= Nov 3 20:18:16.315043 update_engine[801]: Is Delta Payload = 0 Nov 3 20:18:16.315043 update_engine[801]: Max Failure Count Per Url = 10 Nov 3 20:18:16.315043 update_engine[801]: Disable Payload Backoff = 1 Nov 3 20:18:16.315367 update_engine[801]: I1103 20:18:16.315281 801 payload_state.cc:381] Payload Attempt Number = 0 Nov 3 20:18:16.315476 update_engine[801]: I1103 20:18:16.315452 801 payload_state.cc:404] Current URL Index = 0 Nov 3 20:18:16.315629 update_engine[801]: I1103 20:18:16.315616 801 payload_state.cc:425] Current URL (Url0)'s Failure Count = 1 Nov 3 20:18:16.315833 update_engine[801]: I1103 20:18:16.315804 801 payload_state.cc:452] Backoff Expiry Time = 01/01/70 00:00:00 UTC Nov 3 20:18:16.317565 systemd[1]: Started Update Engine. Nov 3 20:18:16.318142 update_engine[801]: I1103 20:18:16.318094 801 update_check_scheduler.cc:74] Next update check in 9m49s Nov 3 20:18:16.318912 systemd[1]: Finished Generate sshd host keys. Nov 3 20:18:16.327709 systemd[1]: Starting Generate /run/issue... Nov 3 20:18:16.331881 systemd[1]: issuegen.service: Deactivated successfully. Nov 3 20:18:16.332087 systemd[1]: Finished Generate /run/issue. Nov 3 20:18:16.334051 systemd[1]: Starting Permit User Sessions... Nov 3 20:18:16.338248 systemd[1]: Finished Permit User Sessions. Nov 3 20:18:16.340261 systemd[1]: Started Getty on tty1. Nov 3 20:18:16.342146 systemd[1]: Started Serial Getty on ttyAMA0. Nov 3 20:18:16.343169 systemd[1]: Reached target Login Prompts. Nov 3 20:18:16.344065 systemd[1]: Reached target Multi-User System. Nov 3 20:18:16.346052 systemd[1]: Starting Record Runlevel Change in UTMP... Nov 3 20:18:16.351055 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Nov 3 20:18:16.351281 systemd[1]: Finished Record Runlevel Change in UTMP. Nov 3 20:18:16.352356 systemd[1]: Startup finished in 700ms (kernel) + 1.102s (initrd) + 2.586s (userspace) = 4.390s. Nov 3 20:18:17.752868 systemd-networkd[725]: eth0: Gained IPv6LL Nov 3 20:18:26.124606 systemd[1]: Created slice Slice /system/sshd. Nov 3 20:18:26.125592 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:38564). Nov 3 20:18:26.166016 sshd[828]: Accepted publickey for core from 10.0.0.1 port 38564 ssh2: RSA SHA256:692sSxTeBYXCSoeByc7zvUD/VRPArpPZwEkxc0ZTfNg Nov 3 20:18:26.167940 sshd[828]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 3 20:18:26.180090 systemd[1]: Created slice User Slice of UID 500. Nov 3 20:18:26.180971 systemd[1]: Starting User Runtime Directory /run/user/500... Nov 3 20:18:26.182306 systemd-logind[799]: New session 1 of user core. Nov 3 20:18:26.186537 systemd[1]: Finished User Runtime Directory /run/user/500. Nov 3 20:18:26.187614 systemd[1]: Starting User Manager for UID 500... Nov 3 20:18:26.189715 systemd[831]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Nov 3 20:18:26.234292 systemd[831]: Queued start job for default target Main User Target. Nov 3 20:18:26.234432 systemd[831]: Reached target Paths. Nov 3 20:18:26.234445 systemd[831]: Reached target Sockets. Nov 3 20:18:26.234456 systemd[831]: Reached target Timers. Nov 3 20:18:26.234476 systemd[831]: Reached target Basic System. Nov 3 20:18:26.234558 systemd[1]: Started User Manager for UID 500. Nov 3 20:18:26.235126 systemd[831]: Reached target Main User Target. Nov 3 20:18:26.235154 systemd[831]: Startup finished in 41ms. Nov 3 20:18:26.244866 systemd[1]: Started Session 1 of User core. Nov 3 20:18:26.303953 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:38568). Nov 3 20:18:26.344376 sshd[840]: Accepted publickey for core from 10.0.0.1 port 38568 ssh2: RSA SHA256:692sSxTeBYXCSoeByc7zvUD/VRPArpPZwEkxc0ZTfNg Nov 3 20:18:26.345369 sshd[840]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 3 20:18:26.347975 systemd-logind[799]: New session 2 of user core. Nov 3 20:18:26.355811 systemd[1]: Started Session 2 of User core. Nov 3 20:18:26.407134 sshd[840]: pam_unix(sshd:session): session closed for user core Nov 3 20:18:26.420192 systemd[1]: sshd@1-10.0.0.8:22-10.0.0.1:38568.service: Deactivated successfully. Nov 3 20:18:26.420882 systemd[1]: session-2.scope: Deactivated successfully. Nov 3 20:18:26.421476 systemd-logind[799]: Session 2 logged out. Waiting for processes to exit. Nov 3 20:18:26.422411 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:38580). Nov 3 20:18:26.423072 systemd-logind[799]: Removed session 2. Nov 3 20:18:26.458921 sshd[846]: Accepted publickey for core from 10.0.0.1 port 38580 ssh2: RSA SHA256:692sSxTeBYXCSoeByc7zvUD/VRPArpPZwEkxc0ZTfNg Nov 3 20:18:26.459715 sshd[846]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 3 20:18:26.462351 systemd-logind[799]: New session 3 of user core. Nov 3 20:18:26.472880 systemd[1]: Started Session 3 of User core. Nov 3 20:18:26.520283 sshd[846]: pam_unix(sshd:session): session closed for user core Nov 3 20:18:26.529044 systemd[1]: sshd@2-10.0.0.8:22-10.0.0.1:38580.service: Deactivated successfully. Nov 3 20:18:26.529615 systemd[1]: session-3.scope: Deactivated successfully. Nov 3 20:18:26.530151 systemd-logind[799]: Session 3 logged out. Waiting for processes to exit. Nov 3 20:18:26.531003 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:38596). Nov 3 20:18:26.531541 systemd-logind[799]: Removed session 3. Nov 3 20:18:26.567443 sshd[853]: Accepted publickey for core from 10.0.0.1 port 38596 ssh2: RSA SHA256:692sSxTeBYXCSoeByc7zvUD/VRPArpPZwEkxc0ZTfNg Nov 3 20:18:26.568249 sshd[853]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 3 20:18:26.570826 systemd-logind[799]: New session 4 of user core. Nov 3 20:18:26.583799 systemd[1]: Started Session 4 of User core. Nov 3 20:18:26.634076 sshd[853]: pam_unix(sshd:session): session closed for user core Nov 3 20:18:26.646157 systemd[1]: sshd@3-10.0.0.8:22-10.0.0.1:38596.service: Deactivated successfully. Nov 3 20:18:26.646749 systemd[1]: session-4.scope: Deactivated successfully. Nov 3 20:18:26.647254 systemd-logind[799]: Session 4 logged out. Waiting for processes to exit. Nov 3 20:18:26.648147 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:38608). Nov 3 20:18:26.648795 systemd-logind[799]: Removed session 4. Nov 3 20:18:26.685112 sshd[859]: Accepted publickey for core from 10.0.0.1 port 38608 ssh2: RSA SHA256:692sSxTeBYXCSoeByc7zvUD/VRPArpPZwEkxc0ZTfNg Nov 3 20:18:26.685369 sshd[859]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 3 20:18:26.687613 systemd-logind[799]: New session 5 of user core. Nov 3 20:18:26.699824 systemd[1]: Started Session 5 of User core. Nov 3 20:18:26.754790 sudo[862]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/setenforce 1 Nov 3 20:18:26.754982 sudo[862]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Nov 3 20:18:26.763220 sudo[862]: pam_unix(sudo:session): session closed for user root Nov 3 20:18:26.764919 sshd[859]: pam_unix(sshd:session): session closed for user core Nov 3 20:18:26.784099 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:38612). Nov 3 20:18:26.785174 dbus-daemon[787]: [system] Reloaded configuration Nov 3 20:18:26.787453 systemd[1]: sshd@4-10.0.0.8:22-10.0.0.1:38608.service: Deactivated successfully. Nov 3 20:18:26.788071 systemd[1]: session-5.scope: Deactivated successfully. Nov 3 20:18:26.788669 systemd-logind[799]: Session 5 logged out. Waiting for processes to exit. Nov 3 20:18:26.789404 systemd-logind[799]: Removed session 5. Nov 3 20:18:26.823230 sshd[865]: Accepted publickey for core from 10.0.0.1 port 38612 ssh2: RSA SHA256:692sSxTeBYXCSoeByc7zvUD/VRPArpPZwEkxc0ZTfNg Nov 3 20:18:26.824178 sshd[865]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 3 20:18:26.826896 systemd-logind[799]: New session 6 of user core. Nov 3 20:18:26.839870 systemd[1]: Started Session 6 of User core. Nov 3 20:18:26.891962 sudo[870]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Nov 3 20:18:26.892224 sudo[870]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Nov 3 20:18:26.895434 sudo[870]: pam_unix(sudo:session): session closed for user root Nov 3 20:18:26.899007 sudo[869]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl restart audit-rules Nov 3 20:18:26.899214 sudo[869]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Nov 3 20:18:26.913644 systemd[1]: Stopping Load Security Auditing Rules... Nov 3 20:18:26.914000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Nov 3 20:18:26.916062 auditctl[873]: No rules Nov 3 20:18:26.916354 systemd[1]: audit-rules.service: Deactivated successfully. Nov 3 20:18:26.916601 systemd[1]: Stopped Load Security Auditing Rules. Nov 3 20:18:26.917200 kernel: kauditd_printk_skb: 49 callbacks suppressed Nov 3 20:18:26.917235 kernel: audit: type=1305 audit(1762201106.914:60): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Nov 3 20:18:26.917913 systemd[1]: Starting Load Security Auditing Rules... Nov 3 20:18:26.932650 augenrules[890]: No rules Nov 3 20:18:26.933339 systemd[1]: Finished Load Security Auditing Rules. Nov 3 20:18:26.934142 sudo[869]: pam_unix(sudo:session): session closed for user root Nov 3 20:18:26.935283 sshd[865]: pam_unix(sshd:session): session closed for user core Nov 3 20:18:26.946208 systemd[1]: sshd@5-10.0.0.8:22-10.0.0.1:38612.service: Deactivated successfully. Nov 3 20:18:26.946779 systemd[1]: session-6.scope: Deactivated successfully. Nov 3 20:18:26.947402 systemd-logind[799]: Session 6 logged out. Waiting for processes to exit. Nov 3 20:18:26.948403 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:38628). Nov 3 20:18:26.949075 systemd-logind[799]: Removed session 6. Nov 3 20:18:26.985489 sshd[896]: Accepted publickey for core from 10.0.0.1 port 38628 ssh2: RSA SHA256:692sSxTeBYXCSoeByc7zvUD/VRPArpPZwEkxc0ZTfNg Nov 3 20:18:26.986452 sshd[896]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 3 20:18:26.988706 kernel: audit: type=1006 audit(1762201106.985:61): pid=896 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=7 res=1 Nov 3 20:18:26.989131 systemd-logind[799]: New session 7 of user core. Nov 3 20:18:27.003815 systemd[1]: Started Session 7 of User core. Nov 3 20:18:27.051641 sshd[896]: pam_unix(sshd:session): session closed for user core Nov 3 20:18:27.059455 systemd[1]: sshd@6-10.0.0.8:22-10.0.0.1:38628.service: Deactivated successfully. Nov 3 20:18:27.059988 systemd[1]: session-7.scope: Deactivated successfully. Nov 3 20:18:27.060569 systemd-logind[799]: Session 7 logged out. Waiting for processes to exit. Nov 3 20:18:27.061490 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:38644). Nov 3 20:18:27.062144 systemd-logind[799]: Removed session 7. Nov 3 20:18:27.097926 sshd[902]: Accepted publickey for core from 10.0.0.1 port 38644 ssh2: RSA SHA256:692sSxTeBYXCSoeByc7zvUD/VRPArpPZwEkxc0ZTfNg Nov 3 20:18:27.099129 sshd[902]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 3 20:18:27.101635 systemd-logind[799]: New session 8 of user core. Nov 3 20:18:27.101727 kernel: audit: type=1006 audit(1762201107.098:62): pid=902 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=8 res=1 Nov 3 20:18:27.108817 systemd[1]: Started Session 8 of User core. Nov 3 20:18:27.156776 sshd[902]: pam_unix(sshd:session): session closed for user core Nov 3 20:18:27.158572 systemd[1]: sshd@7-10.0.0.8:22-10.0.0.1:38644.service: Deactivated successfully. Nov 3 20:18:27.159177 systemd[1]: session-8.scope: Deactivated successfully. Nov 3 20:18:27.159778 systemd-logind[799]: Session 8 logged out. Waiting for processes to exit. Nov 3 20:18:27.160435 systemd-logind[799]: Removed session 8.