Re: [ovirt-users] fakevdsm vs ovirt engine 4.0.4

18 Oct 2016

      Hi,

On Tue, Oct 18, 2016 at 12:28 AM, <joost@familiealbers.nl> wrote:
...
Hi All,
I am trying to start load tests against my newly installed ovirt engine
version 4.0.4
Note that latest fakevdsm is currently broken when using it with 4.0
releases, it only works on master.

You can check out the code from here

https://gerrit.ovirt.org/#/c/65333/

to get a working version for 4.0.
...
I can run fakevdsm as follows (minor changes to pom.xml (mainly jetty
plugin version)
i also changed vdsm-jsonrpc-java-client to match that of the server.
<groupId>org.ovirt.vdsm-jsonrpc-java</groupId>
                        <artifactId>vdsm-jsonrpc-java-client</artifactId>
                        <version>1.2.5</version>
i can run the app using
mvn jetty:run -Dfake.host=0.0.0.0 -DjsonListenPort=54321 -DvdsmPort=54322
could you try running "mvn clean" before starting it again? Fakevdsm has a
cache and if something is wrong with that data there (e.g. a bug or
switching between engine versions) you can end up in such situations.
Running "mvn clean" cleans the cache too.
...
fakevdsm runs but keeps on looping over the following operations. I have
not add vms yet.
I sincerely hope someone can help me , i really need to stress test this
install urgently and fakevdsm looks like the perfect tool to use for this.
thanks, joost
2016-10-17 22:24:59,505 CONNECT
accept-version:1.2
heart-beat:0,21234
host:null
2016-10-17 22:24:59,506 CONNECT
accept-version:1.2
heart-beat:0,21234
host:null
2016-10-17 22:24:59,507 CONNECTED
heart-beat:21234,0
session:196ed7d6-5125-4608-8299-bcaef308db87
2016-10-17 22:24:59,507 Message sent: CONNECTED
heart-beat:21234,0
session:196ed7d6-5125-4608-8299-bcaef308db87
2016-10-17 22:24:59,636 SUBSCRIBE
destination:jms.topic.vdsm_responses
ack:auto
id:9c734bb3-2dc4-4181-a049-2b77133b521f
SEND
destination:jms.topic.vdsm_requests
reply-to:jms.topic.vdsm_responses
content-length:105
{"jsonrpc":"2.0","method":"Host.getCapabilities","params":{}
,"id":"9f78265b-21c3-4e77-9b11-7c126c2d84ed"}
2016-10-17 22:24:59,636 SUBSCRIBE
destination:jms.topic.vdsm_responses
ack:auto
id:9c734bb3-2dc4-4181-a049-2b77133b521f
2016-10-17 22:24:59,636 ACK
id:9c734bb3-2dc4-4181-a049-2b77133b521f
2016-10-17 22:24:59,636 Message sent: ACK
id:9c734bb3-2dc4-4181-a049-2b77133b521f
2016-10-17 22:24:59,636 SEND
destination:jms.topic.vdsm_requests
reply-to:jms.topic.vdsm_responses
content-length:105
{"jsonrpc":"2.0","method":"Host.getCapabilities","params":{}
,"id":"9f78265b-21c3-4e77-9b11-7c126c2d84ed"}
2016-10-17 22:24:59,637 client policy identifier null
2016-10-17 22:24:59,714 Request is Host.getCapabilities got response
{"jsonrpc":"2.0","result":{"version_name":"Snow
Man","operatingSystem":{"name":"Fedora","release":"1","versi
on":"17"},"cpuSpeed":"1200.000","clusterLevels":["3.0","
3.1","3.2","3.3","3.4","3.5","3.6","4.0","4.1"],"hooks":{},"
ISCSIInitiatorName":"iqn.1994-05.com.example:ef52ec17bb0","
cpuSockets":"1","kvmEnabled":"true","reservedMem":"321","las
tClientIface":"ovirtmgmt","numaNodes":{"1":{"cpus":[0,2,4,6,
8,10,12,14],"totalMemory":3988},"0":{"cpus":[1,3,5,7,9,11,
13,15],"totalMemory":3988}},"cpuFlags":"fpu,vme,de,pse,tsc,
msr,pae,mce,cx8,apic,sep,mtrr,pge,mca,cmov,pat,pse36,clflush
,mmx,fxsr,sse,sse2,ss,syscall,nx,pdpe1gb,rdtscp,lm,constant_
tsc,rep_good,nopl,eagerfpu,pni,pclmulqdq,vmx,ssse3,fma,
cx16,pcid,sse4_1,sse4_2,x2apic,movbe,popcnt,tsc_
deadline_timer,aes,xsave,avx,f16c,rdrand,hypervisor,lahf_
lm,abm,tpr_shadow,vnmi,flexpriority,ept,fsgsbase,bmi1,avx2,
smep,bmi2,erms,invpcid,xsaveopt,model_Haswell-noTSX,
model_Nehalem,model_Conroe,model_Penryn,model_Westmere,
model_SandyBridge","HBAInventory":{"iSCSI":[{"InitiatorName":"iqn.1994-0
5.com.ex
ample:ef52ec17bb0"}],"FC":[]},"lastClient":"10.36.6.76","sel
inux":{"mode":"1"},"vlans":{},"software_version":"4.10","kdu
mpStatus":"1","emulatedMachines":["pc-0.10","pc-0.11","pc-0.
12","pc-0.13","pc-0.14","pc-0.15","pc-1.0","pc-1.0","pc-
i440fx-2.1","pseries-rhel7.2.0","pc-i440fx-rhel7.2.0","
rhel6.4.0","rhel6.5.0","rhel6.6.0","rhel6.7.0","rhel6.8.0","
rhel6.9.0","rhel7.0.0","rhel7.2.0","rhel7.5.0","pc","isapc"]
,"vmTypes":["kvm"],"software_revision":"0.141","bridges":{"
ovirtmgmt":{"mtu":"1500","ports":["em1"],"gateway":"252.
197.29.20","addr":"186.190.35.84","cfg":{"DELAY":"0","
DEVICE":"ovirtmgmt","ONBOOT":"yes","BOOTPROTO":"dhcp","TYPE"
:"Ethernet"},"stp":"off","netmask":"255.255.252.0"}},"netCon
figDirty":"False","autoNumaBalancing":"1","guestOverhead":"
65","networks":{"ovirtmgmt":{"mtu":"1500","ports":["em1"],"
iface":"ovirtmgmt","gateway":"10.34.63.254","bridged":true,"
switch":"legacy","addr":"186.190.35.84","stp":"off","cfg":{"
DELAY":"0","DEVICE":"ovirtmgmt","ONBOOT":"yes","BOOT
PROTO":"
dhcp","TYPE":"Ethernet"},"netmask":"255.255.252.0"}},"memSiz
e":"7976","rngSources":["RANDOM"],"management_ip":"","s
upportedENGINEs":["3.0","3.1","3.2","3.3","3.4","3.5","3.6",
"4.0","4.1"],"cpuModel":"Intel(R) Xeon(R) CPU E5606 @
2.13GHz","cpuCores":"4","supportedProtocols":["2.2","2.3"],"
packages2":{"libvirt":{"buildtime":"1349642820","release":"
2.fc17","version":"1.0.1"},"spice-server":{"buildtime":"
1336983054","release":"5.fc17","version":"0.10.1"},"vdsm":{"
buildtime":"1359653302","release":"0.141.gita11e8f2.
fc17","version":"4.10.3"},"qemu-kvm":{"buildtime":"
1349642820","release":"2.fc17","version":"1.0.1"},"qemu-img"
:{"buildtime":"1349642820","release":"2.fc17","version":"
1.0.1"},"kernel":{"buildtime":"1357699251.0","release":"5.
fc17.x86_64","version":"3.6.11"},"mom":{"buildtime":"
1354824066","release":"1.fc17","version":"0.3.0"}},"uuid":"
7e6d8c6a-ca0c-46d2-ae6e-3af293fa6c4a_80:0D:F4:97:64:
9B:3A","nics":{"em1":{"mtu":"1500","speed":1000,"addr":"","
hwaddr":"55:2B:B0:FD:88:55","cfg":{"NM_CONTROLLED":"yes","
NETBOOT":"yes","DEVICE":"em1","NAME":"Boot Disk","HWADDR":"55:2B:B0:FD:88
:55","BRIDGE":"ovirtmgmt","UUID":"f1962c67-78ab-4b2b-8095-
07354d2eba73","ONBOOT":"yes","BOOTPROTO":"dhcp","TYPE":"
Ethernet"},"netmask":""},"em2":{"mtu":"1500","speed":1000,"
addr":"","hwaddr":"F3:1F:F7:F0:29:76","cfg":{"NM_CONTROLLE
D":"yes","NETBOOT":"yes","DEVICE":"em2","HWADDR":"F3:1F:
F7:F0:29:76","BRIDGE":"ovirtmgmt","UUID":"6f563109-b336-
4091-96d8-18c2c323189e","ONBOOT":"no","BOOTPROTO":"dhcp","
TYPE":"Ethernet"},"netmask":""}},"numaNodeDistance":{"1":["
20","10"],"0":["10","20"]},"onlineCpus":[1,3,5,7,9,11,13,15,
0,2,4,6,8,10,12,14],"cpuThreads":"4","bondings":{"bond4":{"
mtu":"150","slaves":[],"addr":"","hwaddr":"00:00:00:00:00:
00","cfg":{},"netmask":""},"bond3":{"mtu":"150","slaves":[
],"addr":"","hwaddr":"00:00:00:00:00:00","cfg":{},"
netmask":""},"bond0":{"mtu":"150","slaves":[],"addr":"","
hwaddr":"00:00:00:00:00:00","cfg":{},"netmask":""},"bond1":
{"mtu":"150","slaves":[],"addr":"","hwaddr":"00:00:00:
00:00:00","cfg":{},"netmask":""},"bond2":{"m
tu":"150
","slaves":[],"addr":"","hwaddr":"00:00:00:00:00:00","cfg":{
},"netmask":""}}},"id":"9f78265b-21c3-4e77-9b11-7c126c2d84ed"}
2016-10-17 22:24:59,726 MESSAGE
destination:jms.queue.reponses
content-length:4230
{"jsonrpc":"2.0","result":{"version_name":"Snow
Man","operatingSystem":{"name":"Fedora","release":"1","versi
on":"17"},"cpuSpeed":"1200.000","clusterLevels":["3.0","
3.1","3.2","3.3","3.4","3.5","3.6","4.0","4.1"],"hooks":{},"
ISCSIInitiatorName":"iqn.1994-05.com.example:ef52ec17bb0","
cpuSockets":"1","kvmEnabled":"true","reservedMem":"321","las
tClientIface":"ovirtmgmt","numaNodes":{"1":{"cpus":[0,2,4,6,
8,10,12,14],"totalMemory":3988},"0":{"cpus":[1,3,5,7,9,11,
13,15],"totalMemory":3988}},"cpuFlags":"fpu,vme,de,pse,tsc,
msr,pae,mce,cx8,apic,sep,mtrr,pge,mca,cmov,pat,pse36,clflush
,mmx,fxsr,sse,sse2,ss,syscall,nx,pdpe1gb,rdtscp,lm,constant_
tsc,rep_good,nopl,eagerfpu,pni,pclmulqdq,vmx,ssse3,fma,
cx16,pcid,sse4_1,sse4_2,x2apic,movbe,popcnt,tsc_
deadline_timer,aes,xsave,avx,f16c,rdrand,hypervisor,lahf_
lm,abm,tpr_shadow,vnmi,flexpriority,ept,fsgsbase,bmi1,avx2,
smep,bmi2,erms,invpcid,xsaveopt,model_Haswell-noTSX,
model_Nehalem,model_Conroe,model_Penryn,model_Westmere,
model_SandyBridge","HBAInventory":{"iSCSI":[{"InitiatorName":"iqn.1994-0
5.com.ex
ample:ef52ec17bb0"}],"FC":[]},"lastClient":"10.36.6.76","sel
inux":{"mode":"1"},"vlans":{},"software_version":"4.10","kdu
mpStatus":"1","emulatedMachines":["pc-0.10","pc-0.11","pc-0.
12","pc-0.13","pc-0.14","pc-0.15","pc-1.0","pc-1.0","pc-
i440fx-2.1","pseries-rhel7.2.0","pc-i440fx-rhel7.2.0","
rhel6.4.0","rhel6.5.0","rhel6.6.0","rhel6.7.0","rhel6.8.0","
rhel6.9.0","rhel7.0.0","rhel7.2.0","rhel7.5.0","pc","isapc"]
,"vmTypes":["kvm"],"software_revision":"0.141","bridges":{"
ovirtmgmt":{"mtu":"1500","ports":["em1"],"gateway":"252.
197.29.20","addr":"186.190.35.84","cfg":{"DELAY":"0","
DEVICE":"ovirtmgmt","ONBOOT":"yes","BOOTPROTO":"dhcp","TYPE"
:"Ethernet"},"stp":"off","netmask":"255.255.252.0"}},"netCon
figDirty":"False","autoNumaBalancing":"1","guestOverhead":"
65","networks":{"ovirtmgmt":{"mtu":"1500","ports":["em1"],"
iface":"ovirtmgmt","gateway":"10.34.63.254","bridged":true,"
switch":"legacy","addr":"186.190.35.84","stp":"off","cfg":{"
DELAY":"0","DEVICE":"ovirtmgmt","ONBOOT":"yes","BOOT
PROTO":"
dhcp","TYPE":"Ethernet"},"netmask":"255.255.252.0"}},"memSiz
e":"7976","rngSources":["RANDOM"],"management_ip":"","s
upportedENGINEs":["3.0","3.1","3.2","3.3","3.4","3.5","3.6",
"4.0","4.1"],"cpuModel":"Intel(R) Xeon(R) CPU E5606 @
2.13GHz","cpuCores":"4","supportedProtocols":["2.2","2.3"],"
packages2":{"libvirt":{"buildtime":"1349642820","release":"
2.fc17","version":"1.0.1"},"spice-server":{"buildtime":"
1336983054","release":"5.fc17","version":"0.10.1"},"vdsm":{"
buildtime":"1359653302","release":"0.141.gita11e8f2.
fc17","version":"4.10.3"},"qemu-kvm":{"buildtime":"
1349642820","release":"2.fc17","version":"1.0.1"},"qemu-img"
:{"buildtime":"1349642820","release":"2.fc17","version":"
1.0.1"},"kernel":{"buildtime":"1357699251.0","release":"5.
fc17.x86_64","version":"3.6.11"},"mom":{"buildtime":"
1354824066","release":"1.fc17","version":"0.3.0"}},"uuid":"
7e6d8c6a-ca0c-46d2-ae6e-3af293fa6c4a_80:0D:F4:97:64:
9B:3A","nics":{"em1":{"mtu":"1500","speed":1000,"addr":"","
hwaddr":"55:2B:B0:FD:88:55","cfg":{"NM_CONTROLLED":"yes","
NETBOOT":"yes","DEVICE":"em1","NAME":"Boot Disk","HWADDR":"55:2B:B0:FD:88
:55","BRIDGE":"ovirtmgmt","UUID":"f1962c67-78ab-4b2b-8095-
07354d2eba73","ONBOOT":"yes","BOOTPROTO":"dhcp","TYPE":"
Ethernet"},"netmask":""},"em2":{"mtu":"1500","speed":1000,"
addr":"","hwaddr":"F3:1F:F7:F0:29:76","cfg":{"NM_CONTROLLE
D":"yes","NETBOOT":"yes","DEVICE":"em2","HWADDR":"F3:1F:
F7:F0:29:76","BRIDGE":"ovirtmgmt","UUID":"6f563109-b336-
4091-96d8-18c2c323189e","ONBOOT":"no","BOOTPROTO":"dhcp","
TYPE":"Ethernet"},"netmask":""}},"numaNodeDistance":{"1":["
20","10"],"0":["10","20"]},"onlineCpus":[1,3,5,7,9,11,13,15,
0,2,4,6,8,10,12,14],"cpuThreads":"4","bondings":{"bond4":{"
mtu":"150","slaves":[],"addr":"","hwaddr":"00:00:00:00:00:
00","cfg":{},"netmask":""},"bond3":{"mtu":"150","slaves":[
],"addr":"","hwaddr":"00:00:00:00:00:00","cfg":{},"
netmask":""},"bond0":{"mtu":"150","slaves":[],"addr":"","
hwaddr":"00:00:00:00:00:00","cfg":{},"netmask":""},"bond1":
{"mtu":"150","slaves":[],"addr":"","hwaddr":"00:00:00:
00:00:00","cfg":{},"netmask":""},"bond2":{"m
tu":"150
","slaves":[],"addr":"","hwaddr":"00:00:00:00:00:00","cfg":{
},"netmask":""}}},"id":"9f78265b-21c3-4e77-9b11-7c126c2d84ed"}
2016-10-17 22:24:59,785 Message sent: MESSAGE
content-length:4230
destination:jms.queue.reponses
<JsonRpcResponse id: "9f78265b-21c3-4e77-9b11-7c126c2d84ed" result:
{version_name=Snow Man, operatingSystem={name=Fedora, release=1,
version=17}, cpuSpeed=1200.000, clusterLevels=[3.0, 3.1, 3.2, 3.3, 3.4,
3.5, 3.6, 4.0, 4.1], hooks={}, ISCSIInitiatorName=iqn.1994-05.com.example:ef52ec17bb0,
cpuSockets=1, kvmEnabled=true, reservedMem=321, lastClientIface=ovirtmgmt,
numaNodes={1={cpus=[0, 2, 4, 6, 8, 10, 12, 14], totalMemory=3988},
0={cpus=[1, 3, 5, 7, 9, 11, 13, 15], totalMemory=3988}},
cpuFlags=fpu,vme,de,pse,tsc,msr,pae,mce,cx8,apic,sep,mtrr,pg
e,mca,cmov,pat,pse36,clflush,mmx,fxsr,sse,sse2,ss,syscall,
nx,pdpe1gb,rdtscp,lm,constant_tsc,rep_good,nopl,eagerfpu,
pni,pclmulqdq,vmx,ssse3,fma,cx16,pcid,sse4_1,sse4_2,
x2apic,movbe,popcnt,tsc_deadline_timer,aes,xsave,avx,
f16c,rdrand,hypervisor,lahf_lm,abm,tpr_shadow,vnmi,flexpri
ority,ept,fsgsbase,bmi1,avx2,smep,bmi2,erms,invpcid,
xsaveopt,model_Haswell-noTSX,model_Nehalem,model_Conroe,
model_Penryn,model_Westmere,model_SandyBridge,
HBAInventory={iSCSI=[{InitiatorName=iqn.1994-05.com.example:ef52ec17bb0}],
FC=[]}, lastClient=10.36.6.76, selinux={mode=1}, vlans={},
software_version=4.10, kdumpStatus=1, emulatedMachines=[pc-0.10, pc-0.11,
pc-0.12, pc-0.13, pc-0.14, pc-0.15, pc-1.0, pc-1.0, pc-i440fx-2.1,
pseries-rhel7.2.0, pc-i440fx-rhel7.2.0, rhel6.4.0, rhel6.5.0, rhel6.6.0,
rhel6.7.0, rhel6.8.0, rhel6.9.0, rhel7.0.0, rhel7.2.0, rhel7.5.0, pc,
isapc], vmTypes=[kvm], software_revision=0.141,
bridges={ovirtmgmt={mtu=1500, ports=[em1], gateway=252.197.29.20,
addr=186.190.35.84, cfg={DELAY=0, DEVICE=ovirtmgmt, ONBOOT=yes,
BOOTPROTO=dhcp, TYPE=Ethernet}, stp=off, netmask=255.255.252.0}},
netConfigDirty=False, autoNumaBalancing=1, guestOverhead=65,
networks={ovirtmgmt={mtu=1500, ports=[em1], iface=ovirtmgmt,
gateway=10.34.63.254, bridged=true, switch=legacy, addr=186.190.35.84,
stp=off, cfg={DELAY=0, DEVICE=ovirtmgmt, ONBOOT=yes, BOOTPROTO=dhcp,
TYPE=Ethernet}, netmask=255.255.252.0}}, memSize=7976, rngSources=[RANDOM],
management_ip=, supportedENGINEs=[3.0, 3.1, 3.2, 3.3, 3.4, 3.5, 3.6, 4.0,
4.1], cpuModel=Intel(R) Xeon(R) CPU E5606 @ 2.13GHz, cpuCores=4,
supportedProtocols=[2.2, 2.3], packages2={libvirt={buildtime=1349642820,
release=2.fc17, version=1.0.1}, spice-server={buildtime=1336983054,
release=5.fc17, version=0.10.1}, vdsm={buildtime=1359653302,
release=0.141.gita11e8f2.fc17, version=4.10.3},
qemu-kvm={buildtime=1349642820, release=2.fc17, version=1.0.1},
qemu-img={buildtime=1349642820, release=2.fc17, version=1.0.1},
kernel={buildtime=1357699251.0, release=5.fc17.x86_64, version=3.6.11},
mom={buildtime=1354824066, release=1.fc17, version=0.3.0}},
uuid=7e6d8c6a-ca0c-46d2-ae6e-3af293fa6c4a_80:0D:F4:97:64:9B:3A,
nics={em1={mtu=1500, speed=1000, addr=, hwaddr=55:2B:B0:FD:88:55,
cfg={NM_CONTROLLED=yes, NETBOOT=yes, DEVICE=em1, NAME=Boot Disk,
HWADDR=55:2B:B0:FD:88:55, BRIDGE=ovirtmgmt, UUID=f1962c67-78ab-4b2b-8095-07354d2eba73,
ONBOOT=yes, BOOTPROTO=dhcp, TYPE=Ethernet}, netmask=}, em2={mtu=1500,
speed=1000, addr=, hwaddr=F3:1F:F7:F0:29:76, cfg={NM_CONTROLLED=yes,
NETBOOT=yes, DEVICE=em2, HWADDR=F3:1F:F7:F0:29:76, BRIDGE=ovirtmgmt,
UUID=6f563109-b336-4091-96d8-18c2c323189e, ONBOOT=no, BOOTPROTO=dhcp,
TYPE=Ethernet}, netmask=}}, numaNodeDistance={1=[20, 10], 0=[10, 20]},
onlineCpus=[1, 3, 5, 7, 9, 11, 13, 15, 0, 2, 4, 6, 8, 10, 12, 14],
cpuThreads=4, bondings={bond4={mtu=150, slaves=[], addr=,
hwaddr=00:00:00:00:00:00, cfg={}, netmask=}, bond3={mtu=150, slaves=[],
addr=, hwaddr=00:00:00:00:00:00, cfg={}, netmask=}, bond0={mtu=150,
slaves=[], addr=, hwaddr=00:00:00:00:00:00, cfg={}, netmask=},
bond1={mtu=150, slaves=[], addr=, hwaddr=00:00:00:00:00:00, cfg={},
netmask=}, bond2={mtu=150, slaves=[], addr=, hwaddr=00:00:00:00:00:00,
cfg={}, netmask=}}}>
2016-10-17 22:25:16,722
2016-10-17 22:25:16,722 Message sent: null
2016-10-17 22:25:16,722 Unable to process messages: Broken pipe
java.io.IOException: Broken pipe
        at sun.nio.ch.FileDispatcherImpl.write0(Native Method)
        at sun.nio.ch.SocketDispatcher.write(SocketDispatcher.java:47)
        at sun.nio.ch.IOUtil.writeFromNativeBuffer(IOUtil.java:93)
        at sun.nio.ch.IOUtil.write(IOUtil.java:65)
        at sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:492)
        at org.ovirt.vdsm.jsonrpc.client.reactors.PlainClient.write(Pla
inClient.java:55)
        at org.ovirt.vdsm.jsonrpc.client.reactors.ReactorClient.process
Outgoing(ReactorClient.java:261)
        at org.ovirt.vdsm.jsonrpc.client.reactors.ReactorClient.process
(ReactorClient.java:224)
        at org.ovirt.vdsm.jsonrpc.client.reactors.Reactor.processChanne
ls(Reactor.java:89)
        at org.ovirt.vdsm.jsonrpc.client.reactors.Reactor.run(Reactor.j
ava:65)
2016-10-17 22:25:16,723
2016-10-17 22:25:16,723 Message sent: null
2016-10-17 22:25:16,723 Failure in processing request
java.lang.IllegalArgumentException: 'method' field missing in node
        at org.ovirt.vdsm.jsonrpc.client.JsonRpcRequest.fromJsonNode(Js
onRpcRequest.java:79)
        at org.ovirt.vdsm.jsonrpc.client.JsonRpcRequest.fromByteArray(J
sonRpcRequest.java:103)
        at org.ovirt.vdsmfake.rpc.json.JsonRpcServer$MessageHandler.run
(JsonRpcServer.java:122)
        at java.util.concurrent.Executors$RunnableAdapter.call(
Executors.java:471)
        at java.util.concurrent.FutureTask.run(FutureTask.java:262)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPool
Executor.java:1145)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoo
lExecutor.java:615)
        at java.lang.Thread.run(Thread.java:745)
2016-10-17 22:25:19,647 CONNECT
accept-version:1.2
heart-beat:0,21234
host:null
2016-10-17 22:25:19,648 CONNECT
accept-version:1.2
heart-beat:0,21234
host:null
2016-10-17 22:25:19,648 CONNECTED
heart-beat:21234,0
session:ef2d8f70-3a0d-4ebd-b424-343ad130cb06
2016-10-17 22:25:19,648 Message sent: CONNECTED
heart-beat:21234,0
session:ef2d8f70-3a0d-4ebd-b424-343ad130cb06
2016-10-17 22:25:19,768 SUBSCRIBE
destination:jms.topic.vdsm_responses
ack:auto
id:7843c1e0-b514-4b7e-8687-66dae07b29d1
SEND
destination:jms.topic.vdsm_requests
reply-to:jms.topic.vdsm_responses
content-length:103
{"jsonrpc":"2.0","method":"Host.getAllVmStats","params":{},"
id":"b9e1ef26-3046-4aaa-82d9-d00a0c5f8d55"}
2016-10-17 22:25:19,773 SUBSCRIBE
destination:jms.topic.vdsm_responses
ack:auto
id:7843c1e0-b514-4b7e-8687-66dae07b29d1
2016-10-17 22:25:19,773 ACK
id:7843c1e0-b514-4b7e-8687-66dae07b29d1
2016-10-17 22:25:19,773 Message sent: ACK
id:7843c1e0-b514-4b7e-8687-66dae07b29d1
2016-10-17 22:25:19,773 SEND
destination:jms.topic.vdsm_requests
reply-to:jms.topic.vdsm_responses
content-length:103
{"jsonrpc":"2.0","method":"Host.getAllVmStats","params":{},"
id":"b9e1ef26-3046-4aaa-82d9-d00a0c5f8d55"}
2016-10-17 22:25:19,777 client policy identifier null
2016-10-17 22:25:19,786 Request is Host.getAllVmStats got response
{"jsonrpc":"2.0","result":[],"id":"b9e1ef26-3046-4aaa-82d9-d00a0c5f8d55"}
2016-10-17 22:25:19,789 MESSAGE
destination:jms.queue.reponses
content-length:73
{"jsonrpc":"2.0","result":[],"id":"b9e1ef26-3046-4aaa-82d9-d00a0c5f8d55"}
2016-10-17 22:25:19,789 Message sent: MESSAGE
content-length:73
destination:jms.queue.reponses
<JsonRpcResponse id: "b9e1ef26-3046-4aaa-82d9-d00a0c5f8d55" result: []>
2016-10-17 22:25:36,779
2016-10-17 22:25:36,779 Message sent: null
2016-10-17 22:25:36,779 Unable to process messages: Broken pipe
java.io.IOException: Broken pipe
        at sun.nio.ch.FileDispatcherImpl.write0(Native Method)
        at sun.nio.ch.SocketDispatcher.write(SocketDispatcher.java:47)
        at sun.nio.ch.IOUtil.writeFromNativeBuffer(IOUtil.java:93)
        at sun.nio.ch.IOUtil.write(IOUtil.java:65)
        at sun.nio.ch.SocketChannelImpl.write(SocketChannelImpl.java:492)
        at org.ovirt.vdsm.jsonrpc.client.reactors.PlainClient.write(Pla
inClient.java:55)
        at org.ovirt.vdsm.jsonrpc.client.reactors.ReactorClient.process
Outgoing(ReactorClient.java:261)
        at org.ovirt.vdsm.jsonrpc.client.reactors.ReactorClient.process
(ReactorClient.java:224)
        at org.ovirt.vdsm.jsonrpc.client.reactors.Reactor.processChanne
ls(Reactor.java:89)
        at org.ovirt.vdsm.jsonrpc.client.reactors.Reactor.run(Reactor.j
ava:65)
2016-10-17 22:25:36,780
2016-10-17 22:25:36,780 Message sent: null
2016-10-17 22:25:36,780 Failure in processing request
java.lang.IllegalArgumentException: 'method' field missing in node
        at org.ovirt.vdsm.jsonrpc.client.JsonRpcRequest.fromJsonNode(Js
onRpcRequest.java:79)
        at org.ovirt.vdsm.jsonrpc.client.JsonRpcRequest.fromByteArray(J
sonRpcRequest.java:103)
        at org.ovirt.vdsmfake.rpc.json.JsonRpcServer$MessageHandler.run
(JsonRpcServer.java:122)
        at java.util.concurrent.Executors$RunnableAdapter.call(
Executors.java:471)
        at java.util.concurrent.FutureTask.run(FutureTask.java:262)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPool
Executor.java:1145)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoo
lExecutor.java:615)
        at java.lang.Thread.run(Thread.java:745)
2016-10-17 22:25:39,763 CONNECT
accept-version:1.2
heart-beat:0,21234
host:null
On the ovirt-engine i see the following
2016-10-17 22:25:19,435 INFO  [org.ovirt.vdsm.jsonrpc.client.reactors.ReactorClient]
(Stomp Reactor) [] Connecting to hyp1/52.25.68.41
2016-10-17 22:25:19,710 ERROR [org.ovirt.vdsm.jsonrpc.client.reactors.Reactor]
(Stomp Reactor) [] Unable to process messages: Unrecognized message received
2016-10-17 22:25:19,711 ERROR [org.ovirt.engine.core.vdsbrok
er.vdsbroker.GetAllVmStatsVDSCommand] (DefaultQuartzScheduler2) []
Command 'GetAllVmStatsVDSCommand(HostName = hyp1Host1,
VdsIdAndVdsVDSCommandParametersBase:{runAsync='true',
hostId='7b0a7f76-b877-4fc4-aa30-2e94b533b7b3',
vds='Host[hyp1Host1,7b0a7f76-b877-4fc4-aa30-2e94b533b7b3]'})' execution
failed: VDSGenericException: VDSNetworkException: Unrecognized message
received
2016-10-17 22:25:19,711 INFO  [org.ovirt.engine.core.vdsbrok
er.monitoring.PollVmStatsRefresher] (DefaultQuartzScheduler2) [] Failed
to fetch vms info for host 'hyp1Host1' - skipping VMs monitoring.
2016-10-17 22:25:39,567 INFO  [org.ovirt.vdsm.jsonrpc.client.reactors.ReactorClient]
(Stomp Reactor) [] Connecting to hyp1/52.25.68.41
2016-10-17 22:25:39,827 ERROR [org.ovirt.vdsm.jsonrpc.client.reactors.Reactor]
(Stomp Reactor) [] Unable to process messages: Unrecognized message received
2016-10-17 22:25:39,831 ERROR [org.ovirt.engine.core.dal.dbb
roker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler10) []
Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: VDSM
hyp1Host1 command failed: Unrecognized message received
2016-10-17 22:25:39,831 INFO  [org.ovirt.engine.core.vdsbrok
er.vdsbroker.GetCapabilitiesVDSCommand] (DefaultQuartzScheduler10) []
Command 'org.ovirt.engine.core.vdsbroker.vdsbroker.GetCapabilitiesVDSCommand'
return value 'org.ovirt.engine.core.vdsbroker.vdsbroker.VDSInfoReturnForX
mlRpc@2e358a37'
2016-10-17 22:25:39,831 INFO  [org.ovirt.engine.core.vdsbrok
er.vdsbroker.GetCapabilitiesVDSCommand] (DefaultQuartzScheduler10) []
HostName = hyp1Host1
2016-10-17 22:25:39,832 ERROR [org.ovirt.engine.core.vdsbrok
er.vdsbroker.GetCapabilitiesVDSCommand] (DefaultQuartzScheduler10) []
Command 'GetCapabilitiesVDSCommand(HostName = hyp1Host1,
VdsIdAndVdsVDSCommandParametersBase:{runAsync='true',
hostId='7b0a7f76-b877-4fc4-aa30-2e94b533b7b3',
vds='Host[hyp1Host1,7b0a7f76-b877-4fc4-aa30-2e94b533b7b3]'})' execution
failed: VDSGenericException: VDSNetworkException: Unrecognized message
received
2016-10-17 22:25:39,832 ERROR [org.ovirt.engine.core.vdsbroker.monitoring.HostMonitoring]
(DefaultQuartzScheduler10) [] Failure to refresh Vds runtime info:
VDSGenericException: VDSNetworkException: Unrecognized message received
2016-10-17 22:25:39,832 ERROR [org.ovirt.engine.core.vdsbroker.monitoring.HostMonitoring]
(DefaultQuartzScheduler10) [] Exception: org.ovirt.engine.core.vdsbroke
r.vdsbroker.VDSNetworkException: VDSGenericException:
VDSNetworkException: Unrecognized message received
        at org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase.
proceedProxyReturnValue(BrokerCommandBase.java:188) [vdsbroker.jar:]
        at org.ovirt.engine.core.vdsbroker.vdsbroker.GetCapabilitiesVDS
Command.executeVdsBrokerCommand(GetCapabilitiesVDSCommand.java:16)
[vdsbroker.jar:]
        at org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand.e
xecuteVDSCommand(VdsBrokerCommand.java:110) [vdsbroker.jar:]
        at org.ovirt.engine.core.vdsbroker.VDSCommandBase.executeCommand(VDSCommandBase.java:73)
[vdsbroker.jar:]
        at org.ovirt.engine.core.dal.VdcCommandBase.execute(VdcCommandBase.java:33)
[dal.jar:]
        at org.ovirt.engine.core.vdsbroker.ResourceManager.runVdsCommand(ResourceManager.java:451)
[vdsbroker.jar:]
        at org.ovirt.engine.core.vdsbroker.VdsManager.refreshCapabilities(VdsManager.java:653)
[vdsbroker.jar:]
        at org.ovirt.engine.core.vdsbroker.monitoring.HostMonitoring.re
freshVdsRunTimeInfo(HostMonitoring.java:121) [vdsbroker.jar:]
        at org.ovirt.engine.core.vdsbroker.monitoring.HostMonitoring.
refresh(HostMonitoring.java:85) [vdsbroker.jar:]
        at org.ovirt.engine.core.vdsbroker.VdsManager.onTimer(VdsManager.java:238)
[vdsbroker.jar:]
        at sun.reflect.GeneratedMethodAccessor70.invoke(Unknown Source)
[:1.8.0_102]
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[rt.jar:1.8.0_102]
        at java.lang.reflect.Method.invoke(Method.java:498)
[rt.jar:1.8.0_102]
        at org.ovirt.engine.core.utils.timer.JobWrapper.invokeMethod(JobWrapper.java:77)
[scheduler.jar:]
        at org.ovirt.engine.core.utils.timer.JobWrapper.execute(JobWrapper.java:51)
[scheduler.jar:]
        at org.quartz.core.JobRunShell.run(JobRunShell.java:213)
[quartz.jar:]
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
[rt.jar:1.8.0_102]
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[rt.jar:1.8.0_102]
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[rt.jar:1.8.0_102]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[rt.jar:1.8.0_102]
        at java.lang.Thread.run(Thread.java:745) [rt.jar:1.8.0_102]
2016-10-17 22:25:59,697 INFO  [org.ovirt.vdsm.jsonrpc.client.reactors.ReactorClient]
(Stomp Reactor) [] Connecting to hyp1/52.25.68.41
2016-10-17 22:25:59,974 ERROR [org.ovirt.vdsm.jsonrpc.client.reactors.Reactor]
(Stomp Reactor) [] Unable to process messages: Unrecognized message received
2016-10-17 22:25:59,974 ERROR [org.ovirt.engine.core.vdsbrok
er.vdsbroker.GetAllVmStatsVDSCommand] (DefaultQuartzScheduler1) []
Command 'GetAllVmStatsVDSCommand(HostName = hyp1Host1,
VdsIdAndVdsVDSCommandParametersBase:{runAsync='true',
hostId='7b0a7f76-b877-4fc4-aa30-2e94b533b7b3',
vds='Host[hyp1Host1,7b0a7f76-b877-4fc4-aa30-2e94b533b7b3]'})' execution
failed: VDSGenericException: VDSNetworkException: Unrecognized message
received
2016-10-17 22:25:59,975 INFO  [org.ovirt.engine.core.vdsbrok
er.monitoring.PollVmStatsRefresher] (DefaultQuartzScheduler1) [] Failed
to fetch vms info for host 'hyp1Host1' - skipping VMs monitoring.
2016-10-17 22:26:19,830 INFO  [org.ovirt.vdsm.jsonrpc.client.reactors.ReactorClient]
(Stomp Reactor) [] Connecting to hyp1/52.25.68.41
2016-10-17 22:26:20,119 ERROR [org.ovirt.vdsm.jsonrpc.client.reactors.Reactor]
(Stomp Reactor) [] Unable to process messages: Unrecognized message received
2016-10-17 22:26:20,125 ERROR [org.ovirt.engine.core.dal.dbb
roker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler3) []
Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: VDSM
hyp1Host1 command failed: Unrecognized message received
2016-10-17 22:26:20,125 INFO  [org.ovirt.engine.core.vdsbrok
er.vdsbroker.GetCapabilitiesVDSCommand] (DefaultQuartzScheduler3) []
Command 'org.ovirt.engine.core.vdsbroker.vdsbroker.GetCapabilitiesVDSCommand'
return value 'org.ovirt.engine.core.vdsbroker.vdsbroker.VDSInfoReturnForX
mlRpc@33b8f1a0'
2016-10-17 22:26:20,125 INFO  [org.ovirt.engine.core.vdsbrok
er.vdsbroker.GetCapabilitiesVDSCommand] (DefaultQuartzScheduler3) []
HostName = hyp1Host1
2016-10-17 22:26:20,125 ERROR [org.ovirt.engine.core.vdsbrok
er.vdsbroker.GetCapabilitiesVDSCommand] (DefaultQuartzScheduler3) []
Command 'GetCapabilitiesVDSCommand(HostName = hyp1Host1,
VdsIdAndVdsVDSCommandParametersBase:{runAsync='true',
hostId='7b0a7f76-b877-4fc4-aa30-2e94b533b7b3',
vds='Host[hyp1Host1,7b0a7f76-b877-4fc4-aa30-2e94b533b7b3]'})' execution
failed: VDSGenericException: VDSNetworkException: Unrecognized message
received
2016-10-17 22:26:20,125 ERROR [org.ovirt.engine.core.vdsbroker.monitoring.HostMonitoring]
(DefaultQuartzScheduler3) [] Failure to refresh Vds runtime info:
VDSGenericException: VDSNetworkException: Unrecognized message received
2016-10-17 22:26:20,125 ERROR [org.ovirt.engine.core.vdsbroker.monitoring.HostMonitoring]
(DefaultQuartzScheduler3) [] Exception: org.ovirt.engine.core.vdsbroke
r.vdsbroker.VDSNetworkException: VDSGenericException:
VDSNetworkException: Unrecognized message received
        at org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase.
proceedProxyReturnValue(BrokerCommandBase.java:188) [vdsbroker.jar:]
        at org.ovirt.engine.core.vdsbroker.vdsbroker.GetCapabilitiesVDS
Command.executeVdsBrokerCommand(GetCapabilitiesVDSCommand.java:16)
[vdsbroker.jar:]
        at org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand.e
xecuteVDSCommand(VdsBrokerCommand.java:110) [vdsbroker.jar:]
        at org.ovirt.engine.core.vdsbroker.VDSCommandBase.executeCommand(VDSCommandBase.java:73)
[vdsbroker.jar:]
        at org.ovirt.engine.core.dal.VdcCommandBase.execute(VdcCommandBase.java:33)
[dal.jar:]
        at org.ovirt.engine.core.vdsbroker.ResourceManager.runVdsCommand(ResourceManager.java:451)
[vdsbroker.jar:]
        at org.ovirt.engine.core.vdsbroker.VdsManager.refreshCapabilities(VdsManager.java:653)
[vdsbroker.jar:]
        at org.ovirt.engine.core.vdsbroker.monitoring.HostMonitoring.re
freshVdsRunTimeInfo(HostMonitoring.java:121) [vdsbroker.jar:]
        at org.ovirt.engine.core.vdsbroker.monitoring.HostMonitoring.
refresh(HostMonitoring.java:85) [vdsbroker.jar:]
        at org.ovirt.engine.core.vdsbroker.VdsManager.onTimer(VdsManager.java:238)
[vdsbroker.jar:]
        at sun.reflect.GeneratedMethodAccessor70.invoke(Unknown Source)
[:1.8.0_102]
        at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[rt.jar:1.8.0_102]
        at java.lang.reflect.Method.invoke(Method.java:498)
[rt.jar:1.8.0_102]
        at org.ovirt.engine.core.utils.timer.JobWrapper.invokeMethod(JobWrapper.java:77)
[scheduler.jar:]
        at org.ovirt.engine.core.utils.timer.JobWrapper.execute(JobWrapper.java:51)
[scheduler.jar:]
        at org.quartz.core.JobRunShell.run(JobRunShell.java:213)
[quartz.jar:]
        at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
[rt.jar:1.8.0_102]
        at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[rt.jar:1.8.0_102]
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[rt.jar:1.8.0_102]
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[rt.jar:1.8.0_102]
        at java.lang.Thread.run(Thread.java:745) [rt.jar:1.8.0_102]
2016-10-17 22:26:39,965 INFO  [org.ovirt.vdsm.jsonrpc.client.reactors.ReactorClient]
(Stomp Reactor) [] Connecting to hyp1/52.25.68.41
2016-10-17 22:26:40,229 ERROR [org.ovirt.vdsm.jsonrpc.client.reactors.Reactor]
(Stomp Reactor) [] Unable to process messages: Unrecognized message received
2016-10-17 22:26:40,229 ERROR [org.ovirt.engine.core.vdsbrok
er.vdsbroker.GetAllVmStatsVDSCommand] (DefaultQuartzScheduler1) []
Command 'GetAllVmStatsVDSCommand(HostName = hyp1Host1,
VdsIdAndVdsVDSCommandParametersBase:{runAsync='true',
hostId='7b0a7f76-b877-4fc4-aa30-2e94b533b7b3',
vds='Host[hyp1Host1,7b0a7f76-b877-4fc4-aa30-2e94b533b7b3]'})' execution
failed: VDSGenericException: VDSNetworkException: Unrecognized message
received
2016-10-17 22:26:40,229 INFO  [org.ovirt.engine.core.vdsbrok
er.monitoring.PollVmStatsRefresher] (DefaultQuartzScheduler1) [] Failed
to fetch vms info for host 'hyp1Host1' - skipping VMs monitoring.
_______________________________________________
Users mailing list
Users@ovirt.org
http://lists.ovirt.org/mailman/listinfo/users
Best Regards,

Roman

Re: [ovirt-users] fakevdsm vs ovirt engine 4.0.4

Roman Mohr