Nov 5 15:03:19.690694 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Nov 5 15:03:19.690711 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 5 15:03:19.690719 kernel: efi: EFI v2.70 by EDK II Nov 5 15:03:19.690725 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb82c018 ACPI 2.0=0xdbfd0018 RNG=0xdcfdd318 MEMRESERVE=0xdb834c18 Nov 5 15:03:19.690729 kernel: efi: seeding entropy pool Nov 5 15:03:19.690734 kernel: efi: memattr: Unexpected EFI Memory Attributes table version 2 Nov 5 15:03:19.690740 kernel: ACPI: Early table checksum verification disabled Nov 5 15:03:19.690745 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Nov 5 15:03:19.690750 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Nov 5 15:03:19.690755 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Nov 5 15:03:19.690762 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 5 15:03:19.690767 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Nov 5 15:03:19.690772 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 5 15:03:19.690777 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 5 15:03:19.690783 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Nov 5 15:03:19.690874 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Nov 5 15:03:19.690889 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Nov 5 15:03:19.690895 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Nov 5 15:03:19.690900 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Nov 5 15:03:19.690905 kernel: ACPI: NUMA: Failed to initialise from firmware Nov 5 15:03:19.690911 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Nov 5 15:03:19.690917 kernel: NUMA: NODE_DATA [mem 0xdc95b900-0xdc960fff] Nov 5 15:03:19.690922 kernel: Zone ranges: Nov 5 15:03:19.690927 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Nov 5 15:03:19.690933 kernel: DMA32 empty Nov 5 15:03:19.690938 kernel: Normal empty Nov 5 15:03:19.690943 kernel: Movable zone start for each node Nov 5 15:03:19.690949 kernel: Early memory node ranges Nov 5 15:03:19.690955 kernel: node 0: [mem 0x0000000040000000-0x00000000dc09ffff] Nov 5 15:03:19.690960 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Nov 5 15:03:19.690965 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Nov 5 15:03:19.690971 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Nov 5 15:03:19.690976 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Nov 5 15:03:19.690981 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Nov 5 15:03:19.690986 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Nov 5 15:03:19.690992 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Nov 5 15:03:19.691008 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Nov 5 15:03:19.691013 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Nov 5 15:03:19.691018 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Nov 5 15:03:19.691024 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Nov 5 15:03:19.691032 kernel: On node 0 totalpages: 643072 Nov 5 15:03:19.691038 kernel: DMA zone: 10048 pages used for memmap Nov 5 15:03:19.691044 kernel: DMA zone: 0 pages reserved Nov 5 15:03:19.691049 kernel: DMA zone: 643072 pages, LIFO batch:63 Nov 5 15:03:19.691055 kernel: psci: probing for conduit method from ACPI. Nov 5 15:03:19.691060 kernel: psci: PSCIv1.1 detected in firmware. Nov 5 15:03:19.691066 kernel: psci: Using standard PSCI v0.2 function IDs Nov 5 15:03:19.691071 kernel: psci: Trusted OS migration not required Nov 5 15:03:19.691078 kernel: psci: SMC Calling Convention v1.1 Nov 5 15:03:19.691084 kernel: ACPI: SRAT not present Nov 5 15:03:19.691089 kernel: percpu: Embedded 32 pages/cpu s93720 r8192 d29160 u131072 Nov 5 15:03:19.691095 kernel: pcpu-alloc: s93720 r8192 d29160 u131072 alloc=32*4096 Nov 5 15:03:19.691100 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Nov 5 15:03:19.691106 kernel: Detected PIPT I-cache on CPU0 Nov 5 15:03:19.691112 kernel: CPU features: detected: GIC system register CPU interface Nov 5 15:03:19.691117 kernel: CPU features: kernel page table isolation forced ON by KASLR Nov 5 15:03:19.691123 kernel: CPU features: detected: Kernel page table isolation (KPTI) Nov 5 15:03:19.691128 kernel: CPU features: detected: Hardware dirty bit management Nov 5 15:03:19.691134 kernel: CPU features: detected: Spectre-v4 Nov 5 15:03:19.691141 kernel: CPU features: detected: ARM erratum 1418040 Nov 5 15:03:19.691146 kernel: CPU features: detected: Spectre-BHB Nov 5 15:03:19.691152 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Nov 5 15:03:19.691157 kernel: Policy zone: DMA Nov 5 15:03:19.691164 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 5 15:03:19.691170 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Nov 5 15:03:19.691175 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Nov 5 15:03:19.691181 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Nov 5 15:03:19.691187 kernel: Memory: 2452104K/2572288K available (9344K kernel code, 2274K rwdata, 6844K rodata, 34240K init, 769K bss, 120184K reserved, 0K cma-reserved) Nov 5 15:03:19.691193 kernel: random: get_random_u64 called from __kmem_cache_create+0x28/0x4a0 with crng_init=0 Nov 5 15:03:19.691198 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Nov 5 15:03:19.691205 kernel: rcu: Preemptible hierarchical RCU implementation. Nov 5 15:03:19.691211 kernel: rcu: RCU event tracing is enabled. Nov 5 15:03:19.691217 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Nov 5 15:03:19.691222 kernel: Trampoline variant of Tasks RCU enabled. Nov 5 15:03:19.691228 kernel: Tracing variant of Tasks RCU enabled. Nov 5 15:03:19.691234 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Nov 5 15:03:19.691239 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Nov 5 15:03:19.691245 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Nov 5 15:03:19.691250 kernel: GICv3: 256 SPIs implemented Nov 5 15:03:19.691256 kernel: GICv3: 0 Extended SPIs implemented Nov 5 15:03:19.691261 kernel: GICv3: Distributor has no Range Selector support Nov 5 15:03:19.691267 kernel: GICv3: 16 PPIs implemented Nov 5 15:03:19.691273 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Nov 5 15:03:19.691279 kernel: ACPI: SRAT not present Nov 5 15:03:19.691322 kernel: ITS [mem 0x08080000-0x0809ffff] Nov 5 15:03:19.691329 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Nov 5 15:03:19.691335 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Nov 5 15:03:19.691341 kernel: GICv3: using LPI property table @0x00000000400d0000 Nov 5 15:03:19.691347 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Nov 5 15:03:19.691352 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 5 15:03:19.691358 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Nov 5 15:03:19.691364 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Nov 5 15:03:19.691370 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Nov 5 15:03:19.691376 kernel: arm-pv: using stolen time PV Nov 5 15:03:19.691384 kernel: Console: colour dummy device 80x25 Nov 5 15:03:19.691390 kernel: ACPI: Core revision 20200925 Nov 5 15:03:19.691396 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Nov 5 15:03:19.691401 kernel: pid_max: default: 32768 minimum: 301 Nov 5 15:03:19.691407 kernel: LSM: Security Framework initializing Nov 5 15:03:19.691413 kernel: SELinux: Initializing. Nov 5 15:03:19.691419 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 5 15:03:19.691425 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Nov 5 15:03:19.691431 kernel: rcu: Hierarchical SRCU implementation. Nov 5 15:03:19.691438 kernel: Platform MSI: ITS@0x8080000 domain created Nov 5 15:03:19.691444 kernel: PCI/MSI: ITS@0x8080000 domain created Nov 5 15:03:19.691450 kernel: Remapping and enabling EFI services. Nov 5 15:03:19.691455 kernel: smp: Bringing up secondary CPUs ... Nov 5 15:03:19.691461 kernel: Detected PIPT I-cache on CPU1 Nov 5 15:03:19.691467 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Nov 5 15:03:19.691473 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Nov 5 15:03:19.691479 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 5 15:03:19.691485 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Nov 5 15:03:19.691491 kernel: Detected PIPT I-cache on CPU2 Nov 5 15:03:19.691498 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Nov 5 15:03:19.691504 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Nov 5 15:03:19.691510 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 5 15:03:19.691515 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Nov 5 15:03:19.691521 kernel: Detected PIPT I-cache on CPU3 Nov 5 15:03:19.691532 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Nov 5 15:03:19.691538 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Nov 5 15:03:19.691545 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Nov 5 15:03:19.691551 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Nov 5 15:03:19.691557 kernel: smp: Brought up 1 node, 4 CPUs Nov 5 15:03:19.691563 kernel: SMP: Total of 4 processors activated. Nov 5 15:03:19.691569 kernel: CPU features: detected: Privileged Access Never Nov 5 15:03:19.691575 kernel: CPU features: detected: LSE atomic instructions Nov 5 15:03:19.691581 kernel: CPU features: detected: User Access Override Nov 5 15:03:19.691587 kernel: CPU features: detected: 32-bit EL0 Support Nov 5 15:03:19.691593 kernel: CPU features: detected: Common not Private translations Nov 5 15:03:19.691600 kernel: CPU features: detected: RAS Extension Support Nov 5 15:03:19.691608 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Nov 5 15:03:19.691614 kernel: CPU features: detected: CRC32 instructions Nov 5 15:03:19.691620 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Nov 5 15:03:19.691627 kernel: CPU: All CPU(s) started at EL1 Nov 5 15:03:19.691633 kernel: alternatives: patching kernel code Nov 5 15:03:19.691640 kernel: devtmpfs: initialized Nov 5 15:03:19.691646 kernel: KASLR enabled Nov 5 15:03:19.691652 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Nov 5 15:03:19.691658 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Nov 5 15:03:19.691664 kernel: pinctrl core: initialized pinctrl subsystem Nov 5 15:03:19.691670 kernel: SMBIOS 3.0.0 present. Nov 5 15:03:19.691676 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Nov 5 15:03:19.691682 kernel: NET: Registered protocol family 16 Nov 5 15:03:19.691689 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Nov 5 15:03:19.691695 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Nov 5 15:03:19.691702 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Nov 5 15:03:19.691709 kernel: audit: initializing netlink subsys (disabled) Nov 5 15:03:19.691715 kernel: audit: type=2000 audit(0.038:1): state=initialized audit_enabled=0 res=1 Nov 5 15:03:19.691721 kernel: thermal_sys: Registered thermal governor 'step_wise' Nov 5 15:03:19.691727 kernel: cpuidle: using governor menu Nov 5 15:03:19.691733 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Nov 5 15:03:19.691739 kernel: ASID allocator initialised with 32768 entries Nov 5 15:03:19.691745 kernel: ACPI: bus type PCI registered Nov 5 15:03:19.691751 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Nov 5 15:03:19.691758 kernel: Serial: AMBA PL011 UART driver Nov 5 15:03:19.691764 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Nov 5 15:03:19.691771 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Nov 5 15:03:19.691777 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Nov 5 15:03:19.691783 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Nov 5 15:03:19.691796 kernel: cryptd: max_cpu_qlen set to 1000 Nov 5 15:03:19.691804 kernel: ACPI: Added _OSI(Module Device) Nov 5 15:03:19.691810 kernel: ACPI: Added _OSI(Processor Device) Nov 5 15:03:19.691817 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Nov 5 15:03:19.691824 kernel: ACPI: Added _OSI(Processor Aggregator Device) Nov 5 15:03:19.691830 kernel: ACPI: Added _OSI(Linux-Dell-Video) Nov 5 15:03:19.691837 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Nov 5 15:03:19.691843 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Nov 5 15:03:19.691849 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Nov 5 15:03:19.691855 kernel: ACPI: Interpreter enabled Nov 5 15:03:19.691861 kernel: ACPI: Using GIC for interrupt routing Nov 5 15:03:19.691867 kernel: ACPI: MCFG table detected, 1 entries Nov 5 15:03:19.691873 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Nov 5 15:03:19.691879 kernel: printk: console [ttyAMA0] enabled Nov 5 15:03:19.691887 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Nov 5 15:03:19.692040 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Nov 5 15:03:19.692102 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Nov 5 15:03:19.692156 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Nov 5 15:03:19.692210 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Nov 5 15:03:19.692263 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Nov 5 15:03:19.692271 kernel: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Nov 5 15:03:19.692279 kernel: PCI host bridge to bus 0000:00 Nov 5 15:03:19.692344 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Nov 5 15:03:19.692395 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Nov 5 15:03:19.692446 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Nov 5 15:03:19.692495 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Nov 5 15:03:19.692565 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Nov 5 15:03:19.692631 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Nov 5 15:03:19.692693 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Nov 5 15:03:19.692751 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Nov 5 15:03:19.692826 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Nov 5 15:03:19.692886 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Nov 5 15:03:19.692945 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Nov 5 15:03:19.693009 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Nov 5 15:03:19.693062 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Nov 5 15:03:19.693115 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Nov 5 15:03:19.693174 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Nov 5 15:03:19.693182 kernel: ACPI: PCI Interrupt Link [GSI0] (IRQs *35) Nov 5 15:03:19.693188 kernel: ACPI: PCI Interrupt Link [GSI1] (IRQs *36) Nov 5 15:03:19.693194 kernel: ACPI: PCI Interrupt Link [GSI2] (IRQs *37) Nov 5 15:03:19.693201 kernel: ACPI: PCI Interrupt Link [GSI3] (IRQs *38) Nov 5 15:03:19.693207 kernel: vgaarb: loaded Nov 5 15:03:19.693213 kernel: Registered efivars operations Nov 5 15:03:19.693219 kernel: clocksource: Switched to clocksource arch_sys_counter Nov 5 15:03:19.693227 kernel: VFS: Disk quotas dquot_6.6.0 Nov 5 15:03:19.693234 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Nov 5 15:03:19.693240 kernel: pnp: PnP ACPI init Nov 5 15:03:19.693308 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Nov 5 15:03:19.693363 kernel: system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active) Nov 5 15:03:19.693371 kernel: pnp: PnP ACPI: found 1 devices Nov 5 15:03:19.693377 kernel: NET: Registered protocol family 2 Nov 5 15:03:19.693384 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Nov 5 15:03:19.693392 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Nov 5 15:03:19.693398 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Nov 5 15:03:19.693404 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Nov 5 15:03:19.693411 kernel: TCP: Hash tables configured (established 32768 bind 32768) Nov 5 15:03:19.693417 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 5 15:03:19.693423 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Nov 5 15:03:19.693429 kernel: NET: Registered protocol family 1 Nov 5 15:03:19.693436 kernel: PCI: CLS 0 bytes, default 64 Nov 5 15:03:19.693442 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Nov 5 15:03:19.693449 kernel: kvm [1]: HYP mode not available Nov 5 15:03:19.693455 kernel: Initialise system trusted keyrings Nov 5 15:03:19.693461 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Nov 5 15:03:19.693468 kernel: Key type asymmetric registered Nov 5 15:03:19.693474 kernel: Asymmetric key parser 'x509' registered Nov 5 15:03:19.693480 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Nov 5 15:03:19.693486 kernel: io scheduler mq-deadline registered Nov 5 15:03:19.693492 kernel: io scheduler kyber registered Nov 5 15:03:19.693498 kernel: io scheduler bfq registered Nov 5 15:03:19.693506 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Nov 5 15:03:19.693513 kernel: ACPI: Power Button [PWRB] Nov 5 15:03:19.693519 kernel: PCI Interrupt Link [GSI1] enabled at IRQ 36 Nov 5 15:03:19.693575 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Nov 5 15:03:19.693584 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Nov 5 15:03:19.693590 kernel: msm_serial: driver initialized Nov 5 15:03:19.693596 kernel: random: fast init done Nov 5 15:03:19.693602 kernel: random: crng init done Nov 5 15:03:19.693608 kernel: wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information. Nov 5 15:03:19.693615 kernel: wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld . All Rights Reserved. Nov 5 15:03:19.693623 kernel: thunder_xcv, ver 1.0 Nov 5 15:03:19.693629 kernel: thunder_bgx, ver 1.0 Nov 5 15:03:19.693635 kernel: nicpf, ver 1.0 Nov 5 15:03:19.693641 kernel: nicvf, ver 1.0 Nov 5 15:03:19.693704 kernel: rtc-efi rtc-efi.0: registered as rtc0 Nov 5 15:03:19.693757 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-11-05T15:03:19 UTC (1762354999) Nov 5 15:03:19.693765 kernel: hid: raw HID events driver (C) Jiri Kosina Nov 5 15:03:19.693772 kernel: NET: Registered protocol family 10 Nov 5 15:03:19.693780 kernel: Segment Routing with IPv6 Nov 5 15:03:19.693787 kernel: NET: Registered protocol family 17 Nov 5 15:03:19.693800 kernel: Key type dns_resolver registered Nov 5 15:03:19.693807 kernel: registered taskstats version 1 Nov 5 15:03:19.693813 kernel: Loading compiled-in X.509 certificates Nov 5 15:03:19.693819 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.10.107-flatcar: acb58ed5bddf18757940d1f794ad1dcb34e85378' Nov 5 15:03:19.693825 kernel: Key type ._fscrypt registered Nov 5 15:03:19.693831 kernel: Key type .fscrypt registered Nov 5 15:03:19.693837 kernel: Key type fscrypt-provisioning registered Nov 5 15:03:19.693845 kernel: ima: No TPM chip found, activating TPM-bypass! Nov 5 15:03:19.693852 kernel: ima: Allocated hash algorithm: sha1 Nov 5 15:03:19.693858 kernel: ima: No architecture policies found Nov 5 15:03:19.693864 kernel: Freeing unused kernel memory: 34240K Nov 5 15:03:19.693870 kernel: Run /init as init process Nov 5 15:03:19.693876 kernel: with arguments: Nov 5 15:03:19.693882 kernel: /init Nov 5 15:03:19.693888 kernel: with environment: Nov 5 15:03:19.693894 kernel: HOME=/ Nov 5 15:03:19.693900 kernel: TERM=linux Nov 5 15:03:19.693908 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Nov 5 15:03:19.693916 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 5 15:03:19.693925 systemd[1]: Detected virtualization kvm. Nov 5 15:03:19.693932 systemd[1]: Detected architecture arm64. Nov 5 15:03:19.693939 systemd[1]: Running in initial RAM disk. Nov 5 15:03:19.693945 systemd[1]: No hostname configured, using default hostname. Nov 5 15:03:19.693951 systemd[1]: Hostname set to . Nov 5 15:03:19.693960 systemd[1]: Initializing machine ID from VM UUID. Nov 5 15:03:19.693967 systemd[1]: Queued start job for default target Initrd Default Target. Nov 5 15:03:19.693973 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Nov 5 15:03:19.693980 systemd[1]: Reached target Local Encrypted Volumes. Nov 5 15:03:19.693986 systemd[1]: Reached target Path Units. Nov 5 15:03:19.693993 systemd[1]: Reached target Slice Units. Nov 5 15:03:19.694008 systemd[1]: Reached target Swaps. Nov 5 15:03:19.694015 systemd[1]: Reached target Timer Units. Nov 5 15:03:19.694024 systemd[1]: Listening on Open-iSCSI iscsid Socket. Nov 5 15:03:19.694031 systemd[1]: Listening on Open-iSCSI iscsiuio Socket. Nov 5 15:03:19.694037 systemd[1]: Listening on Journal Audit Socket. Nov 5 15:03:19.694044 systemd[1]: Listening on Journal Socket (/dev/log). Nov 5 15:03:19.694051 systemd[1]: Listening on Journal Socket. Nov 5 15:03:19.694057 systemd[1]: Listening on Network Service Netlink Socket. Nov 5 15:03:19.694064 systemd[1]: Listening on udev Control Socket. Nov 5 15:03:19.694071 systemd[1]: Listening on udev Kernel Socket. Nov 5 15:03:19.694077 systemd[1]: Reached target Socket Units. Nov 5 15:03:19.694085 systemd[1]: Starting Create List of Static Device Nodes... Nov 5 15:03:19.694092 systemd[1]: Finished Network Cleanup. Nov 5 15:03:19.694098 systemd[1]: Started Hardware RNG Entropy Gatherer Daemon. Nov 5 15:03:19.694105 systemd[1]: Starting Journal Service... Nov 5 15:03:19.694112 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Nov 5 15:03:19.694119 systemd[1]: Starting Apply Kernel Variables... Nov 5 15:03:19.694125 systemd[1]: Starting Setup Virtual Console... Nov 5 15:03:19.694132 systemd[1]: Finished Create List of Static Device Nodes. Nov 5 15:03:19.694139 systemd[1]: Finished Apply Kernel Variables. Nov 5 15:03:19.694147 systemd[1]: Finished Setup Virtual Console. Nov 5 15:03:19.694153 systemd[1]: Starting dracut ask for additional cmdline parameters... Nov 5 15:03:19.694164 systemd-journald[279]: Journal started Nov 5 15:03:19.694206 systemd-journald[279]: Runtime Journal (/run/log/journal/119adc6b86d747ff889c05594f6ef708) is 6.0M, max 48.5M, 42.4M free. Nov 5 15:03:19.697663 systemd[1]: Starting Create Static Device Nodes in /dev... Nov 5 15:03:19.699152 systemd[1]: Started Journal Service. Nov 5 15:03:19.699745 systemd[1]: Finished Create Static Device Nodes in /dev. Nov 5 15:03:19.710483 systemd[1]: Finished dracut ask for additional cmdline parameters. Nov 5 15:03:19.712447 systemd[1]: Starting dracut cmdline hook... Nov 5 15:03:19.721710 dracut-cmdline[295]: dracut-dracut-053 Nov 5 15:03:19.724136 dracut-cmdline[295]: 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 5 15:03:19.787017 kernel: SCSI subsystem initialized Nov 5 15:03:19.791020 kernel: Loading iSCSI transport class v2.0-870. Nov 5 15:03:19.797037 kernel: iscsi: registered transport (tcp) Nov 5 15:03:19.809025 kernel: iscsi: registered transport (qla4xxx) Nov 5 15:03:19.809047 kernel: QLogic iSCSI HBA Driver Nov 5 15:03:19.819346 systemd[1]: Finished dracut cmdline hook. Nov 5 15:03:19.821058 systemd[1]: Starting dracut pre-udev hook... Nov 5 15:03:19.835257 kernel: device-mapper: uevent: version 1.0.3 Nov 5 15:03:19.835309 kernel: device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com Nov 5 15:03:19.872032 kernel: raid6: neonx8 gen() 13791 MB/s Nov 5 15:03:19.889030 kernel: raid6: neonx8 xor() 10738 MB/s Nov 5 15:03:19.906028 kernel: raid6: neonx4 gen() 13564 MB/s Nov 5 15:03:19.923030 kernel: raid6: neonx4 xor() 11471 MB/s Nov 5 15:03:19.940024 kernel: raid6: neonx2 gen() 12890 MB/s Nov 5 15:03:19.957026 kernel: raid6: neonx2 xor() 9886 MB/s Nov 5 15:03:19.974025 kernel: raid6: neonx1 gen() 10737 MB/s Nov 5 15:03:19.991027 kernel: raid6: neonx1 xor() 8834 MB/s Nov 5 15:03:20.008023 kernel: raid6: int64x8 gen() 6827 MB/s Nov 5 15:03:20.025027 kernel: raid6: int64x8 xor() 3786 MB/s Nov 5 15:03:20.042025 kernel: raid6: int64x4 gen() 6896 MB/s Nov 5 15:03:20.059028 kernel: raid6: int64x4 xor() 3832 MB/s Nov 5 15:03:20.076026 kernel: raid6: int64x2 gen() 6178 MB/s Nov 5 15:03:20.093023 kernel: raid6: int64x2 xor() 3320 MB/s Nov 5 15:03:20.110024 kernel: raid6: int64x1 gen() 5005 MB/s Nov 5 15:03:20.127287 kernel: raid6: int64x1 xor() 2596 MB/s Nov 5 15:03:20.127298 kernel: raid6: using algorithm neonx8 gen() 13791 MB/s Nov 5 15:03:20.127306 kernel: raid6: .... xor() 10738 MB/s, rmw enabled Nov 5 15:03:20.128551 kernel: raid6: using neon recovery algorithm Nov 5 15:03:20.139308 kernel: xor: measuring software checksum speed Nov 5 15:03:20.139329 kernel: 8regs : 17188 MB/sec Nov 5 15:03:20.140370 kernel: 32regs : 20765 MB/sec Nov 5 15:03:20.141271 kernel: arm64_neon : 29173 MB/sec Nov 5 15:03:20.141283 kernel: xor: using function: arm64_neon (29173 MB/sec) Nov 5 15:03:20.188019 kernel: Btrfs loaded, crc32c=crc32c-generic Nov 5 15:03:20.198873 systemd[1]: Finished dracut pre-udev hook. Nov 5 15:03:20.200000 audit: BPF prog-id=6 op=LOAD Nov 5 15:03:20.201000 audit: BPF prog-id=7 op=LOAD Nov 5 15:03:20.201914 systemd[1]: Starting Rule-based Manager for Device Events and Files... Nov 5 15:03:20.204385 kernel: audit: type=1334 audit(1762355000.200:2): prog-id=6 op=LOAD Nov 5 15:03:20.204408 kernel: audit: type=1334 audit(1762355000.201:3): prog-id=7 op=LOAD Nov 5 15:03:20.204425 kernel: audit: type=1334 audit(1762355000.201:4): prog-id=8 op=LOAD Nov 5 15:03:20.201000 audit: BPF prog-id=8 op=LOAD Nov 5 15:03:20.214274 systemd-udevd[415]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Nov 5 15:03:20.216752 systemd[1]: Started Rule-based Manager for Device Events and Files. Nov 5 15:03:20.220588 systemd[1]: Starting dracut pre-trigger hook... Nov 5 15:03:20.223430 systemd[1]: Starting Network Configuration... Nov 5 15:03:20.222000 audit: BPF prog-id=9 op=LOAD Nov 5 15:03:20.227033 kernel: audit: type=1334 audit(1762355000.222:5): prog-id=9 op=LOAD Nov 5 15:03:20.232148 dracut-pre-trigger[426]: rd.md=0: removing MD RAID activation Nov 5 15:03:20.243667 systemd-networkd[427]: lo: Link UP Nov 5 15:03:20.244628 systemd-networkd[427]: lo: Gained carrier Nov 5 15:03:20.245015 systemd-networkd[427]: Enumeration completed Nov 5 15:03:20.248053 kernel: audit: type=1334 audit(1762355000.246:6): prog-id=10 op=LOAD Nov 5 15:03:20.246000 audit: BPF prog-id=10 op=LOAD Nov 5 15:03:20.245121 systemd[1]: Started Network Configuration. Nov 5 15:03:20.247562 systemd[1]: Starting Network Name Resolution... Nov 5 15:03:20.261440 systemd[1]: Finished dracut pre-trigger hook. Nov 5 15:03:20.263190 systemd[1]: Starting Coldplug All udev Devices... Nov 5 15:03:20.273597 systemd-udevd[415]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Nov 5 15:03:20.276285 systemd-resolved[447]: Positive Trust Anchors: Nov 5 15:03:20.276301 systemd-resolved[447]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 5 15:03:20.276331 systemd-resolved[447]: 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 5 15:03:20.276635 systemd-resolved[447]: Defaulting to hostname 'linux'. Nov 5 15:03:20.277596 systemd[1]: Started Network Name Resolution. Nov 5 15:03:20.278826 systemd[1]: Reached target Network. Nov 5 15:03:20.280939 systemd[1]: Reached target Host and Network Name Lookups. Nov 5 15:03:20.289392 systemd[1]: Starting iSCSI UserSpace I/O driver... Nov 5 15:03:20.296652 systemd[1]: Started iSCSI UserSpace I/O driver. Nov 5 15:03:20.297921 systemd[1]: Finished Coldplug All udev Devices. Nov 5 15:03:20.300364 systemd[1]: Starting Open-iSCSI... Nov 5 15:03:20.304924 iscsid[480]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Nov 5 15:03:20.304924 iscsid[480]: 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 5 15:03:20.304924 iscsid[480]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Nov 5 15:03:20.304924 iscsid[480]: If using hardware iscsi like qla4xxx this message can be ignored. Nov 5 15:03:20.304924 iscsid[480]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Nov 5 15:03:20.304924 iscsid[480]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Nov 5 15:03:20.309324 systemd[1]: Started Open-iSCSI. Nov 5 15:03:20.314755 systemd[1]: Starting dracut initqueue hook... Nov 5 15:03:20.347050 systemd-udevd[467]: Using default interface naming scheme 'v249'. Nov 5 15:03:20.351033 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Nov 5 15:03:20.351201 kernel: vda: detected capacity change from 0 to 4756340736 Nov 5 15:03:20.354023 systemd-networkd[427]: eth0: Link UP Nov 5 15:03:20.361033 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 5 15:03:20.385834 systemd[1]: Found device /dev/disk/by-partlabel/USR-A. Nov 5 15:03:20.388478 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/vda6 scanned by systemd-udevd (465) Nov 5 15:03:20.387613 systemd[1]: Found device /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Nov 5 15:03:20.394343 systemd[1]: Found device /dev/disk/by-label/ROOT. Nov 5 15:03:20.397111 systemd[1]: Found device /dev/disk/by-label/EFI-SYSTEM. Nov 5 15:03:20.401382 systemd[1]: Found device /dev/disk/by-label/OEM. Nov 5 15:03:20.402373 systemd[1]: Reached target Initrd Root Device. Nov 5 15:03:20.404658 systemd[1]: Starting Generate new UUID for disk GPT if necessary... Nov 5 15:03:20.409324 systemd[1]: Finished dracut initqueue hook. Nov 5 15:03:20.410481 systemd[1]: Reached target Preparation for Remote File Systems. Nov 5 15:03:20.412357 systemd[1]: Reached target Remote Encrypted Volumes. Nov 5 15:03:20.414126 systemd[1]: Reached target Remote File Systems. Nov 5 15:03:20.416784 systemd[1]: Starting dracut pre-mount hook... Nov 5 15:03:20.418877 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 5 15:03:20.428133 systemd[1]: Finished dracut pre-mount hook. Nov 5 15:03:21.403063 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Nov 5 15:03:21.403155 systemd-networkd[427]: eth0: Gained carrier Nov 5 15:03:21.414087 systemd-networkd[427]: eth0: DHCPv4 address 10.0.0.7/16 via 10.0.0.1 Nov 5 15:03:21.426310 disk-uuid[501]: The operation has completed successfully. Nov 5 15:03:21.427588 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Nov 5 15:03:21.454097 systemd[1]: disk-uuid.service: Deactivated successfully. Nov 5 15:03:21.454232 systemd[1]: Finished Generate new UUID for disk GPT if necessary. Nov 5 15:03:21.457213 systemd[1]: Starting Ignition (setup)... Nov 5 15:03:21.465618 kernel: BTRFS info (device vda6): disk space caching is enabled Nov 5 15:03:21.465661 kernel: BTRFS info (device vda6): has skinny extents Nov 5 15:03:21.472112 systemd[1]: mnt-oem.mount: Deactivated successfully. Nov 5 15:03:21.477505 systemd[1]: ignition-setup.service: Deactivated successfully. Nov 5 15:03:21.477633 systemd[1]: Finished Ignition (setup). Nov 5 15:03:21.480170 systemd[1]: Starting Ignition (disks)... Nov 5 15:03:21.489846 ignition[539]: Ignition v0.36.1-15-gde4e6cc9 Nov 5 15:03:21.489865 ignition[539]: Stage: disks Nov 5 15:03:21.489874 ignition[539]: reading system config file "/usr/lib/ignition/base.ign" Nov 5 15:03:21.489886 ignition[539]: no config at "/usr/lib/ignition/base.ign" Nov 5 15:03:21.489934 ignition[539]: parsed url from cmdline: "" Nov 5 15:03:21.489937 ignition[539]: no config URL provided Nov 5 15:03:21.489942 ignition[539]: reading system config file "/usr/lib/ignition/user.ign" Nov 5 15:03:21.489950 ignition[539]: no config at "/usr/lib/ignition/user.ign" Nov 5 15:03:21.489970 ignition[539]: op(1): [started] loading QEMU firmware config module Nov 5 15:03:21.489974 ignition[539]: op(1): executing: "modprobe" "qemu_fw_cfg" Nov 5 15:03:21.490814 ignition[539]: op(1): [finished] loading QEMU firmware config module Nov 5 15:03:21.505232 ignition[539]: parsing config with SHA512: 98000ff647fa3b80cfe96ff9102c63331ef55a6b85c9034d58193089bf8f299065d20e0d5555df8cb0e1c475cb23b8ee8fcc93c8e66e3a217822991e96441fc8 Nov 5 15:03:21.507559 ignition[539]: disks: disks passed Nov 5 15:03:21.507570 ignition[539]: Ignition finished successfully Nov 5 15:03:21.508302 systemd[1]: ignition-disks.service: Deactivated successfully. Nov 5 15:03:21.508503 systemd[1]: Finished Ignition (disks). Nov 5 15:03:21.510276 systemd[1]: Reached target Preparation for Local File Systems. Nov 5 15:03:21.512817 systemd[1]: Starting File System Check on /dev/disk/by-label/ROOT... Nov 5 15:03:21.516440 systemd[1]: Starting Verity Setup for /dev/mapper/usr... Nov 5 15:03:21.523134 systemd-fsck[551]: ROOT: clean, 528/553792 files, 37752/553472 blocks Nov 5 15:03:21.527939 systemd[1]: Finished File System Check on /dev/disk/by-label/ROOT. Nov 5 15:03:21.531654 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Nov 5 15:03:21.531402 systemd[1]: Mounting /sysroot... Nov 5 15:03:21.555016 systemd[1]: Found device /dev/mapper/usr. Nov 5 15:03:21.557296 systemd[1]: Starting File System Check on /dev/mapper/usr... Nov 5 15:03:21.560056 systemd[1]: Finished Verity Setup for /dev/mapper/usr. Nov 5 15:03:21.568169 systemd-fsck[569]: fsck.ext4: Operation not permitted while trying to open /dev/mapper/usr Nov 5 15:03:21.568169 systemd-fsck[569]: You must have r/w access to the filesystem or be root Nov 5 15:03:21.571612 systemd-fsck[566]: fsck failed with exit status 8. Nov 5 15:03:21.571621 systemd-fsck[566]: Ignoring error. Nov 5 15:03:21.574187 systemd[1]: Finished File System Check on /dev/mapper/usr. Nov 5 15:03:21.576503 systemd[1]: Mounting /sysusr/usr... Nov 5 15:03:21.581490 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null) Nov 5 15:03:21.581554 kernel: ext4 filesystem being mounted at /sysroot supports timestamps until 2038 (0x7fffffff) Nov 5 15:03:21.583309 systemd[1]: Mounted /sysroot. Nov 5 15:03:21.584655 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: (null) Nov 5 15:03:21.584601 systemd[1]: Mounted /sysusr/usr. Nov 5 15:03:21.585571 systemd[1]: Reached target Initrd Root File System. Nov 5 15:03:21.586833 systemd[1]: Reached target Local File Systems. Nov 5 15:03:21.588432 systemd[1]: Reached target System Initialization. Nov 5 15:03:21.589904 systemd[1]: Reached target Basic System. Nov 5 15:03:21.592657 systemd[1]: Mounting /sysroot/usr... Nov 5 15:03:21.594869 systemd[1]: Mounted /sysroot/usr. Nov 5 15:03:21.596426 systemd[1]: Starting Root filesystem setup... Nov 5 15:03:21.624360 systemd[1]: initrd-setup-root.service: Deactivated successfully. Nov 5 15:03:21.624491 systemd[1]: Finished Root filesystem setup. Nov 5 15:03:21.627107 systemd[1]: Starting Ignition (files)... Nov 5 15:03:21.628486 systemd[1]: Starting /sysroot/boot... Nov 5 15:03:21.630356 ignition[584]: Ignition v0.36.1-15-gde4e6cc9 Nov 5 15:03:21.630372 ignition[584]: Stage: files Nov 5 15:03:21.630382 ignition[584]: reading system config file "/usr/lib/ignition/base.ign" Nov 5 15:03:21.630392 ignition[584]: no config at "/usr/lib/ignition/base.ign" Nov 5 15:03:21.630916 ignition[584]: files: compiled without relabeling support, skipping Nov 5 15:03:21.645615 systemd[1]: Finished /sysroot/boot. Nov 5 15:03:21.648591 ignition[584]: files: createUsers: op(1): [started] creating or modifying user "core" Nov 5 15:03:21.648604 ignition[584]: files: createUsers: op(1): executing: "/usr/sbin/usermod" "--root" "/sysroot" "core" Nov 5 15:03:21.649883 ignition[584]: files: createUsers: op(1): [finished] creating or modifying user "core" Nov 5 15:03:21.649890 ignition[584]: files: createUsers: op(2): [started] adding ssh keys to user "core" Nov 5 15:03:21.651013 ignition[584]: files: createUsers: op(2): [finished] adding ssh keys to user "core" Nov 5 15:03:21.653591 systemd[1]: ignition-files.service: Deactivated successfully. Nov 5 15:03:21.651045 ignition[584]: files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/etc/flatcar/update.conf" Nov 5 15:03:21.653711 systemd[1]: Finished Ignition (files). Nov 5 15:03:21.651342 ignition[584]: files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/etc/flatcar/update.conf" Nov 5 15:03:21.655637 systemd[1]: Condition check resulted in Populate torcx store to satisfy profile being skipped. Nov 5 15:03:21.662000 audit: BPF prog-id=6 op=UNLOAD Nov 5 15:03:21.651348 ignition[584]: files: op(4): [started] processing unit "coreos-metadata.service" Nov 5 15:03:21.666900 kernel: audit: type=1334 audit(1762355001.662:7): prog-id=6 op=UNLOAD Nov 5 15:03:21.656378 systemd[1]: Starting Ignition (record completion)... Nov 5 15:03:21.651443 ignition[584]: files: op(4): op(5): [started] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Nov 5 15:03:21.657957 systemd[1]: Starting Reload Configuration from the Real Root... Nov 5 15:03:21.651785 ignition[584]: files: op(4): op(5): [finished] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Nov 5 15:03:21.680161 kernel: audit: type=1334 audit(1762355001.670:8): prog-id=9 op=UNLOAD Nov 5 15:03:21.680185 kernel: audit: type=1334 audit(1762355001.670:9): prog-id=3 op=UNLOAD Nov 5 15:03:21.680193 kernel: audit: type=1334 audit(1762355001.670:10): prog-id=10 op=UNLOAD Nov 5 15:03:21.670000 audit: BPF prog-id=9 op=UNLOAD Nov 5 15:03:21.670000 audit: BPF prog-id=3 op=UNLOAD Nov 5 15:03:21.670000 audit: BPF prog-id=10 op=UNLOAD Nov 5 15:03:21.660121 systemd[1]: ignition-quench.service: Deactivated successfully. Nov 5 15:03:21.651791 ignition[584]: files: op(4): [finished] processing unit "coreos-metadata.service" Nov 5 15:03:21.660242 systemd[1]: Finished Ignition (record completion). Nov 5 15:03:21.651797 ignition[584]: files: files passed Nov 5 15:03:21.662852 systemd[1]: Reloading. Nov 5 15:03:21.651808 ignition[584]: Ignition finished successfully Nov 5 15:03:21.756000 audit: BPF prog-id=11 op=LOAD Nov 5 15:03:21.756000 audit: BPF prog-id=12 op=LOAD Nov 5 15:03:21.756000 audit: BPF prog-id=13 op=LOAD Nov 5 15:03:21.756000 audit: BPF prog-id=7 op=UNLOAD Nov 5 15:03:21.757000 audit: BPF prog-id=8 op=UNLOAD Nov 5 15:03:21.757000 audit: BPF prog-id=14 op=LOAD Nov 5 15:03:21.758000 audit: BPF prog-id=15 op=LOAD Nov 5 15:03:21.758000 audit: BPF prog-id=16 op=LOAD Nov 5 15:03:21.758000 audit: BPF prog-id=17 op=LOAD Nov 5 15:03:21.758000 audit: BPF prog-id=4 op=UNLOAD Nov 5 15:03:21.758000 audit: BPF prog-id=5 op=UNLOAD Nov 5 15:03:21.758000 audit: BPF prog-id=18 op=LOAD Nov 5 15:03:21.773926 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Nov 5 15:03:21.774063 systemd[1]: Finished Reload Configuration from the Real Root. Nov 5 15:03:21.776193 systemd[1]: Reached target Initrd File Systems. Nov 5 15:03:21.777875 systemd[1]: Reached target Initrd Default Target. Nov 5 15:03:21.779591 systemd[1]: Condition check resulted in dracut mount hook being skipped. Nov 5 15:03:21.780441 systemd[1]: Starting dracut pre-pivot and cleanup hook... Nov 5 15:03:21.791324 systemd[1]: Finished dracut pre-pivot and cleanup hook. Nov 5 15:03:21.793202 systemd[1]: Starting Cleaning Up and Shutting Down Daemons... Nov 5 15:03:21.799710 systemd[1]: Stopped target Host and Network Name Lookups. Nov 5 15:03:21.800891 systemd[1]: Stopped target Remote Encrypted Volumes. Nov 5 15:03:21.802837 systemd[1]: Stopped target Timer Units. Nov 5 15:03:21.804540 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Nov 5 15:03:21.804662 systemd[1]: Stopped dracut pre-pivot and cleanup hook. Nov 5 15:03:21.806245 systemd[1]: Stopped target Initrd Default Target. Nov 5 15:03:21.807981 systemd[1]: Stopped target Basic System. Nov 5 15:03:21.809591 systemd[1]: Stopped target Initrd Root Device. Nov 5 15:03:21.811144 systemd[1]: Stopped target Path Units. Nov 5 15:03:21.812501 systemd[1]: Stopped target Remote File Systems. Nov 5 15:03:21.814067 systemd[1]: Stopped target Preparation for Remote File Systems. Nov 5 15:03:21.815817 systemd[1]: Stopped target Slice Units. Nov 5 15:03:21.817324 systemd[1]: Stopped target Socket Units. Nov 5 15:03:21.819021 systemd[1]: Stopped target System Initialization. Nov 5 15:03:21.820540 systemd[1]: Stopped target Local File Systems. Nov 5 15:03:21.821939 systemd[1]: Stopped target Preparation for Local File Systems. Nov 5 15:03:21.823763 systemd[1]: Stopped target Swaps. Nov 5 15:03:21.825190 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Nov 5 15:03:21.825313 systemd[1]: Stopped dracut pre-mount hook. Nov 5 15:03:21.826930 systemd[1]: Stopped target Local Encrypted Volumes. Nov 5 15:03:21.828364 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Nov 5 15:03:21.830049 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Nov 5 15:03:21.835499 iscsid[480]: iscsid shutting down. Nov 5 15:03:21.831224 systemd[1]: dracut-initqueue.service: Deactivated successfully. Nov 5 15:03:21.831340 systemd[1]: Stopped dracut initqueue hook. Nov 5 15:03:21.832960 systemd[1]: Stopping Open-iSCSI... Nov 5 15:03:21.835216 systemd[1]: Stopping /sysroot/boot... Nov 5 15:03:21.836102 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Nov 5 15:03:21.836235 systemd[1]: Stopped Coldplug All udev Devices. Nov 5 15:03:21.837621 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Nov 5 15:03:21.837707 systemd[1]: Stopped dracut pre-trigger hook. Nov 5 15:03:21.840410 systemd[1]: iscsid.service: Deactivated successfully. Nov 5 15:03:21.840608 systemd[1]: Stopped Open-iSCSI. Nov 5 15:03:21.842206 systemd[1]: iscsid.socket: Deactivated successfully. Nov 5 15:03:21.842270 systemd[1]: Closed Open-iSCSI iscsid Socket. Nov 5 15:03:21.843399 systemd[1]: Stopping iSCSI UserSpace I/O driver... Nov 5 15:03:21.847053 systemd[1]: iscsiuio.service: Deactivated successfully. Nov 5 15:03:21.847171 systemd[1]: Stopped iSCSI UserSpace I/O driver. Nov 5 15:03:21.848655 systemd[1]: initrd-cleanup.service: Deactivated successfully. Nov 5 15:03:21.848766 systemd[1]: Finished Cleaning Up and Shutting Down Daemons. Nov 5 15:03:21.850517 systemd[1]: sysroot-boot.service: Deactivated successfully. Nov 5 15:03:21.850628 systemd[1]: Stopped /sysroot/boot. Nov 5 15:03:21.852456 systemd[1]: Stopped target Network. Nov 5 15:03:21.853318 systemd[1]: iscsiuio.socket: Deactivated successfully. Nov 5 15:03:21.867000 audit: BPF prog-id=18 op=UNLOAD Nov 5 15:03:21.853397 systemd[1]: Closed Open-iSCSI iscsiuio Socket. Nov 5 15:03:21.854769 systemd[1]: Stopping Network Name Resolution... Nov 5 15:03:21.859199 systemd[1]: systemd-resolved.service: Deactivated successfully. Nov 5 15:03:21.859324 systemd[1]: Stopped Network Name Resolution. Nov 5 15:03:21.861115 systemd[1]: Stopping Network Configuration... Nov 5 15:03:21.864139 systemd-networkd[427]: eth0: DHCP lease lost Nov 5 15:03:21.871042 systemd-networkd[427]: eth0: DHCPv6 lease lost Nov 5 15:03:21.872050 systemd[1]: systemd-networkd.service: Deactivated successfully. Nov 5 15:03:21.872178 systemd[1]: Stopped Network Configuration. Nov 5 15:03:21.873570 systemd[1]: systemd-networkd.socket: Deactivated successfully. Nov 5 15:03:21.873601 systemd[1]: Closed Network Service Netlink Socket. Nov 5 15:03:21.882000 audit: BPF prog-id=14 op=UNLOAD Nov 5 15:03:21.875647 systemd[1]: Stopping Network Cleanup... Nov 5 15:03:21.876351 systemd[1]: systemd-sysctl.service: Deactivated successfully. Nov 5 15:03:21.876426 systemd[1]: Stopped Apply Kernel Variables. Nov 5 15:03:21.888000 audit: BPF prog-id=11 op=UNLOAD Nov 5 15:03:21.878203 systemd[1]: Stopping Rule-based Manager for Device Events and Files... Nov 5 15:03:21.884902 systemd[1]: systemd-udevd.service: Deactivated successfully. Nov 5 15:03:21.885117 systemd[1]: Stopped Rule-based Manager for Device Events and Files. Nov 5 15:03:21.887346 systemd[1]: network-cleanup.service: Deactivated successfully. Nov 5 15:03:21.887460 systemd[1]: Stopped Network Cleanup. Nov 5 15:03:21.888949 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Nov 5 15:03:21.888986 systemd[1]: Closed udev Control Socket. Nov 5 15:03:21.890365 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Nov 5 15:03:21.890409 systemd[1]: Closed udev Kernel Socket. Nov 5 15:03:21.891953 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Nov 5 15:03:21.892019 systemd[1]: Stopped dracut pre-udev hook. Nov 5 15:03:21.893568 systemd[1]: dracut-cmdline.service: Deactivated successfully. Nov 5 15:03:21.893612 systemd[1]: Stopped dracut cmdline hook. Nov 5 15:03:21.895188 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Nov 5 15:03:21.895231 systemd[1]: Stopped dracut ask for additional cmdline parameters. Nov 5 15:03:21.897771 systemd[1]: Starting Cleanup udev Database... Nov 5 15:03:21.899555 systemd[1]: Stopping Hardware RNG Entropy Gatherer Daemon... Nov 5 15:03:21.900868 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Nov 5 15:03:21.900931 systemd[1]: Stopped Create Static Device Nodes in /dev. Nov 5 15:03:21.902850 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Nov 5 15:03:21.916000 audit: BPF prog-id=15 op=UNLOAD Nov 5 15:03:21.902901 systemd[1]: Stopped Create List of Static Device Nodes. Nov 5 15:03:21.904415 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Nov 5 15:03:21.904463 systemd[1]: Stopped Setup Virtual Console. Nov 5 15:03:21.906351 systemd[1]: rngd.service: Deactivated successfully. Nov 5 15:03:21.906469 systemd[1]: Stopped Hardware RNG Entropy Gatherer Daemon. Nov 5 15:03:21.907793 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Nov 5 15:03:21.907915 systemd[1]: Finished Cleanup udev Database. Nov 5 15:03:21.909334 systemd[1]: Reached target Switch Root. Nov 5 15:03:21.911334 systemd[1]: Starting Switch Root... Nov 5 15:03:21.916639 systemd[1]: Switching root. Nov 5 15:03:21.927374 systemd-journald[279]: Journal stopped Nov 5 15:03:24.107227 systemd-journald[279]: Received SIGTERM from PID 1 (n/a). Nov 5 15:03:24.107287 kernel: SELinux: policy capability network_peer_controls=1 Nov 5 15:03:24.107300 kernel: SELinux: policy capability open_perms=1 Nov 5 15:03:24.107310 kernel: SELinux: policy capability extended_socket_class=1 Nov 5 15:03:24.107320 kernel: SELinux: policy capability always_check_network=0 Nov 5 15:03:24.107329 kernel: SELinux: policy capability cgroup_seclabel=1 Nov 5 15:03:24.107339 kernel: SELinux: policy capability nnp_nosuid_transition=1 Nov 5 15:03:24.107349 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Nov 5 15:03:24.107362 systemd[1]: Successfully loaded SELinux policy in 31.279ms. Nov 5 15:03:24.107380 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 6.797ms. Nov 5 15:03:24.107390 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 5 15:03:24.107401 systemd[1]: Detected virtualization kvm. Nov 5 15:03:24.107411 systemd[1]: Detected architecture arm64. Nov 5 15:03:24.107420 systemd[1]: Detected first boot. Nov 5 15:03:24.107430 systemd[1]: Initializing machine ID from VM UUID. Nov 5 15:03:24.107440 systemd[1]: Populated /etc with preset unit settings. Nov 5 15:03:24.107453 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 5 15:03:24.107464 systemd[1]: initrd-switch-root.service: Current command vanished from the unit file, execution of the command list won't be resumed. Nov 5 15:03:24.107474 kernel: kauditd_printk_skb: 24 callbacks suppressed Nov 5 15:03:24.107483 kernel: audit: type=1334 audit(1762355003.965:35): prog-id=21 op=LOAD Nov 5 15:03:24.107493 kernel: audit: type=1334 audit(1762355003.965:36): prog-id=22 op=LOAD Nov 5 15:03:24.107502 kernel: audit: type=1334 audit(1762355003.965:37): prog-id=23 op=LOAD Nov 5 15:03:24.107512 systemd[1]: initrd-switch-root.service: Deactivated successfully. Nov 5 15:03:24.107522 kernel: audit: type=1334 audit(1762355003.965:38): prog-id=16 op=UNLOAD Nov 5 15:03:24.107532 systemd[1]: Stopped Switch Root. Nov 5 15:03:24.107543 kernel: audit: type=1334 audit(1762355003.965:39): prog-id=17 op=UNLOAD Nov 5 15:03:24.107552 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Nov 5 15:03:24.107562 systemd[1]: Created slice Slice /system/addon-config. Nov 5 15:03:24.107576 systemd[1]: Created slice Slice /system/addon-run. Nov 5 15:03:24.107587 systemd[1]: Created slice Slice /system/getty. Nov 5 15:03:24.107597 systemd[1]: Created slice Slice /system/modprobe. Nov 5 15:03:24.107607 kernel: audit: type=1334 audit(1762355003.982:40): prog-id=21 op=UNLOAD Nov 5 15:03:24.107616 systemd[1]: Created slice Slice /system/serial-getty. Nov 5 15:03:24.107626 systemd[1]: Created slice Slice /system/system-cloudinit. Nov 5 15:03:24.107638 systemd[1]: Created slice Slice /system/systemd-fsck. Nov 5 15:03:24.107648 systemd[1]: Created slice User and Session Slice. Nov 5 15:03:24.107658 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Nov 5 15:03:24.107669 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Nov 5 15:03:24.107679 systemd[1]: Set up automount Boot partition Automount Point. Nov 5 15:03:24.107689 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Nov 5 15:03:24.107699 systemd[1]: Stopped target Switch Root. Nov 5 15:03:24.107709 systemd[1]: Stopped target Initrd File Systems. Nov 5 15:03:24.107720 systemd[1]: Stopped target Initrd Root File System. Nov 5 15:03:24.107731 systemd[1]: Reached target Remote Encrypted Volumes. Nov 5 15:03:24.107741 systemd[1]: Reached target Remote File Systems. Nov 5 15:03:24.107752 systemd[1]: Reached target Slice Units. Nov 5 15:03:24.107762 systemd[1]: Reached target Swaps. Nov 5 15:03:24.107771 systemd[1]: Reached target Verify torcx succeeded. Nov 5 15:03:24.107781 systemd[1]: Reached target Local Verity Protected Volumes. Nov 5 15:03:24.107791 systemd[1]: Listening on Process Core Dump Socket. Nov 5 15:03:24.107801 systemd[1]: Listening on initctl Compatibility Named Pipe. Nov 5 15:03:24.107810 systemd[1]: Listening on Network Service Netlink Socket. Nov 5 15:03:24.107942 systemd[1]: Listening on udev Control Socket. Nov 5 15:03:24.107963 systemd[1]: Listening on udev Kernel Socket. Nov 5 15:03:24.107973 systemd[1]: Mounting Huge Pages File System... Nov 5 15:03:24.107983 systemd[1]: Mounting POSIX Message Queue File System... Nov 5 15:03:24.107993 systemd[1]: Mounting External Media Directory... Nov 5 15:03:24.108018 systemd[1]: Mounting Kernel Debug File System... Nov 5 15:03:24.108028 systemd[1]: Mounting Kernel Trace File System... Nov 5 15:03:24.108038 systemd[1]: Mounting Temporary Directory /tmp... Nov 5 15:03:24.108047 systemd[1]: Starting Create missing system files... Nov 5 15:03:24.108058 systemd[1]: Starting Create List of Static Device Nodes... Nov 5 15:03:24.108068 systemd[1]: Starting Load Kernel Module configfs... Nov 5 15:03:24.108080 systemd[1]: Starting Load Kernel Module drm... Nov 5 15:03:24.108090 systemd[1]: Starting Load Kernel Module fuse... Nov 5 15:03:24.108100 systemd[1]: Condition check resulted in Create /etc/nsswitch.conf being skipped. Nov 5 15:03:24.108110 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Nov 5 15:03:24.108121 systemd[1]: Stopped File System Check on Root Device. Nov 5 15:03:24.108130 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Nov 5 15:03:24.108140 systemd[1]: Stopped systemd-fsck-usr.service. Nov 5 15:03:24.108150 systemd[1]: Stopped Journal Service. Nov 5 15:03:24.108159 kernel: fuse: init (API version 7.32) Nov 5 15:03:24.108170 systemd[1]: Starting Journal Service... Nov 5 15:03:24.108180 kernel: audit: type=1334 audit(1762355004.085:41): prog-id=24 op=LOAD Nov 5 15:03:24.108189 kernel: audit: type=1334 audit(1762355004.085:42): prog-id=25 op=LOAD Nov 5 15:03:24.108198 kernel: audit: type=1334 audit(1762355004.085:43): prog-id=26 op=LOAD Nov 5 15:03:24.108208 kernel: audit: type=1334 audit(1762355004.085:44): prog-id=22 op=UNLOAD Nov 5 15:03:24.108241 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Nov 5 15:03:24.108257 systemd[1]: Starting Remount Root and Kernel File Systems... Nov 5 15:03:24.108268 systemd[1]: Starting Apply Kernel Variables... Nov 5 15:03:24.108280 systemd[1]: Starting Coldplug All udev Devices... Nov 5 15:03:24.108290 systemd[1]: verity-setup.service: Deactivated successfully. Nov 5 15:03:24.108300 systemd[1]: Stopped verity-setup.service. Nov 5 15:03:24.108310 systemd[1]: Mounted Huge Pages File System. Nov 5 15:03:24.108322 systemd-journald[799]: Journal started Nov 5 15:03:24.108363 systemd-journald[799]: Runtime Journal (/run/log/journal/119adc6b86d747ff889c05594f6ef708) is 6.0M, max 48.5M, 42.4M free. Nov 5 15:03:21.991000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Nov 5 15:03:22.023000 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 5 15:03:22.023000 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 5 15:03:22.023000 audit: BPF prog-id=19 op=LOAD Nov 5 15:03:22.023000 audit: BPF prog-id=19 op=UNLOAD Nov 5 15:03:22.023000 audit: BPF prog-id=20 op=LOAD Nov 5 15:03:22.023000 audit: BPF prog-id=20 op=UNLOAD Nov 5 15:03:22.055000 audit[748]: AVC avc: denied { associate } for pid=748 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 5 15:03:23.965000 audit: BPF prog-id=21 op=LOAD Nov 5 15:03:23.965000 audit: BPF prog-id=22 op=LOAD Nov 5 15:03:23.965000 audit: BPF prog-id=23 op=LOAD Nov 5 15:03:23.965000 audit: BPF prog-id=16 op=UNLOAD Nov 5 15:03:23.965000 audit: BPF prog-id=17 op=UNLOAD Nov 5 15:03:23.982000 audit: BPF prog-id=21 op=UNLOAD Nov 5 15:03:24.085000 audit: BPF prog-id=24 op=LOAD Nov 5 15:03:24.085000 audit: BPF prog-id=25 op=LOAD Nov 5 15:03:24.085000 audit: BPF prog-id=26 op=LOAD Nov 5 15:03:24.085000 audit: BPF prog-id=22 op=UNLOAD Nov 5 15:03:24.085000 audit: BPF prog-id=23 op=UNLOAD Nov 5 15:03:24.106000 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 5 15:03:23.954504 systemd[1]: Queued start job for default target Multi-User System. Nov 5 15:03:22.054818 /usr/lib/systemd/system-generators/torcx-generator[748]: time="2025-11-05T15:03:22Z" 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 5 15:03:23.967638 systemd[1]: systemd-journald.service: Deactivated successfully. Nov 5 15:03:22.055130 /usr/lib/systemd/system-generators/torcx-generator[748]: time="2025-11-05T15:03:22Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Nov 5 15:03:22.055151 /usr/lib/systemd/system-generators/torcx-generator[748]: time="2025-11-05T15:03:22Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Nov 5 15:03:22.055288 /usr/lib/systemd/system-generators/torcx-generator[748]: time="2025-11-05T15:03:22Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Nov 5 15:03:22.055301 /usr/lib/systemd/system-generators/torcx-generator[748]: time="2025-11-05T15:03:22Z" level=debug msg="skipped missing lower profile" missing profile=oem Nov 5 15:03:22.055332 /usr/lib/systemd/system-generators/torcx-generator[748]: time="2025-11-05T15:03:22Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Nov 5 15:03:22.055346 /usr/lib/systemd/system-generators/torcx-generator[748]: time="2025-11-05T15:03:22Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Nov 5 15:03:22.055564 /usr/lib/systemd/system-generators/torcx-generator[748]: time="2025-11-05T15:03:22Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Nov 5 15:03:22.055600 /usr/lib/systemd/system-generators/torcx-generator[748]: time="2025-11-05T15:03:22Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Nov 5 15:03:22.055620 /usr/lib/systemd/system-generators/torcx-generator[748]: time="2025-11-05T15:03:22Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Nov 5 15:03:22.055994 /usr/lib/systemd/system-generators/torcx-generator[748]: time="2025-11-05T15:03:22Z" 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 5 15:03:22.056037 /usr/lib/systemd/system-generators/torcx-generator[748]: time="2025-11-05T15:03:22Z" 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 5 15:03:22.056058 /usr/lib/systemd/system-generators/torcx-generator[748]: time="2025-11-05T15:03:22Z" 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 5 15:03:22.056074 /usr/lib/systemd/system-generators/torcx-generator[748]: time="2025-11-05T15:03:22Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Nov 5 15:03:22.056094 /usr/lib/systemd/system-generators/torcx-generator[748]: time="2025-11-05T15:03:22Z" 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 5 15:03:22.056108 /usr/lib/systemd/system-generators/torcx-generator[748]: time="2025-11-05T15:03:22Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Nov 5 15:03:23.771611 /usr/lib/systemd/system-generators/torcx-generator[748]: time="2025-11-05T15:03:23Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Nov 5 15:03:23.771924 /usr/lib/systemd/system-generators/torcx-generator[748]: time="2025-11-05T15:03:23Z" 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 5 15:03:23.772051 /usr/lib/systemd/system-generators/torcx-generator[748]: time="2025-11-05T15:03:23Z" 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 5 15:03:23.772202 /usr/lib/systemd/system-generators/torcx-generator[748]: time="2025-11-05T15:03:23Z" 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 5 15:03:23.772264 /usr/lib/systemd/system-generators/torcx-generator[748]: time="2025-11-05T15:03:23Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Nov 5 15:03:23.772326 /usr/lib/systemd/system-generators/torcx-generator[748]: time="2025-11-05T15:03:23Z" 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 5 15:03:24.110629 systemd[1]: Started Journal Service. Nov 5 15:03:24.111193 systemd[1]: Mounted POSIX Message Queue File System. Nov 5 15:03:24.112288 systemd[1]: Mounted External Media Directory. Nov 5 15:03:24.113277 systemd[1]: Mounted Kernel Debug File System. Nov 5 15:03:24.114308 systemd[1]: Mounted Kernel Trace File System. Nov 5 15:03:24.115323 systemd[1]: Mounted Temporary Directory /tmp. Nov 5 15:03:24.116636 systemd[1]: Finished Create missing system files. Nov 5 15:03:24.117934 systemd[1]: Finished Create List of Static Device Nodes. Nov 5 15:03:24.119264 systemd[1]: modprobe@configfs.service: Deactivated successfully. Nov 5 15:03:24.119474 systemd[1]: Finished Load Kernel Module configfs. Nov 5 15:03:24.132434 systemd[1]: modprobe@drm.service: Deactivated successfully. Nov 5 15:03:24.133347 systemd[1]: Finished Load Kernel Module drm. Nov 5 15:03:24.134513 systemd[1]: modprobe@fuse.service: Deactivated successfully. Nov 5 15:03:24.134708 systemd[1]: Finished Load Kernel Module fuse. Nov 5 15:03:24.135959 systemd[1]: Finished Remount Root and Kernel File Systems. Nov 5 15:03:24.137411 systemd[1]: Finished Apply Kernel Variables. Nov 5 15:03:24.140669 systemd[1]: Mounting FUSE Control File System... Nov 5 15:03:24.143008 systemd[1]: Mounting Kernel Configuration File System... Nov 5 15:03:24.143909 systemd[1]: Condition check resulted in Remount Root File System being skipped. Nov 5 15:03:24.145907 systemd[1]: Starting Rebuild Hardware Database... Nov 5 15:03:24.148046 systemd[1]: Starting Flush Journal to Persistent Storage... Nov 5 15:03:24.149264 systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. Nov 5 15:03:24.150450 systemd[1]: Starting Load/Save Random Seed... Nov 5 15:03:24.152966 systemd-journald[799]: Time spent on flushing to /var/log/journal/119adc6b86d747ff889c05594f6ef708 is 8.878ms for 771 entries. Nov 5 15:03:24.152966 systemd-journald[799]: System Journal (/var/log/journal/119adc6b86d747ff889c05594f6ef708) is 8.0M, max 203.0M, 195.0M free. Nov 5 15:03:24.152640 systemd[1]: Starting Create System Users... Nov 5 15:03:24.156526 systemd[1]: Mounted FUSE Control File System. Nov 5 15:03:24.157586 systemd[1]: Mounted Kernel Configuration File System. Nov 5 15:03:24.160349 systemd[1]: Finished Coldplug All udev Devices. Nov 5 15:03:24.162702 systemd[1]: Starting Wait for udev To Complete Device Initialization... Nov 5 15:03:24.238152 systemd-sysusers[810]: Creating group sgx with gid 999. Nov 5 15:03:24.239013 systemd-sysusers[810]: Creating group systemd-oom with gid 998. Nov 5 15:03:24.239529 systemd-sysusers[810]: Creating user systemd-oom (systemd Userspace OOM Killer) with uid 998 and gid 998. Nov 5 15:03:24.240176 systemd-sysusers[810]: Creating group systemd-timesync with gid 997. Nov 5 15:03:24.240637 systemd-sysusers[810]: Creating user systemd-timesync (systemd Time Synchronization) with uid 997 and gid 997. Nov 5 15:03:24.241270 systemd-sysusers[810]: Creating group systemd-coredump with gid 996. Nov 5 15:03:24.241731 systemd-sysusers[810]: Creating user systemd-coredump (systemd Core Dumper) with uid 996 and gid 996. Nov 5 15:03:24.331434 systemd[1]: Finished Load/Save Random Seed. Nov 5 15:03:24.332993 systemd[1]: Finished Flush Journal to Persistent Storage. Nov 5 15:03:24.334320 systemd[1]: Reached target First Boot Complete. Nov 5 15:03:24.353584 systemd[1]: Finished Create System Users. Nov 5 15:03:24.355842 systemd[1]: Starting Create Static Device Nodes in /dev... Nov 5 15:03:24.370557 systemd[1]: Finished Create Static Device Nodes in /dev. Nov 5 15:03:24.545633 systemd[1]: Finished Rebuild Hardware Database. Nov 5 15:03:24.554000 audit: BPF prog-id=27 op=LOAD Nov 5 15:03:24.554000 audit: BPF prog-id=28 op=LOAD Nov 5 15:03:24.554000 audit: BPF prog-id=29 op=LOAD Nov 5 15:03:24.554000 audit: BPF prog-id=12 op=UNLOAD Nov 5 15:03:24.554000 audit: BPF prog-id=13 op=UNLOAD Nov 5 15:03:24.555561 systemd[1]: Starting Rule-based Manager for Device Events and Files... Nov 5 15:03:24.579704 systemd[1]: Started Rule-based Manager for Device Events and Files. Nov 5 15:03:24.588000 audit: BPF prog-id=30 op=LOAD Nov 5 15:03:24.590345 systemd[1]: Starting Network Configuration... Nov 5 15:03:24.632513 systemd-networkd[835]: lo: Link UP Nov 5 15:03:24.632527 systemd-networkd[835]: lo: Gained carrier Nov 5 15:03:24.632948 systemd-networkd[835]: Enumeration completed Nov 5 15:03:24.633071 systemd[1]: Started Network Configuration. Nov 5 15:03:24.635310 systemd-udevd[828]: Using default interface naming scheme 'v249'. Nov 5 15:03:24.659776 udevadm[812]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Nov 5 15:03:24.676649 systemd-networkd[835]: eth0: Link UP Nov 5 15:03:24.686214 systemd[1]: Finished Wait for udev To Complete Device Initialization. Nov 5 15:03:24.692098 systemd-networkd[835]: eth0: Gained carrier Nov 5 15:03:24.697069 systemd[1]: Starting Activation of LVM2 logical volumes... Nov 5 15:03:24.702266 systemd-networkd[835]: eth0: DHCPv4 address 10.0.0.7/16 via 10.0.0.1 Nov 5 15:03:24.705716 lvm[843]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Nov 5 15:03:24.731056 systemd[1]: Finished Activation of LVM2 logical volumes. Nov 5 15:03:24.732179 systemd[1]: Reached target Local Encrypted Volumes. Nov 5 15:03:24.746096 systemd[1]: Starting Activation of LVM2 logical volumes... Nov 5 15:03:24.749675 lvm[844]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Nov 5 15:03:24.775094 systemd[1]: Finished Activation of LVM2 logical volumes. Nov 5 15:03:24.776206 systemd[1]: Reached target Preparation for Local File Systems. Nov 5 15:03:24.777285 systemd[1]: Condition check resulted in Virtual Machine and Container Storage (Compatibility) being skipped. Nov 5 15:03:24.777309 systemd[1]: Reached target Containers. Nov 5 15:03:24.788270 systemd[1]: Starting File System Check on /dev/disk/by-label/OEM... Nov 5 15:03:24.803253 systemd[1]: Finished File System Check on /dev/disk/by-label/OEM. Nov 5 15:03:24.821116 systemd[1]: Mounting /usr/share/oem... Nov 5 15:03:24.827566 kernel: BTRFS info (device vda6): disk space caching is enabled Nov 5 15:03:24.827656 kernel: BTRFS info (device vda6): has skinny extents Nov 5 15:03:24.830078 systemd[1]: Mounted /usr/share/oem. Nov 5 15:03:24.830947 systemd[1]: Reached target Local File Systems. Nov 5 15:03:24.833078 systemd[1]: Starting Rebuild Dynamic Linker Cache... Nov 5 15:03:24.834311 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Nov 5 15:03:24.834384 systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Nov 5 15:03:24.835654 systemd[1]: Starting Commit a transient machine-id on disk... Nov 5 15:03:24.837919 systemd[1]: Starting Create Volatile Files and Directories... Nov 5 15:03:24.846007 systemd-tmpfiles[869]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Nov 5 15:03:24.847557 systemd-tmpfiles[869]: /usr/lib/tmpfiles.d/systemd.conf:33: Duplicate line for path "/var/lib/systemd", ignoring. Nov 5 15:03:24.854499 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Nov 5 15:03:24.855347 systemd[1]: Finished Commit a transient machine-id on disk. Nov 5 15:03:24.883859 systemd-tmpfiles[869]: Detected autofs mount point /boot during canonicalization of /boot. Nov 5 15:03:24.883875 systemd-tmpfiles[869]: Skipping /boot Nov 5 15:03:24.891907 systemd-tmpfiles[869]: Detected autofs mount point /boot during canonicalization of /boot. Nov 5 15:03:24.891925 systemd-tmpfiles[869]: Skipping /boot Nov 5 15:03:24.909706 systemd[1]: Finished Rebuild Dynamic Linker Cache. Nov 5 15:03:24.937632 systemd[1]: Finished Create Volatile Files and Directories. Nov 5 15:03:24.954112 systemd[1]: Starting Load Security Auditing Rules... Nov 5 15:03:24.956135 systemd[1]: Starting Clean up broken links in /etc/ssl/certs... Nov 5 15:03:24.958481 systemd[1]: Starting Rebuild Journal Catalog... Nov 5 15:03:24.959000 audit: BPF prog-id=31 op=LOAD Nov 5 15:03:24.962753 systemd[1]: Starting Network Name Resolution... Nov 5 15:03:24.964000 audit: BPF prog-id=32 op=LOAD Nov 5 15:03:24.965551 systemd[1]: Starting Network Time Synchronization... Nov 5 15:03:24.967798 systemd[1]: Starting Record System Boot/Shutdown in UTMP... Nov 5 15:03:24.969544 systemd[1]: Finished Clean up broken links in /etc/ssl/certs. Nov 5 15:03:24.971077 systemd[1]: Condition check resulted in Update CA bundle at /etc/ssl/certs/ca-certificates.crt being skipped. Nov 5 15:03:24.975260 systemd[1]: Finished Rebuild Journal Catalog. Nov 5 15:03:24.977713 systemd[1]: Starting Update is Completed... Nov 5 15:03:24.979866 systemd[1]: Finished Record System Boot/Shutdown in UTMP. Nov 5 15:03:24.983458 systemd[1]: Finished Update is Completed. Nov 5 15:03:24.992000 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 5 15:03:24.993474 augenrules[891]: No rules Nov 5 15:03:24.994495 systemd[1]: Finished Load Security Auditing Rules. Nov 5 15:03:25.009120 systemd-resolved[877]: Positive Trust Anchors: Nov 5 15:03:25.009138 systemd-resolved[877]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Nov 5 15:03:25.009165 systemd-resolved[877]: 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 5 15:03:25.010015 systemd-resolved[877]: Defaulting to hostname 'linux'. Nov 5 15:03:25.011873 systemd[1]: Started Network Name Resolution. Nov 5 15:03:25.012886 systemd[1]: Reached target Network. Nov 5 15:03:25.013688 systemd[1]: Reached target Host and Network Name Lookups. Nov 5 15:03:25.014913 systemd[1]: Started Network Time Synchronization. Nov 5 15:03:25.016159 systemd-timesyncd[880]: Initial synchronization to time server 10.0.0.1:123 (10.0.0.1). Nov 5 15:03:25.016231 systemd[1]: Reached target System Initialization. Nov 5 15:03:25.017211 systemd[1]: Started Watch for update engine configuration changes. Nov 5 15:03:25.018366 systemd[1]: Started Watch for a cloud-config at /var/lib/flatcar-install/user_data. Nov 5 15:03:25.019584 systemd[1]: Started Daily Cleanup of Temporary Directories. Nov 5 15:03:25.020624 systemd[1]: Condition check resulted in Update Engine Stub Timer being skipped. Nov 5 15:03:25.020653 systemd[1]: Reached target Path Units. Nov 5 15:03:25.021495 systemd[1]: Reached target System Time Set. Nov 5 15:03:25.022521 systemd[1]: Started Daily Log Rotation. Nov 5 15:03:25.023467 systemd[1]: Started Weekly check for MD array's redundancy information.. Nov 5 15:03:25.024683 systemd[1]: Reached target Timer Units. Nov 5 15:03:25.025908 systemd[1]: Listening on D-Bus System Message Bus Socket. Nov 5 15:03:25.028016 systemd[1]: Starting Docker Socket for the API... Nov 5 15:03:25.031301 systemd[1]: Listening on OpenSSH Server Socket. Nov 5 15:03:25.032778 systemd[1]: Listening on Docker Socket for the API. Nov 5 15:03:25.033799 systemd[1]: Reached target Socket Units. Nov 5 15:03:25.034687 systemd[1]: Reached target Basic System. Nov 5 15:03:25.035652 systemd[1]: Condition check resulted in Configure Addon /usr/share/oem being skipped. Nov 5 15:03:25.035686 systemd[1]: Condition check resulted in Run Addon /usr/share/oem being skipped. Nov 5 15:03:25.036965 systemd[1]: Started D-Bus System Message Bus. Nov 5 15:03:25.041250 systemd[1]: Starting Extend Filesystems... Nov 5 15:03:25.042166 systemd[1]: Condition check resulted in Modifies /etc/environment for CoreOS being skipped. Nov 5 15:03:25.043476 systemd[1]: Starting Generate /run/flatcar/motd... Nov 5 15:03:25.045667 systemd[1]: Starting Install an ssh key from /proc/cmdline... Nov 5 15:03:25.049067 systemd[1]: Starting Generate sshd host keys... Nov 5 15:03:25.049982 systemd[1]: Condition check resulted in Load cloud-config from /usr/share/oem/cloud-config.yml being skipped. Nov 5 15:03:25.050032 systemd[1]: Reached target Load system-provided cloud configs. Nov 5 15:03:25.050889 extend-filesystems[900]: Found vda Nov 5 15:03:25.054529 extend-filesystems[900]: Found vda1 Nov 5 15:03:25.054529 extend-filesystems[900]: Found vda2 Nov 5 15:03:25.054529 extend-filesystems[900]: Found vda3 Nov 5 15:03:25.054529 extend-filesystems[900]: Found usr Nov 5 15:03:25.054529 extend-filesystems[900]: Found vda4 Nov 5 15:03:25.054529 extend-filesystems[900]: Found vda6 Nov 5 15:03:25.054529 extend-filesystems[900]: Found vda7 Nov 5 15:03:25.054529 extend-filesystems[900]: Found vda9 Nov 5 15:03:25.054529 extend-filesystems[900]: Checking size of /dev/vda9 Nov 5 15:03:25.056217 systemd[1]: Starting User Login Management... Nov 5 15:03:25.057642 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Nov 5 15:03:25.058367 systemd[1]: Starting Update Engine... Nov 5 15:03:25.059163 systemd[1]: Condition check resulted in Load cloud-config from url defined in /proc/cmdline being skipped. Nov 5 15:03:25.059195 systemd[1]: Reached target Load user-provided cloud configs. Nov 5 15:03:25.061305 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Nov 5 15:03:25.061561 systemd[1]: Finished Install an ssh key from /proc/cmdline. Nov 5 15:03:25.093225 update_engine[916]: I1105 15:03:25.092917 916 main.cc:89] Flatcar Update Engine starting Nov 5 15:03:25.100435 systemd[1]: motdgen.service: Deactivated successfully. Nov 5 15:03:25.102027 extend-filesystems[900]: Old size kept for /dev/vda9 Nov 5 15:03:25.100680 systemd[1]: Finished Generate /run/flatcar/motd. Nov 5 15:03:25.103149 systemd[1]: extend-filesystems.service: Deactivated successfully. Nov 5 15:03:25.103384 systemd[1]: Finished Extend Filesystems. Nov 5 15:03:25.104352 systemd[1]: Started Update Engine. Nov 5 15:03:25.107408 update_engine[916]: I1105 15:03:25.107363 916 update_check_scheduler.cc:74] Next update check in 11m4s Nov 5 15:03:25.110382 systemd[1]: Started Cluster reboot manager. Nov 5 15:03:25.123877 systemd-logind[914]: Watching system buttons on /dev/input/event0 (Power Button) Nov 5 15:03:25.124108 systemd-logind[914]: New seat seat0. Nov 5 15:03:25.125348 systemd[1]: Started User Login Management. Nov 5 15:03:25.140744 locksmithd[924]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Nov 5 15:03:25.321032 systemd[1]: Created slice Slice /system/sshd. Nov 5 15:03:25.469672 sshd_keygen[918]: ssh-keygen: generating new host keys: RSA DSA ECDSA ED25519 Nov 5 15:03:25.487295 systemd[1]: Finished Generate sshd host keys. Nov 5 15:03:25.503129 systemd[1]: Starting Generate /run/issue... Nov 5 15:03:25.504902 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:55246). Nov 5 15:03:25.508923 systemd[1]: issuegen.service: Deactivated successfully. Nov 5 15:03:25.509168 systemd[1]: Finished Generate /run/issue. Nov 5 15:03:25.511486 systemd[1]: Starting Permit User Sessions... Nov 5 15:03:25.516841 systemd[1]: Finished Permit User Sessions. Nov 5 15:03:25.519269 systemd[1]: Started Getty on tty1. Nov 5 15:03:25.521232 systemd[1]: Started Serial Getty on ttyAMA0. Nov 5 15:03:25.522250 systemd[1]: Reached target Login Prompts. Nov 5 15:03:25.523218 systemd[1]: Reached target Multi-User System. Nov 5 15:03:25.525628 systemd[1]: Starting Record Runlevel Change in UTMP... Nov 5 15:03:25.531600 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Nov 5 15:03:25.531837 systemd[1]: Finished Record Runlevel Change in UTMP. Nov 5 15:03:25.533056 systemd[1]: Startup finished in 748ms (kernel) + 2.399s (initrd) + 3.574s (userspace) = 6.722s. Nov 5 15:03:25.545814 sshd[939]: Accepted publickey for core from 10.0.0.1 port 55246 ssh2: RSA SHA256:I8f+lQmjTB/T1JEI5UHiQReHKozNW8V2mcio2VQ6X8c Nov 5 15:03:25.547810 sshd[939]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 5 15:03:25.553564 systemd[1]: Created slice User Slice of UID 500. Nov 5 15:03:25.554619 systemd[1]: Starting User Runtime Directory /run/user/500... Nov 5 15:03:25.556106 systemd-logind[914]: New session 1 of user core. Nov 5 15:03:25.560656 systemd[1]: Finished User Runtime Directory /run/user/500. Nov 5 15:03:25.561816 systemd[1]: Starting User Manager for UID 500... Nov 5 15:03:25.563992 systemd[948]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Nov 5 15:03:25.611759 systemd[948]: Queued start job for default target Main User Target. Nov 5 15:03:25.611936 systemd[948]: Reached target Paths. Nov 5 15:03:25.611949 systemd[948]: Reached target Sockets. Nov 5 15:03:25.611961 systemd[948]: Reached target Timers. Nov 5 15:03:25.611971 systemd[948]: Reached target Basic System. Nov 5 15:03:25.612026 systemd[948]: Reached target Main User Target. Nov 5 15:03:25.612039 systemd[948]: Startup finished in 44ms. Nov 5 15:03:25.612135 systemd[1]: Started User Manager for UID 500. Nov 5 15:03:25.628204 systemd[1]: Started Session 1 of User core. Nov 5 15:03:25.687537 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:55248). Nov 5 15:03:25.722613 sshd[957]: Accepted publickey for core from 10.0.0.1 port 55248 ssh2: RSA SHA256:I8f+lQmjTB/T1JEI5UHiQReHKozNW8V2mcio2VQ6X8c Nov 5 15:03:25.723805 sshd[957]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 5 15:03:25.726664 systemd-logind[914]: New session 2 of user core. Nov 5 15:03:25.736200 systemd[1]: Started Session 2 of User core. Nov 5 15:03:25.790195 sshd[957]: pam_unix(sshd:session): session closed for user core Nov 5 15:03:25.810715 systemd[1]: sshd@1-10.0.0.7:22-10.0.0.1:55248.service: Deactivated successfully. Nov 5 15:03:25.811413 systemd[1]: session-2.scope: Deactivated successfully. Nov 5 15:03:25.812927 systemd-logind[914]: Session 2 logged out. Waiting for processes to exit. Nov 5 15:03:25.813072 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:55252). Nov 5 15:03:25.813974 systemd-logind[914]: Removed session 2. Nov 5 15:03:25.850274 sshd[963]: Accepted publickey for core from 10.0.0.1 port 55252 ssh2: RSA SHA256:I8f+lQmjTB/T1JEI5UHiQReHKozNW8V2mcio2VQ6X8c Nov 5 15:03:25.851613 sshd[963]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 5 15:03:25.854201 systemd-logind[914]: New session 3 of user core. Nov 5 15:03:25.860188 systemd[1]: Started Session 3 of User core. Nov 5 15:03:25.909040 sshd[963]: pam_unix(sshd:session): session closed for user core Nov 5 15:03:25.917670 systemd[1]: sshd@2-10.0.0.7:22-10.0.0.1:55252.service: Deactivated successfully. Nov 5 15:03:25.918318 systemd[1]: session-3.scope: Deactivated successfully. Nov 5 15:03:25.918857 systemd-logind[914]: Session 3 logged out. Waiting for processes to exit. Nov 5 15:03:25.919916 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:55254). Nov 5 15:03:25.920470 systemd-logind[914]: Removed session 3. Nov 5 15:03:25.955560 sshd[969]: Accepted publickey for core from 10.0.0.1 port 55254 ssh2: RSA SHA256:I8f+lQmjTB/T1JEI5UHiQReHKozNW8V2mcio2VQ6X8c Nov 5 15:03:25.956569 sshd[969]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 5 15:03:25.959455 systemd-logind[914]: New session 4 of user core. Nov 5 15:03:25.966164 systemd[1]: Started Session 4 of User core. Nov 5 15:03:26.016922 sshd[969]: pam_unix(sshd:session): session closed for user core Nov 5 15:03:26.036508 systemd[1]: sshd@3-10.0.0.7:22-10.0.0.1:55254.service: Deactivated successfully. Nov 5 15:03:26.037111 systemd[1]: session-4.scope: Deactivated successfully. Nov 5 15:03:26.037647 systemd-logind[914]: Session 4 logged out. Waiting for processes to exit. Nov 5 15:03:26.038589 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:55262). Nov 5 15:03:26.039291 systemd-logind[914]: Removed session 4. Nov 5 15:03:26.073181 sshd[975]: Accepted publickey for core from 10.0.0.1 port 55262 ssh2: RSA SHA256:I8f+lQmjTB/T1JEI5UHiQReHKozNW8V2mcio2VQ6X8c Nov 5 15:03:26.074094 sshd[975]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 5 15:03:26.076729 systemd-logind[914]: New session 5 of user core. Nov 5 15:03:26.090179 systemd[1]: Started Session 5 of User core. Nov 5 15:03:26.143189 sudo[978]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/setenforce 1 Nov 5 15:03:26.143401 sudo[978]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Nov 5 15:03:26.157901 sudo[978]: pam_unix(sudo:session): session closed for user root Nov 5 15:03:26.159864 sshd[975]: pam_unix(sshd:session): session closed for user core Nov 5 15:03:26.168578 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:55264). Nov 5 15:03:26.170885 dbus-daemon[899]: [system] Reloaded configuration Nov 5 15:03:26.173281 systemd[1]: sshd@4-10.0.0.7:22-10.0.0.1:55262.service: Deactivated successfully. Nov 5 15:03:26.173907 systemd[1]: session-5.scope: Deactivated successfully. Nov 5 15:03:26.174539 systemd-logind[914]: Session 5 logged out. Waiting for processes to exit. Nov 5 15:03:26.175165 systemd-logind[914]: Removed session 5. Nov 5 15:03:26.203934 sshd[981]: Accepted publickey for core from 10.0.0.1 port 55264 ssh2: RSA SHA256:I8f+lQmjTB/T1JEI5UHiQReHKozNW8V2mcio2VQ6X8c Nov 5 15:03:26.204901 sshd[981]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 5 15:03:26.207399 systemd-logind[914]: New session 6 of user core. Nov 5 15:03:26.215149 systemd[1]: Started Session 6 of User core. Nov 5 15:03:26.263573 sudo[986]: 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 5 15:03:26.263776 sudo[986]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Nov 5 15:03:26.265962 sudo[986]: pam_unix(sudo:session): session closed for user root Nov 5 15:03:26.266346 systemd-networkd[835]: eth0: Gained IPv6LL Nov 5 15:03:26.270083 sudo[985]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl restart audit-rules Nov 5 15:03:26.270282 sudo[985]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Nov 5 15:03:26.281902 systemd[1]: Stopping Load Security Auditing Rules... Nov 5 15:03:26.281000 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 5 15:03:26.283012 auditctl[989]: No rules Nov 5 15:03:26.283251 systemd[1]: audit-rules.service: Deactivated successfully. Nov 5 15:03:26.283478 systemd[1]: Stopped Load Security Auditing Rules. Nov 5 15:03:26.284708 systemd[1]: Starting Load Security Auditing Rules... Nov 5 15:03:26.299436 augenrules[1006]: No rules Nov 5 15:03:26.300387 systemd[1]: Finished Load Security Auditing Rules. Nov 5 15:03:26.301516 sudo[985]: pam_unix(sudo:session): session closed for user root Nov 5 15:03:26.302827 sshd[981]: pam_unix(sshd:session): session closed for user core Nov 5 15:03:26.314938 systemd[1]: sshd@5-10.0.0.7:22-10.0.0.1:55264.service: Deactivated successfully. Nov 5 15:03:26.315480 systemd[1]: session-6.scope: Deactivated successfully. Nov 5 15:03:26.316087 systemd-logind[914]: Session 6 logged out. Waiting for processes to exit. Nov 5 15:03:26.317028 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:55270). Nov 5 15:03:26.317623 systemd-logind[914]: Removed session 6. Nov 5 15:03:26.351498 sshd[1012]: Accepted publickey for core from 10.0.0.1 port 55270 ssh2: RSA SHA256:I8f+lQmjTB/T1JEI5UHiQReHKozNW8V2mcio2VQ6X8c Nov 5 15:03:26.352415 sshd[1012]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 5 15:03:26.355318 systemd-logind[914]: New session 7 of user core. Nov 5 15:03:26.367194 systemd[1]: Started Session 7 of User core. Nov 5 15:03:26.417581 sudo[1016]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/mkdir -p . Nov 5 15:03:26.417788 sudo[1016]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Nov 5 15:03:26.420779 sudo[1016]: pam_unix(sudo:session): session closed for user root Nov 5 15:03:26.421852 sshd[1012]: pam_unix(sshd:session): session closed for user core Nov 5 15:03:26.433623 systemd[1]: sshd@6-10.0.0.7:22-10.0.0.1:55270.service: Deactivated successfully. Nov 5 15:03:26.434197 systemd[1]: session-7.scope: Deactivated successfully. Nov 5 15:03:26.434776 systemd-logind[914]: Session 7 logged out. Waiting for processes to exit. Nov 5 15:03:26.435724 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:55284). Nov 5 15:03:26.436346 systemd-logind[914]: Removed session 7. Nov 5 15:03:26.470244 sshd[1020]: Accepted publickey for core from 10.0.0.1 port 55284 ssh2: RSA SHA256:I8f+lQmjTB/T1JEI5UHiQReHKozNW8V2mcio2VQ6X8c Nov 5 15:03:26.471502 sshd[1020]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 5 15:03:26.474059 systemd-logind[914]: New session 8 of user core. Nov 5 15:03:26.480148 systemd[1]: Started Session 8 of User core. Nov 5 15:03:26.529686 sudo[1024]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/install -m 0755 /dev/stdin kolet Nov 5 15:03:26.529914 sudo[1024]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Nov 5 15:03:26.765987 sudo[1024]: pam_unix(sudo:session): session closed for user root Nov 5 15:03:26.767502 sshd[1020]: pam_unix(sshd:session): session closed for user core Nov 5 15:03:26.781750 systemd[1]: sshd@7-10.0.0.7:22-10.0.0.1:55284.service: Deactivated successfully. Nov 5 15:03:26.782303 systemd[1]: session-8.scope: Deactivated successfully. Nov 5 15:03:26.782849 systemd-logind[914]: Session 8 logged out. Waiting for processes to exit. Nov 5 15:03:26.783893 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:55296). Nov 5 15:03:26.784577 systemd-logind[914]: Removed session 8. Nov 5 15:03:26.819075 sshd[1028]: Accepted publickey for core from 10.0.0.1 port 55296 ssh2: RSA SHA256:I8f+lQmjTB/T1JEI5UHiQReHKozNW8V2mcio2VQ6X8c Nov 5 15:03:26.820087 sshd[1028]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 5 15:03:26.822634 systemd-logind[914]: New session 9 of user core. Nov 5 15:03:26.831172 systemd[1]: Started Session 9 of User core. Nov 5 15:03:26.880294 sudo[1031]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/mkdir -p /updates Nov 5 15:03:26.880772 sudo[1031]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Nov 5 15:03:26.883750 sudo[1031]: pam_unix(sudo:session): session closed for user root Nov 5 15:03:26.884959 sshd[1028]: pam_unix(sshd:session): session closed for user core Nov 5 15:03:26.894746 systemd[1]: sshd@8-10.0.0.7:22-10.0.0.1:55296.service: Deactivated successfully. Nov 5 15:03:26.895305 systemd[1]: session-9.scope: Deactivated successfully. Nov 5 15:03:26.896006 systemd-logind[914]: Session 9 logged out. Waiting for processes to exit. Nov 5 15:03:26.897077 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:55300). Nov 5 15:03:26.897671 systemd-logind[914]: Removed session 9. Nov 5 15:03:26.931816 sshd[1035]: Accepted publickey for core from 10.0.0.1 port 55300 ssh2: RSA SHA256:I8f+lQmjTB/T1JEI5UHiQReHKozNW8V2mcio2VQ6X8c Nov 5 15:03:26.933160 sshd[1035]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 5 15:03:26.935807 systemd-logind[914]: New session 10 of user core. Nov 5 15:03:26.945186 systemd[1]: Started Session 10 of User core. Nov 5 15:03:26.995451 sudo[1038]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/install -m 0755 /dev/stdin /updates/update.gz Nov 5 15:03:26.995652 sudo[1038]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Nov 5 15:03:28.373538 sudo[1038]: pam_unix(sudo:session): session closed for user root Nov 5 15:03:28.374724 sshd[1035]: pam_unix(sshd:session): session closed for user core Nov 5 15:03:28.384634 systemd[1]: sshd@9-10.0.0.7:22-10.0.0.1:55300.service: Deactivated successfully. Nov 5 15:03:28.385210 systemd[1]: session-10.scope: Deactivated successfully. Nov 5 15:03:28.385434 systemd[1]: session-10.scope: Consumed 1.131s CPU time. Nov 5 15:03:28.386062 systemd-logind[914]: Session 10 logged out. Waiting for processes to exit. Nov 5 15:03:28.386935 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:55314). Nov 5 15:03:28.387484 systemd-logind[914]: Removed session 10. Nov 5 15:03:28.422751 sshd[1042]: Accepted publickey for core from 10.0.0.1 port 55314 ssh2: RSA SHA256:I8f+lQmjTB/T1JEI5UHiQReHKozNW8V2mcio2VQ6X8c Nov 5 15:03:28.423772 sshd[1042]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 5 15:03:28.426322 systemd-logind[914]: New session 11 of user core. Nov 5 15:03:28.441189 systemd[1]: Started Session 11 of User core. Nov 5 15:03:28.490929 sudo[1045]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemd-run --quiet ./kolet run cl.update.payload Omaha Nov 5 15:03:28.491151 sudo[1045]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Nov 5 15:03:28.509239 systemd[1]: Started /home/core/./kolet run cl.update.payload Omaha. Nov 5 15:03:28.510711 sudo[1045]: pam_unix(sudo:session): session closed for user root Nov 5 15:03:28.511955 sshd[1042]: pam_unix(sshd:session): session closed for user core Nov 5 15:03:28.515230 systemd[1]: sshd@10-10.0.0.7:22-10.0.0.1:55314.service: Deactivated successfully. Nov 5 15:03:28.515800 systemd[1]: session-11.scope: Deactivated successfully. Nov 5 15:03:28.516362 systemd-logind[914]: Session 11 logged out. Waiting for processes to exit. Nov 5 15:03:28.517427 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:55318). Nov 5 15:03:28.518095 systemd-logind[914]: Removed session 11. Nov 5 15:03:28.553045 sshd[1054]: Accepted publickey for core from 10.0.0.1 port 55318 ssh2: RSA SHA256:I8f+lQmjTB/T1JEI5UHiQReHKozNW8V2mcio2VQ6X8c Nov 5 15:03:28.554022 sshd[1054]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Nov 5 15:03:28.556838 systemd-logind[914]: New session 12 of user core. Nov 5 15:03:28.562134 systemd[1]: Started Session 12 of User core. Nov 5 15:03:29.083517 sshd[1054]: pam_unix(sshd:session): session closed for user core Nov 5 15:03:29.085587 systemd[1]: sshd@11-10.0.0.7:22-10.0.0.1:55318.service: Deactivated successfully. Nov 5 15:03:29.086223 systemd[1]: session-12.scope: Deactivated successfully. Nov 5 15:03:29.086727 systemd-logind[914]: Session 12 logged out. Waiting for processes to exit. Nov 5 15:03:29.087376 systemd-logind[914]: Removed session 12. Nov 5 15:04:10.817513 update_engine[916]: I1105 15:04:10.817065 916 update_attempter.cc:505] Updating boot flags...