NUL Test Report

Sun, 26 Apr 2015 01:00:05 +0000 testbox/ivybridge-1-g5bc2785 (Michal Sojka: Move files related to nightly testing to another repo)

25. /home/nul/nul/michal/apps/tests/pingpong.wv

novaboot: Connecting to IP relay... done
<iprelayd: connected>
novaboot: Entering directory `/home/nul/nul/build'
novaboot: Created bin/apps/pingpong.nulconfig
novaboot: Created bin/apps/pong.nulconfig
novaboot: Created bin/apps/ping.nulconfig
novaboot: Created /home/nul/nul/build/config-novaboot
novaboot: Running: rsync -RLp --chmod=Dg+s,ug+w,o-w,+rX --rsync-path="umask 002 && rsync" bin/apps/hypervisor bin/apps/sigma0.nul bin/apps/pingpong.nul bin/apps/ping.nul bin/apps/pong.nul bin/apps/pingpong.nulconfig bin/apps/pong.nulconfig bin/apps/ping.nulconfig config-novaboot rtime.felk.cvut.cz:/srv/tftp/novaboot
novaboot: Reseting the test box... done
novaboot: Serial line interaction (press Ctrl-C to interrupt)...
handle id(3) is not a tty. Not changing mode at /usr/share/perl5/Expect.pm line 393, <> line 19.
handle id(3) is not a tty. Not changing mode at /usr/share/perl5/Expect.pm line 393, <> line 19.
handle id(3) is not a tty. Not changing mode at /usr/share/perl5/Expect.pm line 393, <> line 19.
handle id(3) is not a tty. Not changing mode at /usr/share/perl5/Expect.pm line 393, <> line 19.
***** WHICH IDE Returns: Pri/Sec:0, Mas/Slave: 0
SetSataPortString, Port# 0
***** Found a match!!! Drive= 0
GetDriveInfo()
GDI - Found HDD.
NOVA Microhypervisor v6-7ebd956 (x86_32): Apr 26 2015 01:00:34 [gcc 4.7.2]
[ 0] CORE:0:0:0 6:3a:9:1 [12] Intel(R) Core(TM) i5-3550 CPU @ 3.30GHz
[ 3] CORE:0:3:0 6:3a:9:1 [12] Intel(R) Core(TM) i5-3550 CPU @ 3.30GHz
[ 2] CORE:0:2:0 6:3a:9:1 [12] Intel(R) Core(TM) i5-3550 CPU @ 3.30GHz
[ 1] CORE:0:1:0 6:3a:9:1 [12] Intel(R) Core(TM) i5-3550 CPU @ 3.30GHz
=> verbose <=
=> hostkeyb:0,0x60,1,12,2 <=
s0: map self 60000 -> 0 size 1000 offset 0 s 0 typed 1
s0: map self 64000 -> 0 size 1000 offset 0 s 0 typed 1
s0: map self daffb000 -> b6c00000 size 2000 offset 0 s 0 typed 2
s0: map self daff9000 -> b6800000 size 2000 offset 0 s 0 typed 2
=> script_start:1 <=
=> script_waitchild <=
=> script_start:2,2 <=
=> script_waitchild <=
sc: start 0-1 count 1
s0: map self daff4000 -> b6400000 size 8000 offset 0 s 0 typed 2
s0: [ 5] module 'namespace::/tmp sigma0::mem:16 name::/s0/log name::/s0/timer name::/s0/fs/rom name::/s0/admission ||' \
s0: ... ' rom://bin/apps/pingpong.nul'
s0: map self 3000000 -> b5000000 size 1000000 offset 0 s 0 typed 1
s0: [ 5] using memory: 16 MB (1000000) at 3000000
s0: [ 5] creating PD on CPU 2
s0: [ 5, 4284e] pagefault 4c/0 for 800022b1 err 14 at 800022b1
sc: wait for child 5
s0: [ 5, 4284e] b50022b1 b5000000+1000000 -> order 24: b5000000+1000000
s0: [ 5, 4284e] map 0/1 for 800022b1 err 14 at 800022b1
s0: [ 5, 4284e] pagefault 4c/0 for bffff006 err 4 at 800010c3
s0: [ 5, 4284e] b6400006 b6400000+1000 -> order 12: b6400000+1000
s0: [ 5, 4284e] map 0/1 for bffff006 err 4 at 800010c3
tb: client data 3f000 guid 5 parent 925
(5) ! michal/apps/tests/pingpong.cc:33 ec = create_ec4pt(this, utcb->head.nul_cpunr, 0) okok
(5) ! michal/apps/tests/pingpong.cc:34 ec = 0x809 okok
(5) ! michal/apps/tests/pingpong.cc:36 nova_create_pt(pt, ec, reinterpret_cast<unsigned long>(portal_func), 0) okok
(5) ! michal/apps/tests/pingpong.cc:56 counter == 1000u okok
(5) ! michal/apps/tests/pingpong.cc:57 PERF: avg 333 cycles okok
(5) ! michal/apps/tests/pingpong.cc:58 PERF: min 328 cycles okok
s0: => INIT done <=
(5) ! michal/apps/tests/pingpong.cc:59 PERF: max 612 cycles okok
sc: start 1-2 count 1
s0: [ 6] module 'namespace::/tmp sigma0::mem:16 sigma0::cpu:0 name::/s0/log name::/s0/timer name::/s0/fs/rom name::/s0/admission name::/tmp/pong ||' \
s0: ... ' rom://bin/apps/pong.nul'
s0: map self 2000000 -> b4000000 size 1000000 offset 0 s 0 typed 1
s0: [ 6] using memory: 16 MB (1000000) at 2000000
s0: map self daff3000 -> b6000000 size 1000 offset 0 s 0 typed 1
s0: [ 6] creating PD on CPU 0
s0: [ 7] module 'namespace::/tmp sigma0::mem:16 sigma0::cpu:0 name::/s0/log name::/s0/timer name::/s0/fs/rom name::/s0/admission name::/tmp/pong ||' \
s0: ... ' rom://bin/apps/ping.nul'
s0: map self 1f000000 -> b3000000 size 1000000 offset 0 s 0 typed 1
s0: [ 7] using memory: 16 MB (1000000) at 1f000000
s0: map self daff2000 -> b2c00000 size 1000 offset 0 s 0 typed 1
s0: [ 7] creating PD on CPU 0
sc: wait for child 7
s0: [ 6, 4300e] pagefault 4c/0 for 8000216f err 14 at 8000216f
s0: [ 6, 4300e] b400216f b4000000+1000000 -> order 24: b4000000+1000000
s0: [ 6, 4300e] map 0/1 for 8000216f err 14 at 8000216f
s0: [ 7, 4380e] pagefault 4c/0 for 80001fbb err 14 at 80001fbb
s0: [ 7, 4380e] b3001fbb b3000000+1000000 -> order 24: b3000000+1000000
s0: [ 7, 4380e] map 0/1 for 80001fbb err 14 at 80001fbb
s0: [ 7, 4380e] pagefault 4c/0 for bffff006 err 4 at 800014c9
s0: [ 7, 4380e] b2c00006 b2c00000+1000 -> order 12: b2c00000+1000
s0: [ 7, 4380e] map 0/1 for bffff006 err 4 at 800014c9
tb: client data 3f002 guid 7 parent 91f
(7) ! michal/apps/tests/ping.cc:35 ParentProtocol::get_pseudonym(*utcb, "pong", 0, pseudonym) okok
s0: [ 6, 4300e] pagefault 4c/0 for bffff006 err 4 at 8000100b
(7) ! michal/apps/tests/ping.cc:37 res = ParentProtocol::get_portal(*utcb, pseudonym, pt, true) okok
s0: [ 6, 4300e] b6000006 b6000000+1000 -> order 12: b6000000+1000
s0: [ 6, 4300e] map 0/1 for bffff006 err 4 at 8000100b
tb: client data 3f004 guid 6 parent 3f003
(6) ! michal/apps/tests/pong.cc:31 ec = create_ec4pt(this, utcb->head.nul_cpunr, 0) okok
(6) ! michal/apps/tests/pong.cc:32 ec = 0x80a okok
(6) ! michal/apps/tests/pong.cc:34 nova_create_pt(pt, ec, reinterpret_cast<unsigned long>(portal_func), 0) okok
(6) ! michal/apps/tests/pong.cc:35 ParentProtocol::register_service(*utcb, "/pong", utcb->head.nul_cpunr, pt, service_cap) okok
(7) ! michal/apps/tests/ping.cc:37 res = ParentProtocol::get_portal(*utcb, pseudonym, pt, true) okok
(7) ! michal/apps/tests/ping.cc:39 res okok
(7) ! michal/apps/tests/ping.cc:60 PERF: xpd_avg 712 cycles okok
(7) ! michal/apps/tests/ping.cc:61 PERF: xpd_min 708 cycles okok
(7) ! michal/apps/tests/ping.cc:62 PERF: xpd_max 980 cycles okok
sc: done.
! /home/nul/nul/michal/wvtest/wvnulrun: $assertions_executed > 0 okok
novaboot: Read /home/nul/nul/.novaboot