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)

38. Kernel compile inside VM (on virtual disk, EPT + VPID) (/home/nul/nul/michal/boot/vancouver-kbuild-vdisk.wv)

novaboot: Connecting to IP relay... done
<iprelayd: connected>
novaboot: Entering directory `/home/nul/nul/build'
novaboot: Created vancuver.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/vancouver.nul bin/boot/munich imgs/bzImage-3.1.0-32 imgs/kernelbuild-e2fs.bz2 vancuver.nulconfig config-novaboot rtime.felk.cvut.cz:/srv/tftp/novaboot
wvtest: timeout 1200
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
[ 2] CORE:0:2: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
[ 1] CORE:0:1:0 6:3a:9:1 [12] Intel(R) Core(TM) i5-3550 CPU @ 3.30GHz
=> hostkeyb:0,0x60,1,12 <=
=> script_start:1,1 <=
=> service_config <=
=> vdisk_empty:736870912 <=
vdisk_empty: Attached as vdisk 0.
=> service_disk <=
disk: Added '0'
Constructing service /disk...
Service /disk registered.
sc: start 0-1 count 1
sc: done.
tb: client data 3f000 guid 5 parent 925
(5) Ignored parameter: 'rom://bin/apps/vancouver.nul'
(5) => PC_PS2 <=
(5) => mem:0,0xa0000 <=
(5) physmem: 0 [0, a0000]
(5) => mem:0x100000 <=
(5) physmem: 0 [100000, 3b200000]
(5) => ioio <=
(5) => nullio:0x80 <=
(5) => pic:0x20,,0x4d0 <=
(5) => pic:0xa0,2,0x4d1 <=
(5) => pit:0x40,0 <=
(5) => scp:0x92,0x61 <=
(5) => kbc:0x60,1,12 <=
(5) => keyb:0,0x10000 <=
(5) => mouse:1,0x10001 <=
(5) => rtc:0x70,8 <=
(5) => serial:0x3f8,0x4,0x4711 <=
(5) => hostsink:0x4712,80 <=
(5) => vga:0x03c0 <=
(5) Allocating from guest 3b1e0000+20000
s0: => INIT done <=
(5) VGA console 3b1e0000+20000 0xbb5e0000
(5) => vbios_disk <=
(5) => vbios_keyboard <=
(5) => hostkeyb:0x10,0x60,1,,1 <=
(5) => vbios_mem <=
(5) => vbios_time <=
(5) => vbios_reset <=
(5) => vbios_multiboot <=
(5) => msi <=
(5) => ioapic <=
(5) => pcihostbridge:0,0x10,0xcf8,0xe0000000 <=
(5) => pmtimer:0x8000 <=
(5) => vcpus <=
(5) => vcpu <=
(5) create VCPU
(5) => halifax <=
(5) startup
(5) => vbios <=
(5) => lapic <=
(5) LAPIC freq 102907250
(5) => ahci:0xe0800000,14,0x30 <=
(5) => drive:0,0,0 <=
(5) do_gsi_boot eip 800007b2 esp 80238fec
(5) SATA disk 0 flags 1 sectors 15f5e1
(5) not finished 1404034,0 inprogress 0
(5) not finished 1404034,0 inprogress 0
(5) void DBus<M>::debug_dump() [with M = MessageHwIOIn]: Bus used 0 times.
(5) 0: StaticReceiver<Y>::StaticReceiver() [with Y = IOAccess]
(5)
(5) do_gsi_boot eip 80004bd8 esp 80236fec
(5) do_gsi_boot eip 800059b8 esp 80235fec
(5) RESET device state
(5) INIT done
(5) reset CPU from 9 mtr_in e0010
(5) > bool VirtualBiosMultiboot::receive(MessageBios&) rip ffff ilen 3 cr0 10 efl 10002
(5) module 0 start 0x81c00000+918 cmdline rom://bin/boot/munich
(5) module 1 start 0x81c02000+1d34b0 cmdline rom://imgs/bzImage-3.1.0-32 clocksource=
(5) module 2 start 0x81dd7000+55f543d cmdline rom://imgs/kernelbuild-e2fs.bz2
(5) # MUNICH: v.0.4.6 starts Linux
(5) # MUNICH: 3.1.0 (root@x220) #14 SMP PREEMPT Fri Nov 4 23:54:38 CET 2011
(5) # MUNICH: clocksource=tsc console=ttyS0 quiet prepare_cmd='set -x; mke2fs -F -j /
(5) # | dev/sda && mount /dev/sda /mnt && mv /usr/src/* /mnt && umount /mnt && mount /d
(5) # | ev/sda /usr/src && echo 3 >/proc/sys/vm/drop_caches && set +x'
(5) # MUNICH: initrd 19D7000
(5) # MUNICH: copy image
(5) # MUNICH: start kernel
(5) handle_int15 eax e801 ebx 0 ecx 0 edx 0 eip ffff efl 10002
(5) handle_int15 eax c000 ebx 0 ecx 0 edx 0 eip ffff efl 10002
(5) handle_int15 eax e980 ebx 0 ecx 0 edx 47534943 eip ffff efl 10002
(5) VESA 4f00 tag 0 base 40000+31d0 esi 0
(5) # CPU: vendor_id 'NOVA microHV' unknown, using generic init.
(5) # CPU: Your system may be unstable.
(5) # ACPI: Unable to load the System Description Tables
(5) # TSC:2840943768 NTSC:2841074044 cpu_khz=3293377 max_loops=3372287772
(5) # microcode: no support for this CPU vendor
(5) bool AhciController::receive(MessageMem&)(1c) read failed
(5) bool AhciController::receive(MessageMem&)(20) read failed
(5) not finished 1404034,0 inprogress 0
(5) not finished 1404034,0 inprogress 0
(5) bool AhciController::receive(MessageMem&)(0) write failed
(5) bool AhciController::receive(MessageMem&)(c) write failed
(5) not finished 1404034,0 inprogress 0
(5) not finished 1404034,0 inprogress 0
(5) IDENTIFY
(5) PIO setup fis
(5) push data 200 prdbase 1b695a80 _dsf 1b695a80 0 1
(5) IDENTIFY transfered
(5) SET FEATURES 3 sc 45
(5) IDENTIFY
(5) PIO setup fis
(5) push data 200 prdbase 1b695a80 _dsf 1b695a80 0 1
(5) IDENTIFY transfered
(5) # Starting logging: OK
(5) # Initializing random number generator... done.
(5) # Starting network...
(5) # Preparing benchmark: set -x; mke2fs -F -j /dev/sda && mount /dev/sda /mnt && mv
(5) # | /usr/src/* /mnt && umount /mnt && mount /dev/sda /usr/src && echo 3 >/proc/sys
(5) # | /vm/drop_caches && set +x
(5) # + mke2fs -F -j /dev/sda
(5) # mke2fs 1.41.14 (22-Dec-2010)
(5) # Filesystem label=
(5) # OS type: Linux
(5) # Block size=4096 (log=2)
(5) # Fragment size=4096 (log=2)
(5) # Stride=0 blocks, Stripe width=0 blocks
(5) # 45024 inodes, 179900 blocks
(5) # 8995 blocks (5.00%) reserved for the super user
(5) # First data block=0
(5) # Maximum filesystem blocks=184549376
(5) # 6 block groups
(5) # 32768 blocks per group, 32768 fragments per group
(5) # 7504 inodes per group
(5) # Superblock backups stored on blocks:
(5) # 32768, 98304, 163840
(5) #
(5) # Writing inode tables: 0/61/62/63/64/65/6done
(5) # |
(5) # Creating journal (4096 blocks): done
(5) # Writing superblocks and filesystem accounting information: done
(5) #
(5) # This filesystem will be automatically checked every 34 mounts or
(5) # 180 days, whichever comes first. Use tune2fs -c or -i to override.
(5) # + mount /dev/sda /mnt
(5) # + mv /usr/src/linux /usr/src/linux-3.1.5 /mnt
(5) # + umount /mnt
(5) # + mount /dev/sda /usr/src
(5) # + echo 3
(5) # + set +x
(5) # Starting benchmark: make -C /usr/src/linux-* defconfig bzImage
(5) # ! PERF: kbuild 488 s okok
(5) # wvtest: done
! /home/nul/nul/michal/wvtest/wvnulrun: $assertions_executed > 0 okok
novaboot: Read /home/nul/nul/.novaboot