Attachment 604545 Details for Bug 848298 – oVirt Engine log

oVirt Engine log

engine.log (text/plain), 22.37 KB, created by Allon Mureinik on 2012-08-15 08:17:20 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Allon Mureinik

Created: 2012-08-15 08:17:20 UTC

Size: 22.37 KB

patch

obsolete

>2012-08-15 11:10:01,552 ERROR [org.ovirt.engine.core.dal.dbbroker.DbFacadeLocator] (MSC service thread 1-5) Error in configuration of db facade FileNotFoundException: /etc/ovirt-engine/engine.conf (Permission denied)
>2012-08-15 11:10:02,891 INFO  [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-5) Start time: 8/15/12 11:10 AM
>2012-08-15 11:10:02,964 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-5) Could not find enum value for option: CAEngineKey
>2012-08-15 11:10:02,965 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-5) Could not find enum value for option: CbcCheckOnVdsChange
>2012-08-15 11:10:02,972 ERROR [org.ovirt.engine.core.engineencryptutils.EncryptionUtils] (MSC service thread 1-5) Can't load keystore from file "/usr/share/jboss-as-7.1.1.Final/.keystore". FileNotFoundException: .keystore (No such file or directory)
>2012-08-15 11:10:02,973 ERROR [org.ovirt.engine.core.engineencryptutils.EncryptionUtils] (MSC service thread 1-5) Failed to decrypt java.io.FileNotFoundException: .keystore (No such file or directory)
>2012-08-15 11:10:02,974 ERROR [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (MSC service thread 1-5) Failed to decrypt value for property CertificatePassword will be used encrypted value
>2012-08-15 11:10:02,983 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-5) Could not find enum value for option: ENGINEEARLib
>2012-08-15 11:10:02,986 ERROR [org.ovirt.engine.core.engineencryptutils.EncryptionUtils] (MSC service thread 1-5) Can't load keystore from file "/usr/share/jboss-as-7.1.1.Final/.keystore". FileNotFoundException: .keystore (No such file or directory)
>2012-08-15 11:10:02,987 ERROR [org.ovirt.engine.core.engineencryptutils.EncryptionUtils] (MSC service thread 1-5) Failed to decrypt java.io.FileNotFoundException: .keystore (No such file or directory)
>2012-08-15 11:10:02,988 ERROR [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (MSC service thread 1-5) Failed to decrypt value for property AdminPassword will be used encrypted value
>2012-08-15 11:10:02,991 ERROR [org.ovirt.engine.core.engineencryptutils.EncryptionUtils] (MSC service thread 1-5) Can't load keystore from file "/usr/share/jboss-as-7.1.1.Final/.keystore". FileNotFoundException: .keystore (No such file or directory)
>2012-08-15 11:10:02,993 ERROR [org.ovirt.engine.core.engineencryptutils.EncryptionUtils] (MSC service thread 1-5) Failed to decrypt java.io.FileNotFoundException: .keystore (No such file or directory)
>2012-08-15 11:10:02,994 ERROR [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (MSC service thread 1-5) Failed to decrypt value for property LocalAdminPassword will be used encrypted value
>2012-08-15 11:10:03,003 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-5) Could not find enum value for option: MinimalETLVersion
>2012-08-15 11:10:03,017 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-5) Could not find enum value for option: ScriptsPath
>2012-08-15 11:10:03,142 WARN  [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-5) Could not find enum value for option: SQLServerI18NPrefix
>2012-08-15 11:10:03,145 ERROR [org.ovirt.engine.core.engineencryptutils.EncryptionUtils] (MSC service thread 1-5) Can't load keystore from file "/usr/share/jboss-as-7.1.1.Final/.keystore". FileNotFoundException: .keystore (No such file or directory)
>2012-08-15 11:10:03,147 ERROR [org.ovirt.engine.core.engineencryptutils.EncryptionUtils] (MSC service thread 1-5) Failed to decrypt java.io.FileNotFoundException: .keystore (No such file or directory)
>2012-08-15 11:10:03,148 ERROR [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (MSC service thread 1-5) Failed to decrypt value for property TruststorePass will be used encrypted value
>2012-08-15 11:10:03,158 INFO  [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-5) VDSBrokerFrontend: 8/15/12 11:10 AM
>2012-08-15 11:10:03,162 INFO  [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-5) CpuFlagsManager: 8/15/12 11:10 AM
>2012-08-15 11:10:03,163 INFO  [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-5) Setting audit clean up manager to run at: 35 35 3 * * ?
>2012-08-15 11:10:03,184 INFO  [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-5) AuditLogCleanupManager: 8/15/12 11:10 AM
>2012-08-15 11:10:03,188 INFO  [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-5) TagsDirector initialization
>2012-08-15 11:10:03,206 INFO  [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-5) Tag root added to tree
>2012-08-15 11:10:03,215 INFO  [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-5) TagsDirector: 8/15/12 11:10 AM
>2012-08-15 11:10:03,224 INFO  [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-5) IsoDomainListSyncronizer: 8/15/12 11:10 AM
>2012-08-15 11:10:03,246 INFO  [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-5) InitHandlers: 8/15/12 11:10 AM
>2012-08-15 11:10:03,253 WARN  [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-5) Code VDS_FENCING_OPERATION_FAILED appears more then once in string table.
>2012-08-15 11:10:03,255 INFO  [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-5) ErrorTranslator: 8/15/12 11:10 AM
>2012-08-15 11:10:03,257 INFO  [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-5) VdsErrorTranslator: 8/15/12 11:10 AM
>2012-08-15 11:10:03,258 INFO  [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-5) Mark uncompleted jobs as UNKNOWN: 8/15/12 11:10 AM
>2012-08-15 11:10:03,283 INFO  [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-5) JobRepositoryCleanupManager: 8/15/12 11:10 AM
>2012-08-15 11:10:03,285 INFO  [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-5) AutoRecoveryManager: 8/15/12 11:10 AM
>2012-08-15 11:10:03,288 INFO  [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-5) ExecutionMessageDirector: 8/15/12 11:10 AM
>2012-08-15 11:10:03,323 INFO  [org.ovirt.engine.core.bll.adbroker.UsersDomainsCacheManagerService] (MSC service thread 1-6) UsersDomainsCacheManager: 8/15/12 11:10 AM
>2012-08-15 11:10:03,328 INFO  [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-2) InitResourceManager: 8/15/12 11:10 AM
>2012-08-15 11:10:03,333 INFO  [org.ovirt.engine.core.vdsbroker.ResourceManager] (MSC service thread 1-2) ResourceManager::ResourceManager::Entered
>2012-08-15 11:10:03,406 INFO  [org.ovirt.engine.core.bll.adbroker.UsersDomainsCacheManagerService] (MSC service thread 1-6) DbUserCacheManager: 8/15/12 11:10 AM
>2012-08-15 11:10:03,438 INFO  [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-1) DbUserCacheManager::refreshAllUserData() - entered
>2012-08-15 11:10:08,573 INFO  [org.ovirt.engine.core.bll.LoginAdminUserCommand] (http--0.0.0.0-8080-1) Checking if user admin@internal is an admin, result true
>2012-08-15 11:10:08,577 INFO  [org.ovirt.engine.core.bll.LoginAdminUserCommand] (http--0.0.0.0-8080-1) Running command: LoginAdminUserCommand internal: false.
>2012-08-15 11:10:08,629 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) AuditLogType: UNASSIGNED not exist in string table
>2012-08-15 11:10:08,633 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) AuditLogType: VDS_NETWORK_MTU_DIFFER_FROM_LOGICAL_NETWORK not exist in string table
>2012-08-15 11:10:08,634 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) AuditLogType: STORAGE_ACTIVATE_ASYNC not exist in string table
>2012-08-15 11:10:08,635 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) AuditLogType: DWH_STOPPED not exist in string table
>2012-08-15 11:10:08,636 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) AuditLogType: DWH_STARTED not exist in string table
>2012-08-15 11:10:08,637 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) AuditLogType: DWH_ERROR not exist in string table
>2012-08-15 11:10:08,643 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) AuditLogType: USER_ATTACH_DISK_TO_VM not have severity. Assumed Normal
>2012-08-15 11:10:08,645 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) AuditLogType: USER_FAILED_ATTACH_DISK_TO_VM not have severity. Assumed Normal
>2012-08-15 11:10:08,646 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) AuditLogType: USER_DETACH_DISK_FROM_VM not have severity. Assumed Normal
>2012-08-15 11:10:08,647 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) AuditLogType: USER_FAILED_DETACH_DISK_FROM_VM not have severity. Assumed Normal
>2012-08-15 11:10:08,648 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (http--0.0.0.0-8080-1) AuditLogType: VDS_NETWORK_MTU_DIFFER_FROM_LOGICAL_NETWORK not have severity. Assumed Normal
>2012-08-15 11:10:08,935 WARN  [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (http--0.0.0.0-8080-1) Adding new value to configuration cache.
>2012-08-15 11:10:08,939 WARN  [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (http--0.0.0.0-8080-1) Didnt find the value of DBPagingType in DB for version general - using default: Offset
>2012-08-15 11:10:08,941 WARN  [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (http--0.0.0.0-8080-1) Adding new value to configuration cache.
>2012-08-15 11:10:08,950 WARN  [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (http--0.0.0.0-8080-1) Didnt find the value of DBPagingSyntax in DB for version general - using default: OFFSET (%1$s -1) LIMIT %2$s
>2012-08-15 11:10:08,952 WARN  [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (http--0.0.0.0-8080-1) Adding new value to configuration cache.
>2012-08-15 11:10:08,953 WARN  [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (http--0.0.0.0-8080-1) Didnt find the value of DBSearchTemplate in DB for version general - using default: SELECT * FROM (%2$s) %1$s) as T1 %3$s
>2012-08-15 11:10:09,758 ERROR [org.ovirt.engine.core.ServletUtils] (http--0.0.0.0-8080-1) Can't read file "/usr/share/ovirt-engine/docs/DocumentationPath.csv" for request "/docs/DocumentationPath.csv", will send a 404 error response.
>2012-08-15 11:10:13,451 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (MSC service thread 1-2) Eneterd VdsManager:constructor
>2012-08-15 11:10:13,452 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (MSC service thread 1-2) vdsBroker(10.35.1.62,54,321)
>2012-08-15 11:10:13,510 INFO  [org.ovirt.engine.core.vdsbroker.ResourceManager] (MSC service thread 1-2) ResourceManager::AddVds - VDS f528a7be-e6ac-11e1-8165-f778270e4180 was added to the Resource Manager
>2012-08-15 11:10:13,544 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (MSC service thread 1-2) AsyncTaskManager: Initialization of AsyncTaskManager completed successfully.
>2012-08-15 11:10:13,550 INFO  [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-2) AsyncTaskManager: 8/15/12 11:10 AM
>2012-08-15 11:10:13,552 INFO  [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-2) VdsLoadBalancer: 8/15/12 11:10 AM
>2012-08-15 11:10:13,554 INFO  [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (pool-10-thread-2) MacPoolManager started: 8/15/12 11:10 AM
>2012-08-15 11:10:13,581 INFO  [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (pool-10-thread-2) MacPoolManager finished: 8/15/12 11:10 AM
>2012-08-15 11:10:16,378 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (QuartzScheduler_Worker-2) Initializing Host: mars-vdsi
>2012-08-15 11:10:16,401 INFO  [org.ovirt.engine.core.bll.HandleVdsVersionCommand] (QuartzScheduler_Worker-2) [7b9d23fb] Running command: HandleVdsVersionCommand internal: true. Entities affected :  ID: f528a7be-e6ac-11e1-8165-f778270e4180 Type: VDS
>2012-08-15 11:10:20,540 WARN  [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (http--0.0.0.0-8080-1) Adding new value to configuration cache.
>2012-08-15 11:10:20,541 WARN  [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (http--0.0.0.0-8080-1) Didnt find the value of DBI18NPrefix in DB for version general - using default: 
>2012-08-15 11:10:23,558 INFO  [org.ovirt.engine.core.bll.storage.SetStoragePoolStatusCommand] (QuartzScheduler_Worker-7) [abe24d5] Running command: SetStoragePoolStatusCommand internal: true. Entities affected :  ID: 22f91e5e-fa21-4975-8fac-f6ab5191cab1 Type: StoragePool
>2012-08-15 11:10:23,670 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (QuartzScheduler_Worker-7) [abe24d5] hostFromVds::selectedVds - mars-vdsi, spmStatus Free, storage pool dc2
>2012-08-15 11:10:23,682 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (QuartzScheduler_Worker-7) [abe24d5] SpmStatus on vds f528a7be-e6ac-11e1-8165-f778270e4180: Free
>2012-08-15 11:10:23,705 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (QuartzScheduler_Worker-7) [abe24d5] starting spm on vds mars-vdsi, storage pool dc2, prevId 1, LVER 0
>2012-08-15 11:10:23,710 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (QuartzScheduler_Worker-7) [abe24d5] START, SpmStartVDSCommand(vdsId = f528a7be-e6ac-11e1-8165-f778270e4180, storagePoolId = 22f91e5e-fa21-4975-8fac-f6ab5191cab1, prevId=1, prevLVER=0, storagePoolFormatType=V3, recoveryMode=Manual, SCSIFencing=false), log id: 3564ee26
>2012-08-15 11:10:23,720 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (QuartzScheduler_Worker-7) [abe24d5] spmStart polling started: taskId = 1633319d-222b-41d0-808c-55ac47143904
>2012-08-15 11:10:24,733 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (QuartzScheduler_Worker-7) [abe24d5] spmStart polling ended: taskId = 1633319d-222b-41d0-808c-55ac47143904 task status = finished
>2012-08-15 11:10:24,741 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (QuartzScheduler_Worker-7) [abe24d5] spmStart polling ended. spm status: SPM
>2012-08-15 11:10:24,745 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (QuartzScheduler_Worker-7) [abe24d5] START, HSMClearTaskVDSCommand(vdsId = f528a7be-e6ac-11e1-8165-f778270e4180, taskId=1633319d-222b-41d0-808c-55ac47143904), log id: 4c1d4a0e
>2012-08-15 11:10:24,752 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (QuartzScheduler_Worker-7) [abe24d5] FINISH, HSMClearTaskVDSCommand, log id: 4c1d4a0e
>2012-08-15 11:10:24,754 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (QuartzScheduler_Worker-7) [abe24d5] FINISH, SpmStartVDSCommand, return: org.ovirt.engine.core.common.businessentities.SpmStatusResult@14144125, log id: 3564ee26
>2012-08-15 11:10:24,806 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (QuartzScheduler_Worker-7) [abe24d5] Initialize Irs proxy from vds: 10.35.1.62
>2012-08-15 11:10:24,849 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (QuartzScheduler_Worker-11) Attempting to get and stop tasks on storage pool dc2
>2012-08-15 11:10:24,852 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMGetAllTasksInfoVDSCommand] (QuartzScheduler_Worker-11) START, SPMGetAllTasksInfoVDSCommand(storagePoolId = 22f91e5e-fa21-4975-8fac-f6ab5191cab1, ignoreFailoverLimit = false, compatabilityVersion = null), log id: 38f52260
>2012-08-15 11:10:24,914 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMGetAllTasksInfoVDSCommand] (QuartzScheduler_Worker-11) -- SPMGetAllTasksInfoVDSCommand::ExecuteIrsBrokerCommand: Attempting on storage pool 22f91e5e-fa21-4975-8fac-f6ab5191cab1
>2012-08-15 11:10:24,918 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetAllTasksInfoVDSCommand] (QuartzScheduler_Worker-11) START, HSMGetAllTasksInfoVDSCommand(vdsId = f528a7be-e6ac-11e1-8165-f778270e4180), log id: fc847b6
>2012-08-15 11:10:24,924 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetAllTasksInfoVDSCommand] (QuartzScheduler_Worker-11) FINISH, HSMGetAllTasksInfoVDSCommand, return: [], log id: fc847b6
>2012-08-15 11:10:24,926 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMGetAllTasksInfoVDSCommand] (QuartzScheduler_Worker-11) FINISH, SPMGetAllTasksInfoVDSCommand, return: [], log id: 38f52260
>2012-08-15 11:10:24,926 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (QuartzScheduler_Worker-11) Discovered no tasks on Storage Pool dc2
>2012-08-15 11:10:24,928 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMGetAllTasksInfoVDSCommand] (QuartzScheduler_Worker-11) START, SPMGetAllTasksInfoVDSCommand(storagePoolId = 22f91e5e-fa21-4975-8fac-f6ab5191cab1, ignoreFailoverLimit = false, compatabilityVersion = null), log id: 2ccd58b8
>2012-08-15 11:10:24,929 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMGetAllTasksInfoVDSCommand] (QuartzScheduler_Worker-11) -- SPMGetAllTasksInfoVDSCommand::ExecuteIrsBrokerCommand: Attempting on storage pool 22f91e5e-fa21-4975-8fac-f6ab5191cab1
>2012-08-15 11:10:24,931 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetAllTasksInfoVDSCommand] (QuartzScheduler_Worker-11) START, HSMGetAllTasksInfoVDSCommand(vdsId = f528a7be-e6ac-11e1-8165-f778270e4180), log id: 787d9904
>2012-08-15 11:10:24,939 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMGetAllTasksInfoVDSCommand] (QuartzScheduler_Worker-11) FINISH, HSMGetAllTasksInfoVDSCommand, return: [], log id: 787d9904
>2012-08-15 11:10:24,940 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.SPMGetAllTasksInfoVDSCommand] (QuartzScheduler_Worker-11) FINISH, SPMGetAllTasksInfoVDSCommand, return: [], log id: 2ccd58b8
>2012-08-15 11:10:24,941 INFO  [org.ovirt.engine.core.bll.AsyncTaskManager] (QuartzScheduler_Worker-11) Discovered no tasks on Storage Pool dc2
>2012-08-15 11:10:25,630 INFO  [org.ovirt.engine.core.bll.AddVmFromScratchCommand] (http--0.0.0.0-8080-1) [37362273] Lock Acquired to object EngineLock [exclusiveLocks= key: vm value: VM_NAME
>, sharedLocks= ]
>2012-08-15 11:10:25,687 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (http--0.0.0.0-8080-1) [37362273] START, IsValidVDSCommand(storagePoolId = 22f91e5e-fa21-4975-8fac-f6ab5191cab1, ignoreFailoverLimit = false, compatabilityVersion = null), log id: 114360cc
>2012-08-15 11:10:25,691 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (http--0.0.0.0-8080-1) [37362273] FINISH, IsValidVDSCommand, return: true, log id: 114360cc
>2012-08-15 11:10:25,736 INFO  [org.ovirt.engine.core.bll.AddVmFromScratchCommand] (http--0.0.0.0-8080-1) [37362273] Running command: AddVmFromScratchCommand internal: false. Entities affected :  ID: 1ebfb986-e6ae-11e1-8fb4-8775207131ed Type: VdsGroups
>2012-08-15 11:10:25,739 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (http--0.0.0.0-8080-1) [37362273] START, IsValidVDSCommand(storagePoolId = 22f91e5e-fa21-4975-8fac-f6ab5191cab1, ignoreFailoverLimit = false, compatabilityVersion = null), log id: 2fb4acd4
>2012-08-15 11:10:25,742 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (http--0.0.0.0-8080-1) [37362273] FINISH, IsValidVDSCommand, return: true, log id: 2fb4acd4
>2012-08-15 11:10:25,893 INFO  [org.ovirt.engine.core.bll.AddVmFromScratchCommand] (http--0.0.0.0-8080-1) [37362273] Lock freed to object EngineLock [exclusiveLocks= key: vm value: VM_NAME
>, sharedLocks= ]
>2012-08-15 11:10:39,254 INFO  [org.ovirt.engine.core.bll.CreateAllSnapshotsFromVmCommand] (http--0.0.0.0-8080-1) Lock Acquired to object EngineLock [exclusiveLocks= key: 5ac32111-d4bc-4d6a-b18a-c2455d79ea81 value: VM
>, sharedLocks= ]
>2012-08-15 11:10:39,352 INFO  [org.ovirt.engine.core.bll.CreateAllSnapshotsFromVmCommand] (pool-10-thread-30) [24db4c39] Running command: CreateAllSnapshotsFromVmCommand internal: false. Entities affected :  ID: 5ac32111-d4bc-4d6a-b18a-c2455d79ea81 Type: VM
>2012-08-15 11:10:39,493 INFO  [org.ovirt.engine.core.bll.CreateAllSnapshotsFromVmCommand] (pool-10-thread-30) [24db4c39] Lock freed to object EngineLock [exclusiveLocks= key: 5ac32111-d4bc-4d6a-b18a-c2455d79ea81 value: VM
>, sharedLocks= ]
>2012-08-15 11:10:39,520 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UpdateVMVDSCommand] (pool-10-thread-30) [24db4c39] START, UpdateVMVDSCommand(storagePoolId = 22f91e5e-fa21-4975-8fac-f6ab5191cab1, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 00000000-0000-0000-0000-000000000000, infoDictionary.size = 1), log id: 4331ecb6
>2012-08-15 11:10:39,636 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UpdateVMVDSCommand] (pool-10-thread-30) [24db4c39] FINISH, UpdateVMVDSCommand, log id: 4331ecb6
>2012-08-15 11:10:47,523 INFO  [org.ovirt.engine.core.bll.TryBackToAllSnapshotsOfVmCommand] (http--0.0.0.0-8080-1) [5a6bb8d7] Lock Acquired to object EngineLock [exclusiveLocks= key: 5ac32111-d4bc-4d6a-b18a-c2455d79ea81 value: VM
>, sharedLocks= ]
>2012-08-15 11:10:47,559 INFO  [org.ovirt.engine.core.bll.TryBackToAllSnapshotsOfVmCommand] (http--0.0.0.0-8080-1) [5a6bb8d7] Running command: TryBackToAllSnapshotsOfVmCommand internal: false. Entities affected :  ID: 5ac32111-d4bc-4d6a-b18a-c2455d79ea81 Type: VM
>2012-08-15 11:10:47,874 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UpdateVMVDSCommand] (http--0.0.0.0-8080-1) [5a6bb8d7] START, UpdateVMVDSCommand(storagePoolId = 22f91e5e-fa21-4975-8fac-f6ab5191cab1, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 00000000-0000-0000-0000-000000000000, infoDictionary.size = 1), log id: 68243f7c
>2012-08-15 11:10:47,962 INFO  [org.ovirt.engine.core.vdsbroker.irsbroker.UpdateVMVDSCommand] (http--0.0.0.0-8080-1) [5a6bb8d7] FINISH, UpdateVMVDSCommand, log id: 68243f7c
>2012-08-15 11:10:47,963 INFO  [org.ovirt.engine.core.bll.TryBackToAllSnapshotsOfVmCommand] (http--0.0.0.0-8080-1) [5a6bb8d7] Lock freed to object EngineLock [exclusiveLocks= key: 5ac32111-d4bc-4d6a-b18a-c2455d79ea81 value: VM
>, sharedLocks= ]
>2012-08-15 11:10:52,014 INFO  [org.ovirt.engine.core.bll.RestoreAllSnapshotsCommand] (http--0.0.0.0-8080-1) [b0abd22] Lock Acquired to object EngineLock [exclusiveLocks= key: 5ac32111-d4bc-4d6a-b18a-c2455d79ea81 value: VM
>, sharedLocks= ]
>2012-08-15 11:10:52,016 WARN  [org.ovirt.engine.core.bll.RestoreAllSnapshotsCommand] (http--0.0.0.0-8080-1) [b0abd22] CanDoAction of action RestoreAllSnapshots failed. Reasons:ACTION_TYPE_FAILED_VM_SNAPSHOT_DOES_NOT_EXIST,VAR__ACTION__REVERT_TO,VAR__TYPE__SNAPSHOT
>2012-08-15 11:10:52,018 INFO  [org.ovirt.engine.core.bll.RestoreAllSnapshotsCommand] (http--0.0.0.0-8080-1) [b0abd22] Lock freed to object EngineLock [exclusiveLocks= key: 5ac32111-d4bc-4d6a-b18a-c2455d79ea81 value: VM
>, sharedLocks= ]

Actions: View

Attachments on bug 848298: 604544 | 604545