lava-dispatcher, installed at version: 2023.01
start: 0 validate
Start time: 2023-04-16 07:03:52.396285+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: 2.42
case: validate
case_id: 3921955
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/32917/tftp-deploy-vtcnlh04/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 0.96s (4.00MB/s)
end: 1.1.1 http-download (duration 00:00:01) [common]
case: http-download
case_id: 3921957
definition: lava
duration: 0.96
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/32917/tftp-deploy-vtcnlh04/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.25s (6.53MB/s)
end: 1.2.1 http-download (duration 00:00:01) [common]
case: http-download
case_id: 3921957
definition: lava
duration: 1.26
extra: ...
level: 1.2.1
namespace: common
result: pass
end: 1.2 download-retry (duration 00:00:01) [common]
start: 1.3 prepare-tftp-overlay (timeout 00:09:58) [common]
start: 1.3.1 extract-nfsrootfs (timeout 00:09:58) [common]
end: 1.3.1 extract-nfsrootfs (duration 00:00:00) [common]
start: 1.3.2 lava-overlay (timeout 00:09:58) [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:58) [common]
end: 1.3.3 extract-overlay-ramdisk (duration 00:00:01) [common]
start: 1.3.4 extract-modules (timeout 00:09:57) [common]
end: 1.3.4 extract-modules (duration 00:00:00) [common]
start: 1.3.5 apply-overlay-tftp (timeout 00:09:57) [common]
end: 1.3.5 apply-overlay-tftp (duration 00:00:00) [common]
start: 1.3.6 prepare-kernel (timeout 00:09:57) [common]
end: 1.3.6 prepare-kernel (duration 00:00:00) [common]
start: 1.3.7 configure-preseed-file (timeout 00:09:57) [common]
end: 1.3.7 configure-preseed-file (duration 00:00:00) [common]
start: 1.3.8 compress-ramdisk (timeout 00:09:57) [common]
Building ramdisk /var/lib/lava/dispatcher/tmp/32917/extract-overlay-ramdisk-c0c9mp04/ramdisk.cpio containing /var/lib/lava/dispatcher/tmp/32917/extract-overlay-ramdisk-c0c9mp04/ramdisk
>> 24701 blocks
rename /var/lib/lava/dispatcher/tmp/32917/extract-overlay-ramdisk-c0c9mp04/ramdisk.cpio.gz to /var/lib/lava/dispatcher/tmp/32917/tftp-deploy-vtcnlh04/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:55) [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:05) [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}: 32917/tftp-deploy-vtcnlh04/ramdisk/ramdisk.cpio.gz
- {KERNEL}: 32917/tftp-deploy-vtcnlh04/kernel/bzImage
- {LAVA_MAC}: None
- {PRESEED_CONFIG}: None
- {PRESEED_LOCAL}: None
- {RAMDISK}: 32917/tftp-deploy-vtcnlh04/ramdisk/ramdisk.cpio.gz
- {ROOT_PART}: None
- {ROOT}: None
- {SERVER_IP}: 192.168.66.1
- {TEE}: None
Parsed boot commands:
- net_bootp
- clear
- insmod linux
- clear
- insmod tftp
- clear
- linux (tftp,192.168.66.1)/32917/tftp-deploy-vtcnlh04/kernel/bzImage console=ttyS0,115200 ip=dhcp
- clear
- initrd (tftp,192.168.66.1)/32917/tftp-deploy-vtcnlh04/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: 3921963
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:23) [common]
start: 2.6 bootloader-commands (timeout 00:04:29) [common]
Setting prompt string to ['grub>']
bootloader-commands: Wait for prompt ['grub>'] (timeout 00:04:29)
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:29)
[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:25)
[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:24)
[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:24)
[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:24)
[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:24)
[2;7Hc[2;8H[2;8Hl[2;9H[2;9He[2;10H[2;10Ha[2;11H[2;11Hr[2;12H
linux (tftp,192.168.66.1)/32917/tftp-deploy-vtcnlh04/kernel/bzImage console=ttyS0,115200 ip=dhcp
[0;30;40m[2J[1;1H[0;37;40mgrub> linux (tftp,192.168.66.1)/32917/tftp-deploy-vtcnlh04/kernel/bzImage console=ttyS0,115200 ip=dhcp
bootloader-commands: Wait for prompt ['grub>', 'error: missing (.*) symbol.'] (timeout 00:04:24)
[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;20H9[1;21H[1;21H2[1;22H[1;22H.[1;23H[1;23H1[1;24H[1;24H6[1;25H[1;25H8[1;26H[1;26H.[1;27H[1;27H6[1;28H[1;28H6[1;29H[1;29H.[1;30H[1;30H1[1;31H[1;31H)[1;32H[1;32H/[1;33H[1;33H3[1;34H[1;34H2[1;35H[1;35H9[1;36H[1;36H1[1;37H[1;37H7[1;38H[1;38H/[1;39H[1;39Ht[1;40H[1;40Hf[1;41H[1;41Ht[1;42H[1;42Hp[1;43H[1;43H-[1;44H[1;44Hd[1;45H[1;45He[1;46H[1;46Hp[1;47H[1;47Hl[1;48H[1;48Ho[1;49H[1;49Hy[1;50H[1;50H-[1;51H[1;51Hv[1;52H[1;52Ht[1;53H[1;53Hc[1;54H[1;54Hn[1;55H[1;55Hl[1;56H[1;56Hh[1;57H[1;57H0[1;58H[1;58H4[1;59H[1;59H/[1;60H[1;60Hk[1;61H[1;61He[1;62H[1;62Hr[1;63H[1;63Hn[1;64H[1;64He[1;65H[1;65Hl[1;66H[1;66H/[1;67H[1;67Hb[1;68H[1;68Hz[1;69H[1;69HI[1;70H[1;70Hm[1;71H[1;71Ha[1;72H[1;72Hg[1;73H[1;73He[1;74H[1;74H [1;75H[1;75Hc[1;76H[1;76Ho[1;77H[1;77Hn[1;78H[1;78Hs[1;79H[1;79Ho
[1;80H[1;80Hl
[2;1H[2;1He[2;2H[2;2H=[2;3H[2;3Ht[2;4H[2;4Ht[2;5H[2;5Hy[2;6H[2;6HS[2;7H[2;7H0[2;8H[2;8H,[2;9H[2;9H1[2;10H[2;10H1[2;11H[2;11H5[2;12H[2;12H2[2;13H[2;13H0[2;14H[2;14H0[2;15H[2;15H [2;16H[2;16Hi[2;17H[2;17Hp[2;18H[2;18H=[2;19H[2;19Hd[2;20H[2;20Hh[2;21H[2;21Hc[2;22H[2;22Hp[2;23H[2;23H [2;24H
clear
grub> clear
bootloader-commands: Wait for prompt ['grub>', 'error: missing (.*) symbol.'] (timeout 00:04:20)
[3;7Hc[3;8H[3;8Hl[3;9H[3;9He[3;10H[3;10Ha[3;11H[3;11Hr[3;12H
initrd (tftp,192.168.66.1)/32917/tftp-deploy-vtcnlh04/ramdisk/ramdisk.cpio.gz
[0;30;40m[2J[1;1H[0;37;40mgrub> initrd (tftp,192.168.66.1)/32917/tftp-deploy-vtcnlh04/ramdisk/ramdisk.cpio.gz
bootloader-commands: Wait for prompt ['grub>', 'error: missing (.*) symbol.'] (timeout 00:04:19)
[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;21H9[1;22H[1;22H2[1;23H[1;23H.[1;24H[1;24H1[1;25H[1;25H6[1;26H[1;26H8[1;27H[1;27H.[1;28H[1;28H6[1;29H[1;29H6[1;30H[1;30H.[1;31H[1;31H1[1;32H[1;32H)[1;33H[1;33H/[1;34H[1;34H3[1;35H[1;35H2[1;36H[1;36H9[1;37H[1;37H1[1;38H[1;38H7[1;39H[1;39H/[1;40H[1;40Ht[1;41H[1;41Hf[1;42H[1;42Ht[1;43H[1;43Hp[1;44H[1;44H-[1;45H[1;45Hd[1;46H[1;46He[1;47H[1;47Hp[1;48H[1;48Hl[1;49H[1;49Ho[1;50H[1;50Hy[1;51H[1;51H-[1;52H[1;52Hv[1;53H[1;53Ht[1;54H[1;54Hc[1;55H[1;55Hn[1;56H[1;56Hl[1;57H[1;57Hh[1;58H[1;58H0[1;59H[1;59H4[1;60H[1;60H/[1;61H[1;61Hr[1;62H[1;62Ha[1;63H[1;63Hm[1;64H[1;64Hd[1;65H[1;65Hi[1;66H[1;66Hs[1;67H[1;67Hk[1;68H[1;68H/[1;69H[1;69Hr[1;70H[1;70Ha[1;71H[1;71Hm[1;72H[1;72Hd[1;73H[1;73Hi[1;74H[1;74Hs[1;75H[1;75Hk[1;76H[1;76H.[1;77H[1;77Hc[1;78H[1;78Hp[1;79H[1;79Hi
[1;80H[1;80Ho
[2;1H[2;1H.[2;2H[2;2Hg[2;3H[2;3Hz[2;4H
clear
grub> clear
bootloader-commands: Wait for prompt ['grub>', 'error: missing (.*) symbol.'] (timeout 00:04:17)
[3;7Hc[3;8H[3;8Hl[3;9H[3;9He[3;10H[3;10Ha[3;11H[3;11Hr[3;12H
boot
[0;30;40m[2J[1;1H[0;37;40mgrub> boot
end: 2.6 bootloader-commands (duration 00:00:12) [common]
start: 2.7 auto-login-action (timeout 00:04:17) [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
start: 2.7.1 login-action (timeout 00:04:14) [common]
The string '/ #' 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 ['-\\[ cut here \\]', 'Unhandled fault', 'BUG: KCSAN:', 'BUG: KASAN:', 'BUG: KFENCE:', 'Oops(?: -|:)', 'WARNING:', '(kernel BUG at|BUG:)', 'invalid opcode:', 'Kernel panic - not syncing']
Using line separator: #'\n'#
No login prompt set.
Parsing kernel messages
['-\\[ cut here \\]', 'Unhandled fault', 'BUG: KCSAN:', 'BUG: KASAN:', 'BUG: KFENCE:', 'Oops(?: -|:)', 'WARNING:', '(kernel BUG at|BUG:)', 'invalid opcode:', 'Kernel panic - not syncing', 'root@debian:~#', '/ #', 'Login timed out', 'Login incorrect']
[login-action] Waiting for messages, (timeout 00:04:14)
[ 0.000000] Linux version 5.0.0-rc5-next-20190211+ (clabbe@build2-bionic-1804) (gcc version 7.3.0 (Ubuntu 7.3.0-27ubuntu1~18.04)) #10 SMP Tue Feb 12 10:30:24 UTC 2019
[ 0.000000] Command line: BOOT_IMAGE=(tftp,192.168.66.1)/32917/tftp-deploy-vtcnlh04/kernel/bzImage console=ttyS0,115200 ip=dhcp
[ 0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
[ 0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
[ 0.000000] x86/fpu: Supporting XSAVE feature 0x008: 'MPX bounds registers'
[ 0.000000] x86/fpu: Supporting XSAVE feature 0x010: 'MPX CSR'
[ 0.000000] x86/fpu: xstate_offset[3]: 576, xstate_sizes[3]: 64
[ 0.000000] x86/fpu: xstate_offset[4]: 640, xstate_sizes[4]: 64
[ 0.000000] x86/fpu: Enabled xstate features 0x1b, context size is 704 bytes, using 'compacted' format.
[ 0.000000] BIOS-provided physical RAM map:
[ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000003efff] usable
[ 0.000000] BIOS-e820: [mem 0x000000000003f000-0x000000000003ffff] reserved
[ 0.000000] BIOS-e820: [mem 0x0000000000040000-0x000000000009dfff] usable
[ 0.000000] BIOS-e820: [mem 0x000000000009e000-0x00000000000fffff] reserved
[ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000000fffffff] usable
[ 0.000000] BIOS-e820: [mem 0x0000000010000000-0x0000000012150fff] reserved
[ 0.000000] BIOS-e820: [mem 0x0000000012151000-0x0000000077b0afff] usable
[ 0.000000] BIOS-e820: [mem 0x0000000077b0b000-0x0000000079c28fff] reserved
[ 0.000000] BIOS-e820: [mem 0x0000000079c29000-0x0000000079c41fff] ACPI data
[ 0.000000] BIOS-e820: [mem 0x0000000079c42000-0x0000000079ca1fff] ACPI NVS
[ 0.000000] BIOS-e820: [mem 0x0000000079ca2000-0x000000007a03efff] reserved
[ 0.000000] BIOS-e820: [mem 0x000000007a03f000-0x000000007a08afff] type 20
[ 0.000000] BIOS-e820: [mem 0x000000007a08b000-0x000000007a3f8fff] usable
[ 0.000000] BIOS-e820: [mem 0x000000007a3f9000-0x000000007a3f9fff] ACPI NVS
[ 0.000000] BIOS-e820: [mem 0x000000007a3fa000-0x000000007a423fff] reserved
[ 0.000000] BIOS-e820: [mem 0x000000007a424000-0x000000007a964fff] usable
[ 0.000000] BIOS-e820: [mem 0x000000007a965000-0x000000007a966fff] reserved
[ 0.000000] BIOS-e820: [mem 0x000000007a967000-0x000000007affffff] usable
[ 0.000000] BIOS-e820: [mem 0x000000007b000000-0x000000007fffffff] reserved
[ 0.000000] BIOS-e820: [mem 0x00000000d0000000-0x00000000d0ffffff] reserved
[ 0.000000] BIOS-e820: [mem 0x00000000e0000000-0x00000000efffffff] reserved
[ 0.000000] BIOS-e820: [mem 0x00000000fe042000-0x00000000fe044fff] reserved
[ 0.000000] BIOS-e820: [mem 0x00000000fe900000-0x00000000fe902fff] reserved
[ 0.000000] BIOS-e820: [mem 0x00000000fec00000-0x00000000fec00fff] reserved
[ 0.000000] BIOS-e820: [mem 0x00000000fed01000-0x00000000fed01fff] reserved
[ 0.000000] BIOS-e820: [mem 0x00000000fee00000-0x00000000fee00fff] reserved
[ 0.000000] BIOS-e820: [mem 0x00000000ff000000-0x00000000ffffffff] reserved
[ 0.000000] BIOS-e820: [mem 0x0000000100000000-0x000000017fffffff] usable
[ 0.000000] NX (Execute Disable) protection: active
[ 0.000000] efi: EFI v2.50 by American Megatrends
[ 0.000000] efi: ACPI=0x79c32000 ACPI 2.0=0x79c32000 SMBIOS=0x79ef7000 SMBIOS 3.0=0x79ef6000
[ 0.000000] SMBIOS 3.0.0 present.
[ 0.000000] DMI: AAEON UP-APL01/UP-APL01, BIOS UPA1AM52 08/04/2020
[ 0.000000] tsc: Detected 1094.400 MHz processor
[ 0.000058] last_pfn = 0x180000 max_arch_pfn = 0x400000000
[ 0.000797] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT
[ 0.000956] last_pfn = 0x7b000 max_arch_pfn = 0x400000000
[ 0.009604] check: Scanning 1 areas for low memory corruption
[ 0.009621] Using GB pages for direct mapping
[ 0.010204] Secure boot disabled
[ 0.010208] RAMDISK: [mem 0x3782d000-0x37c0dfff]
[ 0.010220] ACPI: Early table checksum verification disabled
[ 0.010229] ACPI: RSDP 0x0000000079C32000 000024 (v02 ALASKA)
[ 0.010238] ACPI: XSDT 0x0000000079C320D0 0000FC (v01 ALASKA A M I 01072009 AMI 00010013)
[ 0.010251] ACPI: FACP 0x0000000079C3A140 000114 (v06 ALASKA A M I 01072009 AMI 00010013)
[ 0.010263] ACPI: DSDT 0x0000000079C32260 007ED3 (v02 ALASKA A M I 01072009 INTL 20120913)
[ 0.010272] ACPI: FACS 0x0000000079CA1080 000040
[ 0.010278] ACPI: FPDT 0x0000000079C3A260 000044 (v01 ALASKA A M I 01072009 AMI 00010013)
[ 0.010285] ACPI: FIDT 0x0000000079C3A2B0 00009C (v01 ALASKA A M I 01072009 AMI 00010013)
[ 0.010292] ACPI: MCFG 0x0000000079C3A350 00003C (v01 ALASKA A M I 01072009 MSFT 00000097)
[ 0.010299] ACPI: HROT 0x0000000079C3A390 0000DB (v01 INTEL EDK2 00000005 INTL 0100000D)
[ 0.010307] ACPI: DBG2 0x0000000079C3A470 000072 (v00 INTEL EDK2 00000003 BRXT 0100000D)
[ 0.010314] ACPI: DBGP 0x0000000079C3A4F0 000034 (v01 INTEL EDK2 00000003 BRXT 0100000D)
[ 0.010321] ACPI: HPET 0x0000000079C3A530 000038 (v01 INTEL EDK2 00000003 BRXT 0100000D)
[ 0.010328] ACPI: LPIT 0x0000000079C3A570 00005C (v01 INTEL EDK2 00000003 BRXT 0100000D)
[ 0.010335] ACPI: APIC 0x0000000079C3A5D0 000084 (v03 INTEL EDK2 00000003 BRXT 0100000D)
[ 0.010342] ACPI: NPKT 0x0000000079C3A660 000065 (v01 INTEL EDK2 00000003 BRXT 0100000D)
[ 0.010349] ACPI: PRAM 0x0000000079C3A6D0 000030 (v01 INTEL EDK2 00000003 BRXT 0100000D)
[ 0.010356] ACPI: WSMT 0x0000000079C3A700 000028 (v01 INTEL EDK2 00000003 BRXT 0100000D)
[ 0.010363] ACPI: SSDT 0x0000000079C3A730 00125C (v01 AAEON PINCTRL 00000001 INTL 20120913)
[ 0.010370] ACPI: SSDT 0x0000000079C3B990 002532 (v01 MSFT RHPROXY 00000001 INTL 20120913)
[ 0.010378] ACPI: SSDT 0x0000000079C3DED0 00002C (v01 Intel_ Platform 00001000 INTL 20120913)
[ 0.010385] ACPI: SSDT 0x0000000079C3DF00 000400 (v02 PmRef Cpu0Ist 00003000 INTL 20120913)
[ 0.010392] ACPI: SSDT 0x0000000079C3E300 00072B (v02 CpuRef CpuSsdt 00003000 INTL 20120913)
[ 0.010400] ACPI: SSDT 0x0000000079C3EA30 00032D (v02 PmRef Cpu0Tst 00003000 INTL 20120913)
[ 0.010407] ACPI: SSDT 0x0000000079C3ED60 00017C (v02 PmRef ApTst 00003000 INTL 20120913)
[ 0.010414] ACPI: SSDT 0x0000000079C3EEE0 002760 (v02 SaSsdt SaSsdt 00003000 INTL 20120913)
[ 0.010422] ACPI: UEFI 0x0000000079C41640 000042 (v01 ALASKA A M I 00000000 00000000)
[ 0.010429] ACPI: TPM2 0x0000000079C41690 000034 (v03 Tpm2Tabl 00000001 AMI 00000000)
[ 0.010436] ACPI: DMAR 0x0000000079C416D0 0000B0 (v01 INTEL EDK2 00000003 BRXT 0100000D)
[ 0.010443] ACPI: WDAT 0x0000000079C41780 000104 (v01 00000000 00000000)
[ 0.010450] ACPI: NHLT 0x0000000079C41890 00050D (v00 INTEL EDK2 00000002 01000013)
[ 0.010457] ACPI: BGRT 0x0000000079C41DA0 000038 (v01 ALASKA A M I 01072009 AMI 00010013)
[ 0.010744] No NUMA configuration found
[ 0.010747] Faking a node at [mem 0x0000000000000000-0x000000017fffffff]
[ 0.010758] NODE_DATA(0) allocated [mem 0x17fffc000-0x17fffffff]
[ 0.010799] Zone ranges:
[ 0.010802] DMA [mem 0x0000000000001000-0x0000000000ffffff]
[ 0.010806] DMA32 [mem 0x0000000001000000-0x00000000ffffffff]
[ 0.010810] Normal [mem 0x0000000100000000-0x000000017fffffff]
[ 0.010813] Movable zone start for each node
[ 0.010815] Early memory node ranges
[ 0.010818] node 0: [mem 0x0000000000001000-0x000000000003efff]
[ 0.010821] node 0: [mem 0x0000000000040000-0x000000000009dfff]
[ 0.010824] node 0: [mem 0x0000000000100000-0x000000000fffffff]
[ 0.010827] node 0: [mem 0x0000000012151000-0x0000000077b0afff]
[ 0.010829] node 0: [mem 0x000000007a08b000-0x000000007a3f8fff]
[ 0.010832] node 0: [mem 0x000000007a424000-0x000000007a964fff]
[ 0.010834] node 0: [mem 0x000000007a967000-0x000000007affffff]
[ 0.010837] node 0: [mem 0x0000000100000000-0x000000017fffffff]
[ 0.011697] Zeroed struct page in unavailable ranges: 38754 pages
[ 0.011701] Initmem setup node 0 [mem 0x0000000000001000-0x000000017fffffff]
[ 0.054247] Reserving Intel graphics memory at [mem 0x7c000000-0x7fffffff]
[ 0.054614] ACPI: PM-Timer IO Port: 0x408
[ 0.054632] ACPI: LAPIC_NMI (acpi_id[0x01] high level lint[0x1])
[ 0.054636] ACPI: LAPIC_NMI (acpi_id[0x02] high level lint[0x1])
[ 0.054638] ACPI: LAPIC_NMI (acpi_id[0x03] high level lint[0x1])
[ 0.054641] ACPI: LAPIC_NMI (acpi_id[0x04] high level lint[0x1])
[ 0.054677] IOAPIC[0]: apic_id 1, version 32, address 0xfec00000, GSI 0-119
[ 0.054683] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[ 0.054687] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 low level)
[ 0.054697] Using ACPI (MADT) for SMP configuration information
[ 0.054701] ACPI: HPET id: 0x8086a701 base: 0xfed00000
[ 0.054714] smpboot: Allowing 4 CPUs, 0 hotplug CPUs
[ 0.054785] [mem 0x80000000-0xcfffffff] available for PCI devices
[ 0.054795] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
[ 0.433494] random: get_random_bytes called from start_kernel+0x8c/0x482 with crng_init=0
[ 0.433521] setup_percpu: NR_CPUS:4 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1
[ 0.434205] percpu: Embedded 43 pages/cpu @(____ptrval____) s137304 r8192 d30632 u524288
[ 0.434289] Built 1 zonelists, mobility grouping on. Total pages: 994019
[ 0.434293] Policy zone: Normal
[ 0.434299] Kernel command line: BOOT_IMAGE=(tftp,192.168.66.1)/32917/tftp-deploy-vtcnlh04/kernel/bzImage console=ttyS0,115200 ip=dhcp
[ 0.479205] Memory: 3767404K/4039288K available (14340K kernel code, 1296K rwdata, 3156K rodata, 1228K init, 1496K bss, 271884K reserved, 0K cma-reserved)
[ 0.479359] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[ 0.479528] rcu: Hierarchical RCU implementation.
[ 0.479531] rcu: RCU event tracing is enabled.
[ 0.479536] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.
[ 0.479992] NR_IRQS: 4352, nr_irqs: 1024, preallocated irqs: 16
[ 0.480403] Console: colour dummy device 80x25
[ 0.531206] printk: console [ttyS0] enabled
[ 0.531478] ACPI: Core revision 20190108
[ 0.532688] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 99544814920 ns
[ 0.533290] APIC: Switch to symmetric I/O mode setup
[ 0.538165] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[ 0.543240] clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0xfc66f4fc7c, max_idle_ns: 440795224246 ns
[ 0.543855] Calibrating delay loop (skipped), value calculated using timer frequency.. 2188.80 BogoMIPS (lpj=1094400)
[ 0.544836] pid_max: default: 32768 minimum: 301
[ 0.553911] LSM: Security Framework initializing
[ 0.554201] SELinux: Initializing.
[ 0.554866] *** VALIDATE SELinux ***
[ 0.556785] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes)
[ 0.558026] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes)
[ 0.558491] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes)
[ 0.558864] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes)
[ 0.559603] *** VALIDATE proc ***
[ 0.559912] *** VALIDATE cgroup1 ***
[ 0.560142] *** VALIDATE cgroup2 ***
[ 0.560431] mce: CPU supports 7 MCE banks
[ 0.560704] mce: CPU0: Thermal monitoring enabled (TM1)
[ 0.560914] Last level iTLB entries: 4KB 48, 2MB 0, 4MB 0
[ 0.561219] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[ 0.561569] Spectre V2 : Mitigation: Full generic retpoline
[ 0.561834] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch
[ 0.562293] Spectre V2 : Enabling Restricted Speculation for firmware calls
[ 0.562851] Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier
[ 0.563343] Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl and seccomp
[ 0.564359] Freeing SMP alternatives memory: 40K
[ 0.565019] smpboot: CPU0: Intel(R) Pentium(R) CPU N4200 @ 1.10GHz (family: 0x6, model: 0x5c, stepping: 0xa)
[ 0.565831] Performance Events: PEBS fmt3+, Goldmont events, 32-deep LBR, full-width counters, Intel PMU driver.
[ 0.565843] ... version: 4
[ 0.566095] ... bit width: 48
[ 0.566356] ... generic registers: 4
[ 0.566610] ... value mask: 0000ffffffffffff
[ 0.566836] ... max period: 00007fffffffffff
[ 0.567166] ... fixed-purpose events: 3
[ 0.567417] ... event mask: 000000070000000f
[ 0.567853] rcu: Hierarchical SRCU implementation.
[ 0.568615] smp: Bringing up secondary CPUs ...
[ 0.569074] x86: Booting SMP configuration:
[ 0.569340] .... node #0, CPUs: #1 #2 #3
[ 0.571607] smp: Brought up 1 node, 4 CPUs
[ 0.572102] smpboot: Max logical packages: 1
[ 0.572376] smpboot: Total of 4 processors activated (8755.20 BogoMIPS)
[ 0.576250] devtmpfs: initialized
[ 0.577109] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
[ 0.577109] kworker/u8:0 (31) used greatest stack depth: 14128 bytes left
[ 0.577858] futex hash table entries: 1024 (order: 4, 65536 bytes)
[ 0.579110] NET: Registered protocol family 16
[ 0.579626] audit: initializing netlink subsys (disabled)
[ 0.579858] audit: type=2000 audit(1681658585.046:1): state=initialized audit_enabled=0 res=1
[ 0.580412] cpuidle: using governor menu
[ 0.580916] ACPI FADT declares the system doesn't support PCIe ASPM, so disable it
[ 0.580916] ACPI: bus type PCI registered
[ 0.581856] PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xe0000000-0xefffffff] (base 0xe0000000)
[ 0.581856] PCI: MMCONFIG at [mem 0xe0000000-0xefffffff] reserved in E820
[ 0.582293] PCI: Using configuration type 1 for base access
[ 0.593946] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
[ 0.594838] ACPI: Added _OSI(Module Device)
[ 0.594838] ACPI: Added _OSI(Processor Device)
[ 0.594838] ACPI: Added _OSI(3.0 _SCP Extensions)
[ 0.594890] ACPI: Added _OSI(Processor Aggregator Device)
[ 0.595229] ACPI: Added _OSI(Linux-Dell-Video)
[ 0.595510] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio)
[ 0.595838] ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics)
[ 0.611286] ACPI: 9 ACPI AML tables successfully acquired and loaded
[ 0.617797] ACPI: Dynamic OEM Table Load:
[ 0.618848] ACPI: SSDT 0xFFFF907BDFE74800 000102 (v02 PmRef Cpu0Cst 00003001 INTL 20120913)
[ 0.620354] ACPI: Dynamic OEM Table Load:
[ 0.620617] ACPI: SSDT 0xFFFF907BDFE74A00 00015F (v02 PmRef ApIst 00003000 INTL 20120913)
[ 0.621447] ACPI: Dynamic OEM Table Load:
[ 0.621719] ACPI: SSDT 0xFFFF907C0542C6C0 00008D (v02 PmRef ApCst 00003000 INTL 20120913)
[ 0.624451] ACPI: Interpreter enabled
[ 0.624700] ACPI: (supports S0 S5)
[ 0.624838] ACPI: Using IOAPIC for interrupt routing
[ 0.625832] PCI: Using host bridge windows from ACPI; if necessary, use \"pci=nocrs\" and report a bug
[ 0.626490] ACPI: Enabled 9 GPEs in block 00 to 7F
[ 0.648013] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[ 0.648411] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI]
[ 0.648935] acpi PNP0A08:00: _OSC failed (AE_ERROR); disabling ASPM
[ 0.649842] PCI host bridge to bus 0000:00
[ 0.650842] pci_bus 0000:00: root bus resource [io 0x0070-0x0077]
[ 0.651233] pci_bus 0000:00: root bus resource [io 0x0000-0x006f window]
[ 0.651657] pci_bus 0000:00: root bus resource [io 0x0078-0x0cf7 window]
[ 0.651838] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window]
[ 0.652264] pci_bus 0000:00: root bus resource [mem 0x7c000001-0x7fffffff window]
[ 0.652727] pci_bus 0000:00: root bus resource [mem 0x7b800001-0x7bffffff window]
[ 0.652837] pci_bus 0000:00: root bus resource [mem 0x80000000-0xcfffffff window]
[ 0.653301] pci_bus 0000:00: root bus resource [mem 0xe0000000-0xefffffff window]
[ 0.653768] pci_bus 0000:00: root bus resource [bus 00-ff]
[ 0.675413] pci 0000:00:13.0: PCI bridge to [bus 01]
[ 0.675979] pci 0000:02:00.0: Upstream bridge's Max Payload Size set to 128 (was 256, max 256)
[ 0.676522] pci 0000:02:00.0: Max Payload Size set to 128 (was 128, max 128)
[ 0.677119] pci 0000:00:13.1: PCI bridge to [bus 02]
[ 0.677676] pci 0000:03:00.0: Upstream bridge's Max Payload Size set to 128 (was 256, max 256)
[ 0.677845] pci 0000:03:00.0: Max Payload Size set to 128 (was 128, max 128)
[ 0.678834] pci 0000:00:13.2: PCI bridge to [bus 03]
[ 0.678922] pci 0000:00:13.3: PCI bridge to [bus 04]
[ 0.679833] pci 0000:00:14.0: PCI bridge to [bus 05]
[ 0.679872] pci 0000:00:14.1: PCI bridge to [bus 06]
[ 0.680955] ACPI: PCI Interrupt Link [LNKA] (IRQs 3 4 5 6 7 10 11 12 14 *15), disabled.
[ 0.681598] ACPI: PCI Interrupt Link [LNKB] (IRQs 3 4 5 6 7 10 11 12 14 *15), disabled.
[ 0.681975] ACPI: PCI Interrupt Link [LNKC] (IRQs 3 4 5 6 7 10 11 12 14 *15), disabled.
[ 0.682609] ACPI: PCI Interrupt Link [LNKD] (IRQs 3 4 5 6 7 10 11 12 14 *15), disabled.
[ 0.683975] ACPI: PCI Interrupt Link [LNKE] (IRQs 3 4 5 6 10 11 12 14 *15), disabled.
[ 0.684611] ACPI: PCI Interrupt Link [LNKF] (IRQs 3 4 5 6 10 11 12 14 *15), disabled.
[ 0.684974] ACPI: PCI Interrupt Link [LNKG] (IRQs 3 4 5 6 10 11 12 14 *15), disabled.
[ 0.685601] ACPI: PCI Interrupt Link [LNKH] (IRQs 3 4 5 6 10 11 12 14 *15), disabled.
[ 0.687962] pci 0000:00:02.0: vgaarb: setting as boot VGA device
[ 0.688245] pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none
[ 0.688864] pci 0000:00:02.0: vgaarb: bridge control possible
[ 0.689224] vgaarb: loaded
[ 0.689983] SCSI subsystem initialized
[ 0.689992] ACPI: bus type USB registered
[ 0.690897] usbcore: registered new interface driver usbfs
[ 0.691274] usbcore: registered new interface driver hub
[ 0.691659] usbcore: registered new device driver usb
[ 0.691897] pps_core: LinuxPPS API ver. 1 registered
[ 0.692215] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <[email protected]>
[ 0.692791] PTP clock support registered
[ 0.692930] EDAC MC: Ver: 3.0.0
[ 0.693262] Registered efivars operations
[ 0.731241] Advanced Linux Sound Architecture Driver Initialized.
[ 0.731856] PCI: Using ACPI for IRQ routing
[ 0.761269] NetLabel: Initializing
[ 0.761486] NetLabel: domain hash size = 128
[ 0.761757] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO
[ 0.761871] NetLabel: unlabeled traffic allowed by default
[ 0.762268] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0, 0, 0, 0, 0, 0
[ 0.762849] hpet0: 8 comparators, 64-bit 19.200000 MHz counter
[ 0.764887] clocksource: Switched to clocksource tsc-early
[ 1.056205] VFS: Disk quotas dquot_6.6.0
[ 1.056499] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[ 1.057012] *** VALIDATE hugetlbfs ***
[ 1.057332] pnp: PnP ACPI init
[ 1.058414] system 00:00: [io 0x0680-0x069f] has been reserved
[ 1.058789] system 00:00: [io 0x0400-0x047f] has been reserved
[ 1.059175] system 00:00: [io 0x0500-0x05fe] has been reserved
[ 1.059704] system 00:01: [mem 0xe0000000-0xefffffff] has been reserved
[ 1.060133] system 00:01: [mem 0xfea00000-0xfeafffff] has been reserved
[ 1.060545] system 00:01: [mem 0xfed01000-0xfed01fff] has been reserved
[ 1.060965] system 00:01: [mem 0xfed03000-0xfed03fff] has been reserved
[ 1.061373] system 00:01: [mem 0xfed06000-0xfed06fff] has been reserved
[ 1.061788] system 00:01: [mem 0xfed08000-0xfed09fff] has been reserved
[ 1.062208] system 00:01: [mem 0xfed80000-0xfedbffff] has been reserved
[ 1.062621] system 00:01: [mem 0xfed1c000-0xfed1cfff] has been reserved
[ 1.063051] system 00:01: [mem 0xfee00000-0xfeefffff] could not be reserved
[ 1.064376] pnp: PnP ACPI: found 3 devices
[ 1.073663] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[ 1.074255] pci 0000:00:13.0: PCI bridge to [bus 01]
[ 1.074583] pci 0000:00:13.1: PCI bridge to [bus 02]
[ 1.074906] pci 0000:00:13.1: bridge window [io 0xe000-0xefff]
[ 1.075296] pci 0000:00:13.1: bridge window [mem 0x91400000-0x914fffff]
[ 1.075721] pci 0000:00:13.2: PCI bridge to [bus 03]
[ 1.076046] pci 0000:00:13.2: bridge window [io 0xd000-0xdfff]
[ 1.076435] pci 0000:00:13.2: bridge window [mem 0x91300000-0x913fffff]
[ 1.076877] pci 0000:00:13.3: PCI bridge to [bus 04]
[ 1.077196] pci 0000:00:14.0: PCI bridge to [bus 05]
[ 1.077519] pci 0000:00:14.1: PCI bridge to [bus 06]
[ 1.078126] NET: Registered protocol family 2
[ 1.078775] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes)
[ 1.079325] TCP established hash table entries: 32768 (order: 6, 262144 bytes)
[ 1.079944] TCP bind hash table entries: 32768 (order: 7, 524288 bytes)
[ 1.080507] TCP: Hash tables configured (established 32768 bind 32768)
[ 1.081041] UDP hash table entries: 2048 (order: 4, 65536 bytes)
[ 1.081449] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes)
[ 1.081988] NET: Registered protocol family 1
[ 1.082612] RPC: Registered named UNIX socket transport module.
[ 1.083009] RPC: Registered udp transport module.
[ 1.083308] RPC: Registered tcp transport module.
[ 1.083603] RPC: Registered tcp NFSv4.1 backchannel transport module.
[ 1.084285] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[ 1.085655] Trying to unpack rootfs image as initramfs...
[ 1.271530] Freeing initrd memory: 3972K
[ 1.271883] DMAR: Host address width 39
[ 1.272133] DMAR: DRHD base: 0x000000fed64000 flags: 0x0
[ 1.272503] DMAR: dmar0: reg_base_addr fed64000 ver 1:0 cap 1c0000c40660462 ecap 7e3ff0505e
[ 1.273042] DMAR: DRHD base: 0x000000fed65000 flags: 0x1
[ 1.273394] DMAR: dmar1: reg_base_addr fed65000 ver 1:0 cap d2008c40660462 ecap f050da
[ 1.273897] DMAR: RMRR base: 0x00000079bc4000 end: 0x00000079be3fff
[ 1.274292] DMAR: RMRR base: 0x0000007b800000 end: 0x0000007fffffff
[ 1.274725] PCI-DMA: Using software bounce buffering for IO (SWIOTLB)
[ 1.275141] software IO TLB: mapped [mem 0x6df19000-0x71f19000] (64MB)
[ 1.275752] RAPL PMU: API unit is 2^-32 Joules, 4 fixed counters, 655360 ms ovfl timer
[ 1.276276] RAPL PMU: hw unit of domain pp0-core 2^-14 Joules
[ 1.276635] RAPL PMU: hw unit of domain package 2^-14 Joules
[ 1.277029] RAPL PMU: hw unit of domain dram 2^-14 Joules
[ 1.277372] RAPL PMU: hw unit of domain pp1-gpu 2^-14 Joules
[ 1.277903] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0xfc66f4fc7c, max_idle_ns: 440795224246 ns
[ 1.278547] clocksource: Switched to clocksource tsc
[ 1.280144] check: Scanning for low memory corruption every 60 seconds
[ 1.281425] Initialise system trusted keyrings
[ 1.281852] workingset: timestamp_bits=56 max_order=20 bucket_order=0
[ 1.288397] NFS: Registering the id_resolver key type
[ 1.288747] Key type id_resolver registered
[ 1.289038] Key type id_legacy registered
[ 1.319971] Key type asymmetric registered
[ 1.320245] Asymmetric key parser 'x509' registered
[ 1.320587] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)
[ 1.321070] io scheduler mq-deadline registered
[ 1.321357] io scheduler kyber registered
[ 1.323887] input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0
[ 1.324420] ACPI: Power Button [PWRB]
[ 1.324750] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input1
[ 1.325236] ACPI: Power Button [PWRF]
[ 1.329690] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[ 1.331340] Non-volatile memory driver v1.3
[ 1.333191] [drm] Replacing VGA console driver
[ 1.333661] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[ 1.334103] [drm] Driver supports precise vblank timestamp query.
[ 1.334623] i915 0000:00:02.0: vgaarb: changed VGA decodes: olddecodes=io+mem,decodes=io+mem:owns=io+mem
[ 1.335448] i915 0000:00:02.0: Direct firmware load for i915/bxt_dmc_ver1_07.bin failed with error -2
[ 1.336057] i915 0000:00:02.0: Failed to load DMC firmware i915/bxt_dmc_ver1_07.bin. Disabling runtime power management.
[ 1.336744] i915 0000:00:02.0: DMC firmware homepage: https://git.kernel.org/pub/scm/linux/kernel/git/firmware/linux-firmware.git/tree/i915
[ 2.450585] [drm] failed to retrieve link info, disabling eDP
[ 2.460410] [drm] Initialized i915 1.6.0 20190207 for 0000:00:02.0 on minor 0
[ 2.462831] ACPI: Video Device [GFX0] (multi-head: yes rom: no post: no)
[ 2.463952] input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/LNXVIDEO:00/input/input2
[ 2.469413] brd: module loaded
[ 2.473527] loop: module loaded
[ 2.473911] lpc_ich 0000:00:1f.0: I/O space for ACPI uninitialized
[ 2.479724] printk: console [ttyS0] disabled
[ 2.480060] dw-apb-uart.8: ttyS0 at MMIO 0x91526000 (irq = 4, base_baud = 115200) is a 16550A
[ 4.966244] printk: console [ttyS0] enabled
[ 4.966313] fbcon: inteldrmfb (fb0) is primary device
[ 4.967303] dw-apb-uart.9: ttyS1 at MMIO 0x91524000 (irq = 5, base_baud = 115200) is a 16550A
[ 4.969451] ahci 0000:00:12.0: AHCI 0001.0301 32 slots 2 ports 6 Gbps 0x3 impl SATA mode
[ 4.969459] ahci 0000:00:12.0: flags: 64bit ncq sntf pm clo only pmp pio slum part deso sadm sds apst
[ 4.970142] scsi host0: ahci
[ 4.970636] scsi host1: ahci
[ 4.970783] ata1: SATA max UDMA/133 abar m2048@0x91538000 port 0x91538100 irq 121
[ 4.970789] ata2: SATA max UDMA/133 abar m2048@0x91538000 port 0x91538180 irq 121
[ 4.971337] e100: Intel(R) PRO/100 Network Driver, 3.5.24-k2-NAPI
[ 4.971339] e100: Copyright(c) 1999-2006 Intel Corporation
[ 4.971386] e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k8-NAPI
[ 4.971387] e1000: Copyright (c) 1999-2006 Intel Corporation.
[ 4.971426] e1000e: Intel(R) PRO/1000 Network Driver - 3.2.6-k
[ 4.971427] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.
[ 4.971475] sky2: driver version 1.30
[ 4.976952] libphy: r8169: probed
[ 4.977480] r8169 0000:02:00.0 eth0: RTL8168g/8111g, 00:07:32:84:58:40, XID 4c0, IRQ 122
[ 4.977484] r8169 0000:02:00.0 eth0: jumbo features [frames: 9200 bytes, tx checksumming: ko]
[ 4.983402] libphy: r8169: probed
[ 4.983949] r8169 0000:03:00.0 eth1: RTL8168g/8111g, 00:07:32:84:58:41, XID 4c0, IRQ 123
[ 4.983954] r8169 0000:03:00.0 eth1: jumbo features [frames: 9200 bytes, tx checksumming: ko]
[ 4.984207] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[ 4.984209] ehci-pci: EHCI PCI platform driver
[ 4.984244] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[ 4.984250] ohci-pci: OHCI PCI platform driver
[ 4.984285] uhci_hcd: USB Universal Host Controller Interface driver
[ 4.984740] xhci_hcd 0000:00:15.0: xHCI Host Controller
[ 4.984973] xhci_hcd 0000:00:15.0: new USB bus registered, assigned bus number 1
[ 4.986095] xhci_hcd 0000:00:15.0: hcc params 0x200077c1 hci version 0x100 quirks 0x0000000081109810
[ 4.986715] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.00
[ 4.986720] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 4.986724] usb usb1: Product: xHCI Host Controller
[ 4.986727] usb usb1: Manufacturer: Linux 5.0.0-rc5-next-20190211+ xhci-hcd
[ 4.986730] usb usb1: SerialNumber: 0000:00:15.0
[ 4.987055] hub 1-0:1.0: USB hub found
[ 4.987120] hub 1-0:1.0: 8 ports detected
[ 4.988391] xhci_hcd 0000:00:15.0: xHCI Host Controller
[ 4.988480] xhci_hcd 0000:00:15.0: new USB bus registered, assigned bus number 2
[ 4.988489] xhci_hcd 0000:00:15.0: Host supports USB 3.0 SuperSpeed
[ 4.988559] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.00
[ 4.988563] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 4.988566] usb usb2: Product: xHCI Host Controller
[ 4.988570] usb usb2: Manufacturer: Linux 5.0.0-rc5-next-20190211+ xhci-hcd
[ 4.988573] usb usb2: SerialNumber: 0000:00:15.0
[ 4.988782] hub 2-0:1.0: USB hub found
[ 4.988804] hub 2-0:1.0: 7 ports detected
[ 4.989971] usbcore: registered new interface driver usblp
[ 4.990014] usbcore: registered new interface driver usb-storage
[ 4.990081] i8042: PNP: No PS/2 controller found.
[ 4.990481] rtc_cmos 00:02: RTC can wake from S4
[ 4.990886] rtc rtc0: invalid alarm value: 2023-04-16T15:33:65
[ 4.991040] rtc_cmos 00:02: registered as rtc0
[ 4.991074] rtc_cmos 00:02: alarms up to one month, y3k, 242 bytes nvram, hpet irqs
[ 4.991110] i2c /dev entries driver
[ 4.992017] i801_smbus 0000:00:1f.1: can't derive routing for PCI INT A
[ 4.992021] i801_smbus 0000:00:1f.1: PCI INT A: not connected
[ 4.992052] i801_smbus 0000:00:1f.1: SPD Write Disable is set
[ 4.992074] i801_smbus 0000:00:1f.1: SMBus using polling
[ 4.992601] device-mapper: ioctl: 4.39.0-ioctl (2018-04-03) initialised: [email protected]
[ 4.992617] intel_pstate: Intel P-state driver initializing
[ 4.993439] EFI Variables Facility v0.08 2004-May-17
[ 5.030694] Console: switching to colour frame buffer device 240x67
[ 5.047990] pstore: Registered efi as persistent store backend
[ 5.048014] hidraw: raw HID events driver (C) Jiri Kosina
[ 5.048235] usbcore: registered new interface driver usbhid
[ 5.048236] usbhid: USB HID core driver
[ 5.062931] snd_hda_intel 0000:00:0e.0: bound 0000:00:02.0 (ops 0xffffffffa24990c0)
[ 5.072426] i915 0000:00:02.0: fb0: inteldrmfb frame buffer device
[ 5.106683] Initializing XFRM netlink socket
[ 5.106890] NET: Registered protocol family 10
[ 5.148860] random: fast init done
[ 5.223384] hdaudio hdaudioC0D2: Unable to bind the codec
[ 5.228646] Segment Routing with IPv6
[ 5.282941] ata1: SATA link down (SStatus 4 SControl 300)
[ 5.325376] sit: IPv6, IPv4 and MPLS over IPv4 tunneling driver
[ 5.334660] ata2: SATA link down (SStatus 4 SControl 300)
[ 5.342847] NET: Registered protocol family 17
[ 5.348950] usb 1-4: new high-speed USB device number 2 using xhci_hcd
[ 5.355942] Key type dns_resolver registered
[ 5.502152] usb 1-4: New USB device found, idVendor=0bda, idProduct=2838, bcdDevice= 1.00
[ 5.578313] usb 1-4: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[ 5.586291] usb 1-4: Product: RTL2838UHIDIR
[ 5.590964] usb 1-4: Manufacturer: Realtek
[ 5.595540] usb 1-4: SerialNumber: 00000001
[ 5.600633] microcode: sig=0x506ca, pf=0x1, revision=0x1e
[ 5.606764] microcode: Microcode Update Driver: v2.2.
[ 5.606784] sched_clock: Marking stable (5552346099, 53486516)->(5620233876, -14401261)
[ 5.622223] registered taskstats version 1
[ 5.626806] Loading compiled-in X.509 certificates
[ 5.632277] pstore: Using crash dump compression: deflate
[ 5.639195] printk: console [netcon0] enabled
[ 5.644074] netconsole: network logging started
[ 5.649436] r8169 0000:02:00.0: Direct firmware load for rtl_nic/rtl8168g-2.fw failed with error -2
[ 5.659564] r8169 0000:02:00.0 eth0: unable to load firmware patch rtl_nic/rtl8168g-2.fw (-2)
[ 5.669259] Generic Realtek PHY r8169-200:00: attached PHY driver [Generic Realtek PHY] (mii_bus:phy_addr=r8169-200:00, irq=IGNORE)
[ 5.763051] r8169 0000:02:00.0 eth0: Link is Down
[ 5.768379] r8169 0000:03:00.0: Direct firmware load for rtl_nic/rtl8168g-2.fw failed with error -2
[ 5.778524] r8169 0000:03:00.0 eth1: unable to load firmware patch rtl_nic/rtl8168g-2.fw (-2)
[ 5.788215] Generic Realtek PHY r8169-300:00: attached PHY driver [Generic Realtek PHY] (mii_bus:phy_addr=r8169-300:00, irq=IGNORE)
[ 5.881814] r8169 0000:03:00.0 eth1: Link is Down
[ 8.552173] r8169 0000:03:00.0 eth1: Link is Up - 1Gbps/Full - flow control rx/tx
[ 8.560782] IPv6: ADDRCONF(NETDEV_CHANGE): eth1: link becomes ready
[ 8.567860] Sending DHCP requests ., OK
[ 8.576452] IP-Config: Got DHCP answer from 192.168.66.1, my address is 192.168.66.206
[ 8.585310] IP-Config: Complete:
[ 8.588915] device=eth1, hwaddr=00:07:32:84:58:41, ipaddr=192.168.66.206, mask=255.255.255.0, gw=192.168.66.1
[ 8.600487] host=192.168.66.206, domain=, nis-domain=(none)
[ 8.607200] bootserver=192.168.66.1, rootserver=192.168.66.1, rootpath=
[ 8.607200] nameserver0=10.1.1.1
[ 8.619916] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[ 8.633078] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[ 8.640441] ALSA device list:
[ 8.643756] No soundcards found.
[ 8.647605] platform regulatory.0: Direct firmware load for regulatory.db failed with error -2
[ 8.657247] cfg80211: failed to load regulatory.db
[ 8.662871] ttyS0 - failed to request DMA
[ 8.667964] Freeing unused kernel image memory: 1228K
[ 8.679166] Write protecting the kernel read-only data: 20480k
[ 8.686753] Freeing unused kernel image memory: 2008K
[ 8.692894] Freeing unused kernel image memory: 940K
[ 8.698486] Run /init as init process
Starting logging: OK
Populating /dev using udev: [ 8.720250] udevd[1504]: starting version 3.1.5
[ 8.726667] random: udevd: uninitialized urandom read (16 bytes read)
[ 8.733938] random: udevd: uninitialized urandom read (16 bytes read)
[ 8.741173] random: udevd: uninitialized urandom read (16 bytes read)
[ 8.790229] r8169 0000:02:00.0 enp2s0: renamed from eth0
[ 8.805105] udevd[1510]: Error changing net interface name eth1 to enp3s0: Device or resource busy
[ 8.815967] udevd[1510]: could not rename interface '3' from 'eth1' to 'enp3s0': Device or resource busy
[ 8.838601] udevadm (1524) used greatest stack depth: 14024 bytes left
done
Initializing random number generator... done.
Starting network...
ip: RTNETLINK answers: File exists
[ 8.859780] ip (1537) used greatest stack depth: 13928 bytes left
Starting dropbear sshd: OK
/bin/sh: can't access tty; job control turned off
Matched prompt #11: / #
case: kernel-messages
case_id: 3921966
definition: lava
duration: 6.37
extra: ...
level: 2.7.1
namespace: common
result: pass
Setting prompt string to ['root@debian:~#', '/ #']
end: 2.7.1 login-action (duration 00:00:06) [common]
case: login-action
case_id: 3921967
definition: lava
duration: 6.38
extra: ...
level: 2.7.1
namespace: common
result: pass
end: 2.7 auto-login-action (duration 00:00:09) [common]
end: 2 grub-main-action (duration 00:00:52) [common]
start: 3 finalize (timeout 00:05:00) [common]
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: 3921968
definition: lava
duration: 3.61
extra: ...
level: 3.1
namespace: common
result: pass
start: 3.2 read-feedback (timeout 00:04:56) [common]
Finalising connection for namespace 'common'
Disconnecting from shell: Finalise
/ #
end: 3.2 read-feedback (duration 00:00:01) [common]
end: 3 finalize (duration 00:00:05) [common]
Cleaning after the job
Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32917/tftp-deploy-vtcnlh04/ramdisk
Cleaning up download directory: /var/lib/lava/dispatcher/tmp/32917/tftp-deploy-vtcnlh04/kernel
Override tmp directory removed at /var/lib/lava/dispatcher/tmp/32917
Root tmp directory removed at /var/lib/lava/dispatcher/tmp/32917
Job finished correctly
case: job
case_id: 3921969
definition: lava
result: pass
Please read the triage guidelines for help on debugging failures in the test job, test definitions or in individual test cases.
This section helps test writers to check for actions with a duration which is much shorter than the requested timeout. Reducing these timeouts will allow failures to be identified more quickly.
The graph only shows actions that are longer than 1 second. The full list is anyway available in the table.