<div dir="ltr">Found this in /var/log/vdsm/vdsm.log:<div><br></div><div><div>Thread-1080::INFO::2013-10-15 16:39:43,656::logUtils::44::dispatcher::(wrapper) Run and protect: connectStorageServer(domType=7, spUUID=&#39;00000000-0000-0000-0000-000000000000&#39;, conList=[{&#39;port&#39;: &#39;&#39;, &#39;connection&#39;: &#39;192.168.1.1:rep2-virt&#39;, &#39;iqn&#39;: &#39;&#39;, &#39;portal&#39;: &#39;&#39;, &#39;user&#39;: &#39;&#39;, &#39;vfs_type&#39;: &#39;glusterfs&#39;, &#39;password&#39;: &#39;******&#39;, &#39;id&#39;: &#39;abfea73c-31d5-43be-97f1-998fd184d98f&#39;}], options=None)</div>
</div><div><br></div><div>I&#39;m assuming that UUID shouldn&#39;t be zero&#39;s...</div><div><br></div><div>messages:</div><div><div>Oct 15 17:17:10 ovirt001 vdsm initSANLock ERROR Cannot initialize SANLock for domain 636abf41-5133-4252-9aef-8a32ec10668e</div>
<div>Oct 15 17:17:11 ovirt001 sanlock[1284]: 2013-10-15 17:17:11-0400 1228 [4075]: write_sectors delta_leader offset 127488 rv -22 /rhev/data-center/mnt/glusterSD/ovirt001:rep2-virt/636abf41-5133-4252-9aef-8a32ec10668e/dom_md/ids</div>
<div>Oct 15 17:17:12 ovirt001 sanlock[1284]: 2013-10-15 17:17:12-0400 1229 [1297]: s3 add_lockspace fail result -22</div><div>Oct 15 17:17:12 ovirt001 vdsm TaskManager.Task ERROR Task=`e53f26ec-66b9-4f01-aa10-053a738780b8`::Unexpected error</div>
</div><div><br></div><div><br></div><div>sanlock.log:</div><div><div>2013-10-15 17:17:11-0400 1228 [1297]: s3 lockspace 636abf41-5133-4252-9aef-8a32ec10668e:250:/rhev/data-center/mnt/glusterSD/ovirt001:rep2-virt/636abf41-5133-4252-9aef-8a32ec10668e/dom_md/ids:0</div>
<div>2013-10-15 17:17:11-0400 1228 [4075]: 636abf41 aio collect 1 0x7f41f80008c0:0x7f41f80008d0:0x7f41f8101000 result -22:0 match res</div><div>2013-10-15 17:17:11-0400 1228 [4075]: write_sectors delta_leader offset 127488 rv -22 /rhev/data-center/mnt/glusterSD/ovirt001:rep2-virt/636abf41-5133-4252-9aef-8a32ec10668e/dom_md/ids</div>
<div>2013-10-15 17:17:12-0400 1229 [1297]: s3 add_lockspace fail result -22</div></div><div><br></div><div>vdsm.log:</div><div><div>Thread-488::DEBUG::2013-10-15 17:17:07,316::BindingXMLRPC::177::vds::(wrapper) client [10.0.6.22]</div>
<div>Thread-488::DEBUG::2013-10-15 17:17:07,317::task::579::TaskManager.Task::(_updateState) Task=`6a1db668-e38b-4698-8a34-554f55182850`::moving from state init -&gt; state preparing</div><div>Thread-488::INFO::2013-10-15 17:17:07,318::logUtils::44::dispatcher::(wrapper) Run and protect: connectStorageServer(domType=7, spUUID=&#39;00000000-0000-0000-0000-000000000000&#39;, conList=[{&#39;port&#39;: &#39;&#39;, &#39;connection&#39;: &#39;ovirt001:rep2-virt&#39;, &#39;iqn&#39;: &#39;&#39;, &#39;portal&#39;: &#39;&#39;, &#39;user&#39;: &#39;&#39;, &#39;vfs_type&#39;: &#39;glusterfs&#39;, &#39;password&#39;: &#39;******&#39;, &#39;id&#39;: &#39;00000000-0000-0000-0000-000000000000&#39;}], options=None)</div>
<div>Thread-488::DEBUG::2013-10-15 17:17:07,323::mount::226::Storage.Misc.excCmd::(_runcmd) &#39;/usr/bin/sudo -n /usr/bin/mount -t glusterfs ovirt001:rep2-virt /rhev/data-center/mnt/glusterSD/ovirt001:rep2-virt&#39; (cwd None)</div>
<div>Thread-488::DEBUG::2013-10-15 17:17:07,423::hsm::2333::Storage.HSM::(__prefetchDomains) glusterDomPath: glusterSD/*</div><div>Thread-488::DEBUG::2013-10-15 17:17:07,506::hsm::2345::Storage.HSM::(__prefetchDomains) Found SD uuids: ()</div>
<div>Thread-488::DEBUG::2013-10-15 17:17:07,507::hsm::2396::Storage.HSM::(connectStorageServer) knownSDs: {4a81a0e0-a95a-4e02-853f-68df420a7ce4: storage.glusterSD.findDomain}</div><div>Thread-488::INFO::2013-10-15 17:17:07,507::logUtils::47::dispatcher::(wrapper) Run and protect: connectStorageServer, Return response: {&#39;statuslist&#39;: [{&#39;status&#39;: 0, &#39;id&#39;: &#39;00000000-0000-0000-0000-000000000000&#39;}]}</div>
<div>Thread-488::DEBUG::2013-10-15 17:17:07,507::task::1168::TaskManager.Task::(prepare) Task=`6a1db668-e38b-4698-8a34-554f55182850`::finished: {&#39;statuslist&#39;: [{&#39;status&#39;: 0, &#39;id&#39;: &#39;00000000-0000-0000-0000-000000000000&#39;}]}</div>
<div>Thread-488::DEBUG::2013-10-15 17:17:07,507::task::579::TaskManager.Task::(_updateState) Task=`6a1db668-e38b-4698-8a34-554f55182850`::moving from state preparing -&gt; state finished</div><div>Thread-488::DEBUG::2013-10-15 17:17:07,507::resourceManager::939::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}</div>
<div>Thread-488::DEBUG::2013-10-15 17:17:07,508::resourceManager::976::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}</div><div>Thread-488::DEBUG::2013-10-15 17:17:07,508::task::974::TaskManager.Task::(_decref) Task=`6a1db668-e38b-4698-8a34-554f55182850`::ref 0 aborting False</div>
<div>Thread-490::DEBUG::2013-10-15 17:17:07,709::BindingXMLRPC::177::vds::(wrapper) client [10.0.6.22]</div><div>Thread-490::DEBUG::2013-10-15 17:17:07,710::task::579::TaskManager.Task::(_updateState) Task=`95b7da65-b45f-4836-9103-b64c6b5fc15a`::moving from state init -&gt; state preparing</div>
<div>Thread-490::INFO::2013-10-15 17:17:07,710::logUtils::44::dispatcher::(wrapper) Run and protect: createStorageDomain(storageType=7, sdUUID=&#39;636abf41-5133-4252-9aef-8a32ec10668e&#39;, domainName=&#39;rep2-virt&#39;, typeSpecificArg=&#39;ovirt001:rep2-virt&#39;, domClass=1, domVersion=&#39;3&#39;, options=None)</div>
<div>Thread-490::DEBUG::2013-10-15 17:17:07,710::misc::807::SamplingMethod::(__call__) Trying to enter sampling method (storage.sdc.refreshStorage)</div><div>Thread-490::DEBUG::2013-10-15 17:17:07,710::misc::809::SamplingMethod::(__call__) Got in to sampling method</div>
<div>Thread-490::DEBUG::2013-10-15 17:17:07,711::misc::807::SamplingMethod::(__call__) Trying to enter sampling method (storage.iscsi.rescan)</div><div>Thread-490::DEBUG::2013-10-15 17:17:07,711::misc::809::SamplingMethod::(__call__) Got in to sampling method</div>
<div>Thread-490::DEBUG::2013-10-15 17:17:07,711::iscsiadm::91::Storage.Misc.excCmd::(_runCmd) &#39;/usr/bin/sudo -n /sbin/iscsiadm -m session -R&#39; (cwd None)</div><div>Thread-490::DEBUG::2013-10-15 17:17:07,727::iscsiadm::91::Storage.Misc.excCmd::(_runCmd) FAILED: &lt;err&gt; = &#39;iscsiadm: No session found.\n&#39;; &lt;rc&gt; = 21</div>
<div>Thread-490::DEBUG::2013-10-15 17:17:07,727::misc::817::SamplingMethod::(__call__) Returning last result</div><div>Thread-490::DEBUG::2013-10-15 17:17:09,750::multipath::111::Storage.Misc.excCmd::(rescan) &#39;/usr/bin/sudo -n /sbin/multipath&#39; (cwd None)</div>
<div>Thread-490::DEBUG::2013-10-15 17:17:09,943::multipath::111::Storage.Misc.excCmd::(rescan) SUCCESS: &lt;err&gt; = &#39;&#39;; &lt;rc&gt; = 0</div><div>Thread-490::DEBUG::2013-10-15 17:17:09,944::lvm::483::OperationMutex::(_invalidateAllPvs) Operation &#39;lvm invalidate operation&#39; got the operation mutex</div>
<div>Thread-490::DEBUG::2013-10-15 17:17:09,944::lvm::485::OperationMutex::(_invalidateAllPvs) Operation &#39;lvm invalidate operation&#39; released the operation mutex</div><div>Thread-490::DEBUG::2013-10-15 17:17:09,944::lvm::494::OperationMutex::(_invalidateAllVgs) Operation &#39;lvm invalidate operation&#39; got the operation mutex</div>
<div>Thread-490::DEBUG::2013-10-15 17:17:09,945::lvm::496::OperationMutex::(_invalidateAllVgs) Operation &#39;lvm invalidate operation&#39; released the operation mutex</div><div>Thread-490::DEBUG::2013-10-15 17:17:09,945::lvm::514::OperationMutex::(_invalidateAllLvs) Operation &#39;lvm invalidate operation&#39; got the operation mutex</div>
<div>Thread-490::DEBUG::2013-10-15 17:17:09,945::lvm::516::OperationMutex::(_invalidateAllLvs) Operation &#39;lvm invalidate operation&#39; released the operation mutex</div><div>Thread-490::DEBUG::2013-10-15 17:17:09,945::misc::817::SamplingMethod::(__call__) Returning last result</div>
<div>Thread-490::ERROR::2013-10-15 17:17:09,945::sdc::137::Storage.StorageDomainCache::(_findDomain) looking for unfetched domain 636abf41-5133-4252-9aef-8a32ec10668e</div><div>Thread-490::ERROR::2013-10-15 17:17:09,945::sdc::154::Storage.StorageDomainCache::(_findUnfetchedDomain) looking for domain 636abf41-5133-4252-9aef-8a32ec10668e</div>
<div>Thread-490::DEBUG::2013-10-15 17:17:09,946::lvm::374::OperationMutex::(_reloadvgs) Operation &#39;lvm reload operation&#39; got the operation mutex</div><div>Thread-490::DEBUG::2013-10-15 17:17:09,947::lvm::311::Storage.Misc.excCmd::(cmd) &#39;/usr/bin/sudo -n /sbin/lvm vgs --config &quot; devices { preferred_names = [\\&quot;^/dev/mapper/\\&quot;] ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3 filter = [ \&#39;r|.*|\&#39; ] }  global {  locking_type=1  prioritise_write_locks=1  wait_for_locks=1 }  backup {  retain_min = 50  retain_days = 0 } &quot; --noheadings --units b --nosuffix --separator | -o uuid,name,attr,size,free,extent_size,extent_count,free_count,tags,vg_mda_size,vg_mda_free 636abf41-5133-4252-9aef-8a32ec10668e&#39; (cwd None)</div>
<div>Thread-490::DEBUG::2013-10-15 17:17:09,974::lvm::311::Storage.Misc.excCmd::(cmd) FAILED: &lt;err&gt; = &#39;  Volume group &quot;636abf41-5133-4252-9aef-8a32ec10668e&quot; not found\n&#39;; &lt;rc&gt; = 5</div><div>Thread-490::WARNING::2013-10-15 17:17:09,975::lvm::379::Storage.LVM::(_reloadvgs) lvm vgs failed: 5 [] [&#39;  Volume group &quot;636abf41-5133-4252-9aef-8a32ec10668e&quot; not found&#39;]</div>
<div>Thread-490::DEBUG::2013-10-15 17:17:09,975::lvm::403::OperationMutex::(_reloadvgs) Operation &#39;lvm reload operation&#39; released the operation mutex</div><div>Thread-490::ERROR::2013-10-15 17:17:09,981::sdc::143::Storage.StorageDomainCache::(_findDomain) domain 636abf41-5133-4252-9aef-8a32ec10668e not found</div>
<div>Traceback (most recent call last):</div><div>  File &quot;/usr/share/vdsm/storage/sdc.py&quot;, line 141, in _findDomain</div><div>    dom = findMethod(sdUUID)</div><div>  File &quot;/usr/share/vdsm/storage/sdc.py&quot;, line 171, in _findUnfetchedDomain</div>
<div>    raise se.StorageDomainDoesNotExist(sdUUID)</div><div>StorageDomainDoesNotExist: Storage domain does not exist: (&#39;636abf41-5133-4252-9aef-8a32ec10668e&#39;,)</div><div>Thread-490::INFO::2013-10-15 17:17:09,982::nfsSD::69::Storage.StorageDomain::(create) sdUUID=636abf41-5133-4252-9aef-8a32ec10668e domainName=rep2-virt remotePath=ovirt001:rep2-virt domClass=1</div>
<div>Thread-490::DEBUG::2013-10-15 17:17:10,104::persistentDict::192::Storage.PersistentDict::(__init__) Created a persistent dict with FileMetadataRW backend</div><div>Thread-490::DEBUG::2013-10-15 17:17:10,115::persistentDict::234::Storage.PersistentDict::(refresh) read lines (FileMetadataRW)=[]</div>
<div>Thread-490::WARNING::2013-10-15 17:17:10,115::persistentDict::256::Storage.PersistentDict::(refresh) data has no embedded checksum - trust it as it is</div><div>Thread-490::DEBUG::2013-10-15 17:17:10,115::persistentDict::167::Storage.PersistentDict::(transaction) Starting transaction</div>
<div>Thread-490::DEBUG::2013-10-15 17:17:10,115::persistentDict::173::Storage.PersistentDict::(transaction) Flushing changes</div><div>Thread-490::DEBUG::2013-10-15 17:17:10,115::persistentDict::299::Storage.PersistentDict::(flush) about to write lines (FileMetadataRW)=[&#39;CLASS=Data&#39;, &#39;DESCRIPTION=rep2-virt&#39;, &#39;IOOPTIMEOUTSEC=1&#39;, &#39;LEASERETRIES=3&#39;, &#39;LEASETIMESEC=5&#39;, &#39;LOCKPOLICY=&#39;, &#39;LOCKRENEWALINTERVALSEC=5&#39;, &#39;POOL_UUID=&#39;, &#39;REMOTE_PATH=ovirt001:rep2-virt&#39;, &#39;ROLE=Regular&#39;, &#39;SDUUID=636abf41-5133-4252-9aef-8a32ec10668e&#39;, &#39;TYPE=GLUSTERFS&#39;, &#39;VERSION=3&#39;, &#39;_SHA_CKSUM=a38d3de67a9f2b5bc3979eb5f4dfbce7d48986d7&#39;]</div>
<div>Thread-490::DEBUG::2013-10-15 17:17:10,119::persistentDict::175::Storage.PersistentDict::(transaction) Finished transaction</div><div>Thread-490::DEBUG::2013-10-15 17:17:10,122::fileSD::153::Storage.StorageDomain::(__init__) Reading domain in path /rhev/data-center/mnt/glusterSD/ovirt001:rep2-virt/636abf41-5133-4252-9aef-8a32ec10668e</div>
<div>Thread-490::DEBUG::2013-10-15 17:17:10,122::persistentDict::192::Storage.PersistentDict::(__init__) Created a persistent dict with FileMetadataRW backend</div><div>Thread-490::DEBUG::2013-10-15 17:17:10,133::persistentDict::234::Storage.PersistentDict::(refresh) read lines (FileMetadataRW)=[&#39;CLASS=Data&#39;, &#39;DESCRIPTION=rep2-virt&#39;, &#39;IOOPTIMEOUTSEC=1&#39;, &#39;LEASERETRIES=3&#39;, &#39;LEASETIMESEC=5&#39;, &#39;LOCKPOLICY=&#39;, &#39;LOCKRENEWALINTERVALSEC=5&#39;, &#39;POOL_UUID=&#39;, &#39;REMOTE_PATH=ovirt001:rep2-virt&#39;, &#39;ROLE=Regular&#39;, &#39;SDUUID=636abf41-5133-4252-9aef-8a32ec10668e&#39;, &#39;TYPE=GLUSTERFS&#39;, &#39;VERSION=3&#39;, &#39;_SHA_CKSUM=a38d3de67a9f2b5bc3979eb5f4dfbce7d48986d7&#39;]</div>
<div>Thread-490::DEBUG::2013-10-15 17:17:10,135::fileSD::535::Storage.StorageDomain::(imageGarbageCollector) Removing remnants of deleted images []</div><div>Thread-490::DEBUG::2013-10-15 17:17:10,136::resourceManager::420::ResourceManager::(registerNamespace) Registering namespace &#39;636abf41-5133-4252-9aef-8a32ec10668e_imageNS&#39;</div>
<div>Thread-490::DEBUG::2013-10-15 17:17:10,136::resourceManager::420::ResourceManager::(registerNamespace) Registering namespace &#39;636abf41-5133-4252-9aef-8a32ec10668e_volumeNS&#39;</div><div>Thread-490::DEBUG::2013-10-15 17:17:10,136::clusterlock::137::initSANLock::(initSANLock) Initializing SANLock for domain 636abf41-5133-4252-9aef-8a32ec10668e</div>
<div>Thread-490::ERROR::2013-10-15 17:17:10,731::clusterlock::145::initSANLock::(initSANLock) Cannot initialize SANLock for domain 636abf41-5133-4252-9aef-8a32ec10668e</div><div>Traceback (most recent call last):</div><div>
  File &quot;/usr/share/vdsm/storage/clusterlock.py&quot;, line 140, in initSANLock</div><div>    sanlock.init_lockspace(sdUUID, idsPath)</div><div>SanlockException: (22, &#39;Sanlock lockspace init failure&#39;, &#39;Invalid argument&#39;)</div>
<div>Thread-490::WARNING::2013-10-15 17:17:10,732::sd::428::Storage.StorageDomain::(initSPMlease) lease did not initialize successfully</div><div>Traceback (most recent call last):</div><div>  File &quot;/usr/share/vdsm/storage/sd.py&quot;, line 423, in initSPMlease</div>
<div>    self._clusterLock.initLock()</div><div>  File &quot;/usr/share/vdsm/storage/clusterlock.py&quot;, line 163, in initLock</div><div>    initSANLock(self._sdUUID, self._idsPath, self._leasesPath)</div><div>  File &quot;/usr/share/vdsm/storage/clusterlock.py&quot;, line 146, in initSANLock</div>
<div>    raise se.ClusterLockInitError()</div><div>ClusterLockInitError: Could not initialize cluster lock: ()</div><div>Thread-490::DEBUG::2013-10-15 17:17:10,732::hsm::2624::Storage.HSM::(createStorageDomain) knownSDs: {636abf41-5133-4252-9aef-8a32ec10668e: storage.glusterSD.findDomain, 4a81a0e0-a95a-4e02-853f-68df420a7ce4: storage.glusterSD.findDomain}</div>
<div>Thread-490::INFO::2013-10-15 17:17:10,732::logUtils::47::dispatcher::(wrapper) Run and protect: createStorageDomain, Return response: None</div><div>Thread-490::DEBUG::2013-10-15 17:17:10,732::task::1168::TaskManager.Task::(prepare) Task=`95b7da65-b45f-4836-9103-b64c6b5fc15a`::finished: None</div>
<div>Thread-490::DEBUG::2013-10-15 17:17:10,733::task::579::TaskManager.Task::(_updateState) Task=`95b7da65-b45f-4836-9103-b64c6b5fc15a`::moving from state preparing -&gt; state finished</div><div>Thread-490::DEBUG::2013-10-15 17:17:10,733::resourceManager::939::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}</div>
<div>Thread-490::DEBUG::2013-10-15 17:17:10,733::resourceManager::976::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}</div><div>Thread-490::DEBUG::2013-10-15 17:17:10,733::task::974::TaskManager.Task::(_decref) Task=`95b7da65-b45f-4836-9103-b64c6b5fc15a`::ref 0 aborting False</div>
<div>Thread-494::DEBUG::2013-10-15 17:17:10,739::BindingXMLRPC::177::vds::(wrapper) client [10.0.6.22]</div><div>Thread-494::DEBUG::2013-10-15 17:17:10,740::task::579::TaskManager.Task::(_updateState) Task=`7b5b467e-a1e5-413c-934d-18e337cf6dd8`::moving from state init -&gt; state preparing</div>
<div>Thread-494::INFO::2013-10-15 17:17:10,740::logUtils::44::dispatcher::(wrapper) Run and protect: getStorageDomainStats(sdUUID=&#39;636abf41-5133-4252-9aef-8a32ec10668e&#39;, options=None)</div><div>Thread-494::DEBUG::2013-10-15 17:17:10,741::resourceManager::197::ResourceManager.Request::(__init__) ResName=`Storage.636abf41-5133-4252-9aef-8a32ec10668e`ReqID=`0602d0b9-a7ff-4f3f-80bd-c886fe4e3270`::Request was made in &#39;/usr/share/vdsm/storage/hsm.py&#39; line &#39;2784&#39; at &#39;getStorageDomainStats&#39;</div>
<div>Thread-494::DEBUG::2013-10-15 17:17:10,741::resourceManager::541::ResourceManager::(registerResource) Trying to register resource &#39;Storage.636abf41-5133-4252-9aef-8a32ec10668e&#39; for lock type &#39;shared&#39;</div>
<div>Thread-494::DEBUG::2013-10-15 17:17:10,741::resourceManager::600::ResourceManager::(registerResource) Resource &#39;Storage.636abf41-5133-4252-9aef-8a32ec10668e&#39; is free. Now locking as &#39;shared&#39; (1 active user)</div>
<div>Thread-494::DEBUG::2013-10-15 17:17:10,741::resourceManager::237::ResourceManager.Request::(grant) ResName=`Storage.636abf41-5133-4252-9aef-8a32ec10668e`ReqID=`0602d0b9-a7ff-4f3f-80bd-c886fe4e3270`::Granted request</div>
<div>Thread-494::DEBUG::2013-10-15 17:17:10,742::task::811::TaskManager.Task::(resourceAcquired) Task=`7b5b467e-a1e5-413c-934d-18e337cf6dd8`::_resourcesAcquired: Storage.636abf41-5133-4252-9aef-8a32ec10668e (shared)</div>
<div>Thread-494::DEBUG::2013-10-15 17:17:10,742::task::974::TaskManager.Task::(_decref) Task=`7b5b467e-a1e5-413c-934d-18e337cf6dd8`::ref 1 aborting False</div><div>Thread-494::INFO::2013-10-15 17:17:10,743::logUtils::47::dispatcher::(wrapper) Run and protect: getStorageDomainStats, Return response: {&#39;stats&#39;: {&#39;mdasize&#39;: 0, &#39;mdathreshold&#39;: True, &#39;mdavalid&#39;: True, &#39;diskfree&#39;: &#39;14144560824320&#39;, &#39;disktotal&#39;: &#39;14199600185344&#39;, &#39;mdafree&#39;: 0}}</div>
<div>Thread-494::DEBUG::2013-10-15 17:17:10,744::task::1168::TaskManager.Task::(prepare) Task=`7b5b467e-a1e5-413c-934d-18e337cf6dd8`::finished: {&#39;stats&#39;: {&#39;mdasize&#39;: 0, &#39;mdathreshold&#39;: True, &#39;mdavalid&#39;: True, &#39;diskfree&#39;: &#39;14144560824320&#39;, &#39;disktotal&#39;: &#39;14199600185344&#39;, &#39;mdafree&#39;: 0}}</div>
<div>Thread-494::DEBUG::2013-10-15 17:17:10,744::task::579::TaskManager.Task::(_updateState) Task=`7b5b467e-a1e5-413c-934d-18e337cf6dd8`::moving from state preparing -&gt; state finished</div><div>Thread-494::DEBUG::2013-10-15 17:17:10,744::resourceManager::939::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {&#39;Storage.636abf41-5133-4252-9aef-8a32ec10668e&#39;: &lt; ResourceRef &#39;Storage.636abf41-5133-4252-9aef-8a32ec10668e&#39;, isValid: &#39;True&#39; obj: &#39;None&#39;&gt;}</div>
<div>Thread-494::DEBUG::2013-10-15 17:17:10,744::resourceManager::976::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}</div><div>Thread-494::DEBUG::2013-10-15 17:17:10,744::resourceManager::615::ResourceManager::(releaseResource) Trying to release resource &#39;Storage.636abf41-5133-4252-9aef-8a32ec10668e&#39;</div>
<div>Thread-494::DEBUG::2013-10-15 17:17:10,745::resourceManager::634::ResourceManager::(releaseResource) Released resource &#39;Storage.636abf41-5133-4252-9aef-8a32ec10668e&#39; (0 active users)</div><div>Thread-494::DEBUG::2013-10-15 17:17:10,745::resourceManager::640::ResourceManager::(releaseResource) Resource &#39;Storage.636abf41-5133-4252-9aef-8a32ec10668e&#39; is free, finding out if anyone is waiting for it.</div>
<div>Thread-494::DEBUG::2013-10-15 17:17:10,745::resourceManager::648::ResourceManager::(releaseResource) No one is waiting for resource &#39;Storage.636abf41-5133-4252-9aef-8a32ec10668e&#39;, Clearing records.</div><div>
Thread-494::DEBUG::2013-10-15 17:17:10,745::task::974::TaskManager.Task::(_decref) Task=`7b5b467e-a1e5-413c-934d-18e337cf6dd8`::ref 0 aborting False</div><div>Thread-495::DEBUG::2013-10-15 17:17:11,113::BindingXMLRPC::177::vds::(wrapper) client [10.0.6.22]</div>
<div>Thread-495::DEBUG::2013-10-15 17:17:11,113::task::579::TaskManager.Task::(_updateState) Task=`d217edaf-c57b-4c28-aceb-5d68ec306531`::moving from state init -&gt; state preparing</div><div>Thread-495::INFO::2013-10-15 17:17:11,114::logUtils::44::dispatcher::(wrapper) Run and protect: connectStorageServer(domType=7, spUUID=&#39;00000000-0000-0000-0000-000000000000&#39;, conList=[{&#39;port&#39;: &#39;&#39;, &#39;connection&#39;: &#39;ovirt001:rep2-virt&#39;, &#39;iqn&#39;: &#39;&#39;, &#39;portal&#39;: &#39;&#39;, &#39;user&#39;: &#39;&#39;, &#39;vfs_type&#39;: &#39;glusterfs&#39;, &#39;password&#39;: &#39;******&#39;, &#39;id&#39;: &#39;648e518f-11eb-48d1-b7cd-64c7b7cf6204&#39;}], options=None)</div>
<div>Thread-495::DEBUG::2013-10-15 17:17:11,119::hsm::2333::Storage.HSM::(__prefetchDomains) glusterDomPath: glusterSD/*</div><div>Thread-495::DEBUG::2013-10-15 17:17:11,125::hsm::2345::Storage.HSM::(__prefetchDomains) Found SD uuids: (&#39;636abf41-5133-4252-9aef-8a32ec10668e&#39;,)</div>
<div>Thread-495::DEBUG::2013-10-15 17:17:11,126::hsm::2396::Storage.HSM::(connectStorageServer) knownSDs: {636abf41-5133-4252-9aef-8a32ec10668e: storage.glusterSD.findDomain, 4a81a0e0-a95a-4e02-853f-68df420a7ce4: storage.glusterSD.findDomain}</div>
<div>Thread-495::INFO::2013-10-15 17:17:11,126::logUtils::47::dispatcher::(wrapper) Run and protect: connectStorageServer, Return response: {&#39;statuslist&#39;: [{&#39;status&#39;: 0, &#39;id&#39;: &#39;648e518f-11eb-48d1-b7cd-64c7b7cf6204&#39;}]}</div>
<div>Thread-495::DEBUG::2013-10-15 17:17:11,126::task::1168::TaskManager.Task::(prepare) Task=`d217edaf-c57b-4c28-aceb-5d68ec306531`::finished: {&#39;statuslist&#39;: [{&#39;status&#39;: 0, &#39;id&#39;: &#39;648e518f-11eb-48d1-b7cd-64c7b7cf6204&#39;}]}</div>
<div>Thread-495::DEBUG::2013-10-15 17:17:11,126::task::579::TaskManager.Task::(_updateState) Task=`d217edaf-c57b-4c28-aceb-5d68ec306531`::moving from state preparing -&gt; state finished</div><div>Thread-495::DEBUG::2013-10-15 17:17:11,126::resourceManager::939::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}</div>
<div>Thread-495::DEBUG::2013-10-15 17:17:11,127::resourceManager::976::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}</div><div>Thread-495::DEBUG::2013-10-15 17:17:11,127::task::974::TaskManager.Task::(_decref) Task=`d217edaf-c57b-4c28-aceb-5d68ec306531`::ref 0 aborting False</div>
<div>Thread-497::DEBUG::2013-10-15 17:17:11,133::BindingXMLRPC::177::vds::(wrapper) client [10.0.6.22]</div><div>Thread-497::DEBUG::2013-10-15 17:17:11,133::task::579::TaskManager.Task::(_updateState) Task=`e53f26ec-66b9-4f01-aa10-053a738780b8`::moving from state init -&gt; state preparing</div>
<div>Thread-497::INFO::2013-10-15 17:17:11,134::logUtils::44::dispatcher::(wrapper) Run and protect: createStoragePool(poolType=None, spUUID=&#39;5849b030-626e-47cb-ad90-3ce782d831b3&#39;, poolName=&#39;Default&#39;, masterDom=&#39;636abf41-5133-4252-9aef-8a32ec10668e&#39;, domList=[&#39;636abf41-5133-4252-9aef-8a32ec10668e&#39;], masterVersion=12, lockPolicy=None, lockRenewalIntervalSec=5, leaseTimeSec=60, ioOpTimeoutSec=10, leaseRetries=3, options=None)</div>
<div>Thread-497::INFO::2013-10-15 17:17:11,134::fileSD::315::Storage.StorageDomain::(validate) sdUUID=636abf41-5133-4252-9aef-8a32ec10668e</div><div>Thread-497::DEBUG::2013-10-15 17:17:11,144::persistentDict::234::Storage.PersistentDict::(refresh) read lines (FileMetadataRW)=[&#39;CLASS=Data&#39;, &#39;DESCRIPTION=rep2-virt&#39;, &#39;IOOPTIMEOUTSEC=1&#39;, &#39;LEASERETRIES=3&#39;, &#39;LEASETIMESEC=5&#39;, &#39;LOCKPOLICY=&#39;, &#39;LOCKRENEWALINTERVALSEC=5&#39;, &#39;POOL_UUID=&#39;, &#39;REMOTE_PATH=ovirt001:rep2-virt&#39;, &#39;ROLE=Regular&#39;, &#39;SDUUID=636abf41-5133-4252-9aef-8a32ec10668e&#39;, &#39;TYPE=GLUSTERFS&#39;, &#39;VERSION=3&#39;, &#39;_SHA_CKSUM=a38d3de67a9f2b5bc3979eb5f4dfbce7d48986d7&#39;]</div>
<div>Thread-497::DEBUG::2013-10-15 17:17:11,145::resourceManager::197::ResourceManager.Request::(__init__) ResName=`Storage.5849b030-626e-47cb-ad90-3ce782d831b3`ReqID=`938eb5db-1766-43df-8a91-ef34759cfc06`::Request was made in &#39;/usr/share/vdsm/storage/hsm.py&#39; line &#39;954&#39; at &#39;createStoragePool&#39;</div>
<div>Thread-497::DEBUG::2013-10-15 17:17:11,145::resourceManager::541::ResourceManager::(registerResource) Trying to register resource &#39;Storage.5849b030-626e-47cb-ad90-3ce782d831b3&#39; for lock type &#39;exclusive&#39;</div>
<div>Thread-497::DEBUG::2013-10-15 17:17:11,146::resourceManager::600::ResourceManager::(registerResource) Resource &#39;Storage.5849b030-626e-47cb-ad90-3ce782d831b3&#39; is free. Now locking as &#39;exclusive&#39; (1 active user)</div>
<div>Thread-497::DEBUG::2013-10-15 17:17:11,146::resourceManager::237::ResourceManager.Request::(grant) ResName=`Storage.5849b030-626e-47cb-ad90-3ce782d831b3`ReqID=`938eb5db-1766-43df-8a91-ef34759cfc06`::Granted request</div>
<div>Thread-497::DEBUG::2013-10-15 17:17:11,146::task::811::TaskManager.Task::(resourceAcquired) Task=`e53f26ec-66b9-4f01-aa10-053a738780b8`::_resourcesAcquired: Storage.5849b030-626e-47cb-ad90-3ce782d831b3 (exclusive)</div>
<div>Thread-497::DEBUG::2013-10-15 17:17:11,146::task::974::TaskManager.Task::(_decref) Task=`e53f26ec-66b9-4f01-aa10-053a738780b8`::ref 1 aborting False</div><div>Thread-497::DEBUG::2013-10-15 17:17:11,147::resourceManager::197::ResourceManager.Request::(__init__) ResName=`Storage.636abf41-5133-4252-9aef-8a32ec10668e`ReqID=`79bba45b-37b7-417f-bfbf-fc96603ed997`::Request was made in &#39;/usr/share/vdsm/storage/hsm.py&#39; line &#39;956&#39; at &#39;createStoragePool&#39;</div>
<div>Thread-497::DEBUG::2013-10-15 17:17:11,147::resourceManager::541::ResourceManager::(registerResource) Trying to register resource &#39;Storage.636abf41-5133-4252-9aef-8a32ec10668e&#39; for lock type &#39;exclusive&#39;</div>
<div>Thread-497::DEBUG::2013-10-15 17:17:11,147::resourceManager::600::ResourceManager::(registerResource) Resource &#39;Storage.636abf41-5133-4252-9aef-8a32ec10668e&#39; is free. Now locking as &#39;exclusive&#39; (1 active user)</div>
<div>Thread-497::DEBUG::2013-10-15 17:17:11,147::resourceManager::237::ResourceManager.Request::(grant) ResName=`Storage.636abf41-5133-4252-9aef-8a32ec10668e`ReqID=`79bba45b-37b7-417f-bfbf-fc96603ed997`::Granted request</div>
<div>Thread-497::DEBUG::2013-10-15 17:17:11,147::task::811::TaskManager.Task::(resourceAcquired) Task=`e53f26ec-66b9-4f01-aa10-053a738780b8`::_resourcesAcquired: Storage.636abf41-5133-4252-9aef-8a32ec10668e (exclusive)</div>
<div>Thread-497::DEBUG::2013-10-15 17:17:11,148::task::974::TaskManager.Task::(_decref) Task=`e53f26ec-66b9-4f01-aa10-053a738780b8`::ref 1 aborting False</div><div>Thread-497::INFO::2013-10-15 17:17:11,148::sp::592::Storage.StoragePool::(create) spUUID=5849b030-626e-47cb-ad90-3ce782d831b3 poolName=Default master_sd=636abf41-5133-4252-9aef-8a32ec10668e domList=[&#39;636abf41-5133-4252-9aef-8a32ec10668e&#39;] masterVersion=12 {&#39;LEASETIMESEC&#39;: 60, &#39;IOOPTIMEOUTSEC&#39;: 10, &#39;LEASERETRIES&#39;: 3, &#39;LOCKRENEWALINTERVALSEC&#39;: 5}</div>
<div>Thread-497::INFO::2013-10-15 17:17:11,148::fileSD::315::Storage.StorageDomain::(validate) sdUUID=636abf41-5133-4252-9aef-8a32ec10668e</div><div>Thread-497::DEBUG::2013-10-15 17:17:11,158::persistentDict::234::Storage.PersistentDict::(refresh) read lines (FileMetadataRW)=[&#39;CLASS=Data&#39;, &#39;DESCRIPTION=rep2-virt&#39;, &#39;IOOPTIMEOUTSEC=1&#39;, &#39;LEASERETRIES=3&#39;, &#39;LEASETIMESEC=5&#39;, &#39;LOCKPOLICY=&#39;, &#39;LOCKRENEWALINTERVALSEC=5&#39;, &#39;POOL_UUID=&#39;, &#39;REMOTE_PATH=ovirt001:rep2-virt&#39;, &#39;ROLE=Regular&#39;, &#39;SDUUID=636abf41-5133-4252-9aef-8a32ec10668e&#39;, &#39;TYPE=GLUSTERFS&#39;, &#39;VERSION=3&#39;, &#39;_SHA_CKSUM=a38d3de67a9f2b5bc3979eb5f4dfbce7d48986d7&#39;]</div>
<div>Thread-497::DEBUG::2013-10-15 17:17:11,168::persistentDict::234::Storage.PersistentDict::(refresh) read lines (FileMetadataRW)=[&#39;CLASS=Data&#39;, &#39;DESCRIPTION=rep2-virt&#39;, &#39;IOOPTIMEOUTSEC=1&#39;, &#39;LEASERETRIES=3&#39;, &#39;LEASETIMESEC=5&#39;, &#39;LOCKPOLICY=&#39;, &#39;LOCKRENEWALINTERVALSEC=5&#39;, &#39;POOL_UUID=&#39;, &#39;REMOTE_PATH=ovirt001:rep2-virt&#39;, &#39;ROLE=Regular&#39;, &#39;SDUUID=636abf41-5133-4252-9aef-8a32ec10668e&#39;, &#39;TYPE=GLUSTERFS&#39;, &#39;VERSION=3&#39;, &#39;_SHA_CKSUM=a38d3de67a9f2b5bc3979eb5f4dfbce7d48986d7&#39;]</div>
<div>Thread-497::WARNING::2013-10-15 17:17:11,168::fileUtils::167::Storage.fileUtils::(createdir) Dir /rhev/data-center/5849b030-626e-47cb-ad90-3ce782d831b3 already exists</div><div>Thread-497::DEBUG::2013-10-15 17:17:11,168::persistentDict::167::Storage.PersistentDict::(transaction) Starting transaction</div>
<div>Thread-497::DEBUG::2013-10-15 17:17:11,169::persistentDict::173::Storage.PersistentDict::(transaction) Flushing changes</div><div>Thread-497::DEBUG::2013-10-15 17:17:11,169::persistentDict::299::Storage.PersistentDict::(flush) about to write lines (FileMetadataRW)=[&#39;CLASS=Data&#39;, &#39;DESCRIPTION=rep2-virt&#39;, &#39;IOOPTIMEOUTSEC=10&#39;, &#39;LEASERETRIES=3&#39;, &#39;LEASETIMESEC=60&#39;, &#39;LOCKPOLICY=&#39;, &#39;LOCKRENEWALINTERVALSEC=5&#39;, &#39;POOL_UUID=&#39;, &#39;REMOTE_PATH=ovirt001:rep2-virt&#39;, &#39;ROLE=Regular&#39;, &#39;SDUUID=636abf41-5133-4252-9aef-8a32ec10668e&#39;, &#39;TYPE=GLUSTERFS&#39;, &#39;VERSION=3&#39;, &#39;_SHA_CKSUM=acf2b6bfff3dc3de8e33a5b08af5fc959a01bddc&#39;]</div>
<div>Thread-497::DEBUG::2013-10-15 17:17:11,184::persistentDict::175::Storage.PersistentDict::(transaction) Finished transaction</div><div>Thread-497::INFO::2013-10-15 17:17:11,184::clusterlock::174::SANLock::(acquireHostId) Acquiring host id for domain 636abf41-5133-4252-9aef-8a32ec10668e (id: 250)</div>
<div>Thread-498::DEBUG::2013-10-15 17:17:11,697::task::579::TaskManager.Task::(_updateState) Task=`7f1a4313-262f-4458-96af-8e5a0d3626d1`::moving from state init -&gt; state preparing</div><div>Thread-498::INFO::2013-10-15 17:17:11,698::logUtils::44::dispatcher::(wrapper) Run and protect: repoStats(options=None)</div>
<div>Thread-498::INFO::2013-10-15 17:17:11,698::logUtils::47::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}</div><div>Thread-498::DEBUG::2013-10-15 17:17:11,698::task::1168::TaskManager.Task::(prepare) Task=`7f1a4313-262f-4458-96af-8e5a0d3626d1`::finished: {}</div>
<div>Thread-498::DEBUG::2013-10-15 17:17:11,698::task::579::TaskManager.Task::(_updateState) Task=`7f1a4313-262f-4458-96af-8e5a0d3626d1`::moving from state preparing -&gt; state finished</div><div>Thread-498::DEBUG::2013-10-15 17:17:11,698::resourceManager::939::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}</div>
<div>Thread-498::DEBUG::2013-10-15 17:17:11,698::resourceManager::976::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}</div><div>Thread-498::DEBUG::2013-10-15 17:17:11,699::task::974::TaskManager.Task::(_decref) Task=`7f1a4313-262f-4458-96af-8e5a0d3626d1`::ref 0 aborting False</div>
<div>Thread-497::ERROR::2013-10-15 17:17:12,186::task::850::TaskManager.Task::(_setError) Task=`e53f26ec-66b9-4f01-aa10-053a738780b8`::Unexpected error</div><div>Traceback (most recent call last):</div><div>  File &quot;/usr/share/vdsm/storage/task.py&quot;, line 857, in _run</div>
<div>    return fn(*args, **kargs)</div><div>  File &quot;/usr/share/vdsm/logUtils.py&quot;, line 45, in wrapper</div><div>    res = f(*args, **kwargs)</div><div>  File &quot;/usr/share/vdsm/storage/hsm.py&quot;, line 960, in createStoragePool</div>
<div>    masterVersion, leaseParams)</div><div>  File &quot;/usr/share/vdsm/storage/sp.py&quot;, line 617, in create</div><div>    self._acquireTemporaryClusterLock(msdUUID, leaseParams)</div><div>  File &quot;/usr/share/vdsm/storage/sp.py&quot;, line 559, in _acquireTemporaryClusterLock</div>
<div>    msd.acquireHostId(<a href="http://self.id">self.id</a>)</div><div>  File &quot;/usr/share/vdsm/storage/sd.py&quot;, line 458, in acquireHostId</div><div>    self._clusterLock.acquireHostId(hostId, async)</div><div>
  File &quot;/usr/share/vdsm/storage/clusterlock.py&quot;, line 189, in acquireHostId</div><div>    raise se.AcquireHostIdFailure(self._sdUUID, e)</div><div>AcquireHostIdFailure: Cannot acquire host id: (&#39;636abf41-5133-4252-9aef-8a32ec10668e&#39;, SanlockException(22, &#39;Sanlock lockspace add failure&#39;, &#39;Invalid argument&#39;))</div>
<div>Thread-497::DEBUG::2013-10-15 17:17:12,186::task::869::TaskManager.Task::(_run) Task=`e53f26ec-66b9-4f01-aa10-053a738780b8`::Task._run: e53f26ec-66b9-4f01-aa10-053a738780b8 (None, &#39;5849b030-626e-47cb-ad90-3ce782d831b3&#39;, &#39;Default&#39;, &#39;636abf41-5133-4252-9aef-8a32ec10668e&#39;, [&#39;636abf41-5133-4252-9aef-8a32ec10668e&#39;], 12, None, 5, 60, 10, 3) {} failed - stopping task</div>
<div>Thread-497::DEBUG::2013-10-15 17:17:12,187::task::1194::TaskManager.Task::(stop) Task=`e53f26ec-66b9-4f01-aa10-053a738780b8`::stopping in state preparing (force False)</div><div>Thread-497::DEBUG::2013-10-15 17:17:12,187::task::974::TaskManager.Task::(_decref) Task=`e53f26ec-66b9-4f01-aa10-053a738780b8`::ref 1 aborting True</div>
<div>Thread-497::INFO::2013-10-15 17:17:12,187::task::1151::TaskManager.Task::(prepare) Task=`e53f26ec-66b9-4f01-aa10-053a738780b8`::aborting: Task is aborted: &#39;Cannot acquire host id&#39; - code 661</div><div>Thread-497::DEBUG::2013-10-15 17:17:12,187::task::1156::TaskManager.Task::(prepare) Task=`e53f26ec-66b9-4f01-aa10-053a738780b8`::Prepare: aborted: Cannot acquire host id</div>
<div>Thread-497::DEBUG::2013-10-15 17:17:12,187::task::974::TaskManager.Task::(_decref) Task=`e53f26ec-66b9-4f01-aa10-053a738780b8`::ref 0 aborting True</div><div>Thread-497::DEBUG::2013-10-15 17:17:12,187::task::909::TaskManager.Task::(_doAbort) Task=`e53f26ec-66b9-4f01-aa10-053a738780b8`::Task._doAbort: force False</div>
<div>Thread-497::DEBUG::2013-10-15 17:17:12,188::resourceManager::976::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}</div><div>Thread-497::DEBUG::2013-10-15 17:17:12,188::task::579::TaskManager.Task::(_updateState) Task=`e53f26ec-66b9-4f01-aa10-053a738780b8`::moving from state preparing -&gt; state aborting</div>
<div>Thread-497::DEBUG::2013-10-15 17:17:12,188::task::534::TaskManager.Task::(__state_aborting) Task=`e53f26ec-66b9-4f01-aa10-053a738780b8`::_aborting: recover policy none</div><div>Thread-497::DEBUG::2013-10-15 17:17:12,188::task::579::TaskManager.Task::(_updateState) Task=`e53f26ec-66b9-4f01-aa10-053a738780b8`::moving from state aborting -&gt; state failed</div>
<div>Thread-497::DEBUG::2013-10-15 17:17:12,188::resourceManager::939::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {&#39;Storage.5849b030-626e-47cb-ad90-3ce782d831b3&#39;: &lt; ResourceRef &#39;Storage.5849b030-626e-47cb-ad90-3ce782d831b3&#39;, isValid: &#39;True&#39; obj: &#39;None&#39;&gt;, &#39;Storage.636abf41-5133-4252-9aef-8a32ec10668e&#39;: &lt; ResourceRef &#39;Storage.636abf41-5133-4252-9aef-8a32ec10668e&#39;, isValid: &#39;True&#39; obj: &#39;None&#39;&gt;}</div>
<div>Thread-497::DEBUG::2013-10-15 17:17:12,188::resourceManager::976::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}</div><div>Thread-497::DEBUG::2013-10-15 17:17:12,189::resourceManager::615::ResourceManager::(releaseResource) Trying to release resource &#39;Storage.5849b030-626e-47cb-ad90-3ce782d831b3&#39;</div>
<div>Thread-497::DEBUG::2013-10-15 17:17:12,189::resourceManager::634::ResourceManager::(releaseResource) Released resource &#39;Storage.5849b030-626e-47cb-ad90-3ce782d831b3&#39; (0 active users)</div><div>Thread-497::DEBUG::2013-10-15 17:17:12,189::resourceManager::640::ResourceManager::(releaseResource) Resource &#39;Storage.5849b030-626e-47cb-ad90-3ce782d831b3&#39; is free, finding out if anyone is waiting for it.</div>
<div>Thread-497::DEBUG::2013-10-15 17:17:12,189::resourceManager::648::ResourceManager::(releaseResource) No one is waiting for resource &#39;Storage.5849b030-626e-47cb-ad90-3ce782d831b3&#39;, Clearing records.</div><div>
Thread-497::DEBUG::2013-10-15 17:17:12,189::resourceManager::615::ResourceManager::(releaseResource) Trying to release resource &#39;Storage.636abf41-5133-4252-9aef-8a32ec10668e&#39;</div><div>Thread-497::DEBUG::2013-10-15 17:17:12,190::resourceManager::634::ResourceManager::(releaseResource) Released resource &#39;Storage.636abf41-5133-4252-9aef-8a32ec10668e&#39; (0 active users)</div>
<div>Thread-497::DEBUG::2013-10-15 17:17:12,190::resourceManager::640::ResourceManager::(releaseResource) Resource &#39;Storage.636abf41-5133-4252-9aef-8a32ec10668e&#39; is free, finding out if anyone is waiting for it.</div>
<div>Thread-497::DEBUG::2013-10-15 17:17:12,190::resourceManager::648::ResourceManager::(releaseResource) No one is waiting for resource &#39;Storage.636abf41-5133-4252-9aef-8a32ec10668e&#39;, Clearing records.</div><div>
Thread-497::ERROR::2013-10-15 17:17:12,190::dispatcher::67::Storage.Dispatcher.Protect::(run) {&#39;status&#39;: {&#39;message&#39;: &quot;Cannot acquire host id: (&#39;636abf41-5133-4252-9aef-8a32ec10668e&#39;, SanlockException(22, &#39;Sanlock lockspace add failure&#39;, &#39;Invalid argument&#39;))&quot;, &#39;code&#39;: 661}}</div>
<div>Thread-504::DEBUG::2013-10-15 17:17:27,018::task::579::TaskManager.Task::(_updateState) Task=`4df542ea-0b2a-489a-b3d7-9299841ca2d6`::moving from state init -&gt; state preparing</div><div>Thread-504::INFO::2013-10-15 17:17:27,019::logUtils::44::dispatcher::(wrapper) Run and protect: repoStats(options=None)</div>
<div>Thread-504::INFO::2013-10-15 17:17:27,019::logUtils::47::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}</div><div>Thread-504::DEBUG::2013-10-15 17:17:27,019::task::1168::TaskManager.Task::(prepare) Task=`4df542ea-0b2a-489a-b3d7-9299841ca2d6`::finished: {}</div>
<div>Thread-504::DEBUG::2013-10-15 17:17:27,019::task::579::TaskManager.Task::(_updateState) Task=`4df542ea-0b2a-489a-b3d7-9299841ca2d6`::moving from state preparing -&gt; state finished</div><div>Thread-504::DEBUG::2013-10-15 17:17:27,019::resourceManager::939::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}</div>
<div>Thread-504::DEBUG::2013-10-15 17:17:27,019::resourceManager::976::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}</div><div>Thread-504::DEBUG::2013-10-15 17:17:27,019::task::974::TaskManager.Task::(_decref) Task=`4df542ea-0b2a-489a-b3d7-9299841ca2d6`::ref 0 aborting False</div>
<div>Thread-510::DEBUG::2013-10-15 17:17:42,194::task::579::TaskManager.Task::(_updateState) Task=`03b081b7-430b-4207-9272-ba5785f63cb1`::moving from state init -&gt; state preparing</div><div>Thread-510::INFO::2013-10-15 17:17:42,194::logUtils::44::dispatcher::(wrapper) Run and protect: repoStats(options=None)</div>
<div>Thread-510::INFO::2013-10-15 17:17:42,194::logUtils::47::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}</div><div>Thread-510::DEBUG::2013-10-15 17:17:42,195::task::1168::TaskManager.Task::(prepare) Task=`03b081b7-430b-4207-9272-ba5785f63cb1`::finished: {}</div>
<div>Thread-510::DEBUG::2013-10-15 17:17:42,195::task::579::TaskManager.Task::(_updateState) Task=`03b081b7-430b-4207-9272-ba5785f63cb1`::moving from state preparing -&gt; state finished</div><div>Thread-510::DEBUG::2013-10-15 17:17:42,195::resourceManager::939::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}</div>
<div>Thread-510::DEBUG::2013-10-15 17:17:42,195::resourceManager::976::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}</div><div>Thread-510::DEBUG::2013-10-15 17:17:42,195::task::974::TaskManager.Task::(_decref) Task=`03b081b7-430b-4207-9272-ba5785f63cb1`::ref 0 aborting False</div>
<div>Thread-516::DEBUG::2013-10-15 17:17:57,328::task::579::TaskManager.Task::(_updateState) Task=`535b1567-e5de-4a2d-9a34-080f3cea5de6`::moving from state init -&gt; state preparing</div><div>Thread-516::INFO::2013-10-15 17:17:57,328::logUtils::44::dispatcher::(wrapper) Run and protect: repoStats(options=None)</div>
<div>Thread-516::INFO::2013-10-15 17:17:57,328::logUtils::47::dispatcher::(wrapper) Run and protect: repoStats, Return response: {}</div><div>Thread-516::DEBUG::2013-10-15 17:17:57,328::task::1168::TaskManager.Task::(prepare) Task=`535b1567-e5de-4a2d-9a34-080f3cea5de6`::finished: {}</div>
<div>Thread-516::DEBUG::2013-10-15 17:17:57,329::task::579::TaskManager.Task::(_updateState) Task=`535b1567-e5de-4a2d-9a34-080f3cea5de6`::moving from state preparing -&gt; state finished</div><div>Thread-516::DEBUG::2013-10-15 17:17:57,329::resourceManager::939::ResourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {}</div>
<div>Thread-516::DEBUG::2013-10-15 17:17:57,329::resourceManager::976::ResourceManager.Owner::(cancelAll) Owner.cancelAll requests {}</div><div>Thread-516::DEBUG::2013-10-15 17:17:57,329::task::974::TaskManager.Task::(_decref) Task=`535b1567-e5de-4a2d-9a34-080f3cea5de6`::ref 0 aborting False</div>
</div><div><br></div><div><br></div><div><br></div><div><br></div><div><br></div></div><div class="gmail_extra"><br clear="all"><div><div dir="ltr"><span style="font-family:arial,sans-serif;font-size:16px"><strong>Steve Dainard </strong></span><span style="font-size:12px"></span><br>

<span style="font-family:arial,sans-serif;font-size:12px">IT Infrastructure Manager<br>
<a href="http://miovision.com/" target="_blank">Miovision</a> | <em>Rethink Traffic</em><br>
519-513-2407 ex.250<br>
877-646-8476 (toll-free)<br>
<br>
<strong style="font-family:arial,sans-serif;font-size:13px;color:#999999"><a href="http://miovision.com/blog" target="_blank">Blog</a>  |  </strong><font color="#999999" style="font-family:arial,sans-serif;font-size:13px"><strong><a href="https://www.linkedin.com/company/miovision-technologies" target="_blank">LinkedIn</a>  |  <a href="https://twitter.com/miovision" target="_blank">Twitter</a>  |  <a href="https://www.facebook.com/miovision" target="_blank">Facebook</a></strong></font> </span>
<hr style="font-family:arial,sans-serif;font-size:13px;color:#333333;clear:both">
<div style="color:#999999;font-family:arial,sans-serif;font-size:13px;padding-top:5px">
        <span style="font-family:arial,sans-serif;font-size:12px">Miovision Technologies Inc. | 148 Manitou Drive, Suite 101, Kitchener, ON, Canada | N2C 1L3</span><br>
        <span style="font-family:arial,sans-serif;font-size:12px">This e-mail may contain information that is privileged or confidential. If you are not the intended recipient, please delete the e-mail and any attachments and notify us immediately.</span></div>
</div></div>
<br><br><div class="gmail_quote">On Thu, Oct 3, 2013 at 8:02 AM, Allon Mureinik <span dir="ltr">&lt;<a href="mailto:amureini@redhat.com" target="_blank">amureini@redhat.com</a>&gt;</span> wrote:<br><blockquote class="gmail_quote" style="margin:0 0 0 .8ex;border-left:1px #ccc solid;padding-left:1ex">
The root cause seems to be a SANlock issue:<br>
Thread-32382::ERROR:: 2013-09-20 13:16:34,126::clusterlock::145::initSANLock: initSANLock) Cannot initialize SANLock for domain 17d21ac7-5859-4f25-8de7-2a9433d50c11<br>
<div class="im">Traceback (most recent call last):<br>
File &quot;/usr/share/vdsm/storage/clusterlock.py&quot;, line 140, in initSANLock<br>
sanlock.init_lockspace(sdUUID, idsPath)<br>
SanlockException: (22, &#39;Sanlock lockspace init failure&#39;, &#39;Invalid argument&#39;)<br>
</div>Thread-32382::WARNING:: 2013-09-20 13:16:34,127::sd::428::Storage.StorageDomain: initSPMlease) lease did not initialize successfully<br>
<div class="im">Traceback (most recent call last):<br>
File &quot;/usr/share/vdsm/storage/sd.py&quot;, line 423, in initSPMlease<br>
self._clusterLock.initLock()<br>
File &quot;/usr/share/vdsm/storage/clusterlock.py&quot;, line 163, in initLock<br>
initSANLock(self._sdUUID, self._idsPath, self._leasesPath)<br>
File &quot;/usr/share/vdsm/storage/clusterlock.py&quot;, line 146, in initSANLock<br>
raise se.ClusterLockInitError()<br>
ClusterLockInitError: Could not initialize cluster lock: ()<br>
<br>
</div>Can you include /var/log/sanlock.log and /var/log/messages please?<br>
<br>
----- Original Message -----<br>
<br>
&gt; From: &quot;Steve Dainard&quot; &lt;<a href="mailto:sdainard@miovision.com">sdainard@miovision.com</a>&gt;<br>
&gt; To: &quot;Deepak C Shetty&quot; &lt;<a href="mailto:deepakcs@linux.vnet.ibm.com">deepakcs@linux.vnet.ibm.com</a>&gt;<br>
&gt; Cc: &quot;users&quot; &lt;<a href="mailto:users@ovirt.org">users@ovirt.org</a>&gt;<br>
&gt; Sent: Friday, September 20, 2013 8:30:59 PM<br>
&gt; Subject: Re: [Users] Ovirt 3.3 Fedora 19 add gluster storage permissions<br>
&gt; error<br>
<div class="HOEnZb"><div class="h5"><br>
&gt; Awesome, thanks guys. Its weird that that article tells you to set with<br>
&gt; &#39;key=value&#39; rather than &#39;key value&#39; must be some legacy stuff.<br>
<br>
&gt; Once those changes are in place I hit a different error. Deepak, maybe you&#39;ve<br>
&gt; seen this one on new storage domain add:<br>
<br>
&gt; [root@ovirt-manager2 ~]# tail -f /var/log/ovirt-engine/engine.log<br>
&gt; 2013-09-20 13:16:36,226 ERROR<br>
&gt; [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStoragePoolVDSCommand]<br>
&gt; (ajp--127.0.0.1-8702-9) Command CreateStoragePoolVDS execution failed.<br>
&gt; Exception: VDSErrorException: VDSGenericException: VDSErrorException: Failed<br>
&gt; to CreateStoragePoolVDS, error = Cannot acquire host id:<br>
&gt; (&#39;17d21ac7-5859-4f25-8de7-2a9433d50c11&#39;, SanlockException(22, &#39;Sanlock<br>
&gt; lockspace add failure&#39;, &#39;Invalid argument&#39;))<br>
&gt; 2013-09-20 13:16:36,229 INFO<br>
&gt; [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStoragePoolVDSCommand]<br>
&gt; (ajp--127.0.0.1-8702-9) FINISH, CreateStoragePoolVDSCommand, log id:<br>
&gt; 672635cc<br>
&gt; 2013-09-20 13:16:36,231 ERROR<br>
&gt; [org.ovirt.engine.core.bll.storage.AddStoragePoolWithStoragesCommand]<br>
&gt; (ajp--127.0.0.1-8702-9) Command<br>
&gt; org.ovirt.engine.core.bll.storage.AddStoragePoolWithStoragesCommand throw<br>
&gt; Vdc Bll exception. With error message VdcBLLException:<br>
&gt; org.ovirt.engine.core.vdsbroker.vdsbroker.VDSErrorException:<br>
&gt; VDSGenericException: VDSErrorException: Failed to CreateStoragePoolVDS,<br>
&gt; error = Cannot acquire host id: (&#39;17d21ac7-5859-4f25-8de7-2a9433d50c11&#39;,<br>
&gt; SanlockException(22, &#39;Sanlock lockspace add failure&#39;, &#39;Invalid argument&#39;))<br>
&gt; (Failed with VDSM error AcquireHostIdFailure and code 661)<br>
&gt; 2013-09-20 13:16:36,296 INFO<br>
&gt; [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]<br>
&gt; (ajp--127.0.0.1-8702-9) Correlation ID: 11070337, Call Stack: null, Custom<br>
&gt; Event ID: -1, Message: Failed to attach Storage Domains to Data Center<br>
&gt; Default. (User: admin@internal)<br>
&gt; 2013-09-20 13:16:36,299 INFO<br>
&gt; [org.ovirt.engine.core.bll.storage.AddStoragePoolWithStoragesCommand]<br>
&gt; (ajp--127.0.0.1-8702-9) Lock freed to object EngineLock [exclusiveLocks=<br>
&gt; key: 5849b030-626e-47cb-ad90-3ce782d831b3 value: POOL<br>
&gt; , sharedLocks= ]<br>
&gt; 2013-09-20 13:16:36,387 INFO<br>
&gt; [org.ovirt.engine.core.bll.storage.AttachStorageDomainToPoolCommand]<br>
&gt; (ajp--127.0.0.1-8702-9) Command [id=293a1e97-e949-4c17-92c6-c01f2221204e]:<br>
&gt; Compensating CHANGED_ENTITY of<br>
&gt; org.ovirt.engine.core.common.businessentities.StoragePool; snapshot:<br>
&gt; id=5849b030-626e-47cb-ad90-3ce782d831b3.<br>
&gt; 2013-09-20 13:16:36,398 INFO<br>
&gt; [org.ovirt.engine.core.bll.storage.AttachStorageDomainToPoolCommand]<br>
&gt; (ajp--127.0.0.1-8702-9) Command [id=293a1e97-e949-4c17-92c6-c01f2221204e]:<br>
&gt; Compensating NEW_ENTITY_ID of<br>
&gt; org.ovirt.engine.core.common.businessentities.StoragePoolIsoMap; snapshot:<br>
&gt; storagePoolId = 5849b030-626e-47cb-ad90-3ce782d831b3, storageId =<br>
&gt; 17d21ac7-5859-4f25-8de7-2a9433d50c11.<br>
&gt; 2013-09-20 13:16:36,425 INFO<br>
&gt; [org.ovirt.engine.core.bll.storage.AttachStorageDomainToPoolCommand]<br>
&gt; (ajp--127.0.0.1-8702-9) Command [id=293a1e97-e949-4c17-92c6-c01f2221204e]:<br>
&gt; Compensating CHANGED_ENTITY of<br>
&gt; org.ovirt.engine.core.common.businessentities.StorageDomainStatic; snapshot:<br>
&gt; id=17d21ac7-5859-4f25-8de7-2a9433d50c11.<br>
&gt; 2013-09-20 13:16:36,464 INFO<br>
&gt; [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]<br>
&gt; (ajp--127.0.0.1-8702-9) Correlation ID: 302ae6eb, Job ID:<br>
&gt; 014ec59b-e6d7-4e5e-b588-4fb0dfa8f1c8, Call Stack: null, Custom Event ID: -1,<br>
&gt; Message: Failed to attach Storage Domain rep2-virt to Data Center Default.<br>
&gt; (User: admin@internal)<br>
<br>
&gt; [root@ovirt001 ~]# tail -f /var/log/vdsm/vdsm.log<br>
&gt; Thread-32374::DEBUG::2013-09-20<br>
&gt; 13:16:18,107::task::579::TaskManager.Task::(_updateState)<br>
&gt; Task=`f4cab975-d5fa-463a-990e-ab32686c6806`::moving from state init -&gt; state<br>
&gt; preparing<br>
&gt; Thread-32374::INFO::2013-09-20<br>
&gt; 13:16:18,107::logUtils::44::dispatcher::(wrapper) Run and protect:<br>
&gt; repoStats(options=None)<br>
&gt; Thread-32374::INFO::2013-09-20<br>
&gt; 13:16:18,107::logUtils::47::dispatcher::(wrapper) Run and protect:<br>
&gt; repoStats, Return response: {}<br>
&gt; Thread-32374::DEBUG::2013-09-20<br>
&gt; 13:16:18,108::task::1168::TaskManager.Task::(prepare)<br>
&gt; Task=`f4cab975-d5fa-463a-990e-ab32686c6806`::finished: {}<br>
&gt; Thread-32374::DEBUG::2013-09-20<br>
&gt; 13:16:18,108::task::579::TaskManager.Task::(_updateState)<br>
&gt; Task=`f4cab975-d5fa-463a-990e-ab32686c6806`::moving from state preparing -&gt;<br>
&gt; state finished<br>
&gt; Thread-32374::DEBUG::2013-09-20<br>
&gt; 13:16:18,108::resourceManager::939::ResourceManager.Owner::(releaseAll)<br>
&gt; Owner.releaseAll requests {} resources {}<br>
&gt; Thread-32374::DEBUG::2013-09-20<br>
&gt; 13:16:18,108::resourceManager::976::ResourceManager.Owner::(cancelAll)<br>
&gt; Owner.cancelAll requests {}<br>
&gt; Thread-32374::DEBUG::2013-09-20<br>
&gt; 13:16:18,108::task::974::TaskManager.Task::(_decref)<br>
&gt; Task=`f4cab975-d5fa-463a-990e-ab32686c6806`::ref 0 aborting False<br>
&gt; Thread-32379::DEBUG::2013-09-20<br>
&gt; 13:16:29,509::BindingXMLRPC::177::vds::(wrapper) client [10.0.0.34]<br>
&gt; Thread-32379::DEBUG::2013-09-20<br>
&gt; 13:16:29,510::task::579::TaskManager.Task::(_updateState)<br>
&gt; Task=`1ad55ba1-afaa-4524-b3fa-3d55a421e8bc`::moving from state init -&gt; state<br>
&gt; preparing<br>
&gt; Thread-32379::INFO::2013-09-20<br>
&gt; 13:16:29,510::logUtils::44::dispatcher::(wrapper) Run and protect:<br>
&gt; connectStorageServer(domType=7,<br>
&gt; spUUID=&#39;00000000-0000-0000-0000-000000000000&#39;, conList=[{&#39;port&#39;: &#39;&#39;,<br>
&gt; &#39;connection&#39;: &#39;192.168.1.1:rep2-virt&#39;, &#39;iqn&#39;: &#39;&#39;, &#39;portal&#39;: &#39;&#39;, &#39;user&#39;: &#39;&#39;,<br>
&gt; &#39;vfs_type&#39;: &#39;glusterfs&#39;, &#39;password&#39;: &#39;******&#39;, &#39;id&#39;:<br>
&gt; &#39;00000000-0000-0000-0000-000000000000&#39;}], options=None)<br>
&gt; Thread-32379::DEBUG::2013-09-20<br>
&gt; 13:16:29,516::hsm::2333::Storage.HSM::(__prefetchDomains) glusterDomPath:<br>
&gt; glusterSD/*<br>
&gt; Thread-32379::DEBUG::2013-09-20<br>
&gt; 13:16:29,523::hsm::2345::Storage.HSM::(__prefetchDomains) Found SD uuids: ()<br>
&gt; Thread-32379::DEBUG::2013-09-20<br>
&gt; 13:16:29,523::hsm::2396::Storage.HSM::(connectStorageServer) knownSDs:<br>
&gt; {b72b61d1-e11c-496d-ad3a-6f566a1f0ad1: storage.glusterSD.findDomain,<br>
&gt; 983c4aa1-7b00-4d3b-b6ad-1fd2cf9297ce: storage.glusterSD.findDomain,<br>
&gt; b91afb39-f96e-4eb3-bc6c-9f08fa16869c: storage.glusterSD.findDomain}<br>
&gt; Thread-32379::INFO::2013-09-20<br>
&gt; 13:16:29,523::logUtils::47::dispatcher::(wrapper) Run and protect:<br>
&gt; connectStorageServer, Return response: {&#39;statuslist&#39;: [{&#39;status&#39;: 0, &#39;id&#39;:<br>
&gt; &#39;00000000-0000-0000-0000-000000000000&#39;}]}<br>
&gt; Thread-32379::DEBUG::2013-09-20<br>
&gt; 13:16:29,523::task::1168::TaskManager.Task::(prepare)<br>
&gt; Task=`1ad55ba1-afaa-4524-b3fa-3d55a421e8bc`::finished: {&#39;statuslist&#39;:<br>
&gt; [{&#39;status&#39;: 0, &#39;id&#39;: &#39;00000000-0000-0000-0000-000000000000&#39;}]}<br>
&gt; Thread-32379::DEBUG::2013-09-20<br>
&gt; 13:16:29,524::task::579::TaskManager.Task::(_updateState)<br>
&gt; Task=`1ad55ba1-afaa-4524-b3fa-3d55a421e8bc`::moving from state preparing -&gt;<br>
&gt; state finished<br>
&gt; Thread-32379::DEBUG::2013-09-20<br>
&gt; 13:16:29,524::resourceManager::939::ResourceManager.Owner::(releaseAll)<br>
&gt; Owner.releaseAll requests {} resources {}<br>
&gt; Thread-32379::DEBUG::2013-09-20<br>
&gt; 13:16:29,524::resourceManager::976::ResourceManager.Owner::(cancelAll)<br>
&gt; Owner.cancelAll requests {}<br>
&gt; Thread-32379::DEBUG::2013-09-20<br>
&gt; 13:16:29,524::task::974::TaskManager.Task::(_decref)<br>
&gt; Task=`1ad55ba1-afaa-4524-b3fa-3d55a421e8bc`::ref 0 aborting False<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:29,888::BindingXMLRPC::177::vds::(wrapper) client [10.0.0.34]<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:29,888::task::579::TaskManager.Task::(_updateState)<br>
&gt; Task=`a3ba925b-65ee-42a6-8506-927a06f63995`::moving from state init -&gt; state<br>
&gt; preparing<br>
&gt; Thread-32382::INFO::2013-09-20<br>
&gt; 13:16:29,889::logUtils::44::dispatcher::(wrapper) Run and protect:<br>
&gt; createStorageDomain(storageType=7,<br>
&gt; sdUUID=&#39;17d21ac7-5859-4f25-8de7-2a9433d50c11&#39;, domainName=&#39;rep2-virt&#39;,<br>
&gt; typeSpecificArg=&#39;192.168.1.1:rep2-virt&#39;, domClass=1, domVersion=&#39;3&#39;,<br>
&gt; options=None)<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:29,889::misc::807::SamplingMethod::(__call__) Trying to enter sampling<br>
&gt; method (storage.sdc.refreshStorage)<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:29,889::misc::809::SamplingMethod::(__call__) Got in to sampling<br>
&gt; method<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:29,889::misc::807::SamplingMethod::(__call__) Trying to enter sampling<br>
&gt; method (storage.iscsi.rescan)<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:29,889::misc::809::SamplingMethod::(__call__) Got in to sampling<br>
&gt; method<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:29,889::iscsiadm::91::Storage.Misc.excCmd::(_runCmd) &#39;/usr/bin/sudo -n<br>
&gt; /sbin/iscsiadm -m session -R&#39; (cwd None)<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:29,904::iscsiadm::91::Storage.Misc.excCmd::(_runCmd) FAILED: &lt;err&gt; =<br>
&gt; &#39;iscsiadm: No session found.\n&#39;; &lt;rc&gt; = 21<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:29,904::misc::817::SamplingMethod::(__call__) Returning last result<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:32,931::multipath::111::Storage.Misc.excCmd::(rescan) &#39;/usr/bin/sudo<br>
&gt; -n /sbin/multipath&#39; (cwd None)<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:33,255::multipath::111::Storage.Misc.excCmd::(rescan) SUCCESS: &lt;err&gt; =<br>
&gt; &#39;&#39;; &lt;rc&gt; = 0<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:33,256::lvm::483::OperationMutex::(_invalidateAllPvs) Operation &#39;lvm<br>
&gt; invalidate operation&#39; got the operation mutex<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:33,256::lvm::485::OperationMutex::(_invalidateAllPvs) Operation &#39;lvm<br>
&gt; invalidate operation&#39; released the operation mutex<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:33,256::lvm::494::OperationMutex::(_invalidateAllVgs) Operation &#39;lvm<br>
&gt; invalidate operation&#39; got the operation mutex<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:33,256::lvm::496::OperationMutex::(_invalidateAllVgs) Operation &#39;lvm<br>
&gt; invalidate operation&#39; released the operation mutex<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:33,257::lvm::514::OperationMutex::(_invalidateAllLvs) Operation &#39;lvm<br>
&gt; invalidate operation&#39; got the operation mutex<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:33,257::lvm::516::OperationMutex::(_invalidateAllLvs) Operation &#39;lvm<br>
&gt; invalidate operation&#39; released the operation mutex<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:33,257::misc::817::SamplingMethod::(__call__) Returning last result<br>
&gt; Thread-32382::ERROR::2013-09-20<br>
&gt; 13:16:33,257::sdc::137::Storage.StorageDomainCache::(_findDomain) looking<br>
&gt; for unfetched domain 17d21ac7-5859-4f25-8de7-2a9433d50c11<br>
&gt; Thread-32382::ERROR::2013-09-20<br>
&gt; 13:16:33,257::sdc::154::Storage.StorageDomainCache::(_findUnfetchedDomain)<br>
&gt; looking for domain 17d21ac7-5859-4f25-8de7-2a9433d50c11<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:33,258::lvm::374::OperationMutex::(_reloadvgs) Operation &#39;lvm reload<br>
&gt; operation&#39; got the operation mutex<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:33,259::lvm::311::Storage.Misc.excCmd::(cmd) &#39;/usr/bin/sudo -n<br>
&gt; /sbin/lvm vgs --config &quot; devices { preferred_names = [\\&quot;^/dev/mapper/\\&quot;]<br>
&gt; ignore_suspended_devices=1 write_cache_state=0 disable_after_error_count=3<br>
&gt; filter = [ \&#39;r|.*|\&#39; ] } global { locking_type=1 prioritise_write_locks=1<br>
&gt; wait_for_locks=1 } backup { retain_min = 50 retain_days = 0 } &quot; --noheadings<br>
&gt; --units b --nosuffix --separator | -o<br>
&gt; uuid,name,attr,size,free,extent_size,extent_count,free_count,tags,vg_mda_size,vg_mda_free<br>
&gt; 17d21ac7-5859-4f25-8de7-2a9433d50c11&#39; (cwd None)<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:33,285::lvm::311::Storage.Misc.excCmd::(cmd) FAILED: &lt;err&gt; = &#39; Volume<br>
&gt; group &quot;17d21ac7-5859-4f25-8de7-2a9433d50c11&quot; not found\n&#39;; &lt;rc&gt; = 5<br>
&gt; Thread-32382::WARNING::2013-09-20<br>
&gt; 13:16:33,286::lvm::379::Storage.LVM::(_reloadvgs) lvm vgs failed: 5 [] [&#39;<br>
&gt; Volume group &quot;17d21ac7-5859-4f25-8de7-2a9433d50c11&quot; not found&#39;]<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:33,286::lvm::403::OperationMutex::(_reloadvgs) Operation &#39;lvm reload<br>
&gt; operation&#39; released the operation mutex<br>
&gt; Thread-32382::ERROR::2013-09-20<br>
&gt; 13:16:33,295::sdc::143::Storage.StorageDomainCache::(_findDomain) domain<br>
&gt; 17d21ac7-5859-4f25-8de7-2a9433d50c11 not found<br>
&gt; Traceback (most recent call last):<br>
&gt; File &quot;/usr/share/vdsm/storage/sdc.py&quot;, line 141, in _findDomain<br>
&gt; dom = findMethod(sdUUID)<br>
&gt; File &quot;/usr/share/vdsm/storage/sdc.py&quot;, line 171, in _findUnfetchedDomain<br>
&gt; raise se.StorageDomainDoesNotExist(sdUUID)<br>
&gt; StorageDomainDoesNotExist: Storage domain does not exist:<br>
&gt; (&#39;17d21ac7-5859-4f25-8de7-2a9433d50c11&#39;,)<br>
&gt; Thread-32382::INFO::2013-09-20<br>
&gt; 13:16:33,295::nfsSD::69::Storage.StorageDomain::(create)<br>
&gt; sdUUID=17d21ac7-5859-4f25-8de7-2a9433d50c11 domainName=rep2-virt<br>
&gt; remotePath=192.168.1.1:rep2-virt domClass=1<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:33,430::persistentDict::192::Storage.PersistentDict::(__init__)<br>
&gt; Created a persistent dict with FileMetadataRW backend<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:33,445::persistentDict::234::Storage.PersistentDict::(refresh) read<br>
&gt; lines (FileMetadataRW)=[]<br>
&gt; Thread-32382::WARNING::2013-09-20<br>
&gt; 13:16:33,446::persistentDict::256::Storage.PersistentDict::(refresh) data<br>
&gt; has no embedded checksum - trust it as it is<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:33,447::persistentDict::167::Storage.PersistentDict::(transaction)<br>
&gt; Starting transaction<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:33,448::persistentDict::173::Storage.PersistentDict::(transaction)<br>
&gt; Flushing changes<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:33,449::persistentDict::299::Storage.PersistentDict::(flush) about to<br>
&gt; write lines (FileMetadataRW)=[&#39;CLASS=Data&#39;, &#39;DESCRIPTION=rep2-virt&#39;,<br>
&gt; &#39;IOOPTIMEOUTSEC=1&#39;, &#39;LEASERETRIES=3&#39;, &#39;LEASETIMESEC=5&#39;, &#39;LOCKPOLICY=&#39;,<br>
&gt; &#39;LOCKRENEWALINTERVALSEC=5&#39;, &#39;POOL_UUID=&#39;,<br>
&gt; &#39;REMOTE_PATH=192.168.1.1:rep2-virt&#39;, &#39;ROLE=Regular&#39;,<br>
&gt; &#39;SDUUID=17d21ac7-5859-4f25-8de7-2a9433d50c11&#39;, &#39;TYPE=GLUSTERFS&#39;,<br>
&gt; &#39;VERSION=3&#39;, &#39;_SHA_CKSUM=2b07fbc8c65e20eef5180ab785016bde543c6746&#39;]<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:33,454::persistentDict::175::Storage.PersistentDict::(transaction)<br>
&gt; Finished transaction<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:33,457::fileSD::153::Storage.StorageDomain::(__init__) Reading domain<br>
&gt; in path<br>
&gt; /rhev/data-center/mnt/glusterSD/192.168.1.1:rep2-virt/17d21ac7-5859-4f25-8de7-2a9433d50c11<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:33,457::persistentDict::192::Storage.PersistentDict::(__init__)<br>
&gt; Created a persistent dict with FileMetadataRW backend<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:33,469::persistentDict::234::Storage.PersistentDict::(refresh) read<br>
&gt; lines (FileMetadataRW)=[&#39;CLASS=Data&#39;, &#39;DESCRIPTION=rep2-virt&#39;,<br>
&gt; &#39;IOOPTIMEOUTSEC=1&#39;, &#39;LEASERETRIES=3&#39;, &#39;LEASETIMESEC=5&#39;, &#39;LOCKPOLICY=&#39;,<br>
&gt; &#39;LOCKRENEWALINTERVALSEC=5&#39;, &#39;POOL_UUID=&#39;,<br>
&gt; &#39;REMOTE_PATH=192.168.1.1:rep2-virt&#39;, &#39;ROLE=Regular&#39;,<br>
&gt; &#39;SDUUID=17d21ac7-5859-4f25-8de7-2a9433d50c11&#39;, &#39;TYPE=GLUSTERFS&#39;,<br>
&gt; &#39;VERSION=3&#39;, &#39;_SHA_CKSUM=2b07fbc8c65e20eef5180ab785016bde543c6746&#39;]<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:33,472::fileSD::535::Storage.StorageDomain::(imageGarbageCollector)<br>
&gt; Removing remnants of deleted images []<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:33,472::resourceManager::420::ResourceManager::(registerNamespace)<br>
&gt; Registering namespace &#39;17d21ac7-5859-4f25-8de7-2a9433d50c11_imageNS&#39;<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:33,473::resourceManager::420::ResourceManager::(registerNamespace)<br>
&gt; Registering namespace &#39;17d21ac7-5859-4f25-8de7-2a9433d50c11_volumeNS&#39;<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:33,473::clusterlock::137::initSANLock::(initSANLock) Initializing<br>
&gt; SANLock for domain 17d21ac7-5859-4f25-8de7-2a9433d50c11<br>
&gt; Thread-32387::DEBUG::2013-09-20<br>
&gt; 13:16:33,717::task::579::TaskManager.Task::(_updateState)<br>
&gt; Task=`0e11c8e5-4e40-4d28-9eaf-129db67b2f4d`::moving from state init -&gt; state<br>
&gt; preparing<br>
&gt; Thread-32387::INFO::2013-09-20<br>
&gt; 13:16:33,718::logUtils::44::dispatcher::(wrapper) Run and protect:<br>
&gt; repoStats(options=None)<br>
&gt; Thread-32387::INFO::2013-09-20<br>
&gt; 13:16:33,718::logUtils::47::dispatcher::(wrapper) Run and protect:<br>
&gt; repoStats, Return response: {}<br>
&gt; Thread-32387::DEBUG::2013-09-20<br>
&gt; 13:16:33,718::task::1168::TaskManager.Task::(prepare)<br>
&gt; Task=`0e11c8e5-4e40-4d28-9eaf-129db67b2f4d`::finished: {}<br>
&gt; Thread-32387::DEBUG::2013-09-20<br>
&gt; 13:16:33,718::task::579::TaskManager.Task::(_updateState)<br>
&gt; Task=`0e11c8e5-4e40-4d28-9eaf-129db67b2f4d`::moving from state preparing -&gt;<br>
&gt; state finished<br>
&gt; Thread-32387::DEBUG::2013-09-20<br>
&gt; 13:16:33,718::resourceManager::939::ResourceManager.Owner::(releaseAll)<br>
&gt; Owner.releaseAll requests {} resources {}<br>
&gt; Thread-32387::DEBUG::2013-09-20<br>
&gt; 13:16:33,718::resourceManager::976::ResourceManager.Owner::(cancelAll)<br>
&gt; Owner.cancelAll requests {}<br>
&gt; Thread-32387::DEBUG::2013-09-20<br>
&gt; 13:16:33,719::task::974::TaskManager.Task::(_decref)<br>
&gt; Task=`0e11c8e5-4e40-4d28-9eaf-129db67b2f4d`::ref 0 aborting False<br>
&gt; Thread-32382::ERROR::2013-09-20<br>
&gt; 13:16:34,126::clusterlock::145::initSANLock::(initSANLock) Cannot initialize<br>
&gt; SANLock for domain 17d21ac7-5859-4f25-8de7-2a9433d50c11<br>
&gt; Traceback (most recent call last):<br>
&gt; File &quot;/usr/share/vdsm/storage/clusterlock.py&quot;, line 140, in initSANLock<br>
&gt; sanlock.init_lockspace(sdUUID, idsPath)<br>
&gt; SanlockException: (22, &#39;Sanlock lockspace init failure&#39;, &#39;Invalid argument&#39;)<br>
&gt; Thread-32382::WARNING::2013-09-20<br>
&gt; 13:16:34,127::sd::428::Storage.StorageDomain::(initSPMlease) lease did not<br>
&gt; initialize successfully<br>
&gt; Traceback (most recent call last):<br>
&gt; File &quot;/usr/share/vdsm/storage/sd.py&quot;, line 423, in initSPMlease<br>
&gt; self._clusterLock.initLock()<br>
&gt; File &quot;/usr/share/vdsm/storage/clusterlock.py&quot;, line 163, in initLock<br>
&gt; initSANLock(self._sdUUID, self._idsPath, self._leasesPath)<br>
&gt; File &quot;/usr/share/vdsm/storage/clusterlock.py&quot;, line 146, in initSANLock<br>
&gt; raise se.ClusterLockInitError()<br>
&gt; ClusterLockInitError: Could not initialize cluster lock: ()<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:34,127::hsm::2624::Storage.HSM::(createStorageDomain) knownSDs:<br>
&gt; {b72b61d1-e11c-496d-ad3a-6f566a1f0ad1: storage.glusterSD.findDomain,<br>
&gt; 983c4aa1-7b00-4d3b-b6ad-1fd2cf9297ce: storage.glusterSD.findDomain,<br>
&gt; b91afb39-f96e-4eb3-bc6c-9f08fa16869c: storage.glusterSD.findDomain,<br>
&gt; 17d21ac7-5859-4f25-8de7-2a9433d50c11: storage.glusterSD.findDomain}<br>
&gt; Thread-32382::INFO::2013-09-20<br>
&gt; 13:16:34,128::logUtils::47::dispatcher::(wrapper) Run and protect:<br>
&gt; createStorageDomain, Return response: None<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:34,128::task::1168::TaskManager.Task::(prepare)<br>
&gt; Task=`a3ba925b-65ee-42a6-8506-927a06f63995`::finished: None<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:34,128::task::579::TaskManager.Task::(_updateState)<br>
&gt; Task=`a3ba925b-65ee-42a6-8506-927a06f63995`::moving from state preparing -&gt;<br>
&gt; state finished<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:34,128::resourceManager::939::ResourceManager.Owner::(releaseAll)<br>
&gt; Owner.releaseAll requests {} resources {}<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:34,128::resourceManager::976::ResourceManager.Owner::(cancelAll)<br>
&gt; Owner.cancelAll requests {}<br>
&gt; Thread-32382::DEBUG::2013-09-20<br>
&gt; 13:16:34,129::task::974::TaskManager.Task::(_decref)<br>
&gt; Task=`a3ba925b-65ee-42a6-8506-927a06f63995`::ref 0 aborting False<br>
&gt; Thread-32389::DEBUG::2013-09-20<br>
&gt; 13:16:34,219::BindingXMLRPC::177::vds::(wrapper) client [10.0.0.34]<br>
&gt; Thread-32389::DEBUG::2013-09-20<br>
&gt; 13:16:34,219::task::579::TaskManager.Task::(_updateState)<br>
&gt; Task=`a0d5d4d6-dcb7-4293-bf8a-cf1e2204f586`::moving from state init -&gt; state<br>
&gt; preparing<br>
&gt; Thread-32389::INFO::2013-09-20<br>
&gt; 13:16:34,220::logUtils::44::dispatcher::(wrapper) Run and protect:<br>
&gt; getStorageDomainStats(sdUUID=&#39;17d21ac7-5859-4f25-8de7-2a9433d50c11&#39;,<br>
&gt; options=None)<br>
&gt; Thread-32389::DEBUG::2013-09-20<br>
&gt; 13:16:34,220::resourceManager::197::ResourceManager.Request::(__init__)<br>
&gt; ResName=`Storage.17d21ac7-5859-4f25-8de7-2a9433d50c11`ReqID=`9f37d808-9ad2-4c06-99ef-449b43049e80`::Request<br>
&gt; was made in &#39;/usr/share/vdsm/storage/hsm.py&#39; line &#39;2784&#39; at<br>
&gt; &#39;getStorageDomainStats&#39;<br>
&gt; Thread-32389::DEBUG::2013-09-20<br>
&gt; 13:16:34,220::resourceManager::541::ResourceManager::(registerResource)<br>
&gt; Trying to register resource &#39;Storage.17d21ac7-5859-4f25-8de7-2a9433d50c11&#39;<br>
&gt; for lock type &#39;shared&#39;<br>
&gt; Thread-32389::DEBUG::2013-09-20<br>
&gt; 13:16:34,221::resourceManager::600::ResourceManager::(registerResource)<br>
&gt; Resource &#39;Storage.17d21ac7-5859-4f25-8de7-2a9433d50c11&#39; is free. Now locking<br>
&gt; as &#39;shared&#39; (1 active user)<br>
&gt; Thread-32389::DEBUG::2013-09-20<br>
&gt; 13:16:34,221::resourceManager::237::ResourceManager.Request::(grant)<br>
&gt; ResName=`Storage.17d21ac7-5859-4f25-8de7-2a9433d50c11`ReqID=`9f37d808-9ad2-4c06-99ef-449b43049e80`::Granted<br>
&gt; request<br>
&gt; Thread-32389::DEBUG::2013-09-20<br>
&gt; 13:16:34,221::task::811::TaskManager.Task::(resourceAcquired)<br>
&gt; Task=`a0d5d4d6-dcb7-4293-bf8a-cf1e2204f586`::_resourcesAcquired:<br>
&gt; Storage.17d21ac7-5859-4f25-8de7-2a9433d50c11 (shared)<br>
&gt; Thread-32389::DEBUG::2013-09-20<br>
&gt; 13:16:34,221::task::974::TaskManager.Task::(_decref)<br>
&gt; Task=`a0d5d4d6-dcb7-4293-bf8a-cf1e2204f586`::ref 1 aborting False<br>
&gt; Thread-32389::INFO::2013-09-20<br>
&gt; 13:16:34,223::logUtils::47::dispatcher::(wrapper) Run and protect:<br>
&gt; getStorageDomainStats, Return response: {&#39;stats&#39;: {&#39;mdasize&#39;: 0,<br>
&gt; &#39;mdathreshold&#39;: True, &#39;mdavalid&#39;: True, &#39;diskfree&#39;: &#39;14182986809344&#39;,<br>
&gt; &#39;disktotal&#39;: &#39;14199600185344&#39;, &#39;mdafree&#39;: 0}}<br>
&gt; Thread-32389::DEBUG::2013-09-20<br>
&gt; 13:16:34,223::task::1168::TaskManager.Task::(prepare)<br>
&gt; Task=`a0d5d4d6-dcb7-4293-bf8a-cf1e2204f586`::finished: {&#39;stats&#39;: {&#39;mdasize&#39;:<br>
&gt; 0, &#39;mdathreshold&#39;: True, &#39;mdavalid&#39;: True, &#39;diskfree&#39;: &#39;14182986809344&#39;,<br>
&gt; &#39;disktotal&#39;: &#39;14199600185344&#39;, &#39;mdafree&#39;: 0}}<br>
&gt; Thread-32389::DEBUG::2013-09-20<br>
&gt; 13:16:34,223::task::579::TaskManager.Task::(_updateState)<br>
&gt; Task=`a0d5d4d6-dcb7-4293-bf8a-cf1e2204f586`::moving from state preparing -&gt;<br>
&gt; state finished<br>
&gt; Thread-32389::DEBUG::2013-09-20<br>
&gt; 13:16:34,223::resourceManager::939::ResourceManager.Owner::(releaseAll)<br>
&gt; Owner.releaseAll requests {} resources<br>
&gt; {&#39;Storage.17d21ac7-5859-4f25-8de7-2a9433d50c11&#39;: &lt; ResourceRef<br>
&gt; &#39;Storage.17d21ac7-5859-4f25-8de7-2a9433d50c11&#39;, isValid: &#39;True&#39; obj:<br>
&gt; &#39;None&#39;&gt;}<br>
&gt; Thread-32389::DEBUG::2013-09-20<br>
&gt; 13:16:34,224::resourceManager::976::ResourceManager.Owner::(cancelAll)<br>
&gt; Owner.cancelAll requests {}<br>
&gt; Thread-32389::DEBUG::2013-09-20<br>
&gt; 13:16:34,224::resourceManager::615::ResourceManager::(releaseResource)<br>
&gt; Trying to release resource &#39;Storage.17d21ac7-5859-4f25-8de7-2a9433d50c11&#39;<br>
&gt; Thread-32389::DEBUG::2013-09-20<br>
&gt; 13:16:34,224::resourceManager::634::ResourceManager::(releaseResource)<br>
&gt; Released resource &#39;Storage.17d21ac7-5859-4f25-8de7-2a9433d50c11&#39; (0 active<br>
&gt; users)<br>
&gt; Thread-32389::DEBUG::2013-09-20<br>
&gt; 13:16:34,224::resourceManager::640::ResourceManager::(releaseResource)<br>
&gt; Resource &#39;Storage.17d21ac7-5859-4f25-8de7-2a9433d50c11&#39; is free, finding out<br>
&gt; if anyone is waiting for it.<br>
&gt; Thread-32389::DEBUG::2013-09-20<br>
&gt; 13:16:34,224::resourceManager::648::ResourceManager::(releaseResource) No<br>
&gt; one is waiting for resource &#39;Storage.17d21ac7-5859-4f25-8de7-2a9433d50c11&#39;,<br>
&gt; Clearing records.<br>
&gt; Thread-32389::DEBUG::2013-09-20<br>
&gt; 13:16:34,225::task::974::TaskManager.Task::(_decref)<br>
&gt; Task=`a0d5d4d6-dcb7-4293-bf8a-cf1e2204f586`::ref 0 aborting False<br>
&gt; Thread-32390::DEBUG::2013-09-20<br>
&gt; 13:16:35,099::BindingXMLRPC::177::vds::(wrapper) client [10.0.0.34]<br>
&gt; Thread-32390::DEBUG::2013-09-20<br>
&gt; 13:16:35,099::task::579::TaskManager.Task::(_updateState)<br>
&gt; Task=`089c5f71-9cbb-4626-8f2d-3ed3547a98cd`::moving from state init -&gt; state<br>
&gt; preparing<br>
&gt; Thread-32390::INFO::2013-09-20<br>
&gt; 13:16:35,099::logUtils::44::dispatcher::(wrapper) Run and protect:<br>
&gt; connectStorageServer(domType=7,<br>
&gt; spUUID=&#39;00000000-0000-0000-0000-000000000000&#39;, conList=[{&#39;port&#39;: &#39;&#39;,<br>
&gt; &#39;connection&#39;: &#39;192.168.1.1:rep2-virt&#39;, &#39;iqn&#39;: &#39;&#39;, &#39;portal&#39;: &#39;&#39;, &#39;user&#39;: &#39;&#39;,<br>
&gt; &#39;vfs_type&#39;: &#39;glusterfs&#39;, &#39;password&#39;: &#39;******&#39;, &#39;id&#39;:<br>
&gt; &#39;cecee482-87e1-4ecc-8bda-0e0ec84d7792&#39;}], options=None)<br>
&gt; Thread-32390::DEBUG::2013-09-20<br>
&gt; 13:16:35,105::hsm::2333::Storage.HSM::(__prefetchDomains) glusterDomPath:<br>
&gt; glusterSD/*<br>
&gt; Thread-32390::DEBUG::2013-09-20<br>
&gt; 13:16:35,112::hsm::2345::Storage.HSM::(__prefetchDomains) Found SD uuids:<br>
&gt; (&#39;17d21ac7-5859-4f25-8de7-2a9433d50c11&#39;,<br>
&gt; &#39;17d21ac7-5859-4f25-8de7-2a9433d50c11&#39;)<br>
&gt; Thread-32390::DEBUG::2013-09-20<br>
&gt; 13:16:35,113::hsm::2396::Storage.HSM::(connectStorageServer) knownSDs:<br>
&gt; {b72b61d1-e11c-496d-ad3a-6f566a1f0ad1: storage.glusterSD.findDomain,<br>
&gt; 983c4aa1-7b00-4d3b-b6ad-1fd2cf9297ce: storage.glusterSD.findDomain,<br>
&gt; b91afb39-f96e-4eb3-bc6c-9f08fa16869c: storage.glusterSD.findDomain,<br>
&gt; 17d21ac7-5859-4f25-8de7-2a9433d50c11: storage.glusterSD.findDomain}<br>
&gt; Thread-32390::INFO::2013-09-20<br>
&gt; 13:16:35,113::logUtils::47::dispatcher::(wrapper) Run and protect:<br>
&gt; connectStorageServer, Return response: {&#39;statuslist&#39;: [{&#39;status&#39;: 0, &#39;id&#39;:<br>
&gt; &#39;cecee482-87e1-4ecc-8bda-0e0ec84d7792&#39;}]}<br>
&gt; Thread-32390::DEBUG::2013-09-20<br>
&gt; 13:16:35,113::task::1168::TaskManager.Task::(prepare)<br>
&gt; Task=`089c5f71-9cbb-4626-8f2d-3ed3547a98cd`::finished: {&#39;statuslist&#39;:<br>
&gt; [{&#39;status&#39;: 0, &#39;id&#39;: &#39;cecee482-87e1-4ecc-8bda-0e0ec84d7792&#39;}]}<br>
&gt; Thread-32390::DEBUG::2013-09-20<br>
&gt; 13:16:35,113::task::579::TaskManager.Task::(_updateState)<br>
&gt; Task=`089c5f71-9cbb-4626-8f2d-3ed3547a98cd`::moving from state preparing -&gt;<br>
&gt; state finished<br>
&gt; Thread-32390::DEBUG::2013-09-20<br>
&gt; 13:16:35,113::resourceManager::939::ResourceManager.Owner::(releaseAll)<br>
&gt; Owner.releaseAll requests {} resources {}<br>
&gt; Thread-32390::DEBUG::2013-09-20<br>
&gt; 13:16:35,114::resourceManager::976::ResourceManager.Owner::(cancelAll)<br>
&gt; Owner.cancelAll requests {}<br>
&gt; Thread-32390::DEBUG::2013-09-20<br>
&gt; 13:16:35,114::task::974::TaskManager.Task::(_decref)<br>
&gt; Task=`089c5f71-9cbb-4626-8f2d-3ed3547a98cd`::ref 0 aborting False<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:35,148::BindingXMLRPC::177::vds::(wrapper) client [10.0.0.34]<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:35,148::task::579::TaskManager.Task::(_updateState)<br>
&gt; Task=`72348d40-8442-4dbf-bc66-1d354da5fc31`::moving from state init -&gt; state<br>
&gt; preparing<br>
&gt; Thread-32393::INFO::2013-09-20<br>
&gt; 13:16:35,148::logUtils::44::dispatcher::(wrapper) Run and protect:<br>
&gt; createStoragePool(poolType=None,<br>
&gt; spUUID=&#39;5849b030-626e-47cb-ad90-3ce782d831b3&#39;, poolName=&#39;Default&#39;,<br>
&gt; masterDom=&#39;17d21ac7-5859-4f25-8de7-2a9433d50c11&#39;,<br>
&gt; domList=[&#39;17d21ac7-5859-4f25-8de7-2a9433d50c11&#39;], masterVersion=9,<br>
&gt; lockPolicy=None, lockRenewalIntervalSec=5, leaseTimeSec=60,<br>
&gt; ioOpTimeoutSec=10, leaseRetries=3, options=None)<br>
&gt; Thread-32393::INFO::2013-09-20<br>
&gt; 13:16:35,149::fileSD::315::Storage.StorageDomain::(validate)<br>
&gt; sdUUID=17d21ac7-5859-4f25-8de7-2a9433d50c11<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:35,161::persistentDict::234::Storage.PersistentDict::(refresh) read<br>
&gt; lines (FileMetadataRW)=[&#39;CLASS=Data&#39;, &#39;DESCRIPTION=rep2-virt&#39;,<br>
&gt; &#39;IOOPTIMEOUTSEC=1&#39;, &#39;LEASERETRIES=3&#39;, &#39;LEASETIMESEC=5&#39;, &#39;LOCKPOLICY=&#39;,<br>
&gt; &#39;LOCKRENEWALINTERVALSEC=5&#39;, &#39;POOL_UUID=&#39;,<br>
&gt; &#39;REMOTE_PATH=192.168.1.1:rep2-virt&#39;, &#39;ROLE=Regular&#39;,<br>
&gt; &#39;SDUUID=17d21ac7-5859-4f25-8de7-2a9433d50c11&#39;, &#39;TYPE=GLUSTERFS&#39;,<br>
&gt; &#39;VERSION=3&#39;, &#39;_SHA_CKSUM=2b07fbc8c65e20eef5180ab785016bde543c6746&#39;]<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:35,162::resourceManager::197::ResourceManager.Request::(__init__)<br>
&gt; ResName=`Storage.5849b030-626e-47cb-ad90-3ce782d831b3`ReqID=`31060ad0-6633-4bbf-a859-b3f0c27af760`::Request<br>
&gt; was made in &#39;/usr/share/vdsm/storage/hsm.py&#39; line &#39;954&#39; at<br>
&gt; &#39;createStoragePool&#39;<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:35,162::resourceManager::541::ResourceManager::(registerResource)<br>
&gt; Trying to register resource &#39;Storage.5849b030-626e-47cb-ad90-3ce782d831b3&#39;<br>
&gt; for lock type &#39;exclusive&#39;<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:35,162::resourceManager::600::ResourceManager::(registerResource)<br>
&gt; Resource &#39;Storage.5849b030-626e-47cb-ad90-3ce782d831b3&#39; is free. Now locking<br>
&gt; as &#39;exclusive&#39; (1 active user)<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:35,162::resourceManager::237::ResourceManager.Request::(grant)<br>
&gt; ResName=`Storage.5849b030-626e-47cb-ad90-3ce782d831b3`ReqID=`31060ad0-6633-4bbf-a859-b3f0c27af760`::Granted<br>
&gt; request<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:35,163::task::811::TaskManager.Task::(resourceAcquired)<br>
&gt; Task=`72348d40-8442-4dbf-bc66-1d354da5fc31`::_resourcesAcquired:<br>
&gt; Storage.5849b030-626e-47cb-ad90-3ce782d831b3 (exclusive)<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:35,163::task::974::TaskManager.Task::(_decref)<br>
&gt; Task=`72348d40-8442-4dbf-bc66-1d354da5fc31`::ref 1 aborting False<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:35,163::resourceManager::197::ResourceManager.Request::(__init__)<br>
&gt; ResName=`Storage.17d21ac7-5859-4f25-8de7-2a9433d50c11`ReqID=`db5f52a0-d455-419c-b8a5-86fc6b695571`::Request<br>
&gt; was made in &#39;/usr/share/vdsm/storage/hsm.py&#39; line &#39;956&#39; at<br>
&gt; &#39;createStoragePool&#39;<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:35,164::resourceManager::541::ResourceManager::(registerResource)<br>
&gt; Trying to register resource &#39;Storage.17d21ac7-5859-4f25-8de7-2a9433d50c11&#39;<br>
&gt; for lock type &#39;exclusive&#39;<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:35,164::resourceManager::600::ResourceManager::(registerResource)<br>
&gt; Resource &#39;Storage.17d21ac7-5859-4f25-8de7-2a9433d50c11&#39; is free. Now locking<br>
&gt; as &#39;exclusive&#39; (1 active user)<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:35,164::resourceManager::237::ResourceManager.Request::(grant)<br>
&gt; ResName=`Storage.17d21ac7-5859-4f25-8de7-2a9433d50c11`ReqID=`db5f52a0-d455-419c-b8a5-86fc6b695571`::Granted<br>
&gt; request<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:35,165::task::811::TaskManager.Task::(resourceAcquired)<br>
&gt; Task=`72348d40-8442-4dbf-bc66-1d354da5fc31`::_resourcesAcquired:<br>
&gt; Storage.17d21ac7-5859-4f25-8de7-2a9433d50c11 (exclusive)<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:35,165::task::974::TaskManager.Task::(_decref)<br>
&gt; Task=`72348d40-8442-4dbf-bc66-1d354da5fc31`::ref 1 aborting False<br>
&gt; Thread-32393::INFO::2013-09-20<br>
&gt; 13:16:35,166::sp::592::Storage.StoragePool::(create)<br>
&gt; spUUID=5849b030-626e-47cb-ad90-3ce782d831b3 poolName=Default<br>
&gt; master_sd=17d21ac7-5859-4f25-8de7-2a9433d50c11<br>
&gt; domList=[&#39;17d21ac7-5859-4f25-8de7-2a9433d50c11&#39;] masterVersion=9<br>
&gt; {&#39;LEASETIMESEC&#39;: 60, &#39;IOOPTIMEOUTSEC&#39;: 10, &#39;LEASERETRIES&#39;: 3,<br>
&gt; &#39;LOCKRENEWALINTERVALSEC&#39;: 5}<br>
&gt; Thread-32393::INFO::2013-09-20<br>
&gt; 13:16:35,166::fileSD::315::Storage.StorageDomain::(validate)<br>
&gt; sdUUID=17d21ac7-5859-4f25-8de7-2a9433d50c11<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:35,177::persistentDict::234::Storage.PersistentDict::(refresh) read<br>
&gt; lines (FileMetadataRW)=[&#39;CLASS=Data&#39;, &#39;DESCRIPTION=rep2-virt&#39;,<br>
&gt; &#39;IOOPTIMEOUTSEC=1&#39;, &#39;LEASERETRIES=3&#39;, &#39;LEASETIMESEC=5&#39;, &#39;LOCKPOLICY=&#39;,<br>
&gt; &#39;LOCKRENEWALINTERVALSEC=5&#39;, &#39;POOL_UUID=&#39;,<br>
&gt; &#39;REMOTE_PATH=192.168.1.1:rep2-virt&#39;, &#39;ROLE=Regular&#39;,<br>
&gt; &#39;SDUUID=17d21ac7-5859-4f25-8de7-2a9433d50c11&#39;, &#39;TYPE=GLUSTERFS&#39;,<br>
&gt; &#39;VERSION=3&#39;, &#39;_SHA_CKSUM=2b07fbc8c65e20eef5180ab785016bde543c6746&#39;]<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:35,188::persistentDict::234::Storage.PersistentDict::(refresh) read<br>
&gt; lines (FileMetadataRW)=[&#39;CLASS=Data&#39;, &#39;DESCRIPTION=rep2-virt&#39;,<br>
&gt; &#39;IOOPTIMEOUTSEC=1&#39;, &#39;LEASERETRIES=3&#39;, &#39;LEASETIMESEC=5&#39;, &#39;LOCKPOLICY=&#39;,<br>
&gt; &#39;LOCKRENEWALINTERVALSEC=5&#39;, &#39;POOL_UUID=&#39;,<br>
&gt; &#39;REMOTE_PATH=192.168.1.1:rep2-virt&#39;, &#39;ROLE=Regular&#39;,<br>
&gt; &#39;SDUUID=17d21ac7-5859-4f25-8de7-2a9433d50c11&#39;, &#39;TYPE=GLUSTERFS&#39;,<br>
&gt; &#39;VERSION=3&#39;, &#39;_SHA_CKSUM=2b07fbc8c65e20eef5180ab785016bde543c6746&#39;]<br>
&gt; Thread-32393::WARNING::2013-09-20<br>
&gt; 13:16:35,189::fileUtils::167::Storage.fileUtils::(createdir) Dir<br>
&gt; /rhev/data-center/5849b030-626e-47cb-ad90-3ce782d831b3 already exists<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:35,189::persistentDict::167::Storage.PersistentDict::(transaction)<br>
&gt; Starting transaction<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:35,189::persistentDict::173::Storage.PersistentDict::(transaction)<br>
&gt; Flushing changes<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:35,189::persistentDict::299::Storage.PersistentDict::(flush) about to<br>
&gt; write lines (FileMetadataRW)=[&#39;CLASS=Data&#39;, &#39;DESCRIPTION=rep2-virt&#39;,<br>
&gt; &#39;IOOPTIMEOUTSEC=10&#39;, &#39;LEASERETRIES=3&#39;, &#39;LEASETIMESEC=60&#39;, &#39;LOCKPOLICY=&#39;,<br>
&gt; &#39;LOCKRENEWALINTERVALSEC=5&#39;, &#39;POOL_UUID=&#39;,<br>
&gt; &#39;REMOTE_PATH=192.168.1.1:rep2-virt&#39;, &#39;ROLE=Regular&#39;,<br>
&gt; &#39;SDUUID=17d21ac7-5859-4f25-8de7-2a9433d50c11&#39;, &#39;TYPE=GLUSTERFS&#39;,<br>
&gt; &#39;VERSION=3&#39;, &#39;_SHA_CKSUM=61b814a65ea3ede1f0ae1d58e139adc06bf9eda5&#39;]<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:35,194::persistentDict::175::Storage.PersistentDict::(transaction)<br>
&gt; Finished transaction<br>
&gt; Thread-32393::INFO::2013-09-20<br>
&gt; 13:16:35,194::clusterlock::174::SANLock::(acquireHostId) Acquiring host id<br>
&gt; for domain 17d21ac7-5859-4f25-8de7-2a9433d50c11 (id: 250)<br>
&gt; Thread-32393::ERROR::2013-09-20<br>
&gt; 13:16:36,196::task::850::TaskManager.Task::(_setError)<br>
&gt; Task=`72348d40-8442-4dbf-bc66-1d354da5fc31`::Unexpected error<br>
&gt; Traceback (most recent call last):<br>
&gt; File &quot;/usr/share/vdsm/storage/task.py&quot;, line 857, in _run<br>
&gt; return fn(*args, **kargs)<br>
&gt; File &quot;/usr/share/vdsm/logUtils.py&quot;, line 45, in wrapper<br>
&gt; res = f(*args, **kwargs)<br>
&gt; File &quot;/usr/share/vdsm/storage/hsm.py&quot;, line 960, in createStoragePool<br>
&gt; masterVersion, leaseParams)<br>
&gt; File &quot;/usr/share/vdsm/storage/sp.py&quot;, line 617, in create<br>
&gt; self._acquireTemporaryClusterLock(msdUUID, leaseParams)<br>
&gt; File &quot;/usr/share/vdsm/storage/sp.py&quot;, line 559, in<br>
&gt; _acquireTemporaryClusterLock<br>
&gt; msd.acquireHostId( <a href="http://self.id" target="_blank">self.id</a> )<br>
&gt; File &quot;/usr/share/vdsm/storage/sd.py&quot;, line 458, in acquireHostId<br>
&gt; self._clusterLock.acquireHostId(hostId, async)<br>
&gt; File &quot;/usr/share/vdsm/storage/clusterlock.py&quot;, line 189, in acquireHostId<br>
&gt; raise se.AcquireHostIdFailure(self._sdUUID, e)<br>
&gt; AcquireHostIdFailure: Cannot acquire host id:<br>
&gt; (&#39;17d21ac7-5859-4f25-8de7-2a9433d50c11&#39;, SanlockException(22, &#39;Sanlock<br>
&gt; lockspace add failure&#39;, &#39;Invalid argument&#39;))<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:36,196::task::869::TaskManager.Task::(_run)<br>
&gt; Task=`72348d40-8442-4dbf-bc66-1d354da5fc31`::Task._run:<br>
&gt; 72348d40-8442-4dbf-bc66-1d354da5fc31 (None,<br>
&gt; &#39;5849b030-626e-47cb-ad90-3ce782d831b3&#39;, &#39;Default&#39;,<br>
&gt; &#39;17d21ac7-5859-4f25-8de7-2a9433d50c11&#39;,<br>
&gt; [&#39;17d21ac7-5859-4f25-8de7-2a9433d50c11&#39;], 9, None, 5, 60, 10, 3) {} failed -<br>
&gt; stopping task<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:36,197::task::1194::TaskManager.Task::(stop)<br>
&gt; Task=`72348d40-8442-4dbf-bc66-1d354da5fc31`::stopping in state preparing<br>
&gt; (force False)<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:36,197::task::974::TaskManager.Task::(_decref)<br>
&gt; Task=`72348d40-8442-4dbf-bc66-1d354da5fc31`::ref 1 aborting True<br>
&gt; Thread-32393::INFO::2013-09-20<br>
&gt; 13:16:36,197::task::1151::TaskManager.Task::(prepare)<br>
&gt; Task=`72348d40-8442-4dbf-bc66-1d354da5fc31`::aborting: Task is aborted:<br>
&gt; &#39;Cannot acquire host id&#39; - code 661<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:36,197::task::1156::TaskManager.Task::(prepare)<br>
&gt; Task=`72348d40-8442-4dbf-bc66-1d354da5fc31`::Prepare: aborted: Cannot<br>
&gt; acquire host id<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:36,197::task::974::TaskManager.Task::(_decref)<br>
&gt; Task=`72348d40-8442-4dbf-bc66-1d354da5fc31`::ref 0 aborting True<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:36,197::task::909::TaskManager.Task::(_doAbort)<br>
&gt; Task=`72348d40-8442-4dbf-bc66-1d354da5fc31`::Task._doAbort: force False<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:36,198::resourceManager::976::ResourceManager.Owner::(cancelAll)<br>
&gt; Owner.cancelAll requests {}<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:36,198::task::579::TaskManager.Task::(_updateState)<br>
&gt; Task=`72348d40-8442-4dbf-bc66-1d354da5fc31`::moving from state preparing -&gt;<br>
&gt; state aborting<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:36,198::task::534::TaskManager.Task::(__state_aborting)<br>
&gt; Task=`72348d40-8442-4dbf-bc66-1d354da5fc31`::_aborting: recover policy none<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:36,198::task::579::TaskManager.Task::(_updateState)<br>
&gt; Task=`72348d40-8442-4dbf-bc66-1d354da5fc31`::moving from state aborting -&gt;<br>
&gt; state failed<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:36,198::resourceManager::939::ResourceManager.Owner::(releaseAll)<br>
&gt; Owner.releaseAll requests {} resources<br>
&gt; {&#39;Storage.17d21ac7-5859-4f25-8de7-2a9433d50c11&#39;: &lt; ResourceRef<br>
&gt; &#39;Storage.17d21ac7-5859-4f25-8de7-2a9433d50c11&#39;, isValid: &#39;True&#39; obj:<br>
&gt; &#39;None&#39;&gt;, &#39;Storage.5849b030-626e-47cb-ad90-3ce782d831b3&#39;: &lt; ResourceRef<br>
&gt; &#39;Storage.5849b030-626e-47cb-ad90-3ce782d831b3&#39;, isValid: &#39;True&#39; obj:<br>
&gt; &#39;None&#39;&gt;}<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:36,198::resourceManager::976::ResourceManager.Owner::(cancelAll)<br>
&gt; Owner.cancelAll requests {}<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:36,199::resourceManager::615::ResourceManager::(releaseResource)<br>
&gt; Trying to release resource &#39;Storage.17d21ac7-5859-4f25-8de7-2a9433d50c11&#39;<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:36,199::resourceManager::634::ResourceManager::(releaseResource)<br>
&gt; Released resource &#39;Storage.17d21ac7-5859-4f25-8de7-2a9433d50c11&#39; (0 active<br>
&gt; users)<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:36,199::resourceManager::640::ResourceManager::(releaseResource)<br>
&gt; Resource &#39;Storage.17d21ac7-5859-4f25-8de7-2a9433d50c11&#39; is free, finding out<br>
&gt; if anyone is waiting for it.<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:36,199::resourceManager::648::ResourceManager::(releaseResource) No<br>
&gt; one is waiting for resource &#39;Storage.17d21ac7-5859-4f25-8de7-2a9433d50c11&#39;,<br>
&gt; Clearing records.<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:36,199::resourceManager::615::ResourceManager::(releaseResource)<br>
&gt; Trying to release resource &#39;Storage.5849b030-626e-47cb-ad90-3ce782d831b3&#39;<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:36,200::resourceManager::634::ResourceManager::(releaseResource)<br>
&gt; Released resource &#39;Storage.5849b030-626e-47cb-ad90-3ce782d831b3&#39; (0 active<br>
&gt; users)<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:36,200::resourceManager::640::ResourceManager::(releaseResource)<br>
&gt; Resource &#39;Storage.5849b030-626e-47cb-ad90-3ce782d831b3&#39; is free, finding out<br>
&gt; if anyone is waiting for it.<br>
&gt; Thread-32393::DEBUG::2013-09-20<br>
&gt; 13:16:36,200::resourceManager::648::ResourceManager::(releaseResource) No<br>
&gt; one is waiting for resource &#39;Storage.5849b030-626e-47cb-ad90-3ce782d831b3&#39;,<br>
&gt; Clearing records.<br>
&gt; Thread-32393::ERROR::2013-09-20<br>
&gt; 13:16:36,200::dispatcher::67::Storage.Dispatcher.Protect::(run) {&#39;status&#39;:<br>
&gt; {&#39;message&#39;: &quot;Cannot acquire host id:<br>
&gt; (&#39;17d21ac7-5859-4f25-8de7-2a9433d50c11&#39;, SanlockException(22, &#39;Sanlock<br>
&gt; lockspace add failure&#39;, &#39;Invalid argument&#39;))&quot;, &#39;code&#39;: 661}}<br>
&gt; Thread-32398::DEBUG::2013-09-20<br>
&gt; 13:16:48,921::task::579::TaskManager.Task::(_updateState)<br>
&gt; Task=`a5bce432-622b-499b-a216-d9a1f876e3ca`::moving from state init -&gt; state<br>
&gt; preparing<br>
&gt; Thread-32398::INFO::2013-09-20<br>
&gt; 13:16:48,922::logUtils::44::dispatcher::(wrapper) Run and protect:<br>
&gt; repoStats(options=None)<br>
&gt; Thread-32398::INFO::2013-09-20<br>
&gt; 13:16:48,922::logUtils::47::dispatcher::(wrapper) Run and protect:<br>
&gt; repoStats, Return response: {}<br>
&gt; Thread-32398::DEBUG::2013-09-20<br>
&gt; 13:16:48,922::task::1168::TaskManager.Task::(prepare)<br>
&gt; Task=`a5bce432-622b-499b-a216-d9a1f876e3ca`::finished: {}<br>
&gt; Thread-32398::DEBUG::2013-09-20<br>
&gt; 13:16:48,922::task::579::TaskManager.Task::(_updateState)<br>
&gt; Task=`a5bce432-622b-499b-a216-d9a1f876e3ca`::moving from state preparing -&gt;<br>
&gt; state finished<br>
<br>
&gt; Steve Dainard<br>
&gt; Infrastructure Manager<br>
&gt; Miovision | Rethink Traffic<br>
&gt; 519-513-2407 ex.250<br>
&gt; 877-646-8476 (toll-free)<br>
<br>
&gt; Blog | LinkedIn | Twitter | Facebook<br>
</div></div><div class="HOEnZb"><div class="h5">&gt; Miovision Technologies Inc. | 148 Manitou Drive, Suite 101, Kitchener, ON,<br>
&gt; Canada | N2C 1L3<br>
&gt; This e-mail may contain information that is privileged or confidential. If<br>
&gt; you are not the intended recipient, please delete the e-mail and any<br>
&gt; attachments and notify us immediately.<br>
<br>
&gt; On Fri, Sep 20, 2013 at 12:23 PM, Deepak C Shetty &lt;<br>
&gt; <a href="mailto:deepakcs@linux.vnet.ibm.com">deepakcs@linux.vnet.ibm.com</a> &gt; wrote:<br>
<br>
&gt; &gt; Either you can use the volume set .. option as mentioned in the wikipage<br>
&gt;<br>
&gt; &gt; --or --<br>
&gt;<br>
&gt; &gt; If the Gluster volume is added / managed to the oVirt UI.. go to &quot;Volumes&quot;<br>
&gt; &gt; tab, select the gluster volume and<br>
&gt;<br>
&gt; &gt; click on &quot;Optimize for virt. store&quot;. That should also set the volume<br>
&gt; &gt; options<br>
&gt; &gt; in addition to few other things<br>
&gt;<br>
<br>
&gt; &gt; thanx,<br>
&gt;<br>
&gt; &gt; deepak<br>
&gt;<br>
<br>
&gt; &gt; ______________________________ _________________<br>
&gt;<br>
&gt; &gt; Users mailing list<br>
&gt;<br>
&gt; &gt; <a href="mailto:Users@ovirt.org">Users@ovirt.org</a><br>
&gt;<br>
&gt; &gt; <a href="http://lists.ovirt.org/" target="_blank">http://lists.ovirt.org/</a> mailman/listinfo/users<br>
&gt;<br>
<br>
&gt; _______________________________________________<br>
&gt; Users mailing list<br>
&gt; <a href="mailto:Users@ovirt.org">Users@ovirt.org</a><br>
&gt; <a href="http://lists.ovirt.org/mailman/listinfo/users" target="_blank">http://lists.ovirt.org/mailman/listinfo/users</a><br>
</div></div></blockquote></div><br></div>