end: 2.1 boot-qemu-image (duration 00:00:00) [common] start: 2.2 auto-login-action (timeout 00:06:00) [common] Setting prompt string to ['Linux version [0-9]'] auto-login-action: Wait for prompt ['Linux version [0-9]'] (timeout 00:06: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 [ 1.949265] ima: Can not allocate sha384 (reason: -2) [ 1.958773] ima: Can not allocate sha512 (reason: -2) Loading, please wait... Starting systemd-udevd version 252.30-1~deb12u2 Begin: Loading essential drivers ... done. Begin: Running /scripts/init-premount ... done. Begin: Mounting root file system ... Begin: Running /scripts/local-top ... Device /dev/sda4 is not a valid LUKS device. Begin: Encryption of /dev/sda4 ... [2K Progress: 3.2%, ETA 00m35s, 32 MiB written, speed 27.9 MiB/s[2K Progress: 6.3%, ETA 00m23s, 64 MiB written, speed 40.3 MiB/s[2K Progress: 9.5%, ETA 00m20s, 96 MiB written, speed 45.0 MiB/s[2K Progress: 12.7%, ETA 00m18s, 128 MiB written, speed 48.6 MiB/s[2K Progress: 15.9%, ETA 00m17s, 160 MiB written, speed 50.2 MiB/s[2K Progress: 19.0%, ETA 00m16s, 192 MiB written, speed 51.5 MiB/s[2K Progress: 22.2%, ETA 00m15s, 224 MiB written, speed 52.1 MiB/s[2K Progress: 25.4%, ETA 00m14s, 256 MiB written, speed 53.0 MiB/s[2K Progress: 28.6%, ETA 00m13s, 288 MiB written, speed 53.9 MiB/s[2K Progress: 31.7%, ETA 00m12s, 320 MiB written, speed 54.2 MiB/s[2K Progress: 34.9%, ETA 00m12s, 352 MiB written, speed 54.6 MiB/s[2K Progress: 38.1%, ETA 00m11s, 384 MiB written, speed 55.1 MiB/s[2K Progress: 41.3%, ETA 00m10s, 416 MiB written, speed 55.4 MiB/s[2K Progress: 44.4%, ETA 00m10s, 448 MiB written, speed 55.9 MiB/s[2K Progress: 47.6%, ETA 00m09s, 480 MiB written, speed 56.3 MiB/s[2K Progress: 50.8%, ETA 00m09s, 512 MiB written, speed 56.5 MiB/s[2K Progress: 54.0%, ETA 00m08s, 544 MiB written, speed 56.5 MiB/s[2K Progress: 57.1%, ETA 00m07s, 576 MiB written, speed 56.5 MiB/s[2K Progress: 60.3%, ETA 00m07s, 608 MiB written, speed 56.7 MiB/s[2K Progress: 63.5%, ETA 00m06s, 640 MiB written, speed 56.9 MiB/s[2K Progress: 66.7%, ETA 00m06s, 672 MiB written, speed 56.9 MiB/s[2K Progress: 69.8%, ETA 00m05s, 704 MiB written, speed 57.0 MiB/s[2K Progress: 73.0%, ETA 00m05s, 736 MiB written, speed 56.9 MiB/s[2K Progress: 76.2%, ETA 00m04s, 768 MiB written, speed 57.0 MiB/s[2K Progress: 79.4%, ETA 00m03s, 800 MiB written, speed 57.3 MiB/s[2K Progress: 82.5%, ETA 00m03s, 832 MiB written, speed 57.5 MiB/s[2K Progress: 85.7%, ETA 00m02s, 864 MiB written, speed 57.6 MiB/s[2K Progress: 88.9%, ETA 00m02s, 896 MiB written, speed 57.7 MiB/s[2K Progress: 92.1%, ETA 00m01s, 928 MiB written, speed 57.8 MiB/s[2K Progress: 95.2%, ETA 00m01s, 960 MiB written, speed 57.9 MiB/s[2K Progress: 98.4%, ETA 00m00s, 992 MiB written, speed 57.9 MiB/s[2K Finished, time 00m16s, 1008 MiB written, speed 58.8 MiB/s New TPM2 token enrolled as key slot 1. done. Wiped slot 0. Device /dev/sda5 is not a valid LUKS device. Begin: Encryption of /dev/sda5 ... [2K Progress: 3.2%, ETA 00m31s, 32 MiB written, speed 31.3 MiB/s[2K Progress: 6.3%, ETA 00m22s, 64 MiB written, speed 42.6 MiB/s[2K Progress: 9.5%, ETA 00m21s, 96 MiB written, speed 44.0 MiB/s[2K Progress: 12.7%, ETA 00m19s, 128 MiB written, speed 46.4 MiB/s[2K Progress: 15.9%, ETA 00m18s, 160 MiB written, speed 47.8 MiB/s[2K Progress: 19.0%, ETA 00m17s, 192 MiB written, speed 48.7 MiB/s[2K Progress: 22.2%, ETA 00m16s, 224 MiB written, speed 49.6 MiB/s[2K Progress: 25.4%, ETA 00m15s, 256 MiB written, speed 50.5 MiB/s[2K Progress: 28.6%, ETA 00m14s, 288 MiB written, speed 51.3 MiB/s[2K Progress: 31.7%, ETA 00m13s, 320 MiB written, speed 51.9 MiB/s[2K Progress: 34.9%, ETA 00m12s, 352 MiB written, speed 52.4 MiB/s[2K Progress: 38.1%, ETA 00m12s, 384 MiB written, speed 53.0 MiB/s[2K Progress: 41.3%, ETA 00m11s, 416 MiB written, speed 53.2 MiB/s[2K Progress: 44.4%, ETA 00m10s, 448 MiB written, speed 53.8 MiB/s[2K Progress: 47.6%, ETA 00m10s, 480 MiB written, speed 54.3 MiB/s[2K Progress: 52.4%, ETA 00m08s, 528 MiB written, speed 55.1 MiB/s[2K Progress: 55.6%, ETA 00m08s, 560 MiB written, speed 54.7 MiB/s[2K Progress: 58.7%, ETA 00m07s, 592 MiB written, speed 54.9 MiB/s[2K Progress: 61.9%, ETA 00m07s, 624 MiB written, speed 55.0 MiB/s[2K Progress: 65.1%, ETA 00m06s, 656 MiB written, speed 55.1 MiB/s[2K Progress: 68.3%, ETA 00m06s, 688 MiB written, speed 55.2 MiB/s[2K Progress: 71.4%, ETA 00m05s, 720 MiB written, speed 55.5 MiB/s[2K Progress: 74.6%, ETA 00m04s, 752 MiB written, speed 55.7 MiB/s[2K Progress: 77.8%, ETA 00m04s, 784 MiB written, speed 55.9 MiB/s[2K Progress: 81.0%, ETA 00m03s, 816 MiB written, speed 55.8 MiB/s[2K Progress: 84.1%, ETA 00m03s, 848 MiB written, speed 55.8 MiB/s[2K Progress: 87.3%, ETA 00m02s, 880 MiB written, speed 55.9 MiB/s[2K Progress: 90.5%, ETA 00m01s, 912 MiB written, speed 56.0 MiB/s[2K Progress: 93.7%, ETA 00m01s, 944 MiB written, speed 56.1 MiB/s[2K Progress: 96.8%, ETA 00m00s, 976 MiB written, speed 56.2 MiB/s[2K Finished, time 00m17s, 1008 MiB written, speed 57.2 MiB/s New TPM2 token enrolled as key slot 1. done. Wiped slot 0. Device /dev/sda6 is not a valid LUKS device. Begin: Encryption of /dev/sda6 ... home: 19/170544 files (0.0% non-contiguous), 19895/340787 blocks resize2fs 1.47.0 (5-Feb-2023) Resizing the filesystem on /dev/sda6 to 332595 (4k) blocks. The filesystem on /dev/sda6 is now 332595 (4k) blocks long. [2K Progress: 2.4%, ETA 00m46s, 32 MiB written, speed 27.7 MiB/s[2K Progress: 4.9%, ETA 00m30s, 64 MiB written, speed 41.0 MiB/s[2K Progress: 7.3%, ETA 00m26s, 96 MiB written, speed 47.0 MiB/s[2K Progress: 9.7%, ETA 00m24s, 128 MiB written, speed 49.5 MiB/s[2K Progress: 12.2%, ETA 00m22s, 160 MiB written, speed 51.2 MiB/s[2K Progress: 14.6%, ETA 00m21s, 192 MiB written, speed 52.4 MiB/s[2K Progress: 17.0%, ETA 00m20s, 224 MiB written, speed 53.1 MiB/s[2K Progress: 19.5%, ETA 00m20s, 256 MiB written, speed 53.2 MiB/s[2K Progress: 21.9%, ETA 00m19s, 288 MiB written, speed 54.0 MiB/s[2K Progress: 24.3%, ETA 00m18s, 320 MiB written, speed 53.6 MiB/s[2K Progress: 26.8%, ETA 00m18s, 352 MiB written, speed 53.6 MiB/s[2K Progress: 29.2%, ETA 00m17s, 384 MiB written, speed 54.0 MiB/s[2K Progress: 31.6%, ETA 00m16s, 416 MiB written, speed 54.4 MiB/s[2K Progress: 34.1%, ETA 00m16s, 448 MiB written, speed 54.7 MiB/s[2K Progress: 36.5%, ETA 00m15s, 480 MiB written, speed 54.9 MiB/s[2K Progress: 38.9%, ETA 00m14s, 512 MiB written, speed 55.1 MiB/s[2K Progress: 41.4%, ETA 00m14s, 544 MiB written, speed 55.5 MiB/s[2K Progress: 43.8%, ETA 00m13s, 576 MiB written, speed 55.7 MiB/s[2K Progress: 46.2%, ETA 00m12s, 608 MiB written, speed 55.9 MiB/s[2K Progress: 48.7%, ETA 00m12s, 640 MiB written, speed 56.2 MiB/s[2K Progress: 51.1%, ETA 00m11s, 672 MiB written, speed 56.2 MiB/s[2K Progress: 53.5%, ETA 00m11s, 704 MiB written, speed 56.4 MiB/s[2K Progress: 56.0%, ETA 00m10s, 736 MiB written, speed 56.5 MiB/s[2K Progress: 58.4%, ETA 00m09s, 768 MiB written, speed 56.7 MiB/s[2K Progress: 60.8%, ETA 00m09s, 800 MiB written, speed 56.7 MiB/s[2K Progress: 63.3%, ETA 00m08s, 832 MiB written, speed 56.9 MiB/s[2K Progress: 65.7%, ETA 00m08s, 864 MiB written, speed 57.0 MiB/s[2K Progress: 68.1%, ETA 00m07s, 896 MiB written, speed 57.0 MiB/s[2K Progress: 70.6%, ETA 00m07s, 928 MiB written, speed 57.0 MiB/s[2K Progress: 73.0%, ETA 00m06s, 960 MiB written, speed 56.9 MiB/s[2K Progress: 75.4%, ETA 00m05s, 992 MiB written, speed 57.0 MiB/s[2K Progress: 77.9%, ETA 00m05s, 1024 MiB written, speed 57.0 MiB/s[2K Progress: 80.3%, ETA 00m04s, 1056 MiB written, speed 57.2 MiB/s[2K Progress: 82.7%, ETA 00m04s, 1088 MiB written, speed 57.1 MiB/s[2K Progress: 85.2%, ETA 00m03s, 1120 MiB written, speed 56.8 MiB/s[2K Progress: 87.6%, ETA 00m03s, 1152 MiB written, speed 56.9 MiB/s[2K Progress: 90.0%, ETA 00m02s, 1184 MiB written, speed 57.0 MiB/s[2K Progress: 92.5%, ETA 00m02s, 1216 MiB written, speed 57.1 MiB/s[2K Progress: 96.1%, ETA 00m01s, 1264 MiB written, speed 57.3 MiB/s[2K Progress: 98.8%, ETA 00m00s, 1299 MiB written, speed 57.4 MiB/s[2K Finished, time 00m22s, 1315 MiB written, speed 58.1 MiB/s New TPM2 token enrolled as key slot 1. done. Wiped slot 0. Device /dev/sda7 is not a valid LUKS device. Begin: Encryption of /dev/sda7 ... var: 1433/341040 files (0.1% non-contiguous), 90171/681574 blocks resize2fs 1.47.0 (5-Feb-2023) Resizing the filesystem on /dev/sda7 to 673382 (4k) blocks. The filesystem on /dev/sda7 is now 673382 (4k) blocks long. [2K Progress: 1.2%, ETA 01m26s, 32 MiB written, speed 30.3 MiB/s[2K Progress: 2.4%, ETA 00m59s, 64 MiB written, speed 43.8 MiB/s[2K Progress: 3.6%, ETA 00m54s, 96 MiB written, speed 47.4 MiB/s[2K Progress: 4.8%, ETA 00m50s, 128 MiB written, speed 49.7 MiB/s[2K Progress: 6.0%, ETA 00m47s, 160 MiB written, speed 52.3 MiB/s[2K Progress: 7.3%, ETA 00m45s, 192 MiB written, speed 53.8 MiB/s[2K Progress: 9.1%, ETA 00m43s, 240 MiB written, speed 55.7 MiB/s[2K Progress: 10.3%, ETA 00m42s, 272 MiB written, speed 56.2 MiB/s[2K Progress: 11.5%, ETA 00m41s, 304 MiB written, speed 56.5 MiB/s[2K Progress: 12.7%, ETA 00m40s, 336 MiB written, speed 57.0 MiB/s[2K Progress: 13.9%, ETA 00m39s, 368 MiB written, speed 57.4 MiB/s[2K Progress: 15.1%, ETA 00m39s, 400 MiB written, speed 57.8 MiB/s[2K Progress: 16.3%, ETA 00m38s, 432 MiB written, speed 58.1 MiB/s[2K Progress: 17.5%, ETA 00m37s, 464 MiB written, speed 58.2 MiB/s[2K Progress: 18.7%, ETA 00m37s, 496 MiB written, speed 57.9 MiB/s[2K Progress: 20.0%, ETA 00m36s, 528 MiB written, speed 58.2 MiB/s[2K Progress: 21.2%, ETA 00m36s, 560 MiB written, speed 58.4 MiB/s[2K Progress: 22.4%, ETA 00m35s, 592 MiB written, speed 58.2 MiB/s[2K Progress: 23.6%, ETA 00m35s, 624 MiB written, speed 57.6 MiB/s[2K Progress: 24.8%, ETA 00m34s, 656 MiB written, speed 57.5 MiB/s[2K Progress: 26.0%, ETA 00m34s, 688 MiB written, speed 57.4 MiB/s[2K Progress: 27.2%, ETA 00m33s, 720 MiB written, speed 57.6 MiB/s[2K Progress: 28.4%, ETA 00m33s, 752 MiB written, speed 57.5 MiB/s[2K Progress: 29.6%, ETA 00m32s, 784 MiB written, speed 57.7 MiB/s[2K Progress: 30.8%, ETA 00m31s, 816 MiB written, speed 57.8 MiB/s[2K Progress: 32.0%, ETA 00m31s, 848 MiB written, speed 57.9 MiB/s[2K Progress: 33.3%, ETA 00m30s, 880 MiB written, speed 57.8 MiB/s[2K Progress: 34.5%, ETA 00m30s, 912 MiB written, speed 57.8 MiB/s[2K Progress: 35.7%, ETA 00m29s, 944 MiB written, speed 57.6 MiB/s[2K Progress: 36.9%, ETA 00m29s, 976 MiB written, speed 57.5 MiB/s[2K Progress: 38.1%, ETA 00m28s, 1008 MiB written, speed 57.2 MiB/s[2K Progress: 39.3%, ETA 00m28s, 1040 MiB written, speed 57.3 MiB/s[2K Progress: 40.5%, ETA 00m27s, 1072 MiB written, speed 57.3 MiB/s[2K Progress: 41.7%, ETA 00m27s, 1104 MiB written, speed 57.4 MiB/s[2K Progress: 42.9%, ETA 00m26s, 1136 MiB written, speed 57.6 MiB/s[2K Progress: 44.1%, ETA 00m25s, 1168 MiB written, speed 57.6 MiB/s[2K Progress: 45.3%, ETA 00m25s, 1200 MiB written, speed 57.7 MiB/s[2K Progress: 46.6%, ETA 00m24s, 1232 MiB written, speed 57.6 MiB/s[2K Progress: 47.8%, ETA 00m24s, 1264 MiB written, speed 57.6 MiB/s[2K Progress: 49.0%, ETA 00m23s, 1296 MiB written, speed 57.6 MiB/s[2K Progress: 50.2%, ETA 00m23s, 1328 MiB written, speed 57.5 MiB/s[2K Progress: 51.4%, ETA 00m22s, 1360 MiB written, speed 57.4 MiB/s[2K Progress: 52.6%, ETA 00m22s, 1392 MiB written, speed 56.9 MiB/s[2K Progress: 53.8%, ETA 00m21s, 1424 MiB written, speed 56.5 MiB/s[2K Progress: 55.0%, ETA 00m21s, 1456 MiB written, speed 56.1 MiB/s[2K Progress: 56.2%, ETA 00m21s, 1488 MiB written, speed 55.9 MiB/s[2K Progress: 56.8%, ETA 00m20s, 1504 MiB written, speed 55.4 MiB/s[2K Progress: 58.0%, ETA 00m20s, 1536 MiB written, speed 55.2 MiB/s[2K Progress: 59.3%, ETA 00m19s, 1568 MiB written, speed 54.9 MiB/s[2K Progress: 60.5%, ETA 00m19s, 1600 MiB written, speed 54.6 MiB/s[2K Progress: 61.7%, ETA 00m18s, 1632 MiB written, speed 54.4 MiB/s[2K Progress: 62.9%, ETA 00m18s, 1664 MiB written, speed 54.4 MiB/s[2K Progress: 64.1%, ETA 00m17s, 1696 MiB written, speed 54.4 MiB/s[2K Progress: 65.3%, ETA 00m17s, 1728 MiB written, speed 54.2 MiB/s[2K Progress: 66.5%, ETA 00m16s, 1760 MiB written, speed 54.1 MiB/s[2K Progress: 67.7%, ETA 00m16s, 1792 MiB written, speed 53.8 MiB/s[2K Progress: 68.9%, ETA 00m15s, 1824 MiB written, speed 53.7 MiB/s[2K Progress: 70.1%, ETA 00m15s, 1856 MiB written, speed 53.5 MiB/s[2K Progress: 71.3%, ETA 00m14s, 1888 MiB written, speed 53.6 MiB/s[2K Progress: 72.6%, ETA 00m13s, 1920 MiB written, speed 53.7 MiB/s[2K Progress: 74.4%, ETA 00m12s, 1968 MiB written, speed 53.9 MiB/s[2K Progress: 75.6%, ETA 00m12s, 2000 MiB written, speed 53.9 MiB/s[2K Progress: 76.8%, ETA 00m11s, 2032 MiB written, speed 53.9 MiB/s[2K Progress: 78.0%, ETA 00m11s, 2064 MiB written, speed 53.8 MiB/s[2K Progress: 79.2%, ETA 00m10s, 2096 MiB written, speed 54.0 MiB/s[2K Progress: 80.4%, ETA 00m09s, 2128 MiB written, speed 54.0 MiB/s[2K Progress: 82.2%, ETA 00m08s, 2176 MiB written, speed 54.2 MiB/s[2K Progress: 83.4%, ETA 00m08s, 2208 MiB written, speed 54.3 MiB/s[2K Progress: 85.2%, ETA 00m07s, 2256 MiB written, speed 54.5 MiB/s[2K Progress: 87.1%, ETA 00m06s, 2304 MiB written, speed 54.6 MiB/s[2K Progress: 88.3%, ETA 00m05s, 2336 MiB written, speed 54.7 MiB/s[2K Progress: 89.5%, ETA 00m05s, 2368 MiB written, speed 54.7 MiB/s[2K Progress: 90.7%, ETA 00m04s, 2400 MiB written, speed 54.8 MiB/s[2K Progress: 91.9%, ETA 00m04s, 2432 MiB written, speed 54.9 MiB/s[2K Progress: 93.1%, ETA 00m03s, 2464 MiB written, speed 54.9 MiB/s[2K Progress: 94.3%, ETA 00m03s, 2496 MiB written, speed 55.0 MiB/s[2K Progress: 95.5%, ETA 00m02s, 2528 MiB written, speed 55.1 MiB/s[2K Progress: 96.7%, ETA 00m01s, 2560 MiB written, speed 55.2 MiB/s[2K Progress: 97.9%, ETA 00m01s, 2592 MiB written, speed 55.2 MiB/s[2K Progress: 99.4%, ETA 00m00s, 2630 MiB written, speed 55.3 MiB/s[2K Finished, time 00m47s, 2646 MiB written, speed 55.7 MiB/s New TPM2 token enrolled as key slot 1. done. Wiped slot 0. Device /dev/sdb is not a valid VERITY device. Device /dev/mapper/encrypted_home is not a valid VERITY device. done. Begin: Running /scripts/local-premount ... done. Warning: fsck not present, so skipping root file system done. Begin: Running /scripts/local-bottom ... var: 1433/341040 files (0.1% non-contiguous), 90171/673382 blocks done. Begin: Running /scripts/init-bottom ... done. Welcome to [1mDebian GNU/Linux 12 (bookworm)[0m! [[0;32m OK [0m] Created slice [0;1;39msystem-getty.slice[0m - Slice /system/getty. [[0;32m OK [0m] Created slice [0;1;39msystem-modpr���lice[0m - Slice /system/modprobe. [[0;32m OK [0m] Created slice [0;1;39msystem-seria���[0m - Slice /system/serial-getty. [[0;32m OK [0m] Started [0;1;39mntpsec-systemd-netif.path[0m. [[0;32m OK [0m] Started [0;1;39msystemd-ask-passwo���quests to Console Directory Watch. [[0;32m OK [0m] Started [0;1;39msystemd-ask-passwo��� Requests to Wall Directory Watch. [[0;32m OK [0m] Set up automount [0;1;39mproc-sys-���rmats File System Automount Point. 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. [[0;32m OK [0m] Listening on [0;1;39msystemd-fsckd���sck to fsckd communication Socket. [[0;32m OK [0m] Listening on [0;1;39msystemd-initc��� initctl Compatibility Named Pipe. [[0;32m OK [0m] Listening on [0;1;39msystemd-journ���socket[0m - Journal Audit Socket. [[0;32m OK [0m] 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. Mounting [0;1;39mdev-hugepages.mount[0m - Huge Pages File System... Mounting [0;1;39mdev-mqueue.mount���POSIX Message Queue File System... Mounting [0;1;39msys-kernel-debug.���[0m - Kernel Debug File System... Mounting [0;1;39msys-kernel-tracin���[0m - Kernel Trace File System... Mounting [0;1;39mtmp.mount[0m - Temporary Directory /tmp... Starting [0;1;39mkmod-static-nodes���ate List of Static Device Nodes... Starting [0;1;39mmodprobe@configfs���m - Load Kernel Module configfs... 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@fuse.ser���e[0m - Load Kernel Module fuse... Starting [0;1;39mmodprobe@loop.ser���e[0m - Load Kernel Module loop... Starting [0;1;39msystemd-fsck-root���ile System Check on Root Device... Starting [0;1;39msystemd-journald.service[0m - Journal Service... Starting [0;1;39msystemd-modules-l���rvice[0m - Load Kernel Modules... Starting [0;1;39msystemd-network-g��� units from Kernel command line... Starting [0;1;39msystemd-udev-trig���[0m - Coldplug All udev Devices... [[0;32m OK [0m] Mounted [0;1;39mdev-hugepages.mount[0m - Huge Pages File System. [[0;32m OK [0m] Mounted [0;1;39mdev-mqueue.mount[���- POSIX Message Queue File System. [[0;32m OK [0m] Mounted [0;1;39msys-kernel-debug.m���nt[0m - Kernel Debug File System. [[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@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. 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-kernel-config.��� Kernel Configuration File System. [[0;32m OK [0m] Finished [0;1;39mmodprobe@fuse.service[0m - Load Kernel Module fuse. Mounting [0;1;39msys-fs-fuse-conne���[0m - FUSE Control File System... [[0;32m OK [0m] Mounted [0;1;39msys-fs-fuse-connec���nt[0m - FUSE Control File System. [[0;32m OK [0m] Started [0;1;39msystemd-journald.service[0m - Journal Service. [[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... [[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-journal-f���ush Journal to Persistent Storage. [[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-udev-trig���e[0m - Coldplug All udev Devices. [ 187.352661] platform regulatory.0: firmware: failed to load regulatory.db (-2) [ 187.355689] firmware_class: See https://wiki.debian.org/Firmware for information about missing firmware [ 187.359209] platform regulatory.0: firmware: failed to load regulatory.db (-2) [[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... [[0;32m OK [0m] Started [0;1;39msystemd-udevd.serv���nager for Device Events and Files. [[0;32m OK [0m] Listening on [0;1;39msystemd-rfkil���l Switch Status /dev/rfkill Watch. [[0;32m OK [0m] Found device [0;1;39mdev-ttyS0.device[0m - /dev/ttyS0. [[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. 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-tmpfiles-���te System Files and Directories... [[0;32m OK [0m] Finished [0;1;39msystemd-modules-l���service[0m - Load Kernel Modules. Mounting [0;1;39mproc-sys-fs-binfm���utable File Formats File System... Starting [0;1;39msystemd-sysctl.se���ce[0m - Apply Kernel Variables... [[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;39msystemd-sysctl.service[0m - Apply Kernel Variables. Starting [0;1;39msystemd-networkd.���ice[0m - Network Configuration... [[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] Finished [0;1;39msystemd-machine-i���it a transient machine-id on disk. [[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] Started [0;1;39msystemd-networkd.service[0m - Network Configuration. [[0;32m OK [0m] Reached target [0;1;39mnetwork.target[0m - Network. Starting [0;1;39msystemd-networkd-���it for Network to be Configured... [[0;32m OK [0m] Started [0;1;39mauditd.service[0m - Security Auditing Service. 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;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;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;39mserial-getty@ttyS0���rvice[0m - Serial Getty on ttyS0. [[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;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;39msshd-regen-keys.s���e[0m - Regenerate sshd host keys. Starting [0;1;39mssh.service[0m - OpenBSD Secure Shell server... [[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. [[0;32m OK [0m] Started [0;1;39mssh.service[0m - OpenBSD Secure Shell server. System Info: auto-login-action timed out after 360 seconds end: 2.2 auto-login-action (duration 00:06:00) [common] case: auto-login-action definition: lava duration: 360.00 extra: ... level: 2.2 namespace: common result: fail boot-image-retry failed: 1 of 1 attempts. 'auto-login-action timed out after 360 seconds' auto-login-action timed out after 360 seconds end: 2 boot-image-retry (duration 00:06:00) [common] case: boot-image-retry definition: lava duration: 360.01 extra: ... level: 2 namespace: common result: fail Cleaning after the job Cleaning up download directory: /var/lib/lava/dispatcher/tmp/257/deployimages-ksrhdczb/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 device-mgmt login: 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 328, in run res = self.wait(connection) ^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/lava_dispatcher/action.py", line 943, in wait return connection.wait(max_end_time) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/lava_dispatcher/shell.py", line 301, in wait return self.raw_connection.expect(self.prompt_str, timeout=timeout) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 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: auto-login-action timed out after 360 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] case: read-feedback definition: lava duration: 1.69 extra: ... level: 4.2 namespace: common result: fail 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 328, in run res = self.wait(connection) ^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/lava_dispatcher/action.py", line 943, in wait return connection.wait(max_end_time) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ File "/usr/lib/python3/dist-packages/lava_dispatcher/shell.py", line 301, in wait return self.raw_connection.expect(self.prompt_str, timeout=timeout) ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ 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: auto-login-action timed out after 360 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/257 JobError: Your job cannot terminate cleanly. case: job definition: lava error_msg: auto-login-action timed out after 360 seconds error_type: Job result: fail