1:28 a.m.
Hi All,
I am trying to start load tests against my newly installed ovirt engine
version 4.0.4
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
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","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,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-05.com.ex
ample: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":""}}},"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","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,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-05.com.ex
ample: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":""}}},"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,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-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(PlainClient.java:55)
at
org.ovirt.vdsm.jsonrpc.client.reactors.ReactorClient.processOutgoing(ReactorClient.java:261)
at
org.ovirt.vdsm.jsonrpc.client.reactors.ReactorClient.process(ReactorClient.java:224)
at
org.ovirt.vdsm.jsonrpc.client.reactors.Reactor.processChannels(Reactor.java:89)
at org.ovirt.vdsm.jsonrpc.client.reactors.Reactor.run(Reactor.java: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(JsonRpcRequest.java:79)
at
org.ovirt.vdsm.jsonrpc.client.JsonRpcRequest.fromByteArray(JsonRpcRequest.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(ThreadPoolExecutor.java:1145)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.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(PlainClient.java:55)
at
org.ovirt.vdsm.jsonrpc.client.reactors.ReactorClient.processOutgoing(ReactorClient.java:261)
at
org.ovirt.vdsm.jsonrpc.client.reactors.ReactorClient.process(ReactorClient.java:224)
at
org.ovirt.vdsm.jsonrpc.client.reactors.Reactor.processChannels(Reactor.java:89)
at org.ovirt.vdsm.jsonrpc.client.reactors.Reactor.run(Reactor.java: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(JsonRpcRequest.java:79)
at
org.ovirt.vdsm.jsonrpc.client.JsonRpcRequest.fromByteArray(JsonRpcRequest.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(ThreadPoolExecutor.java:1145)
at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.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.vdsbroker.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.vdsbroker.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.dbbroker.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.vdsbroker.vdsbroker.GetCapabilitiesVDSCommand]
(DefaultQuartzScheduler10) [] Command
'org.ovirt.engine.core.vdsbroker.vdsbroker.GetCapabilitiesVDSCommand'
return value
'org.ovirt.engine.core.vdsbroker.vdsbroker.VDSInfoReturnForXmlRpc@2e358a37'
2016-10-17 22:25:39,831 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.GetCapabilitiesVDSCommand]
(DefaultQuartzScheduler10) [] HostName = hyp1Host1
2016-10-17 22:25:39,832 ERROR
[org.ovirt.engine.core.vdsbroker.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.vdsbroker.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.GetCapabilitiesVDSCommand.executeVdsBrokerCommand(GetCapabilitiesVDSCommand.java:16)
[vdsbroker.jar:]
at
org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand.executeVDSCommand(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.refreshVdsRunTimeInfo(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.vdsbroker.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.vdsbroker.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.dbbroker.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.vdsbroker.vdsbroker.GetCapabilitiesVDSCommand]
(DefaultQuartzScheduler3) [] Command
'org.ovirt.engine.core.vdsbroker.vdsbroker.GetCapabilitiesVDSCommand'
return value
'org.ovirt.engine.core.vdsbroker.vdsbroker.VDSInfoReturnForXmlRpc@33b8f1a0'
2016-10-17 22:26:20,125 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.GetCapabilitiesVDSCommand]
(DefaultQuartzScheduler3) [] HostName = hyp1Host1
2016-10-17 22:26:20,125 ERROR
[org.ovirt.engine.core.vdsbroker.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.vdsbroker.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.GetCapabilitiesVDSCommand.executeVdsBrokerCommand(GetCapabilitiesVDSCommand.java:16)
[vdsbroker.jar:]
at
org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand.executeVDSCommand(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.refreshVdsRunTimeInfo(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.vdsbroker.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.vdsbroker.monitoring.PollVmStatsRefresher]
(DefaultQuartzScheduler1) [] Failed to fetch vms info for host
'hyp1Host1' - skipping VMs monitoring.