Attachment 586598 Details for Bug 749479 – vdsm.log

vdsm.log

vdsm.log (text/plain), 48.49 KB, created by Guohua Ouyang on 2012-05-24 10:01:37 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Guohua Ouyang

Created: 2012-05-24 10:01:37 UTC

Size: 48.49 KB

patch

obsolete

>MainThread::INFO::2012-05-24 09:44:10,744::vdsm::70::vds::(run) I am the actual vdsm 4.9-0
>MainThread::DEBUG::2012-05-24 09:44:11,005::resourceManager::379::ResourceManager::(registerNamespace) Registering namespace 'Storage'
>MainThread::DEBUG::2012-05-24 09:44:11,006::threadPool::45::Misc.ThreadPool::(__init__) Enter - numThreads: 10.0, waitTimeout: 3, maxTasks: 500.0
>MainThread::DEBUG::2012-05-24 09:44:11,077::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/sudo -n /bin/cat /etc/multipath.conf' (cwd None)
>MainThread::DEBUG::2012-05-24 09:44:11,093::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>MainThread::DEBUG::2012-05-24 09:44:11,094::multipath::109::Storage.Multipath::(isEnabled) multipath Defaulting to False
>MainThread::DEBUG::2012-05-24 09:44:11,094::misc::533::Storage.Misc::(rotateFiles) dir: /etc, prefixName: multipath.conf, versions: 5
>MainThread::DEBUG::2012-05-24 09:44:11,095::misc::554::Storage.Misc::(rotateFiles) versions found: []
>MainThread::DEBUG::2012-05-24 09:44:11,095::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/sudo -n /bin/cp /tmp/tmpCWnfPA /etc/multipath.conf' (cwd None)
>MainThread::DEBUG::2012-05-24 09:44:11,106::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>MainThread::DEBUG::2012-05-24 09:44:11,107::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/sudo -n /usr/sbin/persist /etc/multipath.conf' (cwd None)
>MainThread::DEBUG::2012-05-24 09:44:11,116::__init__::1164::Storage.Misc.excCmd::(_log) FAILED: <err> = 'sudo: sorry, a password is required to run sudo\n'; <rc> = 1
>MainThread::DEBUG::2012-05-24 09:44:11,116::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/sudo -n /sbin/multipath -F' (cwd None)
>MainThread::DEBUG::2012-05-24 09:44:11,161::__init__::1164::Storage.Misc.excCmd::(_log) FAILED: <err> = ''; <rc> = 1
>MainThread::DEBUG::2012-05-24 09:44:11,165::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/sudo -n /sbin/service multipathd restart' (cwd None)
>MainThread::DEBUG::2012-05-24 09:44:11,330::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>MainThread::DEBUG::2012-05-24 09:44:11,331::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/sudo -n /sbin/lvm dumpconfig global/locking_type' (cwd None)
>MainThread::DEBUG::2012-05-24 09:44:11,446::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>MainThread::DEBUG::2012-05-24 09:44:11,446::lvm::333::OperationMutex::(_reloadpvs) Operation 'lvm reload operation' got the operation mutex
>MainThread::DEBUG::2012-05-24 09:44:11,449::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/sudo -n /sbin/lvm pvs --config " devices { preferred_names = [\\"^/dev/mapper/\\"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 filter = [ \\"a%1ATA_ST3500418AS_5VMMSZNQ|1ATA_ST3500418AS_5VMNB3E3%\\", \\"r%.*%\\" ] }  global {  locking_type=1  prioritise_write_locks=1  wait_for_locks=1 }  backup {  retain_min = 50  retain_days = 0 } " --noheadings --units b --nosuffix --separator | -o uuid,name,size,vg_name,vg_uuid,pe_start,pe_count,pe_alloc_count,mda_count,dev_size' (cwd None)
>MainThread::DEBUG::2012-05-24 09:44:11,675::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>MainThread::DEBUG::2012-05-24 09:44:11,676::lvm::356::OperationMutex::(_reloadpvs) Operation 'lvm reload operation' released the operation mutex
>MainThread::DEBUG::2012-05-24 09:44:11,676::lvm::366::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' got the operation mutex
>MainThread::DEBUG::2012-05-24 09:44:11,676::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/sudo -n /sbin/lvm vgs --config " devices { preferred_names = [\\"^/dev/mapper/\\"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 filter = [ \\"a%1ATA_ST3500418AS_5VMMSZNQ|1ATA_ST3500418AS_5VMNB3E3%\\", \\"r%.*%\\" ] }  global {  locking_type=1  prioritise_write_locks=1  wait_for_locks=1 }  backup {  retain_min = 50  retain_days = 0 } " --noheadings --units b --nosuffix --separator | -o uuid,name,attr,size,free,extent_size,extent_count,free_count,tags,vg_mda_size,vg_mda_free' (cwd None)
>MainThread::DEBUG::2012-05-24 09:44:11,807::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>MainThread::DEBUG::2012-05-24 09:44:11,809::lvm::393::OperationMutex::(_reloadvgs) Operation 'lvm reload operation' released the operation mutex
>MainThread::DEBUG::2012-05-24 09:44:11,809::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/sudo -n /sbin/lvm lvs --config " devices { preferred_names = [\\"^/dev/mapper/\\"] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 filter = [ \\"a%1ATA_ST3500418AS_5VMMSZNQ|1ATA_ST3500418AS_5VMNB3E3%\\", \\"r%.*%\\" ] }  global {  locking_type=1  prioritise_write_locks=1  wait_for_locks=1 }  backup {  retain_min = 50  retain_days = 0 } " --noheadings --units b --nosuffix --separator | -o uuid,name,vg_name,attr,size,seg_start_pe,devices,tags' (cwd None)
>MainThread::DEBUG::2012-05-24 09:44:11,940::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>Thread-12::DEBUG::2012-05-24 09:44:11,945::misc::1086::SamplingMethod::(__call__) Trying to enter sampling method (storage.sdc.refreshStorage)
>MainThread::INFO::2012-05-24 09:44:11,946::dispatcher::95::Storage.Dispatcher::(__init__) Starting StorageDispatcher...
>Thread-12::DEBUG::2012-05-24 09:44:11,947::misc::1088::SamplingMethod::(__call__) Got in to sampling method
>Thread-12::DEBUG::2012-05-24 09:44:11,947::misc::1086::SamplingMethod::(__call__) Trying to enter sampling method (storage.iscsi.rescan)
>Thread-12::DEBUG::2012-05-24 09:44:11,948::misc::1088::SamplingMethod::(__call__) Got in to sampling method
>Thread-12::DEBUG::2012-05-24 09:44:11,948::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/sudo -n /sbin/iscsiadm -m session -R' (cwd None)
>MainThread::DEBUG::2012-05-24 09:44:11,955::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/pgrep -xf ksmd' (cwd None)
>Thread-12::DEBUG::2012-05-24 09:44:11,980::__init__::1164::Storage.Misc.excCmd::(_log) FAILED: <err> = 'iscsiadm: No session found.\n'; <rc> = 21
>Thread-12::DEBUG::2012-05-24 09:44:11,981::misc::1096::SamplingMethod::(__call__) Returning last result
>Thread-12::DEBUG::2012-05-24 09:44:11,981::supervdsm::93::SuperVdsmProxy::(_killSupervdsm) Could not kill old Super Vdsm [Errno 2] No such file or directory: '/var/run/vdsm/svdsm.pid'
>Thread-12::DEBUG::2012-05-24 09:44:11,981::supervdsm::81::SuperVdsmProxy::(_launchSupervdsm) Launching Super Vdsm
>Thread-12::DEBUG::2012-05-24 09:44:11,982::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/sudo -n /usr/bin/python /usr/share/vdsm/supervdsmServer.pyc f1d8f1c3-a3a4-49b6-82b9-17cbb59f14a3 9315' (cwd None)
>MainThread::DEBUG::2012-05-24 09:44:11,999::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>MainThread::INFO::2012-05-24 09:44:12,000::vmChannels::139::vds::(settimeout) Setting channels' timeout to 30 seconds.
>VM Channels Listener::INFO::2012-05-24 09:44:12,001::vmChannels::127::vds::(run) Starting VM channels listener thread.
>MainThread::DEBUG::2012-05-24 09:44:12,128::supervdsmServer::232::SuperVdsm.Server::(main) Making sure I'm root
>MainThread::DEBUG::2012-05-24 09:44:12,129::supervdsmServer::236::SuperVdsm.Server::(main) Parsing cmd args
>MainThread::DEBUG::2012-05-24 09:44:12,129::supervdsmServer::239::SuperVdsm.Server::(main) Creating PID file
>MainThread::DEBUG::2012-05-24 09:44:12,129::supervdsmServer::243::SuperVdsm.Server::(main) Cleaning old socket
>MainThread::DEBUG::2012-05-24 09:44:12,130::supervdsmServer::247::SuperVdsm.Server::(main) Setting up keep alive thread
>MainThread::DEBUG::2012-05-24 09:44:12,130::supervdsmServer::252::SuperVdsm.Server::(main) Creating remote object manager
>MainThread::DEBUG::2012-05-24 09:44:12,131::supervdsmServer::263::SuperVdsm.Server::(main) Started serving super vdsm object
>Thread-14::DEBUG::2012-05-24 09:44:13,074::BindingXMLRPC::864::vds::(wrapper) client [10.66.104.232]::call getCapabilities with () {}
>Thread-14::DEBUG::2012-05-24 09:44:13,324::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/rpm -q --qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" qemu-img-rhev' (cwd None)
>Thread-14::DEBUG::2012-05-24 09:44:13,361::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>Thread-14::DEBUG::2012-05-24 09:44:13,362::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/rpm -q --qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" qemu-kvm-rhev' (cwd None)
>Thread-14::DEBUG::2012-05-24 09:44:13,406::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>Thread-14::DEBUG::2012-05-24 09:44:13,407::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/rpm -q --qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" vdsm' (cwd None)
>Thread-14::DEBUG::2012-05-24 09:44:13,426::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>Thread-14::DEBUG::2012-05-24 09:44:13,426::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/rpm -q --qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" spice-server' (cwd None)
>Thread-14::DEBUG::2012-05-24 09:44:13,445::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>Thread-14::DEBUG::2012-05-24 09:44:13,446::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/rpm -q --qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" libvirt' (cwd None)
>Thread-14::DEBUG::2012-05-24 09:44:13,468::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>Thread-14::DEBUG::2012-05-24 09:44:13,704::BindingXMLRPC::870::vds::(wrapper) return getCapabilities with {'status': {'message': 'Done', 'code': 0}, 'info': {'HBAInventory': {'iSCSI': [{'InitiatorName': 'iqn.1994-05.com.redhat:fcce7ec5233e'}], 'FC': []}, 'packages2': {'kernel': {'release': '270.el6.x86_64', 'buildtime': 1336513483.0, 'version': '2.6.32'}, 'qemu-kvm-rhev': {'release': '2.292.el6', 'buildtime': '1336502700', 'version': '0.12.1.2'}, 'spice-server': {'release': '6.el6', 'buildtime': '1336391789', 'version': '0.10.1'}, 'vdsm': {'release': '11.el6', 'buildtime': '1336575748', 'version': '4.9.6'}, 'libvirt': {'release': '18.el6', 'buildtime': '1336550939', 'version': '0.9.10'}, 'qemu-img-rhev': {'release': '2.292.el6', 'buildtime': '1336502700', 'version': '0.12.1.2'}}, 'cpuModel': 'Intel(R) Core(TM)2 Quad CPU    Q9500  @ 2.83GHz', 'hooks': {'before_vm_start': {'50_vhostmd': {'md5': '2aa9ac48ef07de3c94e3428975e9df1a'}}, 'after_vm_destroy': {'50_vhostmd': {'md5': '47f8d385859e4c3c96113d8ff446b261'}}, 'before_vm_dehibernate': {'50_vhostmd': {'md5': '2aa9ac48ef07de3c94e3428975e9df1a'}}, 'before_vm_migrate_destination': {'50_vhostmd': {'md5': '2aa9ac48ef07de3c94e3428975e9df1a'}}}, 'vmTypes': ['kvm'], 'supportedProtocols': ['2.2', '2.3'], 'networks': {'rhevm': {'addr': '10.66.9.50', 'cfg': {'IPV6FORWARDING': 'no', 'IPV6INIT': 'no', 'IPV6_AUTOCONF': 'no', 'DELAY': '0', 'NM_CONTROLLED': 'no', 'BOOTPROTO': 'dhcp', 'DEVICE': 'rhevm', 'PEERNTP': 'yes', 'TYPE': 'Bridge', 'ONBOOT': 'yes'}, 'mtu': '1500', 'netmask': '255.255.252.0', 'stp': 'off', 'bridged': True, 'gateway': '10.66.11.254', 'ports': ['eth1']}}, 'uuid': '002D8B01-C837-E011-9B91-81F97A74282D_00:0E:0C:B0:9A:0D', 'lastClientIface': 'rhevm', 'nics': {'eth2': {'hwaddr': '00:0E:0C:B0:9A:0D', 'netmask': '', 'speed': 0, 'addr': '', 'mtu': '1500'}, 'eth1': {'hwaddr': '00:1B:21:27:47:0B', 'netmask': '', 'speed': 1000, 'addr': '', 'mtu': '1500'}, 'eth0': {'hwaddr': '1C:6F:65:06:BA:AE', 'netmask': '', 'speed': 0, 'addr': '', 'mtu': '1500'}}, 'software_revision': '0', 'management_ip': '', 'clusterLevels': ['3.0'], 'cpuFlags': u'fpu,vme,de,pse,tsc,msr,pae,mce,cx8,apic,mtrr,pge,mca,cmov,pat,pse36,clflush,dts,acpi,mmx,fxsr,sse,sse2,ss,ht,tm,pbe,syscall,nx,lm,constant_tsc,arch_perfmon,pebs,bts,rep_good,aperfmperf,pni,dtes64,monitor,ds_cpl,vmx,est,tm2,ssse3,cx16,xtpr,pdcm,sse4_1,xsave,lahf_lm,dts,tpr_shadow,vnmi,flexpriority,model_486,model_pentium,model_pentium2,model_pentium3,model_pentiumpro,model_qemu32,model_cpu64-rhel5,model_cpu64-rhel6,model_kvm32,model_coreduo,model_kvm64,model_core2duo,model_n270,model_Conroe,model_Penryn,model_Opteron_G1', 'ISCSIInitiatorName': 'iqn.1994-05.com.redhat:fcce7ec5233e', 'memSize': '7586', 'reservedMem': '321', 'bondings': {'bond4': {'addr': '', 'cfg': {}, 'mtu': '1500', 'netmask': '', 'slaves': [], 'hwaddr': '00:00:00:00:00:00'}, 'bond0': {'addr': '', 'cfg': {}, 'mtu': '1500', 'netmask': '', 'slaves': [], 'hwaddr': '00:00:00:00:00:00'}, 'bond1': {'addr': '', 'cfg': {}, 'mtu': '1500', 'netmask': '', 'slaves': [], 'hwaddr': '00:00:00:00:00:00'}, 'bond2': {'addr': '', 'cfg': {}, 'mtu': '1500', 'netmask': '', 'slaves': [], 'hwaddr': '00:00:00:00:00:00'}, 'bond3': {'addr': '', 'cfg': {}, 'mtu': '1500', 'netmask': '', 'slaves': [], 'hwaddr': '00:00:00:00:00:00'}}, 'software_version': '4.9', 'cpuSpeed': '2826.337', 'cpuSockets': '1', 'vlans': {}, 'cpuCores': '4', 'kvmEnabled': 'true', 'guestOverhead': '65', 'supportedRHEVMs': ['3.0'], 'version_name': 'Snow Man', 'emulatedMachines': [u'rhel6.3.0', u'pc', u'rhel6.2.0', u'rhel6.1.0', u'rhel6.0.0', u'rhel5.5.0', u'rhel5.4.4', u'rhel5.4.0'], 'operatingSystem': {'release': '6.3.0.2.el6', 'version': '6Server', 'name': 'RHEV Hypervisor'}, 'lastClient': '0.0.0.0'}}
>Thread-15::DEBUG::2012-05-24 09:44:13,784::BindingXMLRPC::864::vds::(wrapper) client [10.66.104.232]::call getCapabilities with () {}
>Thread-15::DEBUG::2012-05-24 09:44:13,802::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/rpm -q --qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" qemu-img-rhev' (cwd None)
>Thread-15::DEBUG::2012-05-24 09:44:13,825::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>Thread-15::DEBUG::2012-05-24 09:44:13,826::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/rpm -q --qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" qemu-kvm-rhev' (cwd None)
>Thread-15::DEBUG::2012-05-24 09:44:13,849::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>Thread-15::DEBUG::2012-05-24 09:44:13,850::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/rpm -q --qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" vdsm' (cwd None)
>Thread-15::DEBUG::2012-05-24 09:44:13,868::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>Thread-15::DEBUG::2012-05-24 09:44:13,869::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/rpm -q --qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" spice-server' (cwd None)
>Thread-15::DEBUG::2012-05-24 09:44:13,887::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>Thread-15::DEBUG::2012-05-24 09:44:13,887::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/rpm -q --qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" libvirt' (cwd None)
>Thread-15::DEBUG::2012-05-24 09:44:13,907::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>Thread-15::DEBUG::2012-05-24 09:44:13,908::BindingXMLRPC::870::vds::(wrapper) return getCapabilities with {'status': {'message': 'Done', 'code': 0}, 'info': {'HBAInventory': {'iSCSI': [{'InitiatorName': 'iqn.1994-05.com.redhat:fcce7ec5233e'}], 'FC': []}, 'packages2': {'kernel': {'release': '270.el6.x86_64', 'buildtime': 1336513483.0, 'version': '2.6.32'}, 'qemu-kvm-rhev': {'release': '2.292.el6', 'buildtime': '1336502700', 'version': '0.12.1.2'}, 'spice-server': {'release': '6.el6', 'buildtime': '1336391789', 'version': '0.10.1'}, 'vdsm': {'release': '11.el6', 'buildtime': '1336575748', 'version': '4.9.6'}, 'libvirt': {'release': '18.el6', 'buildtime': '1336550939', 'version': '0.9.10'}, 'qemu-img-rhev': {'release': '2.292.el6', 'buildtime': '1336502700', 'version': '0.12.1.2'}}, 'cpuModel': 'Intel(R) Core(TM)2 Quad CPU    Q9500  @ 2.83GHz', 'hooks': {'before_vm_start': {'50_vhostmd': {'md5': '2aa9ac48ef07de3c94e3428975e9df1a'}}, 'after_vm_destroy': {'50_vhostmd': {'md5': '47f8d385859e4c3c96113d8ff446b261'}}, 'before_vm_dehibernate': {'50_vhostmd': {'md5': '2aa9ac48ef07de3c94e3428975e9df1a'}}, 'before_vm_migrate_destination': {'50_vhostmd': {'md5': '2aa9ac48ef07de3c94e3428975e9df1a'}}}, 'vmTypes': ['kvm'], 'supportedProtocols': ['2.2', '2.3'], 'networks': {'rhevm': {'addr': '10.66.9.50', 'cfg': {'IPV6FORWARDING': 'no', 'IPV6INIT': 'no', 'IPV6_AUTOCONF': 'no', 'DELAY': '0', 'NM_CONTROLLED': 'no', 'BOOTPROTO': 'dhcp', 'DEVICE': 'rhevm', 'PEERNTP': 'yes', 'TYPE': 'Bridge', 'ONBOOT': 'yes'}, 'mtu': '1500', 'netmask': '255.255.252.0', 'stp': 'off', 'bridged': True, 'gateway': '10.66.11.254', 'ports': ['eth1']}}, 'uuid': '002D8B01-C837-E011-9B91-81F97A74282D_00:0E:0C:B0:9A:0D', 'lastClientIface': 'rhevm', 'nics': {'eth2': {'hwaddr': '00:0E:0C:B0:9A:0D', 'netmask': '', 'speed': 0, 'addr': '', 'mtu': '1500'}, 'eth1': {'hwaddr': '00:1B:21:27:47:0B', 'netmask': '', 'speed': 1000, 'addr': '', 'mtu': '1500'}, 'eth0': {'hwaddr': '1C:6F:65:06:BA:AE', 'netmask': '', 'speed': 0, 'addr': '', 'mtu': '1500'}}, 'software_revision': '0', 'management_ip': '', 'clusterLevels': ['3.0'], 'cpuFlags': u'fpu,vme,de,pse,tsc,msr,pae,mce,cx8,apic,mtrr,pge,mca,cmov,pat,pse36,clflush,dts,acpi,mmx,fxsr,sse,sse2,ss,ht,tm,pbe,syscall,nx,lm,constant_tsc,arch_perfmon,pebs,bts,rep_good,aperfmperf,pni,dtes64,monitor,ds_cpl,vmx,est,tm2,ssse3,cx16,xtpr,pdcm,sse4_1,xsave,lahf_lm,dts,tpr_shadow,vnmi,flexpriority,model_486,model_pentium,model_pentium2,model_pentium3,model_pentiumpro,model_qemu32,model_cpu64-rhel5,model_cpu64-rhel6,model_kvm32,model_coreduo,model_kvm64,model_core2duo,model_n270,model_Conroe,model_Penryn,model_Opteron_G1', 'ISCSIInitiatorName': 'iqn.1994-05.com.redhat:fcce7ec5233e', 'memSize': '7586', 'reservedMem': '321', 'bondings': {'bond4': {'addr': '', 'cfg': {}, 'mtu': '1500', 'netmask': '', 'slaves': [], 'hwaddr': '00:00:00:00:00:00'}, 'bond0': {'addr': '', 'cfg': {}, 'mtu': '1500', 'netmask': '', 'slaves': [], 'hwaddr': '00:00:00:00:00:00'}, 'bond1': {'addr': '', 'cfg': {}, 'mtu': '1500', 'netmask': '', 'slaves': [], 'hwaddr': '00:00:00:00:00:00'}, 'bond2': {'addr': '', 'cfg': {}, 'mtu': '1500', 'netmask': '', 'slaves': [], 'hwaddr': '00:00:00:00:00:00'}, 'bond3': {'addr': '', 'cfg': {}, 'mtu': '1500', 'netmask': '', 'slaves': [], 'hwaddr': '00:00:00:00:00:00'}}, 'software_version': '4.9', 'cpuSpeed': '2826.337', 'cpuSockets': '1', 'vlans': {}, 'cpuCores': '4', 'kvmEnabled': 'true', 'guestOverhead': '65', 'supportedRHEVMs': ['3.0'], 'version_name': 'Snow Man', 'emulatedMachines': [u'rhel6.3.0', u'pc', u'rhel6.2.0', u'rhel6.1.0', u'rhel6.0.0', u'rhel5.5.0', u'rhel5.4.4', u'rhel5.4.0'], 'operatingSystem': {'release': '6.3.0.2.el6', 'version': '6Server', 'name': 'RHEV Hypervisor'}, 'lastClient': '10.66.104.232'}}
>Thread-12::DEBUG::2012-05-24 09:44:13,991::supervdsm::102::SuperVdsmProxy::(_connect) Trying to connect to Super Vdsm
>Thread-12::DEBUG::2012-05-24 09:44:13,995::supervdsm::74::SuperVdsmProxy::(__init__) Connected to Super Vdsm
>Thread-12::DEBUG::2012-05-24 09:44:15,222::__init__::1164::Storage.Misc.excCmd::(_log) '/usr/bin/sudo -n /sbin/multipath' (cwd None)
>Thread-12::DEBUG::2012-05-24 09:44:15,275::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>Thread-12::DEBUG::2012-05-24 09:44:15,276::lvm::474::OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' got the operation mutex
>Thread-12::DEBUG::2012-05-24 09:44:15,276::lvm::476::OperationMutex::(_invalidateAllPvs) Operation 'lvm invalidate operation' released the operation mutex
>Thread-12::DEBUG::2012-05-24 09:44:15,277::lvm::486::OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' got the operation mutex
>Thread-12::DEBUG::2012-05-24 09:44:15,277::lvm::488::OperationMutex::(_invalidateAllVgs) Operation 'lvm invalidate operation' released the operation mutex
>Thread-12::DEBUG::2012-05-24 09:44:15,277::lvm::507::OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' got the operation mutex
>Thread-12::DEBUG::2012-05-24 09:44:15,277::lvm::509::OperationMutex::(_invalidateAllLvs) Operation 'lvm invalidate operation' released the operation mutex
>Thread-12::DEBUG::2012-05-24 09:44:15,278::misc::1096::SamplingMethod::(__call__) Returning last result
>Thread-12::DEBUG::2012-05-24 09:44:15,278::hsm::360::Storage.HSM::(__cleanStorageRepository) Started cleaning storage repository at '/rhev/data-center'
>Thread-12::DEBUG::2012-05-24 09:44:15,282::hsm::392::Storage.HSM::(__cleanStorageRepository) White list: ['/rhev/data-center/hsm-tasks', '/rhev/data-center/hsm-tasks/*', '/rhev/data-center/mnt']
>Thread-12::DEBUG::2012-05-24 09:44:15,283::hsm::393::Storage.HSM::(__cleanStorageRepository) Mount list: []
>Thread-12::DEBUG::2012-05-24 09:44:15,283::hsm::395::Storage.HSM::(__cleanStorageRepository) Cleaning leftovers
>Thread-12::DEBUG::2012-05-24 09:44:15,285::hsm::438::Storage.HSM::(__cleanStorageRepository) Finished cleaning storage repository at '/rhev/data-center'
>Thread-18::DEBUG::2012-05-24 09:44:18,067::task::588::TaskManager.Task::(_updateState) Task=`4db15972-4620-4c6d-bf82-4fb2c608f363`::moving from state init -> state preparing
>Thread-18::INFO::2012-05-24 09:44:18,067::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-18::INFO::2012-05-24 09:44:18,067::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-18::DEBUG::2012-05-24 09:44:18,067::task::1172::TaskManager.Task::(prepare) Task=`4db15972-4620-4c6d-bf82-4fb2c608f363`::finished: {}
>Thread-18::DEBUG::2012-05-24 09:44:18,068::task::588::TaskManager.Task::(_updateState) Task=`4db15972-4620-4c6d-bf82-4fb2c608f363`::moving from state preparing -> state finished
>Thread-18::DEBUG::2012-05-24 09:44:18,068::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-18::DEBUG::2012-05-24 09:44:18,068::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-18::DEBUG::2012-05-24 09:44:18,068::task::978::TaskManager.Task::(_decref) Task=`4db15972-4620-4c6d-bf82-4fb2c608f363`::ref 0 aborting False
>Thread-24::DEBUG::2012-05-24 09:44:28,201::task::588::TaskManager.Task::(_updateState) Task=`f4bef334-b854-4875-b4b9-f4f9920ef2b5`::moving from state init -> state preparing
>Thread-24::INFO::2012-05-24 09:44:28,201::logUtils::37::dispatcher::(wrapper) Run and protect: repoStats(options=None)
>Thread-24::INFO::2012-05-24 09:44:28,201::logUtils::39::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}
>Thread-24::DEBUG::2012-05-24 09:44:28,202::task::1172::TaskManager.Task::(prepare) Task=`f4bef334-b854-4875-b4b9-f4f9920ef2b5`::finished: {}
>Thread-24::DEBUG::2012-05-24 09:44:28,202::task::588::TaskManager.Task::(_updateState) Task=`f4bef334-b854-4875-b4b9-f4f9920ef2b5`::moving from state preparing -> state finished
>Thread-24::DEBUG::2012-05-24 09:44:28,202::resourceManager::809::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}
>Thread-24::DEBUG::2012-05-24 09:44:28,202::resourceManager::844::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}
>Thread-24::DEBUG::2012-05-24 09:44:28,202::task::978::TaskManager.Task::(_decref) Task=`f4bef334-b854-4875-b4b9-f4f9920ef2b5`::ref 0 aborting False
>Thread-26::DEBUG::2012-05-24 09:44:38,004::BindingXMLRPC::864::vds::(wrapper) client [10.66.104.232]::call getCapabilities with () {}
>Thread-27::DEBUG::2012-05-24 09:44:38,009::BindingXMLRPC::864::vds::(wrapper) client [10.66.104.232]::call editNetwork with ('rhevm', 'rhevm', '', 'bond4', ['eth1', 'eth0'], {'connectivityCheck': 'true', 'BOOTPROTO': 'dhcp', 'STP': 'no', 'connectivityTimeout': '120', 'BONDING_OPTS': 'mode=4'}) {}
>Thread-27::WARNING::2012-05-24 09:44:38,011::API::1333::vds::(_translateOptionsToNew) options BOOTPROTO is deprecated. Use bootproto instead
>Thread-27::WARNING::2012-05-24 09:44:38,012::API::1333::vds::(_translateOptionsToNew) options BONDING_OPTS is deprecated. Use bondingOptions instead
>Thread-26::DEBUG::2012-05-24 09:44:38,025::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/rpm -q --qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" qemu-img-rhev' (cwd None)
>Thread-26::DEBUG::2012-05-24 09:44:38,051::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>Thread-26::DEBUG::2012-05-24 09:44:38,051::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/rpm -q --qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" qemu-kvm-rhev' (cwd None)
>MainProcess|Thread-27::INFO::2012-05-24 09:44:38,057::configNetwork::726::root::(delNetwork) Removing network rhevm with vlan=None, bonding=None, nics=['eth1']. options={'connectivityCheck': 'true', 'bootproto': 'dhcp', 'STP': 'no', 'connectivityTimeout': '120', 'bondingOptions': 'mode=4'}
>Thread-26::DEBUG::2012-05-24 09:44:38,077::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>Thread-26::DEBUG::2012-05-24 09:44:38,077::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/rpm -q --qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" vdsm' (cwd None)
>Thread-26::DEBUG::2012-05-24 09:44:38,097::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>Thread-26::DEBUG::2012-05-24 09:44:38,098::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/rpm -q --qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" spice-server' (cwd None)
>Thread-26::DEBUG::2012-05-24 09:44:38,116::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>Thread-26::DEBUG::2012-05-24 09:44:38,117::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/rpm -q --qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" libvirt' (cwd None)
>Thread-26::DEBUG::2012-05-24 09:44:38,138::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>Thread-26::DEBUG::2012-05-24 09:44:38,139::BindingXMLRPC::870::vds::(wrapper) return getCapabilities with {'status': {'message': 'Done', 'code': 0}, 'info': {'HBAInventory': {'iSCSI': [{'InitiatorName': 'iqn.1994-05.com.redhat:fcce7ec5233e'}], 'FC': []}, 'packages2': {'kernel': {'release': '270.el6.x86_64', 'buildtime': 1336513483.0, 'version': '2.6.32'}, 'qemu-kvm-rhev': {'release': '2.292.el6', 'buildtime': '1336502700', 'version': '0.12.1.2'}, 'spice-server': {'release': '6.el6', 'buildtime': '1336391789', 'version': '0.10.1'}, 'vdsm': {'release': '11.el6', 'buildtime': '1336575748', 'version': '4.9.6'}, 'libvirt': {'release': '18.el6', 'buildtime': '1336550939', 'version': '0.9.10'}, 'qemu-img-rhev': {'release': '2.292.el6', 'buildtime': '1336502700', 'version': '0.12.1.2'}}, 'cpuModel': 'Intel(R) Core(TM)2 Quad CPU    Q9500  @ 2.83GHz', 'hooks': {'before_vm_start': {'50_vhostmd': {'md5': '2aa9ac48ef07de3c94e3428975e9df1a'}}, 'after_vm_destroy': {'50_vhostmd': {'md5': '47f8d385859e4c3c96113d8ff446b261'}}, 'before_vm_dehibernate': {'50_vhostmd': {'md5': '2aa9ac48ef07de3c94e3428975e9df1a'}}, 'before_vm_migrate_destination': {'50_vhostmd': {'md5': '2aa9ac48ef07de3c94e3428975e9df1a'}}}, 'vmTypes': ['kvm'], 'supportedProtocols': ['2.2', '2.3'], 'networks': {'rhevm': {'addr': '10.66.9.50', 'cfg': {'IPV6FORWARDING': 'no', 'IPV6INIT': 'no', 'IPV6_AUTOCONF': 'no', 'DELAY': '0', 'NM_CONTROLLED': 'no', 'BOOTPROTO': 'dhcp', 'DEVICE': 'rhevm', 'PEERNTP': 'yes', 'TYPE': 'Bridge', 'ONBOOT': 'yes'}, 'mtu': '1500', 'netmask': '255.255.252.0', 'stp': 'off', 'bridged': True, 'gateway': '10.66.11.254', 'ports': ['eth1']}}, 'uuid': '002D8B01-C837-E011-9B91-81F97A74282D_00:0E:0C:B0:9A:0D', 'lastClientIface': 'rhevm', 'nics': {'eth2': {'hwaddr': '00:0E:0C:B0:9A:0D', 'netmask': '', 'speed': 0, 'addr': '', 'mtu': '1500'}, 'eth1': {'hwaddr': '00:1B:21:27:47:0B', 'netmask': '', 'speed': 1000, 'addr': '', 'mtu': '1500'}, 'eth0': {'hwaddr': '1C:6F:65:06:BA:AE', 'netmask': '', 'speed': 0, 'addr': '', 'mtu': '1500'}}, 'software_revision': '0', 'management_ip': '', 'clusterLevels': ['3.0'], 'cpuFlags': u'fpu,vme,de,pse,tsc,msr,pae,mce,cx8,apic,mtrr,pge,mca,cmov,pat,pse36,clflush,dts,acpi,mmx,fxsr,sse,sse2,ss,ht,tm,pbe,syscall,nx,lm,constant_tsc,arch_perfmon,pebs,bts,rep_good,aperfmperf,pni,dtes64,monitor,ds_cpl,vmx,est,tm2,ssse3,cx16,xtpr,pdcm,sse4_1,xsave,lahf_lm,dts,tpr_shadow,vnmi,flexpriority,model_486,model_pentium,model_pentium2,model_pentium3,model_pentiumpro,model_qemu32,model_cpu64-rhel5,model_cpu64-rhel6,model_kvm32,model_coreduo,model_kvm64,model_core2duo,model_n270,model_Conroe,model_Penryn,model_Opteron_G1', 'ISCSIInitiatorName': 'iqn.1994-05.com.redhat:fcce7ec5233e', 'memSize': '7586', 'reservedMem': '321', 'bondings': {'bond4': {'addr': '', 'cfg': {}, 'mtu': '1500', 'netmask': '', 'slaves': [], 'hwaddr': '00:00:00:00:00:00'}, 'bond0': {'addr': '', 'cfg': {}, 'mtu': '1500', 'netmask': '', 'slaves': [], 'hwaddr': '00:00:00:00:00:00'}, 'bond1': {'addr': '', 'cfg': {}, 'mtu': '1500', 'netmask': '', 'slaves': [], 'hwaddr': '00:00:00:00:00:00'}, 'bond2': {'addr': '', 'cfg': {}, 'mtu': '1500', 'netmask': '', 'slaves': [], 'hwaddr': '00:00:00:00:00:00'}, 'bond3': {'addr': '', 'cfg': {}, 'mtu': '1500', 'netmask': '', 'slaves': [], 'hwaddr': '00:00:00:00:00:00'}}, 'software_version': '4.9', 'cpuSpeed': '2826.337', 'cpuSockets': '1', 'vlans': {}, 'cpuCores': '4', 'kvmEnabled': 'true', 'guestOverhead': '65', 'supportedRHEVMs': ['3.0'], 'version_name': 'Snow Man', 'emulatedMachines': [u'rhel6.3.0', u'pc', u'rhel6.2.0', u'rhel6.1.0', u'rhel6.0.0', u'rhel5.5.0', u'rhel5.4.4', u'rhel5.4.0'], 'operatingSystem': {'release': '6.3.0.2.el6', 'version': '6Server', 'name': 'RHEV Hypervisor'}, 'lastClient': '10.66.104.232'}}
>Thread-28::DEBUG::2012-05-24 09:44:38,173::BindingXMLRPC::864::vds::(wrapper) client [10.66.104.232]::call getCapabilities with () {}
>Thread-28::DEBUG::2012-05-24 09:44:38,188::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/rpm -q --qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" qemu-img-rhev' (cwd None)
>Thread-28::DEBUG::2012-05-24 09:44:38,213::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>Thread-28::DEBUG::2012-05-24 09:44:38,213::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/rpm -q --qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" qemu-kvm-rhev' (cwd None)
>Thread-28::DEBUG::2012-05-24 09:44:38,237::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>Thread-28::DEBUG::2012-05-24 09:44:38,237::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/rpm -q --qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" vdsm' (cwd None)
>Thread-28::DEBUG::2012-05-24 09:44:38,256::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>Thread-28::DEBUG::2012-05-24 09:44:38,257::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/rpm -q --qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" spice-server' (cwd None)
>Thread-28::DEBUG::2012-05-24 09:44:38,275::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>Thread-28::DEBUG::2012-05-24 09:44:38,276::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/rpm -q --qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" libvirt' (cwd None)
>Thread-28::DEBUG::2012-05-24 09:44:38,301::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>Thread-28::DEBUG::2012-05-24 09:44:38,302::BindingXMLRPC::870::vds::(wrapper) return getCapabilities with {'status': {'message': 'Done', 'code': 0}, 'info': {'HBAInventory': {'iSCSI': [{'InitiatorName': 'iqn.1994-05.com.redhat:fcce7ec5233e'}], 'FC': []}, 'packages2': {'kernel': {'release': '270.el6.x86_64', 'buildtime': 1336513483.0, 'version': '2.6.32'}, 'qemu-kvm-rhev': {'release': '2.292.el6', 'buildtime': '1336502700', 'version': '0.12.1.2'}, 'spice-server': {'release': '6.el6', 'buildtime': '1336391789', 'version': '0.10.1'}, 'vdsm': {'release': '11.el6', 'buildtime': '1336575748', 'version': '4.9.6'}, 'libvirt': {'release': '18.el6', 'buildtime': '1336550939', 'version': '0.9.10'}, 'qemu-img-rhev': {'release': '2.292.el6', 'buildtime': '1336502700', 'version': '0.12.1.2'}}, 'cpuModel': 'Intel(R) Core(TM)2 Quad CPU    Q9500  @ 2.83GHz', 'hooks': {'before_vm_start': {'50_vhostmd': {'md5': '2aa9ac48ef07de3c94e3428975e9df1a'}}, 'after_vm_destroy': {'50_vhostmd': {'md5': '47f8d385859e4c3c96113d8ff446b261'}}, 'before_vm_dehibernate': {'50_vhostmd': {'md5': '2aa9ac48ef07de3c94e3428975e9df1a'}}, 'before_vm_migrate_destination': {'50_vhostmd': {'md5': '2aa9ac48ef07de3c94e3428975e9df1a'}}}, 'vmTypes': ['kvm'], 'supportedProtocols': ['2.2', '2.3'], 'networks': {}, 'uuid': '002D8B01-C837-E011-9B91-81F97A74282D_00:0E:0C:B0:9A:0D', 'lastClientIface': 'rhevm', 'nics': {'eth2': {'hwaddr': '00:0E:0C:B0:9A:0D', 'netmask': '', 'speed': 0, 'addr': '', 'mtu': '1500'}, 'eth1': {'hwaddr': '00:1B:21:27:47:0B', 'netmask': '', 'speed': 1000, 'addr': '', 'mtu': '1500'}, 'eth0': {'hwaddr': '1C:6F:65:06:BA:AE', 'netmask': '', 'speed': 0, 'addr': '', 'mtu': '1500'}}, 'software_revision': '0', 'management_ip': '', 'clusterLevels': ['3.0'], 'cpuFlags': u'fpu,vme,de,pse,tsc,msr,pae,mce,cx8,apic,mtrr,pge,mca,cmov,pat,pse36,clflush,dts,acpi,mmx,fxsr,sse,sse2,ss,ht,tm,pbe,syscall,nx,lm,constant_tsc,arch_perfmon,pebs,bts,rep_good,aperfmperf,pni,dtes64,monitor,ds_cpl,vmx,est,tm2,ssse3,cx16,xtpr,pdcm,sse4_1,xsave,lahf_lm,dts,tpr_shadow,vnmi,flexpriority,model_486,model_pentium,model_pentium2,model_pentium3,model_pentiumpro,model_qemu32,model_cpu64-rhel5,model_cpu64-rhel6,model_kvm32,model_coreduo,model_kvm64,model_core2duo,model_n270,model_Conroe,model_Penryn,model_Opteron_G1', 'ISCSIInitiatorName': 'iqn.1994-05.com.redhat:fcce7ec5233e', 'memSize': '7586', 'reservedMem': '321', 'bondings': {'bond4': {'addr': '', 'cfg': {}, 'mtu': '1500', 'netmask': '', 'slaves': [], 'hwaddr': '00:00:00:00:00:00'}, 'bond0': {'addr': '', 'cfg': {}, 'mtu': '1500', 'netmask': '', 'slaves': [], 'hwaddr': '00:00:00:00:00:00'}, 'bond1': {'addr': '', 'cfg': {}, 'mtu': '1500', 'netmask': '', 'slaves': [], 'hwaddr': '00:00:00:00:00:00'}, 'bond2': {'addr': '', 'cfg': {}, 'mtu': '1500', 'netmask': '', 'slaves': [], 'hwaddr': '00:00:00:00:00:00'}, 'bond3': {'addr': '', 'cfg': {}, 'mtu': '1500', 'netmask': '', 'slaves': [], 'hwaddr': '00:00:00:00:00:00'}}, 'software_version': '4.9', 'cpuSpeed': '2826.337', 'cpuSockets': '1', 'vlans': {}, 'cpuCores': '4', 'kvmEnabled': 'true', 'guestOverhead': '65', 'supportedRHEVMs': ['3.0'], 'version_name': 'Snow Man', 'emulatedMachines': [u'rhel6.3.0', u'pc', u'rhel6.2.0', u'rhel6.1.0', u'rhel6.0.0', u'rhel5.5.0', u'rhel5.4.4', u'rhel5.4.0'], 'operatingSystem': {'release': '6.3.0.2.el6', 'version': '6Server', 'name': 'RHEV Hypervisor'}, 'lastClient': '10.66.104.232'}}
>Thread-29::DEBUG::2012-05-24 09:44:38,331::BindingXMLRPC::864::vds::(wrapper) client [10.66.104.232]::call getCapabilities with () {}
>Thread-29::DEBUG::2012-05-24 09:44:38,347::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/rpm -q --qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" qemu-img-rhev' (cwd None)
>Thread-29::DEBUG::2012-05-24 09:44:38,371::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>Thread-29::DEBUG::2012-05-24 09:44:38,371::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/rpm -q --qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" qemu-kvm-rhev' (cwd None)
>Thread-29::DEBUG::2012-05-24 09:44:38,395::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>Thread-29::DEBUG::2012-05-24 09:44:38,396::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/rpm -q --qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" vdsm' (cwd None)
>Thread-29::DEBUG::2012-05-24 09:44:38,415::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>Thread-29::DEBUG::2012-05-24 09:44:38,415::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/rpm -q --qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" spice-server' (cwd None)
>Thread-29::DEBUG::2012-05-24 09:44:38,434::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>Thread-29::DEBUG::2012-05-24 09:44:38,434::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/rpm -q --qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" libvirt' (cwd None)
>Thread-29::DEBUG::2012-05-24 09:44:38,454::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>Thread-29::DEBUG::2012-05-24 09:44:38,455::BindingXMLRPC::870::vds::(wrapper) return getCapabilities with {'status': {'message': 'Done', 'code': 0}, 'info': {'HBAInventory': {'iSCSI': [{'InitiatorName': 'iqn.1994-05.com.redhat:fcce7ec5233e'}], 'FC': []}, 'packages2': {'kernel': {'release': '270.el6.x86_64', 'buildtime': 1336513483.0, 'version': '2.6.32'}, 'qemu-kvm-rhev': {'release': '2.292.el6', 'buildtime': '1336502700', 'version': '0.12.1.2'}, 'spice-server': {'release': '6.el6', 'buildtime': '1336391789', 'version': '0.10.1'}, 'vdsm': {'release': '11.el6', 'buildtime': '1336575748', 'version': '4.9.6'}, 'libvirt': {'release': '18.el6', 'buildtime': '1336550939', 'version': '0.9.10'}, 'qemu-img-rhev': {'release': '2.292.el6', 'buildtime': '1336502700', 'version': '0.12.1.2'}}, 'cpuModel': 'Intel(R) Core(TM)2 Quad CPU    Q9500  @ 2.83GHz', 'hooks': {'before_vm_start': {'50_vhostmd': {'md5': '2aa9ac48ef07de3c94e3428975e9df1a'}}, 'after_vm_destroy': {'50_vhostmd': {'md5': '47f8d385859e4c3c96113d8ff446b261'}}, 'before_vm_dehibernate': {'50_vhostmd': {'md5': '2aa9ac48ef07de3c94e3428975e9df1a'}}, 'before_vm_migrate_destination': {'50_vhostmd': {'md5': '2aa9ac48ef07de3c94e3428975e9df1a'}}}, 'vmTypes': ['kvm'], 'supportedProtocols': ['2.2', '2.3'], 'networks': {}, 'uuid': '002D8B01-C837-E011-9B91-81F97A74282D_00:0E:0C:B0:9A:0D', 'lastClientIface': 'rhevm', 'nics': {'eth2': {'hwaddr': '00:0E:0C:B0:9A:0D', 'netmask': '', 'speed': 0, 'addr': '', 'mtu': '1500'}, 'eth1': {'hwaddr': '00:1B:21:27:47:0B', 'netmask': '', 'speed': 1000, 'addr': '', 'mtu': '1500'}, 'eth0': {'hwaddr': '1C:6F:65:06:BA:AE', 'netmask': '', 'speed': 0, 'addr': '', 'mtu': '1500'}}, 'software_revision': '0', 'management_ip': '', 'clusterLevels': ['3.0'], 'cpuFlags': u'fpu,vme,de,pse,tsc,msr,pae,mce,cx8,apic,mtrr,pge,mca,cmov,pat,pse36,clflush,dts,acpi,mmx,fxsr,sse,sse2,ss,ht,tm,pbe,syscall,nx,lm,constant_tsc,arch_perfmon,pebs,bts,rep_good,aperfmperf,pni,dtes64,monitor,ds_cpl,vmx,est,tm2,ssse3,cx16,xtpr,pdcm,sse4_1,xsave,lahf_lm,dts,tpr_shadow,vnmi,flexpriority,model_486,model_pentium,model_pentium2,model_pentium3,model_pentiumpro,model_qemu32,model_cpu64-rhel5,model_cpu64-rhel6,model_kvm32,model_coreduo,model_kvm64,model_core2duo,model_n270,model_Conroe,model_Penryn,model_Opteron_G1', 'ISCSIInitiatorName': 'iqn.1994-05.com.redhat:fcce7ec5233e', 'memSize': '7586', 'reservedMem': '321', 'bondings': {'bond4': {'addr': '', 'cfg': {}, 'mtu': '1500', 'netmask': '', 'slaves': [], 'hwaddr': '00:00:00:00:00:00'}, 'bond0': {'addr': '', 'cfg': {}, 'mtu': '1500', 'netmask': '', 'slaves': [], 'hwaddr': '00:00:00:00:00:00'}, 'bond1': {'addr': '', 'cfg': {}, 'mtu': '1500', 'netmask': '', 'slaves': [], 'hwaddr': '00:00:00:00:00:00'}, 'bond2': {'addr': '', 'cfg': {}, 'mtu': '1500', 'netmask': '', 'slaves': [], 'hwaddr': '00:00:00:00:00:00'}, 'bond3': {'addr': '', 'cfg': {}, 'mtu': '1500', 'netmask': '', 'slaves': [], 'hwaddr': '00:00:00:00:00:00'}}, 'software_version': '4.9', 'cpuSpeed': '2826.337', 'cpuSockets': '1', 'vlans': {}, 'cpuCores': '4', 'kvmEnabled': 'true', 'guestOverhead': '65', 'supportedRHEVMs': ['3.0'], 'version_name': 'Snow Man', 'emulatedMachines': [u'rhel6.3.0', u'pc', u'rhel6.2.0', u'rhel6.1.0', u'rhel6.0.0', u'rhel5.5.0', u'rhel5.4.4', u'rhel5.4.0'], 'operatingSystem': {'release': '6.3.0.2.el6', 'version': '6Server', 'name': 'RHEV Hypervisor'}, 'lastClient': '10.66.104.232'}}
>Thread-30::DEBUG::2012-05-24 09:44:38,483::BindingXMLRPC::864::vds::(wrapper) client [10.66.104.232]::call getCapabilities with () {}
>Thread-30::DEBUG::2012-05-24 09:44:38,510::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/rpm -q --qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" qemu-img-rhev' (cwd None)
>Thread-30::DEBUG::2012-05-24 09:44:38,535::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>Thread-30::DEBUG::2012-05-24 09:44:38,540::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/rpm -q --qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" qemu-kvm-rhev' (cwd None)
>MainProcess|Thread-27::DEBUG::2012-05-24 09:44:38,540::configNetwork::173::root::(_atomicBackup) Backed up /etc/sysconfig/network-scripts/ifcfg-rhevm
>Thread-30::DEBUG::2012-05-24 09:44:38,567::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>Thread-30::DEBUG::2012-05-24 09:44:38,568::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/rpm -q --qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" vdsm' (cwd None)
>MainProcess|Thread-27::DEBUG::2012-05-24 09:44:38,570::configNetwork::199::root::(_persistentBackup) unmounted /etc/sysconfig/network-scripts/ifcfg-rhevm using ovirt
>MainProcess|Thread-27::DEBUG::2012-05-24 09:44:38,571::configNetwork::219::root::(_persistentBackup) Persistently backed up /etc/sysconfig/network-scripts/ifcfg-rhevm (until next 'set safe config')
>Thread-30::DEBUG::2012-05-24 09:44:38,593::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>Thread-30::DEBUG::2012-05-24 09:44:38,593::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/rpm -q --qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" spice-server' (cwd None)
>Thread-30::DEBUG::2012-05-24 09:44:38,614::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>Thread-30::DEBUG::2012-05-24 09:44:38,614::__init__::1164::Storage.Misc.excCmd::(_log) '/bin/rpm -q --qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" libvirt' (cwd None)
>Thread-30::DEBUG::2012-05-24 09:44:38,639::__init__::1164::Storage.Misc.excCmd::(_log) SUCCESS: <err> = ''; <rc> = 0
>Thread-30::DEBUG::2012-05-24 09:44:38,640::BindingXMLRPC::870::vds::(wrapper) return getCapabilities with {'status': {'message': 'Done', 'code': 0}, 'info': {'HBAInventory': {'iSCSI': [{'InitiatorName': 'iqn.1994-05.com.redhat:fcce7ec5233e'}], 'FC': []}, 'packages2': {'kernel': {'release': '270.el6.x86_64', 'buildtime': 1336513483.0, 'version': '2.6.32'}, 'qemu-kvm-rhev': {'release': '2.292.el6', 'buildtime': '1336502700', 'version': '0.12.1.2'}, 'spice-server': {'release': '6.el6', 'buildtime': '1336391789', 'version': '0.10.1'}, 'vdsm': {'release': '11.el6', 'buildtime': '1336575748', 'version': '4.9.6'}, 'libvirt': {'release': '18.el6', 'buildtime': '1336550939', 'version': '0.9.10'}, 'qemu-img-rhev': {'release': '2.292.el6', 'buildtime': '1336502700', 'version': '0.12.1.2'}}, 'cpuModel': 'Intel(R) Core(TM)2 Quad CPU    Q9500  @ 2.83GHz', 'hooks': {'before_vm_start': {'50_vhostmd': {'md5': '2aa9ac48ef07de3c94e3428975e9df1a'}}, 'after_vm_destroy': {'50_vhostmd': {'md5': '47f8d385859e4c3c96113d8ff446b261'}}, 'before_vm_dehibernate': {'50_vhostmd': {'md5': '2aa9ac48ef07de3c94e3428975e9df1a'}}, 'before_vm_migrate_destination': {'50_vhostmd': {'md5': '2aa9ac48ef07de3c94e3428975e9df1a'}}}, 'vmTypes': ['kvm'], 'supportedProtocols': ['2.2', '2.3'], 'networks': {}, 'uuid': '002D8B01-C837-E011-9B91-81F97A74282D_00:0E:0C:B0:9A:0D', 'lastClientIface': '', 'nics': {'eth2': {'hwaddr': '00:0E:0C:B0:9A:0D', 'netmask': '', 'speed': 0, 'addr': '', 'mtu': '1500'}, 'eth1': {'hwaddr': '00:1B:21:27:47:0B', 'netmask': '', 'speed': 1000, 'addr': '', 'mtu': '1500'}, 'eth0': {'hwaddr': '1C:6F:65:06:BA:AE', 'netmask': '', 'speed': 0, 'addr': '', 'mtu': '1500'}}, 'software_revision': '0', 'management_ip': '', 'clusterLevels': ['3.0'], 'cpuFlags': u'fpu,vme,de,pse,tsc,msr,pae,mce,cx8,apic,mtrr,pge,mca,cmov,pat,pse36,clflush,dts,acpi,mmx,fxsr,sse,sse2,ss,ht,tm,pbe,syscall,nx,lm,constant_tsc,arch_perfmon,pebs,bts,rep_good,aperfmperf,pni,dtes64,monitor,ds_cpl,vmx,est,tm2,ssse3,cx16,xtpr,pdcm,sse4_1,xsave,lahf_lm,dts,tpr_shadow,vnmi,flexpriority,model_486,model_pentium,model_pentium2,model_pentium3,model_pentiumpro,model_qemu32,model_cpu64-rhel5,model_cpu64-rhel6,model_kvm32,model_coreduo,model_kvm64,model_core2duo,model_n270,model_Conroe,model_Penryn,model_Opteron_G1', 'ISCSIInitiatorName': 'iqn.1994-05.com.redhat:fcce7ec5233e', 'memSize': '7586', 'reservedMem': '321', 'bondings': {'bond4': {'addr': '', 'cfg': {}, 'mtu': '1500', 'netmask': '', 'slaves': [], 'hwaddr': '00:00:00:00:00:00'}, 'bond0': {'addr': '', 'cfg': {}, 'mtu': '1500', 'netmask': '', 'slaves': [], 'hwaddr': '00:00:00:00:00:00'}, 'bond1': {'addr': '', 'cfg': {}, 'mtu': '1500', 'netmask': '', 'slaves': [], 'hwaddr': '00:00:00:00:00:00'}, 'bond2': {'addr': '', 'cfg': {}, 'mtu': '1500', 'netmask': '', 'slaves': [], 'hwaddr': '00:00:00:00:00:00'}, 'bond3': {'addr': '', 'cfg': {}, 'mtu': '1500', 'netmask': '', 'slaves': [], 'hwaddr': '00:00:00:00:00:00'}}, 'software_version': '4.9', 'cpuSpeed': '2826.337', 'cpuSockets': '1', 'vlans': {}, 'cpuCores': '4', 'kvmEnabled': 'true', 'guestOverhead': '65', 'supportedRHEVMs': ['3.0'], 'version_name': 'Snow Man', 'emulatedMachines': [u'rhel6.3.0', u'pc', u'rhel6.2.0', u'rhel6.1.0', u'rhel6.0.0', u'rhel5.5.0', u'rhel5.4.4', u'rhel5.4.0'], 'operatingSystem': {'release': '6.3.0.2.el6', 'version': '6Server', 'name': 'RHEV Hypervisor'}, 'lastClient': '10.66.104.232'}}
>MainProcess|Thread-27::WARNING::2012-05-24 09:44:38,885::configNetwork::72::root::(ifdown) 
>MainProcess|Thread-27::DEBUG::2012-05-24 09:44:38,896::configNetwork::173::root::(_atomicBackup) Backed up /etc/sysconfig/network-scripts/ifcfg-eth1
>MainProcess|Thread-27::DEBUG::2012-05-24 09:44:38,916::configNetwork::199::root::(_persistentBackup) unmounted /etc/sysconfig/network-scripts/ifcfg-eth1 using ovirt
>MainProcess|Thread-27::DEBUG::2012-05-24 09:44:38,916::configNetwork::219::root::(_persistentBackup) Persistently backed up /etc/sysconfig/network-scripts/ifcfg-eth1 (until next 'set safe config')
>MainProcess|Thread-27::DEBUG::2012-05-24 09:44:38,925::configNetwork::586::root::(addNetwork) validating bridge...
>MainProcess|Thread-27::INFO::2012-05-24 09:44:38,925::configNetwork::595::root::(addNetwork) Adding network rhevm with vlan=None, bonding=bond4, nics=['eth1', 'eth0'], bondingOptions=mode=4, mtu=None, bridged=True, options={'connectivityCheck': 'true', 'bootproto': 'dhcp', 'STP': 'no', 'connectivityTimeout': '120'}
>MainProcess|Thread-27::DEBUG::2012-05-24 09:44:39,108::configNetwork::199::root::(_persistentBackup) unmounted /etc/sysconfig/network-scripts/ifcfg-rhevm using ovirt
>MainProcess|Thread-27::DEBUG::2012-05-24 09:44:39,485::configNetwork::199::root::(_persistentBackup) unmounted /etc/sysconfig/network-scripts/ifcfg-bond4 using ovirt
>MainProcess|Thread-27::DEBUG::2012-05-24 09:44:39,486::configNetwork::219::root::(_persistentBackup) Persistently backed up /etc/sysconfig/network-scripts/ifcfg-bond4 (until next 'set safe config')
>MainProcess|Thread-27::DEBUG::2012-05-24 09:44:39,501::configNetwork::199::root::(_persistentBackup) unmounted /etc/sysconfig/network-scripts/ifcfg-eth1 using ovirt
>MainProcess|Thread-27::DEBUG::2012-05-24 09:44:39,510::configNetwork::173::root::(_atomicBackup) Backed up /etc/sysconfig/network-scripts/ifcfg-eth0
>MainProcess|Thread-27::DEBUG::2012-05-24 09:44:39,527::configNetwork::199::root::(_persistentBackup) unmounted /etc/sysconfig/network-scripts/ifcfg-eth0 using ovirt
>MainProcess|Thread-27::DEBUG::2012-05-24 09:44:39,528::configNetwork::219::root::(_persistentBackup) Persistently backed up /etc/sysconfig/network-scripts/ifcfg-eth0 (until next 'set safe config')
>ifup-waiting-on-dhcp::INFO::2012-05-24 09:44:47,111::configNetwork::81::root::(ifup) 
>Determining IP information for rhevm... done.
>
>MainProcess|Thread-27::INFO::2012-05-24 09:46:41,203::configNetwork::726::root::(delNetwork) Removing network rhevm with vlan=None, bonding=bond4, nics=['eth0', 'eth1']. options={}
>MainProcess|Thread-27::DEBUG::2012-05-24 09:46:41,683::configNetwork::173::root::(_atomicBackup) Backed up /etc/sysconfig/network-scripts/ifcfg-rhevm
>MainProcess|Thread-27::DEBUG::2012-05-24 09:46:41,701::configNetwork::199::root::(_persistentBackup) unmounted /etc/sysconfig/network-scripts/ifcfg-rhevm using ovirt
>MainProcess|Thread-27::WARNING::2012-05-24 09:46:42,253::configNetwork::72::root::(ifdown) 
>MainProcess|Thread-27::DEBUG::2012-05-24 09:46:42,265::configNetwork::173::root::(_atomicBackup) Backed up /etc/sysconfig/network-scripts/ifcfg-bond4
>MainProcess|Thread-27::DEBUG::2012-05-24 09:46:42,281::configNetwork::199::root::(_persistentBackup) unmounted /etc/sysconfig/network-scripts/ifcfg-bond4 using ovirt
>MainProcess|Thread-27::DEBUG::2012-05-24 09:46:42,393::configNetwork::173::root::(_atomicBackup) Backed up /etc/sysconfig/network-scripts/ifcfg-eth0
>MainProcess|Thread-27::DEBUG::2012-05-24 09:46:42,408::configNetwork::199::root::(_persistentBackup) unmounted /etc/sysconfig/network-scripts/ifcfg-eth0 using ovirt
>MainProcess|Thread-27::DEBUG::2012-05-24 09:46:42,518::configNetwork::173::root::(_atomicBackup) Backed up /etc/sysconfig/network-scripts/ifcfg-eth1
>MainProcess|Thread-27::DEBUG::2012-05-24 09:46:42,534::configNetwork::199::root::(_persistentBackup) unmounted /etc/sysconfig/network-scripts/ifcfg-eth1 using ovirt
>MainProcess|Thread-27::INFO::2012-05-24 09:46:42,535::configNetwork::178::root::(restoreAtomicBackup) Rolling back configuration (restoring atomic backup)
>MainProcess|Thread-27::DEBUG::2012-05-24 09:46:42,535::configNetwork::183::root::(restoreAtomicBackup) Restored /etc/sysconfig/network-scripts/ifcfg-rhevm
>MainProcess|Thread-27::DEBUG::2012-05-24 09:46:42,535::configNetwork::183::root::(restoreAtomicBackup) Restored /etc/sysconfig/network-scripts/ifcfg-eth0
>MainProcess|Thread-27::DEBUG::2012-05-24 09:46:42,535::configNetwork::183::root::(restoreAtomicBackup) Restored /etc/sysconfig/network-scripts/ifcfg-eth1
>Thread-27::DEBUG::2012-05-24 09:46:42,538::BindingXMLRPC::870::vds::(wrapper) return editNetwork with {'status': {'message': 'Done', 'code': 0}}

Actions: View

Attachments on bug 749479: 530439 | 530440 | 578262 | 584628 | 584629 | 586598 | 587337 | 587375