MainThread::INFO::2015-12-21 15:39:59,654::vdsm::164::vds::(run) (PID: 23664) I am the actual vdsm 4.17.13-0.el7.centos kvm36-1.virtkust (3.10.0-327.el7.x86_64)
MainThread::DEBUG::2015-12-21 15:39:59,655::resourceManager::421::Storage.ResourceManager::(registerNamespace) Registering namespace 'Storage'
MainThread::DEBUG::2015-12-21 15:39:59,655::threadPool::29::Storage.ThreadPool::(__init__) Enter - numThreads: 10, waitTimeout: 3, maxTasks: 500
MainThread::DEBUG::2015-12-21 15:39:59,657::fileUtils::143::Storage.fileUtils::(createdir) Creating directory: /rhev/data-center/mnt mode: None
MainThread::DEBUG::2015-12-21 15:39:59,694::hsm::401::Storage.Misc.excCmd::(__validateLvmLockingType) /usr/bin/sudo -n /usr/sbin/lvm dumpconfig global/locking_type (cwd None)
MainThread::DEBUG::2015-12-21 15:39:59,714::hsm::401::Storage.Misc.excCmd::(__validateLvmLockingType) SUCCESS: <err> = ''; <rc> = 0
MainThread::DEBUG::2015-12-21 15:39:59,714::hsm::425::Storage.HSM::(__cleanStorageRepository) Started cleaning storage repository at '/rhev/data-center'
MainThread::DEBUG::2015-12-21 15:39:59,715::hsm::457::Storage.HSM::(__cleanStorageRepository) White list: ['/rhev/data-center/hsm-tasks', '/rhev/data-center/hsm-tasks/*', '/rhev/data-center/mnt']
MainThread::DEBUG::2015-12-21 15:39:59,715::hsm::458::Storage.HSM::(__cleanStorageRepository) Mount list: []
MainThread::DEBUG::2015-12-21 15:39:59,715::hsm::460::Storage.HSM::(__cleanStorageRepository) Cleaning leftovers
MainThread::DEBUG::2015-12-21 15:39:59,716::hsm::503::Storage.HSM::(__cleanStorageRepository) Finished cleaning storage repository at '/rhev/data-center'
storageRefresh::DEBUG::2015-12-21 15:39:59,716::misc::750::Storage.SamplingMethod::(__call__) Trying to enter sampling method (storage.sdc.refreshStorage)
MainThread::INFO::2015-12-21 15:39:59,717::dispatcher::46::Storage.Dispatcher::(__init__) Starting StorageDispatcher...
storageRefresh::DEBUG::2015-12-21 15:39:59,717::misc::753::Storage.SamplingMethod::(__call__) Got in to sampling method
MainThread::DEBUG::2015-12-21 15:39:59,717::schedule::98::Scheduler::(start) Starting scheduler vdsm.Scheduler
storageRefresh::DEBUG::2015-12-21 15:39:59,718::misc::750::Storage.SamplingMethod::(__call__) Trying to enter sampling method (storage.iscsi.rescan)
vdsm.Scheduler::DEBUG::2015-12-21 15:39:59,718::schedule::142::Scheduler::(_run) started
storageRefresh::DEBUG::2015-12-21 15:39:59,725::misc::753::Storage.SamplingMethod::(__call__) Got in to sampling method
storageRefresh::DEBUG::2015-12-21 15:39:59,736::iscsi::431::Storage.ISCSI::(rescan) Performing SCSI scan, this will take up to 30 seconds
storageRefresh::DEBUG::2015-12-21 15:39:59,739::iscsiadm::97::Storage.Misc.excCmd::(_runCmd) /usr/bin/sudo -n /sbin/iscsiadm -m session -R (cwd None)
MainThread::DEBUG::2015-12-21 15:39:59,797::task::595::Storage.TaskManager.Task::(_updateState) Task=`2f2a3ed8-eb1d-49e2-a2a4-befa919ad848`::moving from state init -> state preparing
MainThread::INFO::2015-12-21 15:39:59,797::logUtils::48::dispatcher::(wrapper) Run and protect: registerDomainStateChangeCallback(callbackFunc=<functools.partial object at 0x31fcb50>)
storageRefresh::DEBUG::2015-12-21 15:39:59,797::misc::760::Storage.SamplingMethod::(__call__) Returning last result
MainThread::INFO::2015-12-21 15:39:59,797::logUtils::51::dispatcher::(wrapper) Run and protect: registerDomainStateChangeCallback, Return response: None
storageRefresh::DEBUG::2015-12-21 15:39:59,797::misc::750::Storage.SamplingMethod::(__call__) Trying to enter sampling method (storage.hba.rescan)
MainThread::DEBUG::2015-12-21 15:39:59,798::task::1191::Storage.TaskManager.Task::(prepare) Task=`2f2a3ed8-eb1d-49e2-a2a4-befa919ad848`::finished: None
storageRefresh::DEBUG::2015-12-21 15:39:59,798::misc::753::Storage.SamplingMethod::(__call__) Got in to sampling method
MainThread::DEBUG::2015-12-21 15:39:59,798::task::595::Storage.TaskManager.Task::(_updateState) Task=`2f2a3ed8-eb1d-49e2-a2a4-befa919ad848`::moving from state preparing -> state finished
storageRefresh::DEBUG::2015-12-21 15:39:59,798::hba::56::Storage.HBA::(rescan) Starting scan
MainThread::DEBUG::2015-12-21 15:39:59,798::resourceManager::940::Storage.ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
storageRefresh::DEBUG::2015-12-21 15:39:59,798::supervdsm::76::SuperVdsmProxy::(_connect) Trying to connect to Super Vdsm
MainThread::DEBUG::2015-12-21 15:39:59,798::resourceManager::977::Storage.ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
MainThread::DEBUG::2015-12-21 15:39:59,798::task::993::Storage.TaskManager.Task::(_decref) Task=`2f2a3ed8-eb1d-49e2-a2a4-befa919ad848`::ref 0 aborting False
MainThread::INFO::2015-12-21 15:39:59,799::momIF::46::MOM::(__init__) Preparing MOM interface
MainThread::INFO::2015-12-21 15:39:59,805::momIF::55::MOM::(__init__) Using named unix socket /var/run/vdsm/mom-vdsm.sock
MainThread::INFO::2015-12-21 15:39:59,805::secret::90::root::(clear) Unregistering all secrests
MainThread::DEBUG::2015-12-21 15:39:59,805::libvirtconnection::160::root::(get) trying to connect libvirt
MainThread::INFO::2015-12-21 15:39:59,809::vmchannels::196::vds::(settimeout) Setting channels' timeout to 30 seconds.
VM Channels Listener::DEBUG::2015-12-21 15:39:59,816::vmchannels::178::vds::(run) Starting VM channels listener thread.
MainThread::INFO::2015-12-21 15:39:59,818::protocoldetector::172::vds.MultiProtocolAcceptor::(__init__) Listening at 0.0.0.0:54321
MainThread::DEBUG::2015-12-21 15:39:59,820::protocoldetector::199::vds.MultiProtocolAcceptor::(add_detector) Adding detector <rpc.bindingxmlrpc.XmlDetector instance at 0x32a6098>
MainThread::DEBUG::2015-12-21 15:39:59,863::protocoldetector::199::vds.MultiProtocolAcceptor::(add_detector) Adding detector <yajsonrpc.stompreactor.StompDetector instance at 0x33d55f0>
MainThread::DEBUG::2015-12-21 15:39:59,863::executor::69::Executor::(start) Starting executor
MainThread::DEBUG::2015-12-21 15:39:59,863::executor::157::Executor::(__init__) Starting worker jsonrpc.Executor/0
jsonrpc.Executor/0::DEBUG::2015-12-21 15:39:59,863::executor::171::Executor::(_run) Worker started
MainThread::DEBUG::2015-12-21 15:39:59,863::executor::157::Executor::(__init__) Starting worker jsonrpc.Executor/1
jsonrpc.Executor/1::DEBUG::2015-12-21 15:39:59,864::executor::171::Executor::(_run) Worker started
MainThread::DEBUG::2015-12-21 15:39:59,864::executor::157::Executor::(__init__) Starting worker jsonrpc.Executor/2
MainThread::DEBUG::2015-12-21 15:39:59,864::executor::157::Executor::(__init__) Starting worker jsonrpc.Executor/3
jsonrpc.Executor/2::DEBUG::2015-12-21 15:39:59,864::executor::171::Executor::(_run) Worker started
jsonrpc.Executor/3::DEBUG::2015-12-21 15:39:59,864::executor::171::Executor::(_run) Worker started
MainThread::DEBUG::2015-12-21 15:39:59,864::executor::157::Executor::(__init__) Starting worker jsonrpc.Executor/4
MainThread::DEBUG::2015-12-21 15:39:59,865::executor::157::Executor::(__init__) Starting worker jsonrpc.Executor/5
jsonrpc.Executor/4::DEBUG::2015-12-21 15:39:59,865::executor::171::Executor::(_run) Worker started
MainThread::DEBUG::2015-12-21 15:39:59,865::executor::157::Executor::(__init__) Starting worker jsonrpc.Executor/6
jsonrpc.Executor/5::DEBUG::2015-12-21 15:39:59,865::executor::171::Executor::(_run) Worker started
jsonrpc.Executor/6::DEBUG::2015-12-21 15:39:59,866::executor::171::Executor::(_run) Worker started
MainThread::DEBUG::2015-12-21 15:39:59,866::executor::157::Executor::(__init__) Starting worker jsonrpc.Executor/7
jsonrpc.Executor/7::DEBUG::2015-12-21 15:39:59,866::executor::171::Executor::(_run) Worker started
BindingXMLRPC::INFO::2015-12-21 15:39:59,866::bindingxmlrpc::62::vds::(threaded_start) XMLRPC server running
MainThread::DEBUG::2015-12-21 15:39:59,867::executor::69::Executor::(start) Starting executor
MainThread::DEBUG::2015-12-21 15:39:59,867::executor::157::Executor::(__init__) Starting worker periodic/0
periodic/0::DEBUG::2015-12-21 15:39:59,868::executor::171::Executor::(_run) Worker started
MainThread::DEBUG::2015-12-21 15:39:59,868::executor::157::Executor::(__init__) Starting worker periodic/1
periodic/1::DEBUG::2015-12-21 15:39:59,868::executor::171::Executor::(_run) Worker started
MainThread::DEBUG::2015-12-21 15:39:59,868::executor::157::Executor::(__init__) Starting worker periodic/2
MainThread::DEBUG::2015-12-21 15:39:59,868::executor::157::Executor::(__init__) Starting worker periodic/3
periodic/2::DEBUG::2015-12-21 15:39:59,869::executor::171::Executor::(_run) Worker started
MainThread::DEBUG::2015-12-21 15:39:59,869::libvirtconnection::160::root::(get) trying to connect libvirt
periodic/3::DEBUG::2015-12-21 15:39:59,869::executor::171::Executor::(_run) Worker started
MainThread::DEBUG::2015-12-21 15:39:59,874::periodic::153::virt.periodic.Operation::(start) starting operation VmDispatcher(<class 'virt.periodic.UpdateVolumes'>)
MainThread::DEBUG::2015-12-21 15:39:59,875::periodic::153::virt.periodic.Operation::(start) starting operation VmDispatcher(<class 'virt.periodic.NumaInfoMonitor'>)
MainThread::DEBUG::2015-12-21 15:39:59,875::periodic::153::virt.periodic.Operation::(start) starting operation VmDispatcher(<class 'virt.periodic.BlockjobMonitor'>)
MainThread::DEBUG::2015-12-21 15:39:59,875::periodic::153::virt.periodic.Operation::(start) starting operation <virt.sampling.VMBulkSampler object at 0x33edd10>
MainThread::DEBUG::2015-12-21 15:39:59,875::periodic::153::virt.periodic.Operation::(start) starting operation VmDispatcher(<class 'virt.periodic.DriveWatermarkMonitor'>)
storageRefresh::DEBUG::2015-12-21 15:39:59,896::hba::62::Storage.HBA::(rescan) Scan finished
storageRefresh::DEBUG::2015-12-21 15:39:59,896::misc::760::Storage.SamplingMethod::(__call__) Returning last result
storageRefresh::DEBUG::2015-12-21 15:39:59,896::multipath::77::Storage.Misc.excCmd::(rescan) /usr/bin/sudo -n /usr/sbin/multipath (cwd None)
storageRefresh::DEBUG::2015-12-21 15:40:00,018::multipath::77::Storage.Misc.excCmd::(rescan) SUCCESS: <err> = ''; <rc> = 0
storageRefresh::DEBUG::2015-12-21 15:40:00,018::utils::669::root::(execCmd) /sbin/udevadm settle --timeout=5 (cwd None)
storageRefresh::DEBUG::2015-12-21 15:40:00,032::utils::687::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
storageRefresh::DEBUG::2015-12-21 15:40:00,033::lvm::497::Storage.OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' got the operation mutex
storageRefresh::DEBUG::2015-12-21 15:40:00,033::lvm::499::Storage.OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' released the operation mutex
storageRefresh::DEBUG::2015-12-21 15:40:00,033::lvm::508::Storage.OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' got the operation mutex
storageRefresh::DEBUG::2015-12-21 15:40:00,033::lvm::510::Storage.OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' released the operation mutex
storageRefresh::DEBUG::2015-12-21 15:40:00,033::lvm::528::Storage.OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' got the operation mutex
storageRefresh::DEBUG::2015-12-21 15:40:00,033::lvm::530::Storage.OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' released the operation mutex
storageRefresh::DEBUG::2015-12-21 15:40:00,033::misc::760::Storage.SamplingMethod::(__call__) Returning last result
storageRefresh::DEBUG::2015-12-21 15:40:00,033::lvm::319::Storage.OperationMutex::(_reloadpvs) Operation 'lvm reload operation' got the operation mutex
storageRefresh::DEBUG::2015-12-21 15:40:00,034::lvm::290::Storage.Misc.excCmd::(cmd) /usr/bin/sudo -n /usr/sbin/lvm pvs --config ' devices { preferred_names = ["^/dev/mapper/"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 filter = [ '\''r|.*|'\'' ] }  global {  locking_type=1  prioritise_write_locks=1  wait_for_locks=1  use_lvmetad=0 }  backup {  retain_min = 50  retain_days = 0 } ' --noheadings --units b --nosuffix --separator '|' --ignoreskippedcluster -o uuid,name,size,vg_name,vg_uuid,pe_start,pe_count,pe_alloc_count,mda_count,dev_size (cwd None)
Reactor thread::INFO::2015-12-21 15:40:00,078::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle_accept) Accepting connection from 172.16.1.6:55244
storageRefresh::DEBUG::2015-12-21 15:40:00,170::lvm::290::Storage.Misc.excCmd::(cmd) SUCCESS: <err> = '  WARNING: lvmetad is running but disabled. Restart lvmetad before enabling it!\n'; <rc> = 0
storageRefresh::DEBUG::2015-12-21 15:40:00,170::lvm::347::Storage.OperationMutex::(_reloadpvs) Operation 'lvm reload operation' released the operation mutex
storageRefresh::DEBUG::2015-12-21 15:40:00,170::lvm::370::Storage.OperationMutex::(_reloadvgs) Operation 'lvm reload operation' got the operation mutex
storageRefresh::DEBUG::2015-12-21 15:40:00,170::lvm::290::Storage.Misc.excCmd::(cmd) /usr/bin/sudo -n /usr/sbin/lvm vgs --config ' devices { preferred_names = ["^/dev/mapper/"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 filter = [ '\''r|.*|'\'' ] }  global {  locking_type=1  prioritise_write_locks=1  wait_for_locks=1  use_lvmetad=0 }  backup {  retain_min = 50  retain_days = 0 } ' --noheadings --units b --nosuffix --separator '|' --ignoreskippedcluster -o uuid,name,attr,size,free,extent_size,extent_count,free_count,tags,vg_mda_size,vg_mda_free,lv_count,pv_count,pv_name (cwd None)
storageRefresh::DEBUG::2015-12-21 15:40:00,185::lvm::290::Storage.Misc.excCmd::(cmd) SUCCESS: <err> = '  WARNING: lvmetad is running but disabled. Restart lvmetad before enabling it!\n'; <rc> = 0
storageRefresh::DEBUG::2015-12-21 15:40:00,186::lvm::415::Storage.OperationMutex::(_reloadvgs) Operation 'lvm reload operation' released the operation mutex
storageRefresh::DEBUG::2015-12-21 15:40:00,186::lvm::290::Storage.Misc.excCmd::(cmd) /usr/bin/sudo -n /usr/sbin/lvm lvs --config ' devices { preferred_names = ["^/dev/mapper/"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 filter = [ '\''r|.*|'\'' ] }  global {  locking_type=1  prioritise_write_locks=1  wait_for_locks=1  use_lvmetad=0 }  backup {  retain_min = 50  retain_days = 0 } ' --noheadings --units b --nosuffix --separator '|' --ignoreskippedcluster -o uuid,name,vg_name,attr,size,seg_start_pe,devices,tags (cwd None)
storageRefresh::DEBUG::2015-12-21 15:40:00,201::lvm::290::Storage.Misc.excCmd::(cmd) SUCCESS: <err> = '  WARNING: lvmetad is running but disabled. Restart lvmetad before enabling it!\n'; <rc> = 0
storageRefresh::DEBUG::2015-12-21 15:40:00,201::lvm::370::Storage.OperationMutex::(_reloadvgs) Operation 'lvm reload operation' got the operation mutex
storageRefresh::DEBUG::2015-12-21 15:40:00,201::lvm::290::Storage.Misc.excCmd::(cmd) /usr/bin/sudo -n /usr/sbin/lvm vgs --config ' devices { preferred_names = ["^/dev/mapper/"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 filter = [ '\''r|.*|'\'' ] }  global {  locking_type=1  prioritise_write_locks=1  wait_for_locks=1  use_lvmetad=0 }  backup {  retain_min = 50  retain_days = 0 } ' --noheadings --units b --nosuffix --separator '|' --ignoreskippedcluster -o uuid,name,attr,size,free,extent_size,extent_count,free_count,tags,vg_mda_size,vg_mda_free,lv_count,pv_count,pv_name (cwd None)
storageRefresh::DEBUG::2015-12-21 15:40:00,241::lvm::290::Storage.Misc.excCmd::(cmd) SUCCESS: <err> = '  WARNING: lvmetad is running but disabled. Restart lvmetad before enabling it!\n'; <rc> = 0
storageRefresh::DEBUG::2015-12-21 15:40:00,241::lvm::415::Storage.OperationMutex::(_reloadvgs) Operation 'lvm reload operation' released the operation mutex
storageRefresh::DEBUG::2015-12-21 15:40:00,241::hsm::371::Storage.HSM::(storageRefresh) HSM is ready
Reactor thread::DEBUG::2015-12-21 15:40:00,265::protocoldetector::82::ProtocolDetector.Detector::(__init__) Using required_size=11
Reactor thread::INFO::2015-12-21 15:40:00,268::protocoldetector::118::ProtocolDetector.Detector::(handle_read) Detected protocol stomp from 172.16.1.6:55244
Reactor thread::INFO::2015-12-21 15:40:00,268::stompreactor::101::Broker.StompAdapter::(_cmd_connect) Processing CONNECT request
Reactor thread::DEBUG::2015-12-21 15:40:00,268::stompreactor::470::protocoldetector.StompDetector::(handle_socket) Stomp detected from ('172.16.1.6', 55244)
JsonRpc (StompReactor)::INFO::2015-12-21 15:40:00,269::stompreactor::128::Broker.StompAdapter::(_cmd_subscribe) Subscribe command received
JsonRpc (StompReactor)::INFO::2015-12-21 15:40:00,269::stompreactor::128::Broker.StompAdapter::(_cmd_subscribe) Subscribe command received
jsonrpc.Executor/0::DEBUG::2015-12-21 15:40:01,272::__init__::503::jsonrpc.JsonRpcServer::(_serveRequest) Calling 'Host.ping' in bridge with {}
jsonrpc.Executor/0::DEBUG::2015-12-21 15:40:01,272::__init__::533::jsonrpc.JsonRpcServer::(_serveRequest) Return 'Host.ping' in bridge with True
jsonrpc.Executor/1::DEBUG::2015-12-21 15:40:02,316::__init__::503::jsonrpc.JsonRpcServer::(_serveRequest) Calling 'Host.ping' in bridge with {}
jsonrpc.Executor/1::DEBUG::2015-12-21 15:40:02,316::__init__::533::jsonrpc.JsonRpcServer::(_serveRequest) Return 'Host.ping' in bridge with True
jsonrpc.Executor/2::DEBUG::2015-12-21 15:40:03,323::__init__::503::jsonrpc.JsonRpcServer::(_serveRequest) Calling 'Host.getCapabilities' in bridge with {}
jsonrpc.Executor/2::DEBUG::2015-12-21 15:40:03,342::utils::669::root::(execCmd) /usr/sbin/tc qdisc show (cwd None)
jsonrpc.Executor/2::DEBUG::2015-12-21 15:40:03,373::utils::687::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
jsonrpc.Executor/2::DEBUG::2015-12-21 15:40:03,387::utils::669::root::(execCmd) numactl --hardware (cwd None)
jsonrpc.Executor/2::DEBUG::2015-12-21 15:40:03,392::utils::687::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
jsonrpc.Executor/2::DEBUG::2015-12-21 15:40:03,392::utils::669::root::(execCmd) sysctl -n -e kernel.numa_balancing (cwd None)
jsonrpc.Executor/2::DEBUG::2015-12-21 15:40:03,397::utils::687::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
jsonrpc.Executor/2::DEBUG::2015-12-21 15:40:03,400::__init__::533::jsonrpc.JsonRpcServer::(_serveRequest) Return 'Host.getCapabilities' in bridge with {'HBAInventory': {'iSCSI': [{'InitiatorName': 'iqn.1994-05.com.redhat:34c437e0e8'}], 'FC': []}, 'packages2': {'kernel': {'release': '327.el7.x86_64', 'buildtime': 1447967457.0, 'version': '3.10.0'}, 'spice-server': {'release': '15.el7', 'buildtime': 1448026017L, 'version': '0.12.4'}, 'librbd1': {'release': '3.el7', 'buildtime': 1448036424L, 'version': '0.80.7'}, 'vdsm': {'release': '0.el7.centos', 'buildtime': 1449649492L, 'version': '4.17.13'}, 'qemu-kvm': {'release': '31.el7_2.4.1', 'buildtime': 1450438721L, 'version': '2.3.0'}, 'libvirt': {'release': '13.el7_2.2', 'buildtime': 1449666817L, 'version': '1.2.17'}, 'qemu-img': {'release': '31.el7_2.4.1', 'buildtime': 1450438721L, 'version': '2.3.0'}, 'mom': {'release': '2.el7', 'buildtime': 1442501481L, 'version': '0.5.1'}, 'glusterfs-cli': {'release': '1.el7', 'buildtime': 1447082499L, 'version': '3.7.6'}}, 'numaNodeDistance': {'0': [10]}, 'cpuModel': 'Intel(R) Core(TM) i3-4130 CPU @ 3.40GHz', 'liveMerge': 'true', 'hooks': {}, 'vmTypes': ['kvm'], 'selinux': {'mode': '1'}, 'liveSnapshot': 'true', 'kdumpStatus': 0, 'networks': {}, 'bridges': {}, 'uuid': '0D7A721A-469D-ACD7-6A97-E03F49E8F1FD', 'onlineCpus': '0,1,2,3', 'nics': {'enp3s0': {'addr': '172.16.1.7', 'ipv6gateway': 'fe80::217:c5ff:fe16:39dc', 'ipv6addrs': ['fe80::e23f:49ff:fee8:f1fd/64'], 'mtu': '1500', 'dhcpv4': True, 'netmask': '255.255.255.0', 'dhcpv6': False, 'ipv4addrs': ['172.16.1.7/24'], 'cfg': {'PEERROUTES': 'yes', 'IPV6INIT': 'yes', 'NAME': 'enp3s0', 'ONBOOT': 'yes', 'DEFROUTE': 'yes', 'IPV6_PEERDNS': 'yes', 'IPADDR': '172.16.1.7', 'UUID': '5443a1c9-53be-4215-8317-669048b4e7ee', 'PEERDNS': 'yes', 'IPV4_FAILURE_FATAL': 'no', 'DEVICE': 'enp3s0', 'NETMASK': '255.255.255.0', 'BOOTPROTO': 'static', 'IPV6_DEFROUTE': 'yes', 'IPV6_AUTOCONF': 'yes', 'IPV6_FAILURE_FATAL': 'no', 'TYPE': 'Ethernet', 'GATEWAY': '172.16.1.200', 'IPV6_PEERROUTES': 'yes'}, 'hwaddr': 'e0:3f:49:e8:f1:fd', 'speed': 100, 'gateway': '172.16.1.200'}}, 'software_revision': '0', 'hostdevPassthrough': 'false', 'clusterLevels': ['3.4', '3.5', '3.6'], 'cpuFlags': 'fpu,vme,de,pse,tsc,msr,pae,mce,cx8,apic,sep,mtrr,pge,mca,cmov,pat,pse36,clflush,dts,acpi,mmx,fxsr,sse,sse2,ss,ht,tm,pbe,syscall,nx,pdpe1gb,rdtscp,lm,constant_tsc,arch_perfmon,pebs,bts,rep_good,nopl,xtopology,nonstop_tsc,aperfmperf,eagerfpu,pni,pclmulqdq,dtes64,monitor,ds_cpl,vmx,est,tm2,ssse3,fma,cx16,xtpr,pdcm,pcid,sse4_1,sse4_2,movbe,popcnt,tsc_deadline_timer,aes,xsave,avx,f16c,rdrand,lahf_lm,abm,arat,epb,pln,pts,dtherm,tpr_shadow,vnmi,flexpriority,ept,vpid,fsgsbase,tsc_adjust,bmi1,avx2,smep,bmi2,erms,invpcid,xsaveopt,model_Nehalem,model_Conroe,model_coreduo,model_core2duo,model_Penryn,model_Westmere,model_n270', 'ISCSIInitiatorName': 'iqn.1994-05.com.redhat:34c437e0e8', 'netConfigDirty': 'False', 'supportedENGINEs': ['3.4', '3.5', '3.6'], 'autoNumaBalancing': 0, 'additionalFeatures': [], 'reservedMem': '321', 'bondings': {'bond0': {'ipv4addrs': [], 'addr': '', 'cfg': {'BOOTPROTO': 'none'}, 'ipv6addrs': [], 'active_slave': '', 'mtu': '1500', 'dhcpv4': False, 'netmask': '', 'dhcpv6': False, 'slaves': [], 'hwaddr': '72:b6:58:ef:67:76', 'ipv6gateway': '::', 'gateway': '', 'opts': {}}}, 'software_version': '4.17', 'memSize': '3730', 'cpuSpeed': '3400.000', 'numaNodes': {'0': {'totalMemory': '3730', 'cpus': [0, 1, 2, 3]}}, 'cpuSockets': '1', 'vlans': {}, 'lastClientIface': 'enp3s0', 'cpuCores': '2', 'kvmEnabled': 'true', 'guestOverhead': '65', 'version_name': 'Snow Man', 'cpuThreads': '4', 'emulatedMachines': ['pc-i440fx-rhel7.1.0', 'rhel6.3.0', 'pc-q35-rhel7.2.0', 'pc-i440fx-rhel7.0.0', 'rhel6.1.0', 'rhel6.6.0', 'rhel6.2.0', 'pc', 'pc-q35-rhel7.0.0', 'pc-q35-rhel7.1.0', 'q35', 'pc-i440fx-rhel7.2.0', 'rhel6.4.0', 'rhel6.0.0', 'rhel6.5.0'], 'rngSources': ['random'], 'operatingSystem': {'release': '2.1511.el7.centos.2.10', 'version': '7', 'name': 'RHEL'}}
JsonRpc (StompReactor)::ERROR::2015-12-21 15:40:03,551::betterAsyncore::124::vds.dispatcher::(recv) SSL error during reading data: unexpected eof
Reactor thread::INFO::2015-12-21 15:40:03,552::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle_accept) Accepting connection from 172.16.1.6:55245
Reactor thread::DEBUG::2015-12-21 15:40:03,614::protocoldetector::82::ProtocolDetector.Detector::(__init__) Using required_size=11
Reactor thread::INFO::2015-12-21 15:40:03,615::protocoldetector::118::ProtocolDetector.Detector::(handle_read) Detected protocol stomp from 172.16.1.6:55245
Reactor thread::INFO::2015-12-21 15:40:03,615::stompreactor::101::Broker.StompAdapter::(_cmd_connect) Processing CONNECT request
Reactor thread::DEBUG::2015-12-21 15:40:03,615::stompreactor::470::protocoldetector.StompDetector::(handle_socket) Stomp detected from ('172.16.1.6', 55245)
JsonRpc (StompReactor)::INFO::2015-12-21 15:40:03,616::stompreactor::128::Broker.StompAdapter::(_cmd_subscribe) Subscribe command received
JsonRpc (StompReactor)::INFO::2015-12-21 15:40:03,616::stompreactor::128::Broker.StompAdapter::(_cmd_subscribe) Subscribe command received
jsonrpc.Executor/3::DEBUG::2015-12-21 15:40:04,618::__init__::503::jsonrpc.JsonRpcServer::(_serveRequest) Calling 'Host.ping' in bridge with {}
jsonrpc.Executor/4::DEBUG::2015-12-21 15:40:04,619::__init__::503::jsonrpc.JsonRpcServer::(_serveRequest) Calling 'Host.setupNetworks' in bridge with {u'bondings': {}, u'networks': {u'ovirtmgmt': {u'nic': u'enp3s0', u'mtu': u'1500', u'bootproto': u'dhcp', u'STP': u'no', u'bridged': u'true', u'defaultRoute': True}}, u'options': {u'connectivityCheck': u'true', u'connectivityTimeout': 120}}
jsonrpc.Executor/3::DEBUG::2015-12-21 15:40:04,619::__init__::533::jsonrpc.JsonRpcServer::(_serveRequest) Return 'Host.ping' in bridge with True
Reactor thread::INFO::2015-12-21 15:40:14,623::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle_accept) Accepting connection from 127.0.0.1:55057
Reactor thread::DEBUG::2015-12-21 15:40:14,628::protocoldetector::82::ProtocolDetector.Detector::(__init__) Using required_size=11
Reactor thread::INFO::2015-12-21 15:40:14,628::protocoldetector::118::ProtocolDetector.Detector::(handle_read) Detected protocol xml from 127.0.0.1:55057
Reactor thread::DEBUG::2015-12-21 15:40:14,628::bindingxmlrpc::1297::XmlDetector::(handle_socket) xml over http detected from ('127.0.0.1', 55057)
BindingXMLRPC::INFO::2015-12-21 15:40:14,629::xmlrpc::73::vds.XMLRPCServer::(handle_request) Starting request handler for 127.0.0.1:55057
Thread-13::INFO::2015-12-21 15:40:14,629::xmlrpc::84::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55057 started
Thread-13::INFO::2015-12-21 15:40:14,630::xmlrpc::92::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55057 stopped
Reactor thread::INFO::2015-12-21 15:40:14,714::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle_accept) Accepting connection from 127.0.0.1:55058
Reactor thread::DEBUG::2015-12-21 15:40:14,718::protocoldetector::82::ProtocolDetector.Detector::(__init__) Using required_size=11
Reactor thread::INFO::2015-12-21 15:40:14,718::protocoldetector::118::ProtocolDetector.Detector::(handle_read) Detected protocol xml from 127.0.0.1:55058
Reactor thread::DEBUG::2015-12-21 15:40:14,718::bindingxmlrpc::1297::XmlDetector::(handle_socket) xml over http detected from ('127.0.0.1', 55058)
BindingXMLRPC::INFO::2015-12-21 15:40:14,718::xmlrpc::73::vds.XMLRPCServer::(handle_request) Starting request handler for 127.0.0.1:55058
Thread-14::INFO::2015-12-21 15:40:14,719::xmlrpc::84::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55058 started
Thread-14::INFO::2015-12-21 15:40:14,720::xmlrpc::92::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55058 stopped
Reactor thread::INFO::2015-12-21 15:40:29,641::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle_accept) Accepting connection from 127.0.0.1:55059
Reactor thread::DEBUG::2015-12-21 15:40:29,645::protocoldetector::82::ProtocolDetector.Detector::(__init__) Using required_size=11
Reactor thread::INFO::2015-12-21 15:40:29,646::protocoldetector::118::ProtocolDetector.Detector::(handle_read) Detected protocol xml from 127.0.0.1:55059
Reactor thread::DEBUG::2015-12-21 15:40:29,646::bindingxmlrpc::1297::XmlDetector::(handle_socket) xml over http detected from ('127.0.0.1', 55059)
BindingXMLRPC::INFO::2015-12-21 15:40:29,646::xmlrpc::73::vds.XMLRPCServer::(handle_request) Starting request handler for 127.0.0.1:55059
Thread-15::INFO::2015-12-21 15:40:29,646::xmlrpc::84::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55059 started
Thread-15::INFO::2015-12-21 15:40:29,650::xmlrpc::92::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55059 stopped
Reactor thread::INFO::2015-12-21 15:40:44,660::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle_accept) Accepting connection from 127.0.0.1:55060
Reactor thread::DEBUG::2015-12-21 15:40:44,667::protocoldetector::82::ProtocolDetector.Detector::(__init__) Using required_size=11
Reactor thread::INFO::2015-12-21 15:40:44,667::protocoldetector::118::ProtocolDetector.Detector::(handle_read) Detected protocol xml from 127.0.0.1:55060
Reactor thread::DEBUG::2015-12-21 15:40:44,667::bindingxmlrpc::1297::XmlDetector::(handle_socket) xml over http detected from ('127.0.0.1', 55060)
BindingXMLRPC::INFO::2015-12-21 15:40:44,667::xmlrpc::73::vds.XMLRPCServer::(handle_request) Starting request handler for 127.0.0.1:55060
Thread-16::INFO::2015-12-21 15:40:44,668::xmlrpc::84::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55060 started
Thread-16::INFO::2015-12-21 15:40:44,669::xmlrpc::92::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55060 stopped
Reactor thread::INFO::2015-12-21 15:40:59,684::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle_accept) Accepting connection from 127.0.0.1:55061
Reactor thread::DEBUG::2015-12-21 15:40:59,688::protocoldetector::82::ProtocolDetector.Detector::(__init__) Using required_size=11
Reactor thread::INFO::2015-12-21 15:40:59,688::protocoldetector::118::ProtocolDetector.Detector::(handle_read) Detected protocol xml from 127.0.0.1:55061
Reactor thread::DEBUG::2015-12-21 15:40:59,688::bindingxmlrpc::1297::XmlDetector::(handle_socket) xml over http detected from ('127.0.0.1', 55061)
BindingXMLRPC::INFO::2015-12-21 15:40:59,689::xmlrpc::73::vds.XMLRPCServer::(handle_request) Starting request handler for 127.0.0.1:55061
Thread-17::INFO::2015-12-21 15:40:59,689::xmlrpc::84::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55061 started
Thread-17::INFO::2015-12-21 15:40:59,690::xmlrpc::92::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55061 stopped
Reactor thread::INFO::2015-12-21 15:41:14,705::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle_accept) Accepting connection from 127.0.0.1:55062
Reactor thread::DEBUG::2015-12-21 15:41:14,710::protocoldetector::82::ProtocolDetector.Detector::(__init__) Using required_size=11
Reactor thread::INFO::2015-12-21 15:41:14,710::protocoldetector::118::ProtocolDetector.Detector::(handle_read) Detected protocol xml from 127.0.0.1:55062
Reactor thread::DEBUG::2015-12-21 15:41:14,710::bindingxmlrpc::1297::XmlDetector::(handle_socket) xml over http detected from ('127.0.0.1', 55062)
BindingXMLRPC::INFO::2015-12-21 15:41:14,710::xmlrpc::73::vds.XMLRPCServer::(handle_request) Starting request handler for 127.0.0.1:55062
Thread-18::INFO::2015-12-21 15:41:14,710::xmlrpc::84::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55062 started
Thread-18::INFO::2015-12-21 15:41:14,711::xmlrpc::92::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55062 stopped
Reactor thread::INFO::2015-12-21 15:41:29,727::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle_accept) Accepting connection from 127.0.0.1:55063
Reactor thread::DEBUG::2015-12-21 15:41:29,731::protocoldetector::82::ProtocolDetector.Detector::(__init__) Using required_size=11
Reactor thread::INFO::2015-12-21 15:41:29,731::protocoldetector::118::ProtocolDetector.Detector::(handle_read) Detected protocol xml from 127.0.0.1:55063
Reactor thread::DEBUG::2015-12-21 15:41:29,731::bindingxmlrpc::1297::XmlDetector::(handle_socket) xml over http detected from ('127.0.0.1', 55063)
BindingXMLRPC::INFO::2015-12-21 15:41:29,732::xmlrpc::73::vds.XMLRPCServer::(handle_request) Starting request handler for 127.0.0.1:55063
Thread-19::INFO::2015-12-21 15:41:29,732::xmlrpc::84::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55063 started
Thread-19::INFO::2015-12-21 15:41:29,733::xmlrpc::92::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55063 stopped
Reactor thread::INFO::2015-12-21 15:41:44,749::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle_accept) Accepting connection from 127.0.0.1:55064
Reactor thread::DEBUG::2015-12-21 15:41:44,753::protocoldetector::82::ProtocolDetector.Detector::(__init__) Using required_size=11
Reactor thread::INFO::2015-12-21 15:41:44,753::protocoldetector::118::ProtocolDetector.Detector::(handle_read) Detected protocol xml from 127.0.0.1:55064
Reactor thread::DEBUG::2015-12-21 15:41:44,753::bindingxmlrpc::1297::XmlDetector::(handle_socket) xml over http detected from ('127.0.0.1', 55064)
BindingXMLRPC::INFO::2015-12-21 15:41:44,753::xmlrpc::73::vds.XMLRPCServer::(handle_request) Starting request handler for 127.0.0.1:55064
Thread-20::INFO::2015-12-21 15:41:44,753::xmlrpc::84::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55064 started
Thread-20::INFO::2015-12-21 15:41:44,754::xmlrpc::92::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55064 stopped
Reactor thread::INFO::2015-12-21 15:41:59,770::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle_accept) Accepting connection from 127.0.0.1:55065
Reactor thread::DEBUG::2015-12-21 15:41:59,774::protocoldetector::82::ProtocolDetector.Detector::(__init__) Using required_size=11
Reactor thread::INFO::2015-12-21 15:41:59,774::protocoldetector::118::ProtocolDetector.Detector::(handle_read) Detected protocol xml from 127.0.0.1:55065
Reactor thread::DEBUG::2015-12-21 15:41:59,774::bindingxmlrpc::1297::XmlDetector::(handle_socket) xml over http detected from ('127.0.0.1', 55065)
BindingXMLRPC::INFO::2015-12-21 15:41:59,774::xmlrpc::73::vds.XMLRPCServer::(handle_request) Starting request handler for 127.0.0.1:55065
Thread-21::INFO::2015-12-21 15:41:59,775::xmlrpc::84::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55065 started
Thread-21::INFO::2015-12-21 15:41:59,776::xmlrpc::92::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55065 stopped
jsonrpc.Executor/4::ERROR::2015-12-21 15:42:08,576::API::1628::vds::(_rollback) connectivity check failed
Traceback (most recent call last):
  File "/usr/share/vdsm/API.py", line 1626, in _rollback
    yield rollbackCtx
  File "/usr/share/vdsm/API.py", line 1484, in setupNetworks
    supervdsm.getProxy().setupNetworks(networks, bondings, options)
  File "/usr/share/vdsm/supervdsm.py", line 50, in __call__
    return callMethod()
  File "/usr/share/vdsm/supervdsm.py", line 48, in <lambda>
    **kwargs)
  File "<string>", line 2, in setupNetworks
  File "/usr/lib64/python2.7/multiprocessing/managers.py", line 773, in _callmethod
    raise convert_to_error(kind, result)
ConfigNetworkError: (10, 'connectivity check failed')
Reactor thread::INFO::2015-12-21 15:42:14,784::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle_accept) Accepting connection from 127.0.0.1:55066
Reactor thread::DEBUG::2015-12-21 15:42:14,788::protocoldetector::82::ProtocolDetector.Detector::(__init__) Using required_size=11
Reactor thread::INFO::2015-12-21 15:42:14,789::protocoldetector::118::ProtocolDetector.Detector::(handle_read) Detected protocol xml from 127.0.0.1:55066
Reactor thread::DEBUG::2015-12-21 15:42:14,789::bindingxmlrpc::1297::XmlDetector::(handle_socket) xml over http detected from ('127.0.0.1', 55066)
BindingXMLRPC::INFO::2015-12-21 15:42:14,789::xmlrpc::73::vds.XMLRPCServer::(handle_request) Starting request handler for 127.0.0.1:55066
Thread-22::INFO::2015-12-21 15:42:14,789::xmlrpc::84::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55066 started
Thread-22::INFO::2015-12-21 15:42:14,790::xmlrpc::92::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55066 stopped
Reactor thread::INFO::2015-12-21 15:42:29,806::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle_accept) Accepting connection from 127.0.0.1:55067
Reactor thread::DEBUG::2015-12-21 15:42:29,810::protocoldetector::82::ProtocolDetector.Detector::(__init__) Using required_size=11
Reactor thread::INFO::2015-12-21 15:42:29,810::protocoldetector::118::ProtocolDetector.Detector::(handle_read) Detected protocol xml from 127.0.0.1:55067
Reactor thread::DEBUG::2015-12-21 15:42:29,810::bindingxmlrpc::1297::XmlDetector::(handle_socket) xml over http detected from ('127.0.0.1', 55067)
BindingXMLRPC::INFO::2015-12-21 15:42:29,810::xmlrpc::73::vds.XMLRPCServer::(handle_request) Starting request handler for 127.0.0.1:55067
Thread-23::INFO::2015-12-21 15:42:29,811::xmlrpc::84::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55067 started
Thread-23::INFO::2015-12-21 15:42:29,811::xmlrpc::92::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55067 stopped
jsonrpc.Executor/5::DEBUG::2015-12-21 15:42:44,393::__init__::503::jsonrpc.JsonRpcServer::(_serveRequest) Calling 'Host.ping' in bridge with {}
jsonrpc.Executor/5::DEBUG::2015-12-21 15:42:44,394::__init__::533::jsonrpc.JsonRpcServer::(_serveRequest) Return 'Host.ping' in bridge with True
Reactor thread::INFO::2015-12-21 15:42:44,828::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle_accept) Accepting connection from 127.0.0.1:55068
Reactor thread::DEBUG::2015-12-21 15:42:44,832::protocoldetector::82::ProtocolDetector.Detector::(__init__) Using required_size=11
Reactor thread::INFO::2015-12-21 15:42:44,832::protocoldetector::118::ProtocolDetector.Detector::(handle_read) Detected protocol xml from 127.0.0.1:55068
Reactor thread::DEBUG::2015-12-21 15:42:44,832::bindingxmlrpc::1297::XmlDetector::(handle_socket) xml over http detected from ('127.0.0.1', 55068)
BindingXMLRPC::INFO::2015-12-21 15:42:44,833::xmlrpc::73::vds.XMLRPCServer::(handle_request) Starting request handler for 127.0.0.1:55068
Thread-24::INFO::2015-12-21 15:42:44,833::xmlrpc::84::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55068 started
Thread-24::INFO::2015-12-21 15:42:44,834::xmlrpc::92::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55068 stopped
JsonRpc (StompReactor)::ERROR::2015-12-21 15:42:45,397::betterAsyncore::124::vds.dispatcher::(recv) SSL error during reading data: unexpected eof
Reactor thread::INFO::2015-12-21 15:42:59,840::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle_accept) Accepting connection from 127.0.0.1:55069
Reactor thread::DEBUG::2015-12-21 15:42:59,844::protocoldetector::82::ProtocolDetector.Detector::(__init__) Using required_size=11
Reactor thread::INFO::2015-12-21 15:42:59,845::protocoldetector::118::ProtocolDetector.Detector::(handle_read) Detected protocol xml from 127.0.0.1:55069
Reactor thread::DEBUG::2015-12-21 15:42:59,845::bindingxmlrpc::1297::XmlDetector::(handle_socket) xml over http detected from ('127.0.0.1', 55069)
BindingXMLRPC::INFO::2015-12-21 15:42:59,845::xmlrpc::73::vds.XMLRPCServer::(handle_request) Starting request handler for 127.0.0.1:55069
Thread-25::INFO::2015-12-21 15:42:59,845::xmlrpc::84::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55069 started
Thread-25::INFO::2015-12-21 15:42:59,846::xmlrpc::92::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55069 stopped
Reactor thread::INFO::2015-12-21 15:43:14,862::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle_accept) Accepting connection from 127.0.0.1:55070
Reactor thread::DEBUG::2015-12-21 15:43:14,866::protocoldetector::82::ProtocolDetector.Detector::(__init__) Using required_size=11
Reactor thread::INFO::2015-12-21 15:43:14,866::protocoldetector::118::ProtocolDetector.Detector::(handle_read) Detected protocol xml from 127.0.0.1:55070
Reactor thread::DEBUG::2015-12-21 15:43:14,866::bindingxmlrpc::1297::XmlDetector::(handle_socket) xml over http detected from ('127.0.0.1', 55070)
BindingXMLRPC::INFO::2015-12-21 15:43:14,866::xmlrpc::73::vds.XMLRPCServer::(handle_request) Starting request handler for 127.0.0.1:55070
Thread-26::INFO::2015-12-21 15:43:14,867::xmlrpc::84::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55070 started
Thread-26::INFO::2015-12-21 15:43:14,867::xmlrpc::92::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55070 stopped
Reactor thread::INFO::2015-12-21 15:43:29,883::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle_accept) Accepting connection from 127.0.0.1:55071
Reactor thread::DEBUG::2015-12-21 15:43:29,887::protocoldetector::82::ProtocolDetector.Detector::(__init__) Using required_size=11
Reactor thread::INFO::2015-12-21 15:43:29,887::protocoldetector::118::ProtocolDetector.Detector::(handle_read) Detected protocol xml from 127.0.0.1:55071
BindingXMLRPC::INFO::2015-12-21 15:43:29,888::xmlrpc::73::vds.XMLRPCServer::(handle_request) Starting request handler for 127.0.0.1:55071
Reactor thread::DEBUG::2015-12-21 15:43:29,888::bindingxmlrpc::1297::XmlDetector::(handle_socket) xml over http detected from ('127.0.0.1', 55071)
Thread-27::INFO::2015-12-21 15:43:29,888::xmlrpc::84::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55071 started
Thread-27::INFO::2015-12-21 15:43:29,889::xmlrpc::92::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55071 stopped
Reactor thread::INFO::2015-12-21 15:43:44,892::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle_accept) Accepting connection from 127.0.0.1:55072
Reactor thread::DEBUG::2015-12-21 15:43:44,896::protocoldetector::82::ProtocolDetector.Detector::(__init__) Using required_size=11
Reactor thread::INFO::2015-12-21 15:43:44,896::protocoldetector::118::ProtocolDetector.Detector::(handle_read) Detected protocol xml from 127.0.0.1:55072
Reactor thread::DEBUG::2015-12-21 15:43:44,896::bindingxmlrpc::1297::XmlDetector::(handle_socket) xml over http detected from ('127.0.0.1', 55072)
BindingXMLRPC::INFO::2015-12-21 15:43:44,897::xmlrpc::73::vds.XMLRPCServer::(handle_request) Starting request handler for 127.0.0.1:55072
Thread-28::INFO::2015-12-21 15:43:44,897::xmlrpc::84::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55072 started
Thread-28::INFO::2015-12-21 15:43:44,898::xmlrpc::92::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55072 stopped
Reactor thread::INFO::2015-12-21 15:43:59,907::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle_accept) Accepting connection from 127.0.0.1:55073
Reactor thread::DEBUG::2015-12-21 15:43:59,912::protocoldetector::82::ProtocolDetector.Detector::(__init__) Using required_size=11
Reactor thread::INFO::2015-12-21 15:43:59,912::protocoldetector::118::ProtocolDetector.Detector::(handle_read) Detected protocol xml from 127.0.0.1:55073
Reactor thread::DEBUG::2015-12-21 15:43:59,912::bindingxmlrpc::1297::XmlDetector::(handle_socket) xml over http detected from ('127.0.0.1', 55073)
BindingXMLRPC::INFO::2015-12-21 15:43:59,912::xmlrpc::73::vds.XMLRPCServer::(handle_request) Starting request handler for 127.0.0.1:55073
Thread-29::INFO::2015-12-21 15:43:59,913::xmlrpc::84::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55073 started
Thread-29::INFO::2015-12-21 15:43:59,914::xmlrpc::92::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55073 stopped
Reactor thread::INFO::2015-12-21 15:44:14,930::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle_accept) Accepting connection from 127.0.0.1:55074
Reactor thread::DEBUG::2015-12-21 15:44:14,934::protocoldetector::82::ProtocolDetector.Detector::(__init__) Using required_size=11
Reactor thread::INFO::2015-12-21 15:44:14,934::protocoldetector::118::ProtocolDetector.Detector::(handle_read) Detected protocol xml from 127.0.0.1:55074
Reactor thread::DEBUG::2015-12-21 15:44:14,934::bindingxmlrpc::1297::XmlDetector::(handle_socket) xml over http detected from ('127.0.0.1', 55074)
BindingXMLRPC::INFO::2015-12-21 15:44:14,934::xmlrpc::73::vds.XMLRPCServer::(handle_request) Starting request handler for 127.0.0.1:55074
Thread-30::INFO::2015-12-21 15:44:14,934::xmlrpc::84::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55074 started
Thread-30::INFO::2015-12-21 15:44:14,935::xmlrpc::92::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55074 stopped
Reactor thread::INFO::2015-12-21 15:44:20,495::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle_accept) Accepting connection from 172.16.1.6:55246
Reactor thread::DEBUG::2015-12-21 15:44:20,558::protocoldetector::82::ProtocolDetector.Detector::(__init__) Using required_size=11
Reactor thread::INFO::2015-12-21 15:44:20,559::protocoldetector::118::ProtocolDetector.Detector::(handle_read) Detected protocol stomp from 172.16.1.6:55246
Reactor thread::INFO::2015-12-21 15:44:20,559::stompreactor::101::Broker.StompAdapter::(_cmd_connect) Processing CONNECT request
Reactor thread::DEBUG::2015-12-21 15:44:20,559::stompreactor::470::protocoldetector.StompDetector::(handle_socket) Stomp detected from ('172.16.1.6', 55246)
JsonRpc (StompReactor)::INFO::2015-12-21 15:44:20,560::stompreactor::128::Broker.StompAdapter::(_cmd_subscribe) Subscribe command received
JsonRpc (StompReactor)::INFO::2015-12-21 15:44:20,560::stompreactor::128::Broker.StompAdapter::(_cmd_subscribe) Subscribe command received
jsonrpc.Executor/6::DEBUG::2015-12-21 15:44:21,562::__init__::503::jsonrpc.JsonRpcServer::(_serveRequest) Calling 'Host.getCapabilities' in bridge with {}
jsonrpc.Executor/6::DEBUG::2015-12-21 15:44:21,570::utils::669::root::(execCmd) /usr/sbin/tc qdisc show (cwd None)
jsonrpc.Executor/6::DEBUG::2015-12-21 15:44:21,575::utils::687::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
jsonrpc.Executor/6::DEBUG::2015-12-21 15:44:21,582::__init__::533::jsonrpc.JsonRpcServer::(_serveRequest) Return 'Host.getCapabilities' in bridge with {'HBAInventory': {'iSCSI': [{'InitiatorName': 'iqn.1994-05.com.redhat:34c437e0e8'}], 'FC': []}, 'packages2': {'kernel': {'release': '327.el7.x86_64', 'buildtime': 1447967457.0, 'version': '3.10.0'}, 'spice-server': {'release': '15.el7', 'buildtime': 1448026017L, 'version': '0.12.4'}, 'librbd1': {'release': '3.el7', 'buildtime': 1448036424L, 'version': '0.80.7'}, 'vdsm': {'release': '0.el7.centos', 'buildtime': 1449649492L, 'version': '4.17.13'}, 'qemu-kvm': {'release': '31.el7_2.4.1', 'buildtime': 1450438721L, 'version': '2.3.0'}, 'libvirt': {'release': '13.el7_2.2', 'buildtime': 1449666817L, 'version': '1.2.17'}, 'qemu-img': {'release': '31.el7_2.4.1', 'buildtime': 1450438721L, 'version': '2.3.0'}, 'mom': {'release': '2.el7', 'buildtime': 1442501481L, 'version': '0.5.1'}, 'glusterfs-cli': {'release': '1.el7', 'buildtime': 1447082499L, 'version': '3.7.6'}}, 'numaNodeDistance': {'0': [10]}, 'cpuModel': 'Intel(R) Core(TM) i3-4130 CPU @ 3.40GHz', 'liveMerge': 'true', 'hooks': {}, 'vmTypes': ['kvm'], 'selinux': {'mode': '1'}, 'liveSnapshot': 'true', 'kdumpStatus': 0, 'networks': {}, 'bridges': {}, 'uuid': '0D7A721A-469D-ACD7-6A97-E03F49E8F1FD', 'onlineCpus': '0,1,2,3', 'nics': {'enp3s0': {'addr': '172.16.1.7', 'ipv6gateway': '::', 'ipv6addrs': ['fe80::e23f:49ff:fee8:f1fd/64'], 'mtu': '1500', 'dhcpv4': True, 'netmask': '255.255.255.0', 'dhcpv6': False, 'ipv4addrs': ['172.16.1.7/24'], 'cfg': {'PEERROUTES': 'yes', 'IPV6INIT': 'yes', 'NAME': 'enp3s0', 'ONBOOT': 'yes', 'DEFROUTE': 'yes', 'IPV6_PEERDNS': 'yes', 'IPADDR': '172.16.1.7', 'UUID': '5443a1c9-53be-4215-8317-669048b4e7ee', 'PEERDNS': 'yes', 'IPV4_FAILURE_FATAL': 'no', 'DEVICE': 'enp3s0', 'NETMASK': '255.255.255.0', 'BOOTPROTO': 'static', 'IPV6_DEFROUTE': 'yes', 'IPV6_AUTOCONF': 'yes', 'IPV6_FAILURE_FATAL': 'no', 'TYPE': 'Ethernet', 'GATEWAY': '172.16.1.200', 'IPV6_PEERROUTES': 'yes'}, 'hwaddr': 'e0:3f:49:e8:f1:fd', 'speed': 100, 'gateway': '172.16.1.200'}}, 'software_revision': '0', 'hostdevPassthrough': 'false', 'clusterLevels': ['3.4', '3.5', '3.6'], 'cpuFlags': 'fpu,vme,de,pse,tsc,msr,pae,mce,cx8,apic,sep,mtrr,pge,mca,cmov,pat,pse36,clflush,dts,acpi,mmx,fxsr,sse,sse2,ss,ht,tm,pbe,syscall,nx,pdpe1gb,rdtscp,lm,constant_tsc,arch_perfmon,pebs,bts,rep_good,nopl,xtopology,nonstop_tsc,aperfmperf,eagerfpu,pni,pclmulqdq,dtes64,monitor,ds_cpl,vmx,est,tm2,ssse3,fma,cx16,xtpr,pdcm,pcid,sse4_1,sse4_2,movbe,popcnt,tsc_deadline_timer,aes,xsave,avx,f16c,rdrand,lahf_lm,abm,arat,epb,pln,pts,dtherm,tpr_shadow,vnmi,flexpriority,ept,vpid,fsgsbase,tsc_adjust,bmi1,avx2,smep,bmi2,erms,invpcid,xsaveopt,model_Nehalem,model_Conroe,model_coreduo,model_core2duo,model_Penryn,model_Westmere,model_n270', 'ISCSIInitiatorName': 'iqn.1994-05.com.redhat:34c437e0e8', 'netConfigDirty': 'True', 'supportedENGINEs': ['3.4', '3.5', '3.6'], 'autoNumaBalancing': 0, 'additionalFeatures': [], 'reservedMem': '321', 'bondings': {'bond0': {'ipv4addrs': [], 'addr': '', 'cfg': {'BOOTPROTO': 'none'}, 'ipv6addrs': [], 'active_slave': '', 'mtu': '1500', 'dhcpv4': False, 'netmask': '', 'dhcpv6': False, 'slaves': [], 'hwaddr': '72:b6:58:ef:67:76', 'ipv6gateway': '::', 'gateway': '', 'opts': {}}}, 'software_version': '4.17', 'memSize': '3730', 'cpuSpeed': '3400.132', 'numaNodes': {'0': {'totalMemory': '3730', 'cpus': [0, 1, 2, 3]}}, 'cpuSockets': '1', 'vlans': {}, 'lastClientIface': 'enp3s0', 'cpuCores': '2', 'kvmEnabled': 'true', 'guestOverhead': '65', 'version_name': 'Snow Man', 'cpuThreads': '4', 'emulatedMachines': ['pc-i440fx-rhel7.1.0', 'rhel6.3.0', 'pc-q35-rhel7.2.0', 'pc-i440fx-rhel7.0.0', 'rhel6.1.0', 'rhel6.6.0', 'rhel6.2.0', 'pc', 'pc-q35-rhel7.0.0', 'pc-q35-rhel7.1.0', 'q35', 'pc-i440fx-rhel7.2.0', 'rhel6.4.0', 'rhel6.0.0', 'rhel6.5.0'], 'rngSources': ['random'], 'operatingSystem': {'release': '2.1511.el7.centos.2.10', 'version': '7', 'name': 'RHEL'}}
jsonrpc.Executor/7::DEBUG::2015-12-21 15:44:21,596::__init__::503::jsonrpc.JsonRpcServer::(_serveRequest) Calling 'Host.getHardwareInfo' in bridge with {}
jsonrpc.Executor/7::DEBUG::2015-12-21 15:44:21,614::__init__::533::jsonrpc.JsonRpcServer::(_serveRequest) Return 'Host.getHardwareInfo' in bridge with {'systemProductName': 'UNLOCK INSTALL', 'systemSerialNumber': '92171335', 'systemFamily': 'ASUS MB', 'systemVersion': 'Not used', 'systemUUID': '0D7A721A-469D-ACD7-6A97-E03F49E8F1FD', 'systemManufacturer': 'PRIMINFO'}
jsonrpc.Executor/0::DEBUG::2015-12-21 15:44:22,906::__init__::503::jsonrpc.JsonRpcServer::(_serveRequest) Calling 'Host.getCapabilities' in bridge with {}
jsonrpc.Executor/0::DEBUG::2015-12-21 15:44:22,914::utils::669::root::(execCmd) /usr/sbin/tc qdisc show (cwd None)
jsonrpc.Executor/0::DEBUG::2015-12-21 15:44:22,919::utils::687::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
jsonrpc.Executor/0::DEBUG::2015-12-21 15:44:22,926::__init__::533::jsonrpc.JsonRpcServer::(_serveRequest) Return 'Host.getCapabilities' in bridge with {'HBAInventory': {'iSCSI': [{'InitiatorName': 'iqn.1994-05.com.redhat:34c437e0e8'}], 'FC': []}, 'packages2': {'kernel': {'release': '327.el7.x86_64', 'buildtime': 1447967457.0, 'version': '3.10.0'}, 'spice-server': {'release': '15.el7', 'buildtime': 1448026017L, 'version': '0.12.4'}, 'librbd1': {'release': '3.el7', 'buildtime': 1448036424L, 'version': '0.80.7'}, 'vdsm': {'release': '0.el7.centos', 'buildtime': 1449649492L, 'version': '4.17.13'}, 'qemu-kvm': {'release': '31.el7_2.4.1', 'buildtime': 1450438721L, 'version': '2.3.0'}, 'libvirt': {'release': '13.el7_2.2', 'buildtime': 1449666817L, 'version': '1.2.17'}, 'qemu-img': {'release': '31.el7_2.4.1', 'buildtime': 1450438721L, 'version': '2.3.0'}, 'mom': {'release': '2.el7', 'buildtime': 1442501481L, 'version': '0.5.1'}, 'glusterfs-cli': {'release': '1.el7', 'buildtime': 1447082499L, 'version': '3.7.6'}}, 'numaNodeDistance': {'0': [10]}, 'cpuModel': 'Intel(R) Core(TM) i3-4130 CPU @ 3.40GHz', 'liveMerge': 'true', 'hooks': {}, 'vmTypes': ['kvm'], 'selinux': {'mode': '1'}, 'liveSnapshot': 'true', 'kdumpStatus': 0, 'networks': {}, 'bridges': {}, 'uuid': '0D7A721A-469D-ACD7-6A97-E03F49E8F1FD', 'onlineCpus': '0,1,2,3', 'nics': {'enp3s0': {'addr': '172.16.1.7', 'ipv6gateway': '::', 'ipv6addrs': ['fe80::e23f:49ff:fee8:f1fd/64'], 'mtu': '1500', 'dhcpv4': True, 'netmask': '255.255.255.0', 'dhcpv6': False, 'ipv4addrs': ['172.16.1.7/24'], 'cfg': {'PEERROUTES': 'yes', 'IPV6INIT': 'yes', 'NAME': 'enp3s0', 'ONBOOT': 'yes', 'DEFROUTE': 'yes', 'IPV6_PEERDNS': 'yes', 'IPADDR': '172.16.1.7', 'UUID': '5443a1c9-53be-4215-8317-669048b4e7ee', 'PEERDNS': 'yes', 'IPV4_FAILURE_FATAL': 'no', 'DEVICE': 'enp3s0', 'NETMASK': '255.255.255.0', 'BOOTPROTO': 'static', 'IPV6_DEFROUTE': 'yes', 'IPV6_AUTOCONF': 'yes', 'IPV6_FAILURE_FATAL': 'no', 'TYPE': 'Ethernet', 'GATEWAY': '172.16.1.200', 'IPV6_PEERROUTES': 'yes'}, 'hwaddr': 'e0:3f:49:e8:f1:fd', 'speed': 100, 'gateway': '172.16.1.200'}}, 'software_revision': '0', 'hostdevPassthrough': 'false', 'clusterLevels': ['3.4', '3.5', '3.6'], 'cpuFlags': 'fpu,vme,de,pse,tsc,msr,pae,mce,cx8,apic,sep,mtrr,pge,mca,cmov,pat,pse36,clflush,dts,acpi,mmx,fxsr,sse,sse2,ss,ht,tm,pbe,syscall,nx,pdpe1gb,rdtscp,lm,constant_tsc,arch_perfmon,pebs,bts,rep_good,nopl,xtopology,nonstop_tsc,aperfmperf,eagerfpu,pni,pclmulqdq,dtes64,monitor,ds_cpl,vmx,est,tm2,ssse3,fma,cx16,xtpr,pdcm,pcid,sse4_1,sse4_2,movbe,popcnt,tsc_deadline_timer,aes,xsave,avx,f16c,rdrand,lahf_lm,abm,arat,epb,pln,pts,dtherm,tpr_shadow,vnmi,flexpriority,ept,vpid,fsgsbase,tsc_adjust,bmi1,avx2,smep,bmi2,erms,invpcid,xsaveopt,model_Nehalem,model_Conroe,model_coreduo,model_core2duo,model_Penryn,model_Westmere,model_n270', 'ISCSIInitiatorName': 'iqn.1994-05.com.redhat:34c437e0e8', 'netConfigDirty': 'True', 'supportedENGINEs': ['3.4', '3.5', '3.6'], 'autoNumaBalancing': 0, 'additionalFeatures': [], 'reservedMem': '321', 'bondings': {'bond0': {'ipv4addrs': [], 'addr': '', 'cfg': {'BOOTPROTO': 'none'}, 'ipv6addrs': [], 'active_slave': '', 'mtu': '1500', 'dhcpv4': False, 'netmask': '', 'dhcpv6': False, 'slaves': [], 'hwaddr': '72:b6:58:ef:67:76', 'ipv6gateway': '::', 'gateway': '', 'opts': {}}}, 'software_version': '4.17', 'memSize': '3730', 'cpuSpeed': '3400.132', 'numaNodes': {'0': {'totalMemory': '3730', 'cpus': [0, 1, 2, 3]}}, 'cpuSockets': '1', 'vlans': {}, 'lastClientIface': 'enp3s0', 'cpuCores': '2', 'kvmEnabled': 'true', 'guestOverhead': '65', 'version_name': 'Snow Man', 'cpuThreads': '4', 'emulatedMachines': ['pc-i440fx-rhel7.1.0', 'rhel6.3.0', 'pc-q35-rhel7.2.0', 'pc-i440fx-rhel7.0.0', 'rhel6.1.0', 'rhel6.6.0', 'rhel6.2.0', 'pc', 'pc-q35-rhel7.0.0', 'pc-q35-rhel7.1.0', 'q35', 'pc-i440fx-rhel7.2.0', 'rhel6.4.0', 'rhel6.0.0', 'rhel6.5.0'], 'rngSources': ['random'], 'operatingSystem': {'release': '2.1511.el7.centos.2.10', 'version': '7', 'name': 'RHEL'}}
jsonrpc.Executor/1::DEBUG::2015-12-21 15:44:22,932::__init__::503::jsonrpc.JsonRpcServer::(_serveRequest) Calling 'Host.getHardwareInfo' in bridge with {}
jsonrpc.Executor/1::DEBUG::2015-12-21 15:44:22,933::__init__::533::jsonrpc.JsonRpcServer::(_serveRequest) Return 'Host.getHardwareInfo' in bridge with {'systemProductName': 'UNLOCK INSTALL', 'systemSerialNumber': '92171335', 'systemFamily': 'ASUS MB', 'systemVersion': 'Not used', 'systemUUID': '0D7A721A-469D-ACD7-6A97-E03F49E8F1FD', 'systemManufacturer': 'PRIMINFO'}
Reactor thread::INFO::2015-12-21 15:44:29,952::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle_accept) Accepting connection from 127.0.0.1:55075
Reactor thread::DEBUG::2015-12-21 15:44:29,958::protocoldetector::82::ProtocolDetector.Detector::(__init__) Using required_size=11
Reactor thread::INFO::2015-12-21 15:44:29,958::protocoldetector::118::ProtocolDetector.Detector::(handle_read) Detected protocol xml from 127.0.0.1:55075
Reactor thread::DEBUG::2015-12-21 15:44:29,958::bindingxmlrpc::1297::XmlDetector::(handle_socket) xml over http detected from ('127.0.0.1', 55075)
BindingXMLRPC::INFO::2015-12-21 15:44:29,958::xmlrpc::73::vds.XMLRPCServer::(handle_request) Starting request handler for 127.0.0.1:55075
Thread-31::INFO::2015-12-21 15:44:29,959::xmlrpc::84::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55075 started
Thread-31::INFO::2015-12-21 15:44:29,960::xmlrpc::92::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55075 stopped
Reactor thread::INFO::2015-12-21 15:44:44,973::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle_accept) Accepting connection from 127.0.0.1:55076
Reactor thread::DEBUG::2015-12-21 15:44:44,979::protocoldetector::82::ProtocolDetector.Detector::(__init__) Using required_size=11
Reactor thread::INFO::2015-12-21 15:44:44,979::protocoldetector::118::ProtocolDetector.Detector::(handle_read) Detected protocol xml from 127.0.0.1:55076
BindingXMLRPC::INFO::2015-12-21 15:44:44,979::xmlrpc::73::vds.XMLRPCServer::(handle_request) Starting request handler for 127.0.0.1:55076
Reactor thread::DEBUG::2015-12-21 15:44:44,980::bindingxmlrpc::1297::XmlDetector::(handle_socket) xml over http detected from ('127.0.0.1', 55076)
Thread-32::INFO::2015-12-21 15:44:44,980::xmlrpc::84::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55076 started
Thread-32::INFO::2015-12-21 15:44:44,981::xmlrpc::92::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55076 stopped
Reactor thread::INFO::2015-12-21 15:44:59,998::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle_accept) Accepting connection from 127.0.0.1:55077
Reactor thread::DEBUG::2015-12-21 15:45:00,002::protocoldetector::82::ProtocolDetector.Detector::(__init__) Using required_size=11
Reactor thread::INFO::2015-12-21 15:45:00,002::protocoldetector::118::ProtocolDetector.Detector::(handle_read) Detected protocol xml from 127.0.0.1:55077
Reactor thread::DEBUG::2015-12-21 15:45:00,003::bindingxmlrpc::1297::XmlDetector::(handle_socket) xml over http detected from ('127.0.0.1', 55077)
BindingXMLRPC::INFO::2015-12-21 15:45:00,003::xmlrpc::73::vds.XMLRPCServer::(handle_request) Starting request handler for 127.0.0.1:55077
Thread-33::INFO::2015-12-21 15:45:00,003::xmlrpc::84::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55077 started
Thread-33::INFO::2015-12-21 15:45:00,004::xmlrpc::92::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55077 stopped
jsonrpc.Executor/2::DEBUG::2015-12-21 15:45:00,156::__init__::503::jsonrpc.JsonRpcServer::(_serveRequest) Calling 'Host.getCapabilities' in bridge with {}
jsonrpc.Executor/2::DEBUG::2015-12-21 15:45:00,166::utils::669::root::(execCmd) /usr/sbin/tc qdisc show (cwd None)
jsonrpc.Executor/2::DEBUG::2015-12-21 15:45:00,171::utils::687::root::(execCmd) SUCCESS: <err> = ''; <rc> = 0
jsonrpc.Executor/2::DEBUG::2015-12-21 15:45:00,178::__init__::533::jsonrpc.JsonRpcServer::(_serveRequest) Return 'Host.getCapabilities' in bridge with {'HBAInventory': {'iSCSI': [{'InitiatorName': 'iqn.1994-05.com.redhat:34c437e0e8'}], 'FC': []}, 'packages2': {'kernel': {'release': '327.el7.x86_64', 'buildtime': 1447967457.0, 'version': '3.10.0'}, 'spice-server': {'release': '15.el7', 'buildtime': 1448026017L, 'version': '0.12.4'}, 'librbd1': {'release': '3.el7', 'buildtime': 1448036424L, 'version': '0.80.7'}, 'vdsm': {'release': '0.el7.centos', 'buildtime': 1449649492L, 'version': '4.17.13'}, 'qemu-kvm': {'release': '31.el7_2.4.1', 'buildtime': 1450438721L, 'version': '2.3.0'}, 'libvirt': {'release': '13.el7_2.2', 'buildtime': 1449666817L, 'version': '1.2.17'}, 'qemu-img': {'release': '31.el7_2.4.1', 'buildtime': 1450438721L, 'version': '2.3.0'}, 'mom': {'release': '2.el7', 'buildtime': 1442501481L, 'version': '0.5.1'}, 'glusterfs-cli': {'release': '1.el7', 'buildtime': 1447082499L, 'version': '3.7.6'}}, 'numaNodeDistance': {'0': [10]}, 'cpuModel': 'Intel(R) Core(TM) i3-4130 CPU @ 3.40GHz', 'liveMerge': 'true', 'hooks': {}, 'vmTypes': ['kvm'], 'selinux': {'mode': '1'}, 'liveSnapshot': 'true', 'kdumpStatus': 0, 'networks': {}, 'bridges': {}, 'uuid': '0D7A721A-469D-ACD7-6A97-E03F49E8F1FD', 'onlineCpus': '0,1,2,3', 'nics': {'enp3s0': {'addr': '172.16.1.7', 'ipv6gateway': '::', 'ipv6addrs': ['fe80::e23f:49ff:fee8:f1fd/64'], 'mtu': '1500', 'dhcpv4': True, 'netmask': '255.255.255.0', 'dhcpv6': False, 'ipv4addrs': ['172.16.1.7/24'], 'cfg': {'PEERROUTES': 'yes', 'IPV6INIT': 'yes', 'NAME': 'enp3s0', 'ONBOOT': 'yes', 'DEFROUTE': 'yes', 'IPV6_PEERDNS': 'yes', 'IPADDR': '172.16.1.7', 'UUID': '5443a1c9-53be-4215-8317-669048b4e7ee', 'PEERDNS': 'yes', 'IPV4_FAILURE_FATAL': 'no', 'DEVICE': 'enp3s0', 'NETMASK': '255.255.255.0', 'BOOTPROTO': 'static', 'IPV6_DEFROUTE': 'yes', 'IPV6_AUTOCONF': 'yes', 'IPV6_FAILURE_FATAL': 'no', 'TYPE': 'Ethernet', 'GATEWAY': '172.16.1.200', 'IPV6_PEERROUTES': 'yes'}, 'hwaddr': 'e0:3f:49:e8:f1:fd', 'speed': 100, 'gateway': '172.16.1.200'}}, 'software_revision': '0', 'hostdevPassthrough': 'false', 'clusterLevels': ['3.4', '3.5', '3.6'], 'cpuFlags': 'fpu,vme,de,pse,tsc,msr,pae,mce,cx8,apic,sep,mtrr,pge,mca,cmov,pat,pse36,clflush,dts,acpi,mmx,fxsr,sse,sse2,ss,ht,tm,pbe,syscall,nx,pdpe1gb,rdtscp,lm,constant_tsc,arch_perfmon,pebs,bts,rep_good,nopl,xtopology,nonstop_tsc,aperfmperf,eagerfpu,pni,pclmulqdq,dtes64,monitor,ds_cpl,vmx,est,tm2,ssse3,fma,cx16,xtpr,pdcm,pcid,sse4_1,sse4_2,movbe,popcnt,tsc_deadline_timer,aes,xsave,avx,f16c,rdrand,lahf_lm,abm,arat,epb,pln,pts,dtherm,tpr_shadow,vnmi,flexpriority,ept,vpid,fsgsbase,tsc_adjust,bmi1,avx2,smep,bmi2,erms,invpcid,xsaveopt,model_Nehalem,model_Conroe,model_coreduo,model_core2duo,model_Penryn,model_Westmere,model_n270', 'ISCSIInitiatorName': 'iqn.1994-05.com.redhat:34c437e0e8', 'netConfigDirty': 'True', 'supportedENGINEs': ['3.4', '3.5', '3.6'], 'autoNumaBalancing': 0, 'additionalFeatures': [], 'reservedMem': '321', 'bondings': {'bond0': {'ipv4addrs': [], 'addr': '', 'cfg': {'BOOTPROTO': 'none'}, 'ipv6addrs': [], 'active_slave': '', 'mtu': '1500', 'dhcpv4': False, 'netmask': '', 'dhcpv6': False, 'slaves': [], 'hwaddr': '72:b6:58:ef:67:76', 'ipv6gateway': '::', 'gateway': '', 'opts': {}}}, 'software_version': '4.17', 'memSize': '3730', 'cpuSpeed': '3400.000', 'numaNodes': {'0': {'totalMemory': '3730', 'cpus': [0, 1, 2, 3]}}, 'cpuSockets': '1', 'vlans': {}, 'lastClientIface': 'enp3s0', 'cpuCores': '2', 'kvmEnabled': 'true', 'guestOverhead': '65', 'version_name': 'Snow Man', 'cpuThreads': '4', 'emulatedMachines': ['pc-i440fx-rhel7.1.0', 'rhel6.3.0', 'pc-q35-rhel7.2.0', 'pc-i440fx-rhel7.0.0', 'rhel6.1.0', 'rhel6.6.0', 'rhel6.2.0', 'pc', 'pc-q35-rhel7.0.0', 'pc-q35-rhel7.1.0', 'q35', 'pc-i440fx-rhel7.2.0', 'rhel6.4.0', 'rhel6.0.0', 'rhel6.5.0'], 'rngSources': ['random'], 'operatingSystem': {'release': '2.1511.el7.centos.2.10', 'version': '7', 'name': 'RHEL'}}
jsonrpc.Executor/3::DEBUG::2015-12-21 15:45:00,184::__init__::503::jsonrpc.JsonRpcServer::(_serveRequest) Calling 'Host.getHardwareInfo' in bridge with {}
jsonrpc.Executor/3::DEBUG::2015-12-21 15:45:00,185::__init__::533::jsonrpc.JsonRpcServer::(_serveRequest) Return 'Host.getHardwareInfo' in bridge with {'systemProductName': 'UNLOCK INSTALL', 'systemSerialNumber': '92171335', 'systemFamily': 'ASUS MB', 'systemVersion': 'Not used', 'systemUUID': '0D7A721A-469D-ACD7-6A97-E03F49E8F1FD', 'systemManufacturer': 'PRIMINFO'}
Reactor thread::INFO::2015-12-21 15:45:15,018::protocoldetector::72::ProtocolDetector.AcceptorImpl::(handle_accept) Accepting connection from 127.0.0.1:55078
Reactor thread::DEBUG::2015-12-21 15:45:15,023::protocoldetector::82::ProtocolDetector.Detector::(__init__) Using required_size=11
Reactor thread::INFO::2015-12-21 15:45:15,023::protocoldetector::118::ProtocolDetector.Detector::(handle_read) Detected protocol xml from 127.0.0.1:55078
Reactor thread::DEBUG::2015-12-21 15:45:15,023::bindingxmlrpc::1297::XmlDetector::(handle_socket) xml over http detected from ('127.0.0.1', 55078)
BindingXMLRPC::INFO::2015-12-21 15:45:15,023::xmlrpc::73::vds.XMLRPCServer::(handle_request) Starting request handler for 127.0.0.1:55078
Thread-34::INFO::2015-12-21 15:45:15,024::xmlrpc::84::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55078 started
Thread-34::INFO::2015-12-21 15:45:15,025::xmlrpc::92::vds.XMLRPCServer::(_process_requests) Request handler for 127.0.0.1:55078 stopped