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--