NUL Test Report

Wed, 26 Sep 2012 02:35:22 CEST

6. libvirt basic functionality (./libvirt.wv)

>>> make -C /home/sojka/nul/alexb/apps/libvirt install DESTDIR=/home/sojka/nul/alexb/apps/libvirt/_install
make: Entering directory `/home/sojka/nul/alexb/apps/libvirt'
make: Nothing to be done for `install'.
make: Leaving directory `/home/sojka/nul/alexb/apps/libvirt'
! ./libvirt.wv.inc:14 make -C /home/sojka/nul/alexb/apps/libvirt install DESTDIR=/home/sojka/nul/alexb/apps/libvirt/_install okok
>>> cp /home/sojka/nul/michal/imgs/passive/CAcertSrv.pem cacert.pem
! ./libvirt.wv.inc:15 cp /home/sojka/nul/michal/imgs/passive/CAcertSrv.pem cacert.pem okok
+ PATH=/home/sojka/nul/alexb/apps/libvirt/_install/usr/local/bin:/home/sojka/bin:/home/sojka/bin:/home/sojka/bin:/usr/local/bin:/usr/bin:/bin
+ LD_LIBRARY_PATH=/home/sojka/nul/alexb/apps/libvirt/_install/usr/local/lib
+ set +x
wvtest: ignore exit patterns
+ WVTEST_NOKILL=1
+ WVTEST_OUTPUT=passive.log
+ /home/sojka/nul/michal/wvtest/wvnulrun /home/sojka/nul/michal/boot/passive/nova-82576 -I
Use of uninitialized value $ENV{"TERM"} in string ne at /home/sojka/nul/michal/wvtest/wvnulrun line 30.
novaboot: Connecting to IP relay... done
<iprelayd: connected>
novaboot: Entering directory `/home/sojka/nul/build'
novaboot: Created bin/apps/logdisk.nulconfig
novaboot: Created bin/apps/remote_config.nulconfig
novaboot: Created template.config
novaboot: Created diskuuid.config
novaboot: Created linux.nulconfig
novaboot: Created passive-demo.nulconfig
novaboot: Created cpuload.nulconfig
novaboot: Created reboot.nulconfig
Use of uninitialized value $ENV{"TERM"} in string ne at /home/sojka/bin/novaboot line 516, <> line 60.
novaboot: Running: rsync --progress -RLp --chmod=Dg+s,ug+w,o-w,+rX --rsync-path="umask 002 && rsync" menu.lst bin/apps/hypervisor bin/apps/sigma0.nul bin/apps/logdisk.nul bin/apps/remote_config.nul bin/apps/vancouver.nul bin/boot/munich imgs/passive/bzImage imgs/passive/initramfs imgs/passive/privkeySrv.der imgs/passive/certSrv.crt imgs/passive/CAcertSrv.crt bin/apps/logdisk.nulconfig bin/apps/remote_config.nulconfig template.config diskuuid.config linux.nulconfig passive-demo.nulconfig cpuload.nulconfig reboot.nulconfig erwin.inf.tu-dresden.de:~passive/boot
cpuload.nulconfig
285 100% 0.00kB/s 0:00:00 285 100% 0.00kB/s 0:00:00 (xfer#1, to-check=26/27)
diskuuid.config
420 100% 37.29kB/s 0:00:00 420 100% 37.29kB/s 0:00:00 (xfer#2, to-check=25/27)
linux.nulconfig
381 100% 31.01kB/s 0:00:00 381 100% 31.01kB/s 0:00:00 (xfer#3, to-check=24/27)
menu.lst
700 55% 56.97kB/s 0:00:00 1260 100% 102.54kB/s 0:00:00 (xfer#4, to-check=23/27)
passive-demo.nulconfig
282 100% 22.95kB/s 0:00:00 282 100% 22.95kB/s 0:00:00 (xfer#5, to-check=22/27)
reboot.nulconfig
284 100% 23.11kB/s 0:00:00 284 100% 23.11kB/s 0:00:00 (xfer#6, to-check=21/27)
template.config
270 100% 20.28kB/s 0:00:00 270 100% 20.28kB/s 0:00:00 (xfer#7, to-check=20/27)
bin/apps/hypervisor
700 1% 52.58kB/s 0:00:01 67346 100% 4.01MB/s 0:00:00 (xfer#8, to-check=15/27)
bin/apps/logdisk.nul
700 3% 42.72kB/s 0:00:00 18076 100% 1.01MB/s 0:00:00 (xfer#9, to-check=14/27)
bin/apps/logdisk.nulconfig
182 100% 10.45kB/s 0:00:00 182 100% 10.45kB/s 0:00:00 (xfer#10, to-check=13/27)
bin/apps/remote_config.nul
700 0% 37.98kB/s 0:00:04 170364 100% 6.77MB/s 0:00:00 (xfer#11, to-check=12/27)
bin/apps/remote_config.nulconfig
700 87% 28.48kB/s 0:00:00 803 100% 32.67kB/s 0:00:00 (xfer#12, to-check=11/27)
bin/apps/sigma0.nul
700 0% 26.29kB/s 0:00:15 417092 100% 9.70MB/s 0:00:00 (xfer#13, to-check=10/27)
bin/apps/vancouver.nul
700 0% 16.28kB/s 0:00:14 237532 100% 4.44MB/s 0:00:00 (xfer#14, to-check=9/27)
bin/boot/munich
700 29% 13.40kB/s 0:00:00 2356 100% 45.11kB/s 0:00:00 (xfer#15, to-check=7/27)
imgs/passive/CAcertSrv.crt
700 92% 13.40kB/s 0:00:00 756 100% 14.20kB/s 0:00:00 (xfer#16, to-check=4/27)
imgs/passive/bzImage
2208 0% 14.47kB/s 0:05:38 4904720 100% 14.85MB/s 0:00:00 (xfer#17, to-check=3/27)
imgs/passive/certSrv.crt
700 96% 2.16kB/s 0:00:00 726 100% 2.24kB/s 0:00:00 (xfer#18, to-check=2/27)
imgs/passive/initramfs
3008 0% 9.24kB/s 0:16:19 9054208 100% 13.86MB/s 0:00:00 (xfer#19, to-check=1/27)
imgs/passive/privkeySrv.der
608 100% 0.95kB/s 0:00:00 608 100% 0.95kB/s 0:00:00 (xfer#20, to-check=0/27)
sent 27577 bytes received 39728 bytes 44870.00 bytes/sec
total size is 14877951 speedup is 221.05
novaboot: Reseting the test box... done
NOVA Microhypervisor v5-29c6ee8 (x86_32): Sep 26 2012 01:56:33 [gcc 4.6.2]
[ 0] CORE:0:0:0 6:1a:5:1 [11] Intel(R) Core(TM) i7 CPU 920 @ 2.67GHz
[ 3] CORE:0:3:0 6:1a:5:1 [11] Intel(R) Core(TM) i7 CPU 920 @ 2.67GHz
[ 7] CORE:0:3:1 6:1a:5:1 [11] Intel(R) Core(TM) i7 CPU 920 @ 2.67GHz
[ 4] CORE:0:0:1 6:1a:5:1 [11] Intel(R) Core(TM) i7 CPU 920 @ 2.67GHz
[ 1] CORE:0:1:0 6:1a:5:1 [11] Intel(R) Core(TM) i7 CPU 920 @ 2.67GHz
[ 5] CORE:0:1:1 6:1a:5:1 [11] Intel(R) Core(TM) i7 CPU 920 @ 2.67GHz
[ 6] CORE:0:2:1 6:1a:5:1 [11] Intel(R) Core(TM) i7 CPU 920 @ 2.67GHz
[ 2] CORE:0:2:0 6:1a:5:1 [11] Intel(R) Core(TM) i7 CPU 920 @ 2.67GHz
=> hostvesa <=
s0: map self 0 -> b3400000 size 100000 offset 0 s 0 typed 1
=> mem <=
physmem: 0 [0, 100000]
=> pit:0x40,0 <=
=> scp:0x92,0x61 <=
=> pcihostbridge:0,0x100,0xcf8 <=
=> dpci:3,0,0,0,0,0 <=
search_device(3,0,0) bdf 200
s0: map self e0000000 -> a0000000 size 10000000 offset 0 s 0 typed 1
s0: map self d1100000 -> b3000000 size 10000 offset 0 s 0 typed 1
s0: map self 2000000 -> 0 size 100000 offset 0 s 0 typed 1
=> dio:0x3c0+0x20 <=
s0: map self 3c0000 -> 0 size 20000 offset 0 s 0 typed 1
=> dio:0x3b0+0x10 <=
s0: map self 3b0000 -> 0 size 10000 offset 0 s 0 typed 1
=> vcpu <=
=> halifax <=
VBE version 300 tag 41534556 memsize 1000000 oem 'ATI ATOMBIOS' vendor '(C) 1988-2005, ATI Technologies Inc. ' product 'RV710' version '01.00'
framebuffer e0000000+10000000
=> hostkeyb:0,0x60,1,12,1 <=
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
(1) created sc - prio=2 quantum=10000 cpu=0 sigma0.irq 1 - keyb
(1) created sc - prio=2 quantum=10000 cpu=0 sigma0.irq 12 - aux
=> nubus <=
bus[1] Enabling ARI.
dev[1:00.0] Adapting ARI status on SR-IOV capable device.
dev[1:00.0] Enabled 8 VFs. Wait for them to settle down.
dev[1:00.1] Enabled 8 VFs. Wait for them to settle down.
bus[3] Enabling ARI.
Nubus initialized.
=> host82576:0 <=
82576PF 00: Found Intel 82576-style controller at 100. Attaching IRQ 0.
82576PF 00: BAR 0: d0820000 (size 00020000)
s0: map self d0820000 -> b2c00000 size 20000 offset 0 s 0 typed 1
82576PF 00: Found MMIO window at 0xb2c00000 (phys d0820000).
82576PF 00: BAR 1: d0400000 (size 00400000)
s0: map self d0400000 -> b2800000 size 400000 offset 0 s 0 typed 1
82576PF 00: BAR 2: 00003021 (size 00000020)
82576PF 00: BAR 3: d08c4000 (size 00004000)
s0: map self d08c4000 -> b2400000 size 4000 offset 0 s 0 typed 1
82576PF 00: Perform Global Reset.
82576PF 00: Global Reset successful.
s0: Attaching to CPU 0 (ffffffff 0)
(1) created sc - prio=2 quantum=10000 cpu=0 sigma0.irq 126 - gsi msi
82576PF 00: Attached to IRQ 126 (MSI-X).
82576PF 00: Configuring VFs...
82576PF 00: Configuring link parameters...
82576PF 00: We are 00:1b:21:86:a1:4a
82576PF 00: Notifying VFs that PF is done...
82576PF 00: Initialization complete.
82576PF 00: Enabling interrupts...
82576PF 00: Link status changed to UP.
82576PF 00: UP 100BASE-T FD | 8 VFs | 0 RX | 0 TX
=> host82576vf:0,0,1 <=
s0: map self d0840000 -> b2000000 size 4000 offset 0 s 0 typed 1
s0: map self d0860000 -> b1c00000 size 4000 offset 0 s 0 typed 1
s0: Attaching to CPU 0 (ffffffff 0)
(1) created sc - prio=2 quantum=10000 cpu=0 sigma0.irq 125 - gsi msi
s0: Attaching to CPU 0 (ffffffff 0)
(1) created sc - prio=2 quantum=10000 cpu=0 sigma0.irq 124 - gsi msi
82576VF 80: Found Intel 82576VF-style controller.
82576VF 80: Interrupt throttling DISABLED.
82576VF 80: 00000200 bytes allocated for RX descriptor ring (32 descriptors).
82576VF 80: RDBAL bea4b080 RDBAH 00000000 RXDCTL 02000000
82576VF 80: 00000200 bytes allocated for TX descriptor ring (32 descriptors).
82576PF 00: VF0 sent RESET
82576PF 00: VF0 is now 00:1b:21:86:a1:4a
82576VF 80: We are 00:1b:21:86:a1:4a
82576VF 80: Asking to be promiscuous.
82576PF 00: VF_SET_PROMISC 40006 ENABLE
82576VF 80: Unrecognized message.
=> hostahci <=
s0: Attaching to CPU 0 (ffffffff 0)
(1) created sc - prio=2 quantum=10000 cpu=0 sigma0.irq 123 - gsi msi
MSI 7b enabled for bdf fa MSI fee00018/7b
DISK controller #1 AHCI fa id 3a228086 mmio d1200000
s0: map self d1200000 -> b1800000 size 1000 offset 0 s 0 typed 1
AHCI: cap e720ffc5 cap2 0 global 80000000 ports 3f version 10200 bohc 0
PORT 0 sig 101
_cl (0xbe9b3400,0xbe9b3800) _ct (0xbe967c00, 0xbe970c00)
HDD SAMSUNG HD080HJ LBA48 sectors 950f8b0
PORT 2 sig 101
_cl (0xbe967400,0xbe967800) _ct (0xbe95cc00, 0xbe965c00)
HDD WDC WD5000AAKS-007AA0 LBA48 sectors 3a386030
PORT 4 sig 101
_cl (0xbe95c400,0xbe95c800) _ct (0xbe951c00, 0xbe95ac00)
HDD OCZ-VERTEX3 LBA48 sectors df94bb0
=> service_config <=
=> service_disk <=
disk: Added '0'
disk: Added '1'
disk: Added '2'
Constructing service /disk...
Service /disk registered.
=> script_start:1 <=
=> script_waitchild <=
=> script_start:2 <=
=> name::/s0/events <=
sc: start 0-1 count 1
s0: [ 5] module 'namespace::/tmp sigma0::mem:16 name::/s0/log name::/s0/timer name::/s0/fs/rom name::/s0/admission name::/s0/disk diskadd disk::0 ||' \
s0: ... ' rom://(nd)/tftpboot/passive//bin/apps/logdisk.nul'
s0: map self 7e000000 -> b0000000 size 1000000 offset 0 s 0 typed 1
s0: [ 5] using memory: 16 MB (1000000) at 7e000000
s0: [ 5] creating PD on CPU 2
(1) **** created admission client 0xf0013 0xf001c
(1) created sc - prio=1 quantum=10000 cpu=2 .main
s0: [ 5, 4284e] pagefault 48/0 for 800027fd err 4 at 800027fd
sc: wait for child 5
s0: [ 5, 4284e] b00027fd b0000000+1000000 -> order 24: b0000000+1000000
s0: [ 5, 4284e] map 0/1 for 800027fd err 4 at 800027fd
s0: [ 5, 4284e] pagefault 48/0 for bffff006 err 4 at 80001487
s0: [ 5, 4284e] be919006 be919000+1000 -> order 12: be919000+1000
s0: [ 5, 4284e] map 0/1 for bffff006 err 4 at 80001487
disk: Added '0p1' 'uuid:bb8984cd-7972-4f9b-831a-9ee7e5b9d04e' 'type:2be899e8-009f-47d1-a069-bd4bc05c20ea' 'name:PEIG_VM'
kb: receive() failed at 325 -- buggy keyboard?
disk: Added '0p2' 'uuid:3e57f5a9-f02d-4857-8447-cd8456e6fa55' 'type:21686148-6449-6e6f-744e-656564454649' 'name:BIOS boot partition'
disk: Added '0p3' 'uuid:2bb67411-8ddb-45e5-b98a-cdc46a87f3eb' 'type:ebd0a0a2-b9e5-4433-87c0-68b6b72699c7' 'name:'
disk: Added '0p4' 'uuid:26807986-695c-4e92-a02c-3766b923e507' 'type:ebd0a0a2-b9e5-4433-87c0-68b6b72699c7' 'name:disk donor test'
disk: Added '0p5' 'uuid:6ec3cb42-3c6d-45c7-a07b-02c85ecf12f2' 'type:2be899e8-009f-47d1-a069-bd4bc05c20ea' 'name:wvtest_playground'
disk: Added '0p6' 'uuid:d7c88b36-13e8-4379-9f85-40fd6e913e63' 'type:2be899e8-009f-47d1-a069-bd4bc05c20ea' 'name:passive-demo'
disk: Added '0p7' 'uuid:c7f7af37-cbbd-497c-91a1-bca55caf36ec' 'type:2be899e8-009f-47d1-a069-bd4bc05c20ea' 'name:passive-cpuload'
disk: Added '0p8' 'uuid:a516be9b-4bde-4a85-b562-e5d290958834' 'type:2be899e8-009f-47d1-a069-bd4bc05c20ea' 'name:passive-reboot'
disk: Added '0p9' 'uuid:c297613e-14d5-4d74-9390-b47e9ae1f359' 'type:2be899e8-009f-47d1-a069-bd4bc05c20ea' 'name:passive-vm1'
disk: Added '0p10' 'uuid:a586ae52-1fcf-47c5-82df-1577460a5f04' 'type:2be899e8-009f-47d1-a069-bd4bc05c20ea' 'name:passive-vm2'
disk: Added '0p11' 'uuid:531945c3-5605-4a2e-ad42-fe7d2707b069' 'type:2be899e8-009f-47d1-a069-bd4bc05c20ea' 'name:passive-vm3'
disk: Added '0p12' 'uuid:145d8ac4-85ad-4b35-aba1-1bf11d1e6721' 'type:2be899e8-009f-47d1-a069-bd4bc05c20ea' 'name:passive-vm4'
disk: Added '0p13' 'uuid:e37af749-a69a-46ee-81f3-4dcd9b8c46d1' 'type:2be899e8-009f-47d1-a069-bd4bc05c20ea' 'name:passive-vm5'
sc: start 1-1 count 1
s0: [ 6] module 'namespace::/s0 name::/s0/events name::/s0/log name::/s0/timer name::/s0/fs/rom name::/s0/admission name::/s0/config sigma0::mem:64 quota::guid name::/s0/disk disk::type:2be899e8-009f-47d1-a069-bd4bc05c20ea ||' \
s0: ... ' rom://(nd)/tftpboot/passive//bin/apps/remote_config.nul linux:rom://(nd)/tftpboot/passive//linux.nulconfig demo:rom://(nd)/tftpboot/passive//passive-demo.nulconfig cpuload:rom://(nd)/tftpboot/passive//cpuload.nulconfig reboot:rom://(nd)/tftpboot/passive//reboot.nulconfig servercert=rom://(nd)/tftpboot/passive//imgs/passive/certSrv.crt serverkey=rom://(nd)/tftpboot/passive//imgs/passive/privkeySrv.der cacert=rom://(nd)/tftpboot/passive//imgs/passive/CAcertSrv.crt template=rom://(nd)/tftpboot/passive//template.config diskuuid=rom://(nd)/tftpboot/passive//diskuuid.config verbose IP_CONFIG'
s0: map self 7a000000 -> 9c000000 size 4000000 offset 0 s 0 typed 2
s0: [ 6] using memory: 64 MB (4000000) at 7a000000
s0: [ 6] creating PD on CPU 3
(1) **** created admission client 0xf001d 0xf001f
(1) created sc - prio=1 quantum=10000 cpu=3 .main
s0: [ 6, 4306e] pagefault 48/0 for 80008285 err 4 at 80008285
sc: done.
s0: => INIT done <=
s0: [ 6, 4306e] 9c008285 9c000000+4000000 -> order 26: 9c000000+4000000
s0: [ 6, 4306e] map 0/1 for 80008285 err 4 at 80008285
s0: [ 6, 4306e] pagefault 48/0 for bffff006 err 4 at 80003a21
s0: [ 6, 4306e] be91e006 be91e000+1000 -> order 12: be91e000+1000
s0: [ 6, 4306e] map 0/1 for bffff006 err 4 at 80003a21
tb: client data 3f002 guid 6 parent 9bc
(6) booting - NOVA daemon ...
(6) done - request timer attach
(1) open - session rebind pseudo=f001d->f2008
(6) - disk 'uuid:6ec3cb42-3c6d-45c7-a07b-02c85ecf12f2'
(6) - disk 'uuid:4867d304-5b38-475f-90ed-00eec3e44907'
(6) - disk 'uuid:88d72d2b-d126-4bd8-80e9-a4ddbb86118f'
(6) - disk 'uuid:2832f211-38dd-44f5-83b7-c6dc86a2a333'
(6) - disk 'uuid:a4817cb4-f2bc-468b-96ec-337a7709c711'
(6) - disk 'uuid:c297613e-14d5-4d74-9390-b47e9ae1f359'
(6) - disk 'uuid:a586ae52-1fcf-47c5-82df-1577460a5f04'
(6) - disk 'uuid:531945c3-5605-4a2e-ad42-fe7d2707b069'
(6) - disk 'uuid:145d8ac4-85ad-4b35-aba1-1bf11d1e6721'
(6) - disk 'uuid:e37af749-a69a-46ee-81f3-4dcd9b8c46d1'
(6) - libvirt protocol version 0xb002
(6) warning - osdepentropyopen not implemented!
(6) warning - tls - entropy not implemented!
(6) done - request network attach
(6) done - mac 00:1b:21:86:a1:4a
(6) - sys_now unimpl.
(6) done - open tcp port 9999, 10000, 10043 - ssl/tls enabled
(6) ....... looking for an IP address via DHCP
(6) update - got ip=141.76.49.60 mask=255.255.255.192 gw=141.76.49.1
(6) ready - NOVA management daemon is up. Waiting for libvirt connection ...
Keeping PID 21047 alive
+ test ''
++ sed -ne '/.*update - got ip=\([^ ]*\).*/ s//\1/p' passive.log
+ NOVA_IP=141.76.49.60
++ sed -ne 's/^Keeping PID \([0-9]\+\) alive$/\1/p' passive.log
+ pid=21047
+ set +x
>>> test 141.76.49.60
! ./libvirt.wv.inc:39 test 141.76.49.60 okok
>>> virsh -c nova+tls://141.76.49.60:9999 nodeinfo | tee log
(6) [tcp] - connection from 141.76.48.80:34462 -> 9999
(6) [tcp] - connection from 141.76.48.80:34556 -> 10000
CPU model: GenuineIntel
CPU(s): 8
CPU frequency: 2702 MHz
CPU socket(s): 1
Core(s) per socket: 4
Thread(s) per core: 2
NUMA cell(s): 1
Memory size: 2087020 kB
eventthread - exiting due to error -9 (A TLS packet with unexpected length was received.)
nova - success - closing mgmt connection ...
nova - success - closing event connection ...
! ./libvirt.wv:10 virsh -c nova+tls://141.76.49.60:9999 nodeinfo | tee log okok
>>> grep CPU model: log
CPU model: GenuineIntel
! ./libvirt.wv:11 grep CPU model: log okok
>>> virsh -c nova+tls://141.76.49.60:9999 list --all | tee log
(6) - authentication request :Hello (6)
(6) ok - op NOVA_AUTH 5 ms, t=3269272
(6) ok - op NOVA_HW_INFO 21 us, t=3278345
(6) [tcp] - incoming connection closed - port 9999
(6) [tcp] - incoming connection closed - port 10000
(6) [tcp] - connection from 141.76.48.80:34464 -> 9999
(6) [tcp] - connection from 141.76.48.80:34558 -> 10000
(6) - authentication request :Hello (6)
(6) ok - op NOVA_AUTH 5 ms, t=3789468
(6) ok - op NOVA_NUM_OF_ACTIVE_DOMAINS 0 us, t=3798481
(6) ok - op NOVA_NUM_OF_DEFINED_DOMAINS 1 us, t=3807138
(6) ok - op NOVA_LIST_DEFINED_DOMAINS 1 us, t=3815614
(6) ok - op NOVA_GET_NAME 0 us, t=3824156
(6) ok - op NOVA_GET_VM_INFO 0 us, t=3832547
(6) ok - op NOVA_GET_VM_INFO 0 us, t=3844586
(6) ok - op NOVA_GET_NAME 0 us, t=3853462
(6) ok - op NOVA_GET_VM_INFO 0 us, t=3862016
(6) ok - op NOVA_GET_VM_INFO 0 us, t=3872646
(6) ok - op NOVA_GET_NAME 1 us, t=3881285
(6) ok - op NOVA_GET_VM_INFO 0 us, t=3889665
Id Name State
----------------------------------
- cpuload no state
- demo no state
- linux no state
- reboot no state
eventthread - exiting due to error -9 (A TLS packet with unexpected length was received.)
nova - success - closing mgmt connection ...
nova - success - closing event connection ...
(6) ok - op NOVA_GET_VM_INFO 1 us, t=3900263
(6) ok - op NOVA_GET_NAME 1 us, t=3908895
(6) ok - op NOVA_GET_VM_INFO 1 us, t=3917424
(6) ok - op NOVA_GET_VM_INFO 0 us, t=3928373
(6) [tcp] - incoming connection closed - port 9999
(6) [tcp] - incoming connection closed - port 10000
! ./libvirt.wv:13 virsh -c nova+tls://141.76.49.60:9999 list --all | tee log okok
>>> grep linux.*no state log
- linux no state
! ./libvirt.wv:14 grep linux.*no state log okok
>>> virsh -c nova+tls://141.76.49.60:9999 start linux
(6) [tcp] - connection from 141.76.48.80:34466 -> 9999
(6) [tcp] - connection from 141.76.48.80:34560 -> 10000
(6) - authentication request :Hello (6)
(6) ok - op NOVA_AUTH 5 ms, t=4289473
(6) ok - op NOVA_GET_NAME 1 us, t=4298511
(6) ok - op NOVA_GET_VM_INFO 0 us, t=4307082
s0: [ 7] module 'name::/s0/log name::/s0/admission name::/s0/events name::/s0/timer name::/s0/fs/rom name::/s0/disk sigma0::mem:256 ||' \
s0: ... ' rom://(nd)/tftpboot/passive//bin/apps/vancouver.nul kbmodifier:0x40000 PC_PS2 82576vf service_events ||' \
s0: ... ' rom://(nd)/tftpboot/passive//bin/boot/munich ||' \
s0: ... ' rom://(nd)/tftpboot/passive//imgs/passive/bzImage vga=0 ||' \
s0: ... ' rom://(nd)/tftpboot/passive//imgs/passive/initramfs '
s0: map self 6a000000 -> 8c000000 size 10000000 offset 0 s 0 typed 8
s0: [ 7] using memory: 256 MB (10000000) at 6a000000
Domain linux started
eventthread - exiting due to error -9 (A TLS packet with unexpected length was received.)
nova - success - closing mgmt connection ...
nova - success - closing event connection ...
! ./libvirt.wv:17 virsh -c nova+tls://141.76.49.60:9999 start linux okok
>>> virsh -c nova+tls://141.76.49.60:9999 list | tee log
s0: [ 7] creating PD on CPU 4
(1) **** created admission client 0xf6004 0xf6005
(1) created sc - prio=1 quantum=10000 cpu=4 .main
s0: [ 7, 4388e] pagefault 48/0 for 80005e7c err 4 at 80005e7c
(6) ok - op NOVA_VM_START 111 ms, t=4433818
s0: [ 7, 4388e] 8c005e7c 8c000000+10000000 -> order 26: 8c000000+4000000
(6) [tcp] - incoming connection closed - port 9999
s0: [ 7, 4388e] map 0/4 for 80005e7c err 4 at 80005e7c
(6) [tcp] - incoming connection closed - port 10000
s0: [ 7, 4388e] pagefault 48/0 for bffff006 err 4 at 80005039
s0: [ 7, 4388e] be91d006 be91d000+1000 -> order 12: be91d000+1000
s0: [ 7, 4388e] map 0/1 for bffff006 err 4 at 80005039
(6) [tcp] - connection from 141.76.48.80:34468 -> 9999
(1) open - session rebind pseudo=f6004->f2009
tb: client data 3f004 guid 7 parent 9bf
(7) Ignored parameter: 'rom://(nd)/tftpboot/passive//bin/apps/vancouver.nul'
(7) => kbmodifier:0x40000 <=
(7) => PC_PS2 <=
(7) => mem:0,0xa0000 <=
(7) physmem: 0 [0, a0000]
(7) => mem:0x100000 <=
(7) physmem: 0 [100000, fc00000]
(7) => ioio <=
(7) => nullio:0x80 <=
(7) => pic:0x20,,0x4d0 <=
(7) => pic:0xa0,2,0x4d1 <=
(7) => pit:0x40,0 <=
(6) [tcp] - connection from 141.76.48.80:34562 -> 10000
(7) => scp:0x92,0x61 <=
(7) => kbc:0x60,1,12 <=
(7) => keyb:0,0x10000 <=
(7) => mouse:1,0x10001 <=
(7) => rtc:0x70,8 <=
(7) => serial:0x3f8,0x4,0x4711 <=
(7) => hostsink:0x4712,80 <=
(7) => vga:0x03c0 <=
(7) Allocating from guest 0fbe0000+20000
(7) VGA console fbe0000+20000 0x8ffe0000
(7) => vbios_disk <=
(7) => vbios_keyboard <=
(7) => hostkeyb:0x10,0x60,1,,1 <=
(7) => vbios_mem <=
(7) => vbios_time <=
(7) => vbios_reset <=
(7) => vbios_multiboot <=
(7) => msi <=
(7) => ioapic <=
(7) => pcihostbridge:0,0x10,0xcf8,0xe0000000 <=
(7) => pmtimer:0x8000 <=
(7) => vcpus <=
(7) => vcpu <=
(7) create VCPU
(1) created sc - prio=1 quantum=10000 cpu=4 vancouver.vcpu
(7) startup
(7) => halifax <=
(7) => vbios <=
(7) => lapic <=
(7) LAPIC freq 168936187
(7) => 82576vf <=
(7) Attached 82576VF model at f7ce0000+0x4000, f7cc0000+0x1000
(7) => service_events <=
(7) void DBus<M>::debug_dump() [with M = MessageHwIOIn]: Bus used 0 times.
(7) 0: StaticReceiver<Y>::StaticReceiver() [with Y = IOAccess]
(7)
(7) do_gsi_boot eip 80004c81 esp 80216fec
(1) created sc - prio=2 quantum=10000 cpu=4 vancouver.timer
(7) do_gsi_boot eip 8000599b esp 80215fec
(1) created sc - prio=2 quantum=10000 cpu=4 vancouver.stdin
(7) do_gsi_boot eip 80005c99 esp 80213fec
(1) created sc - prio=2 quantum=10000 cpu=4 vancouver.net
(7) RESET device state
(6) **** created event client 0xec0b4 0xec121
(6) check - 0x80223664, eventid=48059, event_slot=ffffffff
Id Name State
----------------------------------
5 linux 5 running
eventthread - exiting due to error -9 (A TLS packet with unexpected length was received.)
nova - success - closing mgmt connection ...
nova - success - closing event connection ...
! ./libvirt.wv:18 virsh -c nova+tls://141.76.49.60:9999 list | tee log okok
>>> grep linux.*running log
5 linux 5 running
! ./libvirt.wv:19 grep linux.*running log okok
>>> grep linux.*running log
(6) - got event from guid=7 eventid=bbbb res=0x0 forwarded=no
(7) INIT done
(7) reset CPU from 9 mtr_in e0010
(7) > bool VirtualBiosMultiboot::receive(MessageBios&) rip ffff ilen 0 cr0 10 efl 2
(6) - authentication request :Hello (6)
(7) module 0 start 0x81c00000+934 cmdline rom://(nd)/tftpboot/passive//bin/boot/mu
(6) ok - op NOVA_AUTH 10 ms, t=4794307
(7) module 1 start 0x81c02000+4ad710 cmdline rom://(nd)/tftpboot/passive//imgs/passiv
(6) ok - op NOVA_NUM_OF_ACTIVE_DOMAINS 0 us, t=4813337
(7) module 2 start 0x820b1000+8a2800 cmdline rom://(nd)/tftpboot/passive//imgs/passiv
(6) ok - op NOVA_LIST_ACTIVE_DOMAINS 1 us, t=4830825
(7) # MUNICH: v.0.4.6 starts Linux
(6) ok - op NOVA_GET_NAME_ID 0 us, t=4848925
(7) # MUNICH: 2.6.38-rc4+ (julian@tabernacle) #2 SMP Mon Feb 14 17:09:51 CET 2011
(6) ok - op NOVA_GET_VM_INFO 7 us, t=4861180
(7) # MUNICH: video mode 0
5 linux 5 running
! ./libvirt.wv:20 grep linux.*running log okok
>>> virsh -c nova+tls://141.76.49.60:9999 destroy 5
(6) ok - op NOVA_GET_VM_INFO 3 us, t=4881989
(7) # MUNICH: vga=0
(6) [tcp] - incoming connection closed - port 9999
(7) # MUNICH: initrd 1CB1000
(6) [tcp] - incoming connection closed - port 10000
(7) # MUNICH: copy image
(7) # MUNICH: start kernel
(7) handle_int15 eax e801 ebx 0 ecx 0 edx 0 eip ffff efl 2
(7) handle_int15 eax c000 ebx 0 ecx 0 edx 0 eip ffff efl 2
(7) handle_int15 eax e980 ebx 0 ecx 0 edx 47534943 eip ffff efl 2
(7) VESA 4f00 tag 0 base 40000+3390 esi 0
(6) [tcp] - connection from 141.76.48.80:34470 -> 9999
(6) [tcp] - connection from 141.76.48.80:34564 -> 10000
Domain 5 destroyed
eventthread - exiting due to error -9 (A TLS packet with unexpected length was received.)
nova - success - closing mgmt connection ...
nova - success - closing event connection ...
! ./libvirt.wv:21 virsh -c nova+tls://141.76.49.60:9999 destroy 5 okok
>>> virsh -c nova+tls://141.76.49.60:9999 start linux; start linux
(6) - authentication request :Hello (6)
(6) ok - op NOVA_AUTH 5 ms, t=5299618
(6) warning - tls - entropy not implemented!
(6) ok - op NOVA_GET_NAME_ID 1 us, t=5314100
(6) ok - op NOVA_GET_VM_INFO 7 us, t=5322681
s0: [ 7] - initiate destruction of client ...
s0: [ 7] revoke all caps
s0: [ 7] revoke all memory 0x8c000000 + hip 0xbe91d000
[s 0:4 ][ K7i]l l e dr eEvCo:k0ex c1al2l73 m6e0m0o rSyC :00xx8cc102020e020000 +V :0hixpe 0CxSb:e09x11db0 0E0I P-:0 xdo8n0e0 01d7d CR2:0x 8
0s000:1 d[7 d 7E]R R :b0exf4o r(eP Tl onockt found)
[
4s]0 :K i[l l7e]d E Cd:0etxacc1h2 7n37et0w0o rSkC:0xc122e20 0
sV0::0 x[e 7C]S : 0 xd1ebt aEcIhP :0sxtd8i0n00315c CR2:0 x
s800:0 0[3 175]c -E RdRe:s0txr4u c(tIiPCon Adboonret)
[ 4] Ki l
le(6d) EoCk:0 x c 1 2 7-3 8o0p0 NSOCV:0A_xcVM1_2D2EeS2T0R0O YV : 0 x 3 0 C R 0 : 0 x 11 C R 3 : 0 x409 CmRs4,: 0tx=05 3(I84P2C2 A3 bo rt)
(6) [tcp] - incoming connection closed - port 9999
(6) [tcp] - incoming connection closed - port 10000
(6) [tcp] - connection from 141.76.48.80:34472 -> 9999
(6) [tcp] - connection from 141.76.48.80:34566 -> 10000
(6) - authentication request :Hello (6)
(6) ok - op NOVA_AUTH 5 ms, t=5799974
(6) ok - op NOVA_GET_NAME 1 us, t=5809070
(6) ok - op NOVA_GET_VM_INFO 0 us, t=5817609
s0: [ 7] module 'name::/s0/log name::/s0/admission name::/s0/events name::/s0/timer name::/s0/fs/rom name::/s0/disk sigma0::mem:256 ||' \
s0: ... ' rom://(nd)/tftpboot/passive//bin/apps/vancouver.nul kbmodifier:0x40000 PC_PS2 82576vf service_events ||' \
s0: ... ' rom://(nd)/tftpboot/passive//bin/boot/munich ||' \
s0: ... ' rom://(nd)/tftpboot/passive//imgs/passive/bzImage vga=0 ||' \
s0: ... ' rom://(nd)/tftpboot/passive//imgs/passive/initramfs '
s0: [ 7] using memory: 256 MB (10000000) at 6a000000
Domain linux started
s0: [ 7] creating PD on CPU 5
(1) ad: found dead client - freeing datastructure
(1) ad: found dead client - freeing datastructure
(1) **** created admission client 0xf6006 0xf6004
(1) created sc - prio=1 quantum=10000 cpu=5 .main
s0: [ 7, 438ae] pagefault 48/0 for 80005e7c err 4 at 80005e7c
(6) ok - op NOVA_VM_START 114 ms, t=5946230
s0: [ 7, 438ae] 8c005e7c 8c000000+10000000 -> order 26: 8c000000+4000000
(6) ok - op NOVA_GET_NAME 0 us, t=5962207
s0: [ 7, 438ae] map 0/4 for 80005e7c err 4 at 80005e7c
s0: [ 8] module 's0: [ 7, 438ae] pagefault 48/0 for bffff006 err 4 at 80005039
ns0: [ 7, 438ae] be91d006 be91d000+1000 -> order 12: be91d000+1000
as0: [ 7, 438ae] map 0/1 for bffff006 err 4 at 80005039
m(1) open - session rebind pseudo=f6006->f200a
etb: found dead client - freeing datastructure
:tb: client data 3f006 guid 7 parent 9c2
:(7) Ignored parameter: 'rom://(nd)/tftpboot/passive//bin/apps/vancouver.nul'
/(7) => kbmodifier:0x40000 <=
s(7) => PC_PS2 <=
0(7) => mem:0,0xa0000 <=
/(7) physmem: 0 [0, a0000]
l(7) => mem:0x100000 <=
o(7) physmem: 0 [100000, fc00000]
g(7) => ioio <=
(7) => nullio:0x80 <=
n(7) => pic:0x20,,0x4d0 <=
a(7) => pic:0xa0,2,0x4d1 <=
m(7) => pit:0x40,0 <=
e(7) => scp:0x92,0x61 <=
:(7) => kbc:0x60,1,12 <=
:(7) => keyb:0,0x10000 <=
/(7) => mouse:1,0x10001 <=
s(7) => rtc:0x70,8 <=
0(7) => serial:0x3f8,0x4,0x4711 <=
/(7) => hostsink:0x4712,80 <=
a(7) => vga:0x03c0 <=
d(7) Allocating from guest 0fbe0000+20000
m(7) VGA console fbe0000+20000 0x8ffe0000
i(7) => vbios_disk <=
s(7) => vbios_keyboard <=
s(7) => hostkeyb:0x10,0x60,1,,1 <=
i(7) => vbios_mem <=
o(7) => vbios_time <=
n(7) => vbios_reset <=
(7) => vbios_multiboot <=
n(7) => msi <=
a(7) => ioapic <=
m(7) => pcihostbridge:0,0x10,0xcf8,0xe0000000 <=
e(7) => pmtimer:0x8000 <=
:(7) => vcpus <=
:(7) => vcpu <=
/(7) create VCPU
s(1) created sc - prio=1 quantum=10000 cpu=5 vancouver.vcpu
0(7) startup
/(7) => halifax <=
e(7) => vbios <=
v(7) => lapic <=
e(7) LAPIC freq 168936187
n(7) => 82576vf <=
t(7) Attached 82576VF model at f7ce0000+0x4000, f7cc0000+0x1000
s(7) => service_events <=
(7) void DBus<M>::debug_dump() [with M = MessageHwIOIn]: Bus used 0 times.
n(7) 0: StaticReceiver<Y>::StaticReceiver() [with Y = IOAccess]
a(7)
m(7) do_gsi_boot eip 80004c81 esp 80216fec
e(1) created sc - prio=2 quantum=10000 cpu=5 vancouver.timer
:(7) do_gsi_boot eip 8000599b esp 80215fec
:(1) created sc - prio=2 quantum=10000 cpu=5 vancouver.stdin
/(7) do_gsi_boot eip 80005c99 esp 80213fec
s(1) created sc - prio=2 quantum=10000 cpu=5 vancouver.net
0(7) RESET device state
/(6) ad: found dead client - freeing datastructure
t(6) **** created event client 0xec0d8 0xec123
i(6) check - 0x80223664, eventid=48059, event_slot=ffffffff
m(6) - got event from guid=7 eventid=bbbb res=0x0 forwarded=no
e(7) INIT done
r(7) reset CPU from 9 mtr_in e0010
n(7) > bool VirtualBiosMultiboot::receive(MessageBios&) rip ffff ilen 0 cr0 10 efl 2
a(7) module 0 start 0x81c00000+934 cmdline rom://(nd)/tftpboot/passive//bin/boot/mu
me::/s0/fs/rom name::/s0/d(7) module 1 start 0x81c02000+4ad710 cmdline rom://(nd)/tftpboot/passive//imgs/passiv
isk sigma0::mem:256 ||' \
s0: ... '(7) module 2 start 0x820b1000+8a2800 cmdline rom://(nd)/tftpboot/passive//imgs/passiv
r(7) # MUNICH: v.0.4.6 starts Linux
om(7) # MUNICH: 2.6.38-rc4+ (julian@tabernacle) #2 SMP Mon Feb 14 17:09:51 CET 2011
:(7) # MUNICH: video mode 0
/(7) # MUNICH: vga=0
/(7) # MUNICH: initrd 1CB1000
((7) # MUNICH: copy image
nd)/tftpboot/passive//(7) # MUNICH: start kernel
bin/apps/vancouv(7) handle_int15 eax e801 ebx 0 ecx 0 edx 0 eip ffff efl 2
er.n(7) handle_int15 eax c000 ebx 0 ecx 0 edx 0 eip ffff efl 2
ul(7) handle_int15 eax e980 ebx 0 ecx 0 edx 47534943 eip ffff efl 2
kbmodifie(7) VESA 4f00 tag 0 base 40000+3390 esi 0
r:0x40000 PC_PS2 82576vf service_events ||' \
s0: ... ' rom://(nd)/tftpboot/passive//bin/boot/munich ||' \
s0: ... ' rom://(nd)/tftpboot/passive//imgs/passive/bzImage vga=0 ||' \
s0: ... ' rom://(nd)/tftpboot/passive//imgs/passive/initramfs '
s0: map self 5a000000 -> 7c000000 size 10000000 offset 0 s 0 typed 8
s0: [ 8] using memory: 256 MB (10000000) at 5a000000
Domain linux started
s0: [ 8] creating PD on CPU 6
(1) **** created admission client 0xf6005 0xf6006
(1) created sc - prio=1 quantum=10000 cpu=6 .main
s0: [ 8, 440ce] pagefault 48/0 for 80005e7c err 4 at 80005e7c
eventthread - exiting due to error -9 (A TLS packet with unexpected length was received.)
nova - success - closing mgmt connection ...
nova - success - closing event connection ...
! ./libvirt.wv:24 virsh -c nova+tls://141.76.49.60:9999 start linux; start linux okok
>>> virsh -c nova+tls://141.76.49.60:9999 list --all | tee log
(6) ok - op NOVA_VM_START 512 ms, t=6491819
s0: [ 8, 440ce] 7c005e7c 7c000000+10000000 -> order 26: 7c000000+4000000
(6) [tcp] - incoming connection closed - port 9999
s0: [ 8, 440ce] map 0/4 for 80005e7c err 4 at 80005e7c
(6) [tcp] - incoming connection closed - port 10000
s0: [ 8, 440ce] pagefault 48/0 for bffff006 err 4 at 80005039
s0: [ 8, 440ce] be91c006 be91c000+1000 -> order 12: be91c000+1000
(6) [tcp] - connection from 141.76.48.80:34474 -> 9999
s0: [ 8, 440ce] map 0/1 for bffff006 err 4 at 80005039
(1) open - session rebind pseudo=f6005->f200b
tb: client data 3f008 guid 8 parent 9c5
(8) Ignored parameter: 'rom://(nd)/tftpboot/passive//bin/apps/vancouver.nul'
(8) => kbmodifier:0x40000 <=
(8) => PC_PS2 <=
(8) => mem:0,0xa0000 <=
(8) physmem: 0 [0, a0000]
(8) => mem:0x100000 <=
(8) physmem: 0 [100000, fc00000]
(8) => ioio <=
(8) => nullio:0x80 <=
(8) => pic:0x20,,0x4d0 <=
(8) => pic:0xa0,2,0x4d1 <=
(8) => pit:0x40,0 <=
(8) => scp:0x92,0x61 <=
(8) => kbc:0x60,1,12 <=
(8) => keyb:0,0x10000 <=
(8) => mouse:1,0x10001 <=
(8) => rtc:0x70,8 <=
(8) => serial:0x3f8,0x4,0x4711 <=
(8) => hostsink:0x4712,80 <=
(8) => vga:0x03c0 <=
(8) Allocating from guest 0fbe0000+20000
(8) VGA console fbe0000+20000 0x8ffe0000
(8) => vbios_disk <=
(8) => vbios_keyboard <=
(8) => hostkeyb:0x10,0x60,1,,1 <=
(8) => vbios_mem <=
(8) => vbios_time <=
(8) => vbios_reset <=
(8) => vbios_multiboot <=
(8) => msi <=
(8) => ioapic <=
(8) => pcihostbridge:0,0x10,0xcf8,0xe0000000 <=
(8) => pmtimer:0x8000 <=
(8) => vcpus <=
(8) => vcpu <=
(8) create VCPU
(1) created sc - prio=1 quantum=10000 cpu=6 vancouver.vcpu
(8) startup
(8) => halifax <=
(8) => vbios <=
(8) => lapic <=
(8) LAPIC freq 168936187
(8) => 82576vf <=
(8) Attached 82576VF model at f7ce0000+0x4000, f7cc0000+0x1000
(8) => service_events <=
(8) void DBus<M>::debug_dump() [with M = MessageHwIOIn]: Bus used 0 times.
(8) 0: StaticReceiver<Y>::StaticReceiver() [with Y = IOAccess]
(8)
(6) [tcp] - connection from 141.76.48.80:34568 -> 10000
(8) do_gsi_boot eip 80004c81 esp 80216fec
(1) created sc - prio=2 quantum=10000 cpu=6 vancouver.timer
(8) do_gsi_boot eip 8000599b esp 80215fec
(1) created sc - prio=2 quantum=10000 cpu=6 vancouver.stdin
(8) do_gsi_boot eip 80005c99 esp 80213fec
(1) created sc - prio=2 quantum=10000 cpu=6 vancouver.net
(8) RESET device state
(6) **** created event client 0xec0fc 0xec121
(6) check - 0x802237d8, eventid=48059, event_slot=ffffffff
(6) - got event from guid=8 eventid=bbbb res=0x0 forwarded=no
(8) INIT done
(8) reset CPU from 9 mtr_in e0010
(8) > bool VirtualBiosMultiboot::receive(MessageBios&) rip ffff ilen 0 cr0 10 efl 2
(8) module 0 start 0x81c00000+934 cmdline rom://(nd)/tftpboot/passive//bin/boot/mu
(8) module 1 start 0x81c02000+4ad710 cmdline rom://(nd)/tftpboot/passive//imgs/passiv
(8) module 2 start 0x820b1000+8a2800 cmdline rom://(nd)/tftpboot/passive//imgs/passiv
(8) # MUNICH: v.0.4.6 starts Linux
(8) # MUNICH: 2.6.38-rc4+ (julian@tabernacle) #2 SMP Mon Feb 14 17:09:51 CET 2011
(8) # MUNICH: video mode 0
(8) # MUNICH: vga=0
(8) # MUNICH: initrd 1CB1000
(8) # MUNICH: copy image
(8) # MUNICH: start kernel
(8) handle_int15 eax e801 ebx 0 ecx 0 edx 0 eip ffff efl 2
(8) handle_int15 eax c000 ebx 0 ecx 0 edx 0 eip ffff efl 2
(8) handle_int15 eax e980 ebx 0 ecx 0 edx 47534943 eip ffff efl 2
(8) VESA 4f00 tag 0 base 40000+3390 esi 0
(6) - authentication request :Hello (6)
(6) ok - op NOVA_AUTH 5 ms, t=6990008
(6) ok - op NOVA_NUM_OF_ACTIVE_DOMAINS 1 us, t=6998968
(6) ok - op NOVA_LIST_ACTIVE_DOMAINS 0 us, t=7007397
(6) ok - op NOVA_NUM_OF_DEFINED_DOMAINS 1 us, t=7015886
(6) ok - op NOVA_LIST_DEFINED_DOMAINS 1 us, t=7024570
(6) ok - op NOVA_GET_NAME_ID 0 us, t=7033318
(6) ok - op NOVA_GET_VM_INFO 7 us, t=7041798
(6) ok - op NOVA_GET_VM_INFO 2 us, t=7053758
(6) ok - op NOVA_GET_NAME_ID 0 us, t=7062516
(6) ok - op NOVA_GET_VM_INFO 3 us, t=7070866
(6) ok - op NOVA_GET_VM_INFO 3 us, t=7081354
(6) ok - op NOVA_GET_NAME 0 us, t=7089933
Id Name State
----------------------------------
5 linux 5 running
6 linux 6 running
- cpuload no state
- demo no state
- linux no state
- reboot no state
eventthread - exiting due to error -9 (A TLS packet with unexpected length was received.)
nova - success - closing mgmt connection ...
nova - success - closing event connection ...
! ./libvirt.wv:25 virsh -c nova+tls://141.76.49.60:9999 list --all | tee log okok
(6) ok - op NOVA_GET_VM_INFO 0 us, t=7098332
(6) ok - op NOVA_GET_VM_INFO 0 us, t=7108764
(6) ok - op NOVA_GET_NAME 1 us, t=7117304
(6) ok - op NOVA_GET_VM_INFO 0 us, t=7125690
(6) ok - op NOVA_GET_VM_INFO 1 us, t=7136241
(6) ok - op NOVA_GET_NAME 0 us, t=7144767
(6) ok - op NOVA_GET_VM_INFO 0 us, t=7153241
(6) check - 0x80223664, eventid=48064, event_slot=ffffffff
(6) ok - op NOVA_GET_VM_INFO 0 us, t=7164335
(6) - got event from guid=7 eventid=bbc0 res=0x0 forwarded=no
(6) ok - op NOVA_GET_NAME 1 us, t=7176312
(6) ok - op NOVA_GET_VM_INFO 0 us, t=7191501
(6) ok - op NOVA_GET_VM_INFO 0 us, t=7202133
(6) [tcp] - incoming connection closed - port 9999
(6) [tcp] - incoming connection closed - port 10000
>>> test 2 -eq 2
! ./libvirt.wv:26 test 2 -eq 2 okok
>>> virsh -c nova+tls://141.76.49.60:9999 destroy 5; destroy 6
(6) [tcp] - connection from 141.76.48.80:34476 -> 9999
(6) check - 0x80223664, eventid=48064, event_slot=ffffffff
(6) - got event from guid=7 eventid=bbc0 res=0x0 forwarded=no
(6) check - 0x80223664, eventid=48064, event_slot=ffffffff
(6) - got event from guid=7 eventid=bbc0 res=0x0 forwarded=no
(6) check - 0x80223664, eventid=48064, event_slot=ffffffff
(6) - got event from guid=7 eventid=bbc0 res=0x0 forwarded=no
(6) [tcp] - connection from 141.76.48.80:34570 -> 10000
(6) check - 0x802237d8, eventid=48064, event_slot=ffffffff
(6) - got event from guid=8 eventid=bbc0 res=0x0 forwarded=no
(6) check - 0x80223664, eventid=48064, event_slot=ffffffff
(6) - got event from guid=7 eventid=bbc0 res=0x0 forwarded=no
(6) check - 0x80223664, eventid=48064, event_slot=ffffffff
(6) - got event from guid=7 eventid=bbc0 res=0x0 forwarded=no
(6) - authentication request :Hello (6)
(6) check - 0x80223664, eventid=48064, event_slot=ffffffff
(6) ok - op NOVA_AUTH 6 ms, t=7740740
(6) - got event from guid=7 eventid=bbc0 res=0x0 forwarded=no
(6) ok - op NOVA_GET_NAME_ID 0 us, t=7756454
(6) check - 0x80223664, eventid=48064, event_slot=ffffffff
(6) ok - op NOVA_GET_VM_INFO 8 us, t=7771389
(6) - got event from guid=7 eventid=bbc0 res=0x0 forwarded=no
Domain 5 destroyed
s0: [ 7] - initiate destruction of client ...
(6) check - 0x80223664, eventid=48064, event_slot=ffffffff
s0: [ 7] revoke all caps
(6) - got event from guid=7 eventid=bbc0 res=0x0 forwarded=no
s0: [ 7] revoke all memory 0x8c000000 + hip 0xbe91d000
(6) - got event from guid=45054 eventid=bbc0 res=0x105 forwarded=no
s[0 5: ] [ Ki7]ll ed r EevC:ok0xe c1al27l 3dm0em0 orSCy :00xxc8c10220e0048000 +V: h0xipe 0CSxb:0e9x11db 00EI0 P:- 0xdo80ne00 1d7d CR2:0x 8
0(06)01 dch7dec Ek RR :0- x40x (80PT22 n37otd8 f, ouevnde)nt
id=48064, event_slot=ffffffff
s0: [ 7[] 5 ] beKifollree d loECck:0xc1273e00 S
(C6:)0x c 12 2e 48 0 -V g:0otxe e CvSen:0t x1frb omEI gP:ui0xd8=800 e00ve08nt5 idCR=b2:bc0x08 r00es00=008x05 EfoRRrw:0arx4de (d=InPCo Abort)
s0: [ 7] detach network
s0: [ 7] [ d5et] acKih llstedd inEC:0xc1273f0 0
s0SC: :[0 xc71] 22- ed48es0 trV:uc0xt1ioe n CdR0on:e0x8005003b C
R(36:)0 x1oak 3f 00 0 -CR o4:p 0xNO0 VA(I_VPCM _DAbESorTRt)OY
96 ms, t=7885622
(6) ok - op NOVA_GET_NAME_ID 0 us, t=7899278
(6) check - 0x802237d8, eventid=48064, event_slot=ffffffff
(6) ok - op NOVA_GET_VM_INFO 4 us, t=7907917
(6) - got event from guid=8 eventid=bbc0 res=0x0 forwarded=no
s0: [ 8] - initiate destruction of client ...
(6) check - 0x802237d8, eventid=48064, event_slot=ffffffff
s0: [ 8] revoke all caps
(6) - got event from guid=8 eventid=bbc0 res=0x0 forwarded=no
s0: [ 8] revoke all memory 0x7c000000 + hip 0xbe91c000
[ 6] Killed EC:0xc0130500 SC:0xc122e700 V:0xe CS:0x1b EIP:0x80001d7d CR2:0x80001d7d ERR:0x4 (PT not found)
[ 6] Killed EC:0xc0130600 SC:0xc122e700 V:0xe CS:0x1b EIP:0x8000315c CR2:0x8000315c ERR:0x4 (IPC Abort)
[ 6] Killed EC:0xc0130700 SC:0xc122e700 V:0x30 CR0:0x8005003b CR3:0x1a3f000 CR4:0x0 (IPC Abort)
[ 6] Killed EC:0xc0130800 SC:0xc122e780 V:0xe CS:0x1b EIP:0x80000085 CR2:0x80000085 ERR:0x4 (IPC Timeout)
s0: [ 8] revoke all memory 0x7c000000 + hip 0xbe91c000 - done
s0: [ 8] before lock
s0: [ 8] detach network
s0: [ 8] detach stdin
Domain 6 destroyed
eventthread - exiting due to error -9 (A TLS packet with unexpected length was received.)
nova - success - closing mgmt connection ...
nova - success - closing event connection ...
s0: [ 8] - destruction done
(6) ok - op NOVA_VM_DESTROY 109 ms, t=8035589
! ./libvirt.wv:27 virsh -c nova+tls://141.76.49.60:9999 destroy 5; destroy 6 okok
+ trap - EXIT
+ '[' 21047 ']'
+ kill 21047
! /home/sojka/bin/wvnulrun: $tests_executed > 0 okok