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)

20. /home/nul/nul/michal/boot/vancouver-boot-from-disk.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 imgs/bootable-hdd.img vancuver.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 14.
handle id(3) is not a tty. Not changing mode at /usr/share/perl5/Expect.pm line 393, <> line 14.
handle id(3) is not a tty. Not changing mode at /usr/share/perl5/Expect.pm line 393, <> line 14.
handle id(3) is not a tty. Not changing mode at /usr/share/perl5/Expect.pm line 393, <> line 14.
***** 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
[ 1] CORE:0:1: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
=> hostkeyb:0,0x60,1,12 <=
=> service_config <=
=> vdisk:rom://imgs/bootable-hdd.img <=
vdisk: Opened 'imgs/bootable-hdd.img' 0x7dde00 bytes.
vdisk: Attached as vdisk 0.
=> service_disk <=
disk: Added '0'
Constructing service /disk...
Service /disk registered.
=> script_start:1 <=
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, 3c00000]
(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 03be0000+20000
(5) VGA console 3be0000+20000 0x83fe0000
s0: => INIT done <=
(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) => vbios <=
(5) startup
(5) => lapic <=
(5) LAPIC freq 102901562
(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 3eef
(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) boot from disk
(5) VB: driveparam[0] size 1a sectors 3eef efl 10046 eax 0
(5) VB: driveparam[0] size 1a sectors 3eef efl 10046 eax 0
(5) VB: driveparam[0] size 1a sectors 3eef efl 10046 eax 0
(5) handle_int15 eax c0 ebx 0 ecx 2bf03 edx 1 eip ffff efl 10046
(5) handle_int15 eax 5300 ebx 0 ecx 2bf03 edx 0 eip ffff efl 10046
(5) handle_int15 eax 5304 ebx 0 ecx 2bf03 edx 0 eip ffff efl 10046
(5) VB: driveparam[0] size 1a sectors 3eef efl 10046 eax 0
(5) VB: driveparam[0] size 1a sectors 3eef efl 10046 eax 0
(5) # Press `ESC' to enter the menu... 0  Booting 'test'
(5) #
(5) # kernel /boot/bzImage root=/dev/sda1 console=ttyS0
(5) VB: driveparam[0] size 1a sectors 3eef efl 10046 eax 0
(5) # [Linux-bzImage, setup=0x3400, size=0x4a8ab0]
(5) #
(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 90000+34d0 esi 0
(5) # [ 0.000000] Initializing cgroup subsys cpuset
(5) # [ 0.000000] Initializing cgroup subsys cpu
(5) # [ 0.000000] Linux version 3.1.4 (sojka@compile) (gcc version 4.3.6 (Buildroo
(5) # | t 2011.11-dirty) ) #1 SMP Wed Dec 7 00:58:16 CET 2011
(5) # [ 0.000000] CPU: vendor_id 'NOVA microHV' unknown, using generic init.
(5) # [ 0.000000] CPU: Your system may be unstable.
(5) # [ 0.000000] BIOS-provided physical RAM map:
(5) # [ 0.000000] BIOS-e820: 0000000000000000 - 000000000009ac00 (usable)
(5) # [ 0.000000] BIOS-e820: 000000000009ac00 - 00000000000a0000 (reserved)
(5) # [ 0.000000] BIOS-e820: 0000000000100000 - 0000000003be0000 (usable)
(5) # [ 0.000000] Notice: NX (Execute Disable) protection missing in CPU!
(5) # [ 0.000000] DMI not present or invalid.
(5) # [ 0.000000] last_pfn = 0x3be0 max_arch_pfn = 0x100000
(5) # [ 0.000000] init_memory_mapping: 0000000000000000-0000000003be0000
(5) # [ 0.000000] ACPI: RSDP 0009ce00 00014 (v00 NOVA )
(5) # [ 0.000000] ACPI: RSDT 0009e000 00030 (v01 NOVA bk@vmmon 00000001 000
(5) # | 00000)
(5) # [ 0.000000] ACPI: APIC 0009f000 00064 (v01 NOVA bk@vmmon 00000001 000
(5) # | 00000)
(5) # [ 0.000000] ACPI: MCFG 0009bc00 0003C (v01 NOVA bk@vmmon 00000001 000
(5) # | 00000)
(5) # [ 0.000000] ACPI: FACP 0009ac00 000DC (v01 NOVA bk@vmmon 00000001 000
(5) # | 00000)
(5) # [ 0.000000] ACPI Error: Required field Pm1aEventBlock has zero address and/o
(5) # | r length: 0x0000000000000000/0x0 (20110623/tbfadt-544)
(5) # [ 0.000000] ACPI Error: Required field Pm1aControlBlock has zero address and
(5) # | /or length: 0x0000000000000000/0x0 (20110623/tbfadt-544)
(5) # [ 0.000000] ACPI Error: Null physical address for ACPI table [DSDT] (2011062
(5) # | 3/tbutils-459)
(5) # [ 0.000000] ACPI Error: Null physical address for ACPI table [FACS] (2011062
(5) # | 3/tbutils-459)
(5) # [ 0.000000] 0MB HIGHMEM available.
(5) # [ 0.000000] 59MB LOWMEM available.
(5) # [ 0.000000] mapped low ram: 0 - 03be0000
(5) # [ 0.000000] low ram: 0 - 03be0000
(5) # [ 0.000000] Zone PFN ranges:
(5) # [ 0.000000] DMA 0x00000010 -> 0x00001000
(5) # [ 0.000000] Normal 0x00001000 -> 0x00003be0
(5) # [ 0.000000] HighMem empty
(5) # [ 0.000000] Movable zone start PFN for each node
(5) # [ 0.000000] early_node_map[2] active PFN ranges
(5) # [ 0.000000] 0: 0x00000010 -> 0x0000009a
(5) # [ 0.000000] 0: 0x00000100 -> 0x00003be0
(5) # [ 0.000000] Using APIC driver default
(5) # [ 0.000000] ACPI: PM-Timer IO Port: 0x8000
(5) # [ 0.000000] ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled)
(5) # [ 0.000000] ACPI: X2APIC_NMI (uid[0xffffffff] dfl dfl lint[0x1])
(5) # [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
(5) # [ 0.000000] ACPI: IOAPIC (id[0x00] address[0xfec00000] gsi_base[0])
(5) # [ 0.000000] IOAPIC[0]: apic_id 0, version 32, address 0xfec00000, GSI 0-23
(5) # [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
(5) # [ 0.000000] ACPI: NMI_SRC (dfl dfl global_irq 23)
(5) # [ 0.000000] Using ACPI (MADT) for SMP configuration information
(5) # [ 0.000000] SMP: Allowing 1 CPUs, 0 hotplug CPUs
(5) # [ 0.000000] PM: Registered nosave memory: 000000000009a000 - 000000000009b00
(5) # | 0
(5) # [ 0.000000] PM: Registered nosave memory: 000000000009b000 - 00000000000a000
(5) # | 0
(5) # [ 0.000000] PM: Registered nosave memory: 00000000000a0000 - 000000000010000
(5) # | 0
(5) # [ 0.000000] Allocating PCI resources starting at 3be0000 (gap: 3be0000:fc420
(5) # | 000)
(5) # [ 0.000000] setup_percpu: NR_CPUS:8 nr_cpumask_bits:8 nr_cpu_ids:1 nr_node_i
(5) # | ds:1
(5) # [ 0.000000] PERCPU: Embedded 12 pages/cpu @c3400000 s28288 r0 d20864 u419430
(5) # | 4
(5) # [ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pa
(5) # | ges: 15090
(5) # [ 0.000000] Kernel command line: root=/dev/sda1 console=ttyS0
(5) # [ 0.000000] PID hash table entries: 256 (order: -2, 1024 bytes)
(5) # [ 0.000000] Dentry cache hash table entries: 8192 (order: 3, 32768 bytes)
(5) # [ 0.000000] Inode-cache hash table entries: 4096 (order: 2, 16384 bytes)
(5) # [ 0.000000] Initializing CPU#0
(5) # [ 0.000000] Initializing HighMem for node 0 (00000000:00000000)
(5) # [ 0.000000] Memory: 50220k/61312k available (6346k kernel code, 10620k reser
(5) # | ved, 2463k data, 464k init, 0k highmem)
(5) # [ 0.000000] virtual kernel memory layout:
(5) # [ 0.000000] fixmap : 0xfff16000 - 0xfffff000 ( 932 kB)
(5) # [ 0.000000] pkmap : 0xff800000 - 0xffc00000 (4096 kB)
(5) # [ 0.000000] vmalloc : 0xc43e0000 - 0xff7fe000 ( 948 MB)
(5) # [ 0.000000] lowmem : 0xc0000000 - 0xc3be0000 ( 59 MB)
(5) # [ 0.000000] .init : 0xc189b000 - 0xc190f000 ( 464 kB)
(5) # [ 0.000000] .data : 0xc163282e - 0xc189a580 (2463 kB)
(5) # [ 0.000000] .text : 0xc1000000 - 0xc163282e (6346 kB)
(5) # [ 0.000000] Checking if this processor honours the WP bit even in supervisor
(5) # | mode...Ok.
(5) # [ 0.000000] SLUB: Genslabs=15, HWalign=32, Order=0-3, MinObjects=0, CPUs=1,
(5) # | Nodes=1
(5) # [ 0.000000] Hierarchical RCU implementation.
(5) # [ 0.000000] NR_IRQS:2304 nr_irqs:256 16
(5) # [ 0.000000] Console: colour VGA+ 80x25
(5) # [ 0.000000] console [ttyS0] enabled
(5) # [ 0.000000] Fast TSC calibration using PIT
(5) # [ 0.000000] Detected 3293.399 MHz processor.
(5) # [ 0.003000] Calibrating delay loop (skipped), value calculated using timer f
(5) # | requency.. 6586.79 BogoMIPS (lpj=3293399)
(5) # [ 0.005001] pid_max: default: 32768 minimum: 301
(5) # [ 0.006010] Security Framework initialized
(5) # [ 0.007004] SELinux: Initializing.
(5) # [ 0.008008] Mount-cache hash table entries: 512
(5) # [ 0.009056] Initializing cgroup subsys cpuacct
(5) # [ 0.010002] Initializing cgroup subsys freezer
(5) # [ 0.011250] SMP alternatives: switching to UP code
(5) # [ 0.019687] Freeing SMP alternatives: 24k freed
(5) # [ 0.020002] ACPI: Core revision 20110623
(5) # [ 0.021009] ACPI Exception: AE_NO_ACPI_TABLES, While loading namespace from
(5) # | ACPI tables (20110623/tbxface-640)
(5) # [ 0.024000] ACPI: Unable to load the System Description Tables
(5) # [ 0.025042] Enabling APIC mode: Flat. Using 1 I/O APICs
(5) # [ 0.026778] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
(5) # [ 0.037018] CPU0: NOVA microHV Vancouver VMM proudly presents this VirtualCP
(5) # | U. stepping 03
(5) # [ 0.039997] Performance Events:
(5) # [ 0.039997] Brought up 1 CPUs
(5) # [ 0.040999] Total of 1 processors activated (6586.79 BogoMIPS).
(5) # [ 0.042286] RTC time: 23:16:30, date: 04/25/15
(5) # [ 0.043015] NET: Registered protocol family 16
(5) # [ 0.044031] kworker/u:0 used greatest stack depth: 7172 bytes left
(5) # [ 0.071751] PCI: Using configuration type 1 for base access
(5) # [ 0.072120] kworker/u:0 used greatest stack depth: 6920 bytes left
(5) # [ 0.077343] bio: create slab <bio-0> at 0
(5) # [ 0.078119] ACPI: Interpreter disabled.
(5) # [ 0.079039] vgaarb: loaded
(5) # [ 0.080067] SCSI subsystem initialized
(5) # [ 0.081115] usbcore: registered new interface driver usbfs
(5) # [ 0.082016] usbcore: registered new interface driver hub
(5) # [ 0.083020] usbcore: registered new device driver usb
(5) # [ 0.085027] Advanced Linux Sound Architecture Driver Version 1.0.24.
(5) # [ 0.085997] PCI: Probing PCI hardware
(5) # [ 0.094455] cfg80211: Calling CRDA to update world regulatory domain
(5) # [ 0.095077] NetLabel: Initializing
(5) # [ 0.095997] NetLabel: domain hash size = 128
(5) # [ 0.096992] NetLabel: protocols = UNLABELED CIPSOv4
(5) # [ 0.097998] NetLabel: unlabeled traffic allowed by default
(5) # [ 0.100327] pnp: PnP ACPI: disabled
(5) # [ 0.137719] Switching to clocksource acpi_pm
(5) # [ 0.138018] NET: Registered protocol family 2
(5) # [ 0.143539] Switched to NOHz mode on CPU #0
(5) # [ 0.148937] IP route cache hash table entries: 1024 (order: 0, 4096 bytes)
(5) # [ 0.157346] TCP established hash table entries: 2048 (order: 2, 16384 bytes)
(5) # | [ 0.165855] TCP bind hash table entries: 2048 (order: 2, 16384 bytes)
(5) # [ 0.173955] TCP: Hash tables configured (established 2048 bind 2048)
(5) # [ 0.181862] TCP reno registered
(5) # [ 0.186183] UDP hash table entries: 128 (order: 0, 4096 bytes)
(5) # [ 0.193213] UDP-Lite hash table entries: 128 (order: 0, 4096 bytes)
(5) # [ 0.200699] NET: Registered protocol family 1
(5) # [ 0.206249] RPC: Registered named UNIX socket transport module.
(5) # [ 0.213336] RPC: Registered udp transport module.
(5) # [ 0.219223] RPC: Registered tcp transport module.
(5) # [ 0.225116] RPC: Registered tcp NFSv4.1 backchannel transport module.
(5) # [ 0.233098] platform rtc_cmos: registered platform RTC device (no PNP device
(5) # | found)
(5) # [ 0.243905] microcode: no support for this CPU vendor
(5) # [ 0.250193] audit: initializing netlink socket (disabled)
(5) # [ 0.256735] type=2000 audit(1430003790.255:1): initialized
(5) # [ 0.274930] HugeTLB registered 4 MB page size, pre-allocated 0 pages
(5) # [ 0.284521] VFS: Disk quotas dquot_6.5.2
(5) # [ 0.289648] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
(5) # [ 0.298051] msgmni has been set to 98
(5) # [ 0.302983] Block layer SCSI generic (bsg) driver version 0.4 loaded (major
(5) # | 253)
(5) # [ 0.313354] io scheduler noop registered
(5) # [ 0.318459] io scheduler deadline registered
(5) # [ 0.323972] io scheduler cfq registered (default)
(5) # [ 0.329922] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
(5) # [ 0.336697] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
(5) # ÿÿ[ 0.588188] serial8250: ttyS0 at I/O 0x3f8 (irq = 4) is a 16450
(5) # [ 0.595551] Non-volatile memory driver v1.3
(5) # [ 0.600851] Linux agpgart interface v0.103
(5) # [ 0.606265] [drm] Initialized drm 1.1.0 20060810
(5) # [ 0.612001] [drm:i915_init] *ERROR* drm/i915 can't work without intel_agp mo
(5) # | dule!
(5) # [ 0.623273] brd: module loaded
(5) # [ 0.627842] loop: module loaded
(5) # [ 0.632184] ahci 0000:00:06.0: can't find IRQ for PCI INT A; probably buggy
(5) # | MP table
(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) # [ 0.674940] ahci 0000:00:06.0: AHCI 0001.0200 32 slots 1 ports 1.5 Gbps 0x1
(5) # | impl SATA mode
(5) # [ 0.686190] ahci 0000:00:06.0: flags: ncq only pio
(5) # [ 0.692384] scsi0 : ahci
(5) # [ 0.696070] ata1: SATA max UDMA/133 abar m8192@0xe0800000 port 0xe0800100 ir
(5) # | q 40
(5) not finished 1404034,0 inprogress 0
(5) not finished 1404034,0 inprogress 0
(5) # [ 0.714672] e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k8-NAPI
(5) # | [ 0.723180] e1000: Copyright (c) 1999-2006 Intel Corporation.
(5) # [ 0.730318] e1000e: Intel(R) PRO/1000 Network Driver - 1.4.4-k
(5) # [ 0.737328] e1000e: Copyright(c) 1999 - 2011 Intel Corporation.
(5) # [ 0.744471] e100: Intel(R) PRO/100 Network Driver, 3.5.24-k2-NAPI
(5) # [ 0.751730] e100: Copyright(c) 1999-2006 Intel Corporation
(5) # [ 0.758456] sky2: driver version 1.29
(5) # [ 0.763373] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
(5) # [ 0.771371] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
(5) # [ 0.778750] uhci_hcd: USB Universal Host Controller Interface driver
(5) # [ 0.786681] usbcore: registered new interface driver usblp
(5) # [ 0.793315] Initializing USB Mass Storage driver...
(5) # [ 0.799396] usbcore: registered new interface driver usb-storage
(5) # [ 0.806583] USB Mass Storage support registered.
(5) # [ 0.812399] usbcore: registered new interface driver libusual
(5) # [ 0.819386] i8042: PNP: No PS/2 controller found. Probing ports directly.
(5) # [ 0.827919] serio: i8042 KBD port at 0x60,0x64 irq 1
(5) # [ 0.834020] serio: i8042 AUX port at 0x60,0x64 irq 12
(5) # [ 0.840317] mousedev: PS/2 mouse device common for all mice
(5) # [ 0.847170] input: AT Translated Set 2 keyboard as /devices/platform/i8042/s
(5) # | erio0/input/input0
(5) # [ 0.858939] rtc_cmos rtc_cmos: rtc core: registered rtc_cmos as rtc0
(5) # [ 0.866868] rtc0: alarms up to one day, 114 bytes nvram
(5) # [ 0.873691] device-mapper: ioctl: 4.21.0-ioctl (2011-07-06) initialised: dm-
(5) # | devel@redhat.com
(5) # [ 0.885142] cpuidle: using governor ladder
(5) # [ 0.890396] cpuidle: using governor menu
(5) # [ 0.895489] EFI Variables Facility v0.08 2004-May-17
(5) # [ 0.901990] usbcore: registered new interface driver usbhid
(5) # [ 0.908706] usbhid: USB HID core driver
(5) # [ 0.913891] ALSA device list:
(5) # [ 0.917951] No soundcards found.
(5) # [ 0.922538] Netfilter messages via NETLINK v0.30.
(5) # [ 0.928422] nf_conntrack version 0.5.0 (785 buckets, 3140 max)
(5) # [ 0.935524] ctnetlink v0.93: registering with nfnetlink.
(5) # [ 0.942236] ip_tables: (C) 2000-2006 Netfilter Core Team
(5) # [ 0.948701] TCP cubic registered
(5) # [ 0.953083] Initializing XFRM netlink socket
(5) # [ 0.958672] NET: Registered protocol family 10
(5) # [ 0.964460] ip6_tables: (C) 2000-2006 Netfilter Core Team
(5) # [ 0.971048] IPv6 over IPv4 tunneling driver
(5) # [ 0.976583] NET: Registered protocol family 17
(5) # [ 0.982260] Registering the dns_resolver key type
(5) # [ 0.988151] Using IPI No-Shortcut mode
(5) # [ 0.993126] registered taskstats version 1
(5) # [ 0.998373] Magic number: 11:28:303
(5) # [ 1.003185] tty tty32: hash matches
(5) # [ 1.007851] console [netcon0] enabled
(5) # [ 1.012684] netconsole: network logging started
(5) # [ 1.036062] ata1: SATA link up 1.5 Gbps (SStatus 113 SControl 300)
(5) IDENTIFY
(5) PIO setup fis
(5) push data 200 prdbase 3255a80 _dsf 3255a80 0 1
(5) IDENTIFY transfered
(5) # [ 1.054886] ata1.00: ATA-6: virtualdisk, , max UDMA/100
(5) # [ 1.061288] ata1.00: 16111 sectors, multi 0: LBA48
(5) SET FEATURES 3 sc 45
(5) IDENTIFY
(5) PIO setup fis
(5) push data 200 prdbase 3255a80 _dsf 3255a80 0 1
(5) IDENTIFY transfered
(5) # [ 1.081632] ata1.00: configured for UDMA/100
(5) # [ 1.087070] scsi 0:0:0:0: Direct-Access ATA virtualdisk n/a P
(5) # | Q: 0 ANSI: 5
(5) # [ 1.098225] sd 0:0:0:0: [sda] 16111 512-byte logical blocks: (8.24 MB/7.86 M
(5) # | iB)
(5) # [ 1.108623] sd 0:0:0:0: Attached scsi generic sg0 type 0
(5) # [ 1.115129] sd 0:0:0:0: [sda] Write Protect is off
(5) # [ 1.121090] sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, do
(5) # | esn't support DPO or FUA
(5) # [ 1.133480] sda: sda1
(5) # [ 1.137042] sd 0:0:0:0: [sda] Attached SCSI disk
(5) # [ 1.244029] Refined TSC clocksource calibration: 3292.849 MHz.
(5) # [ 1.251024] Switching to clocksource tsc
(5) # [ 1.881283] input: PS/2 Generic Mouse as /devices/platform/i8042/serio1/inpu
(5) # | t/input1
(5) # [ 1.892067] md: Waiting for all devices to be available before autodetect
(5) # [ 1.900303] md: If you don't use raid, use raid=noautodetect
(5) # [ 1.907223] md: Autodetecting RAID arrays.
(5) # [ 1.912434] md: Scanned 0 and added 0 devices.
(5) # [ 1.918056] md: autorun ...
(5) # [ 1.922020] md: ... autorun DONE.
(5) # [ 1.928123] VFS: Mounted root (ext2 filesystem) readonly on device 8:1.
(5) # [ 1.936191] Freeing unused kernel memory: 464k freed
(5) # [ 1.942394] Write protecting the kernel text: 6348k
(5) # [ 1.948411] Write protecting the kernel read-only data: 2136k
(5) # [ 1.957136] mount used greatest stack depth: 5952 bytes left
(5) # Starting logging: OK
(5) # Initializing random number generator... read-only file system detected...done
(5) # Starting network...
(5) # [ 1.994263] ip used greatest stack depth: 5820 bytes left
(5) # ! /etc/init.d/S99wvtestboot boot finished okok
(5) # wvtest: done
! /home/nul/nul/michal/wvtest/wvnulrun: $assertions_executed > 0 okok
novaboot: Read /home/nul/nul/.novaboot