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","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-0
5.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","BOOT
PROTO":"
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":{"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","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-0
5.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","BOOT
PROTO":"
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":{"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,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.

_______________________________________________
Users mailing list
Users@ovirt.org
http://lists.ovirt.org/mailman/listinfo/users

Best Regards,

Roman