end: 2.1 boot-qemu-image (duration 00:00:00) [common] start: 2.2 auto-login-action (timeout 00:15:00) [common] Setting prompt string to ['Linux version [0-9]'] auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:15:00) [2J[01;01H[=3h[2J[01;01H[2J[01;01H[=3h[2J[01;01H[2J[01;01H[=3h[2J[01;01HBdsDxe: failed to load Boot0001 "UEFI QEMU HARDDISK QM00001 " from PciRoot(0x0)/Pci(0x1F,0x2)/Sata(0x0,0xFFFF,0x0): Not Found BdsDxe: loading Boot0002 "UEFI QEMU HARDDISK QM00011 " from PciRoot(0x0)/Pci(0x1F,0x2)/Sata(0x5,0xFFFF,0x0) BdsDxe: starting Boot0002 "UEFI QEMU HARDDISK QM00011 " from PciRoot(0x0)/Pci(0x1F,0x2)/Sata(0x5,0xFFFF,0x0) [2J[01;01H[0m[36m[40mEFI Boot Guard v0.16 [0m[37m[40mBoot medium: PciRoot(0)/Pci(0x1F,0x2)/Sata(0x5,0xFFFF,0x0) Found 3 handles for file IO Volume 0: (On boot medium) PciRoot(0)/Pci(0x1F,0x2)/Sata(0x5,0xFFFF,0x0)/HD(1,GPT,D1360F76-B09A-4BCC-B923-8195088CBE02), LABEL=efi, CLABEL=(null) Volume 1: (On boot medium) PciRoot(0)/Pci(0x1F,0x2)/Sata(0x5,0xFFFF,0x0)/HD(2,GPT,F870258B-706F-4A66-8D58-B5A75CE61B8B), LABEL=BOOT0, CLABEL=BOOT0 Volume 2: (On boot medium) PciRoot(0)/Pci(0x1F,0x2)/Sata(0x5,0xFFFF,0x0)/HD(3,GPT,6E41F2A7-E3EB-403F-8637-B111E4482EE9), LABEL=BOOT1, CLABEL=BOOT1 Loading configuration... Config file found on volume 1. Config file found on volume 2. 2 config partitions detected. Config filter: Booting with environments from boot medium only. Config Revision: 2: ustate: 0 kernel: C:BOOT0:linux.efi args: timeout: 0 seconds Full path for kernel is: PciRoot(0)/Pci(0x1F,0x2)/Sata(0x5,0xFFFF,0x0)/HD(2,GPT,F870258B-706F-4A66-8D58-B5A75CE61B8B)/linux.efi [1m[33m[40mWARNING: [0m[37m[40mWatchdog is disabled. LoaderDevicePartUUID=D1360F76-B09A-4BCC-B923-8195088CBE02 Starting C:BOOT0:linux.efi with watchdog set to 0 seconds ... Unified kernel stub (EFI Boot Guard v0.16) EFI stub: Loaded initrd from LINUX_EFI_INITRD_MEDIA_GUID device path EFI stub: Measured initrd data into PCR 9 start: 2.2.1 login-action (timeout 00:14:53) [common] The string 'root@device-mgmt' does not look like a typical prompt and could match status messages instead. Please check the job log files and use a prompt string which matches the actual prompt string more closely. Setting prompt string to [] Using line separator: #'\n'# Waiting for the login prompt Parsing kernel messages ['device-mgmt login:', 'root@device-mgmt', 'You are currently connected via: ttyS0 (115200)', 'device-mgmt login:', 'Login incorrect'] [login-action] Waiting for messages, (timeout 00:14:53) Waiting using forced prompt support (timeout 00:07:27) [ 0.000000] Linux version 6.1.0-25-amd64 (debian-kernel@lists.debian.org) (gcc-12 (Debian 12.2.0-14) 12.2.0, GNU ld (GNU Binutils for Debian) 2.40) #1 SMP PREEMPT_DYNAMIC Debian 6.1.106-3 (2024-08-26) [ 0.000000] Command line: console=tty0 rootwait earlyprintk watchdog.handle_boot_enabled=0 iTCO_wdt.nowayout=1 loglevel=7 panic=5 console=ttyS0,115200 [ 0.000000] BIOS-provided physical RAM map: [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x0000000000805fff] usable [ 0.000000] BIOS-e820: [mem 0x0000000000806000-0x0000000000807fff] ACPI NVS [ 0.000000] BIOS-e820: [mem 0x0000000000808000-0x000000000080ffff] usable [ 0.000000] BIOS-e820: [mem 0x0000000000810000-0x00000000008fffff] ACPI NVS [ 0.000000] BIOS-e820: [mem 0x0000000000900000-0x000000003ee88fff] usable [ 0.000000] BIOS-e820: [mem 0x000000003ee89000-0x000000003ef8afff] reserved [ 0.000000] BIOS-e820: [mem 0x000000003ef8b000-0x000000003f8ebfff] usable [ 0.000000] BIOS-e820: [mem 0x000000003f8ec000-0x000000003fb6bfff] reserved [ 0.000000] BIOS-e820: [mem 0x000000003fb6c000-0x000000003fb7dfff] ACPI data [ 0.000000] BIOS-e820: [mem 0x000000003fb7e000-0x000000003fbfdfff] ACPI NVS [ 0.000000] BIOS-e820: [mem 0x000000003fbfe000-0x000000003fedbfff] usable [ 0.000000] BIOS-e820: [mem 0x000000003fedc000-0x000000003ff5ffff] reserved [ 0.000000] BIOS-e820: [mem 0x000000003ff60000-0x000000003fffffff] ACPI NVS [ 0.000000] BIOS-e820: [mem 0x00000000b0000000-0x00000000bfffffff] reserved [ 0.000000] NX (Execute Disable) protection: active [ 0.000000] extended physical RAM map: [ 0.000000] reserve setup_data: [mem 0x0000000000000000-0x000000000009ffff] usable [ 0.000000] reserve setup_data: [mem 0x0000000000100000-0x0000000000805fff] usable [ 0.000000] reserve setup_data: [mem 0x0000000000806000-0x0000000000807fff] ACPI NVS [ 0.000000] reserve setup_data: [mem 0x0000000000808000-0x000000000080ffff] usable [ 0.000000] reserve setup_data: [mem 0x0000000000810000-0x00000000008fffff] ACPI NVS [ 0.000000] reserve setup_data: [mem 0x0000000000900000-0x000000003c6c0017] usable [ 0.000000] reserve setup_data: [mem 0x000000003c6c0018-0x000000003c6fce57] usable [ 0.000000] reserve setup_data: [mem 0x000000003c6fce58-0x000000003c6fd017] usable [ 0.000000] reserve setup_data: [mem 0x000000003c6fd018-0x000000003c739e57] usable [ 0.000000] reserve setup_data: [mem 0x000000003c739e58-0x000000003c73a017] usable [ 0.000000] reserve setup_data: [mem 0x000000003c73a018-0x000000003c776e57] usable [ 0.000000] reserve setup_data: [mem 0x000000003c776e58-0x000000003c777017] usable [ 0.000000] reserve setup_data: [mem 0x000000003c777018-0x000000003c7b4057] usable [ 0.000000] reserve setup_data: [mem 0x000000003c7b4058-0x000000003c7b5017] usable [ 0.000000] reserve setup_data: [mem 0x000000003c7b5018-0x000000003c7bec57] usable [ 0.000000] reserve setup_data: [mem 0x000000003c7bec58-0x000000003ee88fff] usable [ 0.000000] reserve setup_data: [mem 0x000000003ee89000-0x000000003ef8afff] reserved [ 0.000000] reserve setup_data: [mem 0x000000003ef8b000-0x000000003f8ebfff] usable [ 0.000000] reserve setup_data: [mem 0x000000003f8ec000-0x000000003fb6bfff] reserved [ 0.000000] reserve setup_data: [mem 0x000000003fb6c000-0x000000003fb7dfff] ACPI data [ 0.000000] reserve setup_data: [mem 0x000000003fb7e000-0x000000003fbfdfff] ACPI NVS [ 0.000000] reserve setup_data: [mem 0x000000003fbfe000-0x000000003fedbfff] usable [ 0.000000] reserve setup_data: [mem 0x000000003fedc000-0x000000003ff5ffff] reserved [ 0.000000] reserve setup_data: [mem 0x000000003ff60000-0x000000003fffffff] ACPI NVS [ 0.000000] reserve setup_data: [mem 0x00000000b0000000-0x00000000bfffffff] reserved [ 0.000000] efi: EFI v2.70 by EDK II [ 0.000000] efi: TPMFinalLog=0x3fbf6000 SMBIOS=0x3f920000 ACPI=0x3fb7d000 ACPI 2.0=0x3fb7d014 MEMATTR=0x3e9d6098 INITRD=0x3d651a98 TPMEventLog=0x3cfec018 [ 0.000000] secureboot: Secure boot disabled [ 0.000000] SMBIOS 2.8 present. [ 0.000000] DMI: QEMU Standard PC (Q35 + ICH9, 2009), BIOS 0.0.0 02/06/2015 [ 0.000000] Hypervisor detected: KVM [ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00 [ 0.000003] kvm-clock: using sched offset of 6503954144 cycles [ 0.000004] clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns [ 0.000082] tsc: Detected 2419.206 MHz processor [ 0.000937] last_pfn = 0x3fedc max_arch_pfn = 0x400000000 [ 0.001540] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT [ 0.015436] RAMDISK: [mem 0x3928d000-0x3bf1dfff] [ 0.015492] ACPI: Early table checksum verification disabled [ 0.015595] ACPI: RSDP 0x000000003FB7D014 000024 (v02 BOCHS ) [ 0.015625] ACPI: XSDT 0x000000003FB7C0E8 00005C (v01 BOCHS BXPC 00000001 01000013) [ 0.015698] ACPI: FACP 0x000000003FB78000 0000F4 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.015725] ACPI: DSDT 0x000000003FB79000 002431 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.015744] ACPI: FACS 0x000000003FBC4000 000040 [ 0.015764] ACPI: APIC 0x000000003FB77000 000090 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.015783] ACPI: HPET 0x000000003FB76000 000038 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.015802] ACPI: TPM2 0x000000003FB75000 00004C (v04 BOCHS BXPC 00000001 BXPC 00000001) [ 0.015820] ACPI: MCFG 0x000000003FB74000 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.015839] ACPI: WAET 0x000000003FB73000 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.015842] ACPI: BGRT 0x000000003FB72000 000038 (v01 INTEL EDK2 00000002 01000013) [ 0.015844] ACPI: Reserving FACP table memory at [mem 0x3fb78000-0x3fb780f3] [ 0.015845] ACPI: Reserving DSDT table memory at [mem 0x3fb79000-0x3fb7b430] [ 0.015845] ACPI: Reserving FACS table memory at [mem 0x3fbc4000-0x3fbc403f] [ 0.015846] ACPI: Reserving APIC table memory at [mem 0x3fb77000-0x3fb7708f] [ 0.015846] ACPI: Reserving HPET table memory at [mem 0x3fb76000-0x3fb76037] [ 0.015847] ACPI: Reserving TPM2 table memory at [mem 0x3fb75000-0x3fb7504b] [ 0.015847] ACPI: Reserving MCFG table memory at [mem 0x3fb74000-0x3fb7403b] [ 0.015848] ACPI: Reserving WAET table memory at [mem 0x3fb73000-0x3fb73027] [ 0.015848] ACPI: Reserving BGRT table memory at [mem 0x3fb72000-0x3fb72037] [ 0.017102] No NUMA configuration found [ 0.017103] Faking a node at [mem 0x0000000000000000-0x000000003fedbfff] [ 0.017127] NODE_DATA(0) allocated [mem 0x3fe25000-0x3fe4ffff] [ 0.018271] Zone ranges: [ 0.018272] DMA [mem 0x0000000000001000-0x0000000000ffffff] [ 0.018274] DMA32 [mem 0x0000000001000000-0x000000003fedbfff] [ 0.018275] Normal empty [ 0.018276] Device empty [ 0.018276] Movable zone start for each node [ 0.018277] Early memory node ranges [ 0.018278] node 0: [mem 0x0000000000001000-0x000000000009ffff] [ 0.018279] node 0: [mem 0x0000000000100000-0x0000000000805fff] [ 0.018279] node 0: [mem 0x0000000000808000-0x000000000080ffff] [ 0.018280] node 0: [mem 0x0000000000900000-0x000000003ee88fff] [ 0.018280] node 0: [mem 0x000000003ef8b000-0x000000003f8ebfff] [ 0.018281] node 0: [mem 0x000000003fbfe000-0x000000003fedbfff] [ 0.018317] Initmem setup node 0 [mem 0x0000000000001000-0x000000003fedbfff] [ 0.018339] On node 0, zone DMA: 1 pages in unavailable ranges [ 0.018357] On node 0, zone DMA: 96 pages in unavailable ranges [ 0.018358] On node 0, zone DMA: 2 pages in unavailable ranges [ 0.018376] On node 0, zone DMA: 240 pages in unavailable ranges [ 0.018948] On node 0, zone DMA32: 258 pages in unavailable ranges [ 0.018954] On node 0, zone DMA32: 786 pages in unavailable ranges [ 0.018957] On node 0, zone DMA32: 292 pages in unavailable ranges [ 0.020670] ACPI: PM-Timer IO Port: 0x608 [ 0.020765] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) [ 0.021009] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 [ 0.021012] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) [ 0.021013] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) [ 0.021014] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) [ 0.021033] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) [ 0.021034] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) [ 0.021038] ACPI: Using ACPI (MADT) for SMP configuration information [ 0.021039] ACPI: HPET id: 0x8086a201 base: 0xfed00000 [ 0.021135] smpboot: Allowing 4 CPUs, 0 hotplug CPUs [ 0.021332] PM: hibernation: Registered nosave memory: [mem 0x00000000-0x00000fff] [ 0.021334] PM: hibernation: Registered nosave memory: [mem 0x000a0000-0x000fffff] [ 0.021335] PM: hibernation: Registered nosave memory: [mem 0x00806000-0x00807fff] [ 0.021336] PM: hibernation: Registered nosave memory: [mem 0x00810000-0x008fffff] [ 0.021336] PM: hibernation: Registered nosave memory: [mem 0x3c6c0000-0x3c6c0fff] [ 0.021337] PM: hibernation: Registered nosave memory: [mem 0x3c6fc000-0x3c6fcfff] [ 0.021338] PM: hibernation: Registered nosave memory: [mem 0x3c6fd000-0x3c6fdfff] [ 0.021339] PM: hibernation: Registered nosave memory: [mem 0x3c739000-0x3c739fff] [ 0.021339] PM: hibernation: Registered nosave memory: [mem 0x3c73a000-0x3c73afff] [ 0.021340] PM: hibernation: Registered nosave memory: [mem 0x3c776000-0x3c776fff] [ 0.021340] PM: hibernation: Registered nosave memory: [mem 0x3c777000-0x3c777fff] [ 0.021341] PM: hibernation: Registered nosave memory: [mem 0x3c7b4000-0x3c7b4fff] [ 0.021342] PM: hibernation: Registered nosave memory: [mem 0x3c7b5000-0x3c7b5fff] [ 0.021343] PM: hibernation: Registered nosave memory: [mem 0x3c7be000-0x3c7befff] [ 0.021343] PM: hibernation: Registered nosave memory: [mem 0x3d002000-0x3d00afff] [ 0.021344] PM: hibernation: Registered nosave memory: [mem 0x3ee89000-0x3ef8afff] [ 0.021345] PM: hibernation: Registered nosave memory: [mem 0x3f8ec000-0x3fb6bfff] [ 0.021346] PM: hibernation: Registered nosave memory: [mem 0x3fb6c000-0x3fb7dfff] [ 0.021346] PM: hibernation: Registered nosave memory: [mem 0x3fb7e000-0x3fbfdfff] [ 0.021347] [mem 0x40000000-0xafffffff] available for PCI devices [ 0.021348] Booting paravirtualized kernel on KVM [ 0.021367] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645519600211568 ns [ 0.025866] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1 [ 0.027134] percpu: Embedded 61 pages/cpu s212992 r8192 d28672 u524288 [ 0.027271] kvm-guest: PV spinlocks disabled, no host support [ 0.027274] Fallback order for Node 0: 0 [ 0.027293] Built 1 zonelists, mobility grouping on. Total pages: 254431 [ 0.027295] Policy zone: DMA32 [ 0.027296] Kernel command line: console=tty0 rootwait earlyprintk watchdog.handle_boot_enabled=0 iTCO_wdt.nowayout=1 loglevel=7 panic=5 console=ttyS0,115200 [ 0.027545] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) [ 0.027562] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes, linear) [ 0.027756] mem auto-init: stack:all(zero), heap alloc:on, heap free:off [ 0.030203] Memory: 247604K/1041876K available (14342K kernel code, 2335K rwdata, 9072K rodata, 2796K init, 17396K bss, 175724K reserved, 0K cma-reserved) [ 0.030610] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.030675] Kernel/User page tables isolation: enabled [ 0.030838] ftrace: allocating 40246 entries in 158 pages [ 0.062327] ftrace: allocated 158 pages with 5 groups [ 0.063714] Dynamic Preempt: voluntary [ 0.064112] rcu: Preemptible hierarchical RCU implementation. [ 0.064114] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=4. [ 0.064115] Trampoline variant of Tasks RCU enabled. [ 0.064116] Rude variant of Tasks RCU enabled. [ 0.064116] Tracing variant of Tasks RCU enabled. [ 0.064117] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies. [ 0.064118] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 [ 0.067477] NR_IRQS: 524544, nr_irqs: 456, preallocated irqs: 16 [ 0.067828] rcu: srcu_init: Setting srcu_struct sizes based on contention. [ 0.067908] Console: colour dummy device 80x25 [ 0.068069] printk: console [tty0] enabled [ 0.683379] printk: console [ttyS0] enabled [ 0.685688] ACPI: Core revision 20220331 [ 0.688598] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns [ 0.694230] APIC: Switch to symmetric I/O mode setup [ 0.699196] x2apic enabled [ 0.702898] Switched APIC routing to physical x2apic. [ 0.714752] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 [ 0.717806] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x22df16ed990, max_idle_ns: 440795329518 ns [ 0.723582] Calibrating delay loop (skipped) preset value.. 4838.41 BogoMIPS (lpj=9676824) [ 0.727577] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 [ 0.727577] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 [ 0.727577] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization [ 0.727616] Spectre V2 : Mitigation: Retpolines [ 0.730231] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch [ 0.731577] Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT [ 0.731577] Speculative Store Bypass: Vulnerable [ 0.731589] MDS: Vulnerable: Clear CPU buffers attempted, no microcode [ 0.735454] MMIO Stale Data: Unknown: No mitigations [ 0.735577] x86/fpu: x87 FPU will use FXSAVE [ 0.739577] Freeing SMP alternatives memory: 36K [ 0.739577] pid_max: default: 32768 minimum: 301 [ 0.739577] LSM: Security Framework initializing [ 0.739607] landlock: Up and running. [ 0.741731] Yama: disabled by default; enable with sysctl kernel.yama.* [ 0.743577] AppArmor: AppArmor initialized [ 0.743577] TOMOYO Linux initialized [ 0.743577] LSM support for eBPF active [ 0.743577] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) [ 0.743610] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes, linear) [ 0.779577] smpboot: CPU0: Intel QEMU Virtual CPU version 2.5+ (family: 0xf, model: 0x6b, stepping: 0x1) [ 0.779929] cblist_init_generic: Setting adjustable number of callback queues. [ 0.783581] cblist_init_generic: Setting shift to 2 and lim to 1. [ 0.787690] cblist_init_generic: Setting adjustable number of callback queues. [ 0.791580] cblist_init_generic: Setting shift to 2 and lim to 1. [ 0.794857] cblist_init_generic: Setting adjustable number of callback queues. [ 0.795579] cblist_init_generic: Setting shift to 2 and lim to 1. [ 0.799628] Performance Events: unsupported Netburst CPU model 107 no PMU driver, software events only. [ 0.803935] signal: max sigframe size: 1440 [ 0.805852] rcu: Hierarchical SRCU implementation. [ 0.807579] rcu: Max phase no-delay instances is 1000. [ 0.811730] NMI watchdog: Perf NMI watchdog permanently disabled [ 0.815470] smp: Bringing up secondary CPUs ... [ 0.815946] x86: Booting SMP configuration: [ 0.819580] .... node #0, CPUs: #1 #2 #3 [ 0.851911] smp: Brought up 1 node, 4 CPUs [ 0.859580] smpboot: Max logical packages: 1 [ 0.861886] smpboot: Total of 4 processors activated (19353.64 BogoMIPS) [ 0.868207] node 0 deferred pages initialised in 0ms [ 0.871939] devtmpfs: initialized [ 0.875659] x86/mm: Memory block size: 128MB [ 0.878259] ACPI: PM: Registering ACPI NVS region [mem 0x00806000-0x00807fff] (8192 bytes) [ 0.879585] ACPI: PM: Registering ACPI NVS region [mem 0x00810000-0x008fffff] (983040 bytes) [ 0.883594] ACPI: PM: Registering ACPI NVS region [mem 0x3fb7e000-0x3fbfdfff] (524288 bytes) [ 0.887585] ACPI: PM: Registering ACPI NVS region [mem 0x3ff60000-0x3fffffff] (655360 bytes) [ 0.891741] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 0.895685] futex hash table entries: 1024 (order: 4, 65536 bytes, linear) [ 0.899644] pinctrl core: initialized pinctrl subsystem [ 0.905103] NET: Registered PF_NETLINK/PF_ROUTE protocol family [ 0.907847] DMA: preallocated 128 KiB GFP_KERNEL pool for atomic allocations [ 0.912466] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.915726] DMA: preallocated 128 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.919591] audit: initializing netlink subsys (disabled) [ 0.923654] audit: type=2000 audit(1730279103.931:1): state=initialized audit_enabled=0 res=1 [ 0.923773] thermal_sys: Registered thermal governor 'fair_share' [ 0.927577] thermal_sys: Registered thermal governor 'bang_bang' [ 0.927609] thermal_sys: Registered thermal governor 'step_wise' [ 0.931580] thermal_sys: Registered thermal governor 'user_space' [ 0.935580] thermal_sys: Registered thermal governor 'power_allocator' [ 0.939610] cpuidle: using governor ladder [ 0.947604] cpuidle: using governor menu [ 0.950083] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.951967] PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xb0000000-0xbfffffff] (base 0xb0000000) [ 0.955583] PCI: MMCONFIG at [mem 0xb0000000-0xbfffffff] reserved in E820 [ 0.959619] PCI: Using configuration type 1 for base access [ 0.964334] kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. [ 0.968287] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages [ 0.968287] HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page [ 0.975745] ACPI: Added _OSI(Module Device) [ 0.978098] ACPI: Added _OSI(Processor Device) [ 0.978098] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.978098] ACPI: Added _OSI(Processor Aggregator Device) [ 0.978098] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.979673] ACPI: Interpreter enabled [ 0.979673] ACPI: PM: (supports S0 S3 S4 S5) [ 0.981300] ACPI: Using IOAPIC for interrupt routing [ 0.983577] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug [ 0.983584] PCI: Using E820 reservations for host bridge windows [ 0.986037] ACPI: Enabled 2 GPEs in block 00 to 3F [ 0.986039] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.987577] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] [ 0.987626] acpi PNP0A08:00: _OSC: platform does not support [PCIeHotplug LTR] [ 0.990566] acpi PNP0A08:00: _OSC: OS now controls [SHPCHotplug PME AER PCIeCapability] [ 0.990566] PCI host bridge to bus 0000:00 [ 0.990566] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] [ 0.991577] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] [ 0.991583] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] [ 0.995580] pci_bus 0000:00: root bus resource [mem 0x40000000-0xafffffff window] [ 0.998590] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] [ 0.999577] pci_bus 0000:00: root bus resource [mem 0x800000000-0xfffffffff window] [ 0.999577] pci_bus 0000:00: root bus resource [mem 0xfed40000-0xfed44fff] [ 0.999577] pci_bus 0000:00: root bus resource [bus 00-ff] [ 1.003816] pci 0000:00:00.0: [8086:29c0] type 00 class 0x060000 [ 1.003816] pci 0000:00:01.0: [1234:1111] type 00 class 0x030000 [ 1.023880] pci 0000:00:01.0: reg 0x10: [mem 0xc0000000-0xc0ffffff pref] [ 1.041078] pci 0000:00:01.0: reg 0x18: [mem 0xc1088000-0xc1088fff] [ 1.076566] pci 0000:00:01.0: reg 0x30: [mem 0xffff0000-0xffffffff pref] [ 1.080675] pci 0000:00:01.0: BAR 0: assigned to efifb [ 1.082870] pci 0000:00:01.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] [ 1.082870] pci 0000:00:02.0: [8086:10d3] type 00 class 0x020000 [ 1.085110] pci 0000:00:02.0: reg 0x10: [mem 0xc1060000-0xc107ffff] [ 1.092525] pci 0000:00:02.0: reg 0x14: [mem 0xc1040000-0xc105ffff] [ 1.096740] pci 0000:00:02.0: reg 0x18: [io 0x60c0-0x60df] [ 1.096740] pci 0000:00:02.0: reg 0x1c: [mem 0xc1080000-0xc1083fff] [ 1.103207] pci 0000:00:02.0: reg 0x30: [mem 0xfffc0000-0xffffffff pref] [ 1.105594] pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 [ 1.107581] pci 0000:00:03.0: reg 0x10: [io 0x60a0-0x60bf] [ 1.110487] pci 0000:00:03.0: reg 0x14: [mem 0xc1087000-0xc1087fff] [ 1.111577] pci 0000:00:03.0: reg 0x20: [mem 0x800000000-0x800003fff 64bit pref] [ 1.111581] pci 0000:00:03.0: reg 0x30: [mem 0xfffc0000-0xffffffff pref] [ 1.118043] pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 [ 1.118794] pci 0000:00:04.0: reg 0x10: [io 0x6080-0x609f] [ 1.118794] pci 0000:00:04.0: reg 0x14: [mem 0xc1086000-0xc1086fff] [ 1.119577] pci 0000:00:04.0: reg 0x20: [mem 0x800004000-0x800007fff 64bit pref] [ 1.120335] pci 0000:00:04.0: reg 0x30: [mem 0xfffc0000-0xffffffff pref] [ 1.124229] pci 0000:00:05.0: [1af4:1000] type 00 class 0x020000 [ 1.127378] pci 0000:00:05.0: reg 0x10: [io 0x6060-0x607f] [ 1.127577] pci 0000:00:05.0: reg 0x14: [mem 0xc1085000-0xc1085fff] [ 1.129722] pci 0000:00:05.0: reg 0x20: [mem 0x800008000-0x80000bfff 64bit pref] [ 1.131577] pci 0000:00:05.0: reg 0x30: [mem 0xfffc0000-0xffffffff pref] [ 1.132561] pci 0000:00:1f.0: [8086:2918] type 00 class 0x060100 [ 1.137393] pci 0000:00:1f.0: quirk: [io 0x0600-0x067f] claimed by ICH6 ACPI/GPIO/TCO [ 1.140135] pci 0000:00:1f.2: [8086:2922] type 00 class 0x010601 [ 1.143577] pci 0000:00:1f.2: reg 0x20: [io 0x6040-0x605f] [ 1.143577] pci 0000:00:1f.2: reg 0x24: [mem 0xc1084000-0xc1084fff] [ 1.151806] pci 0000:00:1f.3: [8086:2930] type 00 class 0x0c0500 [ 1.155577] pci 0000:00:1f.3: reg 0x20: [io 0x6000-0x603f] [ 1.159918] ACPI: PCI: Interrupt link LNKA configured for IRQ 10 [ 1.162584] ACPI: PCI: Interrupt link LNKB configured for IRQ 10 [ 1.163577] ACPI: PCI: Interrupt link LNKC configured for IRQ 11 [ 1.163873] ACPI: PCI: Interrupt link LNKD configured for IRQ 11 [ 1.166941] ACPI: PCI: Interrupt link LNKE configured for IRQ 10 [ 1.167577] ACPI: PCI: Interrupt link LNKF configured for IRQ 10 [ 1.171701] ACPI: PCI: Interrupt link LNKG configured for IRQ 11 [ 1.175667] ACPI: PCI: Interrupt link LNKH configured for IRQ 11 [ 1.178202] ACPI: PCI: Interrupt link GSIA configured for IRQ 16 [ 1.179577] ACPI: PCI: Interrupt link GSIB configured for IRQ 17 [ 1.179583] ACPI: PCI: Interrupt link GSIC configured for IRQ 18 [ 1.182127] ACPI: PCI: Interrupt link GSID configured for IRQ 19 [ 1.183622] ACPI: PCI: Interrupt link GSIE configured for IRQ 20 [ 1.183622] ACPI: PCI: Interrupt link GSIF configured for IRQ 21 [ 1.185923] ACPI: PCI: Interrupt link GSIG configured for IRQ 22 [ 1.187577] ACPI: PCI: Interrupt link GSIH configured for IRQ 23 [ 1.188851] iommu: Default domain type: Translated [ 1.191582] iommu: DMA domain TLB invalidation policy: lazy mode [ 1.192507] pps_core: LinuxPPS API ver. 1 registered [ 1.195157] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 1.195585] PTP clock support registered [ 1.197781] EDAC MC: Ver: 3.0.0 [ 1.200410] Registered efivars operations [ 1.203793] NetLabel: Initializing [ 1.205615] NetLabel: domain hash size = 128 [ 1.211580] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 1.214568] NetLabel: unlabeled traffic allowed by default [ 1.215629] PCI: Using ACPI for IRQ routing [ 1.284478] pci 0000:00:01.0: vgaarb: setting as boot VGA device [ 1.286604] pci 0000:00:01.0: vgaarb: bridge control possible [ 1.287577] pci 0000:00:01.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none [ 1.287581] vgaarb: loaded [ 1.291690] hpet: 3 channels of 0 reserved for per-cpu timers [ 1.294768] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 [ 1.294777] hpet0: 3 comparators, 64-bit 100.000000 MHz counter [ 1.294777] clocksource: Switched to clocksource kvm-clock [ 1.300464] VFS: Disk quotas dquot_6.6.0 [ 1.302640] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 1.307267] AppArmor: AppArmor Filesystem Enabled [ 1.310108] pnp: PnP ACPI init [ 1.312965] system 00:05: [mem 0xb0000000-0xbfffffff window] has been reserved [ 1.318903] pnp: PnP ACPI: found 6 devices [ 1.333532] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns [ 1.339777] NET: Registered PF_INET protocol family [ 1.342682] IP idents hash table entries: 16384 (order: 5, 131072 bytes, linear) [ 1.349379] tcp_listen_portaddr_hash hash table entries: 512 (order: 1, 8192 bytes, linear) [ 1.354758] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) [ 1.358995] TCP established hash table entries: 8192 (order: 4, 65536 bytes, linear) [ 1.363614] TCP bind hash table entries: 8192 (order: 6, 262144 bytes, linear) [ 1.367483] TCP: Hash tables configured (established 8192 bind 8192) [ 1.371592] MPTCP token hash table entries: 1024 (order: 2, 24576 bytes, linear) [ 1.375931] UDP hash table entries: 512 (order: 2, 16384 bytes, linear) [ 1.379922] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes, linear) [ 1.384217] NET: Registered PF_UNIX/PF_LOCAL protocol family [ 1.387578] NET: Registered PF_XDP protocol family [ 1.390410] pci 0000:00:02.0: can't claim BAR 6 [mem 0xfffc0000-0xffffffff pref]: no compatible bridge window [ 1.395858] pci 0000:00:03.0: can't claim BAR 6 [mem 0xfffc0000-0xffffffff pref]: no compatible bridge window [ 1.401167] pci 0000:00:04.0: can't claim BAR 6 [mem 0xfffc0000-0xffffffff pref]: no compatible bridge window [ 1.406357] pci 0000:00:05.0: can't claim BAR 6 [mem 0xfffc0000-0xffffffff pref]: no compatible bridge window [ 1.411670] pci 0000:00:02.0: BAR 6: assigned [mem 0x40000000-0x4003ffff pref] [ 1.415501] pci 0000:00:03.0: BAR 6: assigned [mem 0x40040000-0x4007ffff pref] [ 1.419397] pci 0000:00:04.0: BAR 6: assigned [mem 0x40080000-0x400bffff pref] [ 1.423340] pci 0000:00:05.0: BAR 6: assigned [mem 0x400c0000-0x400fffff pref] [ 1.427216] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] [ 1.430524] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] [ 1.433928] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] [ 1.438057] pci_bus 0000:00: resource 7 [mem 0x40000000-0xafffffff window] [ 1.441785] pci_bus 0000:00: resource 8 [mem 0xc0000000-0xfebfffff window] [ 1.445409] pci_bus 0000:00: resource 9 [mem 0x800000000-0xfffffffff window] [ 1.449115] pci_bus 0000:00: resource 10 [mem 0xfed40000-0xfed44fff] [ 1.453062] PCI: CLS 0 bytes, default 64 [ 1.455841] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x22df16ed990, max_idle_ns: 440795329518 ns [ 1.456214] Trying to unpack rootfs image as initramfs... [ 1.462411] Initialise system trusted keyrings [ 1.465941] Key type blacklist registered [ 1.468924] workingset: timestamp_bits=36 max_order=18 bucket_order=0 [ 1.474115] zbud: loaded [ 1.477034] integrity: Platform Keyring initialized [ 1.479627] integrity: Machine keyring initialized [ 1.482240] Key type asymmetric registered [ 1.484536] Asymmetric key parser 'x509' registered [ 2.672782] Freeing initrd memory: 45636K [ 2.698891] alg: self-tests for CTR-KDF (hmac(sha256)) passed [ 2.701555] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248) [ 2.705200] io scheduler mq-deadline registered [ 2.709316] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 [ 2.713462] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 2.717422] 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A [ 2.722662] Linux agpgart interface v0.103 [ 2.738170] tpm_tis MSFT0101:00: 2.0 TPM (device-id 0x1, rev-id 1) [ 2.803042] AMD-Vi: AMD IOMMUv2 functionality not available on this system - This is not a bug. [ 2.809912] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 [ 2.816846] serio: i8042 KBD port at 0x60,0x64 irq 1 [ 2.819187] serio: i8042 AUX port at 0x60,0x64 irq 12 [ 2.822233] mousedev: PS/2 mouse device common for all mice [ 2.825511] rtc_cmos 00:04: RTC can wake from S4 [ 2.830560] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 [ 2.836234] rtc_cmos 00:04: registered as rtc0 [ 2.839283] rtc_cmos 00:04: setting system clock to 2024-10-30T09:05:06 UTC (1730279106) [ 2.844255] rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram [ 2.848042] intel_pstate: CPU model not supported [ 2.851482] ledtrig-cpu: registered to indicate activity on CPUs [ 2.855150] efifb: probing for efifb [ 2.857312] efifb: framebuffer at 0xc0000000, using 4000k, total 4000k [ 2.861014] efifb: mode is 1280x800x32, linelength=5120, pages=1 [ 2.864450] efifb: scrolling: redraw [ 2.866357] efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 [ 2.890183] Console: switching to colour frame buffer device 160x50 [ 2.896045] fb0: EFI VGA frame buffer device [ 2.919368] NET: Registered PF_INET6 protocol family [ 2.932435] Segment Routing with IPv6 [ 2.934691] In-situ OAM (IOAM) with IPv6 [ 2.937196] mip6: Mobile IPv6 [ 2.938721] NET: Registered PF_PACKET protocol family [ 2.942303] mpls_gso: MPLS GSO support [ 2.946518] IPI shorthand broadcast: enabled [ 2.949169] sched_clock: Marking stable (2301347626, 647730809)->(3483237096, -534158661) [ 2.955188] registered taskstats version 1 [ 2.958150] Loading compiled-in X.509 certificates [ 2.975524] Loaded X.509 cert 'Debian Secure Boot CA: 6ccece7e4c6c0d1f6149f3dd27dfcc5cbb419ea1' [ 2.980558] Loaded X.509 cert 'Debian Secure Boot Signer 2022 - linux: 14011249c2675ea8e5148542202005810584b25f' [ 2.987716] zswap: loaded using pool lzo/zbud [ 2.991610] Key type .fscrypt registered [ 2.993885] Key type fscrypt-provisioning registered [ 3.002675] Key type encrypted registered [ 3.005402] AppArmor: AppArmor sha1 policy hashing enabled [ 3.009603] ima: Allocated hash algorithm: sha256 [ 3.017507] ima: Can not allocate sha384 (reason: -2) [ 3.025468] ima: Can not allocate sha512 (reason: -2) [ 3.197214] ima: No architecture policies found [ 3.200239] evm: Initialising EVM extended attributes: [ 3.203739] evm: security.selinux [ 3.206030] evm: security.SMACK64 (disabled) [ 3.209048] evm: security.SMACK64EXEC (disabled) [ 3.212262] evm: security.SMACK64TRANSMUTE (disabled) [ 3.215259] evm: security.SMACK64MMAP (disabled) [ 3.218209] evm: security.apparmor [ 3.220492] evm: security.ima [ 3.222347] evm: security.capability [ 3.224578] evm: HMAC attrs: 0x1 [ 3.306692] clk: Disabling unused clocks [ 3.314145] Freeing unused decrypted memory: 2036K [ 3.324499] Freeing unused kernel image (initmem) memory: 2796K [ 3.327020] Write protecting the kernel read-only data: 26624k [ 3.339243] Freeing unused kernel image (text/rodata gap) memory: 2040K [ 3.343726] Freeing unused kernel image (rodata/data gap) memory: 1168K [ 3.385997] x86/mm: Checked W+X mappings: passed, no W+X pages found. [ 3.388830] x86/mm: Checking user space page tables [ 3.448846] x86/mm: Checked W+X mappings: passed, no W+X pages found. [ 3.451476] Run /init as init process Loading, please wait... Starting systemd-udevd version 252.30-1~deb12u2 [ 3.539383] lpc_ich 0000:00:1f.0: I/O space for GPIO uninitialized [ 3.551123] ACPI: \_SB_.GSIH: Enabled at IRQ 23 [ 3.558888] ACPI: \_SB_.GSIA: Enabled at IRQ 16 [ 3.561964] SCSI subsystem initialized [ 3.562130] i801_smbus 0000:00:1f.3: Enabling SMBus device [ 3.562492] e1000e: Intel(R) PRO/1000 Network Driver [ 3.562530] e1000e: Copyright(c) 1999 - 2015 Intel Corporation. [ 3.563161] ACPI: \_SB_.GSIG: Enabled at IRQ 22 [ 3.565650] e1000e 0000:00:02.0: Interrupt Throttling Rate (ints/sec) set to dynamic conservative mode [ 3.566871] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt [ 3.577218] ACPI: \_SB_.GSIE: Enabled at IRQ 20 [ 3.578218] i2c i2c-0: 1/1 memory slots populated (from DMI) [ 3.586030] ACPI: \_SB_.GSIF: Enabled at IRQ 21 [ 3.586113] i2c i2c-0: Memory type 0x07 not supported yet, not instantiating SPD [ 3.593442] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input3 [ 3.610302] input: VirtualPS/2 VMware VMMouse as /devices/platform/i8042/serio1/input/input2 [ 3.629924] e1000e 0000:00:02.0 0000:00:02.0 (uninitialized): registered PHC clock [ 3.647143] ahci 0000:00:1f.2: AHCI 0001.0000 32 slots 6 ports 1.5 Gbps 0x3f impl SATA mode [ 3.649312] ahci 0000:00:1f.2: flags: 64bit ncq only [ 3.657792] virtio_net virtio1 enp0s4: renamed from eth1 [ 3.658084] scsi host0: ahci [ 3.662240] scsi host1: ahci [ 3.664676] scsi host2: ahci [ 3.666567] scsi host3: ahci [ 3.669231] scsi host4: ahci [ 3.670854] scsi host5: ahci [ 3.672384] ata1: SATA max UDMA/133 abar m4096@0xc1084000 port 0xc1084100 irq 36 [ 3.675164] ata2: SATA max UDMA/133 abar m4096@0xc1084000 port 0xc1084180 irq 36 [ 3.678009] ata3: SATA max UDMA/133 abar m4096@0xc1084000 port 0xc1084200 irq 36 [ 3.680837] ata4: SATA max UDMA/133 abar m4096@0xc1084000 port 0xc1084280 irq 36 [ 3.684556] ata5: SATA max UDMA/133 abar m4096@0xc1084000 port 0xc1084300 irq 36 [ 3.687574] ata6: SATA max UDMA/133 abar m4096@0xc1084000 port 0xc1084380 irq 36 [ 3.699293] virtio_net virtio0 enp0s3: renamed from eth0 [ 3.826343] e1000e 0000:00:02.0 eth0: (PCI Express:2.5GT/s:Width x1) 52:54:00:12:34:56 [ 3.827545] virtio_net virtio2 enp0s5: renamed from eth2 [ 3.829769] e1000e 0000:00:02.0 eth0: Intel(R) PRO/1000 Network Connection [ 3.835137] e1000e 0000:00:02.0 eth0: MAC: 3, PHY: 8, PBA No: 000000-000 [ 4.017972] ata2: SATA link down (SStatus 0 SControl 300) [ 4.026445] ata4: SATA link down (SStatus 0 SControl 300) [ 4.031670] ata3: SATA link down (SStatus 0 SControl 300) [ 4.037261] ata6: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [ 4.041112] ata5: SATA link down (SStatus 0 SControl 300) [ 4.044564] ata6.00: ATA-7: QEMU HARDDISK, 2.5+, max UDMA/100 [ 4.046983] ata6.00: 12682068 sectors, multi 16: LBA48 NCQ (depth 32) [ 4.049555] ata6.00: applying bridge limits [ 4.052166] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300) [ 4.054724] ata1.00: ATA-7: QEMU HARDDISK, 2.5+, max UDMA/100 [ 4.056991] ata1.00: 1048576 sectors, multi 16: LBA48 NCQ (depth 32) [ 4.059430] ata1.00: applying bridge limits [ 4.061334] ata6.00: configured for UDMA/100 [ 4.063769] ata1.00: configured for UDMA/100 [ 4.066552] scsi 0:0:0:0: Direct-Access ATA QEMU HARDDISK 2.5+ PQ: 0 ANSI: 5 [ 4.071360] scsi 5:0:0:0: Direct-Access ATA QEMU HARDDISK 2.5+ PQ: 0 ANSI: 5 [ 4.142454] e1000e 0000:00:02.0 enp0s2: renamed from eth0 [ 4.287949] sd 0:0:0:0: [sda] 1048576 512-byte logical blocks: (537 MB/512 MiB) [ 4.288105] sd 5:0:0:0: [sdb] 12682068 512-byte logical blocks: (6.49 GB/6.05 GiB) [ 4.290815] sd 0:0:0:0: [sda] Write Protect is off [ 4.293662] sd 5:0:0:0: [sdb] Write Protect is off [ 4.297587] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA [ 4.297784] sd 5:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA [ 4.301613] sd 0:0:0:0: [sda] Preferred minimum I/O size 512 bytes [ 4.307975] sd 5:0:0:0: [sdb] Preferred minimum I/O size 512 bytes [ 4.311429] sd 0:0:0:0: [sda] Attached SCSI disk [ 4.319194] sdb: sdb1 sdb2 sdb3 sdb4 sdb5 sdb6 sdb7 [ 4.322695] sd 5:0:0:0: [sdb] Attached SCSI disk Begin: Loading essential drivers ... done. Begin: Running /scripts/init-premount ... done. Begin: Mounting root file system ... Begin: Running /scripts/local-top ... [ 4.434265] loop: module loaded Device /dev/sdb4 is not a valid LUKS device. [ 7.001501] random: crng init done Begin: Encryption of /dev/sdb4 ... Warning: keyslot operation could fail as it requires more than available memory. [2K Progress: 4.8%, ETA 00m20s, 48 MiB written, speed 47.5 MiB/s[2K Progress: 9.5%, ETA 00m15s, 96 MiB written, speed 59.5 MiB/s[2K Progress: 14.3%, ETA 00m13s, 144 MiB written, speed 63.7 MiB/s[2K Progress: 19.0%, ETA 00m12s, 192 MiB written, speed 65.8 MiB/s[2K Progress: 23.8%, ETA 00m11s, 240 MiB written, speed 66.9 MiB/s[2K Progress: 28.6%, ETA 00m10s, 288 MiB written, speed 67.5 MiB/s[2K Progress: 33.3%, ETA 00m10s, 336 MiB written, speed 68.2 MiB/s[2K Progress: 38.1%, ETA 00m09s, 384 MiB written, speed 68.5 MiB/s[2K Progress: 42.9%, ETA 00m08s, 432 MiB written, speed 69.1 MiB/s[2K Progress: 47.6%, ETA 00m07s, 480 MiB written, speed 69.6 MiB/s[2K Progress: 52.4%, ETA 00m07s, 528 MiB written, speed 70.0 MiB/s[2K Progress: 57.1%, ETA 00m06s, 576 MiB written, speed 70.4 MiB/s[2K Progress: 61.9%, ETA 00m05s, 624 MiB written, speed 70.8 MiB/s[2K Progress: 66.7%, ETA 00m04s, 672 MiB written, speed 71.1 MiB/s[2K Progress: 71.4%, ETA 00m04s, 720 MiB written, speed 71.4 MiB/s[2K Progress: 76.2%, ETA 00m03s, 768 MiB written, speed 71.6 MiB/s[2K Progress: 81.0%, ETA 00m02s, 816 MiB written, speed 71.9 MiB/s[2K Progress: 85.7%, ETA 00m02s, 864 MiB written, speed 72.0 MiB/s[2K Progress: 90.5%, ETA 00m01s, 912 MiB written, speed 72.2 MiB/s[2K Progress: 95.2%, ETA 00m00s, 960 MiB written, speed 72.3 MiB/s[2K Finished, time 00m13s, 1008 MiB written, speed 73.4 MiB/s New TPM2 token enrolled as key slot 1. [ 39.501236] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. [ 39.510127] device-mapper: uevent: version 1.0.3 [ 39.512823] device-mapper: ioctl: 4.47.0-ioctl (2022-07-28) initialised: dm-devel@redhat.com done. Wiped slot 0. Device /dev/sdb5 is not a valid LUKS device. Begin: Encryption of /dev/sdb5 ... Warning: keyslot operation could fail as it requires more than available memory. [2K Progress: 4.8%, ETA 00m20s, 48 MiB written, speed 47.5 MiB/s[2K Progress: 9.5%, ETA 00m15s, 96 MiB written, speed 59.6 MiB/s[2K Progress: 14.3%, ETA 00m13s, 144 MiB written, speed 64.0 MiB/s[2K Progress: 19.0%, ETA 00m12s, 192 MiB written, speed 65.6 MiB/s[2K Progress: 23.8%, ETA 00m11s, 240 MiB written, speed 66.4 MiB/s[2K Progress: 28.6%, ETA 00m10s, 288 MiB written, speed 67.4 MiB/s[2K Progress: 33.3%, ETA 00m10s, 336 MiB written, speed 68.0 MiB/s[2K Progress: 38.1%, ETA 00m09s, 384 MiB written, speed 68.5 MiB/s[2K Progress: 42.9%, ETA 00m08s, 432 MiB written, speed 68.7 MiB/s[2K Progress: 47.6%, ETA 00m07s, 480 MiB written, speed 69.1 MiB/s[2K Progress: 52.4%, ETA 00m07s, 528 MiB written, speed 69.5 MiB/s[2K Progress: 57.1%, ETA 00m06s, 576 MiB written, speed 69.7 MiB/s[2K Progress: 61.9%, ETA 00m05s, 624 MiB written, speed 69.7 MiB/s[2K Progress: 66.7%, ETA 00m05s, 672 MiB written, speed 70.0 MiB/s[2K Progress: 71.4%, ETA 00m04s, 720 MiB written, speed 70.2 MiB/s[2K Progress: 76.2%, ETA 00m03s, 768 MiB written, speed 70.2 MiB/s[2K Progress: 81.0%, ETA 00m02s, 816 MiB written, speed 70.4 MiB/s[2K Progress: 85.7%, ETA 00m02s, 864 MiB written, speed 70.6 MiB/s[2K Progress: 90.5%, ETA 00m01s, 912 MiB written, speed 70.7 MiB/s[2K Progress: 95.2%, ETA 00m00s, 960 MiB written, speed 70.8 MiB/s[2K Finished, time 00m13s, 1008 MiB written, speed 72.0 MiB/s New TPM2 token enrolled as key slot 1. done. Wiped slot 0. Device /dev/sdb6 is not a valid LUKS device. Begin: Encryption of /dev/sdb6 ... home: 19/170544 files (0.0% non-contiguous), 19895/340787 blocks resize2fs 1.47.0 (5-Feb-2023) Resizing the filesystem on /dev/sdb6 to 332595 (4k) blocks. The filesystem on /dev/sdb6 is now 332595 (4k) blocks long. Warning: keyslot operation could fail as it requires more than available memory. [2K Progress: 3.6%, ETA 00m26s, 48 MiB written, speed 48.0 MiB/s[2K Progress: 7.3%, ETA 00m20s, 96 MiB written, speed 59.1 MiB/s[2K Progress: 10.9%, ETA 00m18s, 144 MiB written, speed 63.3 MiB/s[2K Progress: 14.6%, ETA 00m17s, 192 MiB written, speed 65.5 MiB/s[2K Progress: 18.2%, ETA 00m16s, 240 MiB written, speed 66.8 MiB/s[2K Progress: 21.9%, ETA 00m15s, 288 MiB written, speed 67.7 MiB/s[2K Progress: 25.5%, ETA 00m14s, 336 MiB written, speed 68.5 MiB/s[2K Progress: 29.2%, ETA 00m13s, 384 MiB written, speed 69.0 MiB/s[2K Progress: 31.6%, ETA 00m13s, 416 MiB written, speed 68.6 MiB/s[2K Progress: 35.3%, ETA 00m12s, 464 MiB written, speed 69.1 MiB/s[2K Progress: 38.9%, ETA 00m11s, 512 MiB written, speed 69.1 MiB/s[2K Progress: 42.6%, ETA 00m11s, 560 MiB written, speed 69.5 MiB/s[2K Progress: 46.2%, ETA 00m10s, 608 MiB written, speed 69.7 MiB/s[2K Progress: 49.9%, ETA 00m09s, 656 MiB written, speed 70.0 MiB/s[2K Progress: 53.5%, ETA 00m08s, 704 MiB written, speed 70.2 MiB/s[2K Progress: 57.2%, ETA 00m08s, 752 MiB written, speed 70.3 MiB/s[2K Progress: 60.8%, ETA 00m07s, 800 MiB written, speed 70.4 MiB/s[2K Progress: 64.5%, ETA 00m06s, 848 MiB written, speed 70.5 MiB/s[2K Progress: 68.1%, ETA 00m06s, 896 MiB written, speed 70.5 MiB/s[2K Progress: 71.8%, ETA 00m05s, 944 MiB written, speed 70.6 MiB/s[2K Progress: 75.4%, ETA 00m04s, 992 MiB written, speed 70.7 MiB/s[2K Progress: 79.1%, ETA 00m04s, 1040 MiB written, speed 70.8 MiB/s[2K Progress: 82.7%, ETA 00m03s, 1088 MiB written, speed 70.9 MiB/s[2K Progress: 86.4%, ETA 00m02s, 1136 MiB written, speed 71.0 MiB/s[2K Progress: 90.0%, ETA 00m02s, 1184 MiB written, speed 71.1 MiB/s[2K Progress: 93.7%, ETA 00m01s, 1232 MiB written, speed 71.1 MiB/s[2K Progress: 97.3%, ETA 00m00s, 1280 MiB written, speed 71.1 MiB/s[2K Finished, time 00m18s, 1315 MiB written, speed 72.0 MiB/s New TPM2 token enrolled as key slot 1. done. Wiped slot 0. Device /dev/sdb7 is not a valid LUKS device. Begin: Encryption of /dev/sdb7 ... var: 1433/341040 files (0.1% non-contiguous), 90171/681574 blocks resize2fs 1.47.0 (5-Feb-2023) Resizing the filesystem on /dev/sdb7 to 673382 (4k) blocks. The filesystem on /dev/sdb7 is now 673382 (4k) blocks long. Warning: keyslot operation could fail as it requires more than available memory. [2K Progress: 1.8%, ETA 00m55s, 48 MiB written, speed 47.0 MiB/s[2K Progress: 3.0%, ETA 00m49s, 80 MiB written, speed 52.2 MiB/s[2K Progress: 4.8%, ETA 00m42s, 128 MiB written, speed 59.0 MiB/s[2K Progress: 6.7%, ETA 00m40s, 176 MiB written, speed 61.8 MiB/s[2K Progress: 8.5%, ETA 00m38s, 224 MiB written, speed 62.7 MiB/s[2K Progress: 10.3%, ETA 00m37s, 272 MiB written, speed 63.8 MiB/s[2K Progress: 12.1%, ETA 00m36s, 320 MiB written, speed 64.5 MiB/s[2K Progress: 13.9%, ETA 00m35s, 368 MiB written, speed 64.8 MiB/s[2K Progress: 15.7%, ETA 00m34s, 416 MiB written, speed 65.3 MiB/s[2K Progress: 17.5%, ETA 00m33s, 464 MiB written, speed 65.6 MiB/s[2K Progress: 19.3%, ETA 00m32s, 512 MiB written, speed 65.9 MiB/s[2K Progress: 21.2%, ETA 00m31s, 560 MiB written, speed 66.1 MiB/s[2K Progress: 23.0%, ETA 00m30s, 608 MiB written, speed 66.4 MiB/s[2K Progress: 24.8%, ETA 00m30s, 656 MiB written, speed 66.5 MiB/s[2K Progress: 26.6%, ETA 00m29s, 704 MiB written, speed 66.7 MiB/s[2K Progress: 28.4%, ETA 00m28s, 752 MiB written, speed 66.8 MiB/s[2K Progress: 30.2%, ETA 00m27s, 800 MiB written, speed 66.9 MiB/s[2K Progress: 32.0%, ETA 00m27s, 848 MiB written, speed 66.9 MiB/s[2K Progress: 33.9%, ETA 00m26s, 896 MiB written, speed 67.2 MiB/s[2K Progress: 35.7%, ETA 00m25s, 944 MiB written, speed 67.3 MiB/s[2K Progress: 36.9%, ETA 00m25s, 976 MiB written, speed 66.9 MiB/s[2K Progress: 38.1%, ETA 00m24s, 1008 MiB written, speed 66.5 MiB/s[2K Progress: 39.3%, ETA 00m24s, 1040 MiB written, speed 66.3 MiB/s[2K Progress: 40.5%, ETA 00m24s, 1072 MiB written, speed 66.1 MiB/s[2K Progress: 41.7%, ETA 00m23s, 1104 MiB written, speed 65.6 MiB/s[2K Progress: 42.9%, ETA 00m23s, 1136 MiB written, speed 65.2 MiB/s[2K Progress: 44.1%, ETA 00m22s, 1168 MiB written, speed 65.0 MiB/s[2K Progress: 45.3%, ETA 00m22s, 1200 MiB written, speed 64.8 MiB/s[2K Progress: 46.6%, ETA 00m22s, 1232 MiB written, speed 64.5 MiB/s[2K Progress: 47.8%, ETA 00m21s, 1264 MiB written, speed 64.2 MiB/s[2K Progress: 49.6%, ETA 00m21s, 1312 MiB written, speed 64.2 MiB/s[2K Progress: 51.4%, ETA 00m20s, 1360 MiB written, speed 64.4 MiB/s[2K Progress: 53.2%, ETA 00m19s, 1408 MiB written, speed 64.6 MiB/s[2K Progress: 55.0%, ETA 00m18s, 1456 MiB written, speed 64.7 MiB/s[2K Progress: 56.8%, ETA 00m17s, 1504 MiB written, speed 64.9 MiB/s[2K Progress: 58.6%, ETA 00m17s, 1552 MiB written, speed 65.2 MiB/s[2K Progress: 60.5%, ETA 00m16s, 1600 MiB written, speed 65.3 MiB/s[2K Progress: 62.3%, ETA 00m15s, 1648 MiB written, speed 65.5 MiB/s[2K Progress: 63.5%, ETA 00m15s, 1680 MiB written, speed 65.5 MiB/s[2K Progress: 65.3%, ETA 00m14s, 1728 MiB written, speed 65.7 MiB/s[2K Progress: 67.1%, ETA 00m13s, 1776 MiB written, speed 65.8 MiB/s[2K Progress: 68.9%, ETA 00m12s, 1824 MiB written, speed 65.9 MiB/s[2K Progress: 70.7%, ETA 00m11s, 1872 MiB written, speed 66.0 MiB/s[2K Progress: 72.6%, ETA 00m11s, 1920 MiB written, speed 66.2 MiB/s[2K Progress: 74.4%, ETA 00m10s, 1968 MiB written, speed 66.3 MiB/s[2K Progress: 76.2%, ETA 00m09s, 2016 MiB written, speed 66.4 MiB/s[2K Progress: 78.0%, ETA 00m08s, 2064 MiB written, speed 66.5 MiB/s[2K Progress: 79.8%, ETA 00m08s, 2112 MiB written, speed 66.6 MiB/s[2K Progress: 81.6%, ETA 00m07s, 2160 MiB written, speed 66.7 MiB/s[2K Progress: 83.4%, ETA 00m06s, 2208 MiB written, speed 66.8 MiB/s[2K Progress: 85.2%, ETA 00m06s, 2256 MiB written, speed 66.8 MiB/s[2K Progress: 87.1%, ETA 00m05s, 2304 MiB written, speed 66.9 MiB/s[2K Progress: 88.9%, ETA 00m04s, 2352 MiB written, speed 67.0 MiB/s[2K Progress: 90.7%, ETA 00m03s, 2400 MiB written, speed 67.1 MiB/s[2K Progress: 92.5%, ETA 00m03s, 2448 MiB written, speed 67.2 MiB/s[2K Progress: 94.3%, ETA 00m02s, 2496 MiB written, speed 67.4 MiB/s[2K Progress: 96.1%, ETA 00m01s, 2544 MiB written, speed 67.4 MiB/s[2K Progress: 97.9%, ETA 00m01s, 2592 MiB written, speed 67.5 MiB/s[2K Progress: 99.4%, ETA 00m00s, 2630 MiB written, speed 67.5 MiB/s[2K Finished, time 00m38s, 2646 MiB written, speed 67.9 MiB/s New TPM2 token enrolled as key slot 1. done. Wiped slot 0. Device /dev/mapper/encrypted_home is not a valid VERITY device. [ 172.277345] device-mapper: verity: sha256 using implementation "sha256-generic" done. Begin: Running /scripts/local-premount ... done. Warning: fsck not present, so skipping root file system [ 172.428568] squashfs: version 4.0 (2009/01/31) Phillip Lougher done. Begin: Running /scripts/local-bottom ... var: 1433/341040 files (0.1% non-contiguous), 90171/673382 blocks [ 172.796452] EXT4-fs (dm-3): mounted filesystem with ordered data mode. Quota mode: none. [ 172.864295] EXT4-fs (dm-2): mounted filesystem with ordered data mode. Quota mode: none. done. Begin: Running /scripts/init-bottom ... done. [ 172.954632] Not activating Mandatory Access Control as /sbin/tomoyo-init does not exist. [ 173.408324] systemd[1]: Inserted module 'autofs4' [ 173.566861] systemd[1]: systemd 252.30-1~deb12u2 running in system mode (+PAM +AUDIT +SELINUX +APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL +ACL +BLKID +CURL +ELFUTILS +FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY +P11KIT +QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) [ 173.578469] systemd[1]: Detected virtualization kvm. [ 173.580695] systemd[1]: Detected architecture x86-64. [ 173.586777] systemd[1]: Detected first boot. Welcome to [1mDebian GNU/Linux 12 (bookworm)[0m! [ 173.593049] systemd[1]: Hostname set to . [ 173.609845] systemd[1]: Initializing machine ID from random generator. [ 174.095819] systemd[1]: Populated /etc with preset unit settings. [ 174.190403] systemd[1]: Queued start job for default target graphical.target. [ 174.192523] systemd[1]: Unnecessary job was removed for dev-disk-by\x2dlabel-var.device - /dev/disk/by-label/var. [ 174.195144] systemd[1]: Unnecessary job was removed for dev-mapper-encrypted_home.device - /dev/mapper/encrypted_home. [ 174.255203] systemd[1]: Created slice system-getty.slice - Slice /system/getty. [[0;32m OK [0m] Created slice [0;1;39msystem-getty.slice[0m - Slice /system/getty. [ 174.261797] systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. [[0;32m OK [0m] Created slice [0;1;39msystem-modpr���lice[[ 174.265433] systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. 0m - Slice /system/modprobe. [[ 174.268293] systemd[1]: Started ntpsec-systemd-netif.path. [0;32m OK [0m] Created slice[ 174.270307] systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. [0;1;39msystem-seria���[0m - [ 174.273543] systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Slice /system/serial-getty. [[ 174.277397] systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. [0;32m OK [0m] Started [0;1;[ 174.282525] systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... 39mntpsec-systemd-netif.path[0m[ 174.285694] systemd[1]: Expecting device sys-subsystem-net-devices-mgmt0.device - /sys/subsystem/net/devices/mgmt0... . [[0;32m OK [0m] Started [ 174.289982] systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. [0;1;39msystemd-ask-passwo���qu[ 174.293305] systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. ests to Console Directory Watch.[ 174.297256] systemd[1]: Reached target paths.target - Path Units. [[0;32m OK [0m] Started [ 174.300137] systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. [0;1;39msystemd-ask-passwo��� Re[ 174.303688] systemd[1]: Reached target remote-fs.target - Remote File Systems. quests to Wall Directory Watch. [ 174.306900] systemd[1]: Reached target remote-veritysetup.target - Remote Verity Protected Volumes. [[0;32m OK [0m] Set up aut[ 174.311067] systemd[1]: Reached target slices.target - Slice Units. omount [0;1;39mproc-sys-���rmat[ 174.314046] systemd[1]: Reached target swap.target - Swaps. s File System Automount Point. [ 174.316903] systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Expecting device [0;1;39mdev-ttyS0.device[0m - /dev/ttyS0... Expecting device [0;1;39msys-subsy���sys/subsystem/net/devices/mgmt0... [[0;32m OK [0m] Reached target [0;1;39mcryptsetup.���get[0m - Local Encrypted Volumes. [[0;32m OK [0m] Reached target [0;1;39mintegrityse���Local Integrity Protected Volumes. [[0;32m OK [0m] Reached target [0;1;39mpaths.target[0m - Path Units. [[0;32m OK [0m] Reached target [0;1;39mremote-cryp���et[0m - Remote Encrypted Volumes. [[0;32m OK [0m] Reached target [0;1;39mremote-fs.target[0m - Remote File Systems. [[0;32m OK [0m] Reached target [0;1;39mremote-veri���- Remote Verity Protected Volumes. [[0;32m OK [0m] Reached target [0;1;39mslices.target[0m - Slice Units. [[0;32m OK [0m] Reached target [0;1;39mswap.target[0m - Swaps. [[0;32m OK [0m] Reached target [0;1;39mveritysetup��� - Local Verity Protected Volumes. [ 174.358498] systemd[1]: Listening on systemd-fsckd.socket - fsck to fsckd communication Socket. [[0;32m OK [0m] Listening on [0;1;39msystem[ 174.362745] systemd[1]: Listening on systemd-initctl.socket - initctl Compatibility Named Pipe. d-fsckd���sck to fsckd communication Socket. [[ 174.367449] systemd[1]: Listening on systemd-journald-audit.socket - Journal Audit Socket. [0;32m OK [0m] Listening on [ 174.370035] systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). [0;1;39msystemd-initc��� initct[ 174.372933] systemd[1]: Listening on systemd-journald.socket - Journal Socket. l Compatibility Named Pipe. [[ 174.377243] systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. [0;32m OK [0m] Listening on [0;1;39msystemd-journ���socket[0m - Journal Aud[ 174.385846] systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. it Socket. [[0;32m OK [0m][ 174.389653] systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Listening on [0;1;39msystemd-journ���t[0m - Journal Socket (/dev/log). [[0;32m OK [0m] Listening on [0;1;39msystemd-journald.socket[0m - Journal Socket. [[0;32m OK [0m] Listening on [0;1;39msystemd-netwo��� - Network Service Netlink Socket. [[0;32m OK [0m] Listening on [0;1;39msystemd-udevd���.socket[0m - udev Control Socket. [[0;32m OK [0m] Listening on [0;1;39msystemd-udevd���l.socket[0m - udev Kernel Socket. [ 174.409209] systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Mounting [0;1;39mdev-hugepages.mount[[ 174.415144] systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... 0m - Huge Pages File System... Mounting [0;1;39mdev-mqueue.mount���POSIX Message Queue File System... [ 174.427381] systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Mounting [0;1;39msys-kernel-debug.���[0m - Kernel Debug File System... [ 174.434013] systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Mounting [0;1;39msys-kernel-tracin���[0m - Kernel Trace File System... [ 174.442836] systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Mounting [0;1;39mtmp.mount[0m - Temporary Directory /tmp... [ 174.457781] systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Starting [0;1;39mkmod-static-nodes���ate List of Static Device Nodes... [ 174.464970] systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Starting [0;1;39mmodprobe@configfs���m - Load Kernel Module configfs... [ 174.471754] systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Starting [0;1;39mmodprobe@dm_mod.s���[0m - Load Kernel Module dm_mod... [ 174.479249] systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Starting [0;1;39mmodprobe@efi_psto���- Load Kernel Module efi_pstore... [ 174.486021] systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Starting [0;1;39mmodprobe@fuse.ser���e[0m - Load Kernel Module fuse... [ 174.492051] systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Starting [0;1;39mmodprobe@loop.ser���e[0m - Load Kernel Module loop... [ 174.498891] systemd[1]: Starting systemd-fsck-root.service - File System Check on Root Device... [ 174.499574] pstore: Using crash dump compression: deflate Starting [0;1;39msyste[ 174.507205] pstore: Registered efi as persistent store backend md-fsck-root���ile System Check on Root Device... [ 174.519643] fuse: init (API version 7.37) [ 174.522871] systemd[1]: Starting systemd-journald.service - Journal Service... Starting [0;1;39msystemd-journald.service[0m - Journal Service... [ 174.539294] systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Starting [0;1;39msystemd-modules-l���rvice[0m - Load Kernel Modules... [ 174.546832] systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Starting [0;1;39msystemd-network-g��� units from Kernel command line... [ 174.555180] systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Starting [0;1;39msystemd-udev-trig���[0m - Coldplug All udev Devices... [ 174.564332] systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. [[0;32m OK [0m] Mounted [0;1;39mdev-hugepages.mount[0m - Huge Pages File System. [ 174.570665] systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. [[0;32m OK [0m] Mounted [0;1;39mdev-mqueue.mount[���- POSIX Message Queue File System. [ 174.577474] systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. [[0;32m OK [0m] Mounted [0;1;39msys-kernel-debug.m���nt[0m - Kernel Debug File System. [ 174.583397] systemd[1]: Started systemd-journald.service - Journal Service. [[0;32m OK [0m] Started [0;1;39msystemd-journald.service[0m - Journal Service. [[0;32m OK [0m] Mounted [0;1;39msys-kernel-tracing���nt[0m - Kernel Trace File System. [[0;32m OK [0m] Mounted [0;1;39mtmp.mount[0m - Temporary Directory /tmp. [[0;32m OK [0m] Finished [0;1;39mkmod-static-nodes���reate List of Static Device Nodes. [[0;32m OK [0m] Finished [0;1;39mmodprobe@configfs���[0m - Load Kernel Module configfs. [[0;32m OK [0m] Finished [0;1;39mmodprobe@dm_mod.s���e[0m - Load Kernel Module dm_mod. [[0;32m OK [0m] Finished [0;1;39mmodprobe@efi_psto���m - Load Kernel Module efi_pstore. [[0;32m OK [0m] Finished [0;1;39mmodprobe@fuse.service[0m - Load Kernel Module fuse. [[0;32m OK [0m] Finished [0;1;39mmodprobe@loop.service[0m - Load Kernel Module loop. [[0;32m OK [0m] Finished [0;1;39msystemd-fsck-root��� File System Check on Root Device. [[0;32m OK [0m] Finished [0;1;39msystemd-network-g���rk units from Kernel command line. [[0;32m OK [0m] Reached target [0;1;39mnetwork-pre���get[0m - Preparation for Network. [ 174.632039] ACPI: bus type USB registered [ 174.634007] usbcore: registered new interface driver usbfs [ 174.636413] usbcore: registered new interface driver hub [ 174.638415] usbcore: registered new device driver usb Mounting [0;1;39msys-fs-fuse-conne���[0m - FUSE Control File System... Mounting [0;1;39msys-kernel-config���ernel Configuration File System... Starting [0;1;39msystemd-remount-f���nt Root and Kernel File Systems... [[0;32m OK [0m] Mounted [0;1;39msys-fs-fuse-connec���nt[0m - FUSE Control File System. [[0;32m OK [0m] Mounted [0;1;39msys-kernel-config.��� Kernel Configuration File System. [[0;32m OK [0m] Finished [0;1;39msystemd-udev-trig���e[0m - Coldplug All udev Devices. [[0;32m OK [0m] Finished [0;1;39msystemd-remount-f���ount Root and Kernel File Systems. Starting [0;1;39mexpand-on-first-b���ice[0m - Expand last partition... Starting [0;1;39msystemd-journal-f���h Journal to Persistent Storage... Starting [0;1;39msystemd-random-se���ice[0m - Load/Save Random Seed... Starting [0;1;39msystemd-sysusers.���rvice[0m - Create System Users... [ 174.763442] systemd-journald[9476]: Received client request to flush runtime journal. [[0;32m OK [0m] Finished [0;1;39msystemd-random-se���rvice[0m - Load/Save Random Seed. [[0;32m OK [0m] Reached target [0;1;39mfirst-boot-���.target[0m - First Boot Complete. Starting [0;1;39msshd-regen-keys.s���[0m - Regenerate sshd host keys... [[0;32m OK [0m] Finished [0;1;39msystemd-sysusers.service[0m - Create System Users. Starting [0;1;39msystemd-tmpfiles-���ate Static Device Nodes in /dev... [[0;32m OK [0m] Finished [0;1;39msystemd-journal-f���ush Journal to Persistent Storage. [ 174.890067] cfg80211: Loading compiled-in X.509 certificates for regulatory database [ 174.893412] cfg80211: Loaded X.509 cert 'benh@debian.org: 577e021cb980e0e820821ba7b54b4961b8b4fadf' [ 174.897536] cfg80211: Loaded X.509 cert 'romain.perier@gmail.com: 3abbc6ec146e09d1b6016ab9d6cf71dd233f0328' [ 174.901948] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' [ 174.904748] cfg80211: Loaded X.509 cert 'wens: 61c038651aabdcf94bd0ac7ff06c7248db18c600' [ 174.909931] platform regulatory.0: firmware: failed to load regulatory.db (-2) [ 174.912891] firmware_class: See https://wiki.debian.org/Firmware for information about missing firmware [ 174.916412] platform regulatory.0: firmware: failed to load regulatory.db (-2) [ 174.919498] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2 [ 174.921874] cfg80211: failed to load regulatory.db [[0;32m OK [0m] Finished [0;1;39msystemd-tmpfiles-���reate Static Device Nodes in /dev. Starting [0;1;39msystemd-udevd.ser���ger for Device Events and Files... [ 175.018654] 8821cu: loading out-of-tree module taints kernel. [ 175.027653] 8821cu: module verification failed: signature and/or required key missing - tainting kernel [ 175.139647] usbcore: registered new interface driver rtl8821cu [[0;32m OK [0m] Started [0;1;39msystemd-udevd.serv���nager for Device Events and Files. [ 175.349693] sd 0:0:0:0: Attached scsi generic sg0 type 0 [ 175.355292] sd 5:0:0:0: Attached scsi generic sg1 type 0 [ 175.358509] usbcore: registered new interface driver rtl88x2bu [[0;32m OK [0m] Finished [0;1;39msystemd-modules-l���service[0m - Load Kernel Modules. [ 175.392546] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input4 [ 175.419014] ACPI: button: Power Button [PWRF] [ 175.476438] parport_pc 00:02: reported by Plug and Play ACPI [ 175.512036] parport0: PC-style at 0x378, irq 7 [PCSPP,TRISTATE] [ 175.525242] input: PC Speaker as /devices/platform/pcspkr/input/input5 [ 175.535085] iTCO_vendor_support: vendor-support=0 [ 175.551388] ACPI: bus type drm_connector registered [ 175.561531] iTCO_wdt iTCO_wdt.1.auto: Found a ICH9 TCO device (Version=2, TCOBASE=0x0660) [ 175.564392] watchdog: watchdog0 running and kernel based pre-userspace handler disabled [ 175.566702] iTCO_wdt iTCO_wdt.1.auto: initialized. heartbeat=30 sec (nowayout=1) [ 175.594960] Console: switching to colour dummy device 80x25 [ 175.597083] bochs-drm 0000:00:01.0: vgaarb: deactivate vga console [ 175.599747] [drm] Found bochs VGA, ID 0xb0c5. [ 175.600914] [drm] Framebuffer size 16384 kB @ 0xc0000000, mmio @ 0xc1088000. [ 175.611882] [drm] Found EDID data blob. [ 175.613897] [drm] Initialized bochs-drm 1.0.0 20130925 for 0000:00:01.0 on minor 0 [ 175.619898] fbcon: bochs-drmdrmfb (fb0) is primary device [ 175.709128] Console: switching to colour frame buffer device 160x50 [ 175.756731] bochs-drm 0000:00:01.0: [drm] fb0: bochs-drmdrmfb frame buffer device [[0;32m OK [0m] Found device [0;1;39mdev-ttyS0.device[0m - /dev/ttyS0. [ 175.775470] ppdev: user-space parallel port driver [[0;32m OK [0m] Finished [0;1;39mexpand-on-first-b���rvice[0m - Expand last partition. [[0;32m OK [0m] Reached target [0;1;39mlocal-fs-pr���reparation for Local File Systems. [[0;32m OK [0m] Reached target [0;1;39mlocal-fs.target[0m - Local File Systems. [[0;32m OK [0m] Listening on [0;1;39msystemd-rfkil���l Switch Status /dev/rfkill Watch. Starting [0;1;39msystemd-binfmt.se���et Up Additional Binary Formats... Starting [0;1;39msystemd-machine-i��� a transient machine-id on disk... Starting [0;1;39msystemd-sysctl.se���ce[0m - Apply Kernel Variables... Starting [0;1;39msystemd-tmpfiles-���te System Files and Directories... Mounting [0;1;39mproc-sys-fs-binfm���utable File Formats File System... [[0;32m OK [0m] Finished [0;1;39msystemd-sysctl.service[0m - Apply Kernel Variables. Starting [0;1;39msystemd-networkd.���ice[0m - Network Configuration... [[0;32m OK [0m] Mounted [0;1;39mproc-sys-fs-binfmt���ecutable File Formats File System. [[0;32m OK [0m] Finished [0;1;39msystemd-binfmt.se��� Set Up Additional Binary Formats. [[0;32m OK [0m] Finished [0;1;39msystemd-machine-i���it a transient machine-id on disk. [[0;32m OK [0m] Finished [0;1;39msystemd-tmpfiles-���eate System Files and Directories. Starting [0;1;39mauditd.service[0m - Security Auditing Service... Starting [0;1;39mmodprobe@dm_mod.s���[0m - Load Kernel Module dm_mod... Starting [0;1;39mmodprobe@efi_psto���- Load Kernel Module efi_pstore... Starting [0;1;39mmodprobe@loop.ser���e[0m - Load Kernel Module loop... [[0;32m OK [0m] Finished [0;1;39mmodprobe@dm_mod.s���e[0m - Load Kernel Module dm_mod. [[0;32m OK [0m] Finished [0;1;39mmodprobe@efi_psto���m - Load Kernel Module efi_pstore. [[0;32m OK [0m] Finished [0;1;39mmodprobe@loop.service[0m - Load Kernel Module loop. [[0;32m OK [0m] Started [0;1;39msystemd-networkd.service[0m - Network Configuration. [[0;32m OK [0m] Reached target [0;1;39mnetwork.target[0m - Network. [ 182.545986] clocksource: Long readout interval, skipping watchdog check: cs_nsec: 5775579719 wd_nsec: 5775569811 Starting [0;1;39msystemd-networkd-���it for Network to be Configured... [[0m[0;31m* [0m] (1 of 4) Job auditd.service/start running (8s / 1min 31s) M [K[[0;32m OK [0m] Started [0;1;39mauditd.service[0m - Security Auditing Service. [K Starting [0;1;39msystemd-update-ut���rd System Boot/Shutdown in UTMP... [[0;32m OK [0m] Finished [0;1;39msystemd-update-ut���cord System Boot/Shutdown in UTMP. [[0;32m OK [0m] Reached target [0;1;39msysinit.target[0m - System Initialization. [[0;32m OK [0m] Started [0;1;39mapt-daily.timer[0m - Daily apt download activities. [[0;32m OK [0m] Started [0;1;39mapt-daily-upgrade.��� apt upgrade and clean activities. [[0;32m OK [0m] Started [0;1;39mdpkg-db-backup.tim��� Daily dpkg database backup timer. [[0;32m OK [0m] Started [0;1;39me2scrub_all.timer���etadata Check for All Filesystems. [[0;32m OK [0m] Started [0;1;39mfstrim.timer[0m - Discard unused blocks once a week. [[0;32m OK [0m] Started [0;1;39mntpsec-rotate-stat���mer[0m - Rotate ntpd stats daily. [[0;32m OK [0m] Started [0;1;39msystemd-tmpfiles-c��� Cleanup of Temporary Directories. [[0;32m OK [0m] Reached target [0;1;39mtimers.target[0m - Timer Units. [[0;32m OK [0m] Listening on [0;1;39mssh.socket[0���penBSD Secure Shell server socket. Starting [0;1;39mswupdate.socket[0m - SWUpdate socket listener... [[0;32m OK [0m] Listening on [0;1;39mswupdate.socket[0m - SWUpdate socket listener. [[0;32m OK [0m] Reached target [0;1;39msockets.target[0m - Socket Units. [[0;32m OK [0m] Reached target [0;1;39mbasic.target[0m - Basic System. [[0;32m OK [0m] Started [0;1;39mdevice-manager.service[0m - device manager service. Starting [0;1;39mdnsmasq.service[���ght DHCP and caching DNS server... Starting [0;1;39me2scrub_reap.serv���e ext4 Metadata Check Snapshots... Starting [0;1;39mgetty-static.serv���us and logind are not available... [[0;32m OK [0m] Started [0;1;39mntpsec-systemd-netif.service[0m. [[0;32m OK [0m] Started [0;1;39mswupdate.service[0m - SWUpdate daemon. [[0;32m OK [0m] Started [0;1;39mswupdate-progress.���e[0m - swupdate progress service. Starting [0;1;39msystemd-user-sess���vice[0m - Permit User Sessions... [[0;32m OK [0m] Finished [0;1;39msshd-regen-keys.s���e[0m - Regenerate sshd host keys. [ 183.044417] process '/usr/bin/swupdate-progress' started with executable stack Starting [0;1;39mssh.service[0m - OpenBSD Secure Shell server... [[0;32m OK [0m] Finished [0;1;39msystemd-user-sess���ervice[0m - Permit User Sessions. [[0;32m OK [0m] Started [0;1;39mgetty@tty1.service[0m - Getty on tty1. [[0;32m OK [0m] Started [0;1;39mgetty@tty2.service[0m - Getty on tty2. [[0;32m OK [0m] Started [0;1;39mserial-getty@ttyS0���rvice[0m - Serial Getty on ttyS0. [[0;32m OK [0m] Started [0;1;39mgetty@tty3.service[0m - Getty on tty3. [[0;32m OK [0m] Started [0;1;39mgetty@tty4.service[0m - Getty on tty4. [[0;32m OK [0m] Started [0;1;39mgetty@tty5.service[0m - Getty on tty5. [[0;32m OK [0m] Started [0;1;39mgetty@tty6.service[0m - Getty on tty6. [[0;32m OK [0m] Finished [0;1;39mgetty-static.serv���dbus and logind are not available. [[0;32m OK [0m] Reached target [0;1;39mgetty.target[0m - Login Prompts. [[0;32m OK [0m] Started [0;1;39mssh.service[0m - OpenBSD Secure Shell server. [[0;32m OK [0m] Started [0;1;39mdnsmasq.service[0���eight DHCP and caching DNS server. [[0;32m OK [0m] Reached target [0;1;39mnss-lookup.���m - Host and Network Name Lookups. Starting [0;1;39mntpsec.service[0m - Network Time Service... [[0;32m OK [0m] Finished [0;1;39me2scrub_reap.serv���ine ext4 Metadata Check Snapshots. [[0;32m OK [0m] Started [0;1;39mntpsec.service[0m - Network Time Service. _____ ____ ______ |_ _ / __ \ / ____| | | | | | | (___ | | | | | |\___ \ _| | | |__| |____) | |_____ \____/|_____/ System Info: Version.....: Debian OS Type......: Linux Interfaces: eth0.......:192.168.1.10 Instruction: 1. welcome to console 2. login with user and password Alternative : 1. Make sure network interface is up of eth0 network 2. Open a browser and type "http://"[0m You are currently connected via: ttyS0 (115200) Setting prompt string to ['-+\\[ end trace \\w* \\]-+[^\\n]*\\r'] device-mgmt login: [ 194.754113] bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. [ 194.769960] Bridge firewalling registered [ 222.705931] cni0: port 1(veth4554b4f9) entered blocking state [ 222.708583] cni0: port 1(veth4554b4f9) entered disabled state [ 222.711063] device veth4554b4f9 entered promiscuous mode [ 222.714444] cni0: port 1(veth4554b4f9) entered blocking state [ 222.716615] cni0: port 1(veth4554b4f9) entered forwarding state [ 222.723453] cni0: port 1(veth4554b4f9) entered disabled state [ 222.730702] cni0: port 2(veth093fce7f) entered blocking state [ 222.733063] cni0: port 2(veth093fce7f) entered disabled state [ 222.736754] device veth093fce7f entered promiscuous mode [ 222.740475] cni0: port 2(veth093fce7f) entered blocking state [ 222.743878] cni0: port 2(veth093fce7f) entered forwarding state [ 222.747594] cni0: port 3(veth07097cef) entered blocking state [ 222.750417] cni0: port 3(veth07097cef) entered disabled state [ 222.754065] device veth07097cef entered promiscuous mode [ 222.757150] cni0: port 3(veth07097cef) entered blocking state [ 222.760403] cni0: port 3(veth07097cef) entered forwarding state [ 222.763499] cni0: port 4(vethae4834dd) entered blocking state [ 222.765951] cni0: port 4(vethae4834dd) entered disabled state [ 222.769453] device vethae4834dd entered promiscuous mode [ 222.774008] cni0: port 4(vethae4834dd) entered blocking state [ 222.776549] cni0: port 4(vethae4834dd) entered forwarding state [ 222.780167] cni0: port 4(vethae4834dd) entered disabled state [ 222.784384] cni0: port 3(veth07097cef) entered disabled state [ 222.787363] cni0: port 2(veth093fce7f) entered disabled state [ 222.790765] cni0: port 5(vethd6a4e853) entered blocking state [ 222.793266] cni0: port 5(vethd6a4e853) entered disabled state [ 222.796223] device vethd6a4e853 entered promiscuous mode [ 222.800667] IPv6: ADDRCONF(NETDEV_CHANGE): veth4554b4f9: link becomes ready [ 222.804454] cni0: port 1(veth4554b4f9) entered blocking state [ 222.807810] cni0: port 1(veth4554b4f9) entered forwarding state [ 222.814212] IPv6: ADDRCONF(NETDEV_CHANGE): vethae4834dd: link becomes ready [ 222.818179] cni0: port 4(vethae4834dd) entered blocking state [ 222.821346] cni0: port 4(vethae4834dd) entered forwarding state [ 222.827590] IPv6: ADDRCONF(NETDEV_CHANGE): veth07097cef: link becomes ready [ 222.832379] cni0: port 3(veth07097cef) entered blocking state [ 222.835613] cni0: port 3(veth07097cef) entered forwarding state [ 222.838915] IPv6: ADDRCONF(NETDEV_CHANGE): veth093fce7f: link becomes ready [ 222.843524] cni0: port 2(veth093fce7f) entered blocking state [ 222.846458] cni0: port 2(veth093fce7f) entered forwarding state [ 222.853828] IPv6: ADDRCONF(NETDEV_CHANGE): vethd6a4e853: link becomes ready [ 222.857673] cni0: port 5(vethd6a4e853) entered blocking state [ 222.861673] cni0: port 5(vethd6a4e853) entered forwarding state [ 252.785085] cni0: port 5(vethd6a4e853) entered disabled state [ 252.800905] device vethd6a4e853 left promiscuous mode [ 252.804097] cni0: port 5(vethd6a4e853) entered disabled state [ 255.682943] cni0: port 5(veth0845ab3b) entered blocking state [ 255.686414] cni0: port 5(veth0845ab3b) entered disabled state [ 255.690247] device veth0845ab3b entered promiscuous mode [ 255.705978] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [ 255.709353] IPv6: ADDRCONF(NETDEV_CHANGE): veth0845ab3b: link becomes ready [ 255.713264] cni0: port 5(veth0845ab3b) entered blocking state [ 255.717017] cni0: port 5(veth0845ab3b) entered forwarding state [ 255.722599] cni0: port 6(veth2e2f5f0d) entered blocking state [ 255.726149] cni0: port 6(veth2e2f5f0d) entered disabled state [ 255.729722] device veth2e2f5f0d entered promiscuous mode [ 255.747093] IPv6: ADDRCONF(NETDEV_CHANGE): veth2e2f5f0d: link becomes ready [ 255.752363] cni0: port 6(veth2e2f5f0d) entered blocking state [ 255.755800] cni0: port 6(veth2e2f5f0d) entered forwarding state [ 258.248257] cni0: port 1(veth4554b4f9) entered disabled state [ 258.262545] device veth4554b4f9 left promiscuous mode [ 258.266103] cni0: port 1(veth4554b4f9) entered disabled state login-action timed out after 893 seconds end: 2.2.1 login-action (duration 00:14:53) [common] definition: lava namespace: common case: login-action level: 2.2.1 duration: 893.01 result: fail extra: ... auto-login-action failed: 1 of 1 attempts. 'login-action timed out after 893 seconds' login-action timed out after 893 seconds end: 2.2 auto-login-action (duration 00:15:00) [common] definition: lava namespace: common case: auto-login-action level: 2.2 duration: 899.66 result: fail extra: ... boot-image-retry failed: 1 of 1 attempts. 'login-action timed out after 893 seconds' login-action timed out after 893 seconds end: 2 boot-image-retry (duration 00:15:00) [common] definition: lava namespace: common case: boot-image-retry level: 2 duration: 899.67 result: fail extra: ... Cleaning after the job Cleaning up download directory: /var/lib/lava/dispatcher/tmp/279/deployimages-k4ph8tux/rootfs start: 4.1 power-off (timeout 00:00:10) [common] end: 4.1 power-off (duration 00:00:00) [common] start: 4.2 read-feedback (timeout 00:05:00) [common] Listened to connection for namespace 'common' for up to 1s Finalising connection for namespace 'common' Sending line: 'poweroff' poweroff Already disconnected poweroff Traceback (most recent call last): File "/usr/lib/python3/dist-packages/lava_dispatcher/job.py", line 239, in run self._run() File "/usr/lib/python3/dist-packages/lava_dispatcher/job.py", line 229, in _run self.pipeline.run_actions(self.connection, max_end_time) File "/usr/lib/python3/dist-packages/lava_dispatcher/action.py", line 249, in run_actions new_connection = action.run(connection, action_max_end_time) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/lava_dispatcher/logical.py", line 62, in run connection = self.pipeline.run_actions(connection, max_end_time) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/lava_dispatcher/action.py", line 249, in run_actions new_connection = action.run(connection, action_max_end_time) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/lava_dispatcher/actions/boot/__init__.py", line 336, in run connection = super().run(connection, max_end_time) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/lava_dispatcher/logical.py", line 62, in run connection = self.pipeline.run_actions(connection, max_end_time) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/lava_dispatcher/action.py", line 249, in run_actions new_connection = action.run(connection, action_max_end_time) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/lava_dispatcher/actions/boot/__init__.py", line 170, in run self.check_kernel_messages( File "/usr/lib/python3/dist-packages/lava_dispatcher/actions/boot/__init__.py", line 90, in check_kernel_messages parsed = LinuxKernelMessages.parse_failures( ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/lava_dispatcher/utils/messages.py", line 159, in parse_failures sub_index = connection.wait(max_end_time, max_searchwindowsize=True) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/lava_dispatcher/shell.py", line 297, in wait return self.raw_connection.expect( ^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/lava_dispatcher/shell.py", line 173, in expect proc = super().expect(*args, **kw) ^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/pexpect/spawnbase.py", line 343, in expect return self.expect_list(compiled_pattern_list, ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/pexpect/spawnbase.py", line 372, in expect_list return exp.expect_loop(timeout) ^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/pexpect/expect.py", line 169, in expect_loop incoming = spawn.read_nonblocking(spawn.maxread, timeout) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/pexpect/pty_spawn.py", line 500, in read_nonblocking if (timeout != 0) and select(timeout): ^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/pexpect/pty_spawn.py", line 450, in select return select_ignore_interrupts([self.child_fd], [], [], timeout)[0] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/pexpect/utils.py", line 143, in select_ignore_interrupts return select.select(iwtd, owtd, ewtd, timeout) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/lava_common/timeout.py", line 80, in _timed_out raise self.exception("%s timed out after %s seconds" % (self.name, duration)) lava_common.exceptions.JobError: login-action timed out after 893 seconds During handling of the above exception, another exception occurred: Traceback (most recent call last): File "/usr/lib/python3/dist-packages/pexpect/pty_spawn.py", line 23, in _wrap_ptyprocess_err yield File "/usr/lib/python3/dist-packages/pexpect/pty_spawn.py", line 328, in close self.ptyproc.close(force=force) File "/usr/lib/python3/dist-packages/ptyprocess/ptyprocess.py", line 406, in close raise PtyProcessError('Could not terminate the child.') ptyprocess.util.PtyProcessError: Could not terminate the child. During handling of the above exception, another exception occurred: Traceback (most recent call last): File "/usr/lib/python3/dist-packages/lava_dispatcher/action.py", line 249, in run_actions new_connection = action.run(connection, action_max_end_time) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/lava_dispatcher/power.py", line 308, in run feedback[1].finalise() File "/usr/lib/python3/dist-packages/lava_dispatcher/connections/serial.py", line 246, in finalise self.disconnect("closing") File "/usr/lib/python3/dist-packages/lava_dispatcher/connections/serial.py", line 253, in disconnect super().disconnect(reason) File "/usr/lib/python3/dist-packages/lava_dispatcher/connection.py", line 153, in disconnect self.raw_connection.close(force=True) File "/usr/lib/python3/dist-packages/pexpect/pty_spawn.py", line 325, in close with _wrap_ptyprocess_err(): File "/usr/lib/python3.11/contextlib.py", line 155, in __exit__ self.gen.throw(typ, value, traceback) File "/usr/lib/python3/dist-packages/pexpect/pty_spawn.py", line 25, in _wrap_ptyprocess_err raise ExceptionPexpect(*e.args) pexpect.exceptions.ExceptionPexpect: Could not terminate the child. end: 4.2 read-feedback (duration 00:00:02) [common] definition: lava namespace: common case: read-feedback level: 4.2 duration: 1.68 result: fail extra: ... Failed to run 'finalize': Could not terminate the child. Traceback (most recent call last): File "/usr/lib/python3/dist-packages/lava_dispatcher/job.py", line 239, in run self._run() File "/usr/lib/python3/dist-packages/lava_dispatcher/job.py", line 229, in _run self.pipeline.run_actions(self.connection, max_end_time) File "/usr/lib/python3/dist-packages/lava_dispatcher/action.py", line 249, in run_actions new_connection = action.run(connection, action_max_end_time) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/lava_dispatcher/logical.py", line 62, in run connection = self.pipeline.run_actions(connection, max_end_time) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/lava_dispatcher/action.py", line 249, in run_actions new_connection = action.run(connection, action_max_end_time) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/lava_dispatcher/actions/boot/__init__.py", line 336, in run connection = super().run(connection, max_end_time) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/lava_dispatcher/logical.py", line 62, in run connection = self.pipeline.run_actions(connection, max_end_time) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/lava_dispatcher/action.py", line 249, in run_actions new_connection = action.run(connection, action_max_end_time) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/lava_dispatcher/actions/boot/__init__.py", line 170, in run self.check_kernel_messages( File "/usr/lib/python3/dist-packages/lava_dispatcher/actions/boot/__init__.py", line 90, in check_kernel_messages parsed = LinuxKernelMessages.parse_failures( ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/lava_dispatcher/utils/messages.py", line 159, in parse_failures sub_index = connection.wait(max_end_time, max_searchwindowsize=True) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/lava_dispatcher/shell.py", line 297, in wait return self.raw_connection.expect( ^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/lava_dispatcher/shell.py", line 173, in expect proc = super().expect(*args, **kw) ^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/pexpect/spawnbase.py", line 343, in expect return self.expect_list(compiled_pattern_list, ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/pexpect/spawnbase.py", line 372, in expect_list return exp.expect_loop(timeout) ^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/pexpect/expect.py", line 169, in expect_loop incoming = spawn.read_nonblocking(spawn.maxread, timeout) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/pexpect/pty_spawn.py", line 500, in read_nonblocking if (timeout != 0) and select(timeout): ^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/pexpect/pty_spawn.py", line 450, in select return select_ignore_interrupts([self.child_fd], [], [], timeout)[0] ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/pexpect/utils.py", line 143, in select_ignore_interrupts return select.select(iwtd, owtd, ewtd, timeout) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/lava_common/timeout.py", line 80, in _timed_out raise self.exception("%s timed out after %s seconds" % (self.name, duration)) lava_common.exceptions.JobError: login-action timed out after 893 seconds During handling of the above exception, another exception occurred: Traceback (most recent call last): File "/usr/lib/python3/dist-packages/pexpect/pty_spawn.py", line 23, in _wrap_ptyprocess_err yield File "/usr/lib/python3/dist-packages/pexpect/pty_spawn.py", line 328, in close self.ptyproc.close(force=force) File "/usr/lib/python3/dist-packages/ptyprocess/ptyprocess.py", line 406, in close raise PtyProcessError('Could not terminate the child.') ptyprocess.util.PtyProcessError: Could not terminate the child. During handling of the above exception, another exception occurred: Traceback (most recent call last): File "/usr/lib/python3/dist-packages/lava_dispatcher/action.py", line 249, in run_actions new_connection = action.run(connection, action_max_end_time) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/lava_dispatcher/power.py", line 308, in run feedback[1].finalise() File "/usr/lib/python3/dist-packages/lava_dispatcher/connections/serial.py", line 246, in finalise self.disconnect("closing") File "/usr/lib/python3/dist-packages/lava_dispatcher/connections/serial.py", line 253, in disconnect super().disconnect(reason) File "/usr/lib/python3/dist-packages/lava_dispatcher/connection.py", line 153, in disconnect self.raw_connection.close(force=True) File "/usr/lib/python3/dist-packages/pexpect/pty_spawn.py", line 325, in close with _wrap_ptyprocess_err(): File "/usr/lib/python3.11/contextlib.py", line 155, in __exit__ self.gen.throw(typ, value, traceback) File "/usr/lib/python3/dist-packages/pexpect/pty_spawn.py", line 25, in _wrap_ptyprocess_err raise ExceptionPexpect(*e.args) pexpect.exceptions.ExceptionPexpect: Could not terminate the child. During handling of the above exception, another exception occurred: Traceback (most recent call last): File "/usr/lib/python3/dist-packages/lava_dispatcher/power.py", line 341, in run connection = super().run(connection, max_end_time) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/lava_dispatcher/action.py", line 848, in run return self.pipeline.run_actions(connection, max_end_time) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/lava_dispatcher/action.py", line 278, in run_actions raise LAVABug(str(exc)) lava_common.exceptions.LAVABug: Could not terminate the child. Removing root tmp directory at /var/lib/lava/dispatcher/tmp/279 JobError: Your job cannot terminate cleanly. definition: lava case: job result: fail error_msg: login-action timed out after 893 seconds error_type: Job