[ovirt-users] fakevdsm vs ovirt engine 4.0.4

Roman Mohr rmohr at redhat.com
Tue Oct 18 07:06:25 UTC 2016


Hi,

On Tue, Oct 18, 2016 at 12:28 AM, <joost at 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 at 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 at 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 at ovirt.org
> http://lists.ovirt.org/mailman/listinfo/users
>

Best Regards,

Roman
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.ovirt.org/pipermail/users/attachments/20161018/7b2ddea9/attachment-0001.html>


More information about the Users mailing list