[Users] Making bonds in oVirt

Tue Oct 23 11:26:11 UTC 2012

Sorry Mike, hit the wrong button and didn't send it to the list

>> Hi All,
>
> Hi Joop
>
>>
>> I'm slowly progressing with our test environment and currently I'm
>> testing my 10Ge nics and switches and hit a snag.
>> I watched the webcast about setting up a non-vm network which
>> included
>> creating a bond using the webinterface. I can't get it to work
>> correctly.
>> I have two problems
>> - my ovirtmgmt network always returns to DHCP in Hosts/Network
>> Interfaces/Setup Host Networks
>> - bonding p3p1 and p3p2 into bond0 with mode 4 and assigning an ip
>> address doesn't work either.
>
> It might be a good idea to try and isolate the two problems:
>
> 1. Even without bonding the BOOTPROTO won't stick? Do you see any errors?
No, the following is the relevant part of vdsm.log:
Thread-1873::DEBUG::2012-10-23
13:07:07,031::task::588::TaskManager.Task::(_updateState)
Task=`88b706f8-80ec-49ef-afee-63ecd491ad93`::moving from state init ->
state preparing
Thread-1873::INFO::2012-10-23
13:07:07,031::logUtils::37::dispatcher::(wrapper) Run and protect:
repoStats(options=None)
Thread-1873::INFO::2012-10-23
13:07:07,031::logUtils::39::dispatcher::(wrapper) Run and protect:
repoStats, Return response: {}
Thread-1873::DEBUG::2012-10-23
13:07:07,032::task::1172::TaskManager.Task::(prepare)
Task=`88b706f8-80ec-49ef-afee-63ecd491ad93`::finished: {}
Thread-1873::DEBUG::2012-10-23
13:07:07,032::task::588::TaskManager.Task::(_updateState)
Task=`88b706f8-80ec-49ef-afee-63ecd491ad93`::moving from state preparing
-> state finished
Thread-1873::DEBUG::2012-10-23
13:07:07,032::resourceManager::809::ResourceManager.Owner::(releaseAll)
Owner.releaseAll requests {} resources {}
Thread-1873::DEBUG::2012-10-23
13:07:07,032::resourceManager::844::ResourceManager.Owner::(cancelAll)
Owner.cancelAll requests {}
Thread-1873::DEBUG::2012-10-23
13:07:07,033::task::978::TaskManager.Task::(_decref)
Task=`88b706f8-80ec-49ef-afee-63ecd491ad93`::ref 0 aborting False
Thread-1879::DEBUG::2012-10-23
13:07:17,286::task::588::TaskManager.Task::(_updateState)
Task=`21796aac-5c43-4c38-939e-919a20217b7f`::moving from state init ->
state preparing
Thread-1879::INFO::2012-10-23
13:07:17,286::logUtils::37::dispatcher::(wrapper) Run and protect:
repoStats(options=None)
Thread-1879::INFO::2012-10-23
13:07:17,287::logUtils::39::dispatcher::(wrapper) Run and protect:
repoStats, Return response: {}
Thread-1879::DEBUG::2012-10-23
13:07:17,287::task::1172::TaskManager.Task::(prepare)
Task=`21796aac-5c43-4c38-939e-919a20217b7f`::finished: {}
Thread-1879::DEBUG::2012-10-23
13:07:17,287::task::588::TaskManager.Task::(_updateState)
Task=`21796aac-5c43-4c38-939e-919a20217b7f`::moving from state preparing
-> state finished
Thread-1879::DEBUG::2012-10-23
13:07:17,287::resourceManager::809::ResourceManager.Owner::(releaseAll)
Owner.releaseAll requests {} resources {}
Thread-1879::DEBUG::2012-10-23
13:07:17,288::resourceManager::844::ResourceManager.Owner::(cancelAll)
Owner.cancelAll requests {}
Thread-1879::DEBUG::2012-10-23
13:07:17,288::task::978::TaskManager.Task::(_decref)
Task=`21796aac-5c43-4c38-939e-919a20217b7f`::ref 0 aborting False
Thread-1884::DEBUG::2012-10-23
13:07:24,350::BindingXMLRPC::859::vds::(wrapper) client
[192.168.216.150]::call setupNetworks with ({}, {}, {'connectivityCheck':
'true', 'connectivityTimeout': 60000}) {} flowID [435ea2d8]
Thread-1885::DEBUG::2012-10-23
13:07:24,351::BindingXMLRPC::859::vds::(wrapper) client
[192.168.216.150]::call ping with () {} flowID [435ea2d8]
Thread-1885::DEBUG::2012-10-23
13:07:24,352::BindingXMLRPC::865::vds::(wrapper) return ping with
{'status': {'message': 'Done', 'code': 0}}
MainProcess|Thread-1884::DEBUG::2012-10-23
13:07:24,375::configNetwork::1083::setupNetworks::(setupNetworks) Setting
up network according to configuration: networks:{}, bondings:{},
options:{'connectivityCheck': 'true', 'connectivityTimeout': 60000}
MainProcess|Thread-1884::DEBUG::2012-10-23
13:07:24,375::configNetwork::1087::root::(setupNetworks) Validating
configuration
MainProcess|Thread-1884::DEBUG::2012-10-23
13:07:24,394::configNetwork::1090::setupNetworks::(setupNetworks)
Applying...
MainProcess|Thread-1884::DEBUG::2012-10-23
13:07:24,434::configNetwork::1140::setupNetworks::(setupNetworks) Checking
connectivity...
Thread-1886::DEBUG::2012-10-23
13:07:24,872::BindingXMLRPC::859::vds::(wrapper) client
[192.168.216.150]::call ping with () {} flowID [435ea2d8]
Thread-1886::DEBUG::2012-10-23
13:07:24,872::BindingXMLRPC::865::vds::(wrapper) return ping with
{'status': {'message': 'Done', 'code': 0}}
Thread-1887::DEBUG::2012-10-23
13:07:25,391::BindingXMLRPC::859::vds::(wrapper) client
[192.168.216.150]::call ping with () {} flowID [435ea2d8]
Thread-1887::DEBUG::2012-10-23
13:07:25,392::BindingXMLRPC::865::vds::(wrapper) return ping with
{'status': {'message': 'Done', 'code': 0}}
Thread-1884::DEBUG::2012-10-23
13:07:25,436::BindingXMLRPC::865::vds::(wrapper) return setupNetworks with
{'status': {'message': 'Done', 'code': 0}}
Thread-1889::DEBUG::2012-10-23
13:07:25,911::BindingXMLRPC::859::vds::(wrapper) client
[192.168.216.150]::call getCapabilities with () {} flowID [435ea2d8]
Thread-1889::DEBUG::2012-10-23
13:07:25,935::__init__::1249::Storage.Misc.excCmd::(_log) '/usr/bin/rpm -q
--qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" qemu-kvm' (cwd
None)
Thread-1889::DEBUG::2012-10-23
13:07:25,957::__init__::1249::Storage.Misc.excCmd::(_log) SUCCESS: <err> =
''; <rc> = 0
Thread-1889::DEBUG::2012-10-23
13:07:25,957::__init__::1249::Storage.Misc.excCmd::(_log) '/usr/bin/rpm -q
--qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" qemu-img' (cwd
None)
Thread-1889::DEBUG::2012-10-23
13:07:25,978::__init__::1249::Storage.Misc.excCmd::(_log) SUCCESS: <err> =
''; <rc> = 0
Thread-1889::DEBUG::2012-10-23
13:07:25,979::__init__::1249::Storage.Misc.excCmd::(_log) '/usr/bin/rpm -q
--qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" vdsm' (cwd None)
Thread-1889::DEBUG::2012-10-23
13:07:26,001::__init__::1249::Storage.Misc.excCmd::(_log) SUCCESS: <err> =
''; <rc> = 0
Thread-1889::DEBUG::2012-10-23
13:07:26,002::__init__::1249::Storage.Misc.excCmd::(_log) '/usr/bin/rpm -q
--qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" spice-server' (cwd
None)
Thread-1889::DEBUG::2012-10-23
13:07:26,020::__init__::1249::Storage.Misc.excCmd::(_log) SUCCESS: <err> =
''; <rc> = 0
Thread-1889::DEBUG::2012-10-23
13:07:26,021::__init__::1249::Storage.Misc.excCmd::(_log) '/usr/bin/rpm -q
--qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" libvirt' (cwd None)
Thread-1889::DEBUG::2012-10-23
13:07:26,043::__init__::1249::Storage.Misc.excCmd::(_log) SUCCESS: <err> =
''; <rc> = 0
Thread-1889::DEBUG::2012-10-23
13:07:26,045::BindingXMLRPC::865::vds::(wrapper) return getCapabilities
with {'status': {'message': 'Done', 'code': 0}, 'info': {'HBAInventory':
{'iSCSI': [{'InitiatorName': 'iqn.1994-05.com.redhat:8b24ce75f189'}],
'FC': []}, 'packages2': {'kernel': {'release': '5.fc17.x86_64',
'buildtime': 1336404574.0, 'version': '3.3.4'}, 'spice-server':
{'release': '5.fc17', 'buildtime': '1336983054', 'version': '0.10.1'},
'vdsm': {'release': '10.fc17', 'buildtime': '1349383616', 'version':
'4.10.0'}, 'qemu-kvm': {'release': '2.fc17', 'buildtime': '1349642820',
'version': '1.0.1'}, 'libvirt': {'release': '1.fc17', 'buildtime':
'1349654831', 'version': '0.9.11.6'}, 'qemu-img': {'release': '2.fc17',
'buildtime': '1349642820', 'version': '1.0.1'}}, 'cpuModel': 'Intel(R)
Xeon(R) CPU           X3430  @ 2.40GHz', 'hooks': {}, 'vmTypes': ['kvm'],
'supportedProtocols': ['2.2', '2.3'], 'networks': {'ovirtmgmt': {'addr':
'192.168.216.151', 'cfg': {'IPADDR': '192.168.216.151', 'GATEWAY':
'192.168.216.254', 'DELAY': '0', 'NM_CONTROLLED': 'no', 'NETMASK':
'255.255.255.0', 'DEVICE': 'ovirtmgmt', 'TYPE': 'Bridge', 'ONBOOT':
'yes'}, 'mtu': '1500', 'netmask': '255.255.255.0', 'stp': 'off',
'bridged': True, 'gateway': '192.168.216.254', 'ports': ['em1']}}, 'uuid':
'33221100-5544-7766-8899-AABBCCDDEEFF_00:1b:21:bb:41:21',
'lastClientIface': 'ovirtmgmt', 'nics': {'p2p2': {'hwaddr':
'68:05:ca:06:d3:11', 'netmask': '', 'speed': 1000, 'addr': '', 'mtu':
'1500'}, 'em1': {'hwaddr': 'd8:d3:85:8f:11:dc', 'netmask': '', 'speed':
1000, 'addr': '', 'mtu': '1500'}, 'p3p2': {'hwaddr': '00:1b:21:bb:41:20',
'netmask': '', 'speed': 0, 'addr': '', 'mtu': '1500'}, 'p3p1': {'hwaddr':
'00:1b:21:bb:41:21', 'netmask': '', 'speed': 0, 'addr': '', 'mtu':
'1500'}, 'p2p1': {'hwaddr': '68:05:ca:06:d3:10', 'netmask': '', 'speed':
0, 'addr': '', 'mtu': '1500'}}, 'software_revision': '10',
'management_ip': '', 'clusterLevels': ['3.0', '3.1'], 'cpuFlags':
u'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,rdtscp,lm,constant_tsc,arch_perfmon,pebs,bts,rep_good,nopl,xtopology,nonstop_tsc,aperfmperf,pni,dtes64,monitor,ds_cpl,vmx,smx,est,tm2,ssse3,cx16,xtpr,pdcm,sse4_1,sse4_2,popcnt,lahf_lm,dts,tpr_shadow,vnmi,flexpriority,ept,vpid,model_coreduo,model_Conroe',
'ISCSIInitiatorName': 'iqn.1994-05.com.redhat:8b24ce75f189',
'netConfigDirty': 'True', 'memSize': '7978', '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.10', 'cpuSpeed': '1197.000', 'cpuSockets': '1',
'vlans': {}, 'cpuCores': '4', 'kvmEnabled': 'true', 'guestOverhead': '65',
'supportedRHEVMs': ['3.0', '3.1'], 'version_name': 'Snow Man',
'emulatedMachines': [u'pc-0.15', u'pc-1.0', u'pc', u'pc-0.14', u'pc-0.13',
u'pc-0.12', u'pc-0.11', u'pc-0.10', u'isapc', u'pc-0.15', u'pc-1.0',
u'pc', u'pc-0.14', u'pc-0.13', u'pc-0.12', u'pc-0.11', u'pc-0.10',
u'isapc'], 'operatingSystem': {'release': '1', 'version': '17', 'name':
'Fedora'}, 'lastClient': '192.168.216.150'}}
Thread-1890::DEBUG::2012-10-23
13:07:26,218::BindingXMLRPC::859::vds::(wrapper) client
[192.168.216.150]::call setSafeNetworkConfig with () {} flowID [49b58d22]
Thread-1890::DEBUG::2012-10-23
13:07:26,224::BindingXMLRPC::865::vds::(wrapper) return
setSafeNetworkConfig with {'status': {'message': 'Done', 'code': 0}}
Thread-1891::DEBUG::2012-10-23
13:07:27,528::task::588::TaskManager.Task::(_updateState)
Task=`aa57c835-56e6-4c5c-a836-a7a60bad4632`::moving from state init ->
state preparing
Thread-1891::INFO::2012-10-23
13:07:27,528::logUtils::37::dispatcher::(wrapper) Run and protect:
repoStats(options=None)
Thread-1891::INFO::2012-10-23
13:07:27,528::logUtils::39::dispatcher::(wrapper) Run and protect:
repoStats, Return response: {}
Thread-1891::DEBUG::2012-10-23
13:07:27,529::task::1172::TaskManager.Task::(prepare)
Task=`aa57c835-56e6-4c5c-a836-a7a60bad4632`::finished: {}
Thread-1891::DEBUG::2012-10-23
13:07:27,529::task::588::TaskManager.Task::(_updateState)
Task=`aa57c835-56e6-4c5c-a836-a7a60bad4632`::moving from state preparing
-> state finished
Thread-1891::DEBUG::2012-10-23
13:07:27,529::resourceManager::809::ResourceManager.Owner::(releaseAll)
Owner.releaseAll requests {} resources {}
Thread-1891::DEBUG::2012-10-23
13:07:27,529::resourceManager::844::ResourceManager.Owner::(cancelAll)
Owner.cancelAll requests {}
Thread-1891::DEBUG::2012-10-23
13:07:27,530::task::978::TaskManager.Task::(_decref)
Task=`aa57c835-56e6-4c5c-a836-a7a60bad4632`::ref 0 aborting False

[ifcfg-em1]
DEVICE=em1
ONBOOT=yes
BOOTPROTO=none
HWADDR=d8:d3:85:8f:11:dc
BRIDGE=ovirtmgmt
NM_CONTROLLED=no

[ifcfg-ovirtmgmt]
DEVICE=ovirtmgmt
TYPE=Bridge
ONBOOT=yes
IPADDR=192.168.216.151
NETMASK=255.255.255.0
GATEWAY=192.168.216.254
DELAY=0
NM_CONTROLLED=no

>
> 2. If you have 3 nics (or more) try to bond the 2 that aren't connected to
> the management network and just see if that works.
>
Already doing that.

These are the configs before bonding.

[ifcfg-p3p1]
DEVICE=p3p1
ONBOOT=yes
BOOTPROTO=none
HWADDR=00:1b:21:bb:41:21

[ifcfg-p3p2]
DEVICE=p3p2
ONBOOT=yes
BOOTPROTO=none
HWADDR=00:1b:21:bb:41:20

There is currently no ifcfg-bond0 but it will appear when I goto the
webinterface and configure a bond between p3p1 and p3p2.
Doing that right now ......
Thread-2269::DEBUG::2012-10-23
13:18:06,257::BindingXMLRPC::859::vds::(wrapper) client
[192.168.216.150]::call setupNetworks with ({'storage': {'bonding':
'bond4', 'ipaddr': '172.29.0.1', 'netmask': '255.255.255.0', 'bridged':
'false'}}, {'bond4': {'nics': ['p3p1', 'p3p2'], 'options': 'mode=4'}},
{'connectivityCheck': 'true', 'connectivityTimeout': 60000}) {} flowID
[368e5ef5]
Thread-2270::DEBUG::2012-10-23
13:18:06,259::BindingXMLRPC::859::vds::(wrapper) client
[192.168.216.150]::call ping with () {} flowID [368e5ef5]
Thread-2270::DEBUG::2012-10-23
13:18:06,259::BindingXMLRPC::865::vds::(wrapper) return ping with
{'status': {'message': 'Done', 'code': 0}}
MainProcess|Thread-2269::DEBUG::2012-10-23
13:18:06,283::configNetwork::1083::setupNetworks::(setupNetworks) Setting
up network according to configuration: networks:{'storage': {'bonding':
'bond4', 'ipaddr': '172.29.0.1', 'netmask': '255.255.255.0', 'bridged':
'false'}}, bondings:{'bond4': {'nics': ['p3p1', 'p3p2'], 'options':
'mode=4'}}, options:{'connectivityCheck': 'true', 'connectivityTimeout':
60000}
MainProcess|Thread-2269::DEBUG::2012-10-23
13:18:06,283::configNetwork::1087::root::(setupNetworks) Validating
configuration
MainProcess|Thread-2269::DEBUG::2012-10-23
13:18:06,316::configNetwork::1090::setupNetworks::(setupNetworks)
Applying...
MainProcess|Thread-2269::DEBUG::2012-10-23
13:18:06,341::configNetwork::1126::setupNetworks::(setupNetworks) Adding
network 'storage'
MainProcess|Thread-2269::DEBUG::2012-10-23
13:18:06,363::configNetwork::602::root::(addNetwork) validating bridge...
MainProcess|Thread-2269::INFO::2012-10-23
13:18:06,375::configNetwork::611::root::(addNetwork) Adding network
storage with vlan=None, bonding=bond4, nics=['p3p1', 'p3p2'],
bondingOptions=mode=4, mtu=None, bridged=False,
options={'implicitBonding': True}
Thread-2272::DEBUG::2012-10-23
13:18:06,780::BindingXMLRPC::859::vds::(wrapper) client
[192.168.216.150]::call ping with () {} flowID [368e5ef5]
Thread-2272::DEBUG::2012-10-23
13:18:06,780::BindingXMLRPC::865::vds::(wrapper) return ping with
{'status': {'message': 'Done', 'code': 0}}
MainProcess|Thread-2269::DEBUG::2012-10-23
13:18:06,799::configNetwork::175::root::(_atomicBackup) Backed up
/etc/sysconfig/network-scripts/ifcfg-p3p1
MainProcess|Thread-2269::DEBUG::2012-10-23
13:18:06,801::configNetwork::227::root::(_persistentBackup) Persistently
backed up /etc/sysconfig/network-scripts/ifcfg-p3p1 (until next 'set safe
config')
MainProcess|Thread-2269::DEBUG::2012-10-23
13:18:06,906::configNetwork::175::root::(_atomicBackup) Backed up
/etc/sysconfig/network-scripts/ifcfg-p3p2
MainProcess|Thread-2269::DEBUG::2012-10-23
13:18:06,907::configNetwork::227::root::(_persistentBackup) Persistently
backed up /etc/sysconfig/network-scripts/ifcfg-p3p2 (until next 'set safe
config')
MainProcess|Thread-2269::DEBUG::2012-10-23
13:18:07,022::configNetwork::227::root::(_persistentBackup) Persistently
backed up /etc/sysconfig/network-scripts/ifcfg-bond4 (until next 'set safe
config')
MainProcess|Thread-2269::INFO::2012-10-23
13:18:07,296::configNetwork::81::root::(ifup) ERROR    :
[/etc/sysconfig/network-scripts/ifup-eth] Device p3p2 has different MAC
address than expected, ignoring.

MainProcess|Thread-2269::WARNING::2012-10-23
13:18:07,296::configNetwork::83::root::(ifup)
/etc/sysconfig/network-scripts/ifup-eth: line 137: echo: write error:
Operation not permitted

Thread-2273::DEBUG::2012-10-23
13:18:07,300::BindingXMLRPC::859::vds::(wrapper) client
[192.168.216.150]::call ping with () {} flowID [368e5ef5]
Thread-2273::DEBUG::2012-10-23
13:18:07,301::BindingXMLRPC::865::vds::(wrapper) return ping with
{'status': {'message': 'Done', 'code': 0}}
MainProcess|Thread-2269::DEBUG::2012-10-23
13:18:07,393::configNetwork::1140::setupNetworks::(setupNetworks) Checking
connectivity...
Thread-2274::DEBUG::2012-10-23
13:18:07,826::BindingXMLRPC::859::vds::(wrapper) client
[192.168.216.150]::call ping with () {} flowID [368e5ef5]
Thread-2274::DEBUG::2012-10-23
13:18:07,826::BindingXMLRPC::865::vds::(wrapper) return ping with
{'status': {'message': 'Done', 'code': 0}}
Thread-2275::DEBUG::2012-10-23
13:18:08,348::BindingXMLRPC::859::vds::(wrapper) client
[192.168.216.150]::call ping with () {} flowID [368e5ef5]
Thread-2275::DEBUG::2012-10-23
13:18:08,349::BindingXMLRPC::865::vds::(wrapper) return ping with
{'status': {'message': 'Done', 'code': 0}}
Thread-2269::DEBUG::2012-10-23
13:18:08,395::BindingXMLRPC::865::vds::(wrapper) return setupNetworks with
{'status': {'message': 'Done', 'code': 0}}
Thread-2277::DEBUG::2012-10-23
13:18:08,875::BindingXMLRPC::859::vds::(wrapper) client
[192.168.216.150]::call getCapabilities with () {} flowID [368e5ef5]
Thread-2277::DEBUG::2012-10-23
13:18:08,905::__init__::1249::Storage.Misc.excCmd::(_log) '/usr/bin/rpm -q
--qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" qemu-kvm' (cwd
None)
Thread-2277::DEBUG::2012-10-23
13:18:08,930::__init__::1249::Storage.Misc.excCmd::(_log) SUCCESS: <err> =
''; <rc> = 0
Thread-2277::DEBUG::2012-10-23
13:18:08,931::__init__::1249::Storage.Misc.excCmd::(_log) '/usr/bin/rpm -q
--qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" qemu-img' (cwd
None)
Thread-2277::DEBUG::2012-10-23
13:18:08,951::__init__::1249::Storage.Misc.excCmd::(_log) SUCCESS: <err> =
''; <rc> = 0
Thread-2277::DEBUG::2012-10-23
13:18:08,952::__init__::1249::Storage.Misc.excCmd::(_log) '/usr/bin/rpm -q
--qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" vdsm' (cwd None)
Thread-2277::DEBUG::2012-10-23
13:18:08,975::__init__::1249::Storage.Misc.excCmd::(_log) SUCCESS: <err> =
''; <rc> = 0
Thread-2277::DEBUG::2012-10-23
13:18:08,976::__init__::1249::Storage.Misc.excCmd::(_log) '/usr/bin/rpm -q
--qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" spice-server' (cwd
None)
Thread-2277::DEBUG::2012-10-23
13:18:08,997::__init__::1249::Storage.Misc.excCmd::(_log) SUCCESS: <err> =
''; <rc> = 0
Thread-2277::DEBUG::2012-10-23
13:18:08,998::__init__::1249::Storage.Misc.excCmd::(_log) '/usr/bin/rpm -q
--qf "%{NAME}\t%{VERSION}\t%{RELEASE}\t%{BUILDTIME}\n" libvirt' (cwd None)
Thread-2277::DEBUG::2012-10-23
13:18:09,018::__init__::1249::Storage.Misc.excCmd::(_log) SUCCESS: <err> =
''; <rc> = 0
Thread-2277::DEBUG::2012-10-23
13:18:09,020::BindingXMLRPC::865::vds::(wrapper) return getCapabilities
with {'status': {'message': 'Done', 'code': 0}, 'info': {'HBAInventory':
{'iSCSI': [{'InitiatorName': 'iqn.1994-05.com.redhat:8b24ce75f189'}],
'FC': []}, 'packages2': {'kernel': {'release': '5.fc17.x86_64',
'buildtime': 1336404574.0, 'version': '3.3.4'}, 'spice-server':
{'release': '5.fc17', 'buildtime': '1336983054', 'version': '0.10.1'},
'vdsm': {'release': '10.fc17', 'buildtime': '1349383616', 'version':
'4.10.0'}, 'qemu-kvm': {'release': '2.fc17', 'buildtime': '1349642820',
'version': '1.0.1'}, 'libvirt': {'release': '1.fc17', 'buildtime':
'1349654831', 'version': '0.9.11.6'}, 'qemu-img': {'release': '2.fc17',
'buildtime': '1349642820', 'version': '1.0.1'}}, 'cpuModel': 'Intel(R)
Xeon(R) CPU           X3430  @ 2.40GHz', 'hooks': {}, 'vmTypes': ['kvm'],
'supportedProtocols': ['2.2', '2.3'], 'networks': {'ovirtmgmt': {'addr':
'192.168.216.151', 'cfg': {'IPADDR': '192.168.216.151', 'GATEWAY':
'192.168.216.254', 'DELAY': '0', 'NM_CONTROLLED': 'no', 'NETMASK':
'255.255.255.0', 'DEVICE': 'ovirtmgmt', 'TYPE': 'Bridge', 'ONBOOT':
'yes'}, 'mtu': '1500', 'netmask': '255.255.255.0', 'stp': 'off',
'bridged': True, 'gateway': '192.168.216.254', 'ports': ['em1']},
'storage': {'interface': u'bond4', 'bridged': False}}, 'uuid':
'33221100-5544-7766-8899-AABBCCDDEEFF_00:1b:21:bb:41:21',
'lastClientIface': 'ovirtmgmt', 'nics': {'p2p2': {'hwaddr':
'68:05:ca:06:d3:11', 'netmask': '', 'speed': 1000, 'addr': '', 'mtu':
'1500'}, 'em1': {'hwaddr': 'd8:d3:85:8f:11:dc', 'netmask': '', 'speed':
1000, 'addr': '', 'mtu': '1500'}, 'p3p2': {'permhwaddr':
'00:1B:21:BB:41:20', 'addr': '', 'mtu': '1500', 'netmask': '', 'hwaddr':
'00:1b:21:bb:41:21', 'speed': 0}, 'p3p1': {'permhwaddr':
'00:1B:21:BB:41:21', 'addr': '', 'mtu': '1500', 'netmask': '', 'hwaddr':
'00:1b:21:bb:41:21', 'speed': 0}, 'p2p1': {'hwaddr': '68:05:ca:06:d3:10',
'netmask': '', 'speed': 0, 'addr': '', 'mtu': '1500'}},
'software_revision': '10', 'management_ip': '', 'clusterLevels': ['3.0',
'3.1'], 'cpuFlags':
u'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,rdtscp,lm,constant_tsc,arch_perfmon,pebs,bts,rep_good,nopl,xtopology,nonstop_tsc,aperfmperf,pni,dtes64,monitor,ds_cpl,vmx,smx,est,tm2,ssse3,cx16,xtpr,pdcm,sse4_1,sse4_2,popcnt,lahf_lm,dts,tpr_shadow,vnmi,flexpriority,ept,vpid,model_coreduo,model_Conroe',
'ISCSIInitiatorName': 'iqn.1994-05.com.redhat:8b24ce75f189',
'netConfigDirty': 'True', 'memSize': '7978', 'reservedMem': '321',
'bondings': {'bond4': {'addr': '', 'cfg': {'DEVICE': 'bond4',
'NM_CONTROLLED': 'no', 'BOOTPROTO': 'none', 'ONBOOT': 'yes',
'BONDING_OPTS': 'mode=4'}, 'mtu': '1500', 'netmask': '', 'slaves':
['p3p1', 'p3p2'], 'hwaddr': '00:1b:21:bb:41:21'}, '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.10', 'cpuSpeed': '1197.000', 'cpuSockets': '1',
'vlans': {}, 'cpuCores': '4', 'kvmEnabled': 'true', 'guestOverhead': '65',
'supportedRHEVMs': ['3.0', '3.1'], 'version_name': 'Snow Man',
'emulatedMachines': [u'pc-0.15', u'pc-1.0', u'pc', u'pc-0.14', u'pc-0.13',
u'pc-0.12', u'pc-0.11', u'pc-0.10', u'isapc', u'pc-0.15', u'pc-1.0',
u'pc', u'pc-0.14', u'pc-0.13', u'pc-0.12', u'pc-0.11', u'pc-0.10',
u'isapc'], 'operatingSystem': {'release': '1', 'version': '17', 'name':
'Fedora'}, 'lastClient': '192.168.216.150'}}
Thread-2278::DEBUG::2012-10-23
13:18:09,166::BindingXMLRPC::859::vds::(wrapper) client
[192.168.216.150]::call setSafeNetworkConfig with () {} flowID [3bd2a783]
Thread-2278::DEBUG::2012-10-23
13:18:09,172::BindingXMLRPC::865::vds::(wrapper) return
setSafeNetworkConfig with {'status': {'message': 'Done', 'code': 0}}
Thread-2279::DEBUG::2012-10-23
13:18:10,677::task::588::TaskManager.Task::(_updateState)
Task=`7f8dfafb-4da7-4dd2-9bf8-16ada584bb9c`::moving from state init ->
state preparing
Thread-2279::INFO::2012-10-23
13:18:10,678::logUtils::37::dispatcher::(wrapper) Run and protect:
repoStats(options=None)
Thread-2279::INFO::2012-10-23
13:18:10,678::logUtils::39::dispatcher::(wrapper) Run and protect:
repoStats, Return response: {}
Thread-2279::DEBUG::2012-10-23
13:18:10,678::task::1172::TaskManager.Task::(prepare)
Task=`7f8dfafb-4da7-4dd2-9bf8-16ada584bb9c`::finished: {}
Thread-2279::DEBUG::2012-10-23
13:18:10,678::task::588::TaskManager.Task::(_updateState)
Task=`7f8dfafb-4da7-4dd2-9bf8-16ada584bb9c`::moving from state preparing
-> state finished
Thread-2279::DEBUG::2012-10-23
13:18:10,679::resourceManager::809::ResourceManager.Owner::(releaseAll)
Owner.releaseAll requests {} resources {}
Thread-2279::DEBUG::2012-10-23
13:18:10,679::resourceManager::844::ResourceManager.Owner::(cancelAll)
Owner.cancelAll requests {}
Thread-2279::DEBUG::2012-10-23
13:18:10,679::task::978::TaskManager.Task::(_decref)
Task=`7f8dfafb-4da7-4dd2-9bf8-16ada584bb9c`::ref 0 aborting False

[ifcfg-bond4]
DEVICE=bond4
ONBOOT=yes
BOOTPROTO=none
BONDING_OPTS=mode=4
NM_CONTROLLED=no

output from dmesg:
[ 3868.457852] bonding: bond4: Adding slave p3p1.
[ 3868.479455] 8021q: adding VLAN 0 to HW filter on device p3p1
[ 3868.479842] bonding: bond4: enslaving p3p1 as an active interface with
an up link.
[ 3868.573557] bonding: bond4: Adding slave p3p2.
[ 3868.594368] 8021q: adding VLAN 0 to HW filter on device p3p2
[ 3868.594790] bonding: bond4: enslaving p3p2 as an active interface with
an up link.
[ 3868.655028] bonding: unable to update mode of bond4 because it has slaves.
[ 3868.658528] 8021q: adding VLAN 0 to HW filter on device bond4
[ 3869.994124] ixgbe 0000:20:00.0: p3p1: detected SFP+: 0
[ 3870.055458] ixgbe 0000:20:00.1: p3p2: detected SFP+: 0
[ 3871.955610] ixgbe 0000:20:00.0: p3p1: NIC Link is Up 10 Gbps, Flow
Control: RX/TX
[ 3872.170130] ixgbe 0000:20:00.1: p3p2: NIC Link is Up 10 Gbps, Flow
Control: RX/TX
[ 3879.167139] bond4: no IPv6 routers present

Notice the error about bonding and slaves that error is also in vdsm.log,
atleast I suspect that vdsm.log/ifup is complaining about not able to
change the bond mode.

I ran yum update both on mgmt and storage, current package versions:
mgmt:
[root at mgmt01 ~]# rpm -aq | grep ovirt | sort
ovirt-engine-3.1.0-2.fc17.noarch
ovirt-engine-backend-3.1.0-2.fc17.noarch
ovirt-engine-cli-3.2.0.5-1.fc17.noarch
ovirt-engine-config-3.1.0-2.fc17.noarch
ovirt-engine-dbscripts-3.1.0-2.fc17.noarch
ovirt-engine-genericapi-3.1.0-2.fc17.noarch
ovirt-engine-notification-service-3.1.0-2.fc17.noarch
ovirt-engine-restapi-3.1.0-2.fc17.noarch
ovirt-engine-sdk-3.2.0.2-1.fc17.noarch
ovirt-engine-setup-3.1.0-2.fc17.noarch
ovirt-engine-tools-common-3.1.0-2.fc17.noarch
ovirt-engine-userportal-3.1.0-2.fc17.noarch
ovirt-engine-webadmin-portal-3.1.0-2.fc17.noarch
ovirt-guest-agent-1.0.5-1.fc17.x86_64
ovirt-image-uploader-3.1.0-0.git9c42c8.fc17.noarch
ovirt-iso-uploader-3.1.0-0.git1841d9.fc17.noarch
ovirt-log-collector-3.1.0-0.git10d719.fc17.noarch
ovirt-node-2.5.5-0.fc17.noarch
ovirt-node-iso-2.5.5-0.1.fc17.noarch
ovirt-release-fedora-4-2.noarch

storage:
[root at st01 ~]# rpm -aq | grep vdsm | sort
vdsm-4.10.0-10.fc17.x86_64
vdsm-cli-4.10.0-10.fc17.noarch
vdsm-gluster-4.10.0-10.fc17.noarch
vdsm-python-4.10.0-10.fc17.x86_64
vdsm-rest-4.10.0-10.fc17.noarch
vdsm-xmlrpc-4.10.0-10.fc17.noarch

Joop