lava-dispatcher, installed at version: 2023.01 start: 0 validate Start time: 2023-11-03 19:43:57.755800+00:00 (UTC) Validating that https://github.com/montjoie/lava-healthchecks-binary/blob/master/images/rootfs/buildroot/x86/rootfs.cpio.gz?raw=true exists Validating that https://github.com/montjoie/lava-healthchecks-binary/blob/master/next/master/next-20190211/x86_64/bzImage?raw=true exists validate duration: 3.05 case: validate
case_id: 3933976
definition: lava
result: pass
start: 1 tftp-deploy (timeout 00:10:00) [common] start: 1.1 download-retry (timeout 00:10:00) [common] start: 1.1.1 http-download (timeout 00:10:00) [common] Not decompressing ramdisk as can be used compressed. downloading https://github.com/montjoie/lava-healthchecks-binary/blob/master/images/rootfs/buildroot/x86/rootfs.cpio.gz?raw=true saving as /var/lib/lava/dispatcher/tmp/33259/tftp-deploy-ob5o6g2v/ramdisk/rootfs.cpio.gz total size: 4012068 (3MB) progress 0% (0MB) progress 5% (0MB) progress 10% (0MB) progress 15% (0MB) progress 20% (0MB) progress 25% (0MB) progress 30% (1MB) progress 35% (1MB) progress 40% (1MB) progress 45% (1MB) progress 50% (1MB) progress 55% (2MB) progress 60% (2MB) progress 65% (2MB) progress 70% (2MB) progress 75% (2MB) progress 80% (3MB) progress 85% (3MB) progress 90% (3MB) progress 95% (3MB) progress 100% (3MB) 3MB downloaded in 1.08s (3.53MB/s) end: 1.1.1 http-download (duration 00:00:01) [common] case: http-download
case_id: 3934007
definition: lava
duration: 1.09
extra: ...
level: 1.1.1
namespace: common
result: pass
end: 1.1 download-retry (duration 00:00:01) [common] start: 1.2 download-retry (timeout 00:09:59) [common] start: 1.2.1 http-download (timeout 00:09:59) [common] downloading https://github.com/montjoie/lava-healthchecks-binary/blob/master/next/master/next-20190211/x86_64/bzImage?raw=true saving as /var/lib/lava/dispatcher/tmp/33259/tftp-deploy-ob5o6g2v/kernel/bzImage total size: 8573824 (8MB) No compression specified progress 0% (0MB) progress 5% (0MB) progress 10% (0MB) progress 15% (1MB) progress 20% (1MB) progress 25% (2MB) progress 30% (2MB) progress 35% (2MB) progress 40% (3MB) progress 45% (3MB) progress 50% (4MB) progress 55% (4MB) progress 60% (4MB) progress 65% (5MB) progress 70% (5MB) progress 75% (6MB) progress 80% (6MB) progress 85% (6MB) progress 90% (7MB) progress 95% (7MB) progress 100% (8MB) 8MB downloaded in 1.94s (4.21MB/s) end: 1.2.1 http-download (duration 00:00:02) [common] case: http-download
case_id: 3934007
definition: lava
duration: 1.94
extra: ...
level: 1.2.1
namespace: common
result: pass
end: 1.2 download-retry (duration 00:00:02) [common] start: 1.3 prepare-tftp-overlay (timeout 00:09:57) [common] start: 1.3.1 extract-nfsrootfs (timeout 00:09:57) [common] end: 1.3.1 extract-nfsrootfs (duration 00:00:00) [common] start: 1.3.2 lava-overlay (timeout 00:09:57) [common] [common] skipped lava-overlay - no test action. end: 1.3.2 lava-overlay (duration 00:00:00) [common] start: 1.3.3 extract-overlay-ramdisk (timeout 00:09:57) [common] end: 1.3.3 extract-overlay-ramdisk (duration 00:00:01) [common] start: 1.3.4 extract-modules (timeout 00:09:56) [common] end: 1.3.4 extract-modules (duration 00:00:00) [common] start: 1.3.5 apply-overlay-tftp (timeout 00:09:56) [common] end: 1.3.5 apply-overlay-tftp (duration 00:00:00) [common] start: 1.3.6 prepare-kernel (timeout 00:09:56) [common] end: 1.3.6 prepare-kernel (duration 00:00:00) [common] start: 1.3.7 configure-preseed-file (timeout 00:09:56) [common] end: 1.3.7 configure-preseed-file (duration 00:00:00) [common] start: 1.3.8 compress-ramdisk (timeout 00:09:56) [common] Building ramdisk /var/lib/lava/dispatcher/tmp/33259/extract-overlay-ramdisk-ai8sot8e/ramdisk.cpio containing /var/lib/lava/dispatcher/tmp/33259/extract-overlay-ramdisk-ai8sot8e/ramdisk >> 24701 blocks rename /var/lib/lava/dispatcher/tmp/33259/extract-overlay-ramdisk-ai8sot8e/ramdisk.cpio.gz to /var/lib/lava/dispatcher/tmp/33259/tftp-deploy-ob5o6g2v/ramdisk/ramdisk.cpio.gz end: 1.3.8 compress-ramdisk (duration 00:00:02) [common] end: 1.3 prepare-tftp-overlay (duration 00:00:03) [common] start: 1.4 lxc-create-udev-rule-action (timeout 00:09:54) [common] No LXC device requested end: 1.4 lxc-create-udev-rule-action (duration 00:00:00) [common] Checking files for TFTP limit of 4294967296 bytes. end: 1 tftp-deploy (duration 00:00:06) [common] start: 2 grub-main-action (timeout 00:05:00) [common] start: 2.1 bootloader-from-media (timeout 00:05:00) [common] end: 2.1 bootloader-from-media (duration 00:00:00) [common] start: 2.2 bootloader-overlay (timeout 00:05:00) [common] substitutions: - {DTB}: None - {INITRD}: 33259/tftp-deploy-ob5o6g2v/ramdisk/ramdisk.cpio.gz - {KERNEL}: 33259/tftp-deploy-ob5o6g2v/kernel/bzImage - {LAVA_MAC}: None - {PRESEED_CONFIG}: None - {PRESEED_LOCAL}: None - {RAMDISK}: 33259/tftp-deploy-ob5o6g2v/ramdisk/ramdisk.cpio.gz - {ROOT_PART}: None - {ROOT}: None - {SERVER_IP}: 172.21.0.2 - {TEE}: None Parsed boot commands: - net_bootp - clear - insmod linux - clear - insmod tftp - clear - linux (tftp,172.21.0.2)/33259/tftp-deploy-ob5o6g2v/kernel/bzImage console=ttyS0,115200 ip=dhcp - clear - initrd (tftp,172.21.0.2)/33259/tftp-deploy-ob5o6g2v/ramdisk/ramdisk.cpio.gz - clear - boot end: 2.2 bootloader-overlay (duration 00:00:00) [common] start: 2.3 connect-device (timeout 00:05:00) [common] [common] connect-device Connecting to device using 'telnet 127.0.0.1 63002' Setting prompt string to ['lava-test: # '] end: 2.3 connect-device (duration 00:00:03) [common] start: 2.4 reset-device (timeout 00:04:57) [common] start: 2.4.1 pdu-reboot (timeout 00:04:57) [common] Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'reset' '5' >> Success >> Success Returned 0 in 4 seconds end: 2.4.1 pdu-reboot (duration 00:00:05) [common] case: pdu-reboot
case_id: 3934012
definition: lava
duration: 4.80
extra: ...
level: 2.4.1
namespace: common
result: pass
end: 2.4 reset-device (duration 00:00:05) [common] start: 2.5 bootloader-interrupt (timeout 00:04:52) [common] Setting prompt string to ['for a command-line'] bootloader-interrupt: Wait for prompt ['for a command-line'] (timeout 00:05:00) Trying 127.0.0.1... Connected to 127.0.0.1. Escape character is '^]'. [2J[1;1H[2J[1;1H[1;1H[0;37;40m[2J[1;1H[1;37;40m[2J[1;1H[1;1H >>Checking Media Presence...... >>No Media Present......[2J[1;1H[1;1H >>Checking Media Presence...... >>Media Present...... >>Start PXE over IPv4. Station IP address is 192.168.66.206 Server IP address is 192.168.66.1 NBP filename is /boot/grub/x86_64-efi/core.efi NBP filesize is 249856 Bytes[2J[1;1H[1;1H >>Checking Media Presence...... >>Media Present...... Downloading NBP file... Succeed to download NBP file. [0;30;47mWelcome to GRUB! [0;37;40m[0;30;40m[2J[1;1H[0;37;40m[2;25HGNU GRUB version 2.06-3~deb11u5 [4;2H����������������������������������������������������������������������������Ŀ[5;2H�[5;79H�[6;2H�[6;79H�[7;2H�[7;79H�[8;2H�[8;79H�[9;2H�[9;79H�[10;2H�[10;79H�[11;2H�[11;79H�[12;2H�[12;79H�[13;2H�[13;79H�[14;2H�[14;79H�[15;2H�[15;79H�[16;2H�[16;79H�[17;2H�[17;79H�[18;2H������������������������������������������������������������������������������[19;2H[20;2H Use the  and  keys to select which entry is highlighted. Press enter to boot the selected OS, `e' to edit the commands end: 2.5 bootloader-interrupt (duration 00:00:21) [common] start: 2.6 bootloader-commands (timeout 00:04:31) [common] Setting prompt string to ['grub>'] bootloader-commands: Wait for prompt ['grub>'] (timeout 00:04:31) before booting or `c' for a command-line. [5;80H [0;30;47m[5;3H*fake menu [0;37;40m[5;78H[6;3H [6;78H[7;3H c [7;78H[8;3H [8;78H[9;3H [9;78H[10;3H [10;78H[11;3H [11;78H[12;3H [12;78H[13;3H [13;78H[14;3H [14;78H[15;3H [15;78H[16;3H [16;78H[17;3H [17;78H[17;80H [5;78H[23;1H [24;1H [5;78H[0;30;40m[2J[1;1H[0;37;40m[0;30;40m[2J[1;1H[0;37;40m[2;25HGNU GRUB version 2.06-3~deb11u5 Minimal BASH-like line editing is supported. For the first word, TAB lists possible command completions. Anywhere else TAB lists possible device or file completions. ESC at any time exits. Setting prompt string to ['grub>', 'error: missing (.*) symbol.'] net_bootp grub> net_bootp bootloader-commands: Wait for prompt ['grub>', 'error: missing (.*) symbol.'] (timeout 00:04:30) [9;7Hn[9;8H[9;8He[9;9H[9;9Ht[9;10H[9;10H_[9;11H[9;11Hb[9;12H[9;12Ho[9;13H[9;13Ho[9;14H[9;14Ht[9;15H[9;15Hp[9;16H error: couldn't send network packet. error: couldn't autoconfigure efinet0. clear grub> clear bootloader-commands: Wait for prompt ['grub>', 'error: missing (.*) symbol.'] (timeout 00:04:26) [12;7Hc[12;8H[12;8Hl[12;9H[12;9He[12;10H[12;10Ha[12;11H[12;11Hr[12;12H insmod linux [0;30;40m[2J[1;1H[0;37;40mgrub> insmod linux bootloader-commands: Wait for prompt ['grub>', 'error: missing (.*) symbol.'] (timeout 00:04:26) [1;7Hi[1;8H[1;8Hn[1;9H[1;9Hs[1;10H[1;10Hm[1;11H[1;11Ho[1;12H[1;12Hd[1;13H[1;13H [1;14H[1;14Hl[1;15H[1;15Hi[1;16H[1;16Hn[1;17H[1;17Hu[1;18H[1;18Hx[1;19H clear grub> clear bootloader-commands: Wait for prompt ['grub>', 'error: missing (.*) symbol.'] (timeout 00:04:26) [2;7Hc[2;8H[2;8Hl[2;9H[2;9He[2;10H[2;10Ha[2;11H[2;11Hr[2;12H insmod tftp [0;30;40m[2J[1;1H[0;37;40mgrub> insmod tftp bootloader-commands: Wait for prompt ['grub>', 'error: missing (.*) symbol.'] (timeout 00:04:25) [1;7Hi[1;8H[1;8Hn[1;9H[1;9Hs[1;10H[1;10Hm[1;11H[1;11Ho[1;12H[1;12Hd[1;13H[1;13H [1;14H[1;14Ht[1;15H[1;15Hf[1;16H[1;16Ht[1;17H[1;17Hp[1;18H clear grub> clear bootloader-commands: Wait for prompt ['grub>', 'error: missing (.*) symbol.'] (timeout 00:04:25) [2;7Hc[2;8H[2;8Hl[2;9H[2;9He[2;10H[2;10Ha[2;11H[2;11Hr[2;12H linux (tftp,172.21.0.2)/33259/tftp-deploy-ob5o6g2v/kernel/bzImage console=ttyS0,115200 ip=dhcp [0;30;40m[2J[1;1H[0;37;40mgrub> linux (tftp,172.21.0.2)/33259/tftp-deploy-ob5o6g2v/kernel/bzImage console=ttyS0,115200 ip=dhcp bootloader-commands: Wait for prompt ['grub>', 'error: missing (.*) symbol.'] (timeout 00:04:25) [1;7Hl[1;8H[1;8Hi[1;9H[1;9Hn[1;10H[1;10Hu[1;11H[1;11Hx[1;12H[1;12H [1;13H[1;13H([1;14H[1;14Ht[1;15H[1;15Hf[1;16H[1;16Ht[1;17H[1;17Hp[1;18H[1;18H,[1;19H[1;19H1[1;20H[1;20H7[1;21H[1;21H2[1;22H[1;22H.[1;23H[1;23H2[1;24H[1;24H1[1;25H[1;25H.[1;26H[1;26H0[1;27H[1;27H.[1;28H[1;28H2[1;29H[1;29H)[1;30H[1;30H/[1;31H[1;31H3[1;32H[1;32H3[1;33H[1;33H2[1;34H[1;34H5[1;35H[1;35H9[1;36H[1;36H/[1;37H[1;37Ht[1;38H[1;38Hf[1;39H[1;39Ht[1;40H[1;40Hp[1;41H[1;41H-[1;42H[1;42Hd[1;43H[1;43He[1;44H[1;44Hp[1;45H[1;45Hl[1;46H[1;46Ho[1;47H[1;47Hy[1;48H[1;48H-[1;49H[1;49Ho[1;50H[1;50Hb[1;51H[1;51H5[1;52H[1;52Ho[1;53H[1;53H6[1;54H[1;54Hg[1;55H[1;55H2[1;56H[1;56Hv[1;57H[1;57H/[1;58H[1;58Hk[1;59H[1;59He[1;60H[1;60Hr[1;61H[1;61Hn[1;62H[1;62He[1;63H[1;63Hl[1;64H[1;64H/[1;65H[1;65Hb[1;66H[1;66Hz[1;67H[1;67HI[1;68H[1;68Hm[1;69H[1;69Ha[1;70H[1;70Hg[1;71H[1;71He[1;72H[1;72H [1;73H[1;73Hc[1;74H[1;74Ho[1;75H[1;75Hn[1;76H[1;76Hs[1;77H[1;77Ho[1;78H[1;78Hl[1;79H[1;79He [1;80H[1;80H= [2;1H[2;1Ht[2;2H[2;2Ht[2;3H[2;3Hy[2;4H[2;4HS[2;5H[2;5H0[2;6H[2;6H,[2;7H[2;7H1[2;8H[2;8H1[2;9H[2;9H5[2;10H[2;10H2[2;11H[2;11H0[2;12H[2;12H0[2;13H[2;13H [2;14H[2;14Hi[2;15H[2;15Hp[2;16H[2;16H=[2;17H[2;17Hd[2;18H[2;18Hh[2;19H[2;19Hc[2;20H[2;20Hp[2;21H[2;21H [2;22H error: time out opening `/33259/tftp-deploy-ob5o6g2v/kernel/bzImage'. clear grub> clear bootloader-commands: Wait for prompt ['grub>', 'error: missing (.*) symbol.'] (timeout 00:03:53) [4;7Hc[4;8H[4;8Hl[4;9H[4;9He[4;10H[4;10Ha[4;11H[4;11Hr[4;12H initrd (tftp,172.21.0.2)/33259/tftp-deploy-ob5o6g2v/ramdisk/ramdisk.cpio.gz [0;30;40m[2J[1;1H[0;37;40mgrub> initrd (tftp,172.21.0.2)/33259/tftp-deploy-ob5o6g2v/ramdisk/ramdisk.cpio.gz bootloader-commands: Wait for prompt ['grub>', 'error: missing (.*) symbol.'] (timeout 00:03:53) [1;7Hi[1;8H[1;8Hn[1;9H[1;9Hi[1;10H[1;10Ht[1;11H[1;11Hr[1;12H[1;12Hd[1;13H[1;13H [1;14H[1;14H([1;15H[1;15Ht[1;16H[1;16Hf[1;17H[1;17Ht[1;18H[1;18Hp[1;19H[1;19H,[1;20H[1;20H1[1;21H[1;21H7[1;22H[1;22H2[1;23H[1;23H.[1;24H[1;24H2[1;25H[1;25H1[1;26H[1;26H.[1;27H[1;27H0[1;28H[1;28H.[1;29H[1;29H2[1;30H[1;30H)[1;31H[1;31H/[1;32H[1;32H3[1;33H[1;33H3[1;34H[1;34H2[1;35H[1;35H5[1;36H[1;36H9[1;37H[1;37H/[1;38H[1;38Ht[1;39H[1;39Hf[1;40H[1;40Ht[1;41H[1;41Hp[1;42H[1;42H-[1;43H[1;43Hd[1;44H[1;44He[1;45H[1;45Hp[1;46H[1;46Hl[1;47H[1;47Ho[1;48H[1;48Hy[1;49H[1;49H-[1;50H[1;50Ho[1;51H[1;51Hb[1;52H[1;52H5[1;53H[1;53Ho[1;54H[1;54H6[1;55H[1;55Hg[1;56H[1;56H2[1;57H[1;57Hv[1;58H[1;58H/[1;59H[1;59Hr[1;60H[1;60Ha[1;61H[1;61Hm[1;62H[1;62Hd[1;63H[1;63Hi[1;64H[1;64Hs[1;65H[1;65Hk[1;66H[1;66H/[1;67H[1;67Hr[1;68H[1;68Ha[1;69H[1;69Hm[1;70H[1;70Hd[1;71H[1;71Hi[1;72H[1;72Hs[1;73H[1;73Hk[1;74H[1;74H.[1;75H[1;75Hc[1;76H[1;76Hp[1;77H[1;77Hi[1;78H[1;78Ho[1;79H[1;79H. [1;80H[1;80Hg [2;1H[2;1Hz[2;2H error: you need to load the kernel first. clear grub> clear bootloader-commands: Wait for prompt ['grub>', 'error: missing (.*) symbol.'] (timeout 00:03:53) [4;7Hc[4;8H[4;8Hl[4;9H[4;9He[4;10H[4;10Ha[4;11H[4;11Hr[4;12H boot [0;30;40m[2J[1;1H[0;37;40mgrub> boot end: 2.6 bootloader-commands (duration 00:00:38) [common] start: 2.7 auto-login-action (timeout 00:03:53) [common] Setting prompt string to ['Linux version [0-9]'] Setting prompt string to ['Linux version [0-9]', 'error: missing (.*) symbol.'] auto-login-action: Wait for prompt ['Linux version [0-9]', 'error: missing (.*) symbol.'] (timeout 00:05:00) [1;7Hb[1;8H[1;8Ho[1;9H[1;9Ho[1;10H[1;10Ht[1;11H error: you need to load the kernel first. auto-login-action timed out after 233 seconds end: 2.7 auto-login-action (duration 00:03:53) [common] case: auto-login-action
case_id: 3934152
definition: lava
duration: 233.00
extra: ...
level: 2.7
namespace: common
result: fail
grub-main-action failed: 1 of 1 attempts. 'auto-login-action timed out after 233 seconds' auto-login-action timed out after 233 seconds end: 2 grub-main-action (duration 00:05:00) [common] case: grub-main-action
case_id: 3934153
definition: lava
duration: 300.13
extra: ...
level: 2
namespace: common
result: fail
Cleaning after the job Cleaning up download directory: /var/lib/lava/dispatcher/tmp/33259/tftp-deploy-ob5o6g2v/ramdisk Cleaning up download directory: /var/lib/lava/dispatcher/tmp/33259/tftp-deploy-ob5o6g2v/kernel start: 3.1 power-off (timeout 00:00:30) [common] Calling: 'nice' '/usr/local/bin/acme-cli' '-s' '192.168.66.2' 'switch_off' '5' >> Success Returned 0 in 3 seconds end: 3.1 power-off (duration 00:00:04) [common] case: power-off
case_id: 3934154
definition: lava
duration: 3.55
extra: ...
level: 3.1
namespace: common
result: pass
start: 3.2 read-feedback (timeout 00:04:56) [common] Listened to connection for namespace 'common' for up to 1s Finalising connection for namespace 'common' Disconnecting from shell: Finalise grub> end: 3.2 read-feedback (duration 00:00:01) [common] Override tmp directory removed at /var/lib/lava/dispatcher/tmp/33259 Root tmp directory removed at /var/lib/lava/dispatcher/tmp/33259 JobError: Your job cannot terminate cleanly. case: job
case_id: 3934155
definition: lava
error_msg: auto-login-action timed out after 233 seconds
error_type: Job
result: fail