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