Runtime stats

SUCCESS

  • Machine name: Kaby Lake
  • MAC Address: 54-b2-03-8b-5c-c1
  • Pickup 2020-05-19 12:48:04 UTC
  • Ran for 1m 50s
  • Finished 2020-05-19 12:49:54 UTC

Boot Config

Ipxe config (ipxe-54-b2-03-8b-5c-c1.cfg):
#!ipxe
sleep 10
kernel tftp://${next-server}/sotest/54-b2-03-8b-5c-c1/bender || goto fail
initrd tftp://${next-server}/sotest/54-b2-03-8b-5c-c1/hypervisor serial novga iommu || goto fail
initrd tftp://${next-server}/sotest/54-b2-03-8b-5c-c1/nova-x86.image.elf-x86_64-unknown-genode || goto fail
boot
:fail
echo kernel/initrd load fail
echo cannot boot
:end

Raw log output

Bender: Hello World.

Need 019aa000 bytes to relocate modules.

Relocating to 7e656000: 

Copying 26751648 bytes...

Copying 154216 bytes...


NOVA Microhypervisor v8-0000000 (x86_64): Jan  1 1970 00:00:01 [gcc 9.2.0] [MBI]

[ 0] TSC:2700000 kHz BUS:0 kHz DL
[ 0] CORE:0:0:0 6:8e:9:7 [b4] Intel(R) Core(TM) i5-7300U CPU @ 2.60GHz
[ 2] CORE:0:0:1 6:8e:9:7 [b4] Intel(R) Core(TM) i5-7300U CPU @ 2.60GHz
[ 1] CORE:0:1:0 6:8e:9:7 [b4] Intel(R) Core(TM) i5-7300U CPU @ 2.60GHz
[ 3] CORE:0:1:1 6:8e:9:7 [b4] Intel(R) Core(TM) i5-7300U CPU @ 2.60GHz
[ 0] disabling super pages for DMAR
Hypervisor features VMX[0m
Hypervisor reports 4x1 CPUs[0m
CPU ID (genode->kernel:package:core:thread) remapping[0m
 remap (0->0:0:0:0) boot cpu[0m
 remap (1->1:0:1:0) [0m
 remap (2->2:0:0:1) [0m
 remap (3->3:0:1:1) [0m
Hypervisor info page contains 11 memory descriptors:[0m
core     image  [0000000000100000,0000000001a82000)[0m
binaries region [00000000002a8000,0000000001a82000) free for reuse[0m
detected physical memory: 0x0000000000000000 - size: 0x000000000009c400[0m
use      physical memory: 0x0000000000000000 - size: 0x000000000009c000[0m
detected physical memory: 0x0000000000100000 - size: 0x000000003ff00000[0m
use      physical memory: 0x0000000000100000 - size: 0x000000003ff00000[0m
detected physical memory: 0x0000000040400000 - size: 0x0000000042baa000[0m
use      physical memory: 0x0000000040400000 - size: 0x0000000042baa000[0m
detected physical memory: 0x0000000082fac000 - size: 0x000000000714d000[0m
use      physical memory: 0x0000000082fac000 - size: 0x000000000714d000[0m
detected physical memory: 0x000000008afff000 - size: 0x0000000000001000[0m
use      physical memory: 0x000000008afff000 - size: 0x0000000000001000[0m
detected physical memory: 0x0000000100000000 - size: 0x000000016e000000[0m
use      physical memory: 0x0000000100000000 - size: 0x000000016e000000[0m
reserved memory: 0x7e67c000 - size: 0x19832a0 type=-2[0m
reserved memory: 0x1000000 - size: 0x800000 type=-1[0m
reserved memory: 0x1800000 - size: 0xc00000 type=-1[0m
reserved memory: 0x8a59d028 - size: 0x0 type=-3[0m
reserved memory: 0x8a59d0c0 - size: 0x0 type=-4[0m
ROM modules:
 ROM: [000000007fe6c000,000000007fffc2d8) cached_fs_rom
 ROM: [000000007fffd000,000000007fffe7c9) config
 ROM: [0000000000009000,000000000000d000) core_log
 ROM: [000000007f5c9000,000000007fa2b2e0) init
 ROM: [000000007f5c7000,000000007f5c8bec) init.xsd
 ROM: [000000007e93a000,000000007ea3caf0) ld.lib.so
 ROM: [000000007fddd000,000000007fe6b1a8) libvfs.so
 ROM: [0000000000007000,0000000000008000) platform_info
 ROM: [000000007fb85000,000000007fb8504a) propagated-build-inputs
 ROM: [000000007fa2c000,000000007fb84408) rtc_drv
 ROM: [000000007ea3d000,000000007ea5bb18) sotest-harness
 ROM: [000000007ea5c000,000000007f5c6800) store.tar
 ROM: [000000007e825000,000000007e9398c0) timer_drv
 ROM: [000000007fb86000,000000007fddc8c8) vfs
[0m
20M kernel memory[0m
[0m
Genode 20.02[0m
8014 MiB RAM and 63253 caps assigned to init[0m
[init] [34mWarning: prio levels is not power of two, priorities are disabled[0m[0m
SOTEST VERSION 1 BEGIN 3[0m
[init -> store_fs] tar archive 'store.tar' local at 0x16c000, size is 11972608[0m[0m
[init -> harness -> signal] --- Signalling test ---[0m[0m
[init -> harness -> signal] [0m
[init -> harness -> signal] TEST 1: reliable delivery if the sender is faster than the handlers[0m
[init -> harness -> signal] [0m[0m
[init -> harness -> signal] deactivate sender[0m[0m
[init -> harness -> signal] sender submitted a total of 25 signals[0m[0m
[init -> harness -> signal] handler received a total of 25 signals[0m[0m
[init -> harness -> signal] [0m
[init -> harness -> signal] TEST 1 finished[0m
[init -> harness -> signal] [0m[0m
[init -> harness -> signal] [0m
[init -> harness -> signal] TEST 2: throughput when submitting/handling as fast as possible[0m
[init -> harness -> signal] [0m[0m
[init -> harness -> signal] 1/5[0m[0m
[init -> harness -> signal] 2/5[0m[0m
[init -> harness -> signal] 3/5[0m[0m
[init -> harness -> signal] 4/5[0m[0m
[init -> harness -> signal] 5/5[0m[0m
[init -> harness -> signal] deactivate sender[0m[0m
[init -> harness -> signal] [0m[0m
[init -> harness -> signal] sender submitted a total of 37890693 signals[0m[0m
[init -> harness -> signal] handler received a total of 37890693 signals[0m[0m
[init -> harness -> signal] [0m[0m
[init -> harness -> signal] handler received 7578138 signals per second[0m[0m
[init -> harness -> signal] handler was activated 101 times per second[0m[0m
[init -> harness -> signal] [0m[0m
[init -> harness -> signal] [0m
[init -> harness -> signal] TEST 2 finished[0m
[init -> harness -> signal] [0m[0m
[init -> harness -> signal] [0m
[init -> harness -> signal] TEST 3: lazy and out-of-order signal reception[0m
[init -> harness -> signal] [0m[0m
[init -> harness -> signal] submit and receive signals with multiple receivers in order[0m[0m
[init -> harness -> signal] returned from wait_for_signal for receiver 1[0m[0m
[init -> harness -> signal] returned from wait_for_signal for receiver 2[0m[0m
[init -> harness -> signal] submit and receive signals with multiple receivers out of order[0m[0m
[init -> harness -> signal] returned from wait_for_signal for receiver 2[0m[0m
[init -> harness -> signal] returned from wait_for_signal for receiver 1[0m[0m
[init -> harness -> signal] [0m
[init -> harness -> signal] TEST 3 finished[0m
[init -> harness -> signal] [0m[0m
[init -> harness -> signal] [0m
[init -> harness -> signal] TEST 4: correct initialization and cleanup of receiver and context[0m
[init -> harness -> signal] [0m[0m
[init -> harness -> signal] submit signal 1[0m[0m
[init -> harness -> signal] submit signal 2[0m[0m
[init -> harness -> signal] suspend sender[0m[0m
[init -> harness -> signal] got 2 signal(s) from 0x101fb10[0m[0m
[init -> harness -> signal] resume sender[0m[0m
[init -> harness -> signal] submit signal 3[0m[0m
[init -> harness -> signal] submit signal 4[0m[0m
[init -> harness -> signal] suspend sender[0m[0m
[init -> harness -> signal] destroy sender[0m[0m
[init -> harness -> signal] [0m
[init -> harness -> signal] TEST 4 finished[0m
[init -> harness -> signal] [0m[0m
[init -> harness -> signal] [0m
[init -> harness -> signal] TEST 5: does 'dissolve' block as long as the signal context is referenced?[0m
[init -> harness -> signal] [0m[0m
[init -> harness -> signal] start dissolving[0m[0m
[init -> harness -> signal] destruct signal[0m[0m
[init -> harness -> signal] dissolve finished[0m[0m
[init -> harness -> signal] [0m
[init -> harness -> signal] TEST 5 finished[0m
[init -> harness -> signal] [0m[0m
[init -> harness -> signal] [0m
[init -> harness -> signal] TEST 6: create and manage many contexts[0m
[init -> harness -> signal] [0m[0m
[init -> harness -> signal] round 0: manage 200 contexts[0m[0m
[init -> harness -> signal] round 1: manage 205 contexts[0m[0m
[init -> harness -> signal] round 2: manage 210 contexts[0m[0m
[init -> harness -> signal] round 3: manage 215 contexts[0m[0m
[init -> harness -> signal] round 4: manage 220 contexts[0m[0m
[init -> harness -> signal] round 5: manage 225 contexts[0m[0m
[init -> harness -> signal] round 6: manage 230 contexts[0m[0m
[init -> harness -> signal] round 7: manage 235 contexts[0m[0m
[init -> harness -> signal] round 8: manage 240 contexts[0m[0m
[init -> harness -> signal] round 9: manage 245 contexts[0m[0m
[init -> harness -> signal] [34mWarning: 4 dangling allocations at allocator destruction time[0m[0m
[init -> harness -> signal] [0m
[init -> harness -> signal] TEST 6 finished[0m
[init -> harness -> signal] [0m[0m
[init -> harness -> signal] [0m
[init -> harness -> signal] TEST 7: wait and dispatch signals at entrypoint[0m
[init -> harness -> signal] [0m[0m
[init -> harness -> signal] 1/8: [ep] wait for I/O-level signal during RPC from [outside][0m[0m
[init -> harness -> signal] 2/8: [outside] submit application-level signal (should be deferred)[0m[0m
[init -> harness -> signal] 3/8: [outside] submit I/O-level signal[0m[0m
[init -> harness -> signal] 4/8: [ep] I/O-level signal received - sending nested signal[0m[0m
[init -> harness -> signal] [34mWarning: attempt to handle the same signal context twice (nested)[0m[0m
[init -> harness -> signal] 5/8: [ep] nested I/O-level signal received[0m[0m
[init -> harness -> signal] 6/8: [ep] I/O completed[0m[0m
[init -> harness -> signal] 7/8: [ep] application-level signal received[0m[0m
[init -> harness -> signal] 8/8: [ep] success[0m[0m
[init -> harness -> signal] [0m
[init -> harness -> signal] TEST 7 finished[0m
[init -> harness -> signal] [0m[0m
[init -> harness -> signal] [0m
[init -> harness -> signal] TEST 8: stressful wait and dispatch signals at entrypoint[0m
[init -> harness -> signal] [0m[0m
[init -> harness -> signal] receiver-1 received 1 times[0m[0m
[init -> harness -> signal] receiver-2 received 1 times[0m[0m
[init -> harness -> signal] receiver-3 received 1 times[0m[0m
[init -> harness -> signal] receiver-1 received 33 times[0m[0m
[init -> harness -> signal] receiver-2 received 34 times[0m[0m
[init -> harness -> signal] receiver-3 received 33 times[0m[0m
[init -> harness -> signal] receiver-1 received 66 times[0m[0m
[init -> harness -> signal] receiver-2 received 67 times[0m[0m
[init -> harness -> signal] receiver-3 received 66 times[0m[0m
[init -> harness -> signal] receiver-1 received 99 times[0m[0m
[init -> harness -> signal] receiver-2 received 100 times[0m[0m
[init -> harness -> signal] receiver-3 received 99 times[0m[0m
[init -> harness -> signal] receiver-1 received 132 times[0m[0m
[init -> harness -> signal] receiver-2 received 133 times[0m[0m
[init -> harness -> signal] receiver-3 received 132 times[0m[0m
[init -> harness -> signal] receiver-1 received 165 times[0m[0m
[init -> harness -> signal] receiver-2 received 166 times[0m[0m
[init -> harness -> signal] receiver-3 received 165 times[0m[0m
[init -> harness -> signal] receiver-1 received 198 times[0m[0m
[init -> harness -> signal] receiver-2 received 199 times[0m[0m
[init -> harness -> signal] receiver-3 received 198 times[0m[0m
[init -> harness -> signal] receiver-1 received 231 times[0m[0m
[init -> harness -> signal] receiver-2 received 232 times[0m[0m
[init -> harness -> signal] receiver-3 received 231 times[0m[0m
[init -> harness -> signal] receiver-1 received 263 times[0m[0m
[init -> harness -> signal] receiver-2 received 264 times[0m[0m
[init -> harness -> signal] receiver-3 received 264 times[0m[0m
[init -> harness -> signal] receiver-1 received 296 times[0m[0m
[init -> harness -> signal] receiver-2 received 297 times[0m[0m
[init -> harness -> signal] receiver-3 received 297 times[0m[0m
[init -> harness -> signal] receiver-1 received 329 times[0m[0m
[init -> harness -> signal] receiver-2 received 330 times[0m[0m
[init -> harness -> signal] receiver-3 received 330 times[0m[0m
[init -> harness -> signal] waiting for receivers[0m[0m
[init -> harness -> signal] waiting for senders[0m[0m
[init -> harness -> signal] destructing ...[0m[0m
[init -> harness -> signal] [0m
[init -> harness -> signal] TEST 8 finished[0m
[init -> harness -> signal] [0m[0m
[init -> harness -> signal] --- Signalling test finished ---[0m[0m
SOTEST SUCCESS[0m
[init -> harness -> rtc] --- RTC test started ---[0m[0m
[init -> harness -> rtc] test RTC reading[0m[0m
[init -> harness -> rtc] RTC[0]: 2020-5-19 20:46:58[0m[0m
[init -> harness -> rtc] RTC[1]: 2020-5-19 20:46:59[0m[0m
[init -> harness -> rtc] RTC[0]: 2020-5-19 20:47:1[0m[0m
[init -> harness -> rtc] RTC[1]: 2020-5-19 20:47:2[0m[0m
[init -> harness -> rtc] RTC[0]: 2020-5-19 20:47:4[0m[0m
[init -> harness -> rtc] RTC[1]: 2020-5-19 20:47:5[0m[0m
[init -> harness -> rtc] RTC[0]: 2020-5-19 20:47:7[0m[0m
[init -> harness -> rtc] RTC[1]: 2020-5-19 20:47:8[0m[0m
[init -> harness -> rtc] test RTC setting[0m[0m
[init -> harness -> rtc] --- RTC test finished ---[0m[0m
SOTEST SUCCESS[0m
[init -> harness -> init] parent provides[0m[0m
[init -> harness -> init]   service "IRQ"[0m[0m
[init -> harness -> init]   service "IO_MEM"[0m[0m
[init -> harness -> init]   service "IO_PORT"[0m[0m
[init -> harness -> init]   service "Timer"[0m[0m
[init -> harness -> init]   service "CPU"[0m[0m
[init -> harness -> init]   service "LOG"[0m[0m
[init -> harness -> init]   service "PD"[0m[0m
[init -> harness -> init]   service "RM"[0m[0m
[init -> harness -> init]   service "ROM"[0m[0m
[init -> harness -> init] child "_report_rom"[0m[0m
[init -> harness -> init]   RAM quota:  776K[0m[0m
[init -> harness -> init]   cap quota:  64[0m[0m
[init -> harness -> init]   ELF binary: report_rom[0m[0m
[init -> harness -> init]   priority:   0[0m[0m
[init -> harness -> init]   provides service ROM[0m[0m
[init -> harness -> init]   provides service Report[0m[0m
[init -> harness -> init] child "acpi_drv"[0m[0m
[init -> harness -> init]   RAM quota:  3848K[0m[0m
[init -> harness -> init]   cap quota:  368[0m[0m
[init -> harness -> init]   ELF binary: acpi_drv[0m[0m
[init -> harness -> init]   priority:   0[0m[0m
[init -> harness -> init]   provides service Platform[0m[0m
[init -> harness -> init]   provides service Acpi[0m[0m
[init -> harness -> init] child "platform_drv"[0m[0m
[init -> harness -> init]   RAM quota:  3848K[0m[0m
[init -> harness -> init]   cap quota:  768[0m[0m
[init -> harness -> init]   ELF binary: platform_drv[0m[0m
[init -> harness -> init]   priority:   0[0m[0m
[init -> harness -> init]   provides service Platform[0m[0m
[init -> harness -> init]   provides service Acpi[0m[0m
[init -> harness -> init] child "test-pci"[0m[0m
[init -> harness -> init]   RAM quota:  2824K[0m[0m
[init -> harness -> init]   cap quota:  64[0m[0m
[init -> harness -> init]   ELF binary: test-pci[0m[0m
[init -> harness -> init]   priority:   0[0m[0m
[init -> harness -> init -> test-pci] --- Platform test started ---[0m[0m
[init -> harness -> init] child "_report_rom" announces service "Report"[0m[0m
[init -> harness -> init] child "_report_rom" announces service "ROM"[0m[0m
[init -> harness -> init -> acpi_drv] Found MADT[0m[0m
[init -> harness -> init -> acpi_drv] MADT IRQ 0 -> GSI 2 flags: 0[0m[0m
[init -> harness -> init -> acpi_drv] MADT IRQ 9 -> GSI 9 flags: 13[0m[0m
[init -> harness -> init -> acpi_drv] Found MCFG[0m[0m
[init -> harness -> init -> acpi_drv] MCFG BASE 0xe0000000 seg 0x0 bus 0x0-0xff[0m[0m
[init -> harness -> init -> acpi_drv] Found DMAR[0m[0m
[init -> harness -> init -> acpi_drv] 39 bit DMA physical addressable , IRQ remapping supported[0m[0m
[init -> harness -> init -> acpi_drv] DMA remapping structure type=0[0m[0m
[init -> harness -> init -> acpi_drv] DMA remapping structure type=0[0m[0m
[init -> harness -> init -> acpi_drv] DMA remapping structure type=1[0m[0m
[init -> harness -> init -> acpi_drv] DMA remapping structure type=1[0m[0m
[init -> harness -> init -> acpi_drv] XSDT OEM 'INTEL ', table id 'NUC7i5DN', revision 66, creator 'AMI ' (65555)[0m[0m
[init -> harness -> init -> acpi_drv] SMBIOS 3 table (entry point: 0x170600 structures: 0x8ade0000)[0m[0m
[init -> harness -> init -> platform_drv] ECAM/MMCONF range 00:00.0-ff:1f.7 - addr [00000000e0000000,00000000f0000000)[0m[0m
[init -> harness -> init -> platform_drv] Root bridge: 00:00.0[0m[0m
[init -> harness -> init -> platform_drv] 00:1c.0 - bridge 01:00.0[0m[0m
[init -> harness -> init] child "platform_drv" announces service "Platform"[0m[0m
[init -> harness -> init -> test-pci] 0:0.0 class=0x600 vendor=0x8086 (Intel) device=0x5904[0m[0m
[init -> harness -> init -> test-pci] 0:2.0 class=0x300 vendor=0x8086 (Intel) device=0x5916[0m[0m
[init -> harness -> init -> test-pci]   Resource 0 (MEM): base=0xde000000 size=0x1000000 [0m[0m
[init -> harness -> init -> test-pci]   Resource 2 (MEM): base=0xc0000000 size=0x10000000 prefetchable[0m[0m
[init -> harness -> init -> test-pci]   Resource 4 (I/O): base=0xf000 size=0x40 [0m[0m
[init -> harness -> init -> test-pci] 0:8.0 class=0x880 vendor=0x8086 (Intel) device=0x1911[0m[0m
[init -> harness -> init -> test-pci]   Resource 0 (MEM): base=0xdf152000 size=0x1000 [0m[0m
[init -> harness -> init -> test-pci] 0:14.0 class=0xc03 vendor=0x8086 (Intel) device=0x9d2f[0m[0m
[init -> harness -> init -> test-pci]   Resource 0 (MEM): base=0xdf130000 size=0x10000 [0m[0m
[init -> harness -> init -> test-pci] 0:14.2 class=0x1180 vendor=0x8086 (Intel) device=0x9d31[0m[0m
[init -> harness -> init -> test-pci]   Resource 0 (MEM): base=0xdf151000 size=0x1000 [0m[0m
[init -> harness -> init -> test-pci] 0:15.0 class=0x1180 vendor=0x8086 (Intel) device=0x9d60[0m[0m
[init -> harness -> init -> test-pci]   Resource 0 (MEM): base=0xdf150000 size=0x1000 [0m[0m
[init -> harness -> init -> test-pci] 0:15.1 class=0x1180 vendor=0x8086 (Intel) device=0x9d61[0m[0m
[init -> harness -> init -> test-pci]   Resource 0 (MEM): base=0xdf14f000 size=0x1000 [0m[0m
[init -> harness -> init -> test-pci] 0:16.0 class=0x780 vendor=0x8086 (Intel) device=0x9d3a[0m[0m
[init -> harness -> init -> test-pci]   Resource 0 (MEM): base=0xdf14e000 size=0x1000 [0m[0m
[init -> harness -> init -> test-pci] 0:16.3 class=0x700 vendor=0x8086 (Intel) device=0x9d3d[0m[0m
[init -> harness -> init -> test-pci]   Resource 0 (I/O): base=0xf0a0 size=0x8 [0m[0m
[init -> harness -> init -> test-pci]   Resource 1 (MEM): base=0xdf14d000 size=0x1000 [0m[0m
[init -> harness -> init -> test-pci] 0:17.0 class=0x106 vendor=0x8086 (Intel) device=0x9d03[0m[0m
[init -> harness -> init -> test-pci]   Resource 0 (MEM): base=0xdf148000 size=0x2000 [0m[0m
[init -> harness -> init -> test-pci]   Resource 1 (MEM): base=0xdf14c000 size=0x100 [0m[0m
[init -> harness -> init -> test-pci]   Resource 2 (I/O): base=0xf090 size=0x8 [0m[0m
[init -> harness -> init -> test-pci]   Resource 3 (I/O): base=0xf080 size=0x4 [0m[0m
[init -> harness -> init -> test-pci]   Resource 4 (I/O): base=0xf060 size=0x20 [0m[0m
[init -> harness -> init -> test-pci]   Resource 5 (MEM): base=0xdf14b000 size=0x800 [0m[0m
[init -> harness -> init -> test-pci] 0:1c.0 class=0x604 vendor=0x8086 (Intel) device=0x9d12[0m[0m
[init -> harness -> init -> test-pci] 0:1f.0 class=0x601 vendor=0x8086 (Intel) device=0x9d4e[0m[0m
[init -> harness -> init -> test-pci] 0:1f.2 class=0x580 vendor=0x8086 (Intel) device=0x9d21[0m[0m
[init -> harness -> init -> test-pci]   Resource 0 (MEM): base=0xdf144000 size=0x4000 [0m[0m
[init -> harness -> init -> test-pci] 0:1f.3 class=0x403 vendor=0x8086 (Intel) device=0x9d71[0m[0m
[init -> harness -> init -> test-pci]   Resource 0 (MEM): base=0xdf140000 size=0x4000 [0m[0m
[init -> harness -> init -> test-pci]   Resource 4 (MEM): base=0xdf120000 size=0x10000 [0m[0m
[init -> harness -> init -> test-pci] 0:1f.4 class=0xc05 vendor=0x8086 (Intel) device=0x9d23[0m[0m
[init -> harness -> init -> test-pci]   Resource 0 (MEM): base=0xdf14a000 size=0x100 [0m[0m
[init -> harness -> init -> test-pci]   Resource 4 (I/O): base=0xf040 size=0x20 [0m[0m
[init -> harness -> init -> test-pci] 0:1f.6 class=0x200 vendor=0x8086 (Intel) device=0x156f[0m[0m
[init -> harness -> init -> test-pci]   Resource 0 (MEM): base=0xdf100000 size=0x20000 [0m[0m
[init -> harness -> init -> test-pci] 1:0.0 class=0x280 vendor=0x8086 (Intel) device=0x24fd[0m[0m
[init -> harness -> init -> test-pci]   Resource 0 (MEM): base=0xdf000000 size=0x2000 [0m[0m
[init -> harness -> init -> test-pci] --- Platform test finished ---[0m[0m
SOTEST SUCCESS[0m
SOTEST END[0m
child "init" exited with exit value 0[0m