[Users] Storage domain does not exist

After an normal "yum update" i am unable to get one of the storage domains "UP". My systems is running Centos 6.3. If I try to activate the domain in GUI I got following errors: --------------------------------- 2012-08-14 12:16:22,594 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-5-thread-50) [539cbd7e] Lock Acquired to object EngineLock [exclusiveLocks= key: org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand value: 5de36f78-8303-401b-b6c3-e04091077f9e 2012-08-14 12:16:22,610 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-5-thread-50) [539cbd7e] Running command: ActivateStorageDomainCommand internal: false. Entities affected : ID: 5de36f78-8303-401b-b6c3-e04091077f9e Type: Storage 2012-08-14 12:16:22,618 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-5-thread-50) [539cbd7e] Lock freed to object EngineLock [exclusiveLocks= key: org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand value: 5de36f78-8303-401b-b6c3-e04091077f9e 2012-08-14 12:16:22,709 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.ActivateStorageDomainVDSCommand] (pool-5-thread-50) [539cbd7e] START, ActivateStorageDomainVDSCommand(storagePoolId = 64a0f560-b9a1-4c87-8a30-0967b4ce3c81, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 5de36f78-8303-401b-b6c3-e04091077f9e), log id: 152cc8c3 2012-08-14 12:16:25,050 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] (pool-5-thread-50) [539cbd7e] Error code StorageDomainDoesNotExist and error message IRSGenericException: IRSErrorException: Failed to ActivateStorageDomainVDS, error = Storage domain does not exist: ('5de36f78-8303-401b-b6c3-e04091077f9e',) 2012-08-14 12:16:25,050 ERROR [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (pool-5-thread-50) [539cbd7e] IrsBroker::Failed::ActivateStorageDomainVDS due to: IRSErrorException: IRSGenericException: IRSErrorException: Failed to ActivateStorageDomainVDS, error = Storage domain does not exist: ('5de36f78-8303-401b-b6c3-e04091077f9e',) 2012-08-14 12:16:25,058 ERROR [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-5-thread-50) [539cbd7e] Command org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand throw Vdc Bll exception. With error message VdcBLLException: org.ovirt.engine.core.vdsbroker.irsbroker.IRSErrorException: IRSGenericException: IRSErrorException: Failed to ActivateStorageDomainVDS, error = Storage domain does not exist: ('5de36f78-8303-401b-b6c3-e04091077f9e',) 2012-08-14 12:16:25,074 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-5-thread-50) [539cbd7e] Command [id=6db5eb90-af14-43f7-8494-d59528a77c27]: Compensating CHANGED_STATUS_ONLY of org.ovirt.engine.core.common.businessentities.storage_pool_iso_map; snapshot: EntityStatusSnapshot [id=storagePoolId = 64a0f560-b9a1-4c87-8a30-0967b4ce3c81, storageId = 5de36f78-8303-401b-b6c3-e04091077f9e, status=InActive]. ------------------------------------- Ho can I try to resolv it? Regards //Ricky

Hi Ricky can you please add the log of VDSM, also the full engine log. Regards, Maor On 08/14/2012 01:22 PM, Ricky Schneberger wrote:
After an normal "yum update" i am unable to get one of the storage domains "UP".
My systems is running Centos 6.3.
If I try to activate the domain in GUI I got following errors:
---------------------------------
2012-08-14 12:16:22,594 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-5-thread-50) [539cbd7e] Lock Acquired to object EngineLock [exclusiveLocks= key: org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand value: 5de36f78-8303-401b-b6c3-e04091077f9e 2012-08-14 12:16:22,610 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-5-thread-50) [539cbd7e] Running command: ActivateStorageDomainCommand internal: false. Entities affected : ID: 5de36f78-8303-401b-b6c3-e04091077f9e Type: Storage 2012-08-14 12:16:22,618 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-5-thread-50) [539cbd7e] Lock freed to object EngineLock [exclusiveLocks= key: org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand value: 5de36f78-8303-401b-b6c3-e04091077f9e 2012-08-14 12:16:22,709 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.ActivateStorageDomainVDSCommand] (pool-5-thread-50) [539cbd7e] START, ActivateStorageDomainVDSCommand(storagePoolId = 64a0f560-b9a1-4c87-8a30-0967b4ce3c81, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 5de36f78-8303-401b-b6c3-e04091077f9e), log id: 152cc8c3 2012-08-14 12:16:25,050 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] (pool-5-thread-50) [539cbd7e] Error code StorageDomainDoesNotExist and error message IRSGenericException: IRSErrorException: Failed to ActivateStorageDomainVDS, error = Storage domain does not exist: ('5de36f78-8303-401b-b6c3-e04091077f9e',) 2012-08-14 12:16:25,050 ERROR [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (pool-5-thread-50) [539cbd7e] IrsBroker::Failed::ActivateStorageDomainVDS due to: IRSErrorException: IRSGenericException: IRSErrorException: Failed to ActivateStorageDomainVDS, error = Storage domain does not exist: ('5de36f78-8303-401b-b6c3-e04091077f9e',) 2012-08-14 12:16:25,058 ERROR [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-5-thread-50) [539cbd7e] Command org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand throw Vdc Bll exception. With error message VdcBLLException: org.ovirt.engine.core.vdsbroker.irsbroker.IRSErrorException: IRSGenericException: IRSErrorException: Failed to ActivateStorageDomainVDS, error = Storage domain does not exist: ('5de36f78-8303-401b-b6c3-e04091077f9e',) 2012-08-14 12:16:25,074 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-5-thread-50) [539cbd7e] Command [id=6db5eb90-af14-43f7-8494-d59528a77c27]: Compensating CHANGED_STATUS_ONLY of org.ovirt.engine.core.common.businessentities.storage_pool_iso_map; snapshot: EntityStatusSnapshot [id=storagePoolId = 64a0f560-b9a1-4c87-8a30-0967b4ce3c81, storageId = 5de36f78-8303-401b-b6c3-e04091077f9e, status=InActive]. -------------------------------------
Ho can I try to resolv it?
Regards //Ricky
_______________________________________________ Users mailing list Users@ovirt.org http://lists.ovirt.org/mailman/listinfo/users

This is a MIME-formatted message. If you see this text it means that your E-mail software does not support MIME-formatted messages. --=_bauhaus.teknikservice.nu-24776-1344945707-0001-2 Content-Type: text/plain; charset=iso-8859-1 Content-Transfer-Encoding: 7bit Hi I attached the logs. //Ricky On 2012-08-14 12:56, Maor Lipchuk wrote:
Hi Ricky can you please add the log of VDSM, also the full engine log.
Regards, Maor
On 08/14/2012 01:22 PM, Ricky Schneberger wrote:
After an normal "yum update" i am unable to get one of the storage domains "UP".
My systems is running Centos 6.3.
If I try to activate the domain in GUI I got following errors:
---------------------------------
2012-08-14 12:16:22,594 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-5-thread-50) [539cbd7e] Lock Acquired to object EngineLock [exclusiveLocks= key: org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand value: 5de36f78-8303-401b-b6c3-e04091077f9e 2012-08-14 12:16:22,610 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-5-thread-50) [539cbd7e] Running command: ActivateStorageDomainCommand internal: false. Entities affected : ID: 5de36f78-8303-401b-b6c3-e04091077f9e Type: Storage 2012-08-14 12:16:22,618 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-5-thread-50) [539cbd7e] Lock freed to object EngineLock [exclusiveLocks= key: org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand value: 5de36f78-8303-401b-b6c3-e04091077f9e 2012-08-14 12:16:22,709 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.ActivateStorageDomainVDSCommand] (pool-5-thread-50) [539cbd7e] START, ActivateStorageDomainVDSCommand(storagePoolId = 64a0f560-b9a1-4c87-8a30-0967b4ce3c81, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 5de36f78-8303-401b-b6c3-e04091077f9e), log id: 152cc8c3 2012-08-14 12:16:25,050 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] (pool-5-thread-50) [539cbd7e] Error code StorageDomainDoesNotExist and error message IRSGenericException: IRSErrorException: Failed to ActivateStorageDomainVDS, error = Storage domain does not exist: ('5de36f78-8303-401b-b6c3-e04091077f9e',) 2012-08-14 12:16:25,050 ERROR [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (pool-5-thread-50) [539cbd7e] IrsBroker::Failed::ActivateStorageDomainVDS due to: IRSErrorException: IRSGenericException: IRSErrorException: Failed to ActivateStorageDomainVDS, error = Storage domain does not exist: ('5de36f78-8303-401b-b6c3-e04091077f9e',) 2012-08-14 12:16:25,058 ERROR [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-5-thread-50) [539cbd7e] Command org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand throw Vdc Bll exception. With error message VdcBLLException: org.ovirt.engine.core.vdsbroker.irsbroker.IRSErrorException: IRSGenericException: IRSErrorException: Failed to ActivateStorageDomainVDS, error = Storage domain does not exist: ('5de36f78-8303-401b-b6c3-e04091077f9e',) 2012-08-14 12:16:25,074 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-5-thread-50) [539cbd7e] Command [id=6db5eb90-af14-43f7-8494-d59528a77c27]: Compensating CHANGED_STATUS_ONLY of org.ovirt.engine.core.common.businessentities.storage_pool_iso_map; snapshot: EntityStatusSnapshot [id=storagePoolId = 64a0f560-b9a1-4c87-8a30-0967b4ce3c81, storageId = 5de36f78-8303-401b-b6c3-e04091077f9e, status=InActive]. -------------------------------------
Ho can I try to resolv it?
Regards //Ricky
_______________________________________________ Users mailing list Users@ovirt.org http://lists.ovirt.org/mailman/listinfo/users
=3D 21 Thread-1190101::DEBUG::2012-08-14 13:43:55,633::misc::1063::SamplingMetho= d::(__call__) Returning last result Thread-1190101::DEBUG::2012-08-14 13:43:57,790::__init__::1164::Storage.M= isc.excCmd::(_log) '/usr/bin/sudo -n /sbin/multipath' (cwd None) Thread-1190101::DEBUG::2012-08-14 13:43:57,869::__init__::1164::Storage.M= isc.excCmd::(_log) SUCCESS: <err> =3D ''; <rc> =3D 0 Thread-1190101::DEBUG::2012-08-14 13:43:57,869::lvm::459::OperationMutex:= :(_invalidateAllPvs) Operation 'lvm invalidate operation' got the operati= on mutex Thread-1190101::DEBUG::2012-08-14 13:43:57,870::lvm::461::OperationMutex:= :(_invalidateAllPvs) Operation 'lvm invalidate operation' released the op= eration mutex Thread-1190101::DEBUG::2012-08-14 13:43:57,870::lvm::471::OperationMutex:= :(_invalidateAllVgs) Operation 'lvm invalidate operation' got the operati= on mutex Thread-1190101::DEBUG::2012-08-14 13:43:57,870::lvm::473::OperationMutex:= :(_invalidateAllVgs) Operation 'lvm invalidate operation' released the op= eration mutex Thread-1190101::DEBUG::2012-08-14 13:43:57,870::lvm::492::OperationMutex:= :(_invalidateAllLvs) Operation 'lvm invalidate operation' got the operati= on mutex Thread-1190101::DEBUG::2012-08-14 13:43:57,870::lvm::494::OperationMutex:= :(_invalidateAllLvs) Operation 'lvm invalidate operation' released the op= eration mutex Thread-1190101::DEBUG::2012-08-14 13:43:57,871::misc::1063::SamplingMetho= d::(__call__) Returning last result Thread-1190101::DEBUG::2012-08-14 13:43:57,871::lvm::351::OperationMutex:= :(_reloadvgs) Operation 'lvm reload operation' got the operation mutex Thread-1190101::DEBUG::2012-08-14 13:43:57,872::__init__::1164::Storage.M= isc.excCmd::(_log) '/usr/bin/sudo -n /sbin/lvm vgs --config " devices { p= referred_names =3D [\\"^/dev/mapper/\\"] ignore_suspended_devices=3D1 wri= te_cache_state=3D0 disable_after_error_count=3D3 filter =3D [ \\"r%.*%\\"= ] } global { locking_type=3D1 prioritise_write_locks=3D1 wait_for_lo= cks=3D1 } backup { retain_min =3D 50 retain_days =3D 0 } " --noheading= s --units b --nosuffix --separator | -o uuid,name,attr,size,free,extent_s= ize,extent_count,free_count,tags,vg_mda_size,vg_mda_free 5de36f78-8303-40= 1b-b6c3-e04091077f9e' (cwd None) Thread-1190101::DEBUG::2012-08-14 13:43:57,901::__init__::1164::Storage.M= isc.excCmd::(_log) FAILED: <err> =3D ' Volume group "5de36f78-8303-401b-= b6c3-e04091077f9e" not found\n'; <rc> =3D 5 Thread-1190101::WARNING::2012-08-14 13:43:57,902::lvm::355::Storage.LVM::= (_reloadvgs) lvm vgs failed: 5 [] [' Volume group "5de36f78-8303-401b-b6= c3-e04091077f9e" not found'] Thread-1190101::DEBUG::2012-08-14 13:43:57,902::lvm::378::OperationMutex:= :(_reloadvgs) Operation 'lvm reload operation' released the operation mut= ex Thread-1190101::DEBUG::2012-08-14 13:43:57,909::fileSD::107::Storage.Stor= ageDomain::(__init__) Reading domain in path /rhev/data-center/mnt/10.10.= 6.107:_md2_oVirt_data-win/5de36f78-8303-401b-b6c3-e04091077f9e Thread-1190101::DEBUG::2012-08-14 13:43:57,910::persistentDict::185::Stor= age.PersistentDict::(__init__) Created a persistent dict with FileMetadat= aRW backend Thread-1190101::DEBUG::2012-08-14 13:43:57,912::persistentDict::226::Stor= age.PersistentDict::(refresh) read lines (FileMetadataRW)=3D['CLASS=3DDat= a', 'DESCRIPTION=3Ddata-win', 'IOOPTIMEOUTSEC=3D10', 'LEASERETRIES=3D3', = 'LEASETIMESEC=3D60', 'LOCKPOLICY=3D', 'LOCKRENEWALINTERVALSEC=3D5', 'MAST= ER_VERSION=3D6060', 'POOL_DESCRIPTION=3DDefault-30', 'POOL_DOMAINS=3D1436= 8440-9620-423d-b816-a3d42a1b50d7:Active,5de36f78-8303-401b-b6c3-e04091077= f9e:Active,2563b7db-bbca-44f4-ba68-98a516bae14b:Active,75b7f4bc-1c46-4847= -ba1b-2bbc84c771ce:Attached', 'POOL_SPM_ID=3D2', 'POOL_SPM_LVER=3D61', 'P= OOL_UUID=3D64a0f560-b9a1-4c87-8a30-0967b4ce3c81', 'REMOTE_PATH=3D10.10.1.= 107:/md2/oVirt/data-win', 'ROLE=3DRegular', 'SDUUID=3D5de36f78-8303-401b-= b6c3-e04091077f9e', 'TYPE=3DNFS', 'VERSION=3D0', '_SHA_CKSUM=3De0d678db83= 901ff6986147da4c0490a95d2f72d0'] Thread-1190101::WARNING::2012-08-14 13:43:57,912::persistentDict::264::St= orage.PersistentDict::(refresh) data seal is broken metadata declares `e0= d678db83901ff6986147da4c0490a95d2f72d0` should be `e214065c8753aa7965ac9c= e0d1f64cc2dd2c3339` (lines=3D{'VERSION': '0', 'LEASETIMESEC': '60', 'DESC= RIPTION': 'data-win', 'TYPE': 'NFS', 'LOCKPOLICY': '', 'LEASERETRIES': '3= ', 'POOL_DESCRIPTION': 'Default-30', 'POOL_SPM_LVER': '61', 'REMOTE_PATH'= : '10.10.1.107:/md2/oVirt/data-win', 'SDUUID': '5de36f78-8303-401b-b6c3-e= 04091077f9e', 'MASTER_VERSION': '6060', 'IOOPTIMEOUTSEC': '10', 'ROLE': '= Regular', 'LOCKRENEWALINTERVALSEC': '5', 'POOL_DOMAINS': '14368440-9620-4= 23d-b816-a3d42a1b50d7:Active,5de36f78-8303-401b-b6c3-e04091077f9e:Active,= 2563b7db-bbca-44f4-ba68-98a516bae14b:Active,75b7f4bc-1c46-4847-ba1b-2bbc8= 4c771ce:Attached', 'POOL_UUID': '64a0f560-b9a1-4c87-8a30-0967b4ce3c81', '= POOL_SPM_ID': '2', 'CLASS': 'Data'}) Thread-1190101::ERROR::2012-08-14 13:43:57,912::sdc::116::Storage.Storage= DomainCache::(_findDomain) Error while looking for domain `5de36f78-8303-= 401b-b6c3-e04091077f9e` Traceback (most recent call last): File "/usr/share/vdsm/storage/sdc.py", line 111, in _findDomain return mod.findDomain(sdUUID) File "/usr/share/vdsm/storage/nfsSD.py", line 122, in findDomain return NfsStorageDomain(NfsStorageDomain.findDomainPath(sdUUID)) File "/usr/share/vdsm/storage/fileSD.py", line 113, in __init__ sdUUID =3D metadata[sd.DMDK_SDUUID] File "/usr/share/vdsm/storage/persistentDict.py", line 85, in __getitem= __ return dec(self._dict[key]) File "/usr/share/vdsm/storage/persistentDict.py", line 193, in __getite= m__ with self._accessWrapper(): File "/usr/lib64/python2.6/contextlib.py", line 16, in __enter__ return self.gen.next() File "/usr/share/vdsm/storage/persistentDict.py", line 147, in _accessW= rapper self.refresh() File "/usr/share/vdsm/storage/persistentDict.py", line 265, in refresh raise se.MetaDataSealIsBroken(declaredChecksum, computedChecksum) MetaDataSealIsBroken: Meta Data seal is broken (checksum mismatch): 'cksu= m =3D e0d678db83901ff6986147da4c0490a95d2f72d0, computed_cksum =3D e21406= 5c8753aa7965ac9ce0d1f64cc2dd2c3339' Thread-1190101::ERROR::2012-08-14 13:43:57,913::task::853::TaskManager.Ta= sk::(_setError) Task=3D`2bb8c3bd-3115-414c-9e07-0a5464949c56`::Unexpected= error Traceback (most recent call last): File "/usr/share/vdsm/storage/task.py", line 861, in _run return fn(*args, **kargs) File "/usr/share/vdsm/logUtils.py", line 38, in wrapper res =3D f(*args, **kwargs) File "/usr/share/vdsm/storage/hsm.py", line 1012, in activateStorageDom= ain pool.activateSD(sdUUID) File "/usr/share/vdsm/storage/securable.py", line 63, in wrapper return f(self, *args, **kwargs) File "/usr/share/vdsm/storage/sp.py", line 989, in activateSD dom =3D sdCache.produce(sdUUID) File "/usr/share/vdsm/storage/sdc.py", line 93, in produce dom =3D self._findDomain(sdUUID) File "/usr/share/vdsm/storage/sdc.py", line 118, in _findDomain raise se.StorageDomainDoesNotExist(sdUUID) StorageDomainDoesNotExist: Storage domain does not exist: ('5de36f78-8303= -401b-b6c3-e04091077f9e',) Thread-1190101::DEBUG::2012-08-14 13:43:57,913::task::872::TaskManager.Ta= sk::(_run) Task=3D`2bb8c3bd-3115-414c-9e07-0a5464949c56`::Task._run: 2bb8= c3bd-3115-414c-9e07-0a5464949c56 ('5de36f78-8303-401b-b6c3-e04091077f9e',= '64a0f560-b9a1-4c87-8a30-0967b4ce3c81') {} failed - stopping task Thread-1190101::DEBUG::2012-08-14 13:43:57,914::task::1199::TaskManager.T= ask::(stop) Task=3D`2bb8c3bd-3115-414c-9e07-0a5464949c56`::stopping in st= ate preparing (force False) Thread-1190101::DEBUG::2012-08-14 13:43:57,914::task::978::TaskManager.Ta= sk::(_decref) Task=3D`2bb8c3bd-3115-414c-9e07-0a5464949c56`::ref 1 aborti= ng True Thread-1190101::INFO::2012-08-14 13:43:57,914::task::1157::TaskManager.Ta= sk::(prepare) Task=3D`2bb8c3bd-3115-414c-9e07-0a5464949c56`::aborting: Ta= sk is aborted: 'Storage domain does not exist' - code 358 Thread-1190101::DEBUG::2012-08-14 13:43:57,914::task::1162::TaskManager.T= ask::(prepare) Task=3D`2bb8c3bd-3115-414c-9e07-0a5464949c56`::Prepare: ab= orted: Storage domain does not exist Thread-1190101::DEBUG::2012-08-14 13:43:57,914::task::978::TaskManager.Ta= sk::(_decref) Task=3D`2bb8c3bd-3115-414c-9e07-0a5464949c56`::ref 0 aborti= ng True Thread-1190101::DEBUG::2012-08-14 13:43:57,914::task::913::TaskManager.Ta= sk::(_doAbort) Task=3D`2bb8c3bd-3115-414c-9e07-0a5464949c56`::Task._doAbo= rt: force False Thread-1190101::DEBUG::2012-08-14 13:43:57,915::resourceManager::844::Res= ourceManager.Owner::(cancelAll) Owner.cancelAll requests {} Thread-1190101::DEBUG::2012-08-14 13:43:57,915::task::588::TaskManager.Ta= sk::(_updateState) Task=3D`2bb8c3bd-3115-414c-9e07-0a5464949c56`::moving = from state preparing -> state aborting Thread-1190101::DEBUG::2012-08-14 13:43:57,915::task::537::TaskManager.Ta= sk::(__state_aborting) Task=3D`2bb8c3bd-3115-414c-9e07-0a5464949c56`::_ab= orting: recover policy none Thread-1190101::DEBUG::2012-08-14 13:43:57,915::task::588::TaskManager.Ta= sk::(_updateState) Task=3D`2bb8c3bd-3115-414c-9e07-0a5464949c56`::moving = from state aborting -> state failed Thread-1190101::DEBUG::2012-08-14 13:43:57,915::resourceManager::809::Res= ourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {= 'Storage.5de36f78-8303-401b-b6c3-e04091077f9e': < ResourceRef 'Storage.5d= e36f78-8303-401b-b6c3-e04091077f9e', isValid: 'True' obj: 'None'>, 'Stora= ge.64a0f560-b9a1-4c87-8a30-0967b4ce3c81': < ResourceRef 'Storage.64a0f560= -b9a1-4c87-8a30-0967b4ce3c81', isValid: 'True' obj: 'None'>} Thread-1190101::DEBUG::2012-08-14 13:43:57,915::resourceManager::844::Res= ourceManager.Owner::(cancelAll) Owner.cancelAll requests {} Thread-1190101::DEBUG::2012-08-14 13:43:57,915::resourceManager::538::Res= ourceManager::(releaseResource) Trying to release resource 'Storage.5de36= f78-8303-401b-b6c3-e04091077f9e' Thread-1190101::DEBUG::2012-08-14 13:43:57,916::resourceManager::553::Res= ourceManager::(releaseResource) Released resource 'Storage.5de36f78-8303-= 401b-b6c3-e04091077f9e' (0 active users) Thread-1190101::DEBUG::2012-08-14 13:43:57,916::resourceManager::558::Res= ourceManager::(releaseResource) Resource 'Storage.5de36f78-8303-401b-b6c3= -e04091077f9e' is free, finding out if anyone is waiting for it. Thread-1190101::DEBUG::2012-08-14 13:43:57,916::resourceManager::565::Res= ourceManager::(releaseResource) No one is waiting for resource 'Storage.5= de36f78-8303-401b-b6c3-e04091077f9e', Clearing records. Thread-1190101::DEBUG::2012-08-14 13:43:57,916::resourceManager::538::Res= ourceManager::(releaseResource) Trying to release resource 'Storage.64a0f= 560-b9a1-4c87-8a30-0967b4ce3c81' Thread-1190101::DEBUG::2012-08-14 13:43:57,917::resourceManager::553::Res= ourceManager::(releaseResource) Released resource 'Storage.64a0f560-b9a1-= 4c87-8a30-0967b4ce3c81' (0 active users) Thread-1190101::DEBUG::2012-08-14 13:43:57,917::resourceManager::558::Res= ourceManager::(releaseResource) Resource 'Storage.64a0f560-b9a1-4c87-8a30= -0967b4ce3c81' is free, finding out if anyone is waiting for it. Thread-1190101::DEBUG::2012-08-14 13:43:57,917::resourceManager::565::Res= ourceManager::(releaseResource) No one is waiting for resource 'Storage.6= 4a0f560-b9a1-4c87-8a30-0967b4ce3c81', Clearing records. Thread-1190101::ERROR::2012-08-14 13:43:57,917::dispatcher::66::Storage.D= ispatcher.Protect::(run) {'status': {'message': "Storage domain does not = exist: ('5de36f78-8303-401b-b6c3-e04091077f9e',)", 'code': 358}} Thread-1190107::DEBUG::2012-08-14 13:43:58,411::BindingXMLRPC::160::vds::= (wrapper) [10.10.1.105] Thread-1190107::DEBUG::2012-08-14 13:43:58,412::task::588::TaskManager.Ta= sk::(_updateState) Task=3D`ec537973-a494-4aa7-aed7-a7b926da824c`::moving = from state init -> state preparing Thread-1190107::INFO::2012-08-14 13:43:58,412::logUtils::37::dispatcher::= (wrapper) Run and protect: validateStorageServerConnection(domType=3D1, s=
=3D 21 Thread-1190109::DEBUG::2012-08-14 13:43:58,494::misc::1063::SamplingMetho= d::(__call__) Returning last result Thread-1190110::DEBUG::2012-08-14 13:43:58,806::task::588::TaskManager.Ta= sk::(_updateState) Task=3D`994db129-2bf2-49cf-a593-25e9be3940b8`::moving = from state init -> state preparing Thread-1190110::INFO::2012-08-14 13:43:58,806::logUtils::37::dispatcher::= (wrapper) Run and protect: repoStats(options=3DNone) Thread-1190110::INFO::2012-08-14 13:43:58,806::logUtils::39::dispatcher::= (wrapper) Run and protect: repoStats, Return response: {'14368440-9620-42= 3d-b816-a3d42a1b50d7': {'delay': '0.00109887123108', 'lastCheck': 1344944= 633.614243, 'code': 0, 'valid': True}, '2563b7db-bbca-44f4-ba68-98a516bae= 14b': {'delay': '0.0012149810791', 'lastCheck': 1344944633.207345, 'code'= : 0, 'valid': True}, '75b7f4bc-1c46-4847-ba1b-2bbc84c771ce': {'delay': '0= =2E00146293640137', 'lastCheck': 1344944636.989584, 'code': 0, 'valid': T= rue}} Thread-1190110::DEBUG::2012-08-14 13:43:58,806::task::1172::TaskManager.T= ask::(prepare) Task=3D`994db129-2bf2-49cf-a593-25e9be3940b8`::finished: {= '14368440-9620-423d-b816-a3d42a1b50d7': {'delay': '0.00109887123108', 'la= stCheck': 1344944633.614243, 'code': 0, 'valid': True}, '2563b7db-bbca-44= f4-ba68-98a516bae14b': {'delay': '0.0012149810791', 'lastCheck': 13449446= 33.207345, 'code': 0, 'valid': True}, '75b7f4bc-1c46-4847-ba1b-2bbc84c771= ce': {'delay': '0.00146293640137', 'lastCheck': 1344944636.989584, 'code'= : 0, 'valid': True}} Thread-1190110::DEBUG::2012-08-14 13:43:58,807::task::588::TaskManager.Ta= sk::(_updateState) Task=3D`994db129-2bf2-49cf-a593-25e9be3940b8`::moving = from state preparing -> state finished Thread-1190110::DEBUG::2012-08-14 13:43:58,807::resourceManager::809::Res= ourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {= } Thread-1190110::DEBUG::2012-08-14 13:43:58,807::resourceManager::844::Res= ourceManager.Owner::(cancelAll) Owner.cancelAll requests {} Thread-1190110::DEBUG::2012-08-14 13:43:58,807::task::978::TaskManager.Ta= sk::(_decref) Task=3D`994db129-2bf2-49cf-a593-25e9be3940b8`::ref 0 aborti= ng False Thread-1190109::DEBUG::2012-08-14 13:44:00,643::__init__::1164::Storage.M= isc.excCmd::(_log) '/usr/bin/sudo -n /sbin/multipath' (cwd None) Thread-1190109::DEBUG::2012-08-14 13:44:00,733::__init__::1164::Storage.M= isc.excCmd::(_log) SUCCESS: <err> =3D ''; <rc> =3D 0 Thread-1190109::DEBUG::2012-08-14 13:44:00,734::lvm::459::OperationMutex:= :(_invalidateAllPvs) Operation 'lvm invalidate operation' got the operati= on mutex Thread-1190109::DEBUG::2012-08-14 13:44:00,734::lvm::461::OperationMutex:= :(_invalidateAllPvs) Operation 'lvm invalidate operation' released the op= eration mutex Thread-1190109::DEBUG::2012-08-14 13:44:00,734::lvm::471::OperationMutex:= :(_invalidateAllVgs) Operation 'lvm invalidate operation' got the operati= on mutex Thread-1190109::DEBUG::2012-08-14 13:44:00,735::lvm::473::OperationMutex:= :(_invalidateAllVgs) Operation 'lvm invalidate operation' released the op= eration mutex Thread-1190109::DEBUG::2012-08-14 13:44:00,735::lvm::492::OperationMutex:= :(_invalidateAllLvs) Operation 'lvm invalidate operation' got the operati= on mutex Thread-1190109::DEBUG::2012-08-14 13:44:00,735::lvm::494::OperationMutex:= :(_invalidateAllLvs) Operation 'lvm invalidate operation' released the op= eration mutex Thread-1190109::DEBUG::2012-08-14 13:44:00,735::misc::1063::SamplingMetho= d::(__call__) Returning last result Thread-1190109::DEBUG::2012-08-14 13:44:00,735::lvm::351::OperationMutex:= :(_reloadvgs) Operation 'lvm reload operation' got the operation mutex Thread-1190109::DEBUG::2012-08-14 13:44:00,736::__init__::1164::Storage.M= isc.excCmd::(_log) '/usr/bin/sudo -n /sbin/lvm vgs --config " devices { p= referred_names =3D [\\"^/dev/mapper/\\"] ignore_suspended_devices=3D1 wri= te_cache_state=3D0 disable_after_error_count=3D3 filter =3D [ \\"r%.*%\\"= ] } global { locking_type=3D1 prioritise_write_locks=3D1 wait_for_lo= cks=3D1 } backup { retain_min =3D 50 retain_days =3D 0 } " --noheading= s --units b --nosuffix --separator | -o uuid,name,attr,size,free,extent_s= ize,extent_count,free_count,tags,vg_mda_size,vg_mda_free 5de36f78-8303-40= 1b-b6c3-e04091077f9e' (cwd None) Thread-1190109::DEBUG::2012-08-14 13:44:00,766::__init__::1164::Storage.M= isc.excCmd::(_log) FAILED: <err> =3D ' Volume group "5de36f78-8303-401b-= b6c3-e04091077f9e" not found\n'; <rc> =3D 5 Thread-1190109::WARNING::2012-08-14 13:44:00,767::lvm::355::Storage.LVM::= (_reloadvgs) lvm vgs failed: 5 [] [' Volume group "5de36f78-8303-401b-b6= c3-e04091077f9e" not found'] Thread-1190109::DEBUG::2012-08-14 13:44:00,767::lvm::378::OperationMutex:= :(_reloadvgs) Operation 'lvm reload operation' released the operation mut= ex Thread-1190109::DEBUG::2012-08-14 13:44:00,774::fileSD::107::Storage.Stor= ageDomain::(__init__) Reading domain in path /rhev/data-center/mnt/10.10.= 6.107:_md2_oVirt_data-win/5de36f78-8303-401b-b6c3-e04091077f9e Thread-1190109::DEBUG::2012-08-14 13:44:00,774::persistentDict::185::Stor= age.PersistentDict::(__init__) Created a persistent dict with FileMetadat= aRW backend Thread-1190109::DEBUG::2012-08-14 13:44:00,776::persistentDict::226::Stor= age.PersistentDict::(refresh) read lines (FileMetadataRW)=3D['CLASS=3DDat= a', 'DESCRIPTION=3Ddata-win', 'IOOPTIMEOUTSEC=3D10', 'LEASERETRIES=3D3', = 'LEASETIMESEC=3D60', 'LOCKPOLICY=3D', 'LOCKRENEWALINTERVALSEC=3D5', 'MAST= ER_VERSION=3D6060', 'POOL_DESCRIPTION=3DDefault-30', 'POOL_DOMAINS=3D1436= 8440-9620-423d-b816-a3d42a1b50d7:Active,5de36f78-8303-401b-b6c3-e04091077= f9e:Active,2563b7db-bbca-44f4-ba68-98a516bae14b:Active,75b7f4bc-1c46-4847= -ba1b-2bbc84c771ce:Attached', 'POOL_SPM_ID=3D2', 'POOL_SPM_LVER=3D61', 'P= OOL_UUID=3D64a0f560-b9a1-4c87-8a30-0967b4ce3c81', 'REMOTE_PATH=3D10.10.1.= 107:/md2/oVirt/data-win', 'ROLE=3DRegular', 'SDUUID=3D5de36f78-8303-401b-= b6c3-e04091077f9e', 'TYPE=3DNFS', 'VERSION=3D0', '_SHA_CKSUM=3De0d678db83= 901ff6986147da4c0490a95d2f72d0'] Thread-1190109::WARNING::2012-08-14 13:44:00,776::persistentDict::264::St= orage.PersistentDict::(refresh) data seal is broken metadata declares `e0= d678db83901ff6986147da4c0490a95d2f72d0` should be `e214065c8753aa7965ac9c= e0d1f64cc2dd2c3339` (lines=3D{'VERSION': '0', 'LEASETIMESEC': '60', 'DESC= RIPTION': 'data-win', 'TYPE': 'NFS', 'LOCKPOLICY': '', 'LEASERETRIES': '3= ', 'POOL_DESCRIPTION': 'Default-30', 'POOL_SPM_LVER': '61', 'REMOTE_PATH'= : '10.10.1.107:/md2/oVirt/data-win', 'SDUUID': '5de36f78-8303-401b-b6c3-e= 04091077f9e', 'MASTER_VERSION': '6060', 'IOOPTIMEOUTSEC': '10', 'ROLE': '= Regular', 'LOCKRENEWALINTERVALSEC': '5', 'POOL_DOMAINS': '14368440-9620-4= 23d-b816-a3d42a1b50d7:Active,5de36f78-8303-401b-b6c3-e04091077f9e:Active,= 2563b7db-bbca-44f4-ba68-98a516bae14b:Active,75b7f4bc-1c46-4847-ba1b-2bbc8= 4c771ce:Attached', 'POOL_UUID': '64a0f560-b9a1-4c87-8a30-0967b4ce3c81', '= POOL_SPM_ID': '2', 'CLASS': 'Data'}) Thread-1190109::ERROR::2012-08-14 13:44:00,777::sdc::116::Storage.Storage= DomainCache::(_findDomain) Error while looking for domain `5de36f78-8303-= 401b-b6c3-e04091077f9e` Traceback (most recent call last): File "/usr/share/vdsm/storage/sdc.py", line 111, in _findDomain return mod.findDomain(sdUUID) File "/usr/share/vdsm/storage/nfsSD.py", line 122, in findDomain return NfsStorageDomain(NfsStorageDomain.findDomainPath(sdUUID)) File "/usr/share/vdsm/storage/fileSD.py", line 113, in __init__ sdUUID =3D metadata[sd.DMDK_SDUUID] File "/usr/share/vdsm/storage/persistentDict.py", line 85, in __getitem= __ return dec(self._dict[key]) File "/usr/share/vdsm/storage/persistentDict.py", line 193, in __getite= m__ with self._accessWrapper(): File "/usr/lib64/python2.6/contextlib.py", line 16, in __enter__ return self.gen.next() File "/usr/share/vdsm/storage/persistentDict.py", line 147, in _accessW= rapper self.refresh() File "/usr/share/vdsm/storage/persistentDict.py", line 265, in refresh raise se.MetaDataSealIsBroken(declaredChecksum, computedChecksum) MetaDataSealIsBroken: Meta Data seal is broken (checksum mismatch): 'cksu= m =3D e0d678db83901ff6986147da4c0490a95d2f72d0, computed_cksum =3D e21406= 5c8753aa7965ac9ce0d1f64cc2dd2c3339' Thread-1190109::ERROR::2012-08-14 13:44:00,777::task::853::TaskManager.Ta= sk::(_setError) Task=3D`e80af100-65a9-4c1e-8518-c7172ef80efe`::Unexpected= error Traceback (most recent call last): File "/usr/share/vdsm/storage/task.py", line 861, in _run return fn(*args, **kargs) File "/usr/share/vdsm/logUtils.py", line 38, in wrapper res =3D f(*args, **kwargs) File "/usr/share/vdsm/storage/hsm.py", line 1012, in activateStorageDom= ain pool.activateSD(sdUUID) File "/usr/share/vdsm/storage/securable.py", line 63, in wrapper return f(self, *args, **kwargs) File "/usr/share/vdsm/storage/sp.py", line 989, in activateSD dom =3D sdCache.produce(sdUUID) File "/usr/share/vdsm/storage/sdc.py", line 93, in produce dom =3D self._findDomain(sdUUID) File "/usr/share/vdsm/storage/sdc.py", line 118, in _findDomain raise se.StorageDomainDoesNotExist(sdUUID) StorageDomainDoesNotExist: Storage domain does not exist: ('5de36f78-8303= -401b-b6c3-e04091077f9e',) Thread-1190109::DEBUG::2012-08-14 13:44:00,777::task::872::TaskManager.Ta= sk::(_run) Task=3D`e80af100-65a9-4c1e-8518-c7172ef80efe`::Task._run: e80a= f100-65a9-4c1e-8518-c7172ef80efe ('5de36f78-8303-401b-b6c3-e04091077f9e',= '64a0f560-b9a1-4c87-8a30-0967b4ce3c81') {} failed - stopping task Thread-1190109::DEBUG::2012-08-14 13:44:00,778::task::1199::TaskManager.T= ask::(stop) Task=3D`e80af100-65a9-4c1e-8518-c7172ef80efe`::stopping in st= ate preparing (force False) Thread-1190109::DEBUG::2012-08-14 13:44:00,778::task::978::TaskManager.Ta= sk::(_decref) Task=3D`e80af100-65a9-4c1e-8518-c7172ef80efe`::ref 1 aborti= ng True Thread-1190109::INFO::2012-08-14 13:44:00,778::task::1157::TaskManager.Ta= sk::(prepare) Task=3D`e80af100-65a9-4c1e-8518-c7172ef80efe`::aborting: Ta= sk is aborted: 'Storage domain does not exist' - code 358 Thread-1190109::DEBUG::2012-08-14 13:44:00,778::task::1162::TaskManager.T= ask::(prepare) Task=3D`e80af100-65a9-4c1e-8518-c7172ef80efe`::Prepare: ab= orted: Storage domain does not exist Thread-1190109::DEBUG::2012-08-14 13:44:00,778::task::978::TaskManager.Ta= sk::(_decref) Task=3D`e80af100-65a9-4c1e-8518-c7172ef80efe`::ref 0 aborti= ng True Thread-1190109::DEBUG::2012-08-14 13:44:00,778::task::913::TaskManager.Ta= sk::(_doAbort) Task=3D`e80af100-65a9-4c1e-8518-c7172ef80efe`::Task._doAbo= rt: force False Thread-1190109::DEBUG::2012-08-14 13:44:00,779::resourceManager::844::Res= ourceManager.Owner::(cancelAll) Owner.cancelAll requests {} Thread-1190109::DEBUG::2012-08-14 13:44:00,779::task::588::TaskManager.Ta= sk::(_updateState) Task=3D`e80af100-65a9-4c1e-8518-c7172ef80efe`::moving = from state preparing -> state aborting Thread-1190109::DEBUG::2012-08-14 13:44:00,779::task::537::TaskManager.Ta= sk::(__state_aborting) Task=3D`e80af100-65a9-4c1e-8518-c7172ef80efe`::_ab= orting: recover policy none Thread-1190109::DEBUG::2012-08-14 13:44:00,779::task::588::TaskManager.Ta= sk::(_updateState) Task=3D`e80af100-65a9-4c1e-8518-c7172ef80efe`::moving = from state aborting -> state failed Thread-1190109::DEBUG::2012-08-14 13:44:00,779::resourceManager::809::Res= ourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {= 'Storage.5de36f78-8303-401b-b6c3-e04091077f9e': < ResourceRef 'Storage.5d= e36f78-8303-401b-b6c3-e04091077f9e', isValid: 'True' obj: 'None'>, 'Stora= ge.64a0f560-b9a1-4c87-8a30-0967b4ce3c81': < ResourceRef 'Storage.64a0f560= -b9a1-4c87-8a30-0967b4ce3c81', isValid: 'True' obj: 'None'>} Thread-1190109::DEBUG::2012-08-14 13:44:00,779::resourceManager::844::Res= ourceManager.Owner::(cancelAll) Owner.cancelAll requests {} Thread-1190109::DEBUG::2012-08-14 13:44:00,780::resourceManager::538::Res= ourceManager::(releaseResource) Trying to release resource 'Storage.5de36= f78-8303-401b-b6c3-e04091077f9e' Thread-1190109::DEBUG::2012-08-14 13:44:00,780::resourceManager::553::Res= ourceManager::(releaseResource) Released resource 'Storage.5de36f78-8303-= 401b-b6c3-e04091077f9e' (0 active users) Thread-1190109::DEBUG::2012-08-14 13:44:00,780::resourceManager::558::Res= ourceManager::(releaseResource) Resource 'Storage.5de36f78-8303-401b-b6c3= -e04091077f9e' is free, finding out if anyone is waiting for it. Thread-1190109::DEBUG::2012-08-14 13:44:00,780::resourceManager::565::Res= ourceManager::(releaseResource) No one is waiting for resource 'Storage.5= de36f78-8303-401b-b6c3-e04091077f9e', Clearing records. Thread-1190109::DEBUG::2012-08-14 13:44:00,780::resourceManager::538::Res= ourceManager::(releaseResource) Trying to release resource 'Storage.64a0f= 560-b9a1-4c87-8a30-0967b4ce3c81' Thread-1190109::DEBUG::2012-08-14 13:44:00,781::resourceManager::553::Res= ourceManager::(releaseResource) Released resource 'Storage.64a0f560-b9a1-= 4c87-8a30-0967b4ce3c81' (0 active users) Thread-1190109::DEBUG::2012-08-14 13:44:00,781::resourceManager::558::Res= ourceManager::(releaseResource) Resource 'Storage.64a0f560-b9a1-4c87-8a30= -0967b4ce3c81' is free, finding out if anyone is waiting for it. Thread-1190109::DEBUG::2012-08-14 13:44:00,781::resourceManager::565::Res= ourceManager::(releaseResource) No one is waiting for resource 'Storage.6= 4a0f560-b9a1-4c87-8a30-0967b4ce3c81', Clearing records. Thread-1190109::ERROR::2012-08-14 13:44:00,781::dispatcher::66::Storage.D= ispatcher.Protect::(run) {'status': {'message': "Storage domain does not = exist: ('5de36f78-8303-401b-b6c3-e04091077f9e',)", 'code': 358}} Thread-1190117::DEBUG::2012-08-14 13:44:01,779::BindingXMLRPC::160::vds::= (wrapper) [10.10.1.105] Thread-1190117::DEBUG::2012-08-14 13:44:01,780::task::588::TaskManager.Ta= sk::(_updateState) Task=3D`e26371b4-ce65-45d1-bb9c-3a6347decc8c`::moving = from state init -> state preparing Thread-1190117::INFO::2012-08-14 13:44:01,780::logUtils::37::dispatcher::= (wrapper) Run and protect: getSpmStatus(spUUID=3D'64a0f560-b9a1-4c87-8a30= -0967b4ce3c81', options=3DNone) Thread-1190117::INFO::2012-08-14 13:44:01,780::logUtils::39::dispatcher::= (wrapper) Run and protect: getSpmStatus, Return response: {'spm_st': {'sp= mId': 1, 'spmStatus': 'SPM', 'spmLver': 8}} Thread-1190117::DEBUG::2012-08-14 13:44:01,780::task::1172::TaskManager.T= ask::(prepare) Task=3D`e26371b4-ce65-45d1-bb9c-3a6347decc8c`::finished: {= 'spm_st': {'spmId': 1, 'spmStatus': 'SPM', 'spmLver': 8}} Thread-1190117::DEBUG::2012-08-14 13:44:01,780::task::588::TaskManager.Ta= sk::(_updateState) Task=3D`e26371b4-ce65-45d1-bb9c-3a6347decc8c`::moving = from state preparing -> state finished Thread-1190117::DEBUG::2012-08-14 13:44:01,781::resourceManager::809::Res= ourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {= } Thread-1190117::DEBUG::2012-08-14 13:44:01,781::resourceManager::844::Res= ourceManager.Owner::(cancelAll) Owner.cancelAll requests {} Thread-1190117::DEBUG::2012-08-14 13:44:01,781::task::978::TaskManager.Ta= sk::(_decref) Task=3D`e26371b4-ce65-45d1-bb9c-3a6347decc8c`::ref 0 aborti= ng False Thread-1190118::DEBUG::2012-08-14 13:44:01,805::BindingXMLRPC::160::vds::= (wrapper) [10.10.1.105] Thread-1190118::DEBUG::2012-08-14 13:44:01,805::task::588::TaskManager.Ta= sk::(_updateState) Task=3D`109f93dd-452b-4399-aecb-787c72f2c72d`::moving = from state init -> state preparing Thread-1190118::INFO::2012-08-14 13:44:01,805::logUtils::37::dispatcher::= (wrapper) Run and protect: getStoragePoolInfo(spUUID=3D'64a0f560-b9a1-4c8= 7-8a30-0967b4ce3c81', options=3DNone) Thread-1190118::DEBUG::2012-08-14 13:44:01,806::resourceManager::175::Res= ourceManager.Request::(__init__) ResName=3D`Storage.64a0f560-b9a1-4c87-8a= 30-0967b4ce3c81`ReqID=3D`54ca4dab-e358-486e-9ccf-1adb414a045e`::Request w= as made in '/usr/share/vdsm/storage/resourceManager.py' line '485' at 're= gisterResource' Thread-1190118::DEBUG::2012-08-14 13:44:01,806::resourceManager::486::Res= ourceManager::(registerResource) Trying to register resource 'Storage.64a= 0f560-b9a1-4c87-8a30-0967b4ce3c81' for lock type 'shared' Thread-1190118::DEBUG::2012-08-14 13:44:01,806::resourceManager::528::Res= ourceManager::(registerResource) Resource 'Storage.64a0f560-b9a1-4c87-8a3= 0-0967b4ce3c81' is free. Now locking as 'shared' (1 active user) Thread-1190118::DEBUG::2012-08-14 13:44:01,806::resourceManager::212::Res= ourceManager.Request::(grant) ResName=3D`Storage.64a0f560-b9a1-4c87-8a30-= 0967b4ce3c81`ReqID=3D`54ca4dab-e358-486e-9ccf-1adb414a045e`::Granted requ= est Thread-1190118::DEBUG::2012-08-14 13:44:01,807::task::817::TaskManager.Ta= sk::(resourceAcquired) Task=3D`109f93dd-452b-4399-aecb-787c72f2c72d`::_re=
--=_bauhaus.teknikservice.nu-24776-1344945707-0001-2 Content-Type: text/x-log; name="engine.log"; charset=iso-8859-1 Content-Transfer-Encoding: 7bit Content-Disposition: attachment; filename="engine.log" 2012-08-14 13:40:00,127 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (QuartzScheduler_Worker-68) [535b7281] ActivateStorage Domain. After Connect all hosts to pool. Time:8/14/12 1:40 PM 2012-08-14 13:40:00,128 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.ActivateStorageDomainVDSCommand] (QuartzScheduler_Worker-68) [535b7281] START, ActivateStorageDomainVDSCommand(storagePoolId = 64a0f560-b9a1-4c87-8a30-0967b4ce3c81, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 5de36f78-8303-401b-b6c3-e04091077f9e), log id: 709d91e5 2012-08-14 13:40:02,451 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] (QuartzScheduler_Worker-68) [535b7281] Failed in ActivateStorageDomainVDS method 2012-08-14 13:40:02,452 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] (QuartzScheduler_Worker-68) [535b7281] Error code StorageDomainDoesNotExist and error message IRSGenericException: IRSErrorException: Failed to ActivateStorageDomainVDS, error = Storage domain does not exist: ('5de36f78-8303-401b-b6c3-e04091077f9e',) 2012-08-14 13:40:02,453 ERROR [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (QuartzScheduler_Worker-68) [535b7281] IrsBroker::Failed::ActivateStorageDomainVDS due to: IRSErrorException: IRSGenericException: IRSErrorException: Failed to ActivateStorageDomainVDS, error = Storage domain does not exist: ('5de36f78-8303-401b-b6c3-e04091077f9e',) 2012-08-14 13:40:02,458 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.ActivateStorageDomainVDSCommand] (QuartzScheduler_Worker-68) [535b7281] FINISH, ActivateStorageDomainVDSCommand, log id: 709d91e5 2012-08-14 13:40:02,458 ERROR [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (QuartzScheduler_Worker-68) [535b7281] Command org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand throw Vdc Bll exception. With error message VdcBLLException: org.ovirt.engine.core.vdsbroker.irsbroker.IRSErrorException: IRSGenericException: IRSErrorException: Failed to ActivateStorageDomainVDS, error = Storage domain does not exist: ('5de36f78-8303-401b-b6c3-e04091077f9e',) 2012-08-14 13:40:02,468 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (QuartzScheduler_Worker-68) [535b7281] Command [id=72e050b9-ab4c-4bbf-b7ca-4765db1291fc]: Compensating CHANGED_STATUS_ONLY of org.ovirt.engine.core.common.businessentities.storage_pool_iso_map; snapshot: EntityStatusSnapshot [id=storagePoolId = 64a0f560-b9a1-4c87-8a30-0967b4ce3c81, storageId = 5de36f78-8303-401b-b6c3-e04091077f9e, status=InActive]. 2012-08-14 13:40:02,485 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-68) [535b7281] Checking autorecoverable storage domains done 2012-08-14 13:40:25,880 INFO [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-69) DbUserCacheManager::refreshAllUserData() - entered 2012-08-14 13:44:34,380 INFO [org.ovirt.engine.core.bll.LoginAdminUserCommand] (http--0.0.0.0-8443-8) Running command: LoginAdminUserCommand internal: false. 2012-08-14 13:44:34,479 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-8) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,532 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-8) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,549 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-6) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,555 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-4) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,569 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-3) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,575 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,578 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-8) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,580 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-7) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,588 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-6) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,593 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-4) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,602 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-3) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,609 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,619 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-7) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,621 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-8) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,622 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-6) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,623 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-4) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,635 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-3) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,640 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,650 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-8) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,669 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,665 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-3) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,656 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-4) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,654 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-6) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,697 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-7) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,726 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-4) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,728 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-6) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,733 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,739 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-3) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,758 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-6) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,763 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-8) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,765 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,768 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-4) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,789 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-6) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,792 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-8) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,796 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,840 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-7) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,844 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-8) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,846 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-6) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,847 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,849 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-4) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,850 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-3) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,872 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-7) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,885 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-8) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,886 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-6) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,887 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,891 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-3) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,893 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-4) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,905 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-7) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,918 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-8) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,925 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,928 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-3) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,931 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-4) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,953 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-8) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,959 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-6) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,960 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,968 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-3) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,974 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-7) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,988 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-8) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,995 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-6) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:34,998 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,006 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-7) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,062 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-6) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,065 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-4) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,069 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,071 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-3) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,071 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-7) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,099 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-8) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,101 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-6) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,106 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-4) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,108 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,113 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-7) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,134 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-8) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,140 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-6) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,146 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,149 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-7) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,151 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-3) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,166 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-8) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,181 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-4) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,183 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,188 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-7) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,191 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-3) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,196 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-8) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,214 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,217 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-4) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,219 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-7) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,222 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-3) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,262 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-8) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,270 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,276 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-4) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,278 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-7) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,278 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-6) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,296 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-8) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,311 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-3) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,311 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,319 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-6) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,322 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-7) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,329 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-8) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,346 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-3) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,346 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,354 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-6) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,360 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-7) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,362 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-8) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,382 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,390 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-6) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,394 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-4) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,397 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-7) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,400 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-8) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,416 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-3) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,427 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-6) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,430 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-4) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,433 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-7) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,450 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-3) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,460 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-6) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,462 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,468 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-4) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,470 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-7) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,473 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-8) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,485 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-3) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,494 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-6) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,499 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,505 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-7) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,505 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-4) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,510 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-8) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,518 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-3) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,527 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-6) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,537 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,539 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-7) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,541 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-4) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,546 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-8) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,551 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-3) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,558 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-6) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,569 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-7) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,572 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-4) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,594 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-8) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:35,600 WARN [org.ovirt.engine.core.bll.GetConfigurationValueQuery] (http--0.0.0.0-8443-2) calling GetConfigurationValueQuery with null version, using default general for version 2012-08-14 13:44:52,861 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (pool-5-thread-48) START, IsValidVDSCommand(storagePoolId = 64a0f560-b9a1-4c87-8a30-0967b4ce3c81, ignoreFailoverLimit = false, compatabilityVersion = null), log id: 499a9725 2012-08-14 13:44:52,891 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (pool-5-thread-48) FINISH, IsValidVDSCommand, return: true, log id: 499a9725 2012-08-14 13:44:52,892 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetIsoListVDSCommand] (pool-5-thread-48) START, GetIsoListVDSCommand(storagePoolId = 64a0f560-b9a1-4c87-8a30-0967b4ce3c81, ignoreFailoverLimit = false, compatabilityVersion = null), log id: 335db027 2012-08-14 13:44:52,898 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HsmGetIsoListVDSCommand] (pool-5-thread-48) START, HsmGetIsoListVDSCommand(vdsId = a153a0e6-dbd8-11e1-95da-cf8b5c612611, storagePoolId=64a0f560-b9a1-4c87-8a30-0967b4ce3c81), log id: 4ac2d594 2012-08-14 13:44:52,940 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HsmGetIsoListVDSCommand] (pool-5-thread-48) FINISH, HsmGetIsoListVDSCommand, return: [Fedora-16-i686-Live-Desktop.iso, SW_CD_Windows_Svr_Std_2003_R2_32-BIT_English_-2_R2_CD1_ISO_MLF_X12-58049.ISO, virtio-win-0.1-22.iso, Windows.7.SP1.ENG.x86-x64.ACTiVATED.iso, W7_pro_HP-x64_Rickys_barbar.iso, Fedora-16-x86_64-DVD.iso, CentOS-6.2-x86_64-bin-DVD1.iso, CentOS-6.0-x86_64-bin-DVD1.iso, ABR11SW_ABR11SWUR_en-US.iso, MigrateToRHEV_17454_en-US.iso], log id: 4ac2d594 2012-08-14 13:44:52,941 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetIsoListVDSCommand] (pool-5-thread-48) FINISH, GetIsoListVDSCommand, return: [Fedora-16-i686-Live-Desktop.iso, SW_CD_Windows_Svr_Std_2003_R2_32-BIT_English_-2_R2_CD1_ISO_MLF_X12-58049.ISO, virtio-win-0.1-22.iso, Windows.7.SP1.ENG.x86-x64.ACTiVATED.iso, W7_pro_HP-x64_Rickys_barbar.iso, Fedora-16-x86_64-DVD.iso, CentOS-6.2-x86_64-bin-DVD1.iso, CentOS-6.0-x86_64-bin-DVD1.iso, ABR11SW_ABR11SWUR_en-US.iso, MigrateToRHEV_17454_en-US.iso], log id: 335db027 2012-08-14 13:44:52,979 INFO [org.ovirt.engine.core.bll.IsoDomainListSyncronizer] (pool-5-thread-48) Finished automatic refresh process for ISO file type with success, for storage domain id 14368440-9620-423d-b816-a3d42a1b50d7. 2012-08-14 13:44:57,327 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-5-thread-48) [130fd9db] Lock Acquired to object EngineLock [exclusiveLocks= key: org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand value: 5de36f78-8303-401b-b6c3-e04091077f9e , sharedLocks= ] 2012-08-14 13:44:57,352 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-5-thread-48) [130fd9db] Running command: ActivateStorageDomainCommand internal: false. Entities affected : ID: 5de36f78-8303-401b-b6c3-e04091077f9e Type: Storage 2012-08-14 13:44:57,377 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-5-thread-48) [130fd9db] Lock freed to object EngineLock [exclusiveLocks= key: org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand value: 5de36f78-8303-401b-b6c3-e04091077f9e , sharedLocks= ] 2012-08-14 13:44:57,379 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-5-thread-48) [130fd9db] ActivateStorage Domain. Before Connect all hosts to pool. Time:8/14/12 1:44 PM 2012-08-14 13:44:57,423 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (pool-5-thread-49) [356895df] START, ValidateStorageServerConnectionVDSCommand(vdsId = a153a0e6-dbd8-11e1-95da-cf8b5c612611, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: 0157c4d0-9d74-42c9-ae0b-b3d8fa1fa945, connection: 10.10.6.107:/md2/oVirt/data-win };]), log id: 10943b34 2012-08-14 13:44:57,434 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (pool-5-thread-49) [356895df] FINISH, ValidateStorageServerConnectionVDSCommand, return: {0157c4d0-9d74-42c9-ae0b-b3d8fa1fa945=0}, log id: 10943b34 2012-08-14 13:44:57,434 INFO [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] (pool-5-thread-49) [356895df] Running command: ConnectStorageToVdsCommand internal: true. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System 2012-08-14 13:44:57,437 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (pool-5-thread-49) [356895df] START, ConnectStorageServerVDSCommand(vdsId = a153a0e6-dbd8-11e1-95da-cf8b5c612611, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: 0157c4d0-9d74-42c9-ae0b-b3d8fa1fa945, connection: 10.10.6.107:/md2/oVirt/data-win };]), log id: 5676c48a 2012-08-14 13:44:57,451 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (pool-5-thread-49) [356895df] FINISH, ConnectStorageServerVDSCommand, return: {0157c4d0-9d74-42c9-ae0b-b3d8fa1fa945=0}, log id: 5676c48a 2012-08-14 13:44:57,453 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-5-thread-48) [130fd9db] ActivateStorage Domain. After Connect all hosts to pool. Time:8/14/12 1:44 PM 2012-08-14 13:44:57,455 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.ActivateStorageDomainVDSCommand] (pool-5-thread-48) [130fd9db] START, ActivateStorageDomainVDSCommand(storagePoolId = 64a0f560-b9a1-4c87-8a30-0967b4ce3c81, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 5de36f78-8303-401b-b6c3-e04091077f9e), log id: 21ecd3dc 2012-08-14 13:44:59,776 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] (pool-5-thread-48) [130fd9db] Failed in ActivateStorageDomainVDS method 2012-08-14 13:44:59,777 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] (pool-5-thread-48) [130fd9db] Error code StorageDomainDoesNotExist and error message IRSGenericException: IRSErrorException: Failed to ActivateStorageDomainVDS, error = Storage domain does not exist: ('5de36f78-8303-401b-b6c3-e04091077f9e',) 2012-08-14 13:44:59,778 ERROR [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (pool-5-thread-48) [130fd9db] IrsBroker::Failed::ActivateStorageDomainVDS due to: IRSErrorException: IRSGenericException: IRSErrorException: Failed to ActivateStorageDomainVDS, error = Storage domain does not exist: ('5de36f78-8303-401b-b6c3-e04091077f9e',) 2012-08-14 13:44:59,783 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.ActivateStorageDomainVDSCommand] (pool-5-thread-48) [130fd9db] FINISH, ActivateStorageDomainVDSCommand, log id: 21ecd3dc 2012-08-14 13:44:59,784 ERROR [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-5-thread-48) [130fd9db] Command org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand throw Vdc Bll exception. With error message VdcBLLException: org.ovirt.engine.core.vdsbroker.irsbroker.IRSErrorException: IRSGenericException: IRSErrorException: Failed to ActivateStorageDomainVDS, error = Storage domain does not exist: ('5de36f78-8303-401b-b6c3-e04091077f9e',) 2012-08-14 13:44:59,792 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-5-thread-48) [130fd9db] Command [id=a4584ad3-2e0a-4f74-9d87-4e8c6267b944]: Compensating CHANGED_STATUS_ONLY of org.ovirt.engine.core.common.businessentities.storage_pool_iso_map; snapshot: EntityStatusSnapshot [id=storagePoolId = 64a0f560-b9a1-4c87-8a30-0967b4ce3c81, storageId = 5de36f78-8303-401b-b6c3-e04091077f9e, status=InActive]. 2012-08-14 13:45:00,003 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-75) [6c6941d3] Checking autorecoverable hosts 2012-08-14 13:45:00,052 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-75) [6c6941d3] Checking autorecoverable hosts done 2012-08-14 13:45:00,053 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-75) [6c6941d3] Checking autorecoverable storage domains 2012-08-14 13:45:00,103 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (QuartzScheduler_Worker-75) [4d542bb1] Lock Acquired to object EngineLock [exclusiveLocks= key: org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand value: 5de36f78-8303-401b-b6c3-e04091077f9e , sharedLocks= ] 2012-08-14 13:45:00,169 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (QuartzScheduler_Worker-75) [4d542bb1] Running command: ActivateStorageDomainCommand internal: true. Entities affected : ID: 5de36f78-8303-401b-b6c3-e04091077f9e Type: Storage 2012-08-14 13:45:00,194 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (QuartzScheduler_Worker-75) [4d542bb1] Lock freed to object EngineLock [exclusiveLocks= key: org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand value: 5de36f78-8303-401b-b6c3-e04091077f9e , sharedLocks= ] 2012-08-14 13:45:00,195 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (QuartzScheduler_Worker-75) [4d542bb1] ActivateStorage Domain. Before Connect all hosts to pool. Time:8/14/12 1:45 PM 2012-08-14 13:45:00,249 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (pool-5-thread-48) [fa3d734] START, ValidateStorageServerConnectionVDSCommand(vdsId = a153a0e6-dbd8-11e1-95da-cf8b5c612611, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: 0157c4d0-9d74-42c9-ae0b-b3d8fa1fa945, connection: 10.10.6.107:/md2/oVirt/data-win };]), log id: 299b139 2012-08-14 13:45:00,294 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (pool-5-thread-48) [fa3d734] FINISH, ValidateStorageServerConnectionVDSCommand, return: {0157c4d0-9d74-42c9-ae0b-b3d8fa1fa945=0}, log id: 299b139 2012-08-14 13:45:00,294 INFO [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] (pool-5-thread-48) [fa3d734] Running command: ConnectStorageToVdsCommand internal: true. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System 2012-08-14 13:45:00,299 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (pool-5-thread-48) [fa3d734] START, ConnectStorageServerVDSCommand(vdsId = a153a0e6-dbd8-11e1-95da-cf8b5c612611, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: 0157c4d0-9d74-42c9-ae0b-b3d8fa1fa945, connection: 10.10.6.107:/md2/oVirt/data-win };]), log id: 18888cc2 2012-08-14 13:45:00,314 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (pool-5-thread-48) [fa3d734] FINISH, ConnectStorageServerVDSCommand, return: {0157c4d0-9d74-42c9-ae0b-b3d8fa1fa945=0}, log id: 18888cc2 2012-08-14 13:45:00,316 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (QuartzScheduler_Worker-75) [4d542bb1] ActivateStorage Domain. After Connect all hosts to pool. Time:8/14/12 1:45 PM 2012-08-14 13:45:00,316 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.ActivateStorageDomainVDSCommand] (QuartzScheduler_Worker-75) [4d542bb1] START, ActivateStorageDomainVDSCommand(storagePoolId = 64a0f560-b9a1-4c87-8a30-0967b4ce3c81, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 5de36f78-8303-401b-b6c3-e04091077f9e), log id: 716ac307 2012-08-14 13:45:02,640 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] (QuartzScheduler_Worker-75) [4d542bb1] Failed in ActivateStorageDomainVDS method 2012-08-14 13:45:02,641 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] (QuartzScheduler_Worker-75) [4d542bb1] Error code StorageDomainDoesNotExist and error message IRSGenericException: IRSErrorException: Failed to ActivateStorageDomainVDS, error = Storage domain does not exist: ('5de36f78-8303-401b-b6c3-e04091077f9e',) 2012-08-14 13:45:02,642 ERROR [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (QuartzScheduler_Worker-75) [4d542bb1] IrsBroker::Failed::ActivateStorageDomainVDS due to: IRSErrorException: IRSGenericException: IRSErrorException: Failed to ActivateStorageDomainVDS, error = Storage domain does not exist: ('5de36f78-8303-401b-b6c3-e04091077f9e',) 2012-08-14 13:45:02,646 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.ActivateStorageDomainVDSCommand] (QuartzScheduler_Worker-75) [4d542bb1] FINISH, ActivateStorageDomainVDSCommand, log id: 716ac307 2012-08-14 13:45:02,647 ERROR [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (QuartzScheduler_Worker-75) [4d542bb1] Command org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand throw Vdc Bll exception. With error message VdcBLLException: org.ovirt.engine.core.vdsbroker.irsbroker.IRSErrorException: IRSGenericException: IRSErrorException: Failed to ActivateStorageDomainVDS, error = Storage domain does not exist: ('5de36f78-8303-401b-b6c3-e04091077f9e',) 2012-08-14 13:45:02,655 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (QuartzScheduler_Worker-75) [4d542bb1] Command [id=5b0cfb78-35a3-4033-bc32-b83375f47352]: Compensating CHANGED_STATUS_ONLY of org.ovirt.engine.core.common.businessentities.storage_pool_iso_map; snapshot: EntityStatusSnapshot [id=storagePoolId = 64a0f560-b9a1-4c87-8a30-0967b4ce3c81, storageId = 5de36f78-8303-401b-b6c3-e04091077f9e, status=InActive]. 2012-08-14 13:45:02,720 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (QuartzScheduler_Worker-75) [4d542bb1] No severity for USER_ACTIVATE_STORAGE_DOMAIN_FAILED_ASYNC type 2012-08-14 13:45:02,735 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-75) [4d542bb1] Checking autorecoverable storage domains done --=_bauhaus.teknikservice.nu-24776-1344945707-0001-2 Content-Type: text/x-log; name="vdsm.log"; charset=iso-8859-1 Content-Transfer-Encoding: quoted-printable Content-Disposition: attachment; filename="vdsm.log" Thread-1190096::INFO::2012-08-14 13:43:51,624::logUtils::39::dispatcher::= (wrapper) Run and protect: getStoragePoolInfo, Return response: {'info': = {'spm_id': 1, 'master_uuid': '75b7f4bc-1c46-4847-ba1b-2bbc84c771ce', 'nam= e': 'Default-30', 'version': '0', 'domains': '14368440-9620-423d-b816-a3d= 42a1b50d7:Active,5de36f78-8303-401b-b6c3-e04091077f9e:Attached,2563b7db-b= bca-44f4-ba68-98a516bae14b:Active,75b7f4bc-1c46-4847-ba1b-2bbc84c771ce:Ac= tive', 'pool_status': 'connected', 'isoprefix': '/rhev/data-center/64a0f5= 60-b9a1-4c87-8a30-0967b4ce3c81/14368440-9620-423d-b816-a3d42a1b50d7/image= s/11111111-1111-1111-1111-111111111111', 'type': 'NFS', 'master_ver': 605= 9, 'lver': 8}, 'dominfo': {'14368440-9620-423d-b816-a3d42a1b50d7': {'stat= us': 'Active', 'diskfree': '284985524224', 'alerts': [], 'disktotal': '39= 96801957888'}, '5de36f78-8303-401b-b6c3-e04091077f9e': {'status': 'Attach= ed'}, '2563b7db-bbca-44f4-ba68-98a516bae14b': {'status': 'Active', 'diskf= ree': '284985524224', 'alerts': [], 'disktotal': '3996801957888'}, '75b7f= 4bc-1c46-4847-ba1b-2bbc84c771ce': {'status': 'Active', 'diskfree': '28498= 5524224', 'alerts': [], 'disktotal': '3996801957888'}}} Thread-1190096::DEBUG::2012-08-14 13:43:51,625::task::1172::TaskManager.T= ask::(prepare) Task=3D`591249eb-49e5-4488-85f3-3298c0517509`::finished: {= 'info': {'spm_id': 1, 'master_uuid': '75b7f4bc-1c46-4847-ba1b-2bbc84c771c= e', 'name': 'Default-30', 'version': '0', 'domains': '14368440-9620-423d-= b816-a3d42a1b50d7:Active,5de36f78-8303-401b-b6c3-e04091077f9e:Attached,25= 63b7db-bbca-44f4-ba68-98a516bae14b:Active,75b7f4bc-1c46-4847-ba1b-2bbc84c= 771ce:Active', 'pool_status': 'connected', 'isoprefix': '/rhev/data-cente= r/64a0f560-b9a1-4c87-8a30-0967b4ce3c81/14368440-9620-423d-b816-a3d42a1b50= d7/images/11111111-1111-1111-1111-111111111111', 'type': 'NFS', 'master_v= er': 6059, 'lver': 8}, 'dominfo': {'14368440-9620-423d-b816-a3d42a1b50d7'= : {'status': 'Active', 'diskfree': '284985524224', 'alerts': [], 'disktot= al': '3996801957888'}, '5de36f78-8303-401b-b6c3-e04091077f9e': {'status':= 'Attached'}, '2563b7db-bbca-44f4-ba68-98a516bae14b': {'status': 'Active'= , 'diskfree': '284985524224', 'alerts': [], 'disktotal': '3996801957888'}= , '75b7f4bc-1c46-4847-ba1b-2bbc84c771ce': {'status': 'Active', 'diskfree'= : '284985524224', 'alerts': [], 'disktotal': '3996801957888'}}} Thread-1190096::DEBUG::2012-08-14 13:43:51,625::task::588::TaskManager.Ta= sk::(_updateState) Task=3D`591249eb-49e5-4488-85f3-3298c0517509`::moving = from state preparing -> state finished Thread-1190096::DEBUG::2012-08-14 13:43:51,625::resourceManager::809::Res= ourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {= 'Storage.64a0f560-b9a1-4c87-8a30-0967b4ce3c81': < ResourceRef 'Storage.64= a0f560-b9a1-4c87-8a30-0967b4ce3c81', isValid: 'True' obj: 'None'>} Thread-1190096::DEBUG::2012-08-14 13:43:51,625::resourceManager::844::Res= ourceManager.Owner::(cancelAll) Owner.cancelAll requests {} Thread-1190096::DEBUG::2012-08-14 13:43:51,625::resourceManager::538::Res= ourceManager::(releaseResource) Trying to release resource 'Storage.64a0f= 560-b9a1-4c87-8a30-0967b4ce3c81' Thread-1190096::DEBUG::2012-08-14 13:43:51,626::resourceManager::553::Res= ourceManager::(releaseResource) Released resource 'Storage.64a0f560-b9a1-= 4c87-8a30-0967b4ce3c81' (0 active users) Thread-1190096::DEBUG::2012-08-14 13:43:51,626::resourceManager::558::Res= ourceManager::(releaseResource) Resource 'Storage.64a0f560-b9a1-4c87-8a30= -0967b4ce3c81' is free, finding out if anyone is waiting for it. Thread-1190096::DEBUG::2012-08-14 13:43:51,626::resourceManager::565::Res= ourceManager::(releaseResource) No one is waiting for resource 'Storage.6= 4a0f560-b9a1-4c87-8a30-0967b4ce3c81', Clearing records. Thread-1190096::DEBUG::2012-08-14 13:43:51,626::task::978::TaskManager.Ta= sk::(_decref) Task=3D`591249eb-49e5-4488-85f3-3298c0517509`::ref 0 aborti= ng False Thread-1190099::DEBUG::2012-08-14 13:43:55,576::BindingXMLRPC::160::vds::= (wrapper) [10.10.1.105] Thread-1190099::DEBUG::2012-08-14 13:43:55,576::task::588::TaskManager.Ta= sk::(_updateState) Task=3D`89856844-3e05-4750-929e-6639bdf346d6`::moving = from state init -> state preparing Thread-1190099::INFO::2012-08-14 13:43:55,577::logUtils::37::dispatcher::= (wrapper) Run and protect: validateStorageServerConnection(domType=3D1, s= pUUID=3D'00000000-0000-0000-0000-000000000000', conList=3D[{'connection':= '10.10.6.107:/md2/oVirt/data-win', 'iqn': '', 'portal': '', 'user': '', = 'password': '******', 'id': '0157c4d0-9d74-42c9-ae0b-b3d8fa1fa945', 'port= ': ''}], options=3DNone) Thread-1190099::INFO::2012-08-14 13:43:55,577::logUtils::39::dispatcher::= (wrapper) Run and protect: validateStorageServerConnection, Return respon= se: {'statuslist': [{'status': 0, 'id': '0157c4d0-9d74-42c9-ae0b-b3d8fa1f= a945'}]} Thread-1190099::DEBUG::2012-08-14 13:43:55,577::task::1172::TaskManager.T= ask::(prepare) Task=3D`89856844-3e05-4750-929e-6639bdf346d6`::finished: {= 'statuslist': [{'status': 0, 'id': '0157c4d0-9d74-42c9-ae0b-b3d8fa1fa945'= }]} Thread-1190099::DEBUG::2012-08-14 13:43:55,577::task::588::TaskManager.Ta= sk::(_updateState) Task=3D`89856844-3e05-4750-929e-6639bdf346d6`::moving = from state preparing -> state finished Thread-1190099::DEBUG::2012-08-14 13:43:55,577::resourceManager::809::Res= ourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {= } Thread-1190099::DEBUG::2012-08-14 13:43:55,577::resourceManager::844::Res= ourceManager.Owner::(cancelAll) Owner.cancelAll requests {} Thread-1190099::DEBUG::2012-08-14 13:43:55,577::task::978::TaskManager.Ta= sk::(_decref) Task=3D`89856844-3e05-4750-929e-6639bdf346d6`::ref 0 aborti= ng False Thread-1190100::DEBUG::2012-08-14 13:43:55,590::BindingXMLRPC::160::vds::= (wrapper) [10.10.1.105] Thread-1190100::DEBUG::2012-08-14 13:43:55,590::task::588::TaskManager.Ta= sk::(_updateState) Task=3D`8f211b90-78f7-4493-9dac-d48899d844f2`::moving = from state init -> state preparing Thread-1190100::INFO::2012-08-14 13:43:55,590::logUtils::37::dispatcher::= (wrapper) Run and protect: connectStorageServer(domType=3D1, spUUID=3D'00= 000000-0000-0000-0000-000000000000', conList=3D[{'connection': '10.10.6.1= 07:/md2/oVirt/data-win', 'iqn': '', 'portal': '', 'user': '', 'password':= '******', 'id': '0157c4d0-9d74-42c9-ae0b-b3d8fa1fa945', 'port': ''}], op= tions=3DNone) Thread-1190100::DEBUG::2012-08-14 13:43:55,593::lvm::459::OperationMutex:= :(_invalidateAllPvs) Operation 'lvm invalidate operation' got the operati= on mutex Thread-1190100::DEBUG::2012-08-14 13:43:55,593::lvm::461::OperationMutex:= :(_invalidateAllPvs) Operation 'lvm invalidate operation' released the op= eration mutex Thread-1190100::DEBUG::2012-08-14 13:43:55,593::lvm::471::OperationMutex:= :(_invalidateAllVgs) Operation 'lvm invalidate operation' got the operati= on mutex Thread-1190100::DEBUG::2012-08-14 13:43:55,594::lvm::473::OperationMutex:= :(_invalidateAllVgs) Operation 'lvm invalidate operation' released the op= eration mutex Thread-1190100::DEBUG::2012-08-14 13:43:55,594::lvm::492::OperationMutex:= :(_invalidateAllLvs) Operation 'lvm invalidate operation' got the operati= on mutex Thread-1190100::DEBUG::2012-08-14 13:43:55,594::lvm::494::OperationMutex:= :(_invalidateAllLvs) Operation 'lvm invalidate operation' released the op= eration mutex Thread-1190100::INFO::2012-08-14 13:43:55,594::logUtils::39::dispatcher::= (wrapper) Run and protect: connectStorageServer, Return response: {'statu= slist': [{'status': 0, 'id': '0157c4d0-9d74-42c9-ae0b-b3d8fa1fa945'}]} Thread-1190100::DEBUG::2012-08-14 13:43:55,594::task::1172::TaskManager.T= ask::(prepare) Task=3D`8f211b90-78f7-4493-9dac-d48899d844f2`::finished: {= 'statuslist': [{'status': 0, 'id': '0157c4d0-9d74-42c9-ae0b-b3d8fa1fa945'= }]} Thread-1190100::DEBUG::2012-08-14 13:43:55,594::task::588::TaskManager.Ta= sk::(_updateState) Task=3D`8f211b90-78f7-4493-9dac-d48899d844f2`::moving = from state preparing -> state finished Thread-1190100::DEBUG::2012-08-14 13:43:55,595::resourceManager::809::Res= ourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {= } Thread-1190100::DEBUG::2012-08-14 13:43:55,595::resourceManager::844::Res= ourceManager.Owner::(cancelAll) Owner.cancelAll requests {} Thread-1190100::DEBUG::2012-08-14 13:43:55,595::task::978::TaskManager.Ta= sk::(_decref) Task=3D`8f211b90-78f7-4493-9dac-d48899d844f2`::ref 0 aborti= ng False Thread-1190101::DEBUG::2012-08-14 13:43:55,609::BindingXMLRPC::160::vds::= (wrapper) [10.10.1.105] Thread-1190101::DEBUG::2012-08-14 13:43:55,609::task::588::TaskManager.Ta= sk::(_updateState) Task=3D`2bb8c3bd-3115-414c-9e07-0a5464949c56`::moving = from state init -> state preparing Thread-1190101::INFO::2012-08-14 13:43:55,609::logUtils::37::dispatcher::= (wrapper) Run and protect: activateStorageDomain(sdUUID=3D'5de36f78-8303-= 401b-b6c3-e04091077f9e', spUUID=3D'64a0f560-b9a1-4c87-8a30-0967b4ce3c81',= options=3DNone) Thread-1190101::DEBUG::2012-08-14 13:43:55,610::resourceManager::175::Res= ourceManager.Request::(__init__) ResName=3D`Storage.64a0f560-b9a1-4c87-8a= 30-0967b4ce3c81`ReqID=3D`58d3fbf6-ea11-4f8e-adbc-5c9126ef24c1`::Request w= as made in '/usr/share/vdsm/storage/resourceManager.py' line '485' at 're= gisterResource' Thread-1190101::DEBUG::2012-08-14 13:43:55,610::resourceManager::486::Res= ourceManager::(registerResource) Trying to register resource 'Storage.64a= 0f560-b9a1-4c87-8a30-0967b4ce3c81' for lock type 'exclusive' Thread-1190101::DEBUG::2012-08-14 13:43:55,610::resourceManager::528::Res= ourceManager::(registerResource) Resource 'Storage.64a0f560-b9a1-4c87-8a3= 0-0967b4ce3c81' is free. Now locking as 'exclusive' (1 active user) Thread-1190101::DEBUG::2012-08-14 13:43:55,610::resourceManager::212::Res= ourceManager.Request::(grant) ResName=3D`Storage.64a0f560-b9a1-4c87-8a30-= 0967b4ce3c81`ReqID=3D`58d3fbf6-ea11-4f8e-adbc-5c9126ef24c1`::Granted requ= est Thread-1190101::DEBUG::2012-08-14 13:43:55,611::task::817::TaskManager.Ta= sk::(resourceAcquired) Task=3D`2bb8c3bd-3115-414c-9e07-0a5464949c56`::_re= sourcesAcquired: Storage.64a0f560-b9a1-4c87-8a30-0967b4ce3c81 (exclusive)= Thread-1190101::DEBUG::2012-08-14 13:43:55,611::task::978::TaskManager.Ta= sk::(_decref) Task=3D`2bb8c3bd-3115-414c-9e07-0a5464949c56`::ref 1 aborti= ng False Thread-1190101::DEBUG::2012-08-14 13:43:55,611::resourceManager::175::Res= ourceManager.Request::(__init__) ResName=3D`Storage.5de36f78-8303-401b-b6= c3-e04091077f9e`ReqID=3D`3305116b-a5f4-4354-b6f2-3357b285c14c`::Request w= as made in '/usr/share/vdsm/storage/resourceManager.py' line '485' at 're= gisterResource' Thread-1190101::DEBUG::2012-08-14 13:43:55,612::resourceManager::486::Res= ourceManager::(registerResource) Trying to register resource 'Storage.5de= 36f78-8303-401b-b6c3-e04091077f9e' for lock type 'exclusive' Thread-1190101::DEBUG::2012-08-14 13:43:55,612::resourceManager::528::Res= ourceManager::(registerResource) Resource 'Storage.5de36f78-8303-401b-b6c= 3-e04091077f9e' is free. Now locking as 'exclusive' (1 active user) Thread-1190101::DEBUG::2012-08-14 13:43:55,612::resourceManager::212::Res= ourceManager.Request::(grant) ResName=3D`Storage.5de36f78-8303-401b-b6c3-= e04091077f9e`ReqID=3D`3305116b-a5f4-4354-b6f2-3357b285c14c`::Granted requ= est Thread-1190101::DEBUG::2012-08-14 13:43:55,612::task::817::TaskManager.Ta= sk::(resourceAcquired) Task=3D`2bb8c3bd-3115-414c-9e07-0a5464949c56`::_re= sourcesAcquired: Storage.5de36f78-8303-401b-b6c3-e04091077f9e (exclusive)= Thread-1190101::DEBUG::2012-08-14 13:43:55,612::task::978::TaskManager.Ta= sk::(_decref) Task=3D`2bb8c3bd-3115-414c-9e07-0a5464949c56`::ref 1 aborti= ng False Thread-1190101::INFO::2012-08-14 13:43:55,613::sp::986::Storage.StoragePo= ol::(activateSD) sdUUID=3D5de36f78-8303-401b-b6c3-e04091077f9e spUUID=3D6= 4a0f560-b9a1-4c87-8a30-0967b4ce3c81 Thread-1190101::DEBUG::2012-08-14 13:43:55,613::misc::1053::SamplingMetho= d::(__call__) Trying to enter sampling method (storage.sdc.refreshStorage= ) Thread-1190101::DEBUG::2012-08-14 13:43:55,613::misc::1055::SamplingMetho= d::(__call__) Got in to sampling method Thread-1190101::DEBUG::2012-08-14 13:43:55,613::misc::1053::SamplingMetho= d::(__call__) Trying to enter sampling method (storage.iscsi.rescan) Thread-1190101::DEBUG::2012-08-14 13:43:55,613::misc::1055::SamplingMetho= d::(__call__) Got in to sampling method Thread-1190101::DEBUG::2012-08-14 13:43:55,613::__init__::1164::Storage.M= isc.excCmd::(_log) '/usr/bin/sudo -n /sbin/iscsiadm -m session -R' (cwd N= one) Thread-1190101::DEBUG::2012-08-14 13:43:55,633::__init__::1164::Storage.M= isc.excCmd::(_log) FAILED: <err> =3D 'iscsiadm: No session found.\n'; <rc= pUUID=3D'00000000-0000-0000-0000-000000000000', conList=3D[{'connection':= '10.10.6.107:/md2/oVirt/data-win', 'iqn': '', 'portal': '', 'user': '', = 'password': '******', 'id': '0157c4d0-9d74-42c9-ae0b-b3d8fa1fa945', 'port= ': ''}], options=3DNone) Thread-1190107::INFO::2012-08-14 13:43:58,413::logUtils::39::dispatcher::= (wrapper) Run and protect: validateStorageServerConnection, Return respon= se: {'statuslist': [{'status': 0, 'id': '0157c4d0-9d74-42c9-ae0b-b3d8fa1f= a945'}]} Thread-1190107::DEBUG::2012-08-14 13:43:58,413::task::1172::TaskManager.T= ask::(prepare) Task=3D`ec537973-a494-4aa7-aed7-a7b926da824c`::finished: {= 'statuslist': [{'status': 0, 'id': '0157c4d0-9d74-42c9-ae0b-b3d8fa1fa945'= }]} Thread-1190107::DEBUG::2012-08-14 13:43:58,413::task::588::TaskManager.Ta= sk::(_updateState) Task=3D`ec537973-a494-4aa7-aed7-a7b926da824c`::moving = from state preparing -> state finished Thread-1190107::DEBUG::2012-08-14 13:43:58,413::resourceManager::809::Res= ourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {= } Thread-1190107::DEBUG::2012-08-14 13:43:58,413::resourceManager::844::Res= ourceManager.Owner::(cancelAll) Owner.cancelAll requests {} Thread-1190107::DEBUG::2012-08-14 13:43:58,413::task::978::TaskManager.Ta= sk::(_decref) Task=3D`ec537973-a494-4aa7-aed7-a7b926da824c`::ref 0 aborti= ng False Thread-1190108::DEBUG::2012-08-14 13:43:58,452::BindingXMLRPC::160::vds::= (wrapper) [10.10.1.105] Thread-1190108::DEBUG::2012-08-14 13:43:58,453::task::588::TaskManager.Ta= sk::(_updateState) Task=3D`57ba347b-83aa-474f-8486-040613e335ac`::moving = from state init -> state preparing Thread-1190108::INFO::2012-08-14 13:43:58,453::logUtils::37::dispatcher::= (wrapper) Run and protect: connectStorageServer(domType=3D1, spUUID=3D'00= 000000-0000-0000-0000-000000000000', conList=3D[{'connection': '10.10.6.1= 07:/md2/oVirt/data-win', 'iqn': '', 'portal': '', 'user': '', 'password':= '******', 'id': '0157c4d0-9d74-42c9-ae0b-b3d8fa1fa945', 'port': ''}], op= tions=3DNone) Thread-1190108::DEBUG::2012-08-14 13:43:58,455::lvm::459::OperationMutex:= :(_invalidateAllPvs) Operation 'lvm invalidate operation' got the operati= on mutex Thread-1190108::DEBUG::2012-08-14 13:43:58,456::lvm::461::OperationMutex:= :(_invalidateAllPvs) Operation 'lvm invalidate operation' released the op= eration mutex Thread-1190108::DEBUG::2012-08-14 13:43:58,456::lvm::471::OperationMutex:= :(_invalidateAllVgs) Operation 'lvm invalidate operation' got the operati= on mutex Thread-1190108::DEBUG::2012-08-14 13:43:58,456::lvm::473::OperationMutex:= :(_invalidateAllVgs) Operation 'lvm invalidate operation' released the op= eration mutex Thread-1190108::DEBUG::2012-08-14 13:43:58,456::lvm::492::OperationMutex:= :(_invalidateAllLvs) Operation 'lvm invalidate operation' got the operati= on mutex Thread-1190108::DEBUG::2012-08-14 13:43:58,457::lvm::494::OperationMutex:= :(_invalidateAllLvs) Operation 'lvm invalidate operation' released the op= eration mutex Thread-1190108::INFO::2012-08-14 13:43:58,457::logUtils::39::dispatcher::= (wrapper) Run and protect: connectStorageServer, Return response: {'statu= slist': [{'status': 0, 'id': '0157c4d0-9d74-42c9-ae0b-b3d8fa1fa945'}]} Thread-1190108::DEBUG::2012-08-14 13:43:58,457::task::1172::TaskManager.T= ask::(prepare) Task=3D`57ba347b-83aa-474f-8486-040613e335ac`::finished: {= 'statuslist': [{'status': 0, 'id': '0157c4d0-9d74-42c9-ae0b-b3d8fa1fa945'= }]} Thread-1190108::DEBUG::2012-08-14 13:43:58,457::task::588::TaskManager.Ta= sk::(_updateState) Task=3D`57ba347b-83aa-474f-8486-040613e335ac`::moving = from state preparing -> state finished Thread-1190108::DEBUG::2012-08-14 13:43:58,457::resourceManager::809::Res= ourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {= } Thread-1190108::DEBUG::2012-08-14 13:43:58,457::resourceManager::844::Res= ourceManager.Owner::(cancelAll) Owner.cancelAll requests {} Thread-1190108::DEBUG::2012-08-14 13:43:58,458::task::978::TaskManager.Ta= sk::(_decref) Task=3D`57ba347b-83aa-474f-8486-040613e335ac`::ref 0 aborti= ng False Thread-1190109::DEBUG::2012-08-14 13:43:58,469::BindingXMLRPC::160::vds::= (wrapper) [10.10.1.105] Thread-1190109::DEBUG::2012-08-14 13:43:58,469::task::588::TaskManager.Ta= sk::(_updateState) Task=3D`e80af100-65a9-4c1e-8518-c7172ef80efe`::moving = from state init -> state preparing Thread-1190109::INFO::2012-08-14 13:43:58,469::logUtils::37::dispatcher::= (wrapper) Run and protect: activateStorageDomain(sdUUID=3D'5de36f78-8303-= 401b-b6c3-e04091077f9e', spUUID=3D'64a0f560-b9a1-4c87-8a30-0967b4ce3c81',= options=3DNone) Thread-1190109::DEBUG::2012-08-14 13:43:58,470::resourceManager::175::Res= ourceManager.Request::(__init__) ResName=3D`Storage.64a0f560-b9a1-4c87-8a= 30-0967b4ce3c81`ReqID=3D`01084267-8059-4872-92f1-def68ff78c5f`::Request w= as made in '/usr/share/vdsm/storage/resourceManager.py' line '485' at 're= gisterResource' Thread-1190109::DEBUG::2012-08-14 13:43:58,470::resourceManager::486::Res= ourceManager::(registerResource) Trying to register resource 'Storage.64a= 0f560-b9a1-4c87-8a30-0967b4ce3c81' for lock type 'exclusive' Thread-1190109::DEBUG::2012-08-14 13:43:58,470::resourceManager::528::Res= ourceManager::(registerResource) Resource 'Storage.64a0f560-b9a1-4c87-8a3= 0-0967b4ce3c81' is free. Now locking as 'exclusive' (1 active user) Thread-1190109::DEBUG::2012-08-14 13:43:58,471::resourceManager::212::Res= ourceManager.Request::(grant) ResName=3D`Storage.64a0f560-b9a1-4c87-8a30-= 0967b4ce3c81`ReqID=3D`01084267-8059-4872-92f1-def68ff78c5f`::Granted requ= est Thread-1190109::DEBUG::2012-08-14 13:43:58,471::task::817::TaskManager.Ta= sk::(resourceAcquired) Task=3D`e80af100-65a9-4c1e-8518-c7172ef80efe`::_re= sourcesAcquired: Storage.64a0f560-b9a1-4c87-8a30-0967b4ce3c81 (exclusive)= Thread-1190109::DEBUG::2012-08-14 13:43:58,471::task::978::TaskManager.Ta= sk::(_decref) Task=3D`e80af100-65a9-4c1e-8518-c7172ef80efe`::ref 1 aborti= ng False Thread-1190109::DEBUG::2012-08-14 13:43:58,472::resourceManager::175::Res= ourceManager.Request::(__init__) ResName=3D`Storage.5de36f78-8303-401b-b6= c3-e04091077f9e`ReqID=3D`5fcab071-1d80-4a5d-855d-c57aa7daf8e9`::Request w= as made in '/usr/share/vdsm/storage/resourceManager.py' line '485' at 're= gisterResource' Thread-1190109::DEBUG::2012-08-14 13:43:58,472::resourceManager::486::Res= ourceManager::(registerResource) Trying to register resource 'Storage.5de= 36f78-8303-401b-b6c3-e04091077f9e' for lock type 'exclusive' Thread-1190109::DEBUG::2012-08-14 13:43:58,472::resourceManager::528::Res= ourceManager::(registerResource) Resource 'Storage.5de36f78-8303-401b-b6c= 3-e04091077f9e' is free. Now locking as 'exclusive' (1 active user) Thread-1190109::DEBUG::2012-08-14 13:43:58,472::resourceManager::212::Res= ourceManager.Request::(grant) ResName=3D`Storage.5de36f78-8303-401b-b6c3-= e04091077f9e`ReqID=3D`5fcab071-1d80-4a5d-855d-c57aa7daf8e9`::Granted requ= est Thread-1190109::DEBUG::2012-08-14 13:43:58,473::task::817::TaskManager.Ta= sk::(resourceAcquired) Task=3D`e80af100-65a9-4c1e-8518-c7172ef80efe`::_re= sourcesAcquired: Storage.5de36f78-8303-401b-b6c3-e04091077f9e (exclusive)= Thread-1190109::DEBUG::2012-08-14 13:43:58,473::task::978::TaskManager.Ta= sk::(_decref) Task=3D`e80af100-65a9-4c1e-8518-c7172ef80efe`::ref 1 aborti= ng False Thread-1190109::INFO::2012-08-14 13:43:58,473::sp::986::Storage.StoragePo= ol::(activateSD) sdUUID=3D5de36f78-8303-401b-b6c3-e04091077f9e spUUID=3D6= 4a0f560-b9a1-4c87-8a30-0967b4ce3c81 Thread-1190109::DEBUG::2012-08-14 13:43:58,473::misc::1053::SamplingMetho= d::(__call__) Trying to enter sampling method (storage.sdc.refreshStorage= ) Thread-1190109::DEBUG::2012-08-14 13:43:58,473::misc::1055::SamplingMetho= d::(__call__) Got in to sampling method Thread-1190109::DEBUG::2012-08-14 13:43:58,473::misc::1053::SamplingMetho= d::(__call__) Trying to enter sampling method (storage.iscsi.rescan) Thread-1190109::DEBUG::2012-08-14 13:43:58,474::misc::1055::SamplingMetho= d::(__call__) Got in to sampling method Thread-1190109::DEBUG::2012-08-14 13:43:58,474::__init__::1164::Storage.M= isc.excCmd::(_log) '/usr/bin/sudo -n /sbin/iscsiadm -m session -R' (cwd N= one) Thread-1190109::DEBUG::2012-08-14 13:43:58,494::__init__::1164::Storage.M= isc.excCmd::(_log) FAILED: <err> =3D 'iscsiadm: No session found.\n'; <rc= sourcesAcquired: Storage.64a0f560-b9a1-4c87-8a30-0967b4ce3c81 (shared) Thread-1190118::DEBUG::2012-08-14 13:44:01,807::task::978::TaskManager.Ta= sk::(_decref) Task=3D`109f93dd-452b-4399-aecb-787c72f2c72d`::ref 1 aborti= ng False Thread-1190118::INFO::2012-08-14 13:44:01,810::logUtils::39::dispatcher::= (wrapper) Run and protect: getStoragePoolInfo, Return response: {'info': = {'spm_id': 1, 'master_uuid': '75b7f4bc-1c46-4847-ba1b-2bbc84c771ce', 'nam= e': 'Default-30', 'version': '0', 'domains': '14368440-9620-423d-b816-a3d= 42a1b50d7:Active,5de36f78-8303-401b-b6c3-e04091077f9e:Attached,2563b7db-b= bca-44f4-ba68-98a516bae14b:Active,75b7f4bc-1c46-4847-ba1b-2bbc84c771ce:Ac= tive', 'pool_status': 'connected', 'isoprefix': '/rhev/data-center/64a0f5= 60-b9a1-4c87-8a30-0967b4ce3c81/14368440-9620-423d-b816-a3d42a1b50d7/image= s/11111111-1111-1111-1111-111111111111', 'type': 'NFS', 'master_ver': 605= 9, 'lver': 8}, 'dominfo': {'14368440-9620-423d-b816-a3d42a1b50d7': {'stat= us': 'Active', 'diskfree': '284985524224', 'alerts': [], 'disktotal': '39= 96801957888'}, '5de36f78-8303-401b-b6c3-e04091077f9e': {'status': 'Attach= ed'}, '2563b7db-bbca-44f4-ba68-98a516bae14b': {'status': 'Active', 'diskf= ree': '284985524224', 'alerts': [], 'disktotal': '3996801957888'}, '75b7f= 4bc-1c46-4847-ba1b-2bbc84c771ce': {'status': 'Active', 'diskfree': '28498= 5524224', 'alerts': [], 'disktotal': '3996801957888'}}} Thread-1190118::DEBUG::2012-08-14 13:44:01,810::task::1172::TaskManager.T= ask::(prepare) Task=3D`109f93dd-452b-4399-aecb-787c72f2c72d`::finished: {= 'info': {'spm_id': 1, 'master_uuid': '75b7f4bc-1c46-4847-ba1b-2bbc84c771c= e', 'name': 'Default-30', 'version': '0', 'domains': '14368440-9620-423d-= b816-a3d42a1b50d7:Active,5de36f78-8303-401b-b6c3-e04091077f9e:Attached,25= 63b7db-bbca-44f4-ba68-98a516bae14b:Active,75b7f4bc-1c46-4847-ba1b-2bbc84c= 771ce:Active', 'pool_status': 'connected', 'isoprefix': '/rhev/data-cente= r/64a0f560-b9a1-4c87-8a30-0967b4ce3c81/14368440-9620-423d-b816-a3d42a1b50= d7/images/11111111-1111-1111-1111-111111111111', 'type': 'NFS', 'master_v= er': 6059, 'lver': 8}, 'dominfo': {'14368440-9620-423d-b816-a3d42a1b50d7'= : {'status': 'Active', 'diskfree': '284985524224', 'alerts': [], 'disktot= al': '3996801957888'}, '5de36f78-8303-401b-b6c3-e04091077f9e': {'status':= 'Attached'}, '2563b7db-bbca-44f4-ba68-98a516bae14b': {'status': 'Active'= , 'diskfree': '284985524224', 'alerts': [], 'disktotal': '3996801957888'}= , '75b7f4bc-1c46-4847-ba1b-2bbc84c771ce': {'status': 'Active', 'diskfree'= : '284985524224', 'alerts': [], 'disktotal': '3996801957888'}}} Thread-1190118::DEBUG::2012-08-14 13:44:01,810::task::588::TaskManager.Ta= sk::(_updateState) Task=3D`109f93dd-452b-4399-aecb-787c72f2c72d`::moving = from state preparing -> state finished Thread-1190118::DEBUG::2012-08-14 13:44:01,810::resourceManager::809::Res= ourceManager.Owner::(releaseAll) Owner.releaseAll requests {} resources {= 'Storage.64a0f560-b9a1-4c87-8a30-0967b4ce3c81': < ResourceRef 'Storage.64= a0f560-b9a1-4c87-8a30-0967b4ce3c81', isValid: 'True' obj: 'None'>} Thread-1190118::DEBUG::2012-08-14 13:44:01,810::resourceManager::844::Res= ourceManager.Owner::(cancelAll) Owner.cancelAll requests {} Thread-1190118::DEBUG::2012-08-14 13:44:01,811::resourceManager::538::Res= ourceManager::(releaseResource) Trying to release resource 'Storage.64a0f= 560-b9a1-4c87-8a30-0967b4ce3c81' Thread-1190118::DEBUG::2012-08-14 13:44:01,811::resourceManager::553::Res= ourceManager::(releaseResource) Released resource 'Storage.64a0f560-b9a1-= 4c87-8a30-0967b4ce3c81' (0 active users) Thread-1190118::DEBUG::2012-08-14 13:44:01,811::resourceManager::558::Res= ourceManager::(releaseResource) Resource 'Storage.64a0f560-b9a1-4c87-8a30= -0967b4ce3c81' is free, finding out if anyone is waiting for it. Thread-1190118::DEBUG::2012-08-14 13:44:01,811::resourceManager::565::Res= ourceManager::(releaseResource) No one is waiting for resource 'Storage.6= 4a0f560-b9a1-4c87-8a30-0967b4ce3c81', Clearing records. Thread-1190118::DEBUG::2012-08-14 13:44:01,811::task::978::TaskManager.Ta= sk::(_decref) Task=3D`109f93dd-452b-4399-aecb-787c72f2c72d`::ref 0 aborti= ng False --=_bauhaus.teknikservice.nu-24776-1344945707-0001-2 Content-Type: application/pgp-keys; name="0xB88C0B63.asc" Content-Transfer-Encoding: 7bit Content-Disposition: attachment; filename="0xB88C0B63.asc" -----BEGIN PGP PUBLIC KEY BLOCK----- Version: GnuPG v1.4.11 (GNU/Linux) mQGiBEN3nx4RBACx6yQ1m83u8PBPG6iivIWICsZJJn8JP6ccGCeS03bQpxXjk8kx V9r3pbY2lJUfu6tlVd/0G/RDC2wixLirYIkelYd5QbL8mk3JIwA96zlIxVRBThmW mig+nI189/vXTNPQKC33xm+/g7kkckD/e/jR2jGycyZiEfvmWQZRpEbnEwCgkOpp Erfa3KmTZ4mk3ulTW4Q8KaUEAJmCZBuuy/CjhxpK4addENpsl7WT90aNZbrtzFIk W1xxkEqRwP2BvNyvTv8EHFsmJehxwn9InQ5gSS2LMGKhKapfT5gMHnmyH+YEdcLj h40L64ftXIyEFenfx8UN85cEKmHqhmrYZkCoRKUeakxZaKs2JZDmytrlLLoj5vAP wV06BACvKm4eGGkTzx8jKRsZF0Y0B532KhV7tr7OhYE654cPQLdok/0exO1n6wR8 2nljL5G8lMEpB1LQp2Xdrz/6z7vbI0w1Qhxddg71EDIuRIJWADOMO6uf20Vg/Wh8 MGwXCGVtH1QZ4FY4tza5PpP6EqKcb5dqC4TyPYz0J4xpxlc5gbQ8Umlja3kgU2No bmViZXJnZXIgKGFsdGVybmF0aXZlIHdvcmstZW1haWwpIDxyaWNreUBhY3RuZXQu c2U+iGAEExECACAFAkh0smkCGyMGCwkIBwMCBBUCCAMEFgIDAQIeAQIXgAAKCRA5 qnzVuIwLY704AJ9sXKYXUMsxbEjmthggRkuPCLY8aQCdEMyWeiELV9BpWegz42cy 4WRC51u0J1JpY2sgU2NobmViZXJnZXIgPHJpY2t5QHNjaG5lYmVyZ2VyLnNlPohb BBMRAgAbBQJDd58eBgsJCAcDAgMVAgMDFgIBAh4BAheAAAoJEDmqfNW4jAtjU8YA n1t4P7UmK/MpTQ7FdtALG1Ul45x+AJwOaL66GsKKJLTfVDFvqLu+sFKj54heBBMR AgAeBgsJCAcDAgMVAgMDFgIBAh4BAheABQJNZ2orAhkBAAoJEDmqfNW4jAtjuMUA mwaHDOZF0CcAmZBopNfQJt7AZaGTAJwI+aN7wAg9VOFZcQ6W1YDAmOueOYhGBBAR AgAGBQJLmeIYAAoJEC1o4PaBggLPb68An3ds1SETjFt7FuWNR9RRxxspPZNeAJ0R kDWbt5WzBJEEEia3PmaK9zssgYhGBBARAgAGBQJDe5cHAAoJEO2/HhEm8iS4m9kA n35gnntj0uU2E7qgRb27c+XDgsGNAKCSQIrEF36S8RK8SilPLLjhnrHnJYkBIQQQ AQIADAUCQ3fQFwUDABJ1AAAKCRCXELibyletfNcVB/YyWm59UliFxj+94qpCr7DJ CeuFZE7Yn3lzwnEOU3Y7pOD1s9BwGPeO5iKej4TCT2JtsWFao1HGSztrE3SrWkc/ rg0MYTIZQc/+wbi+6fTSedZVqxf9teW3FdRQXjOZ9JohJLdU0XxT23TPuSmk0ibo lCD35hg93vEfePrC2+lACp41a2UP3VNbqUM4rL6F4PfmgyIqgY5FtTWJjNJN34Uv Tb35YIud+UkQ6eKZggKwBPfma3nwEie1S4f9FskwTGyPNyKGr8Spl2r4snwmFk/Z KP4k615ob8T8Wbza8TGeS5kYw+Za7MF+Oi+jkdXF/aZSdHBNb9pBcKafcbdEfsWJ ASIEEAECAAwFAkR/OX0FAwASdQAACgkQlxC4m8pXrXyv3wf+I+Xr2BP1+0yRPh07 yNQQBDT+LaqmRZ0sxyauABbqQzs3R2GAdm/92CuRSXz7jgBiiRd7ZlzA/wm2ApBF Q8jg9DCxSRNR8KYevTq38ZabMeU3tRkqH5rN++rTmdMbP+MtfoYtTb9+1URXuUBw Ib4NJWV3QGbSSZLJyA/evha4KBu5A16W8LLb0U9CiSAM7y2ES5Qceg1GXVMVbF6q TURf+yUxgJjSBBY3zPkTZVF9mxYWgDlzONj6tRUWThqZHtSSBoKfe63hlppLczND x0TVtuRAFGDlC5HOWF+dAAm5T/Wv9YBmjZi/mcjPvbxhXN5aNff28AMVLW6DqBPX cotg+4kBIgQQAQIADAUCRJBdEQUDABJ1AAAKCRCXELibyletfD0iB/4yljOGMCTh u3Q2XQe8RYn3qMuzUOBpdDimoU0kewdkkDoDXF1Z0uTJPA8pdq6beIv5VXqaEGc3 TKFYuX7xMO6fkWryUKf7Vdbb3xNlChQvxBQwPRNdd2B3w+tBcRgJPUJalshl0IvO DuNbasRyEWbm+LbJF8dZOe5Jr2KTLDPmWURF3faC9YzcfZGbH/Kl5W1SCAigdnzd 62JuAJ8fa/K2zITFF0XkHsXe4jnQSPHUD/sN60zTkeBQdvdjvdtqInRQEJnbJXz6 3Z3Ey3O0736rI9C7MtI9oCCa8U5ed3syPetjf8i3HuVkV8NdmgM+8Cy3MPpWGqm5 oIHDuyS1rh5tiQEiBBABAgAMBQJEoilQBQMAEnUAAAoJEJcQuJvKV618XHsIALtb J9ZVm4e2KW9C9INBLytXXkiT+SJiwcFiZpbFYWQHGckw0YAyzXTXdxK+/qwCWJWV z9lgMybf8JQ5bnFptKfnYVphT7R6s+1uzr4phdO1HHxI6p794m9bk+jFNVp4Uj67 JL7OYek2kn+ZZKf7caxOGrmjqzEN1CP8fZyP3p/ClbnH2hhV8OWrIyetP7+O4/a+ I/mHmdvDpwEiKfpHFXJOiuedKJCZ/4sZHFckdsNi8JJWK7/BZQEAlUesEVg38LV+ z7T7VV3fZRW2mbUz1qaOmZyKgUTBQkgH0zsp9OSrggiAsRUxfhqJLjqCWiiUa4Bb ScPUzM227/5TzR/f/OCJASIEEAECAAwFAkSzTN0FAwASdQAACgkQlxC4m8pXrXxe mwf+OLvUlDuw403e0R3O9mY3gzqPmQR8G2N6ze7cNQsFHQlSzsQ9HJlmbc0JmU9E OkhqLDCGtSd/hZ7hLMK8xRkfanA5kHhBWLGOd+5lqZhJ1MvRY+EhBQ5iMZlhE5Op luRGe5LiKK/esSgL5dr5AnCDl16GI7J8kxYtZcJWGXVj8jEHFyIH7s/E5RS+CJwP 3EAR643o+jtcY0Ut1977+rM18g0l9SRn/BD0AYDAHqYibNxMfVLcbT21MBwBQg/1 aXHBlGb/QmQbclKBWbwATPggc/4zo7kRizFxmVn7cEUPcyz+vA+eqsvuJO5SXZTm gcz/ZbbwVMs6VmD1WrqDir+FIokBIgQQAQIADAUCRMRwwgUDABJ1AAAKCRCXELib yletfLVuB/sGnNs1GYiwZJqiopKp+g+xDnRb4fAvy50hfnrZStaAJAHNhJgT3ekR nLvmi4WWvWZm2d/Kdk/CUuC3LptFLo+nbptbcx6y6f3ulBgyoI+Nza9+fxdv5ieb TNPi6Dk41xatmeEr2ZjQN9x41I1+Ta3pNEFL7XzbClLRtoTL9/qmSUcJtdHQ8Eux 9CD0LkUeqBgQGrH5mZgSNUMUkQJ1eoeKKsYXwXalH8ruphhiXiF3Zyl9Tc/LjLVU 5apJpTq/od/cN/F91Y+IGUX4zAZO/2qSJ9pDzsj9mOpwwTFCMy1f9f+z5It48Wig u1nGDOBqyflwSQ1K4q/+pLWaFSpP2QcDiQEiBBABAgAMBQJE1j0WBQMAEnUAAAoJ EJcQuJvKV618EVIH+gOZ35Wq0r0IwRIV37Cnx+N1MVy6YSEAhzIsEgMZwMSa7u08 8aGHEBvsP8D98Yh33v7Se2g23Ce5pWPYp65gskwxCD6v2K4uCEuJNcxLBq9cIHJv DOuSLnbqVDomzT1qrrVaFiiLrTOMvqHpa4goxbVpjM8m1Yjpqwy6/vs92dYFVlpM zBIpgcN+bDqUuwoHHemCc2h2Sc6EzcGxzOoHvPboo1lUi6JknUaQaJbUx5VkBEiz t3oeV6f1yzvfWwmwWQ7TTHeBqvGSJwZhoyAIWxdP1Cw9GVgq3tSZyEAyNF4ODNic qudk53jotsUIC3mJK3TWDWlXGNScu/Ffc6KexgiJASIEEAECAAwFAkToCVUFAwAS dQAACgkQlxC4m8pXrXzyaAf8ChDiv1O1z4Oo7QHcEYP4HXWxx1sDICPzH2oIec2J 9qCIhTlIbKn7y2Og5CfUYvCPqiQOoxn+pYZm+jL1BHvQwYcKdxKnuMmE/OaLzzPv MlskF2HhRxviN+nFYxWtc+WXNJX+GYJKT23b82P08NZisP/qLnILRur4YV0X3jd9 GbeYGflDdVAKlzsgJo3wWfBYVTVMzmlCeV5ZdE9V9Z1lQht2foD0KiXb+jGcWjFD fMNK2bhofx/f/eowpFXVKuvQTLFxA3od77ngSnUVCoSXb4yF6hFLhfnMYvIdG4MI rRIMWm65hBZa6YuEk1UC3FvPK0PCz2gvUI69J5SkkOpdfYkBIgQQAQIADAUCRPnV igUDABJ1AAAKCRCXELibyletfEkOB/43lBTeg7oa6mDFJ3aL2u/RnzbIb0YYfMUm 1RCQe/3/EMIh+MxlTfbU6UBuc0nzXmdalyVOu1aB5+GbDMi7cKMPMcknd7ItrrmI JNevcSjcXpCsESHjMcUdB1eUoq7VCirIes1+wLeilszDnNUVcC8FI0P9pAyki+w1 +YzyJX8jupnSOwYcqKuq8+AiLsoavKSvjukfJCMTjEtsCjOb2Z/hTBf37+xBVCKW NIQR5Xjjp6d8bO9xrqv/PvjLzuhKYDUAM4PVVb9NXDvLAWBF2xKDxBUjpFZiln+/ ic4YpyMAFiH34moPIWM+LSJ0y826gQybpMqXvP8wU4y2PxtyajmpiQEiBBABAgAM BQJFC6GOBQMAEnUAAAoJEJcQuJvKV618jdgH/2GHXwcE11zfI0Lmn0mLSawcb76b n+wAZEohxWjxoGAjMZdEsuyFDJP/PnkAAT/5MzgyquxGF1+/WnhtEtsm54hqw44i 8/4fwbII7XZk85h+y3srmipOhhThqnmY8PkSz/+1qaq9UzifeqG82mJ16Y0QwG9J tgbPntr11zN0h1+RWpZVnl2I9bFeZSDDV3AltYkmzkhtTa90AaA78jKanvsvfHyr Scr+thgEO2bqJa5LAmtnQ9+Rg+mSSa7eJMhh1CzafOD3AaW9ebQIpkwqSz9VuBga y2y07NUyULp2uSXQW/uVVH+fcrxNGn6E3WnzoLpEOpzVVZ7vFvxhTczu+YGJASIE EAECAAwFAkV0fvAFAwASdQAACgkQlxC4m8pXrXy0bwf/YTPtF8P3WZC2YrVms3+v WzdcreVa0anypXliwNrbZsqI/tUZF8LdNu3echo5kyJM8NVdqhIMU2vNB8Xo1Y8B bE0U+hIWyv7TBl+CYru5HC8RFAvC69AYQbOLJIMsp/DqFLG8JuryxIh4zcRwNHHr zSyAhq20O2Yn2v47kvl/fadsaMBt4YLXoLphJn7MRD2Je1qsCBjJzinNewq7Tf+y fMSSz3b7GV3r5jOdTx5T8WWda+H/pgGLXG050nlZmmrmBFQJS85Jal4mTBpgKwLJ SBG6HrUkmQ/CqiAlxWpmpL88wAtKBr9drCjJ/9pp1Na4onNA14gZKWqSm6ZvHPG4 KYkBIgQQAQIADAUCRYWijAUDABJ1AAAKCRCXELibyletfOvCCACtsRTvZw621Zal RBHMsT1JAS8NRMJBkiesTi71q+/OXStQ7LSUGjSyxyir3c2ssblGT0UdZdeZLrx9 wL7618RSitxSswFAPTgStg3Exs3XqUcmgEAfQKYpIV5UZR237+Re4ViNaU6OxnxF 6DlArL02Mnq3EbfQRg33XRd1/EYk3zdMxzwMcnmBRXNnl/Ey0yJnxZ8GuXpIhRU0 3X8ZhpuCzsSReUQtfRPErQk9U6mp0r1QXr8XAetqb5ya6SVaZfO4gfHjIzpyQg3T SVhwPQOL+nxxEz2EADHk7LQF/ZoGP3L2s++T/HETnlUmXCyCcFn+7nV3nTYUorPo iVnG97pdiQEiBBABAgAMBQJFlsYRBQMAEnUAAAoJEJcQuJvKV618uqcIAKSeEor3 dw3DLmEOUQO7BM2vv2UBFdnDEQKhbsMIJsTtqZ3xBBOlbY3g6eym8qvucS4XSTwi M4y1dJfLBsaT0q2oggla9320ysEaCRrJ1Sm+li8aZwjo8T0UfUGx8LQh53wvbP+i mvH9Utzlo+RD2wrTyvyvxS6tK4wYuF08d44AAopSspgZD/d2Gf0E7WOKP4kQRcA0 7luXzkG7XdFOVmLL81GJEXkMiLSmMdIsmG4Ljw2YyIiTZ9APsV1o3iOKPlEATL4c UnOYzV/5En7jlv95/m2wNYU442Ewm811QzuKdE/OsoQqnh1z9QwtcswWpwDIvr3s SzJAck9BpmEg96eIWwQTEQIAGwUCQ3efHgYLCQgHAwIDFQIDAxYCAQIeAQIXgAAK CRA5qnzVuIwLY1PGAKCF953pK4A/YN0+vXWqFKItF1Uj1QCeMwYzWiiZYJsnmyjj 8/cCta4YC6y0JFJpY2t5IFNjaG5lYmVyZ2VyIDxyaWNreUBhY3QyMDAwLnNlPohg BBMRAgAgBQJFtoMUAhsjBgsJCAcDAgQVAggDBBYCAwECHgECF4AACgkQOap81biM C2MMIQCfYBqhJT3nKJyimLZHNiqxklmM698An0xcrcoX/ltrAvTXZzipABm1Wn4I tCpSaWNreSBTY2huZWJlcmdlciA8cmlja3lAdGVrbmlrc2VydmljZS5udT6IYAQT EQIAIAUCRbaDKAIbIwYLCQgHAwIEFQIIAwQWAgMBAh4BAheAAAoJEDmqfNW4jAtj OaYAoI+lG1KAFfylizmJ8Qee1dPjWurqAJ9aFY/iU7RA52dMXEb0PlnqyHAP3LQr Umlja3kgU2NobmViZXJnZXIgPHJpY2t5QG1qb2xrY2VudHJhbGVuLnNlPohgBBMR AgAgBQJGWBznAhsjBgsJCAcDAgQVAggDBBYCAwECHgECF4AACgkQOap81biMC2Mw UQCdHjzBE528RJ7ZD/WU7Ht5WjZsq2YAnAs6DOHZXtWSsFWv13j9brOEWzWPuQIN BEN3n0kQCAC585+HR4V/5M8/HHb6uRUdLhBzkVjWCiB7eln3E50HRnTfberPbAvK ZZ9Ske4OS90I5PgGj4zjDleHX8/pGXgaSV+8CwFk9eDqoLKzFKvVJaSp8CX1JrO2 975oUNpFqOy2ejX0jXvphBJIquvBInjOPBpGOkRHIMN9p0rlVhMNywo+t8hEj9PI nupnUqEUziZONSURPPH5J4yYHl0TLkcRNd2OvnDXgdF5ja+FMB5DhC6gOyylhCGU 8271KAYmE8T3TSFWM+sT6eXJSkMxDmTwQJsFVyNFKrSSukEljyraiZjY9xOj3G7A tnqu8PVH968fK3jI5Ee0jfGZeDwduJovAAQNB/4rpdE7z3Xa37J+cGwOKrjA9A13 FwoHYa3i1KdfnU2WcxkIRh+BTzjvhdHLzLu2IBHFpGxCrJjO096xR1axhM901k/Q irLaafqf/jt7QCFcC+kKxVk3rN1RWpfHeJxV62q4szS0k0CU8WHAeaF6/JmKnNq0 CNADBFj9XnQrrPV7RmwbF56cFDOqZpZG0BvWWrhdHOOgoi0Cz24rkMrP47IP9TTP u64MZVflCJlEsBFOI1Qpf5XvW0GLnLeGrZfY+qTDwP8c0YoVsq3KfWGMLkc5lL7i snA69d7GPmHpGzm5QqYvJAcoTwKvIHaqyx/JgYHV75imrRlsKKigcRdKrcQqiEYE GBECAAYFAkN3n0kACgkQOap81biMC2NbzQCfXZJckZnUcuURLggeNvttInRgdloA nAhULJ90SW2EyHG05qqI/iOeskY4 =A0DR -----END PGP PUBLIC KEY BLOCK----- --=_bauhaus.teknikservice.nu-24776-1344945707-0001-2--

Ricky, Try do the following : go to the meta data of the data storage (in the storage server go to {storage_domain_name}/######..../dom_md/metadata) delete the chksum line _SHA_CKSUM=################ try to activate the storage domain again the DC (it should fail again) vdsm.log should print the computed cksum of the storage domain (Should be an error there which say "Meta Data seal is broken (checksum mismatch).... computed_cksum = ") copy the comuted chksum to the meta data (_SHA_CKSUM={new chksum number} try to activate it again. Hope it will fix that. Mean while I'm checking what caused the bug. Regards, Maor On 08/14/2012 02:53 PM, Ricky Schneberger wrote:
Hi
I attached the logs.
//Ricky
On 2012-08-14 12:56, Maor Lipchuk wrote:
Hi Ricky can you please add the log of VDSM, also the full engine log.
Regards, Maor
On 08/14/2012 01:22 PM, Ricky Schneberger wrote:
After an normal "yum update" i am unable to get one of the storage domains "UP".
My systems is running Centos 6.3.
If I try to activate the domain in GUI I got following errors:
---------------------------------
2012-08-14 12:16:22,594 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-5-thread-50) [539cbd7e] Lock Acquired to object EngineLock [exclusiveLocks= key: org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand value: 5de36f78-8303-401b-b6c3-e04091077f9e 2012-08-14 12:16:22,610 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-5-thread-50) [539cbd7e] Running command: ActivateStorageDomainCommand internal: false. Entities affected : ID: 5de36f78-8303-401b-b6c3-e04091077f9e Type: Storage 2012-08-14 12:16:22,618 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-5-thread-50) [539cbd7e] Lock freed to object EngineLock [exclusiveLocks= key: org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand value: 5de36f78-8303-401b-b6c3-e04091077f9e 2012-08-14 12:16:22,709 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.ActivateStorageDomainVDSCommand] (pool-5-thread-50) [539cbd7e] START, ActivateStorageDomainVDSCommand(storagePoolId = 64a0f560-b9a1-4c87-8a30-0967b4ce3c81, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 5de36f78-8303-401b-b6c3-e04091077f9e), log id: 152cc8c3 2012-08-14 12:16:25,050 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] (pool-5-thread-50) [539cbd7e] Error code StorageDomainDoesNotExist and error message IRSGenericException: IRSErrorException: Failed to ActivateStorageDomainVDS, error = Storage domain does not exist: ('5de36f78-8303-401b-b6c3-e04091077f9e',) 2012-08-14 12:16:25,050 ERROR [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (pool-5-thread-50) [539cbd7e] IrsBroker::Failed::ActivateStorageDomainVDS due to: IRSErrorException: IRSGenericException: IRSErrorException: Failed to ActivateStorageDomainVDS, error = Storage domain does not exist: ('5de36f78-8303-401b-b6c3-e04091077f9e',) 2012-08-14 12:16:25,058 ERROR [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-5-thread-50) [539cbd7e] Command org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand throw Vdc Bll exception. With error message VdcBLLException: org.ovirt.engine.core.vdsbroker.irsbroker.IRSErrorException: IRSGenericException: IRSErrorException: Failed to ActivateStorageDomainVDS, error = Storage domain does not exist: ('5de36f78-8303-401b-b6c3-e04091077f9e',) 2012-08-14 12:16:25,074 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-5-thread-50) [539cbd7e] Command [id=6db5eb90-af14-43f7-8494-d59528a77c27]: Compensating CHANGED_STATUS_ONLY of org.ovirt.engine.core.common.businessentities.storage_pool_iso_map; snapshot: EntityStatusSnapshot [id=storagePoolId = 64a0f560-b9a1-4c87-8a30-0967b4ce3c81, storageId = 5de36f78-8303-401b-b6c3-e04091077f9e, status=InActive]. -------------------------------------
Ho can I try to resolv it?
Regards //Ricky
_______________________________________________ Users mailing list Users@ovirt.org http://lists.ovirt.org/mailman/listinfo/users

Thanks! It did all the magic! Regards //Ricky On 2012-08-14 14:50, Maor Lipchuk wrote:
Ricky, Try do the following :
go to the meta data of the data storage (in the storage server go to {storage_domain_name}/######..../dom_md/metadata) delete the chksum line _SHA_CKSUM=################ try to activate the storage domain again the DC (it should fail again) vdsm.log should print the computed cksum of the storage domain (Should be an error there which say "Meta Data seal is broken (checksum mismatch).... computed_cksum = ") copy the comuted chksum to the meta data (_SHA_CKSUM={new chksum number} try to activate it again.
Hope it will fix that.
Mean while I'm checking what caused the bug.
Regards, Maor
On 08/14/2012 02:53 PM, Ricky Schneberger wrote:
Hi
I attached the logs.
//Ricky
On 2012-08-14 12:56, Maor Lipchuk wrote:
Hi Ricky can you please add the log of VDSM, also the full engine log.
Regards, Maor
On 08/14/2012 01:22 PM, Ricky Schneberger wrote:
After an normal "yum update" i am unable to get one of the storage domains "UP".
My systems is running Centos 6.3.
If I try to activate the domain in GUI I got following errors:
---------------------------------
2012-08-14 12:16:22,594 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-5-thread-50) [539cbd7e] Lock Acquired to object EngineLock [exclusiveLocks= key: org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand value: 5de36f78-8303-401b-b6c3-e04091077f9e 2012-08-14 12:16:22,610 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-5-thread-50) [539cbd7e] Running command: ActivateStorageDomainCommand internal: false. Entities affected : ID: 5de36f78-8303-401b-b6c3-e04091077f9e Type: Storage 2012-08-14 12:16:22,618 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-5-thread-50) [539cbd7e] Lock freed to object EngineLock [exclusiveLocks= key: org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand value: 5de36f78-8303-401b-b6c3-e04091077f9e 2012-08-14 12:16:22,709 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.ActivateStorageDomainVDSCommand] (pool-5-thread-50) [539cbd7e] START, ActivateStorageDomainVDSCommand(storagePoolId = 64a0f560-b9a1-4c87-8a30-0967b4ce3c81, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 5de36f78-8303-401b-b6c3-e04091077f9e), log id: 152cc8c3 2012-08-14 12:16:25,050 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] (pool-5-thread-50) [539cbd7e] Error code StorageDomainDoesNotExist and error message IRSGenericException: IRSErrorException: Failed to ActivateStorageDomainVDS, error = Storage domain does not exist: ('5de36f78-8303-401b-b6c3-e04091077f9e',) 2012-08-14 12:16:25,050 ERROR [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (pool-5-thread-50) [539cbd7e] IrsBroker::Failed::ActivateStorageDomainVDS due to: IRSErrorException: IRSGenericException: IRSErrorException: Failed to ActivateStorageDomainVDS, error = Storage domain does not exist: ('5de36f78-8303-401b-b6c3-e04091077f9e',) 2012-08-14 12:16:25,058 ERROR [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-5-thread-50) [539cbd7e] Command org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand throw Vdc Bll exception. With error message VdcBLLException: org.ovirt.engine.core.vdsbroker.irsbroker.IRSErrorException: IRSGenericException: IRSErrorException: Failed to ActivateStorageDomainVDS, error = Storage domain does not exist: ('5de36f78-8303-401b-b6c3-e04091077f9e',) 2012-08-14 12:16:25,074 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (pool-5-thread-50) [539cbd7e] Command [id=6db5eb90-af14-43f7-8494-d59528a77c27]: Compensating CHANGED_STATUS_ONLY of org.ovirt.engine.core.common.businessentities.storage_pool_iso_map; snapshot: EntityStatusSnapshot [id=storagePoolId = 64a0f560-b9a1-4c87-8a30-0967b4ce3c81, storageId = 5de36f78-8303-401b-b6c3-e04091077f9e, status=InActive]. -------------------------------------
Ho can I try to resolv it?
Regards //Ricky
_______________________________________________ Users mailing list Users@ovirt.org http://lists.ovirt.org/mailman/listinfo/users
participants (2)
-
Maor Lipchuk
-
Ricky Schneberger