Login
[x]
Log in using an account from:
Fedora Account System
Red Hat Associate
Red Hat Customer
Or login using a Red Hat Bugzilla account
Forgot Password
Login:
Hide Forgot
Create an Account
Red Hat Bugzilla – Attachment 604581 Details for
Bug 848362
[SetupNetworks] BOND created when using network with name eth0
[?]
New
Simple Search
Advanced Search
My Links
Browse
Requests
Reports
Current State
Search
Tabular reports
Graphical reports
Duplicates
Other Reports
User Changes
Plotly Reports
Bug Status
Bug Severity
Non-Defaults
|
Product Dashboard
Help
Page Help!
Bug Writing Guidelines
What's new
Browser Support Policy
5.0.4.rh83 Release notes
FAQ
Guides index
User guide
Web Services
Contact
Legal
This site requires JavaScript to be enabled to function correctly, please enable it.
engine.log
engine.ethname.log (text/x-log), 217.93 KB, created by
Meni Yakove
on 2012-08-15 11:53:47 UTC
(
hide
)
Description:
engine.log
Filename:
MIME Type:
Creator:
Meni Yakove
Created:
2012-08-15 11:53:47 UTC
Size:
217.93 KB
patch
obsolete
>2012-08-15 09:18:48,735 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) Start time: 8/15/12 9:18 AM >2012-08-15 09:18:49,666 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: CbcCheckOnVdsChange >2012-08-15 09:18:49,695 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: CAEngineKey >2012-08-15 09:18:49,695 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: ENGINEEARLib >2012-08-15 09:18:50,059 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: MinimalETLVersion >2012-08-15 09:18:50,718 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: ScriptsPath >2012-08-15 09:18:50,719 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: SQLServerI18NPrefix >2012-08-15 09:18:50,917 ERROR [org.ovirt.engine.core.engineencryptutils.EncryptionUtils] (MSC service thread 1-2) Failed to decrypt Data must start with zero >2012-08-15 09:18:50,919 ERROR [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (MSC service thread 1-2) Failed to decrypt value for property TruststorePass will be used encrypted value >2012-08-15 09:18:51,260 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) VDSBrokerFrontend: 8/15/12 9:18 AM >2012-08-15 09:18:51,277 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) CpuFlagsManager: 8/15/12 9:18 AM >2012-08-15 09:18:51,278 INFO [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-2) Setting audit clean up manager to run at: 35 35 3 * * ? >2012-08-15 09:18:51,331 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) AuditLogCleanupManager: 8/15/12 9:18 AM >2012-08-15 09:18:51,337 INFO [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-2) TagsDirector initialization >2012-08-15 09:18:51,380 INFO [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-2) Tag root added to tree >2012-08-15 09:18:51,407 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) TagsDirector: 8/15/12 9:18 AM >2012-08-15 09:18:51,425 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) IsoDomainListSyncronizer: 8/15/12 9:18 AM >2012-08-15 09:18:51,487 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) InitHandlers: 8/15/12 9:18 AM >2012-08-15 09:18:51,514 WARN [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-2) Code VDS_FENCING_OPERATION_FAILED appears more then once in string table. >2012-08-15 09:18:51,515 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) ErrorTranslator: 8/15/12 9:18 AM >2012-08-15 09:18:51,521 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) VdsErrorTranslator: 8/15/12 9:18 AM >2012-08-15 09:18:51,522 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) Mark uncompleted jobs as UNKNOWN: 8/15/12 9:18 AM >2012-08-15 09:18:51,596 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) JobRepositoryCleanupManager: 8/15/12 9:18 AM >2012-08-15 09:18:51,598 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) AutoRecoveryManager: 8/15/12 9:18 AM >2012-08-15 09:18:51,607 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) ExecutionMessageDirector: 8/15/12 9:18 AM >2012-08-15 09:18:51,659 INFO [org.ovirt.engine.core.bll.adbroker.UsersDomainsCacheManagerService] (MSC service thread 1-1) UsersDomainsCacheManager: 8/15/12 9:18 AM >2012-08-15 09:18:51,665 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-2) InitResourceManager: 8/15/12 9:18 AM >2012-08-15 09:18:51,681 INFO [org.ovirt.engine.core.vdsbroker.ResourceManager] (MSC service thread 1-2) ResourceManager::ResourceManager::Entered >2012-08-15 09:18:51,824 INFO [org.ovirt.engine.core.bll.adbroker.UsersDomainsCacheManagerService] (MSC service thread 1-1) DbUserCacheManager: 8/15/12 9:18 AM >2012-08-15 09:18:52,442 INFO [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-1) DbUserCacheManager::refreshAllUserData() - entered >2012-08-15 09:18:52,773 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (MSC service thread 1-2) AsyncTaskManager: Initialization of AsyncTaskManager completed successfully. >2012-08-15 09:18:52,773 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-2) AsyncTaskManager: 8/15/12 9:18 AM >2012-08-15 09:18:52,779 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-2) VdsLoadBalancer: 8/15/12 9:18 AM >2012-08-15 09:18:52,796 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (pool-4-thread-1) MacPoolManager started: 8/15/12 9:18 AM >2012-08-15 09:18:53,149 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (pool-4-thread-1) MacPoolManager finished: 8/15/12 9:18 AM >2012-08-15 09:21:02,303 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) Start time: 8/15/12 9:21 AM >2012-08-15 09:21:02,539 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: CbcCheckOnVdsChange >2012-08-15 09:21:02,567 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: CAEngineKey >2012-08-15 09:21:02,567 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: ENGINEEARLib >2012-08-15 09:21:02,603 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: MinimalETLVersion >2012-08-15 09:21:03,120 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: ScriptsPath >2012-08-15 09:21:03,121 WARN [org.ovirt.engine.core.utils.ConfigUtilsBase] (MSC service thread 1-2) Could not find enum value for option: SQLServerI18NPrefix >2012-08-15 09:21:03,354 ERROR [org.ovirt.engine.core.engineencryptutils.EncryptionUtils] (MSC service thread 1-2) Failed to decrypt Data must start with zero >2012-08-15 09:21:03,357 ERROR [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (MSC service thread 1-2) Failed to decrypt value for property TruststorePass will be used encrypted value >2012-08-15 09:21:03,607 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) VDSBrokerFrontend: 8/15/12 9:21 AM >2012-08-15 09:21:03,619 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) CpuFlagsManager: 8/15/12 9:21 AM >2012-08-15 09:21:03,619 INFO [org.ovirt.engine.core.bll.AuditLogCleanupManager] (MSC service thread 1-2) Setting audit clean up manager to run at: 35 35 3 * * ? >2012-08-15 09:21:03,660 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) AuditLogCleanupManager: 8/15/12 9:21 AM >2012-08-15 09:21:03,664 INFO [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-2) TagsDirector initialization >2012-08-15 09:21:03,720 INFO [org.ovirt.engine.core.bll.TagsDirector] (MSC service thread 1-2) Tag root added to tree >2012-08-15 09:21:03,760 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) TagsDirector: 8/15/12 9:21 AM >2012-08-15 09:21:03,774 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) IsoDomainListSyncronizer: 8/15/12 9:21 AM >2012-08-15 09:21:03,816 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) InitHandlers: 8/15/12 9:21 AM >2012-08-15 09:21:03,845 WARN [org.ovirt.engine.core.utils.ErrorTranslatorImpl] (MSC service thread 1-2) Code VDS_FENCING_OPERATION_FAILED appears more then once in string table. >2012-08-15 09:21:03,847 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) ErrorTranslator: 8/15/12 9:21 AM >2012-08-15 09:21:03,849 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) VdsErrorTranslator: 8/15/12 9:21 AM >2012-08-15 09:21:03,850 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) Mark uncompleted jobs as UNKNOWN: 8/15/12 9:21 AM >2012-08-15 09:21:03,925 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) JobRepositoryCleanupManager: 8/15/12 9:21 AM >2012-08-15 09:21:03,930 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) AutoRecoveryManager: 8/15/12 9:21 AM >2012-08-15 09:21:03,940 INFO [org.ovirt.engine.core.bll.Backend] (MSC service thread 1-2) ExecutionMessageDirector: 8/15/12 9:21 AM >2012-08-15 09:21:03,975 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-1) InitResourceManager: 8/15/12 9:21 AM >2012-08-15 09:21:03,980 INFO [org.ovirt.engine.core.vdsbroker.ResourceManager] (MSC service thread 1-1) ResourceManager::ResourceManager::Entered >2012-08-15 09:21:03,977 INFO [org.ovirt.engine.core.bll.adbroker.UsersDomainsCacheManagerService] (MSC service thread 1-2) UsersDomainsCacheManager: 8/15/12 9:21 AM >2012-08-15 09:21:04,076 INFO [org.ovirt.engine.core.bll.adbroker.UsersDomainsCacheManagerService] (MSC service thread 1-2) DbUserCacheManager: 8/15/12 9:21 AM >2012-08-15 09:21:04,126 INFO [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-1) DbUserCacheManager::refreshAllUserData() - entered >2012-08-15 09:21:04,312 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (MSC service thread 1-1) AsyncTaskManager: Initialization of AsyncTaskManager completed successfully. >2012-08-15 09:21:04,313 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-1) AsyncTaskManager: 8/15/12 9:21 AM >2012-08-15 09:21:04,318 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (MSC service thread 1-1) VdsLoadBalancer: 8/15/12 9:21 AM >2012-08-15 09:21:04,324 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (pool-4-thread-1) MacPoolManager started: 8/15/12 9:21 AM >2012-08-15 09:21:04,542 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (pool-4-thread-1) MacPoolManager finished: 8/15/12 9:21 AM >2012-08-15 09:22:41,552 INFO [org.ovirt.engine.core.bll.LoginAdminUserCommand] (ajp-/127.0.0.1:8009-3) Checking if user admin@internal is an admin, result true >2012-08-15 09:22:41,560 INFO [org.ovirt.engine.core.bll.LoginAdminUserCommand] (ajp-/127.0.0.1:8009-3) Running command: LoginAdminUserCommand internal: false. >2012-08-15 09:22:41,677 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8009-3) AuditLogType: UNASSIGNED not exist in string table >2012-08-15 09:22:41,678 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8009-3) AuditLogType: STORAGE_ACTIVATE_ASYNC not exist in string table >2012-08-15 09:22:41,678 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8009-3) AuditLogType: DWH_STOPPED not exist in string table >2012-08-15 09:22:41,678 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8009-3) AuditLogType: DWH_STARTED not exist in string table >2012-08-15 09:22:41,679 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8009-3) AuditLogType: DWH_ERROR not exist in string table >2012-08-15 09:22:41,687 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8009-3) AuditLogType: USER_ATTACH_DISK_TO_VM not have severity. Assumed Normal >2012-08-15 09:22:41,688 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8009-3) AuditLogType: USER_FAILED_ATTACH_DISK_TO_VM not have severity. Assumed Normal >2012-08-15 09:22:41,688 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8009-3) AuditLogType: USER_DETACH_DISK_FROM_VM not have severity. Assumed Normal >2012-08-15 09:22:41,688 WARN [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8009-3) AuditLogType: USER_FAILED_DETACH_DISK_FROM_VM not have severity. Assumed Normal >2012-08-15 09:22:50,813 WARN [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (ajp-/127.0.0.1:8009-8) Adding new value to configuration cache. >2012-08-15 09:22:50,819 WARN [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (ajp-/127.0.0.1:8009-8) Didnt find the value of DBPagingType in DB for version general - using default: Offset >2012-08-15 09:22:50,820 WARN [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (ajp-/127.0.0.1:8009-8) Adding new value to configuration cache. >2012-08-15 09:22:50,820 WARN [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (ajp-/127.0.0.1:8009-8) 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 09:22:50,814 WARN [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (ajp-/127.0.0.1:8009-4) Adding new value to configuration cache. >2012-08-15 09:22:50,821 WARN [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (ajp-/127.0.0.1:8009-4) Didnt find the value of DBPagingSyntax in DB for version general - using default: OFFSET (%1$s -1) LIMIT %2$s >2012-08-15 09:22:50,911 ERROR [org.ovirt.engine.core.ServletUtils] (ajp-/127.0.0.1:8009-7) Can't read file "/usr/share/ovirt-engine/docs/en-US/DocumentationPath.csv" for request "/docs/en-US/DocumentationPath.csv", will send a 404 error response. >2012-08-15 09:24:02,162 INFO [org.ovirt.engine.core.bll.storage.AddEmptyStoragePoolCommand] (ajp-/127.0.0.1:8009-7) [31131b0e] Running command: AddEmptyStoragePoolCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2012-08-15 09:24:11,651 INFO [org.ovirt.engine.core.bll.AddVdsGroupCommand] (ajp-/127.0.0.1:8009-3) [1ecc425e] Running command: AddVdsGroupCommand internal: false. Entities affected : ID: 35352af8-fb85-46f7-8433-ee6540b14a47 Type: StoragePool >2012-08-15 09:24:51,860 INFO [org.ovirt.engine.core.utils.hostinstall.HostKeyVerifier] (NioProcessor-2) SSH key fingerprint e5:02:ab:a1:97:74:86:d3:b9:09:d7:e9:ed:aa:ef:c6 for host orchid-vds1.qa.lab.tlv.redhat.com (10.35.97.58) has been successfully verified. >2012-08-15 09:24:51,945 INFO [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (ajp-/127.0.0.1:8009-1) Invoking /bin/echo -e `/bin/bash -c /usr/sbin/dmidecode|/bin/awk ' /UUID/{ print $2; } ' | /usr/bin/tr ' >' '_' && /bin/cat /sys/class/net/*/address | /bin/sed -e '/00:00:00:00/d' -e '/^$/d' | /bin/sort -u | /usr/bin/head --lines=1` on orchid-vds1.qa.lab.tlv.redhat.com >2012-08-15 09:24:52,086 INFO [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (ajp-/127.0.0.1:8009-1) RunSSHCommand returns true >2012-08-15 09:24:52,184 INFO [org.ovirt.engine.core.bll.AddVdsCommand] (ajp-/127.0.0.1:8009-1) [b5ceede] Running command: AddVdsCommand internal: false. Entities affected : ID: d3e09392-e6a1-11e1-9a04-001a4a169777 Type: VdsGroups >2012-08-15 09:24:52,344 INFO [org.ovirt.engine.core.bll.AddVdsSpmIdCommand] (ajp-/127.0.0.1:8009-1) [7bd5520d] Running command: AddVdsSpmIdCommand internal: true. Entities affected : ID: ec084258-e6a1-11e1-a8e4-001a4a169777 Type: VDS >2012-08-15 09:24:52,392 INFO [org.ovirt.engine.core.bll.AddVdsSpmIdCommand] (ajp-/127.0.0.1:8009-1) [7bd5520d] Lock freed to object EngineLock [exclusiveLocks= key: 35352af8-fb85-46f7-8433-ee6540b14a47 value: REGISTER_VDS >, sharedLocks= ] >2012-08-15 09:24:52,404 ERROR [org.ovirt.engine.core.vdsbroker.ResourceManager] (ajp-/127.0.0.1:8009-1) [7bd5520d] Cannot get vdsManager for vdsid=ec084258-e6a1-11e1-a8e4-001a4a169777 >2012-08-15 09:24:52,405 INFO [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (ajp-/127.0.0.1:8009-1) [7bd5520d] START, RemoveVdsVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777), log id: 3e6291f7 >2012-08-15 09:24:52,405 ERROR [org.ovirt.engine.core.vdsbroker.ResourceManager] (ajp-/127.0.0.1:8009-1) [7bd5520d] Cannot get vdsManager for vdsid=ec084258-e6a1-11e1-a8e4-001a4a169777 >2012-08-15 09:24:52,405 INFO [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (ajp-/127.0.0.1:8009-1) [7bd5520d] FINISH, RemoveVdsVDSCommand, log id: 3e6291f7 >2012-08-15 09:24:52,412 ERROR [org.ovirt.engine.core.vdsbroker.ResourceManager] (ajp-/127.0.0.1:8009-1) [7bd5520d] Cannot get vdsManager for vdsid=ec084258-e6a1-11e1-a8e4-001a4a169777 >2012-08-15 09:24:52,412 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (ajp-/127.0.0.1:8009-1) [7bd5520d] START, AddVdsVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777), log id: 5b3a5a2c >2012-08-15 09:24:52,412 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (ajp-/127.0.0.1:8009-1) [7bd5520d] AddVds - entered , starting logic to add VDS ec084258-e6a1-11e1-a8e4-001a4a169777 >2012-08-15 09:24:52,425 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (ajp-/127.0.0.1:8009-1) [7bd5520d] AddVds - VDS ec084258-e6a1-11e1-a8e4-001a4a169777 was added, will try to add it to the resource manager >2012-08-15 09:24:52,438 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (ajp-/127.0.0.1:8009-1) [7bd5520d] Eneterd VdsManager:constructor >2012-08-15 09:24:52,439 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (ajp-/127.0.0.1:8009-1) [7bd5520d] vdsBroker(orchid-vds1.qa.lab.tlv.redhat.com,54,321) >2012-08-15 09:24:52,616 INFO [org.ovirt.engine.core.vdsbroker.ResourceManager] (ajp-/127.0.0.1:8009-1) [7bd5520d] ResourceManager::AddVds - VDS ec084258-e6a1-11e1-a8e4-001a4a169777 was added to the Resource Manager >2012-08-15 09:24:52,616 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (ajp-/127.0.0.1:8009-1) [7bd5520d] FINISH, AddVdsVDSCommand, log id: 5b3a5a2c >2012-08-15 09:24:52,856 INFO [org.ovirt.engine.core.bll.InstallVdsCommand] (pool-4-thread-3) [568bd786] Running command: InstallVdsCommand internal: true. Entities affected : ID: ec084258-e6a1-11e1-a8e4-001a4a169777 Type: VDS >2012-08-15 09:24:52,879 INFO [org.ovirt.engine.core.bll.InstallVdsCommand] (pool-4-thread-3) [568bd786] Before Installation pool-4-thread-3 >2012-08-15 09:24:52,879 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. Executing installation stage. (Stage: Starting Host installation) >2012-08-15 09:24:52,883 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. Executing installation stage. (Stage: Connecting to Host) >2012-08-15 09:24:52,923 INFO [org.ovirt.engine.core.utils.hostinstall.HostKeyVerifier] (NioProcessor-3) SSH key fingerprint e5:02:ab:a1:97:74:86:d3:b9:09:d7:e9:ed:aa:ef:c6 for host orchid-vds1.qa.lab.tlv.redhat.com (10.35.97.58) has been successfully verified. >2012-08-15 09:24:52,997 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='RHEV_INSTALL' status='OK' message='Connected to Host orchid-vds1.qa.lab.tlv.redhat.com with SSH key fingerprint: e5:02:ab:a1:97:74:86:d3:b9:09:d7:e9:ed:aa:ef:c6'/>. FYI. (Stage: Connecting to Host) >2012-08-15 09:24:53,038 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. Successfully connected to server ssh. (Stage: Connecting to Host) >2012-08-15 09:24:53,038 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. Executing installation stage. (Stage: Get the unique vds id) >2012-08-15 09:24:53,039 INFO [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-4-thread-3) Invoking /bin/echo -e `/bin/bash -c /usr/sbin/dmidecode|/bin/awk ' /UUID/{ print $2; } ' | /usr/bin/tr ' >' '_' && /bin/cat /sys/class/net/*/address | /bin/sed -e '/00:00:00:00/d' -e '/^$/d' | /bin/sort -u | /usr/bin/head --lines=1` on orchid-vds1.qa.lab.tlv.redhat.com >2012-08-15 09:24:53,137 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. Received message: 06577002-2B21-3E45-9926-E52BFFFF3659_00:14:5e:17:d5:b0 >. FYI. (Stage: Get the unique vds id) >2012-08-15 09:24:53,242 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. Assigning unique id 06577002-2B21-3E45-9926-E52BFFFF3659_00:14:5e:17:d5:b0 to Host. (Stage: Get the unique vds id) >2012-08-15 09:24:53,320 INFO [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-4-thread-3) RunSSHCommand returns true >2012-08-15 09:24:53,320 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. Executing installation stage. (Stage: Upload Installation script to Host) >2012-08-15 09:24:53,320 INFO [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-4-thread-3) Uploading file /usr/share/ovirt-engine/scripts/vds_installer.py to /tmp/vds_installer_55cd9773-8add-41fc-b622-eb9edfe44955.py on orchid-vds1.qa.lab.tlv.redhat.com >2012-08-15 09:24:53,322 INFO [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-4-thread-3) Uploading file /usr/share/ovirt-engine/scripts/vds_installer.py to /tmp/vds_installer_55cd9773-8add-41fc-b622-eb9edfe44955.py on orchid-vds1.qa.lab.tlv.redhat.com >2012-08-15 09:24:54,630 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (QuartzScheduler_Worker-58) Initializing Host: orchid-vds1 >2012-08-15 09:24:55,307 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. successfully done sftp operation ( Stage: Upload Installation script to Host) >2012-08-15 09:24:55,307 INFO [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-4-thread-3) return true >2012-08-15 09:24:55,307 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. Executing installation stage. (Stage: Running first installation script on Host) >2012-08-15 09:24:55,307 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. Sending SSH Command chmod +x /tmp/vds_installer_55cd9773-8add-41fc-b622-eb9edfe44955.py; /tmp/vds_installer_55cd9773-8add-41fc-b622-eb9edfe44955.py -c 'ssl=true;management_port=54321' -O 'redhat' -t 2012-08-15T06:24:52 -p 80 -b http://meni-rhevm-si.qa.lab.tlv.redhat.com:80/Components/vds/ http://meni-rhevm-si.qa.lab.tlv.redhat.com:80/Components/vds/ orchid-vds1.qa.lab.tlv.redhat.com 55cd9773-8add-41fc-b622-eb9edfe44955 False. (Stage: Running first installation script on Host) >2012-08-15 09:24:55,307 INFO [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-4-thread-3) Invoking chmod +x /tmp/vds_installer_55cd9773-8add-41fc-b622-eb9edfe44955.py; /tmp/vds_installer_55cd9773-8add-41fc-b622-eb9edfe44955.py -c 'ssl=true;management_port=54321' -O 'redhat' -t 2012-08-15T06:24:52 -p 80 -b http://meni-rhevm-si.qa.lab.tlv.redhat.com:80/Components/vds/ http://meni-rhevm-si.qa.lab.tlv.redhat.com:80/Components/vds/ orchid-vds1.qa.lab.tlv.redhat.com 55cd9773-8add-41fc-b622-eb9edfe44955 False on orchid-vds1.qa.lab.tlv.redhat.com >2012-08-15 09:24:56,307 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='INSTALLER' status='OK' message='Test platform succeeded'/> ><BSTRAP component='INSTALLER LIB' status='OK' message='deployUtil.py download succeeded'/> ><BSTRAP component='INSTALLER' status='OK' message='vds_bootstrap.py download succeeded'/> >. FYI. (Stage: Running first installation script on Host) >2012-08-15 09:24:57,384 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='RHN_REGISTRATION' status='OK' message='Host properly registered with RHN/Satellite.'/> ><BSTRAP component='VDSM_MAJOR_VER' status='OK' message='Available VDSM matches requirements'/> ><BSTRAP component='VT_SVM' status='OK' processor='Intel' message='Server supports virtualization'/> ><BSTRAP component='OS' status='OK' type='RHEL6' message='Supported platform version'/> ><BSTRAP component='KERNEL' status='OK' version='279' message='Supported kernel version: 279'/> ><BSTRAP component='REQ PACKAGES' status='WARN' result='SDL.x86_64' message='package SDL.x86_64 is not installed '/> ><BSTRAP component='REQ PACKAGES' status='OK' result='bridge-utils.x86_64' message='bridge-utils-1.2-9.el6.x86_64 '/> ><BSTRAP component='REQ PACKAGES' status='OK' result='mesa-libGLU.x86_64' message='mesa-libGLU-7.11-5.el6.x86_64 '/> ><BSTRAP component='REQ PACKAGES' status='OK' result='openssl.x86_64' message='openssl-1.0.0-20.el6_2.5.x86_64 '/> ><BSTRAP component='REQ PACKAGES' status='OK' result='m2crypto.x86_64' message='m2crypto-0.20.2-9.el6.x86_64 '/> ><BSTRAP component='REQ PACKAGES' status='OK' result='rsync.x86_64' message='rsync-3.0.6-9.el6.x86_64 '/> ><BSTRAP component='VDS PACKAGES' status='WARN' result='qemu-kvm' message='package qemu-kvm is not installed '/> ><BSTRAP component='VDS PACKAGES' status='WARN' result='qemu-kvm-tools' message='package qemu-kvm-tools is not installed '/> ><BSTRAP component='VDS PACKAGES' status='OK' result='vdsm' message='vdsm-4.9.6-27.0.el6_3.x86_64 '/> ><BSTRAP component='VDS PACKAGES' status='WARN' result='vdsm-cli' message='package vdsm-cli is not installed '/> ><BSTRAP component='VDS PACKAGES' status='OK' result='libjpeg' message='libjpeg-6b-46.el6.x86_64 '/> ><BSTRAP component='VDS PACKAGES' status='OK' result='spice-server' message='spice-server-0.10.1-10.el6.x86_64 '/> ><BSTRAP component='VDS PACKAGES' status='OK' result='pixman' message='pixman-0.18.4-1.el6_0.1.x86_64 '/> ><BSTRAP component='VDS PACKAGES' status='OK' result='seabios' message='seabios-0.6.1.2-19.el6.x86_64 '/> ><BSTRAP component='VDS PACKAGES' status='WARN' result='qemu-img' message='package qemu-img is not installed '/> ><BSTRAP component='VDS PACKAGES' status='OK' result='fence-agents' message='fence-agents-3.1.5-17.el6.x86_64 '/> >. FYI. (Stage: Running first installation script on Host) >2012-08-15 09:24:57,849 ERROR [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-4-thread-3) Repository optional is listed more than once in the configuration >Repository qa is listed more than once in the configuration >Repository rhel-source is listed more than once in the configuration >Repository rhel-source-beta is listed more than once in the configuration >Repository rhevm is listed more than once in the configuration >Repository optional is listed more than once in the configuration >Repository qa is listed more than once in the configuration >Repository rhel-source is listed more than once in the configuration >Repository rhel-source-beta is listed more than once in the configuration >Repository rhevm is listed more than once in the configuration > >2012-08-15 09:24:57,849 ERROR [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. Error: Repository optional is listed more than once in the configuration >Repository qa is listed more than once in the configuration >Repository rhel-source is listed more than once in the configuration >Repository rhel-source-beta is listed more than once in the configuration >Repository rhevm is listed more than once in the configuration >Repository optional is listed more than once in the configuration >Repository qa is listed more than once in the configuration >Repository rhel-source is listed more than once in the configuration >Repository rhel-source-beta is listed more than once in the configuration >Repository rhevm is listed more than once in the configuration >. (Stage: Running first installation script on Host) >2012-08-15 09:24:57,849 INFO [org.ovirt.engine.core.bll.InstallerMessages] (pool-4-thread-3) [568bd786] VDS message: Repository optional is listed more than once in the configuration >2012-08-15 09:24:57,849 INFO [org.ovirt.engine.core.bll.InstallerMessages] (pool-4-thread-3) [568bd786] VDS message: Repository qa is listed more than once in the configuration >2012-08-15 09:24:57,849 INFO [org.ovirt.engine.core.bll.InstallerMessages] (pool-4-thread-3) [568bd786] VDS message: Repository rhel-source is listed more than once in the configuration >2012-08-15 09:24:57,849 INFO [org.ovirt.engine.core.bll.InstallerMessages] (pool-4-thread-3) [568bd786] VDS message: Repository rhel-source-beta is listed more than once in the configuration >2012-08-15 09:24:57,849 INFO [org.ovirt.engine.core.bll.InstallerMessages] (pool-4-thread-3) [568bd786] VDS message: Repository rhevm is listed more than once in the configuration >2012-08-15 09:24:57,850 INFO [org.ovirt.engine.core.bll.InstallerMessages] (pool-4-thread-3) [568bd786] VDS message: Repository optional is listed more than once in the configuration >2012-08-15 09:24:57,850 INFO [org.ovirt.engine.core.bll.InstallerMessages] (pool-4-thread-3) [568bd786] VDS message: Repository qa is listed more than once in the configuration >2012-08-15 09:24:57,850 INFO [org.ovirt.engine.core.bll.InstallerMessages] (pool-4-thread-3) [568bd786] VDS message: Repository rhel-source is listed more than once in the configuration >2012-08-15 09:24:57,850 INFO [org.ovirt.engine.core.bll.InstallerMessages] (pool-4-thread-3) [568bd786] VDS message: Repository rhel-source-beta is listed more than once in the configuration >2012-08-15 09:24:57,850 INFO [org.ovirt.engine.core.bll.InstallerMessages] (pool-4-thread-3) [568bd786] VDS message: Repository rhevm is listed more than once in the configuration >2012-08-15 09:24:58,851 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='VDS PACKAGES' status='OK' result='libselinux-python' message='libselinux-python-2.0.94-5.3.el6.x86_64 '/> >. FYI. (Stage: Running first installation script on Host) >2012-08-15 09:25:00,004 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-61) Autorecovering hosts is disabled, skipping >2012-08-15 09:25:00,005 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-61) Autorecovering storage domains is disabled, skipping >2012-08-15 09:25:11,872 INFO [org.ovirt.engine.core.utils.hostinstall.HostKeyVerifier] (NioProcessor-6) SSH key fingerprint 54:f4:7a:03:26:56:75:06:e7:d5:d4:09:f6:64:c4:2a for host orchid-vds2.qa.lab.tlv.redhat.com (10.35.97.59) has been successfully verified. >2012-08-15 09:25:11,951 INFO [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (ajp-/127.0.0.1:8009-4) Invoking /bin/echo -e `/bin/bash -c /usr/sbin/dmidecode|/bin/awk ' /UUID/{ print $2; } ' | /usr/bin/tr ' >' '_' && /bin/cat /sys/class/net/*/address | /bin/sed -e '/00:00:00:00/d' -e '/^$/d' | /bin/sort -u | /usr/bin/head --lines=1` on orchid-vds2.qa.lab.tlv.redhat.com >2012-08-15 09:25:12,088 INFO [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (ajp-/127.0.0.1:8009-4) RunSSHCommand returns true >2012-08-15 09:25:12,121 INFO [org.ovirt.engine.core.bll.AddVdsCommand] (ajp-/127.0.0.1:8009-4) [3c30b522] Running command: AddVdsCommand internal: false. Entities affected : ID: d3e09392-e6a1-11e1-9a04-001a4a169777 Type: VdsGroups >2012-08-15 09:25:12,182 INFO [org.ovirt.engine.core.bll.AddVdsSpmIdCommand] (ajp-/127.0.0.1:8009-4) [6461f05d] Running command: AddVdsSpmIdCommand internal: true. Entities affected : ID: f7ea1dc6-e6a1-11e1-bc36-001a4a169777 Type: VDS >2012-08-15 09:25:12,209 INFO [org.ovirt.engine.core.bll.AddVdsSpmIdCommand] (ajp-/127.0.0.1:8009-4) [6461f05d] Lock freed to object EngineLock [exclusiveLocks= key: 35352af8-fb85-46f7-8433-ee6540b14a47 value: REGISTER_VDS >, sharedLocks= ] >2012-08-15 09:25:12,209 ERROR [org.ovirt.engine.core.vdsbroker.ResourceManager] (ajp-/127.0.0.1:8009-4) [6461f05d] Cannot get vdsManager for vdsid=f7ea1dc6-e6a1-11e1-bc36-001a4a169777 >2012-08-15 09:25:12,210 INFO [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (ajp-/127.0.0.1:8009-4) [6461f05d] START, RemoveVdsVDSCommand(vdsId = f7ea1dc6-e6a1-11e1-bc36-001a4a169777), log id: 448a7dab >2012-08-15 09:25:12,210 ERROR [org.ovirt.engine.core.vdsbroker.ResourceManager] (ajp-/127.0.0.1:8009-4) [6461f05d] Cannot get vdsManager for vdsid=f7ea1dc6-e6a1-11e1-bc36-001a4a169777 >2012-08-15 09:25:12,211 INFO [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (ajp-/127.0.0.1:8009-4) [6461f05d] FINISH, RemoveVdsVDSCommand, log id: 448a7dab >2012-08-15 09:25:12,211 ERROR [org.ovirt.engine.core.vdsbroker.ResourceManager] (ajp-/127.0.0.1:8009-4) [6461f05d] Cannot get vdsManager for vdsid=f7ea1dc6-e6a1-11e1-bc36-001a4a169777 >2012-08-15 09:25:12,211 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (ajp-/127.0.0.1:8009-4) [6461f05d] START, AddVdsVDSCommand(vdsId = f7ea1dc6-e6a1-11e1-bc36-001a4a169777), log id: 4edaa2c1 >2012-08-15 09:25:12,211 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (ajp-/127.0.0.1:8009-4) [6461f05d] AddVds - entered , starting logic to add VDS f7ea1dc6-e6a1-11e1-bc36-001a4a169777 >2012-08-15 09:25:12,214 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (ajp-/127.0.0.1:8009-4) [6461f05d] AddVds - VDS f7ea1dc6-e6a1-11e1-bc36-001a4a169777 was added, will try to add it to the resource manager >2012-08-15 09:25:12,219 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (ajp-/127.0.0.1:8009-4) [6461f05d] Eneterd VdsManager:constructor >2012-08-15 09:25:12,224 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (ajp-/127.0.0.1:8009-4) [6461f05d] vdsBroker(orchid-vds2.qa.lab.tlv.redhat.com,54,321) >2012-08-15 09:25:12,225 INFO [org.ovirt.engine.core.vdsbroker.ResourceManager] (ajp-/127.0.0.1:8009-4) [6461f05d] ResourceManager::AddVds - VDS f7ea1dc6-e6a1-11e1-bc36-001a4a169777 was added to the Resource Manager >2012-08-15 09:25:12,225 INFO [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (ajp-/127.0.0.1:8009-4) [6461f05d] FINISH, AddVdsVDSCommand, log id: 4edaa2c1 >2012-08-15 09:25:12,360 INFO [org.ovirt.engine.core.bll.InstallVdsCommand] (pool-4-thread-4) [5f9896a1] Running command: InstallVdsCommand internal: true. Entities affected : ID: f7ea1dc6-e6a1-11e1-bc36-001a4a169777 Type: VDS >2012-08-15 09:25:12,373 INFO [org.ovirt.engine.core.bll.InstallVdsCommand] (pool-4-thread-4) [5f9896a1] Before Installation pool-4-thread-4 >2012-08-15 09:25:12,373 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. Executing installation stage. (Stage: Starting Host installation) >2012-08-15 09:25:12,373 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. Executing installation stage. (Stage: Connecting to Host) >2012-08-15 09:25:12,404 INFO [org.ovirt.engine.core.utils.hostinstall.HostKeyVerifier] (NioProcessor-7) SSH key fingerprint 54:f4:7a:03:26:56:75:06:e7:d5:d4:09:f6:64:c4:2a for host orchid-vds2.qa.lab.tlv.redhat.com (10.35.97.59) has been successfully verified. >2012-08-15 09:25:12,479 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='RHEV_INSTALL' status='OK' message='Connected to Host orchid-vds2.qa.lab.tlv.redhat.com with SSH key fingerprint: 54:f4:7a:03:26:56:75:06:e7:d5:d4:09:f6:64:c4:2a'/>. FYI. (Stage: Connecting to Host) >2012-08-15 09:25:12,496 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. Successfully connected to server ssh. (Stage: Connecting to Host) >2012-08-15 09:25:12,496 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. Executing installation stage. (Stage: Get the unique vds id) >2012-08-15 09:25:12,497 INFO [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-4-thread-4) Invoking /bin/echo -e `/bin/bash -c /usr/sbin/dmidecode|/bin/awk ' /UUID/{ print $2; } ' | /usr/bin/tr ' >' '_' && /bin/cat /sys/class/net/*/address | /bin/sed -e '/00:00:00:00/d' -e '/^$/d' | /bin/sort -u | /usr/bin/head --lines=1` on orchid-vds2.qa.lab.tlv.redhat.com >2012-08-15 09:25:12,600 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. Received message: 730B44F9-7683-3678-B843-F36FA3BFA798_00:14:5e:17:d4:64 >. FYI. (Stage: Get the unique vds id) >2012-08-15 09:25:12,603 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. Assigning unique id 730B44F9-7683-3678-B843-F36FA3BFA798_00:14:5e:17:d4:64 to Host. (Stage: Get the unique vds id) >2012-08-15 09:25:12,619 INFO [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-4-thread-4) RunSSHCommand returns true >2012-08-15 09:25:12,619 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. Executing installation stage. (Stage: Upload Installation script to Host) >2012-08-15 09:25:12,619 INFO [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-4-thread-4) Uploading file /usr/share/ovirt-engine/scripts/vds_installer.py to /tmp/vds_installer_aa248884-4ac0-4b3b-b976-b276074ba963.py on orchid-vds2.qa.lab.tlv.redhat.com >2012-08-15 09:25:12,620 INFO [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-4-thread-4) Uploading file /usr/share/ovirt-engine/scripts/vds_installer.py to /tmp/vds_installer_aa248884-4ac0-4b3b-b976-b276074ba963.py on orchid-vds2.qa.lab.tlv.redhat.com >2012-08-15 09:25:14,230 INFO [org.ovirt.engine.core.vdsbroker.VdsManager] (QuartzScheduler_Worker-73) Initializing Host: orchid-vds2 >2012-08-15 09:25:15,024 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. successfully done sftp operation ( Stage: Upload Installation script to Host) >2012-08-15 09:25:15,024 INFO [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-4-thread-4) return true >2012-08-15 09:25:15,024 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. Executing installation stage. (Stage: Running first installation script on Host) >2012-08-15 09:25:15,024 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. Sending SSH Command chmod +x /tmp/vds_installer_aa248884-4ac0-4b3b-b976-b276074ba963.py; /tmp/vds_installer_aa248884-4ac0-4b3b-b976-b276074ba963.py -c 'ssl=true;management_port=54321' -O 'redhat' -t 2012-08-15T06:25:12 -p 80 -b http://meni-rhevm-si.qa.lab.tlv.redhat.com:80/Components/vds/ http://meni-rhevm-si.qa.lab.tlv.redhat.com:80/Components/vds/ orchid-vds2.qa.lab.tlv.redhat.com aa248884-4ac0-4b3b-b976-b276074ba963 False. (Stage: Running first installation script on Host) >2012-08-15 09:25:15,025 INFO [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-4-thread-4) Invoking chmod +x /tmp/vds_installer_aa248884-4ac0-4b3b-b976-b276074ba963.py; /tmp/vds_installer_aa248884-4ac0-4b3b-b976-b276074ba963.py -c 'ssl=true;management_port=54321' -O 'redhat' -t 2012-08-15T06:25:12 -p 80 -b http://meni-rhevm-si.qa.lab.tlv.redhat.com:80/Components/vds/ http://meni-rhevm-si.qa.lab.tlv.redhat.com:80/Components/vds/ orchid-vds2.qa.lab.tlv.redhat.com aa248884-4ac0-4b3b-b976-b276074ba963 False on orchid-vds2.qa.lab.tlv.redhat.com >2012-08-15 09:25:16,026 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='INSTALLER' status='OK' message='Test platform succeeded'/> ><BSTRAP component='INSTALLER LIB' status='OK' message='deployUtil.py download succeeded'/> ><BSTRAP component='INSTALLER' status='OK' message='vds_bootstrap.py download succeeded'/> >. FYI. (Stage: Running first installation script on Host) >2012-08-15 09:25:17,104 ERROR [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-4-thread-4) Repository optional is listed more than once in the configuration >Repository rhel-source is listed more than once in the configuration >Repository rhel-source-beta is listed more than once in the configuration >Repository rhevm is listed more than once in the configuration > >2012-08-15 09:25:17,105 ERROR [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. Error: Repository optional is listed more than once in the configuration >Repository rhel-source is listed more than once in the configuration >Repository rhel-source-beta is listed more than once in the configuration >Repository rhevm is listed more than once in the configuration >. (Stage: Running first installation script on Host) >2012-08-15 09:25:17,105 INFO [org.ovirt.engine.core.bll.InstallerMessages] (pool-4-thread-4) [5f9896a1] VDS message: Repository optional is listed more than once in the configuration >2012-08-15 09:25:17,105 INFO [org.ovirt.engine.core.bll.InstallerMessages] (pool-4-thread-4) [5f9896a1] VDS message: Repository rhel-source is listed more than once in the configuration >2012-08-15 09:25:17,105 INFO [org.ovirt.engine.core.bll.InstallerMessages] (pool-4-thread-4) [5f9896a1] VDS message: Repository rhel-source-beta is listed more than once in the configuration >2012-08-15 09:25:17,105 INFO [org.ovirt.engine.core.bll.InstallerMessages] (pool-4-thread-4) [5f9896a1] VDS message: Repository rhevm is listed more than once in the configuration >2012-08-15 09:25:19,107 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='RHN_REGISTRATION' status='OK' message='Host properly registered with RHN/Satellite.'/> ><BSTRAP component='VDSM_MAJOR_VER' status='OK' message='Available VDSM matches requirements'/> ><BSTRAP component='VT_SVM' status='OK' processor='Intel' message='Server supports virtualization'/> ><BSTRAP component='OS' status='OK' type='RHEL6' message='Supported platform version'/> ><BSTRAP component='KERNEL' status='OK' version='279' message='Supported kernel version: 279'/> ><BSTRAP component='REQ PACKAGES' status='WARN' result='SDL.x86_64' message='package SDL.x86_64 is not installed '/> ><BSTRAP component='REQ PACKAGES' status='OK' result='bridge-utils.x86_64' message='bridge-utils-1.2-9.el6.x86_64 '/> ><BSTRAP component='REQ PACKAGES' status='OK' result='mesa-libGLU.x86_64' message='mesa-libGLU-7.11-5.el6.x86_64 '/> ><BSTRAP component='REQ PACKAGES' status='OK' result='openssl.x86_64' message='openssl-1.0.0-20.el6_2.5.x86_64 '/> ><BSTRAP component='REQ PACKAGES' status='OK' result='m2crypto.x86_64' message='m2crypto-0.20.2-9.el6.x86_64 '/> ><BSTRAP component='REQ PACKAGES' status='OK' result='rsync.x86_64' message='rsync-3.0.6-9.el6.x86_64 '/> ><BSTRAP component='VDS PACKAGES' status='WARN' result='qemu-kvm' message='package qemu-kvm is not installed '/> ><BSTRAP component='VDS PACKAGES' status='WARN' result='qemu-kvm-tools' message='package qemu-kvm-tools is not installed '/> ><BSTRAP component='VDS PACKAGES' status='OK' result='vdsm' message='vdsm-4.9.6-27.0.el6_3.x86_64 '/> ><BSTRAP component='VDS PACKAGES' status='WARN' result='vdsm-cli' message='package vdsm-cli is not installed '/> ><BSTRAP component='VDS PACKAGES' status='OK' result='libjpeg' message='libjpeg-6b-46.el6.x86_64 '/> ><BSTRAP component='VDS PACKAGES' status='OK' result='spice-server' message='spice-server-0.10.1-10.el6.x86_64 '/> ><BSTRAP component='VDS PACKAGES' status='OK' result='pixman' message='pixman-0.18.4-1.el6_0.1.x86_64 '/> ><BSTRAP component='VDS PACKAGES' status='OK' result='seabios' message='seabios-0.6.1.2-19.el6.x86_64 '/> >. FYI. (Stage: Running first installation script on Host) >2012-08-15 09:25:19,475 ERROR [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-4-thread-4) Repository optional is listed more than once in the configuration >Repository rhel-source is listed more than once in the configuration >Repository rhel-source-beta is listed more than once in the configuration >Repository rhevm is listed more than once in the configuration > >2012-08-15 09:25:19,475 ERROR [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. Error: Repository optional is listed more than once in the configuration >Repository rhel-source is listed more than once in the configuration >Repository rhel-source-beta is listed more than once in the configuration >Repository rhevm is listed more than once in the configuration >. (Stage: Running first installation script on Host) >2012-08-15 09:25:19,475 INFO [org.ovirt.engine.core.bll.InstallerMessages] (pool-4-thread-4) [5f9896a1] VDS message: Repository optional is listed more than once in the configuration >2012-08-15 09:25:19,475 INFO [org.ovirt.engine.core.bll.InstallerMessages] (pool-4-thread-4) [5f9896a1] VDS message: Repository rhel-source is listed more than once in the configuration >2012-08-15 09:25:19,475 INFO [org.ovirt.engine.core.bll.InstallerMessages] (pool-4-thread-4) [5f9896a1] VDS message: Repository rhel-source-beta is listed more than once in the configuration >2012-08-15 09:25:19,475 INFO [org.ovirt.engine.core.bll.InstallerMessages] (pool-4-thread-4) [5f9896a1] VDS message: Repository rhevm is listed more than once in the configuration >2012-08-15 09:25:20,476 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='VDS PACKAGES' status='WARN' result='qemu-img' message='package qemu-img is not installed '/> ><BSTRAP component='VDS PACKAGES' status='OK' result='fence-agents' message='fence-agents-3.1.5-17.el6.x86_64 '/> ><BSTRAP component='VDS PACKAGES' status='OK' result='libselinux-python' message='libselinux-python-2.0.94-5.3.el6.x86_64 '/> >. FYI. (Stage: Running first installation script on Host) >2012-08-15 09:25:58,894 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='REQ PACKAGES' status='OK' result='SDL.x86_64' message='SDL-1.2.14-3.el6.x86_64 '/> >. FYI. (Stage: Running first installation script on Host) >2012-08-15 09:26:00,926 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='VDS PACKAGES' status='OK' result='vdsm' message='vdsm-4.9.6-27.0.el6_3.x86_64 '/> >. FYI. (Stage: Running first installation script on Host) >2012-08-15 09:26:01,943 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='VDS PACKAGES' status='OK' result='libjpeg' message='libjpeg-6b-46.el6.x86_64 '/> >. FYI. (Stage: Running first installation script on Host) >2012-08-15 09:26:03,982 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='VDS PACKAGES' status='OK' result='spice-server' message='spice-server-0.10.1-10.el6.x86_64 '/> >. FYI. (Stage: Running first installation script on Host) >2012-08-15 09:26:06,000 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='VDS PACKAGES' status='OK' result='pixman' message='pixman-0.18.4-1.el6_0.1.x86_64 '/> >. FYI. (Stage: Running first installation script on Host) >2012-08-15 09:26:08,024 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='VDS PACKAGES' status='OK' result='seabios' message='seabios-0.6.1.2-19.el6.x86_64 '/> >. FYI. (Stage: Running first installation script on Host) >2012-08-15 09:26:10,158 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='VDS PACKAGES' status='OK' result='fence-agents' message='fence-agents-3.1.5-17.el6.x86_64 '/> >. FYI. (Stage: Running first installation script on Host) >2012-08-15 09:26:11,177 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='VDS PACKAGES' status='OK' result='libselinux-python' message='libselinux-python-2.0.94-5.3.el6.x86_64 '/> >. FYI. (Stage: Running first installation script on Host) >2012-08-15 09:26:13,202 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='VDS PACKAGES' status='OK' result='qemu-img' message='package qemu-img is not installed '/> >. FYI. (Stage: Running first installation script on Host) >2012-08-15 09:26:20,551 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='REQ PACKAGES' status='OK' result='SDL.x86_64' message='SDL-1.2.14-3.el6.x86_64 '/> >. FYI. (Stage: Running first installation script on Host) >2012-08-15 09:26:21,261 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='VDS PACKAGES' status='OK' result='vdsm-cli' message='vdsm-cli-4.9.6-27.0.el6_3.noarch '/> >. FYI. (Stage: Running first installation script on Host) >2012-08-15 09:26:22,588 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='VDS PACKAGES' status='OK' result='vdsm' message='vdsm-4.9.6-27.0.el6_3.x86_64 '/> >. FYI. (Stage: Running first installation script on Host) >2012-08-15 09:26:24,624 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='VDS PACKAGES' status='OK' result='libjpeg' message='libjpeg-6b-46.el6.x86_64 '/> >. FYI. (Stage: Running first installation script on Host) >2012-08-15 09:26:25,646 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='VDS PACKAGES' status='OK' result='spice-server' message='spice-server-0.10.1-10.el6.x86_64 '/> >. FYI. (Stage: Running first installation script on Host) >2012-08-15 09:26:27,670 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='VDS PACKAGES' status='OK' result='pixman' message='pixman-0.18.4-1.el6_0.1.x86_64 '/> >. FYI. (Stage: Running first installation script on Host) >2012-08-15 09:26:28,293 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='VDS PACKAGES' status='OK' result='qemu-kvm-tools' message='package qemu-kvm-tools is not installed '/> >. FYI. (Stage: Running first installation script on Host) >2012-08-15 09:26:29,709 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='VDS PACKAGES' status='OK' result='seabios' message='seabios-0.6.1.2-19.el6.x86_64 '/> >. FYI. (Stage: Running first installation script on Host) >2012-08-15 09:26:30,326 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='VDS PACKAGES' status='OK' result='qemu-kvm' message='package qemu-kvm is not installed '/> ><BSTRAP component='CreateConf' status='OK' message='Basic configuration set'/> ><BSTRAP component='CheckLocalHostname' status='OK' message='Local hostname is correct.'/> >. FYI. (Stage: Running first installation script on Host) >2012-08-15 09:26:31,729 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='VDS PACKAGES' status='OK' result='fence-agents' message='fence-agents-3.1.5-17.el6.x86_64 '/> >. FYI. (Stage: Running first installation script on Host) >2012-08-15 09:26:33,777 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='VDS PACKAGES' status='OK' result='libselinux-python' message='libselinux-python-2.0.94-5.3.el6.x86_64 '/> >. FYI. (Stage: Running first installation script on Host) >2012-08-15 09:26:34,794 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='VDS PACKAGES' status='OK' result='qemu-img' message='package qemu-img is not installed '/> >. FYI. (Stage: Running first installation script on Host) >2012-08-15 09:26:36,397 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='SetNetworking' status='OK' message='Created management bridge.'/> ><BSTRAP component='SetSSHAccess' status='OK' message='SUCCESS'/> >. FYI. (Stage: Running first installation script on Host) >2012-08-15 09:26:37,431 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='SET_SYSTEM_TIME' status='OK' message='setSystemTime ended successfully'/> >. FYI. (Stage: Running first installation script on Host) >2012-08-15 09:26:38,488 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='VerifyServices' status='OK' message='Needed services set'/> >. FYI. (Stage: Running first installation script on Host) >2012-08-15 09:26:42,818 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='VDS PACKAGES' status='OK' result='vdsm-cli' message='vdsm-cli-4.9.6-27.0.el6_3.noarch '/> >. FYI. (Stage: Running first installation script on Host) >2012-08-15 09:26:45,685 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='Encryption setup' status='OK' message='Ended successfully'/> ><BSTRAP component='RHEV_INSTALL' status='OK'/> >. Stage completed. (Stage: Running first installation script on Host) >2012-08-15 09:26:45,731 INFO [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-4-thread-3) RunSSHCommand returns true >2012-08-15 09:26:45,731 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] RunScript ended:true >2012-08-15 09:26:45,731 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. Executing installation stage. (Stage: Downloading certificate request from Host) >2012-08-15 09:26:45,731 INFO [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-4-thread-3) Downloading file /tmp/cert_55cd9773-8add-41fc-b622-eb9edfe44955.req from orchid-vds1.qa.lab.tlv.redhat.com to /etc/pki/ovirt-engine/requests/cert_55cd9773-8add-41fc-b622-eb9edfe44955.req >2012-08-15 09:26:47,003 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. successfully done sftp operation ( Stage: Downloading certificate request from Host) >2012-08-15 09:26:47,003 INFO [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-4-thread-3) return true >2012-08-15 09:26:47,003 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] DownloadCertificateRequest ended:true >2012-08-15 09:26:47,003 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. Executing installation stage. (Stage: Sign certificate request and generate certificate) >2012-08-15 09:26:48,034 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] SignCertificateRequest ended:true >2012-08-15 09:26:48,035 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. Executing installation stage. (Stage: Upload signed sertificate to Host) >2012-08-15 09:26:48,035 INFO [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-4-thread-3) Uploading file /etc/pki/ovirt-engine/certs/orchid-vds1.qa.lab.tlv.redhat.comcert.pem to /tmp/cert_55cd9773-8add-41fc-b622-eb9edfe44955.pem on orchid-vds1.qa.lab.tlv.redhat.com >2012-08-15 09:26:48,035 INFO [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-4-thread-3) Uploading file /etc/pki/ovirt-engine/certs/orchid-vds1.qa.lab.tlv.redhat.comcert.pem to /tmp/cert_55cd9773-8add-41fc-b622-eb9edfe44955.pem on orchid-vds1.qa.lab.tlv.redhat.com >2012-08-15 09:26:48,249 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. successfully done sftp operation ( Stage: Upload signed sertificate to Host) >2012-08-15 09:26:48,249 INFO [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-4-thread-3) return true >2012-08-15 09:26:48,249 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] UploadSignedCertificate ended:true >2012-08-15 09:26:48,250 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. Executing installation stage. (Stage: Upload Cerficate Autority to Host) >2012-08-15 09:26:48,250 INFO [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-4-thread-3) Uploading file /etc/pki/ovirt-engine/ca.pem to /tmp/CA_55cd9773-8add-41fc-b622-eb9edfe44955.pem on orchid-vds1.qa.lab.tlv.redhat.com >2012-08-15 09:26:48,250 INFO [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-4-thread-3) Uploading file /etc/pki/ovirt-engine/ca.pem to /tmp/CA_55cd9773-8add-41fc-b622-eb9edfe44955.pem on orchid-vds1.qa.lab.tlv.redhat.com >2012-08-15 09:26:48,430 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. successfully done sftp operation ( Stage: Upload Cerficate Autority to Host) >2012-08-15 09:26:48,430 INFO [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-4-thread-3) return true >2012-08-15 09:26:48,430 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. Executing installation stage. (Stage: Running second installation script on Host) >2012-08-15 09:26:48,430 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. Sending SSH Command chmod +x /tmp/vds_installer_55cd9773-8add-41fc-b622-eb9edfe44955.py; /tmp/vds_installer_55cd9773-8add-41fc-b622-eb9edfe44955.py -c 'ssl=true;management_port=54321' -O 'redhat' -t 2012-08-15T06:24:52 -p 80 -b http://meni-rhevm-si.qa.lab.tlv.redhat.com:80/Components/vds/ http://meni-rhevm-si.qa.lab.tlv.redhat.com:80/Components/vds/ orchid-vds1.qa.lab.tlv.redhat.com 55cd9773-8add-41fc-b622-eb9edfe44955 True. (Stage: Running second installation script on Host) >2012-08-15 09:26:48,430 INFO [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-4-thread-3) Invoking chmod +x /tmp/vds_installer_55cd9773-8add-41fc-b622-eb9edfe44955.py; /tmp/vds_installer_55cd9773-8add-41fc-b622-eb9edfe44955.py -c 'ssl=true;management_port=54321' -O 'redhat' -t 2012-08-15T06:24:52 -p 80 -b http://meni-rhevm-si.qa.lab.tlv.redhat.com:80/Components/vds/ http://meni-rhevm-si.qa.lab.tlv.redhat.com:80/Components/vds/ orchid-vds1.qa.lab.tlv.redhat.com 55cd9773-8add-41fc-b622-eb9edfe44955 True on orchid-vds1.qa.lab.tlv.redhat.com >2012-08-15 09:26:49,431 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='INSTALLER' status='OK' message='Test platform succeeded'/> ><BSTRAP component='INSTALLER LIB' status='OK' message='Install library already exists'/> ><BSTRAP component='INSTALLER' status='OK' message='vds_bootstrap_complete.py download succeeded'/> >. FYI. (Stage: Running second installation script on Host) >2012-08-15 09:26:50,833 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='VDS PACKAGES' status='OK' result='qemu-kvm-tools' message='package qemu-kvm-tools is not installed '/> >. FYI. (Stage: Running first installation script on Host) >2012-08-15 09:26:51,870 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='VDS PACKAGES' status='OK' result='qemu-kvm' message='package qemu-kvm is not installed '/> ><BSTRAP component='CreateConf' status='OK' message='Basic configuration set'/> ><BSTRAP component='CheckLocalHostname' status='OK' message='Local hostname is correct.'/> >. FYI. (Stage: Running first installation script on Host) >2012-08-15 09:27:07,924 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='SetNetworking' status='OK' message='Created management bridge.'/> ><BSTRAP component='SetSSHAccess' status='OK' message='SUCCESS'/> >. FYI. (Stage: Running first installation script on Host) >2012-08-15 09:27:08,970 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='SET_SYSTEM_TIME' status='OK' message='setSystemTime ended successfully'/> >. FYI. (Stage: Running first installation script on Host) >2012-08-15 09:27:09,996 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='VerifyServices' status='OK' message='Needed services set'/> >. FYI. (Stage: Running first installation script on Host) >2012-08-15 09:27:15,091 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='Encryption setup' status='OK' message='Ended successfully'/> ><BSTRAP component='RHEV_INSTALL' status='OK'/> >. Stage completed. (Stage: Running first installation script on Host) >2012-08-15 09:27:15,132 INFO [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-4-thread-4) RunSSHCommand returns true >2012-08-15 09:27:15,132 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] RunScript ended:true >2012-08-15 09:27:15,132 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. Executing installation stage. (Stage: Downloading certificate request from Host) >2012-08-15 09:27:15,132 INFO [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-4-thread-4) Downloading file /tmp/cert_aa248884-4ac0-4b3b-b976-b276074ba963.req from orchid-vds2.qa.lab.tlv.redhat.com to /etc/pki/ovirt-engine/requests/cert_aa248884-4ac0-4b3b-b976-b276074ba963.req >2012-08-15 09:27:16,444 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] Installation of orchid-vds1.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='instCert' status='OK'/> ><BSTRAP component='CoreDump' status='OK'/> ><BSTRAP component='cleanAll' status='OK'/> ><BSTRAP component='VDS Configuration' status='OK'/> ><BSTRAP component='RHEV_INSTALL' status='OK'/> ><BSTRAP component='Reboot' status='OK' message='Rebooting machine' /> >. Stage completed. (Stage: Running second installation script on Host) >2012-08-15 09:27:16,543 INFO [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-4-thread-3) RunSSHCommand returns true >2012-08-15 09:27:16,543 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-3) [568bd786] FinishCommand ended:true >2012-08-15 09:27:16,545 INFO [org.ovirt.engine.core.bll.InstallVdsCommand] (pool-4-thread-3) [568bd786] After Installation pool-4-thread-3 >2012-08-15 09:27:16,552 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-4-thread-3) [568bd786] START, SetVdsStatusVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777, status=Reboot, nonOperationalReason=NONE), log id: 5e700047 >2012-08-15 09:27:16,601 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-4-thread-3) [568bd786] FINISH, SetVdsStatusVDSCommand, log id: 5e700047 >2012-08-15 09:27:16,608 INFO [org.ovirt.engine.core.bll.InstallVdsCommand] (pool-4-thread-5) [568bd786] Waiting 300 seconds, for server to finish reboot process. >2012-08-15 09:27:16,704 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. successfully done sftp operation ( Stage: Downloading certificate request from Host) >2012-08-15 09:27:16,704 INFO [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-4-thread-4) return true >2012-08-15 09:27:16,704 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] DownloadCertificateRequest ended:true >2012-08-15 09:27:16,704 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. Executing installation stage. (Stage: Sign certificate request and generate certificate) >2012-08-15 09:27:17,708 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] SignCertificateRequest ended:true >2012-08-15 09:27:17,709 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. Executing installation stage. (Stage: Upload signed sertificate to Host) >2012-08-15 09:27:17,709 INFO [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-4-thread-4) Uploading file /etc/pki/ovirt-engine/certs/orchid-vds2.qa.lab.tlv.redhat.comcert.pem to /tmp/cert_aa248884-4ac0-4b3b-b976-b276074ba963.pem on orchid-vds2.qa.lab.tlv.redhat.com >2012-08-15 09:27:17,709 INFO [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-4-thread-4) Uploading file /etc/pki/ovirt-engine/certs/orchid-vds2.qa.lab.tlv.redhat.comcert.pem to /tmp/cert_aa248884-4ac0-4b3b-b976-b276074ba963.pem on orchid-vds2.qa.lab.tlv.redhat.com >2012-08-15 09:27:17,930 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. successfully done sftp operation ( Stage: Upload signed sertificate to Host) >2012-08-15 09:27:17,930 INFO [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-4-thread-4) return true >2012-08-15 09:27:17,930 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] UploadSignedCertificate ended:true >2012-08-15 09:27:17,930 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. Executing installation stage. (Stage: Upload Cerficate Autority to Host) >2012-08-15 09:27:17,931 INFO [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-4-thread-4) Uploading file /etc/pki/ovirt-engine/ca.pem to /tmp/CA_aa248884-4ac0-4b3b-b976-b276074ba963.pem on orchid-vds2.qa.lab.tlv.redhat.com >2012-08-15 09:27:17,931 INFO [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-4-thread-4) Uploading file /etc/pki/ovirt-engine/ca.pem to /tmp/CA_aa248884-4ac0-4b3b-b976-b276074ba963.pem on orchid-vds2.qa.lab.tlv.redhat.com >2012-08-15 09:27:18,108 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. successfully done sftp operation ( Stage: Upload Cerficate Autority to Host) >2012-08-15 09:27:18,108 INFO [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-4-thread-4) return true >2012-08-15 09:27:18,108 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. Executing installation stage. (Stage: Running second installation script on Host) >2012-08-15 09:27:18,108 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. Sending SSH Command chmod +x /tmp/vds_installer_aa248884-4ac0-4b3b-b976-b276074ba963.py; /tmp/vds_installer_aa248884-4ac0-4b3b-b976-b276074ba963.py -c 'ssl=true;management_port=54321' -O 'redhat' -t 2012-08-15T06:25:12 -p 80 -b http://meni-rhevm-si.qa.lab.tlv.redhat.com:80/Components/vds/ http://meni-rhevm-si.qa.lab.tlv.redhat.com:80/Components/vds/ orchid-vds2.qa.lab.tlv.redhat.com aa248884-4ac0-4b3b-b976-b276074ba963 True. (Stage: Running second installation script on Host) >2012-08-15 09:27:18,108 INFO [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-4-thread-4) Invoking chmod +x /tmp/vds_installer_aa248884-4ac0-4b3b-b976-b276074ba963.py; /tmp/vds_installer_aa248884-4ac0-4b3b-b976-b276074ba963.py -c 'ssl=true;management_port=54321' -O 'redhat' -t 2012-08-15T06:25:12 -p 80 -b http://meni-rhevm-si.qa.lab.tlv.redhat.com:80/Components/vds/ http://meni-rhevm-si.qa.lab.tlv.redhat.com:80/Components/vds/ orchid-vds2.qa.lab.tlv.redhat.com aa248884-4ac0-4b3b-b976-b276074ba963 True on orchid-vds2.qa.lab.tlv.redhat.com >2012-08-15 09:27:19,108 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='INSTALLER' status='OK' message='Test platform succeeded'/> ><BSTRAP component='INSTALLER LIB' status='OK' message='Install library already exists'/> ><BSTRAP component='INSTALLER' status='OK' message='vds_bootstrap_complete.py download succeeded'/> >. FYI. (Stage: Running second installation script on Host) >2012-08-15 09:27:46,312 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] Installation of orchid-vds2.qa.lab.tlv.redhat.com. Received message: <BSTRAP component='instCert' status='OK'/> ><BSTRAP component='CoreDump' status='OK'/> ><BSTRAP component='cleanAll' status='OK'/> ><BSTRAP component='VDS Configuration' status='OK'/> ><BSTRAP component='RHEV_INSTALL' status='OK'/> ><BSTRAP component='Reboot' status='OK' message='Rebooting machine' /> >. Stage completed. (Stage: Running second installation script on Host) >2012-08-15 09:27:46,419 INFO [org.ovirt.engine.core.utils.hostinstall.MinaInstallWrapper] (pool-4-thread-4) RunSSHCommand returns true >2012-08-15 09:27:46,419 INFO [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [5f9896a1] FinishCommand ended:true >2012-08-15 09:27:46,420 INFO [org.ovirt.engine.core.bll.InstallVdsCommand] (pool-4-thread-4) [5f9896a1] After Installation pool-4-thread-4 >2012-08-15 09:27:46,422 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-4-thread-4) [5f9896a1] START, SetVdsStatusVDSCommand(vdsId = f7ea1dc6-e6a1-11e1-bc36-001a4a169777, status=Reboot, nonOperationalReason=NONE), log id: 243de4e8 >2012-08-15 09:27:46,457 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-4-thread-4) [5f9896a1] FINISH, SetVdsStatusVDSCommand, log id: 243de4e8 >2012-08-15 09:27:46,468 INFO [org.ovirt.engine.core.bll.InstallVdsCommand] (pool-4-thread-6) [5f9896a1] Waiting 300 seconds, for server to finish reboot process. >2012-08-15 09:30:00,003 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-32) Autorecovering hosts is disabled, skipping >2012-08-15 09:30:00,003 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-32) Autorecovering storage domains is disabled, skipping >2012-08-15 09:32:16,609 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-4-thread-5) [568bd786] START, SetVdsStatusVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777, status=NonResponsive, nonOperationalReason=NONE), log id: 1afe9df1 >2012-08-15 09:32:16,655 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-4-thread-5) [568bd786] FINISH, SetVdsStatusVDSCommand, log id: 1afe9df1 >2012-08-15 09:32:19,269 INFO [org.ovirt.engine.core.bll.InitVdsOnUpCommand] (QuartzScheduler_Worker-8) [75c7471d] Running command: InitVdsOnUpCommand internal: true. >2012-08-15 09:32:19,298 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (QuartzScheduler_Worker-8) [75c7471d] No string for UNASSIGNED type. Use default Log >2012-08-15 09:32:19,336 INFO [org.ovirt.engine.core.bll.MultipleActionsRunner] (pool-4-thread-10) [75c7471d] MultipleActionsRunner of type MigrateVmToServer invoked with no actions >2012-08-15 09:32:19,372 INFO [org.ovirt.engine.core.bll.HandleVdsCpuFlagsOrClusterChangedCommand] (QuartzScheduler_Worker-8) [328183fe] Running command: HandleVdsCpuFlagsOrClusterChangedCommand internal: true. Entities affected : ID: ec084258-e6a1-11e1-a8e4-001a4a169777 Type: VDS >2012-08-15 09:32:19,389 INFO [org.ovirt.engine.core.bll.HandleVdsVersionCommand] (QuartzScheduler_Worker-8) [4c437526] Running command: HandleVdsVersionCommand internal: true. Entities affected : ID: ec084258-e6a1-11e1-a8e4-001a4a169777 Type: VDS >2012-08-15 09:32:46,470 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-4-thread-6) [5f9896a1] START, SetVdsStatusVDSCommand(vdsId = f7ea1dc6-e6a1-11e1-bc36-001a4a169777, status=NonResponsive, nonOperationalReason=NONE), log id: 5c19d624 >2012-08-15 09:32:46,508 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-4-thread-6) [5f9896a1] FINISH, SetVdsStatusVDSCommand, log id: 5c19d624 >2012-08-15 09:32:48,361 INFO [org.ovirt.engine.core.bll.InitVdsOnUpCommand] (QuartzScheduler_Worker-43) [7c3c2e4a] Running command: InitVdsOnUpCommand internal: true. >2012-08-15 09:32:48,381 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (QuartzScheduler_Worker-43) [7c3c2e4a] No string for UNASSIGNED type. Use default Log >2012-08-15 09:32:48,395 INFO [org.ovirt.engine.core.bll.MultipleActionsRunner] (pool-4-thread-29) [7c3c2e4a] MultipleActionsRunner of type MigrateVmToServer invoked with no actions >2012-08-15 09:32:48,402 INFO [org.ovirt.engine.core.bll.HandleVdsCpuFlagsOrClusterChangedCommand] (QuartzScheduler_Worker-43) [5b759260] Running command: HandleVdsCpuFlagsOrClusterChangedCommand internal: true. Entities affected : ID: f7ea1dc6-e6a1-11e1-bc36-001a4a169777 Type: VDS >2012-08-15 09:32:48,414 INFO [org.ovirt.engine.core.bll.HandleVdsVersionCommand] (QuartzScheduler_Worker-43) [3f09373] Running command: HandleVdsVersionCommand internal: true. Entities affected : ID: f7ea1dc6-e6a1-11e1-bc36-001a4a169777 Type: VDS >2012-08-15 09:35:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-5) Autorecovering hosts is disabled, skipping >2012-08-15 09:35:00,003 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-5) Autorecovering storage domains is disabled, skipping >2012-08-15 09:40:00,003 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-75) Autorecovering hosts is disabled, skipping >2012-08-15 09:40:00,003 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-75) Autorecovering storage domains is disabled, skipping >2012-08-15 09:44:59,998 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-45) Autorecovering hosts is disabled, skipping >2012-08-15 09:44:59,998 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-45) Autorecovering storage domains is disabled, skipping >2012-08-15 09:50:00,002 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-17) Autorecovering hosts is disabled, skipping >2012-08-15 09:50:00,002 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-17) Autorecovering storage domains is disabled, skipping >2012-08-15 09:55:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-86) Autorecovering hosts is disabled, skipping >2012-08-15 09:55:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-86) Autorecovering storage domains is disabled, skipping >2012-08-15 10:00:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-62) Autorecovering hosts is disabled, skipping >2012-08-15 10:00:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-62) Autorecovering storage domains is disabled, skipping >2012-08-15 10:05:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-33) Autorecovering hosts is disabled, skipping >2012-08-15 10:05:00,002 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-33) Autorecovering storage domains is disabled, skipping >2012-08-15 10:10:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-2) Autorecovering hosts is disabled, skipping >2012-08-15 10:10:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-2) Autorecovering storage domains is disabled, skipping >2012-08-15 10:15:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-76) Autorecovering hosts is disabled, skipping >2012-08-15 10:15:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-76) Autorecovering storage domains is disabled, skipping >2012-08-15 10:20:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-46) Autorecovering hosts is disabled, skipping >2012-08-15 10:20:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-46) Autorecovering storage domains is disabled, skipping >2012-08-15 10:21:04,178 INFO [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-24) DbUserCacheManager::refreshAllUserData() - entered >2012-08-15 10:25:00,002 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-21) Autorecovering hosts is disabled, skipping >2012-08-15 10:25:00,002 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-21) Autorecovering storage domains is disabled, skipping >2012-08-15 10:30:00,000 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-95) Autorecovering hosts is disabled, skipping >2012-08-15 10:30:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-95) Autorecovering storage domains is disabled, skipping >2012-08-15 10:35:00,003 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-71) Autorecovering hosts is disabled, skipping >2012-08-15 10:35:00,003 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-71) Autorecovering storage domains is disabled, skipping >2012-08-15 10:40:00,003 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-35) Autorecovering hosts is disabled, skipping >2012-08-15 10:40:00,004 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-35) Autorecovering storage domains is disabled, skipping >2012-08-15 10:45:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-13) Autorecovering hosts is disabled, skipping >2012-08-15 10:45:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-13) Autorecovering storage domains is disabled, skipping >2012-08-15 10:46:39,253 INFO [org.ovirt.engine.core.bll.MultipleActionsRunner] (ajp-/127.0.0.1:8009-4) MultipleActionsRunner of type DetachNetworkToVdsGroup invoked with no actions >2012-08-15 10:46:39,570 INFO [org.ovirt.engine.core.bll.storage.AddNetworkCommand] (ajp-/127.0.0.1:8009-8) [4f3438de] Running command: AddNetworkCommand internal: false. Entities affected : ID: 35352af8-fb85-46f7-8433-ee6540b14a47 Type: StoragePool >2012-08-15 10:46:39,892 INFO [org.ovirt.engine.core.bll.MultipleActionsRunner] (ajp-/127.0.0.1:8009-7) MultipleActionsRunner of type AttachNetworkToVdsGroup invoked with no actions >2012-08-15 10:46:56,727 INFO [org.ovirt.engine.core.bll.MultipleActionsRunner] (ajp-/127.0.0.1:8009-1) MultipleActionsRunner of type DetachNetworkToVdsGroup invoked with no actions >2012-08-15 10:46:57,021 WARN [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (ajp-/127.0.0.1:8009-5) [1d69094f] Adding new value to configuration cache. >2012-08-15 10:46:57,021 WARN [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (ajp-/127.0.0.1:8009-5) [1d69094f] Didnt find the value of MaxMTU in DB for version general - using default: 9,000 >2012-08-15 10:46:57,069 INFO [org.ovirt.engine.core.bll.storage.UpdateNetworkCommand] (ajp-/127.0.0.1:8009-5) [1d69094f] Running command: UpdateNetworkCommand internal: false. Entities affected : ID: 35352af8-fb85-46f7-8433-ee6540b14a47 Type: StoragePool >2012-08-15 10:46:57,089 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8009-5) [1d69094f] No string for UNASSIGNED type. Use default Log >2012-08-15 10:46:57,369 INFO [org.ovirt.engine.core.bll.MultipleActionsRunner] (ajp-/127.0.0.1:8009-11) MultipleActionsRunner of type AttachNetworkToVdsGroup invoked with no actions >2012-08-15 10:47:14,068 INFO [org.ovirt.engine.core.bll.MultipleActionsRunner] (ajp-/127.0.0.1:8009-9) MultipleActionsRunner of type DetachNetworkToVdsGroup invoked with no actions >2012-08-15 10:47:14,404 INFO [org.ovirt.engine.core.bll.storage.UpdateNetworkCommand] (ajp-/127.0.0.1:8009-4) [3d07357f] Running command: UpdateNetworkCommand internal: false. Entities affected : ID: 35352af8-fb85-46f7-8433-ee6540b14a47 Type: StoragePool >2012-08-15 10:47:14,433 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8009-4) [3d07357f] No string for UNASSIGNED type. Use default Log >2012-08-15 10:47:14,705 INFO [org.ovirt.engine.core.bll.MultipleActionsRunner] (ajp-/127.0.0.1:8009-3) MultipleActionsRunner of type AttachNetworkToVdsGroup invoked with no actions >2012-08-15 10:47:21,658 INFO [org.ovirt.engine.core.bll.MultipleActionsRunner] (ajp-/127.0.0.1:8009-11) MultipleActionsRunner of type DetachNetworkToVdsGroup invoked with no actions >2012-08-15 10:47:21,950 INFO [org.ovirt.engine.core.bll.storage.UpdateNetworkCommand] (ajp-/127.0.0.1:8009-3) [59eeff62] Running command: UpdateNetworkCommand internal: false. Entities affected : ID: 35352af8-fb85-46f7-8433-ee6540b14a47 Type: StoragePool >2012-08-15 10:47:21,961 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8009-3) [59eeff62] No string for UNASSIGNED type. Use default Log >2012-08-15 10:47:22,278 INFO [org.ovirt.engine.core.bll.AttachNetworkToVdsGroupCommand] (pool-4-thread-49) [4fc78b28] Running command: AttachNetworkToVdsGroupCommand internal: false. Entities affected : ID: d3e09392-e6a1-11e1-9a04-001a4a169777 Type: VdsGroups >2012-08-15 10:47:22,292 WARN [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (pool-4-thread-49) [4fc78b28] Adding new value to configuration cache. >2012-08-15 10:47:22,293 WARN [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (pool-4-thread-49) [4fc78b28] Didnt find the value of DBI18NPrefix in DB for version general - using default: >2012-08-15 10:48:28,435 INFO [org.ovirt.engine.core.bll.SetupNetworksCommand] (ajp-/127.0.0.1:8009-11) [69d17aab] Running command: SetupNetworksCommand internal: false. Entities affected : ID: ec084258-e6a1-11e1-a8e4-001a4a169777 Type: VDS >2012-08-15 10:48:28,439 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (ajp-/127.0.0.1:8009-11) [69d17aab] START, SetupNetworksVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777), log id: 120b05d3 >2012-08-15 10:48:28,450 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (ajp-/127.0.0.1:8009-11) [69d17aab] FINISH, SetupNetworksVDSCommand, log id: 120b05d3 >2012-08-15 10:48:35,115 INFO [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (ajp-/127.0.0.1:8009-11) [69d17aab] START, UpdateVdsDynamicDataVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777, vdsDynamic=org.ovirt.engine.core.common.businessentities.VdsDynamic@e406f06e), log id: 7875ffdb >2012-08-15 10:48:35,116 INFO [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (ajp-/127.0.0.1:8009-11) [69d17aab] FINISH, UpdateVdsDynamicDataVDSCommand, log id: 7875ffdb >2012-08-15 10:48:35,200 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8009-11) [69d17aab] No string for UNASSIGNED type. Use default Log >2012-08-15 10:50:00,004 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-80) Autorecovering hosts is disabled, skipping >2012-08-15 10:50:00,005 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-80) Autorecovering storage domains is disabled, skipping >2012-08-15 10:52:14,250 INFO [org.ovirt.engine.core.bll.SetupNetworksCommand] (ajp-/127.0.0.1:8009-2) [7d38e2d7] Running command: SetupNetworksCommand internal: false. Entities affected : ID: ec084258-e6a1-11e1-a8e4-001a4a169777 Type: VDS >2012-08-15 10:52:14,253 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (ajp-/127.0.0.1:8009-2) [7d38e2d7] START, SetupNetworksVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777), log id: 2a8307b9 >2012-08-15 10:52:14,257 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (ajp-/127.0.0.1:8009-2) [7d38e2d7] FINISH, SetupNetworksVDSCommand, log id: 2a8307b9 >2012-08-15 10:52:22,723 INFO [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (ajp-/127.0.0.1:8009-2) [7d38e2d7] START, UpdateVdsDynamicDataVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777, vdsDynamic=org.ovirt.engine.core.common.businessentities.VdsDynamic@e406f06e), log id: 68b1a11d >2012-08-15 10:52:22,724 INFO [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (ajp-/127.0.0.1:8009-2) [7d38e2d7] FINISH, UpdateVdsDynamicDataVDSCommand, log id: 68b1a11d >2012-08-15 10:52:22,783 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8009-2) [7d38e2d7] No string for UNASSIGNED type. Use default Log >2012-08-15 10:53:02,326 INFO [org.ovirt.engine.core.bll.SetupNetworksCommand] (ajp-/127.0.0.1:8009-6) [3691e760] Running command: SetupNetworksCommand internal: false. Entities affected : ID: ec084258-e6a1-11e1-a8e4-001a4a169777 Type: VDS >2012-08-15 10:53:02,328 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (ajp-/127.0.0.1:8009-6) [3691e760] START, SetupNetworksVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777), log id: 604f990f >2012-08-15 10:53:02,329 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (ajp-/127.0.0.1:8009-6) [3691e760] FINISH, SetupNetworksVDSCommand, log id: 604f990f >2012-08-15 10:53:02,861 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] (ajp-/127.0.0.1:8009-6) [3691e760] Failed in SetupNetworksVDS method >2012-08-15 10:53:02,861 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] (ajp-/127.0.0.1:8009-6) [3691e760] Error code unexpected and error message VDSGenericException: VDSErrorException: Failed to SetupNetworksVDS, error = Unexpected exception >2012-08-15 10:53:02,862 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] (ajp-/127.0.0.1:8009-6) [3691e760] org.ovirt.engine.core.vdsbroker.vdsbroker.VDSErrorException: VDSGenericException: VDSErrorException: Failed to SetupNetworksVDS, error = Unexpected exception >2012-08-15 10:53:02,869 ERROR [org.ovirt.engine.core.vdsbroker.VDSCommandBase] (ajp-/127.0.0.1:8009-6) [3691e760] Command SetupNetworksVDS execution failed. Exception: RuntimeException: org.ovirt.engine.core.vdsbroker.vdsbroker.VDSErrorException: VDSGenericException: VDSErrorException: Failed to SetupNetworksVDS, error = Unexpected exception >2012-08-15 10:53:02,880 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8009-6) [3691e760] No string for UNASSIGNED type. Use default Log >2012-08-15 10:55:00,000 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-59) Autorecovering hosts is disabled, skipping >2012-08-15 10:55:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-59) Autorecovering storage domains is disabled, skipping >2012-08-15 11:00:00,000 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-30) Autorecovering hosts is disabled, skipping >2012-08-15 11:00:00,000 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-30) Autorecovering storage domains is disabled, skipping >2012-08-15 11:05:00,000 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-100) Autorecovering hosts is disabled, skipping >2012-08-15 11:05:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-100) Autorecovering storage domains is disabled, skipping >2012-08-15 11:10:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-69) Autorecovering hosts is disabled, skipping >2012-08-15 11:10:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-69) Autorecovering storage domains is disabled, skipping >2012-08-15 11:15:00,002 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-43) [3f09373] Autorecovering hosts is disabled, skipping >2012-08-15 11:15:00,002 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-43) [3f09373] Autorecovering storage domains is disabled, skipping >2012-08-15 11:20:00,000 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-14) Autorecovering hosts is disabled, skipping >2012-08-15 11:20:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-14) Autorecovering storage domains is disabled, skipping >2012-08-15 11:21:04,191 INFO [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-95) DbUserCacheManager::refreshAllUserData() - entered >2012-08-15 11:22:39,289 INFO [org.ovirt.engine.core.bll.MultipleActionsRunner] (ajp-/127.0.0.1:8009-6) MultipleActionsRunner of type DetachNetworkToVdsGroup invoked with no actions >2012-08-15 11:22:39,564 INFO [org.ovirt.engine.core.bll.storage.AddNetworkCommand] (ajp-/127.0.0.1:8009-8) [5d3bb8e6] Running command: AddNetworkCommand internal: false. Entities affected : ID: 35352af8-fb85-46f7-8433-ee6540b14a47 Type: StoragePool >2012-08-15 11:22:39,965 INFO [org.ovirt.engine.core.bll.AttachNetworkToVdsGroupCommand] (pool-4-thread-49) [357a7a38] Running command: AttachNetworkToVdsGroupCommand internal: false. Entities affected : ID: d3e09392-e6a1-11e1-9a04-001a4a169777 Type: VdsGroups >2012-08-15 11:22:53,024 INFO [org.ovirt.engine.core.bll.SetupNetworksCommand] (ajp-/127.0.0.1:8009-1) [65424775] Running command: SetupNetworksCommand internal: false. Entities affected : ID: ec084258-e6a1-11e1-a8e4-001a4a169777 Type: VDS >2012-08-15 11:22:53,025 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (ajp-/127.0.0.1:8009-1) [65424775] START, SetupNetworksVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777), log id: 79e17dfb >2012-08-15 11:22:53,026 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (ajp-/127.0.0.1:8009-1) [65424775] FINISH, SetupNetworksVDSCommand, log id: 79e17dfb >2012-08-15 11:22:58,416 INFO [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (ajp-/127.0.0.1:8009-1) [65424775] START, UpdateVdsDynamicDataVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777, vdsDynamic=org.ovirt.engine.core.common.businessentities.VdsDynamic@e406f06e), log id: 38f158b0 >2012-08-15 11:22:58,417 INFO [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (ajp-/127.0.0.1:8009-1) [65424775] FINISH, UpdateVdsDynamicDataVDSCommand, log id: 38f158b0 >2012-08-15 11:22:58,473 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8009-1) [65424775] No string for UNASSIGNED type. Use default Log >2012-08-15 11:25:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-84) Autorecovering hosts is disabled, skipping >2012-08-15 11:25:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-84) Autorecovering storage domains is disabled, skipping >2012-08-15 11:28:34,307 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (ajp-/127.0.0.1:8009-9) [29380c3] START, ValidateStorageServerConnectionVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: 406ba634-9ada-4a27-a391-da15e56ab20d, connection: lion.qa.lab.tlv:/exports/meni/si };]), log id: 2c10c2f6 >2012-08-15 11:28:34,340 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (ajp-/127.0.0.1:8009-9) [29380c3] FINISH, ValidateStorageServerConnectionVDSCommand, return: {406ba634-9ada-4a27-a391-da15e56ab20d=0}, log id: 2c10c2f6 >2012-08-15 11:28:34,358 INFO [org.ovirt.engine.core.bll.storage.AddStorageServerConnectionCommand] (ajp-/127.0.0.1:8009-9) [29380c3] Running command: AddStorageServerConnectionCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2012-08-15 11:28:34,360 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (ajp-/127.0.0.1:8009-9) [29380c3] START, ConnectStorageServerVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: 406ba634-9ada-4a27-a391-da15e56ab20d, connection: lion.qa.lab.tlv:/exports/meni/si };]), log id: 4af4e69d >2012-08-15 11:28:34,615 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (ajp-/127.0.0.1:8009-9) [29380c3] FINISH, ConnectStorageServerVDSCommand, return: {406ba634-9ada-4a27-a391-da15e56ab20d=0}, log id: 4af4e69d >2012-08-15 11:28:35,059 INFO [org.ovirt.engine.core.bll.storage.AddNFSStorageDomainCommand] (ajp-/127.0.0.1:8009-5) [79f37cd8] Running command: AddNFSStorageDomainCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2012-08-15 11:28:35,106 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStorageDomainVDSCommand] (ajp-/127.0.0.1:8009-5) [79f37cd8] START, CreateStorageDomainVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777, storageDomain=org.ovirt.engine.core.common.businessentities.storage_domain_static@f75018f0, args=lion.qa.lab.tlv:/exports/meni/si), log id: 73b205a5 >2012-08-15 11:28:36,132 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStorageDomainVDSCommand] (ajp-/127.0.0.1:8009-5) [79f37cd8] FINISH, CreateStorageDomainVDSCommand, log id: 73b205a5 >2012-08-15 11:28:36,135 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetStorageDomainStatsVDSCommand] (ajp-/127.0.0.1:8009-5) [79f37cd8] START, GetStorageDomainStatsVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777, storageDomainId=0bbdf65d-d08f-4f95-9da6-ea4d2a10c732), log id: 4bcba483 >2012-08-15 11:28:36,161 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetStorageDomainStatsVDSCommand] (ajp-/127.0.0.1:8009-5) [79f37cd8] FINISH, GetStorageDomainStatsVDSCommand, return: org.ovirt.engine.core.common.businessentities.storage_domains@2f0712b2, log id: 4bcba483 >2012-08-15 11:28:36,709 INFO [org.ovirt.engine.core.bll.storage.RemoveStorageServerConnectionCommand] (ajp-/127.0.0.1:8009-3) [6f17bbb] Running command: RemoveStorageServerConnectionCommand internal: false. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2012-08-15 11:28:36,712 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStorageServerVDSCommand] (ajp-/127.0.0.1:8009-3) [6f17bbb] START, DisconnectStorageServerVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: null, connection: lion.qa.lab.tlv:/exports/meni/si };]), log id: 534a657f >2012-08-15 11:28:37,295 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStorageServerVDSCommand] (ajp-/127.0.0.1:8009-3) [6f17bbb] FINISH, DisconnectStorageServerVDSCommand, return: {00000000-0000-0000-0000-000000000000=0}, log id: 534a657f >2012-08-15 11:28:38,034 INFO [org.ovirt.engine.core.bll.storage.AttachStorageDomainToPoolCommand] (ajp-/127.0.0.1:8009-8) [35335758] Running command: AttachStorageDomainToPoolCommand internal: false. Entities affected : ID: 0bbdf65d-d08f-4f95-9da6-ea4d2a10c732 Type: Storage >2012-08-15 11:28:38,057 INFO [org.ovirt.engine.core.bll.storage.AddStoragePoolWithStoragesCommand] (ajp-/127.0.0.1:8009-8) [60c0dac6] Lock Acquired to object EngineLock [exclusiveLocks= key: 35352af8-fb85-46f7-8433-ee6540b14a47 value: POOL >, sharedLocks= ] >2012-08-15 11:28:38,086 INFO [org.ovirt.engine.core.bll.storage.AddStoragePoolWithStoragesCommand] (ajp-/127.0.0.1:8009-8) [60c0dac6] Running command: AddStoragePoolWithStoragesCommand internal: true. Entities affected : ID: 35352af8-fb85-46f7-8433-ee6540b14a47 Type: StoragePool >2012-08-15 11:28:38,434 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (ajp-/127.0.0.1:8009-8) [4e6a3441] START, ValidateStorageServerConnectionVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: 406ba634-9ada-4a27-a391-da15e56ab20d, connection: lion.qa.lab.tlv:/exports/meni/si };]), log id: 27b9745f >2012-08-15 11:28:38,480 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (ajp-/127.0.0.1:8009-8) [4e6a3441] FINISH, ValidateStorageServerConnectionVDSCommand, return: {406ba634-9ada-4a27-a391-da15e56ab20d=0}, log id: 27b9745f >2012-08-15 11:28:38,480 INFO [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] (ajp-/127.0.0.1:8009-8) [4e6a3441] Running command: ConnectStorageToVdsCommand internal: true. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2012-08-15 11:28:38,482 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (ajp-/127.0.0.1:8009-8) [4e6a3441] START, ConnectStorageServerVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: 406ba634-9ada-4a27-a391-da15e56ab20d, connection: lion.qa.lab.tlv:/exports/meni/si };]), log id: d0b7c56 >2012-08-15 11:28:38,689 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (ajp-/127.0.0.1:8009-8) [4e6a3441] FINISH, ConnectStorageServerVDSCommand, return: {406ba634-9ada-4a27-a391-da15e56ab20d=0}, log id: d0b7c56 >2012-08-15 11:28:38,692 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStoragePoolVDSCommand] (ajp-/127.0.0.1:8009-8) [4e6a3441] START, CreateStoragePoolVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777, storagePoolId=35352af8-fb85-46f7-8433-ee6540b14a47, storageType=NFS, storagePoolName=DC31, masterDomainId=0bbdf65d-d08f-4f95-9da6-ea4d2a10c732, domainsIdList=[0bbdf65d-d08f-4f95-9da6-ea4d2a10c732], masterVersion=1), log id: 36cc8a34 >2012-08-15 11:29:02,888 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateStoragePoolVDSCommand] (ajp-/127.0.0.1:8009-8) [4e6a3441] FINISH, CreateStoragePoolVDSCommand, log id: 36cc8a34 >2012-08-15 11:29:02,978 INFO [org.ovirt.engine.core.bll.storage.AddStoragePoolWithStoragesCommand] (ajp-/127.0.0.1:8009-8) [4e6a3441] Lock freed to object EngineLock [exclusiveLocks= key: 35352af8-fb85-46f7-8433-ee6540b14a47 value: POOL >, sharedLocks= ] >2012-08-15 11:29:02,982 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (ajp-/127.0.0.1:8009-8) [7afcaf27] Lock Acquired to object EngineLock [exclusiveLocks= key: 0bbdf65d-d08f-4f95-9da6-ea4d2a10c732 value: STORAGE >, sharedLocks= ] >2012-08-15 11:29:03,022 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (ajp-/127.0.0.1:8009-8) [7afcaf27] Running command: ActivateStorageDomainCommand internal: true. Entities affected : ID: 0bbdf65d-d08f-4f95-9da6-ea4d2a10c732 Type: Storage >2012-08-15 11:29:03,098 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (ajp-/127.0.0.1:8009-8) [7afcaf27] Lock freed to object EngineLock [exclusiveLocks= key: 0bbdf65d-d08f-4f95-9da6-ea4d2a10c732 value: STORAGE >, sharedLocks= ] >2012-08-15 11:29:03,098 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (ajp-/127.0.0.1:8009-8) [7afcaf27] ActivateStorage Domain. Before Connect all hosts to pool. Time:8/15/12 11:29 AM >2012-08-15 11:29:03,124 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (pool-4-thread-50) [534a9e5d] START, ValidateStorageServerConnectionVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: 406ba634-9ada-4a27-a391-da15e56ab20d, connection: lion.qa.lab.tlv:/exports/meni/si };]), log id: 3ae0b031 >2012-08-15 11:29:03,126 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (pool-4-thread-49) [3390bf6b] START, ValidateStorageServerConnectionVDSCommand(vdsId = f7ea1dc6-e6a1-11e1-bc36-001a4a169777, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: 406ba634-9ada-4a27-a391-da15e56ab20d, connection: lion.qa.lab.tlv:/exports/meni/si };]), log id: aedda93 >2012-08-15 11:29:03,158 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (pool-4-thread-50) [534a9e5d] FINISH, ValidateStorageServerConnectionVDSCommand, return: {406ba634-9ada-4a27-a391-da15e56ab20d=0}, log id: 3ae0b031 >2012-08-15 11:29:03,159 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (pool-4-thread-49) [3390bf6b] FINISH, ValidateStorageServerConnectionVDSCommand, return: {406ba634-9ada-4a27-a391-da15e56ab20d=0}, log id: aedda93 >2012-08-15 11:29:03,159 INFO [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] (pool-4-thread-49) [3390bf6b] Running command: ConnectStorageToVdsCommand internal: true. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2012-08-15 11:29:03,159 INFO [org.ovirt.engine.core.bll.storage.ConnectStorageToVdsCommand] (pool-4-thread-50) [534a9e5d] Running command: ConnectStorageToVdsCommand internal: true. Entities affected : ID: aaa00000-0000-0000-0000-123456789aaa Type: System >2012-08-15 11:29:03,165 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (pool-4-thread-49) [3390bf6b] START, ConnectStorageServerVDSCommand(vdsId = f7ea1dc6-e6a1-11e1-bc36-001a4a169777, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: 406ba634-9ada-4a27-a391-da15e56ab20d, connection: lion.qa.lab.tlv:/exports/meni/si };]), log id: 47146097 >2012-08-15 11:29:03,166 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (pool-4-thread-50) [534a9e5d] START, ConnectStorageServerVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777, storagePoolId = 00000000-0000-0000-0000-000000000000, storageType = NFS, connectionList = [{ id: 406ba634-9ada-4a27-a391-da15e56ab20d, connection: lion.qa.lab.tlv:/exports/meni/si };]), log id: 682a6cb2 >2012-08-15 11:29:03,213 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (pool-4-thread-50) [534a9e5d] FINISH, ConnectStorageServerVDSCommand, return: {406ba634-9ada-4a27-a391-da15e56ab20d=0}, log id: 682a6cb2 >2012-08-15 11:29:03,441 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (pool-4-thread-49) [3390bf6b] FINISH, ConnectStorageServerVDSCommand, return: {406ba634-9ada-4a27-a391-da15e56ab20d=0}, log id: 47146097 >2012-08-15 11:29:03,442 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (ajp-/127.0.0.1:8009-8) [7afcaf27] ActivateStorage Domain. After Connect all hosts to pool. Time:8/15/12 11:29 AM >2012-08-15 11:29:03,445 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.ActivateStorageDomainVDSCommand] (ajp-/127.0.0.1:8009-8) [7afcaf27] START, ActivateStorageDomainVDSCommand(storagePoolId = 35352af8-fb85-46f7-8433-ee6540b14a47, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 0bbdf65d-d08f-4f95-9da6-ea4d2a10c732), log id: 409c6bfc >2012-08-15 11:29:03,458 INFO [org.ovirt.engine.core.bll.storage.SetStoragePoolStatusCommand] (ajp-/127.0.0.1:8009-8) [52e4785e] Running command: SetStoragePoolStatusCommand internal: true. Entities affected : ID: 35352af8-fb85-46f7-8433-ee6540b14a47 Type: StoragePool >2012-08-15 11:29:03,553 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (ajp-/127.0.0.1:8009-8) [52e4785e] hostFromVds::selectedVds - orchid-vds2, spmStatus Unknown_Pool, storage pool DC31 >2012-08-15 11:29:03,573 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (ajp-/127.0.0.1:8009-8) [52e4785e] START, ConnectStoragePoolVDSCommand(vdsId = f7ea1dc6-e6a1-11e1-bc36-001a4a169777, storagePoolId = 35352af8-fb85-46f7-8433-ee6540b14a47, vds_spm_id = 2, masterDomainId = 0bbdf65d-d08f-4f95-9da6-ea4d2a10c732, masterVersion = 1), log id: 7266d4f0 >2012-08-15 11:29:04,638 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (ajp-/127.0.0.1:8009-8) [52e4785e] FINISH, ConnectStoragePoolVDSCommand, log id: 7266d4f0 >2012-08-15 11:29:04,660 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (ajp-/127.0.0.1:8009-8) [52e4785e] hostFromVds::Connected host to pool - selectedVds - orchid-vds2, spmStatus Free, storage pool DC31 >2012-08-15 11:29:04,686 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (ajp-/127.0.0.1:8009-8) [52e4785e] starting spm on vds orchid-vds2, storage pool DC31, prevId -1, LVER -1 >2012-08-15 11:29:04,697 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (ajp-/127.0.0.1:8009-8) [52e4785e] START, SpmStartVDSCommand(vdsId = f7ea1dc6-e6a1-11e1-bc36-001a4a169777, storagePoolId = 35352af8-fb85-46f7-8433-ee6540b14a47, prevId=-1, prevLVER=-1, storagePoolFormatType=V3, recoveryMode=Manual, SCSIFencing=false), log id: 445e1fc1 >2012-08-15 11:29:04,721 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (ajp-/127.0.0.1:8009-8) [52e4785e] spmStart polling started: taskId = e6d653fd-c841-4602-a47b-cbf59664195e >2012-08-15 11:29:26,273 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (ajp-/127.0.0.1:8009-8) [52e4785e] spmStart polling ended: taskId = e6d653fd-c841-4602-a47b-cbf59664195e task status = finished >2012-08-15 11:29:26,291 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (ajp-/127.0.0.1:8009-8) [52e4785e] spmStart polling ended. spm status: SPM >2012-08-15 11:29:26,294 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (ajp-/127.0.0.1:8009-8) [52e4785e] START, HSMClearTaskVDSCommand(vdsId = f7ea1dc6-e6a1-11e1-bc36-001a4a169777, taskId=e6d653fd-c841-4602-a47b-cbf59664195e), log id: 1e5d71d >2012-08-15 11:29:26,313 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (ajp-/127.0.0.1:8009-8) [52e4785e] FINISH, HSMClearTaskVDSCommand, log id: 1e5d71d >2012-08-15 11:29:26,313 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SpmStartVDSCommand] (ajp-/127.0.0.1:8009-8) [52e4785e] FINISH, SpmStartVDSCommand, return: org.ovirt.engine.core.common.businessentities.SpmStatusResult@2f84ee14, log id: 445e1fc1 >2012-08-15 11:29:26,337 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (ajp-/127.0.0.1:8009-8) [52e4785e] Initialize Irs proxy from vds: orchid-vds2.qa.lab.tlv.redhat.com >2012-08-15 11:29:26,398 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.ActivateStorageDomainVDSCommand] (ajp-/127.0.0.1:8009-8) [52e4785e] FINISH, ActivateStorageDomainVDSCommand, log id: 409c6bfc >2012-08-15 11:29:26,398 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (ajp-/127.0.0.1:8009-8) [52e4785e] ActivateStorage Domain. After Activate storage domain in vds. Time:8/15/12 11:29 AM >2012-08-15 11:29:26,448 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (pool-4-thread-49) [52e4785e] START, ConnectStoragePoolVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777, storagePoolId = 35352af8-fb85-46f7-8433-ee6540b14a47, vds_spm_id = 1, masterDomainId = 0bbdf65d-d08f-4f95-9da6-ea4d2a10c732, masterVersion = 1), log id: 7002135c >2012-08-15 11:29:26,516 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (pool-4-thread-45) [52e4785e] START, ConnectStoragePoolVDSCommand(vdsId = f7ea1dc6-e6a1-11e1-bc36-001a4a169777, storagePoolId = 35352af8-fb85-46f7-8433-ee6540b14a47, vds_spm_id = 2, masterDomainId = 0bbdf65d-d08f-4f95-9da6-ea4d2a10c732, masterVersion = 1), log id: 368e539c >2012-08-15 11:29:26,539 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (pool-4-thread-45) [52e4785e] FINISH, ConnectStoragePoolVDSCommand, log id: 368e539c >2012-08-15 11:29:27,251 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (pool-4-thread-49) [52e4785e] FINISH, ConnectStoragePoolVDSCommand, log id: 7002135c >2012-08-15 11:29:27,251 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (ajp-/127.0.0.1:8009-8) [52e4785e] ActivateStorage Domain. After Refresh all pools . Time:8/15/12 11:29 AM >2012-08-15 11:29:27,292 INFO [org.ovirt.engine.core.bll.storage.ActivateStorageDomainCommand] (ajp-/127.0.0.1:8009-8) [52e4785e] ActivateStorage Domain. After change storage pool status in vds. Time:8/15/12 11:29 AM >2012-08-15 11:30:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-63) Autorecovering hosts is disabled, skipping >2012-08-15 11:30:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-63) Autorecovering storage domains is disabled, skipping >2012-08-15 11:30:43,808 INFO [org.ovirt.engine.core.bll.storage.AddNetworkCommand] (ajp-/127.0.0.1:8009-5) [652fa7e2] Running command: AddNetworkCommand internal: false. Entities affected : ID: 35352af8-fb85-46f7-8433-ee6540b14a47 Type: StoragePool >2012-08-15 11:30:44,311 INFO [org.ovirt.engine.core.bll.AttachNetworkToVdsGroupCommand] (pool-4-thread-50) [4fd1a433] Running command: AttachNetworkToVdsGroupCommand internal: false. Entities affected : ID: d3e09392-e6a1-11e1-9a04-001a4a169777 Type: VdsGroups >2012-08-15 11:30:48,139 INFO [org.ovirt.engine.core.bll.storage.AddNetworkCommand] (ajp-/127.0.0.1:8009-5) [68278ec4] Running command: AddNetworkCommand internal: false. Entities affected : ID: 35352af8-fb85-46f7-8433-ee6540b14a47 Type: StoragePool >2012-08-15 11:30:48,734 INFO [org.ovirt.engine.core.bll.AttachNetworkToVdsGroupCommand] (pool-4-thread-50) [4650e802] Running command: AttachNetworkToVdsGroupCommand internal: false. Entities affected : ID: d3e09392-e6a1-11e1-9a04-001a4a169777 Type: VdsGroups >2012-08-15 11:31:12,406 INFO [org.ovirt.engine.core.bll.AddVmFromScratchCommand] (ajp-/127.0.0.1:8009-7) [5513916e] Lock Acquired to object EngineLock [exclusiveLocks= key: VM1 value: VM_NAME >, sharedLocks= ] >2012-08-15 11:31:12,459 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (ajp-/127.0.0.1:8009-7) [5513916e] START, IsValidVDSCommand(storagePoolId = 35352af8-fb85-46f7-8433-ee6540b14a47, ignoreFailoverLimit = false, compatabilityVersion = null), log id: 3e20c979 >2012-08-15 11:31:12,460 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (ajp-/127.0.0.1:8009-7) [5513916e] FINISH, IsValidVDSCommand, return: true, log id: 3e20c979 >2012-08-15 11:31:12,491 INFO [org.ovirt.engine.core.bll.AddVmFromScratchCommand] (ajp-/127.0.0.1:8009-7) [5513916e] Running command: AddVmFromScratchCommand internal: false. Entities affected : ID: d3e09392-e6a1-11e1-9a04-001a4a169777 Type: VdsGroups >2012-08-15 11:31:12,493 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (ajp-/127.0.0.1:8009-7) [5513916e] START, IsValidVDSCommand(storagePoolId = 35352af8-fb85-46f7-8433-ee6540b14a47, ignoreFailoverLimit = false, compatabilityVersion = null), log id: 116c538d >2012-08-15 11:31:12,493 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (ajp-/127.0.0.1:8009-7) [5513916e] FINISH, IsValidVDSCommand, return: true, log id: 116c538d >2012-08-15 11:31:12,593 INFO [org.ovirt.engine.core.bll.AddVmFromScratchCommand] (ajp-/127.0.0.1:8009-7) [5513916e] Lock freed to object EngineLock [exclusiveLocks= key: VM1 value: VM_NAME >, sharedLocks= ] >2012-08-15 11:31:19,102 INFO [org.ovirt.engine.core.bll.AddVmInterfaceCommand] (ajp-/127.0.0.1:8009-9) [72aa5e0c] Running command: AddVmInterfaceCommand internal: false. Entities affected : ID: 5f196943-28c0-4b92-b1b3-941b7b9f540b Type: VM >2012-08-15 11:31:19,106 INFO [org.ovirt.engine.core.bll.MacPoolManager] (ajp-/127.0.0.1:8009-9) [72aa5e0c] MacPoolManager::allocateNewMac entered >2012-08-15 11:31:19,106 INFO [org.ovirt.engine.core.bll.MacPoolManager] (ajp-/127.0.0.1:8009-9) [72aa5e0c] MacPoolManager::allocateNewMac allocated mac = 00:1a:4a:23:61:69 >2012-08-15 11:31:54,602 INFO [org.ovirt.engine.core.bll.SetupNetworksCommand] (ajp-/127.0.0.1:8009-3) [4a7bfa9f] Running command: SetupNetworksCommand internal: false. Entities affected : ID: ec084258-e6a1-11e1-a8e4-001a4a169777 Type: VDS >2012-08-15 11:31:54,604 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (ajp-/127.0.0.1:8009-3) [4a7bfa9f] START, SetupNetworksVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777), log id: 7dd6c46e >2012-08-15 11:31:54,604 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (ajp-/127.0.0.1:8009-3) [4a7bfa9f] FINISH, SetupNetworksVDSCommand, log id: 7dd6c46e >2012-08-15 11:31:56,862 INFO [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (ajp-/127.0.0.1:8009-3) [4a7bfa9f] START, UpdateVdsDynamicDataVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777, vdsDynamic=org.ovirt.engine.core.common.businessentities.VdsDynamic@ee9bd566), log id: 1f86ce5b >2012-08-15 11:31:56,863 INFO [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (ajp-/127.0.0.1:8009-3) [4a7bfa9f] FINISH, UpdateVdsDynamicDataVDSCommand, log id: 1f86ce5b >2012-08-15 11:31:56,895 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8009-3) [4a7bfa9f] No string for UNASSIGNED type. Use default Log >2012-08-15 11:32:16,269 INFO [org.ovirt.engine.core.bll.storage.AddNetworkCommand] (ajp-/127.0.0.1:8009-3) [528f810c] Running command: AddNetworkCommand internal: false. Entities affected : ID: 35352af8-fb85-46f7-8433-ee6540b14a47 Type: StoragePool >2012-08-15 11:32:16,716 INFO [org.ovirt.engine.core.bll.AttachNetworkToVdsGroupCommand] (pool-4-thread-49) [4bfa1506] Running command: AttachNetworkToVdsGroupCommand internal: false. Entities affected : ID: d3e09392-e6a1-11e1-9a04-001a4a169777 Type: VdsGroups >2012-08-15 11:32:38,339 INFO [org.ovirt.engine.core.bll.SetupNetworksCommand] (ajp-/127.0.0.1:8009-6) [47eb859c] Running command: SetupNetworksCommand internal: false. Entities affected : ID: ec084258-e6a1-11e1-a8e4-001a4a169777 Type: VDS >2012-08-15 11:32:38,341 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (ajp-/127.0.0.1:8009-6) [47eb859c] START, SetupNetworksVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777), log id: 7bdfb4bf >2012-08-15 11:32:38,341 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (ajp-/127.0.0.1:8009-6) [47eb859c] FINISH, SetupNetworksVDSCommand, log id: 7bdfb4bf >2012-08-15 11:32:40,589 INFO [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (ajp-/127.0.0.1:8009-6) [47eb859c] START, UpdateVdsDynamicDataVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777, vdsDynamic=org.ovirt.engine.core.common.businessentities.VdsDynamic@ee9bd566), log id: 7f1795e >2012-08-15 11:32:40,590 INFO [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (ajp-/127.0.0.1:8009-6) [47eb859c] FINISH, UpdateVdsDynamicDataVDSCommand, log id: 7f1795e >2012-08-15 11:32:40,698 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8009-6) [47eb859c] No string for UNASSIGNED type. Use default Log >2012-08-15 11:34:43,991 INFO [org.ovirt.engine.core.bll.SetupNetworksCommand] (ajp-/127.0.0.1:8009-11) [52dbcef1] Running command: SetupNetworksCommand internal: false. Entities affected : ID: ec084258-e6a1-11e1-a8e4-001a4a169777 Type: VDS >2012-08-15 11:34:43,992 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (ajp-/127.0.0.1:8009-11) [52dbcef1] START, SetupNetworksVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777), log id: 646207b6 >2012-08-15 11:34:43,993 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (ajp-/127.0.0.1:8009-11) [52dbcef1] FINISH, SetupNetworksVDSCommand, log id: 646207b6 >2012-08-15 11:34:46,278 INFO [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (ajp-/127.0.0.1:8009-11) [52dbcef1] START, UpdateVdsDynamicDataVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777, vdsDynamic=org.ovirt.engine.core.common.businessentities.VdsDynamic@ee9bd566), log id: 31f640c6 >2012-08-15 11:34:46,279 INFO [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (ajp-/127.0.0.1:8009-11) [52dbcef1] FINISH, UpdateVdsDynamicDataVDSCommand, log id: 31f640c6 >2012-08-15 11:34:46,350 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8009-11) [52dbcef1] No string for UNASSIGNED type. Use default Log >2012-08-15 11:35:00,000 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-68) Autorecovering hosts is disabled, skipping >2012-08-15 11:35:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-68) Autorecovering storage domains is disabled, skipping >2012-08-15 11:37:17,496 INFO [org.ovirt.engine.core.bll.SetupNetworksCommand] (ajp-/127.0.0.1:8009-4) [db9b6b7] Running command: SetupNetworksCommand internal: false. Entities affected : ID: ec084258-e6a1-11e1-a8e4-001a4a169777 Type: VDS >2012-08-15 11:37:17,497 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (ajp-/127.0.0.1:8009-4) [db9b6b7] START, SetupNetworksVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777), log id: 63aad3fe >2012-08-15 11:37:17,497 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (ajp-/127.0.0.1:8009-4) [db9b6b7] FINISH, SetupNetworksVDSCommand, log id: 63aad3fe >2012-08-15 11:37:19,908 INFO [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (ajp-/127.0.0.1:8009-4) [db9b6b7] START, UpdateVdsDynamicDataVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777, vdsDynamic=org.ovirt.engine.core.common.businessentities.VdsDynamic@ee9bd566), log id: 1dd0b440 >2012-08-15 11:37:19,908 INFO [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (ajp-/127.0.0.1:8009-4) [db9b6b7] FINISH, UpdateVdsDynamicDataVDSCommand, log id: 1dd0b440 >2012-08-15 11:37:20,012 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8009-4) [db9b6b7] No string for UNASSIGNED type. Use default Log >2012-08-15 11:40:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-69) Autorecovering hosts is disabled, skipping >2012-08-15 11:40:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-69) Autorecovering storage domains is disabled, skipping >2012-08-15 11:45:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-73) Autorecovering hosts is disabled, skipping >2012-08-15 11:45:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-73) Autorecovering storage domains is disabled, skipping >2012-08-15 11:46:59,915 INFO [org.ovirt.engine.core.bll.SetupNetworksCommand] (ajp-/127.0.0.1:8009-8) [5af449de] Running command: SetupNetworksCommand internal: false. Entities affected : ID: ec084258-e6a1-11e1-a8e4-001a4a169777 Type: VDS >2012-08-15 11:46:59,917 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (ajp-/127.0.0.1:8009-8) [5af449de] START, SetupNetworksVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777), log id: 596f9614 >2012-08-15 11:46:59,918 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (ajp-/127.0.0.1:8009-8) [5af449de] FINISH, SetupNetworksVDSCommand, log id: 596f9614 >2012-08-15 11:47:01,649 INFO [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (ajp-/127.0.0.1:8009-8) [5af449de] START, UpdateVdsDynamicDataVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777, vdsDynamic=org.ovirt.engine.core.common.businessentities.VdsDynamic@ee9bd566), log id: 3c27879c >2012-08-15 11:47:01,650 INFO [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (ajp-/127.0.0.1:8009-8) [5af449de] FINISH, UpdateVdsDynamicDataVDSCommand, log id: 3c27879c >2012-08-15 11:47:01,718 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8009-8) [5af449de] No string for UNASSIGNED type. Use default Log >2012-08-15 11:49:37,422 INFO [org.ovirt.engine.core.bll.CommitNetworkChangesCommand] (ajp-/127.0.0.1:8009-3) [74f046f5] Running command: CommitNetworkChangesCommand internal: false. Entities affected : ID: ec084258-e6a1-11e1-a8e4-001a4a169777 Type: VDS >2012-08-15 11:49:37,425 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetSafeNetworkConfigVDSCommand] (ajp-/127.0.0.1:8009-3) [74f046f5] START, SetSafeNetworkConfigVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777), log id: 6fd4f725 >2012-08-15 11:49:37,464 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetSafeNetworkConfigVDSCommand] (ajp-/127.0.0.1:8009-3) [74f046f5] FINISH, SetSafeNetworkConfigVDSCommand, log id: 6fd4f725 >2012-08-15 11:49:50,561 ERROR [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-55) vds::refreshVdsStats Failed getVdsStats, vds = ec084258-e6a1-11e1-a8e4-001a4a169777 : orchid-vds1, error = VDSNetworkException: VDSNetworkException: >2012-08-15 11:49:50,635 WARN [org.ovirt.engine.core.vdsbroker.VdsManager] (QuartzScheduler_Worker-55) ResourceManager::refreshVdsRunTimeInfo::Failed to refresh VDS , vds = ec084258-e6a1-11e1-a8e4-001a4a169777 : orchid-vds1, VDS Network Error, continuing. >VDSNetworkException: >2012-08-15 11:49:52,644 WARN [org.ovirt.engine.core.vdsbroker.VdsManager] (QuartzScheduler_Worker-62) ResourceManager::refreshVdsRunTimeInfo::Failed to refresh VDS , vds = ec084258-e6a1-11e1-a8e4-001a4a169777 : orchid-vds1, VDS Network Error, continuing. >VDSNetworkException: >2012-08-15 11:49:54,653 WARN [org.ovirt.engine.core.vdsbroker.VdsManager] (QuartzScheduler_Worker-61) ResourceManager::refreshVdsRunTimeInfo::Failed to refresh VDS , vds = ec084258-e6a1-11e1-a8e4-001a4a169777 : orchid-vds1, VDS Network Error, continuing. >VDSNetworkException: >2012-08-15 11:49:56,667 WARN [org.ovirt.engine.core.vdsbroker.VdsManager] (QuartzScheduler_Worker-68) ResourceManager::refreshVdsRunTimeInfo::Failed to refresh VDS , vds = ec084258-e6a1-11e1-a8e4-001a4a169777 : orchid-vds1, VDS Network Error, continuing. >VDSNetworkException: >2012-08-15 11:49:58,675 WARN [org.ovirt.engine.core.vdsbroker.VdsManager] (QuartzScheduler_Worker-69) ResourceManager::refreshVdsRunTimeInfo::Failed to refresh VDS , vds = ec084258-e6a1-11e1-a8e4-001a4a169777 : orchid-vds1, VDS Network Error, continuing. >VDSNetworkException: >2012-08-15 11:50:00,000 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-72) Autorecovering hosts is disabled, skipping >2012-08-15 11:50:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-72) Autorecovering storage domains is disabled, skipping >2012-08-15 11:51:06,683 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand] (QuartzScheduler_Worker-70) XML RPC error in command GetCapabilitiesVDS ( Vds: orchid-vds1 ), the error was: java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException, NoRouteToHostException: No route to host >2012-08-15 11:51:06,684 ERROR [org.ovirt.engine.core.vdsbroker.VdsManager] (QuartzScheduler_Worker-70) VDS::handleNetworkException Server failed to respond, vds_id = ec084258-e6a1-11e1-a8e4-001a4a169777, vds_name = orchid-vds1, error = VDSNetworkException: >2012-08-15 11:51:06,719 INFO [org.ovirt.engine.core.bll.VdsEventListener] (pool-4-thread-46) ResourceManager::vdsNotResponding entered for Host ec084258-e6a1-11e1-a8e4-001a4a169777, orchid-vds1.qa.lab.tlv.redhat.com >2012-08-15 11:51:06,834 ERROR [org.ovirt.engine.core.bll.VdsNotRespondingTreatmentCommand] (pool-4-thread-46) [1ee49e5a] Failed to run Fence script on vds:orchid-vds1, VMs moved to UnKnown instead. >2012-08-15 11:51:09,684 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand] (QuartzScheduler_Worker-33) XML RPC error in command GetCapabilitiesVDS ( Vds: orchid-vds1 ), the error was: java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException, NoRouteToHostException: No route to host >2012-08-15 11:51:14,861 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand] (QuartzScheduler_Worker-39) XML RPC error in command GetCapabilitiesVDS ( Vds: orchid-vds1 ), the error was: java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException, NoRouteToHostException: No route to host >2012-08-15 11:51:17,861 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand] (QuartzScheduler_Worker-44) XML RPC error in command GetCapabilitiesVDS ( Vds: orchid-vds1 ), the error was: java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException, NoRouteToHostException: No route to host >2012-08-15 11:51:23,229 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand] (QuartzScheduler_Worker-49) XML RPC error in command GetCapabilitiesVDS ( Vds: orchid-vds1 ), the error was: java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException, NoRouteToHostException: No route to host >2012-08-15 11:51:26,230 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand] (QuartzScheduler_Worker-51) XML RPC error in command GetCapabilitiesVDS ( Vds: orchid-vds1 ), the error was: java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException, NoRouteToHostException: No route to host >2012-08-15 11:51:31,276 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand] (QuartzScheduler_Worker-60) XML RPC error in command GetCapabilitiesVDS ( Vds: orchid-vds1 ), the error was: java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException, NoRouteToHostException: No route to host >2012-08-15 11:51:34,277 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand] (QuartzScheduler_Worker-57) XML RPC error in command GetCapabilitiesVDS ( Vds: orchid-vds1 ), the error was: java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException, NoRouteToHostException: No route to host >2012-08-15 11:51:39,340 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand] (QuartzScheduler_Worker-63) XML RPC error in command GetCapabilitiesVDS ( Vds: orchid-vds1 ), the error was: java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException, NoRouteToHostException: No route to host >2012-08-15 11:51:42,343 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand] (QuartzScheduler_Worker-69) XML RPC error in command GetCapabilitiesVDS ( Vds: orchid-vds1 ), the error was: java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException, NoRouteToHostException: No route to host >2012-08-15 11:51:47,388 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand] (QuartzScheduler_Worker-74) XML RPC error in command GetCapabilitiesVDS ( Vds: orchid-vds1 ), the error was: java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException, NoRouteToHostException: No route to host >2012-08-15 11:51:50,389 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand] (QuartzScheduler_Worker-77) XML RPC error in command GetCapabilitiesVDS ( Vds: orchid-vds1 ), the error was: java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException, NoRouteToHostException: No route to host >2012-08-15 11:51:55,414 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand] (QuartzScheduler_Worker-81) XML RPC error in command GetCapabilitiesVDS ( Vds: orchid-vds1 ), the error was: java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException, NoRouteToHostException: No route to host >2012-08-15 11:51:58,415 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand] (QuartzScheduler_Worker-92) XML RPC error in command GetCapabilitiesVDS ( Vds: orchid-vds1 ), the error was: java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException, NoRouteToHostException: No route to host >2012-08-15 11:52:03,509 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand] (QuartzScheduler_Worker-90) XML RPC error in command GetCapabilitiesVDS ( Vds: orchid-vds1 ), the error was: java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException, NoRouteToHostException: No route to host >2012-08-15 11:52:06,510 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand] (QuartzScheduler_Worker-98) XML RPC error in command GetCapabilitiesVDS ( Vds: orchid-vds1 ), the error was: java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException, NoRouteToHostException: No route to host >2012-08-15 11:52:11,550 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand] (QuartzScheduler_Worker-3) XML RPC error in command GetCapabilitiesVDS ( Vds: orchid-vds1 ), the error was: java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException, NoRouteToHostException: No route to host >2012-08-15 11:52:14,547 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand] (QuartzScheduler_Worker-7) XML RPC error in command GetCapabilitiesVDS ( Vds: orchid-vds1 ), the error was: java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException, NoRouteToHostException: No route to host >2012-08-15 11:52:19,621 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand] (QuartzScheduler_Worker-12) XML RPC error in command GetCapabilitiesVDS ( Vds: orchid-vds1 ), the error was: java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException, NoRouteToHostException: No route to host >2012-08-15 11:52:22,625 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand] (QuartzScheduler_Worker-14) XML RPC error in command GetCapabilitiesVDS ( Vds: orchid-vds1 ), the error was: java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException, NoRouteToHostException: No route to host >2012-08-15 11:52:27,687 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand] (QuartzScheduler_Worker-23) XML RPC error in command GetCapabilitiesVDS ( Vds: orchid-vds1 ), the error was: java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException, NoRouteToHostException: No route to host >2012-08-15 11:52:30,688 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand] (QuartzScheduler_Worker-27) XML RPC error in command GetCapabilitiesVDS ( Vds: orchid-vds1 ), the error was: java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException, NoRouteToHostException: No route to host >2012-08-15 11:52:35,743 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand] (QuartzScheduler_Worker-30) XML RPC error in command GetCapabilitiesVDS ( Vds: orchid-vds1 ), the error was: java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException, NoRouteToHostException: No route to host >2012-08-15 11:52:38,744 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand] (QuartzScheduler_Worker-34) XML RPC error in command GetCapabilitiesVDS ( Vds: orchid-vds1 ), the error was: java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException, NoRouteToHostException: No route to host >2012-08-15 11:52:43,792 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand] (QuartzScheduler_Worker-40) XML RPC error in command GetCapabilitiesVDS ( Vds: orchid-vds1 ), the error was: java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException, NoRouteToHostException: No route to host >2012-08-15 11:52:46,793 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand] (QuartzScheduler_Worker-45) XML RPC error in command GetCapabilitiesVDS ( Vds: orchid-vds1 ), the error was: java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException, NoRouteToHostException: No route to host >2012-08-15 11:52:51,884 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand] (QuartzScheduler_Worker-43) [3f09373] XML RPC error in command GetCapabilitiesVDS ( Vds: orchid-vds1 ), the error was: java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException, NoRouteToHostException: No route to host >2012-08-15 11:52:54,888 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand] (QuartzScheduler_Worker-48) XML RPC error in command GetCapabilitiesVDS ( Vds: orchid-vds1 ), the error was: java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException, NoRouteToHostException: No route to host >2012-08-15 11:52:59,951 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand] (QuartzScheduler_Worker-56) XML RPC error in command GetCapabilitiesVDS ( Vds: orchid-vds1 ), the error was: java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException, NoRouteToHostException: No route to host >2012-08-15 11:53:02,952 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand] (QuartzScheduler_Worker-52) XML RPC error in command GetCapabilitiesVDS ( Vds: orchid-vds1 ), the error was: java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException, NoRouteToHostException: No route to host >2012-08-15 11:53:08,245 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand] (QuartzScheduler_Worker-67) XML RPC error in command GetCapabilitiesVDS ( Vds: orchid-vds1 ), the error was: java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException, NoRouteToHostException: No route to host >2012-08-15 11:53:11,246 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand] (QuartzScheduler_Worker-68) XML RPC error in command GetCapabilitiesVDS ( Vds: orchid-vds1 ), the error was: java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException, NoRouteToHostException: No route to host >2012-08-15 11:53:16,288 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand] (QuartzScheduler_Worker-73) XML RPC error in command GetCapabilitiesVDS ( Vds: orchid-vds1 ), the error was: java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException, NoRouteToHostException: No route to host >2012-08-15 11:53:19,289 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.VdsBrokerCommand] (QuartzScheduler_Worker-76) XML RPC error in command GetCapabilitiesVDS ( Vds: orchid-vds1 ), the error was: java.util.concurrent.ExecutionException: java.lang.reflect.InvocationTargetException, NoRouteToHostException: No route to host >2012-08-15 11:53:40,662 INFO [org.ovirt.engine.core.bll.InitVdsOnUpCommand] (QuartzScheduler_Worker-3) [7ad21c20] Running command: InitVdsOnUpCommand internal: true. >2012-08-15 11:53:40,852 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (QuartzScheduler_Worker-3) [40920128] START, ValidateStorageServerConnectionVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777, storagePoolId = 35352af8-fb85-46f7-8433-ee6540b14a47, storageType = NFS, connectionList = [{ id: 406ba634-9ada-4a27-a391-da15e56ab20d, connection: lion.qa.lab.tlv:/exports/meni/si };]), log id: 55a28114 >2012-08-15 11:53:40,877 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (QuartzScheduler_Worker-3) [40920128] FINISH, ValidateStorageServerConnectionVDSCommand, return: {406ba634-9ada-4a27-a391-da15e56ab20d=0}, log id: 55a28114 >2012-08-15 11:53:40,878 INFO [org.ovirt.engine.core.bll.storage.ConnectHostToStoragePoolServersCommand] (QuartzScheduler_Worker-3) [40920128] Running command: ConnectHostToStoragePoolServersCommand internal: true. Entities affected : ID: 35352af8-fb85-46f7-8433-ee6540b14a47 Type: StoragePool >2012-08-15 11:53:40,879 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (QuartzScheduler_Worker-3) [40920128] START, ConnectStorageServerVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777, storagePoolId = 35352af8-fb85-46f7-8433-ee6540b14a47, storageType = NFS, connectionList = [{ id: 406ba634-9ada-4a27-a391-da15e56ab20d, connection: lion.qa.lab.tlv:/exports/meni/si };]), log id: 662156d6 >2012-08-15 11:53:41,198 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (QuartzScheduler_Worker-3) [40920128] FINISH, ConnectStorageServerVDSCommand, return: {406ba634-9ada-4a27-a391-da15e56ab20d=477}, log id: 662156d6 >2012-08-15 11:53:41,231 ERROR [org.ovirt.engine.core.bll.storage.NFSStorageHelper] (QuartzScheduler_Worker-3) [40920128] The connection with details lion.qa.lab.tlv:/exports/meni/si failed because of error code 477 and error message is: 477 >2012-08-15 11:53:41,231 INFO [org.ovirt.engine.core.bll.storage.ConnectHostToStoragePoolServersCommand] (QuartzScheduler_Worker-3) [40920128] Host orchid-vds1 storage connection was failed >2012-08-15 11:53:41,239 INFO [org.ovirt.engine.core.bll.SetNonOperationalVdsCommand] (QuartzScheduler_Worker-3) [4b8f091d] Running command: SetNonOperationalVdsCommand internal: true. Entities affected : ID: ec084258-e6a1-11e1-a8e4-001a4a169777 Type: VDS >2012-08-15 11:53:41,241 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (QuartzScheduler_Worker-3) [4b8f091d] START, SetVdsStatusVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777, status=NonOperational, nonOperationalReason=STORAGE_DOMAIN_UNREACHABLE), log id: 496fbe77 >2012-08-15 11:53:41,244 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (QuartzScheduler_Worker-3) [4b8f091d] FINISH, SetVdsStatusVDSCommand, log id: 496fbe77 >2012-08-15 11:53:41,396 INFO [org.ovirt.engine.core.bll.MultipleActionsRunner] (pool-4-thread-50) [4b8f091d] MultipleActionsRunner of type MigrateVmToServer invoked with no actions >2012-08-15 11:53:41,443 INFO [org.ovirt.engine.core.bll.HandleVdsCpuFlagsOrClusterChangedCommand] (QuartzScheduler_Worker-3) [51692a4e] Running command: HandleVdsCpuFlagsOrClusterChangedCommand internal: true. Entities affected : ID: ec084258-e6a1-11e1-a8e4-001a4a169777 Type: VDS >2012-08-15 11:53:41,448 INFO [org.ovirt.engine.core.bll.HandleVdsVersionCommand] (QuartzScheduler_Worker-3) [71e691f6] Running command: HandleVdsVersionCommand internal: true. Entities affected : ID: ec084258-e6a1-11e1-a8e4-001a4a169777 Type: VDS >2012-08-15 11:53:41,450 INFO [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-3) [71e691f6] Host ec084258-e6a1-11e1-a8e4-001a4a169777 : orchid-vds1 is already in NonOperational status. SetNonOperationalVds command is skipped. >2012-08-15 11:55:00,002 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-13) Autorecovering hosts is disabled, skipping >2012-08-15 11:55:00,002 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-13) Autorecovering storage domains is disabled, skipping >2012-08-15 11:59:19,305 INFO [org.ovirt.engine.core.bll.MaintananceNumberOfVdssCommand] (pool-4-thread-46) [568bff5d] Running command: MaintananceNumberOfVdssCommand internal: false. Entities affected : ID: ec084258-e6a1-11e1-a8e4-001a4a169777 Type: VDS >2012-08-15 11:59:19,305 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-4-thread-46) [568bff5d] START, SetVdsStatusVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777, status=PreparingForMaintenance, nonOperationalReason=NONE), log id: 2ea1356 >2012-08-15 11:59:19,394 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-4-thread-46) [568bff5d] FINISH, SetVdsStatusVDSCommand, log id: 2ea1356 >2012-08-15 11:59:19,480 INFO [org.ovirt.engine.core.bll.MaintananceVdsCommand] (pool-4-thread-46) [32089589] Running command: MaintananceVdsCommand internal: true. Entities affected : ID: ec084258-e6a1-11e1-a8e4-001a4a169777 Type: VDS >2012-08-15 11:59:19,959 INFO [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-52) vds::Updated vds status from Preparing for Maintenance to Maintenance in database, vds = ec084258-e6a1-11e1-a8e4-001a4a169777 : orchid-vds1 >2012-08-15 11:59:20,083 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IrsBrokerCommand] (QuartzScheduler_Worker-52) Clearing cache of pool: 35352af8-fb85-46f7-8433-ee6540b14a47 for problematic entities of VDS: orchid-vds1. >2012-08-15 11:59:20,089 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStoragePoolVDSCommand] (QuartzScheduler_Worker-52) START, DisconnectStoragePoolVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777, storagePoolId = 35352af8-fb85-46f7-8433-ee6540b14a47, vds_spm_id = 1, masterDomainId = 00000000-0000-0000-0000-000000000000, masterVersion = 0), log id: 9136d41 >2012-08-15 11:59:20,107 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStoragePoolVDSCommand] (QuartzScheduler_Worker-52) FINISH, DisconnectStoragePoolVDSCommand, log id: 9136d41 >2012-08-15 11:59:20,114 INFO [org.ovirt.engine.core.bll.storage.DisconnectHostFromStoragePoolServersCommand] (QuartzScheduler_Worker-52) [5a69cb0e] Running command: DisconnectHostFromStoragePoolServersCommand internal: true. Entities affected : ID: 35352af8-fb85-46f7-8433-ee6540b14a47 Type: StoragePool >2012-08-15 11:59:20,127 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStorageServerVDSCommand] (QuartzScheduler_Worker-52) [5a69cb0e] START, DisconnectStorageServerVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777, storagePoolId = 35352af8-fb85-46f7-8433-ee6540b14a47, storageType = NFS, connectionList = [{ id: 406ba634-9ada-4a27-a391-da15e56ab20d, connection: lion.qa.lab.tlv:/exports/meni/si };]), log id: 39a60404 >2012-08-15 11:59:20,707 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DisconnectStorageServerVDSCommand] (QuartzScheduler_Worker-52) [5a69cb0e] FINISH, DisconnectStorageServerVDSCommand, return: {406ba634-9ada-4a27-a391-da15e56ab20d=477}, log id: 39a60404 >2012-08-15 11:59:35,920 INFO [org.ovirt.engine.core.bll.SetupNetworksCommand] (ajp-/127.0.0.1:8009-8) [24bf51bf] Running command: SetupNetworksCommand internal: false. Entities affected : ID: ec084258-e6a1-11e1-a8e4-001a4a169777 Type: VDS >2012-08-15 11:59:35,922 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (ajp-/127.0.0.1:8009-8) [24bf51bf] START, SetupNetworksVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777), log id: b3d7f35 >2012-08-15 11:59:35,922 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (ajp-/127.0.0.1:8009-8) [24bf51bf] FINISH, SetupNetworksVDSCommand, log id: b3d7f35 >2012-08-15 11:59:38,108 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] (ajp-/127.0.0.1:8009-8) [24bf51bf] Failed in SetupNetworksVDS method >2012-08-15 11:59:38,108 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] (ajp-/127.0.0.1:8009-8) [24bf51bf] Error code unexpected and error message VDSGenericException: VDSErrorException: Failed to SetupNetworksVDS, error = Unexpected exception >2012-08-15 11:59:38,108 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] (ajp-/127.0.0.1:8009-8) [24bf51bf] org.ovirt.engine.core.vdsbroker.vdsbroker.VDSErrorException: VDSGenericException: VDSErrorException: Failed to SetupNetworksVDS, error = Unexpected exception >2012-08-15 11:59:38,108 ERROR [org.ovirt.engine.core.vdsbroker.VDSCommandBase] (ajp-/127.0.0.1:8009-8) [24bf51bf] Command SetupNetworksVDS execution failed. Exception: RuntimeException: org.ovirt.engine.core.vdsbroker.vdsbroker.VDSErrorException: VDSGenericException: VDSErrorException: Failed to SetupNetworksVDS, error = Unexpected exception >2012-08-15 11:59:38,110 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8009-8) [24bf51bf] No string for UNASSIGNED type. Use default Log >2012-08-15 12:00:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-10) Autorecovering hosts is disabled, skipping >2012-08-15 12:00:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-10) Autorecovering storage domains is disabled, skipping >2012-08-15 12:00:11,321 INFO [org.ovirt.engine.core.bll.SetupNetworksCommand] (ajp-/127.0.0.1:8009-7) [7fa4e08f] Running command: SetupNetworksCommand internal: false. Entities affected : ID: f7ea1dc6-e6a1-11e1-bc36-001a4a169777 Type: VDS >2012-08-15 12:00:11,325 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (ajp-/127.0.0.1:8009-7) [7fa4e08f] START, SetupNetworksVDSCommand(vdsId = f7ea1dc6-e6a1-11e1-bc36-001a4a169777), log id: 6c11f717 >2012-08-15 12:00:11,325 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (ajp-/127.0.0.1:8009-7) [7fa4e08f] FINISH, SetupNetworksVDSCommand, log id: 6c11f717 >2012-08-15 12:00:27,372 INFO [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (ajp-/127.0.0.1:8009-7) [7fa4e08f] START, UpdateVdsDynamicDataVDSCommand(vdsId = f7ea1dc6-e6a1-11e1-bc36-001a4a169777, vdsDynamic=org.ovirt.engine.core.common.businessentities.VdsDynamic@59af779b), log id: 76366a2 >2012-08-15 12:00:27,373 INFO [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (ajp-/127.0.0.1:8009-7) [7fa4e08f] FINISH, UpdateVdsDynamicDataVDSCommand, log id: 76366a2 >2012-08-15 12:00:27,464 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8009-7) [7fa4e08f] No string for UNASSIGNED type. Use default Log >2012-08-15 12:03:33,329 INFO [org.ovirt.engine.core.bll.MultipleActionsRunner] (pool-4-thread-50) Start time: 8/15/12 12:03 PM. Start running CanDoAction for command number 1/5 (Command type: AttachNetworkToVdsGroup) >2012-08-15 12:03:33,333 INFO [org.ovirt.engine.core.bll.MultipleActionsRunner] (pool-4-thread-49) Start time: 8/15/12 12:03 PM. Start running CanDoAction for command number 2/5 (Command type: AttachNetworkToVdsGroup) >2012-08-15 12:03:33,337 INFO [org.ovirt.engine.core.bll.MultipleActionsRunner] (pool-4-thread-46) Start time: 8/15/12 12:03 PM. Start running CanDoAction for command number 3/5 (Command type: AttachNetworkToVdsGroup) >2012-08-15 12:03:33,342 INFO [org.ovirt.engine.core.bll.MultipleActionsRunner] (pool-4-thread-45) Start time: 8/15/12 12:03 PM. Start running CanDoAction for command number 4/5 (Command type: AttachNetworkToVdsGroup) >2012-08-15 12:03:33,344 INFO [org.ovirt.engine.core.bll.MultipleActionsRunner] (pool-4-thread-47) Start time: 8/15/12 12:03 PM. Start running CanDoAction for command number 5/5 (Command type: AttachNetworkToVdsGroup) >2012-08-15 12:03:33,351 INFO [org.ovirt.engine.core.bll.MultipleActionsRunner] (pool-4-thread-49) End time: 8/15/12 12:03 PM. Finish handling CanDoAction for command number 2/5 (Command type: AttachNetworkToVdsGroup) >2012-08-15 12:03:33,367 INFO [org.ovirt.engine.core.bll.MultipleActionsRunner] (pool-4-thread-46) End time: 8/15/12 12:03 PM. Finish handling CanDoAction for command number 3/5 (Command type: AttachNetworkToVdsGroup) >2012-08-15 12:03:33,367 INFO [org.ovirt.engine.core.bll.MultipleActionsRunner] (pool-4-thread-50) End time: 8/15/12 12:03 PM. Finish handling CanDoAction for command number 1/5 (Command type: AttachNetworkToVdsGroup) >2012-08-15 12:03:33,405 INFO [org.ovirt.engine.core.bll.MultipleActionsRunner] (pool-4-thread-47) End time: 8/15/12 12:03 PM. Finish handling CanDoAction for command number 5/5 (Command type: AttachNetworkToVdsGroup) >2012-08-15 12:03:33,504 INFO [org.ovirt.engine.core.bll.MultipleActionsRunner] (pool-4-thread-45) End time: 8/15/12 12:03 PM. Finish handling CanDoAction for command number 4/5 (Command type: AttachNetworkToVdsGroup) >2012-08-15 12:03:33,507 INFO [org.ovirt.engine.core.bll.AttachNetworkToVdsGroupCommand] (pool-4-thread-45) [5e798cd9] Running command: AttachNetworkToVdsGroupCommand internal: false. Entities affected : ID: d3e09392-e6a1-11e1-9a04-001a4a169777 Type: VdsGroups >2012-08-15 12:03:33,719 INFO [org.ovirt.engine.core.bll.AttachNetworkToVdsGroupCommand] (pool-4-thread-45) [7e0e2b03] Running command: AttachNetworkToVdsGroupCommand internal: false. Entities affected : ID: d3e09392-e6a1-11e1-9a04-001a4a169777 Type: VdsGroups >2012-08-15 12:03:33,883 INFO [org.ovirt.engine.core.bll.AttachNetworkToVdsGroupCommand] (pool-4-thread-45) [542a129c] Running command: AttachNetworkToVdsGroupCommand internal: false. Entities affected : ID: d3e09392-e6a1-11e1-9a04-001a4a169777 Type: VdsGroups >2012-08-15 12:03:34,014 INFO [org.ovirt.engine.core.bll.AttachNetworkToVdsGroupCommand] (pool-4-thread-45) [162beaef] Running command: AttachNetworkToVdsGroupCommand internal: false. Entities affected : ID: d3e09392-e6a1-11e1-9a04-001a4a169777 Type: VdsGroups >2012-08-15 12:03:34,106 INFO [org.ovirt.engine.core.bll.AttachNetworkToVdsGroupCommand] (pool-4-thread-45) [1d4cfc48] Running command: AttachNetworkToVdsGroupCommand internal: false. Entities affected : ID: d3e09392-e6a1-11e1-9a04-001a4a169777 Type: VdsGroups >2012-08-15 12:04:04,672 INFO [org.ovirt.engine.core.bll.ActivateVdsCommand] (ajp-/127.0.0.1:8009-3) Lock Acquired to object EngineLock [exclusiveLocks= key: ec084258-e6a1-11e1-a8e4-001a4a169777 value: VDS >, sharedLocks= ] >2012-08-15 12:04:04,766 INFO [org.ovirt.engine.core.bll.ActivateVdsCommand] (pool-4-thread-47) [3222a9b5] Running command: ActivateVdsCommand internal: false. Entities affected : ID: ec084258-e6a1-11e1-a8e4-001a4a169777 Type: VDS >2012-08-15 12:04:04,810 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-4-thread-47) [3222a9b5] START, SetVdsStatusVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777, status=Unassigned, nonOperationalReason=NONE), log id: 4dda94a2 >2012-08-15 12:04:04,817 INFO [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-4-thread-47) [3222a9b5] FINISH, SetVdsStatusVDSCommand, log id: 4dda94a2 >2012-08-15 12:04:04,906 INFO [org.ovirt.engine.core.vdsbroker.ActivateVdsVDSCommand] (pool-4-thread-47) [3222a9b5] START, ActivateVdsVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777), log id: 433722a2 >2012-08-15 12:04:05,556 INFO [org.ovirt.engine.core.bll.HandleVdsCpuFlagsOrClusterChangedCommand] (pool-4-thread-47) [3cbf9d6a] Running command: HandleVdsCpuFlagsOrClusterChangedCommand internal: true. Entities affected : ID: ec084258-e6a1-11e1-a8e4-001a4a169777 Type: VDS >2012-08-15 12:04:05,563 INFO [org.ovirt.engine.core.bll.HandleVdsVersionCommand] (pool-4-thread-47) [4769df9f] Running command: HandleVdsVersionCommand internal: true. Entities affected : ID: ec084258-e6a1-11e1-a8e4-001a4a169777 Type: VDS >2012-08-15 12:04:05,570 INFO [org.ovirt.engine.core.vdsbroker.ActivateVdsVDSCommand] (pool-4-thread-47) [4769df9f] FINISH, ActivateVdsVDSCommand, log id: 433722a2 >2012-08-15 12:04:05,850 INFO [org.ovirt.engine.core.bll.ActivateVdsCommand] (pool-4-thread-47) [4769df9f] Lock freed to object EngineLock [exclusiveLocks= key: ec084258-e6a1-11e1-a8e4-001a4a169777 value: VDS >, sharedLocks= ] >2012-08-15 12:04:06,437 INFO [org.ovirt.engine.core.bll.InitVdsOnUpCommand] (QuartzScheduler_Worker-45) [292c85ca] Running command: InitVdsOnUpCommand internal: true. >2012-08-15 12:04:06,567 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (QuartzScheduler_Worker-45) [97c430e] START, ValidateStorageServerConnectionVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777, storagePoolId = 35352af8-fb85-46f7-8433-ee6540b14a47, storageType = NFS, connectionList = [{ id: 406ba634-9ada-4a27-a391-da15e56ab20d, connection: lion.qa.lab.tlv:/exports/meni/si };]), log id: db777ec >2012-08-15 12:04:06,605 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ValidateStorageServerConnectionVDSCommand] (QuartzScheduler_Worker-45) [97c430e] FINISH, ValidateStorageServerConnectionVDSCommand, return: {406ba634-9ada-4a27-a391-da15e56ab20d=0}, log id: db777ec >2012-08-15 12:04:06,605 INFO [org.ovirt.engine.core.bll.storage.ConnectHostToStoragePoolServersCommand] (QuartzScheduler_Worker-45) [97c430e] Running command: ConnectHostToStoragePoolServersCommand internal: true. Entities affected : ID: 35352af8-fb85-46f7-8433-ee6540b14a47 Type: StoragePool >2012-08-15 12:04:06,607 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (QuartzScheduler_Worker-45) [97c430e] START, ConnectStorageServerVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777, storagePoolId = 35352af8-fb85-46f7-8433-ee6540b14a47, storageType = NFS, connectionList = [{ id: 406ba634-9ada-4a27-a391-da15e56ab20d, connection: lion.qa.lab.tlv:/exports/meni/si };]), log id: 26ac1809 >2012-08-15 12:04:06,732 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (QuartzScheduler_Worker-45) [97c430e] FINISH, ConnectStorageServerVDSCommand, return: {406ba634-9ada-4a27-a391-da15e56ab20d=0}, log id: 26ac1809 >2012-08-15 12:04:06,732 INFO [org.ovirt.engine.core.bll.storage.ConnectHostToStoragePoolServersCommand] (QuartzScheduler_Worker-45) [97c430e] Host orchid-vds1 storage connection was succeeded >2012-08-15 12:04:06,781 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (QuartzScheduler_Worker-45) [97c430e] START, ConnectStoragePoolVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777, storagePoolId = 35352af8-fb85-46f7-8433-ee6540b14a47, vds_spm_id = 1, masterDomainId = 0bbdf65d-d08f-4f95-9da6-ea4d2a10c732, masterVersion = 1), log id: 4e78c48d >2012-08-15 12:04:07,922 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (QuartzScheduler_Worker-45) [97c430e] FINISH, ConnectStoragePoolVDSCommand, log id: 4e78c48d >2012-08-15 12:04:07,933 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (QuartzScheduler_Worker-45) [97c430e] No string for UNASSIGNED type. Use default Log >2012-08-15 12:04:07,936 INFO [org.ovirt.engine.core.bll.MultipleActionsRunner] (pool-4-thread-46) [97c430e] MultipleActionsRunner of type MigrateVmToServer invoked with no actions >2012-08-15 12:04:08,015 INFO [org.ovirt.engine.core.bll.HandleVdsCpuFlagsOrClusterChangedCommand] (QuartzScheduler_Worker-45) [596444ea] Running command: HandleVdsCpuFlagsOrClusterChangedCommand internal: true. Entities affected : ID: ec084258-e6a1-11e1-a8e4-001a4a169777 Type: VDS >2012-08-15 12:04:08,018 INFO [org.ovirt.engine.core.bll.HandleVdsVersionCommand] (QuartzScheduler_Worker-45) [72593029] Running command: HandleVdsVersionCommand internal: true. Entities affected : ID: ec084258-e6a1-11e1-a8e4-001a4a169777 Type: VDS >2012-08-15 12:05:00,000 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-11) Autorecovering hosts is disabled, skipping >2012-08-15 12:05:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-11) Autorecovering storage domains is disabled, skipping >2012-08-15 12:05:44,130 INFO [org.ovirt.engine.core.bll.AddVmInterfaceCommand] (ajp-/127.0.0.1:8009-2) [104a22a9] Running command: AddVmInterfaceCommand internal: false. Entities affected : ID: 5f196943-28c0-4b92-b1b3-941b7b9f540b Type: VM >2012-08-15 12:05:44,132 INFO [org.ovirt.engine.core.bll.MacPoolManager] (ajp-/127.0.0.1:8009-2) [104a22a9] MacPoolManager::allocateNewMac entered >2012-08-15 12:05:44,132 INFO [org.ovirt.engine.core.bll.MacPoolManager] (ajp-/127.0.0.1:8009-2) [104a22a9] MacPoolManager::allocateNewMac allocated mac = 00:1a:4a:23:61:68 >2012-08-15 12:05:50,308 INFO [org.ovirt.engine.core.bll.AddVmInterfaceCommand] (ajp-/127.0.0.1:8009-7) [76fd2090] Running command: AddVmInterfaceCommand internal: false. Entities affected : ID: 5f196943-28c0-4b92-b1b3-941b7b9f540b Type: VM >2012-08-15 12:05:50,309 INFO [org.ovirt.engine.core.bll.MacPoolManager] (ajp-/127.0.0.1:8009-7) [76fd2090] MacPoolManager::allocateNewMac entered >2012-08-15 12:05:50,309 INFO [org.ovirt.engine.core.bll.MacPoolManager] (ajp-/127.0.0.1:8009-7) [76fd2090] MacPoolManager::allocateNewMac allocated mac = 00:1a:4a:23:61:67 >2012-08-15 12:06:01,559 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetDeviceListVDSCommand] (ajp-/127.0.0.1:8009-1) START, GetDeviceListVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777, storageType=ISCSI), log id: 5ccb214b >2012-08-15 12:06:02,370 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetDeviceListVDSCommand] (ajp-/127.0.0.1:8009-1) FINISH, GetDeviceListVDSCommand, return: [], log id: 5ccb214b >2012-08-15 12:10:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-13) Autorecovering hosts is disabled, skipping >2012-08-15 12:10:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-13) Autorecovering storage domains is disabled, skipping >2012-08-15 12:15:00,000 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-18) Autorecovering hosts is disabled, skipping >2012-08-15 12:15:00,000 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-18) Autorecovering storage domains is disabled, skipping >2012-08-15 12:20:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-9) Autorecovering hosts is disabled, skipping >2012-08-15 12:20:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-9) Autorecovering storage domains is disabled, skipping >2012-08-15 12:21:04,194 INFO [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-97) DbUserCacheManager::refreshAllUserData() - entered >2012-08-15 12:21:34,192 ERROR [org.ovirt.engine.core.ServletUtils] (ajp-/127.0.0.1:8009-1) Can't read file "/usr/share/ovirt-engine/docs/en-US/DocumentationPath.csv" for request "/docs/en-US/DocumentationPath.csv", will send a 404 error response. >2012-08-15 12:21:54,222 ERROR [org.ovirt.engine.core.ServletUtils] (ajp-/127.0.0.1:8009-8) Can't read file "/usr/share/ovirt-engine/docs/en-US/DocumentationPath.csv" for request "/docs/en-US/DocumentationPath.csv", will send a 404 error response. >2012-08-15 12:22:41,703 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetDeviceListVDSCommand] (ajp-/127.0.0.1:8009-6) START, GetDeviceListVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777, storageType=ISCSI), log id: 744fe20d >2012-08-15 12:22:42,554 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.GetDeviceListVDSCommand] (ajp-/127.0.0.1:8009-6) FINISH, GetDeviceListVDSCommand, return: [], log id: 744fe20d >2012-08-15 12:22:51,838 INFO [org.ovirt.engine.core.bll.AddDiskCommand] (ajp-/127.0.0.1:8009-7) Lock Acquired to object EngineLock [exclusiveLocks= key: 5f196943-28c0-4b92-b1b3-941b7b9f540b value: VM_DISK_BOOT >, sharedLocks= key: 5f196943-28c0-4b92-b1b3-941b7b9f540b value: VM >] >2012-08-15 12:22:51,859 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (ajp-/127.0.0.1:8009-7) START, IsValidVDSCommand(storagePoolId = 35352af8-fb85-46f7-8433-ee6540b14a47, ignoreFailoverLimit = false, compatabilityVersion = null), log id: 119e41cf >2012-08-15 12:22:51,859 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (ajp-/127.0.0.1:8009-7) FINISH, IsValidVDSCommand, return: true, log id: 119e41cf >2012-08-15 12:22:51,933 INFO [org.ovirt.engine.core.bll.AddDiskCommand] (pool-4-thread-47) [4189dddb] Running command: AddDiskCommand internal: false. Entities affected : ID: 5f196943-28c0-4b92-b1b3-941b7b9f540b Type: VM, ID: 0bbdf65d-d08f-4f95-9da6-ea4d2a10c732 Type: Storage >2012-08-15 12:22:52,095 INFO [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (pool-4-thread-47) [2d96b75d] Running command: AddImageFromScratchCommand internal: true. Entities affected : ID: 0bbdf65d-d08f-4f95-9da6-ea4d2a10c732 Type: Storage >2012-08-15 12:22:52,173 INFO [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (pool-4-thread-47) [2d96b75d] Lock freed to object EngineLock [exclusiveLocks= key: 5f196943-28c0-4b92-b1b3-941b7b9f540b value: VM_DISK_BOOT >, sharedLocks= key: 5f196943-28c0-4b92-b1b3-941b7b9f540b value: VM >] >2012-08-15 12:22:52,176 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (pool-4-thread-47) [2d96b75d] START, CreateImageVDSCommand(storagePoolId = 35352af8-fb85-46f7-8433-ee6540b14a47, ignoreFailoverLimit = false, compatabilityVersion = 3.1, storageDomainId = 0bbdf65d-d08f-4f95-9da6-ea4d2a10c732, imageGroupId = e20424b1-92ac-415a-9229-d77a3aea3e49, imageSizeInBytes = 21474836480, volumeFormat = RAW, newImageId = b62ebef4-cb75-42ee-915b-9a5a09673e68, newImageDescription = ), log id: 4059bc9f >2012-08-15 12:22:52,176 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (pool-4-thread-47) [2d96b75d] -- CreateImageVDSCommand::ExecuteIrsBrokerCommand: calling 'createVolume' with two new parameters: description and UUID >2012-08-15 12:22:52,177 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (pool-4-thread-47) [2d96b75d] -- createVolume parameters: > sdUUID=0bbdf65d-d08f-4f95-9da6-ea4d2a10c732 > spUUID=35352af8-fb85-46f7-8433-ee6540b14a47 > imgGUID=e20424b1-92ac-415a-9229-d77a3aea3e49 > size=21,474,836,480 bytes > volFormat=RAW > volType=Sparse > volUUID=b62ebef4-cb75-42ee-915b-9a5a09673e68 > descr= > srcImgGUID=00000000-0000-0000-0000-000000000000 > srcVolUUID=00000000-0000-0000-0000-000000000000 > >2012-08-15 12:22:52,998 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CreateImageVDSCommand] (pool-4-thread-47) [2d96b75d] FINISH, CreateImageVDSCommand, return: b62ebef4-cb75-42ee-915b-9a5a09673e68, log id: 4059bc9f >2012-08-15 12:22:53,026 INFO [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-4-thread-47) [2d96b75d] EntityAsyncTask::Adding EntityMultiAsyncTasks object for entity e20424b1-92ac-415a-9229-d77a3aea3e49 >2012-08-15 12:22:53,027 INFO [org.ovirt.engine.core.bll.EntityMultiAsyncTasks] (pool-4-thread-47) [2d96b75d] EntityMultiAsyncTasks::AttachTask: Attaching task fed50047-4cf5-44d4-af22-b07dcce5bc16 to entity e20424b1-92ac-415a-9229-d77a3aea3e49. >2012-08-15 12:22:53,046 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskUtils] (pool-4-thread-47) [2d96b75d] Adding task fed50047-4cf5-44d4-af22-b07dcce5bc16 to DataBase >2012-08-15 12:22:53,188 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (pool-4-thread-47) [2d96b75d] Adding task fed50047-4cf5-44d4-af22-b07dcce5bc16 (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet.. >2012-08-15 12:22:53,650 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (pool-4-thread-47) [2d96b75d] BaseAsyncTask::StartPollingTask: Starting to poll task fed50047-4cf5-44d4-af22-b07dcce5bc16. >2012-08-15 12:22:53,650 INFO [org.ovirt.engine.core.bll.EntityMultiAsyncTasks] (pool-4-thread-47) [2d96b75d] EntityMultiAsyncTasks::StartPollingTask: Current Action Type for entity e20424b1-92ac-415a-9229-d77a3aea3e49 is AddDisk (determined by task fed50047-4cf5-44d4-af22-b07dcce5bc16) >2012-08-15 12:22:54,626 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (QuartzScheduler_Worker-36) Polling and updating Async Tasks: 1 tasks, 1 tasks to poll now >2012-08-15 12:22:54,652 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (QuartzScheduler_Worker-36) SPMAsyncTask::PollTask: Polling task fed50047-4cf5-44d4-af22-b07dcce5bc16 (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'. >2012-08-15 12:22:54,669 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (QuartzScheduler_Worker-36) BaseAsyncTask::OnTaskEndSuccess: Task fed50047-4cf5-44d4-af22-b07dcce5bc16 (Parent Command AddDisk, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully. >2012-08-15 12:22:54,669 INFO [org.ovirt.engine.core.bll.EntityAsyncTask] (QuartzScheduler_Worker-36) EntityAsyncTask::EndActionIfNecessary: All tasks of entity e20424b1-92ac-415a-9229-d77a3aea3e49 has ended -> executing EndAction >2012-08-15 12:22:54,670 INFO [org.ovirt.engine.core.bll.EntityAsyncTask] (QuartzScheduler_Worker-36) EntityAsyncTask::EndAction: Ending action for 1 tasks (entity ID: e20424b1-92ac-415a-9229-d77a3aea3e49): calling EndAction for action type AddDisk. >2012-08-15 12:22:54,671 INFO [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-4-thread-45) EntityAsyncTask::EndCommandAction [within thread]context: Attempting to EndAction AddDisk >2012-08-15 12:22:54,704 INFO [org.ovirt.engine.core.bll.AddDiskCommand] (pool-4-thread-45) Ending command successfully: org.ovirt.engine.core.bll.AddDiskCommand >2012-08-15 12:22:54,704 INFO [org.ovirt.engine.core.bll.AddImageFromScratchCommand] (pool-4-thread-45) [2d96b75d] Ending command successfully: org.ovirt.engine.core.bll.AddImageFromScratchCommand >2012-08-15 12:22:54,715 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (pool-4-thread-45) [2d96b75d] START, GetImageInfoVDSCommand(storagePoolId = 35352af8-fb85-46f7-8433-ee6540b14a47, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 0bbdf65d-d08f-4f95-9da6-ea4d2a10c732, imageGroupId = e20424b1-92ac-415a-9229-d77a3aea3e49, imageId = b62ebef4-cb75-42ee-915b-9a5a09673e68), log id: 4cc9c52d >2012-08-15 12:22:54,772 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (pool-4-thread-45) [2d96b75d] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@b353b33a, log id: 4cc9c52d >2012-08-15 12:22:54,934 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.UpdateVMVDSCommand] (pool-4-thread-45) START, UpdateVMVDSCommand(storagePoolId = 35352af8-fb85-46f7-8433-ee6540b14a47, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 00000000-0000-0000-0000-000000000000, infoDictionary.size = 1), log id: 334803cd >2012-08-15 12:22:54,998 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.UpdateVMVDSCommand] (pool-4-thread-45) FINISH, UpdateVMVDSCommand, log id: 334803cd >2012-08-15 12:22:55,055 INFO [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-4-thread-45) EntityAsyncTask::HandleEndActionResult [within thread]: EndAction for action type AddDisk completed, handling the result. >2012-08-15 12:22:55,055 INFO [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-4-thread-45) EntityAsyncTask::HandleEndActionResult [within thread]: EndAction for action type AddDisk succeeded, clearing tasks. >2012-08-15 12:22:55,074 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (pool-4-thread-45) SPMAsyncTask::ClearAsyncTask: Attempting to clear task fed50047-4cf5-44d4-af22-b07dcce5bc16 >2012-08-15 12:22:55,075 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (pool-4-thread-45) START, SPMClearTaskVDSCommand(storagePoolId = 35352af8-fb85-46f7-8433-ee6540b14a47, ignoreFailoverLimit = false, compatabilityVersion = null, taskId = fed50047-4cf5-44d4-af22-b07dcce5bc16), log id: 68d50733 >2012-08-15 12:22:55,077 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (pool-4-thread-45) START, HSMClearTaskVDSCommand(vdsId = f7ea1dc6-e6a1-11e1-bc36-001a4a169777, taskId=fed50047-4cf5-44d4-af22-b07dcce5bc16), log id: 46d60d4 >2012-08-15 12:22:55,151 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (pool-4-thread-45) FINISH, HSMClearTaskVDSCommand, log id: 46d60d4 >2012-08-15 12:22:55,151 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (pool-4-thread-45) FINISH, SPMClearTaskVDSCommand, log id: 68d50733 >2012-08-15 12:22:55,165 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (pool-4-thread-45) BaseAsyncTask::RemoveTaskFromDB: Removed task fed50047-4cf5-44d4-af22-b07dcce5bc16 from DataBase >2012-08-15 12:22:55,165 INFO [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-4-thread-45) EntityAsyncTask::HandleEndActionResult [within thread]: Removing EntityMultiAsyncTasks object for entity e20424b1-92ac-415a-9229-d77a3aea3e49 >2012-08-15 12:23:21,393 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (ajp-/127.0.0.1:8009-3) [166524ee] START, IsValidVDSCommand(storagePoolId = 35352af8-fb85-46f7-8433-ee6540b14a47, ignoreFailoverLimit = false, compatabilityVersion = null), log id: 3f3498c3 >2012-08-15 12:23:21,397 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (ajp-/127.0.0.1:8009-3) [166524ee] FINISH, IsValidVDSCommand, return: true, log id: 3f3498c3 >2012-08-15 12:23:21,419 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (ajp-/127.0.0.1:8009-3) [166524ee] START, GetImageInfoVDSCommand(storagePoolId = 35352af8-fb85-46f7-8433-ee6540b14a47, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 0bbdf65d-d08f-4f95-9da6-ea4d2a10c732, imageGroupId = e20424b1-92ac-415a-9229-d77a3aea3e49, imageId = b62ebef4-cb75-42ee-915b-9a5a09673e68), log id: 4dedc6a6 >2012-08-15 12:23:21,480 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (ajp-/127.0.0.1:8009-3) [166524ee] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@b353b33a, log id: 4dedc6a6 >2012-08-15 12:23:21,513 INFO [org.ovirt.engine.core.bll.AddVmTemplateCommand] (ajp-/127.0.0.1:8009-3) [166524ee] Running command: AddVmTemplateCommand internal: false. Entities affected : ID: 35352af8-fb85-46f7-8433-ee6540b14a47 Type: StoragePool >2012-08-15 12:23:21,539 INFO [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (ajp-/127.0.0.1:8009-3) [166524ee] START, SetVmStatusVDSCommand(vmId = 5f196943-28c0-4b92-b1b3-941b7b9f540b, status = ImageLocked), log id: 3b5a4477 >2012-08-15 12:23:21,562 INFO [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (ajp-/127.0.0.1:8009-3) [166524ee] FINISH, SetVmStatusVDSCommand, log id: 3b5a4477 >2012-08-15 12:23:21,613 INFO [org.ovirt.engine.core.bll.CreateImageTemplateCommand] (ajp-/127.0.0.1:8009-3) [11869b0f] Running command: CreateImageTemplateCommand internal: true. Entities affected : ID: 0bbdf65d-d08f-4f95-9da6-ea4d2a10c732 Type: Storage >2012-08-15 12:23:21,615 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (ajp-/127.0.0.1:8009-3) [11869b0f] START, GetImageInfoVDSCommand(storagePoolId = 35352af8-fb85-46f7-8433-ee6540b14a47, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 0bbdf65d-d08f-4f95-9da6-ea4d2a10c732, imageGroupId = e20424b1-92ac-415a-9229-d77a3aea3e49, imageId = b62ebef4-cb75-42ee-915b-9a5a09673e68), log id: 2dffdbbe >2012-08-15 12:23:21,653 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (ajp-/127.0.0.1:8009-3) [11869b0f] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@b353b33a, log id: 2dffdbbe >2012-08-15 12:23:21,694 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (ajp-/127.0.0.1:8009-3) [11869b0f] START, CopyImageVDSCommand(storagePoolId = 35352af8-fb85-46f7-8433-ee6540b14a47, ignoreFailoverLimit = false, compatabilityVersion = 3.1, storageDomainId = 0bbdf65d-d08f-4f95-9da6-ea4d2a10c732, imageGroupId = e20424b1-92ac-415a-9229-d77a3aea3e49, imageId = b62ebef4-cb75-42ee-915b-9a5a09673e68, dstImageGroupId = 12139d2f-a23a-40ab-9074-6bb7afa72392, vmId = 5f196943-28c0-4b92-b1b3-941b7b9f540b, dstImageId = 8a87342b-5494-4044-bf79-1b789f651f01, imageDescription = Active VM, dstStorageDomainId = 0bbdf65d-d08f-4f95-9da6-ea4d2a10c732, copyVolumeType = SharedVol, volumeFormat = RAW, preallocate = Sparse, postZero = false, force = false), log id: 3f99a9cf >2012-08-15 12:23:21,695 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (ajp-/127.0.0.1:8009-3) [11869b0f] -- CopyImageVDSCommand::ExecuteIrsBrokerCommand: calling 'copyImage' with two new parameters: description and UUID >2012-08-15 12:23:21,695 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (ajp-/127.0.0.1:8009-3) [11869b0f] -- copyImage parameters: > sdUUID=0bbdf65d-d08f-4f95-9da6-ea4d2a10c732 > spUUID=35352af8-fb85-46f7-8433-ee6540b14a47 > vmGUID=5f196943-28c0-4b92-b1b3-941b7b9f540b > srcImageGUID=e20424b1-92ac-415a-9229-d77a3aea3e49 > srcVolUUID=b62ebef4-cb75-42ee-915b-9a5a09673e68 > dstImageGUID=12139d2f-a23a-40ab-9074-6bb7afa72392 > dstVolUUID=8a87342b-5494-4044-bf79-1b789f651f01 > descr=Active VM > >2012-08-15 12:23:21,796 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.CopyImageVDSCommand] (ajp-/127.0.0.1:8009-3) [11869b0f] FINISH, CopyImageVDSCommand, return: 00000000-0000-0000-0000-000000000000, log id: 3f99a9cf >2012-08-15 12:23:21,807 INFO [org.ovirt.engine.core.bll.EntityAsyncTask] (ajp-/127.0.0.1:8009-3) [11869b0f] EntityAsyncTask::Adding EntityMultiAsyncTasks object for entity 6a5577ae-ed54-44a7-8ad6-031d151734c8 >2012-08-15 12:23:21,807 INFO [org.ovirt.engine.core.bll.EntityMultiAsyncTasks] (ajp-/127.0.0.1:8009-3) [11869b0f] EntityMultiAsyncTasks::AttachTask: Attaching task 05bf0e4e-ad30-4f79-a459-c64bcaaaa5ce to entity 6a5577ae-ed54-44a7-8ad6-031d151734c8. >2012-08-15 12:23:21,809 INFO [org.ovirt.engine.core.bll.tasks.AsyncTaskUtils] (ajp-/127.0.0.1:8009-3) [11869b0f] Adding task 05bf0e4e-ad30-4f79-a459-c64bcaaaa5ce to DataBase >2012-08-15 12:23:21,858 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (ajp-/127.0.0.1:8009-3) [11869b0f] Adding task 05bf0e4e-ad30-4f79-a459-c64bcaaaa5ce (Parent Command AddVmTemplate, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters), polling hasn't started yet.. >2012-08-15 12:23:22,012 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (ajp-/127.0.0.1:8009-3) [11869b0f] BaseAsyncTask::StartPollingTask: Starting to poll task 05bf0e4e-ad30-4f79-a459-c64bcaaaa5ce. >2012-08-15 12:23:22,012 INFO [org.ovirt.engine.core.bll.EntityMultiAsyncTasks] (ajp-/127.0.0.1:8009-3) [11869b0f] EntityMultiAsyncTasks::StartPollingTask: Current Action Type for entity 6a5577ae-ed54-44a7-8ad6-031d151734c8 is AddVmTemplate (determined by task 05bf0e4e-ad30-4f79-a459-c64bcaaaa5ce) >2012-08-15 12:23:24,674 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (QuartzScheduler_Worker-92) Polling and updating Async Tasks: 2 tasks, 1 tasks to poll now >2012-08-15 12:23:24,702 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (QuartzScheduler_Worker-92) SPMAsyncTask::PollTask: Polling task 05bf0e4e-ad30-4f79-a459-c64bcaaaa5ce (Parent Command AddVmTemplate, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status running. >2012-08-15 12:23:24,703 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (QuartzScheduler_Worker-92) Finished polling Tasks, will poll again in 10 seconds. >2012-08-15 12:24:04,519 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (QuartzScheduler_Worker-41) Setting new tasks map. The map contains now 1 tasks >2012-08-15 12:24:04,801 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (QuartzScheduler_Worker-40) Polling and updating Async Tasks: 1 tasks, 1 tasks to poll now >2012-08-15 12:24:04,835 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (QuartzScheduler_Worker-40) Finished polling Tasks, will poll again in 10 seconds. >2012-08-15 12:25:00,000 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-10) Autorecovering hosts is disabled, skipping >2012-08-15 12:25:00,000 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-10) Autorecovering storage domains is disabled, skipping >2012-08-15 12:25:48,794 INFO [org.ovirt.engine.core.bll.storage.AddNetworkCommand] (ajp-/127.0.0.1:8009-5) [5139c643] Running command: AddNetworkCommand internal: false. Entities affected : ID: 35352af8-fb85-46f7-8433-ee6540b14a47 Type: StoragePool >2012-08-15 12:25:49,143 INFO [org.ovirt.engine.core.bll.AttachNetworkToVdsGroupCommand] (pool-4-thread-47) [667d032e] Running command: AttachNetworkToVdsGroupCommand internal: false. Entities affected : ID: d3e09392-e6a1-11e1-9a04-001a4a169777 Type: VdsGroups >2012-08-15 12:26:10,079 INFO [org.ovirt.engine.core.bll.SetupNetworksCommand] (ajp-/127.0.0.1:8009-4) [4cbdae0f] Running command: SetupNetworksCommand internal: false. Entities affected : ID: f7ea1dc6-e6a1-11e1-bc36-001a4a169777 Type: VDS >2012-08-15 12:26:10,081 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (ajp-/127.0.0.1:8009-4) [4cbdae0f] START, SetupNetworksVDSCommand(vdsId = f7ea1dc6-e6a1-11e1-bc36-001a4a169777), log id: 5766011e >2012-08-15 12:26:10,081 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (ajp-/127.0.0.1:8009-4) [4cbdae0f] FINISH, SetupNetworksVDSCommand, log id: 5766011e >2012-08-15 12:26:14,192 INFO [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (ajp-/127.0.0.1:8009-4) [4cbdae0f] START, UpdateVdsDynamicDataVDSCommand(vdsId = f7ea1dc6-e6a1-11e1-bc36-001a4a169777, vdsDynamic=org.ovirt.engine.core.common.businessentities.VdsDynamic@59af779b), log id: 4f21697c >2012-08-15 12:26:14,193 INFO [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (ajp-/127.0.0.1:8009-4) [4cbdae0f] FINISH, UpdateVdsDynamicDataVDSCommand, log id: 4f21697c >2012-08-15 12:26:14,257 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8009-4) [4cbdae0f] No string for UNASSIGNED type. Use default Log >2012-08-15 12:26:32,438 INFO [org.ovirt.engine.core.bll.DetachNetworkToVdsGroupCommand] (pool-4-thread-46) [1adbf628] Running command: DetachNetworkToVdsGroupCommand internal: false. Entities affected : ID: d3e09392-e6a1-11e1-9a04-001a4a169777 Type: VdsGroups >2012-08-15 12:26:34,617 INFO [org.ovirt.engine.core.bll.MultipleActionsRunner] (ajp-/127.0.0.1:8009-7) MultipleActionsRunner of type DetachNetworkToVdsGroup invoked with no actions >2012-08-15 12:26:34,975 INFO [org.ovirt.engine.core.bll.storage.UpdateNetworkCommand] (ajp-/127.0.0.1:8009-1) [795030f1] Running command: UpdateNetworkCommand internal: false. Entities affected : ID: 35352af8-fb85-46f7-8433-ee6540b14a47 Type: StoragePool >2012-08-15 12:26:34,997 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8009-1) [795030f1] No string for UNASSIGNED type. Use default Log >2012-08-15 12:26:35,281 INFO [org.ovirt.engine.core.bll.MultipleActionsRunner] (ajp-/127.0.0.1:8009-11) MultipleActionsRunner of type AttachNetworkToVdsGroup invoked with no actions >2012-08-15 12:26:49,076 INFO [org.ovirt.engine.core.bll.SetupNetworksCommand] (ajp-/127.0.0.1:8009-10) [10e0ca3f] Running command: SetupNetworksCommand internal: false. Entities affected : ID: f7ea1dc6-e6a1-11e1-bc36-001a4a169777 Type: VDS >2012-08-15 12:26:49,077 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (ajp-/127.0.0.1:8009-10) [10e0ca3f] START, SetupNetworksVDSCommand(vdsId = f7ea1dc6-e6a1-11e1-bc36-001a4a169777), log id: 2c4048da >2012-08-15 12:26:49,078 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (ajp-/127.0.0.1:8009-10) [10e0ca3f] FINISH, SetupNetworksVDSCommand, log id: 2c4048da >2012-08-15 12:26:51,488 INFO [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (ajp-/127.0.0.1:8009-10) [10e0ca3f] START, UpdateVdsDynamicDataVDSCommand(vdsId = f7ea1dc6-e6a1-11e1-bc36-001a4a169777, vdsDynamic=org.ovirt.engine.core.common.businessentities.VdsDynamic@59af779b), log id: 7447eba0 >2012-08-15 12:26:51,489 INFO [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (ajp-/127.0.0.1:8009-10) [10e0ca3f] FINISH, UpdateVdsDynamicDataVDSCommand, log id: 7447eba0 >2012-08-15 12:26:51,534 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8009-10) [10e0ca3f] No string for UNASSIGNED type. Use default Log >2012-08-15 12:27:27,097 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (QuartzScheduler_Worker-11) SPMAsyncTask::PollTask: Polling task 05bf0e4e-ad30-4f79-a459-c64bcaaaa5ce (Parent Command AddVmTemplate, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) returned status finished, result 'success'. >2012-08-15 12:27:27,114 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (QuartzScheduler_Worker-11) BaseAsyncTask::OnTaskEndSuccess: Task 05bf0e4e-ad30-4f79-a459-c64bcaaaa5ce (Parent Command AddVmTemplate, Parameters Type org.ovirt.engine.core.common.asynctasks.AsyncTaskParameters) ended successfully. >2012-08-15 12:27:27,114 INFO [org.ovirt.engine.core.bll.EntityAsyncTask] (QuartzScheduler_Worker-11) EntityAsyncTask::EndActionIfNecessary: All tasks of entity 6a5577ae-ed54-44a7-8ad6-031d151734c8 has ended -> executing EndAction >2012-08-15 12:27:27,114 INFO [org.ovirt.engine.core.bll.EntityAsyncTask] (QuartzScheduler_Worker-11) EntityAsyncTask::EndAction: Ending action for 1 tasks (entity ID: 6a5577ae-ed54-44a7-8ad6-031d151734c8): calling EndAction for action type AddVmTemplate. >2012-08-15 12:27:27,114 INFO [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-4-thread-46) EntityAsyncTask::EndCommandAction [within thread]context: Attempting to EndAction AddVmTemplate >2012-08-15 12:27:27,144 INFO [org.ovirt.engine.core.bll.AddVmTemplateCommand] (pool-4-thread-46) [166524ee] Ending command successfully: org.ovirt.engine.core.bll.AddVmTemplateCommand >2012-08-15 12:27:27,146 INFO [org.ovirt.engine.core.bll.CreateImageTemplateCommand] (pool-4-thread-46) [11869b0f] Ending command successfully: org.ovirt.engine.core.bll.CreateImageTemplateCommand >2012-08-15 12:27:27,152 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (pool-4-thread-46) [11869b0f] START, GetImageInfoVDSCommand(storagePoolId = 35352af8-fb85-46f7-8433-ee6540b14a47, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 0bbdf65d-d08f-4f95-9da6-ea4d2a10c732, imageGroupId = 12139d2f-a23a-40ab-9074-6bb7afa72392, imageId = 8a87342b-5494-4044-bf79-1b789f651f01), log id: 1ae90456 >2012-08-15 12:27:27,201 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.GetImageInfoVDSCommand] (pool-4-thread-46) [11869b0f] FINISH, GetImageInfoVDSCommand, return: org.ovirt.engine.core.common.businessentities.DiskImage@fcdd1760, log id: 1ae90456 >2012-08-15 12:27:27,254 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.UpdateVMVDSCommand] (pool-4-thread-46) START, UpdateVMVDSCommand(storagePoolId = 35352af8-fb85-46f7-8433-ee6540b14a47, ignoreFailoverLimit = false, compatabilityVersion = null, storageDomainId = 00000000-0000-0000-0000-000000000000, infoDictionary.size = 1), log id: 281845dd >2012-08-15 12:27:27,291 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.UpdateVMVDSCommand] (pool-4-thread-46) FINISH, UpdateVMVDSCommand, log id: 281845dd >2012-08-15 12:27:27,292 INFO [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (pool-4-thread-46) START, SetVmStatusVDSCommand(vmId = 5f196943-28c0-4b92-b1b3-941b7b9f540b, status = Down), log id: 7ab1fad3 >2012-08-15 12:27:27,297 INFO [org.ovirt.engine.core.vdsbroker.SetVmStatusVDSCommand] (pool-4-thread-46) FINISH, SetVmStatusVDSCommand, log id: 7ab1fad3 >2012-08-15 12:27:27,325 INFO [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-4-thread-46) EntityAsyncTask::HandleEndActionResult [within thread]: EndAction for action type AddVmTemplate completed, handling the result. >2012-08-15 12:27:27,325 INFO [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-4-thread-46) EntityAsyncTask::HandleEndActionResult [within thread]: EndAction for action type AddVmTemplate succeeded, clearing tasks. >2012-08-15 12:27:27,355 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (pool-4-thread-46) SPMAsyncTask::ClearAsyncTask: Attempting to clear task 05bf0e4e-ad30-4f79-a459-c64bcaaaa5ce >2012-08-15 12:27:27,356 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (pool-4-thread-46) START, SPMClearTaskVDSCommand(storagePoolId = 35352af8-fb85-46f7-8433-ee6540b14a47, ignoreFailoverLimit = false, compatabilityVersion = null, taskId = 05bf0e4e-ad30-4f79-a459-c64bcaaaa5ce), log id: 2f72f766 >2012-08-15 12:27:27,358 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (pool-4-thread-46) START, HSMClearTaskVDSCommand(vdsId = f7ea1dc6-e6a1-11e1-bc36-001a4a169777, taskId=05bf0e4e-ad30-4f79-a459-c64bcaaaa5ce), log id: 53090d43 >2012-08-15 12:27:27,391 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HSMClearTaskVDSCommand] (pool-4-thread-46) FINISH, HSMClearTaskVDSCommand, log id: 53090d43 >2012-08-15 12:27:27,392 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.SPMClearTaskVDSCommand] (pool-4-thread-46) FINISH, SPMClearTaskVDSCommand, log id: 2f72f766 >2012-08-15 12:27:27,404 INFO [org.ovirt.engine.core.bll.SPMAsyncTask] (pool-4-thread-46) BaseAsyncTask::RemoveTaskFromDB: Removed task 05bf0e4e-ad30-4f79-a459-c64bcaaaa5ce from DataBase >2012-08-15 12:27:27,404 INFO [org.ovirt.engine.core.bll.EntityAsyncTask] (pool-4-thread-46) EntityAsyncTask::HandleEndActionResult [within thread]: Removing EntityMultiAsyncTasks object for entity 6a5577ae-ed54-44a7-8ad6-031d151734c8 >2012-08-15 12:28:34,528 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (QuartzScheduler_Worker-1) Setting new tasks map. The map contains now 0 tasks >2012-08-15 12:28:34,528 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (QuartzScheduler_Worker-1) Cleared all tasks of pool 35352af8-fb85-46f7-8433-ee6540b14a47. >2012-08-15 12:28:34,531 INFO [org.ovirt.engine.core.bll.AsyncTaskManager] (QuartzScheduler_Worker-1) Could not find vds that is spm and non-operational. >2012-08-15 12:30:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-19) Autorecovering hosts is disabled, skipping >2012-08-15 12:30:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-19) Autorecovering storage domains is disabled, skipping >2012-08-15 12:34:42,774 INFO [org.ovirt.engine.core.bll.RunVmCommand] (ajp-/127.0.0.1:8009-6) Lock Acquired to object EngineLock [exclusiveLocks= key: 5f196943-28c0-4b92-b1b3-941b7b9f540b value: VM >, sharedLocks= ] >2012-08-15 12:34:42,794 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (ajp-/127.0.0.1:8009-6) START, IsValidVDSCommand(storagePoolId = 35352af8-fb85-46f7-8433-ee6540b14a47, ignoreFailoverLimit = false, compatabilityVersion = null), log id: 29b826a5 >2012-08-15 12:34:42,795 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (ajp-/127.0.0.1:8009-6) FINISH, IsValidVDSCommand, return: true, log id: 29b826a5 >2012-08-15 12:34:42,832 INFO [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp-/127.0.0.1:8009-6) START, IsVmDuringInitiatingVDSCommand(vmId = 5f196943-28c0-4b92-b1b3-941b7b9f540b), log id: e0490d9 >2012-08-15 12:34:42,832 INFO [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp-/127.0.0.1:8009-6) FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: e0490d9 >2012-08-15 12:34:42,916 INFO [org.ovirt.engine.core.bll.RunVmCommand] (pool-4-thread-50) [40488984] Running command: RunVmCommand internal: false. Entities affected : ID: 5f196943-28c0-4b92-b1b3-941b7b9f540b Type: VM >2012-08-15 12:34:42,929 ERROR [org.ovirt.engine.core.bll.RunVmCommand] (pool-4-thread-50) [40488984] Cant find VDS to run the VM 5f196943-28c0-4b92-b1b3-941b7b9f540b on, so this VM will not be run. >2012-08-15 12:34:42,975 INFO [org.ovirt.engine.core.bll.RunVmCommand] (pool-4-thread-50) [40488984] Lock freed to object EngineLock [exclusiveLocks= key: 5f196943-28c0-4b92-b1b3-941b7b9f540b value: VM >, sharedLocks= ] >2012-08-15 12:35:00,002 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-15) Autorecovering hosts is disabled, skipping >2012-08-15 12:35:00,002 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-15) Autorecovering storage domains is disabled, skipping >2012-08-15 12:35:43,048 INFO [org.ovirt.engine.core.bll.SetupNetworksCommand] (ajp-/127.0.0.1:8009-11) [732d89ec] Running command: SetupNetworksCommand internal: false. Entities affected : ID: f7ea1dc6-e6a1-11e1-bc36-001a4a169777 Type: VDS >2012-08-15 12:35:43,049 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (ajp-/127.0.0.1:8009-11) [732d89ec] START, SetupNetworksVDSCommand(vdsId = f7ea1dc6-e6a1-11e1-bc36-001a4a169777), log id: 60aa0188 >2012-08-15 12:35:43,050 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (ajp-/127.0.0.1:8009-11) [732d89ec] FINISH, SetupNetworksVDSCommand, log id: 60aa0188 >2012-08-15 12:35:47,851 INFO [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (ajp-/127.0.0.1:8009-11) [732d89ec] START, UpdateVdsDynamicDataVDSCommand(vdsId = f7ea1dc6-e6a1-11e1-bc36-001a4a169777, vdsDynamic=org.ovirt.engine.core.common.businessentities.VdsDynamic@1c54b0d9), log id: 58141644 >2012-08-15 12:35:47,852 INFO [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (ajp-/127.0.0.1:8009-11) [732d89ec] FINISH, UpdateVdsDynamicDataVDSCommand, log id: 58141644 >2012-08-15 12:35:47,915 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8009-11) [732d89ec] No string for UNASSIGNED type. Use default Log >2012-08-15 12:36:12,288 INFO [org.ovirt.engine.core.bll.SetupNetworksCommand] (ajp-/127.0.0.1:8009-5) [7c724798] Running command: SetupNetworksCommand internal: false. Entities affected : ID: ec084258-e6a1-11e1-a8e4-001a4a169777 Type: VDS >2012-08-15 12:36:12,289 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (ajp-/127.0.0.1:8009-5) [7c724798] START, SetupNetworksVDSCommand(vdsId = ec084258-e6a1-11e1-a8e4-001a4a169777), log id: 4d327c5a >2012-08-15 12:36:12,290 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.SetupNetworksVDSCommand] (ajp-/127.0.0.1:8009-5) [7c724798] FINISH, SetupNetworksVDSCommand, log id: 4d327c5a >2012-08-15 12:36:12,822 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] (ajp-/127.0.0.1:8009-5) [7c724798] Failed in SetupNetworksVDS method >2012-08-15 12:36:12,822 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] (ajp-/127.0.0.1:8009-5) [7c724798] Error code unexpected and error message VDSGenericException: VDSErrorException: Failed to SetupNetworksVDS, error = Unexpected exception >2012-08-15 12:36:12,823 ERROR [org.ovirt.engine.core.vdsbroker.vdsbroker.BrokerCommandBase] (ajp-/127.0.0.1:8009-5) [7c724798] org.ovirt.engine.core.vdsbroker.vdsbroker.VDSErrorException: VDSGenericException: VDSErrorException: Failed to SetupNetworksVDS, error = Unexpected exception >2012-08-15 12:36:12,823 ERROR [org.ovirt.engine.core.vdsbroker.VDSCommandBase] (ajp-/127.0.0.1:8009-5) [7c724798] Command SetupNetworksVDS execution failed. Exception: RuntimeException: org.ovirt.engine.core.vdsbroker.vdsbroker.VDSErrorException: VDSGenericException: VDSErrorException: Failed to SetupNetworksVDS, error = Unexpected exception >2012-08-15 12:36:12,825 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8009-5) [7c724798] No string for UNASSIGNED type. Use default Log >2012-08-15 12:36:46,245 INFO [org.ovirt.engine.core.bll.RunVmCommand] (ajp-/127.0.0.1:8009-5) Lock Acquired to object EngineLock [exclusiveLocks= key: 5f196943-28c0-4b92-b1b3-941b7b9f540b value: VM >, sharedLocks= ] >2012-08-15 12:36:46,255 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (ajp-/127.0.0.1:8009-5) START, IsValidVDSCommand(storagePoolId = 35352af8-fb85-46f7-8433-ee6540b14a47, ignoreFailoverLimit = false, compatabilityVersion = null), log id: 42f46c74 >2012-08-15 12:36:46,256 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (ajp-/127.0.0.1:8009-5) FINISH, IsValidVDSCommand, return: true, log id: 42f46c74 >2012-08-15 12:36:46,310 INFO [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp-/127.0.0.1:8009-5) START, IsVmDuringInitiatingVDSCommand(vmId = 5f196943-28c0-4b92-b1b3-941b7b9f540b), log id: 1a1b7723 >2012-08-15 12:36:46,310 INFO [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp-/127.0.0.1:8009-5) FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 1a1b7723 >2012-08-15 12:36:46,416 INFO [org.ovirt.engine.core.bll.RunVmCommand] (pool-4-thread-50) [5cebfd6a] Running command: RunVmCommand internal: false. Entities affected : ID: 5f196943-28c0-4b92-b1b3-941b7b9f540b Type: VM >2012-08-15 12:36:46,444 INFO [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (pool-4-thread-50) [5cebfd6a] START, CreateVmVDSCommand(vdsId = f7ea1dc6-e6a1-11e1-bc36-001a4a169777, vmId=5f196943-28c0-4b92-b1b3-941b7b9f540b, vm=org.ovirt.engine.core.common.businessentities.VM@6a98100d), log id: 2a84a5a9 >2012-08-15 12:36:46,498 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (pool-4-thread-50) [5cebfd6a] START, CreateVDSCommand(vdsId = f7ea1dc6-e6a1-11e1-bc36-001a4a169777, vmId=5f196943-28c0-4b92-b1b3-941b7b9f540b, vm=org.ovirt.engine.core.common.businessentities.VM@6a98100d), log id: 3e32640f >2012-08-15 12:36:46,730 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (pool-4-thread-50) [5cebfd6a] org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand spiceSslCipherSuite=DEFAULT,memSize=512,kvmEnable=true,smp=1,emulatedMachine=rhel6.3.0,vmType=kvm,keyboardLayout=en-us,nice=0,display=qxl,smpCoresPerSocket=1,spiceSecureChannels=smain,sinputs,timeOffset=0,transparentHugePages=true,vmId=5f196943-28c0-4b92-b1b3-941b7b9f540b,devices=[Ljava.util.Map;@6190c32,acpiEnable=true,vmName=VM1,cpuType=Conroe,custom={} >2012-08-15 12:36:46,730 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (pool-4-thread-50) [5cebfd6a] FINISH, CreateVDSCommand, log id: 3e32640f >2012-08-15 12:36:46,741 INFO [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (pool-4-thread-50) [5cebfd6a] IncreasePendingVms::CreateVmIncreasing vds orchid-vds2 pending vcpu count, now 1. Vm: VM1 >2012-08-15 12:36:46,768 INFO [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (pool-4-thread-50) [5cebfd6a] FINISH, CreateVmVDSCommand, return: WaitForLaunch, log id: 2a84a5a9 >2012-08-15 12:36:46,768 INFO [org.ovirt.engine.core.bll.RunVmCommand] (pool-4-thread-50) [5cebfd6a] Lock freed to object EngineLock [exclusiveLocks= key: 5f196943-28c0-4b92-b1b3-941b7b9f540b value: VM >, sharedLocks= ] >2012-08-15 12:36:50,901 INFO [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-61) VM VM1 5f196943-28c0-4b92-b1b3-941b7b9f540b moved from WaitForLaunch --> PoweringUp >2012-08-15 12:36:50,929 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (QuartzScheduler_Worker-61) START, FullListVdsCommand(vdsId = f7ea1dc6-e6a1-11e1-bc36-001a4a169777, vds=null, vmIds=[5f196943-28c0-4b92-b1b3-941b7b9f540b]), log id: 30adef2b >2012-08-15 12:36:50,950 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (QuartzScheduler_Worker-61) FINISH, FullListVdsCommand, return: [Lorg.ovirt.engine.core.vdsbroker.xmlrpc.XmlRpcStruct;@6b310904, log id: 30adef2b >2012-08-15 12:37:47,682 INFO [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-43) [3f09373] VM VM1 5f196943-28c0-4b92-b1b3-941b7b9f540b moved from PoweringUp --> Up >2012-08-15 12:37:47,702 INFO [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (QuartzScheduler_Worker-43) [3f09373] START, UpdateVdsDynamicDataVDSCommand(vdsId = f7ea1dc6-e6a1-11e1-bc36-001a4a169777, vdsDynamic=org.ovirt.engine.core.common.businessentities.VdsDynamic@be8546eb), log id: 23a9950e >2012-08-15 12:37:47,713 INFO [org.ovirt.engine.core.vdsbroker.UpdateVdsDynamicDataVDSCommand] (QuartzScheduler_Worker-43) [3f09373] FINISH, UpdateVdsDynamicDataVDSCommand, log id: 23a9950e >2012-08-15 12:37:54,891 INFO [org.ovirt.engine.core.bll.RunVmCommand] (ajp-/127.0.0.1:8009-1) Lock Acquired to object EngineLock [exclusiveLocks= key: 5f196943-28c0-4b92-b1b3-941b7b9f540b value: VM >, sharedLocks= ] >2012-08-15 12:37:54,903 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (ajp-/127.0.0.1:8009-1) START, IsValidVDSCommand(storagePoolId = 35352af8-fb85-46f7-8433-ee6540b14a47, ignoreFailoverLimit = false, compatabilityVersion = null), log id: 6876463c >2012-08-15 12:37:54,904 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (ajp-/127.0.0.1:8009-1) FINISH, IsValidVDSCommand, return: true, log id: 6876463c >2012-08-15 12:37:54,950 INFO [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp-/127.0.0.1:8009-1) START, IsVmDuringInitiatingVDSCommand(vmId = 5f196943-28c0-4b92-b1b3-941b7b9f540b), log id: 8bfe065 >2012-08-15 12:37:54,950 INFO [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp-/127.0.0.1:8009-1) FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 8bfe065 >2012-08-15 12:37:54,951 WARN [org.ovirt.engine.core.bll.RunVmCommand] (ajp-/127.0.0.1:8009-1) CanDoAction of action RunVm failed. Reasons:VAR__ACTION__RUN,VAR__TYPE__VM,ACTION_TYPE_FAILED_VM_IS_RUNNING >2012-08-15 12:37:54,951 INFO [org.ovirt.engine.core.bll.RunVmCommand] (ajp-/127.0.0.1:8009-1) Lock freed to object EngineLock [exclusiveLocks= key: 5f196943-28c0-4b92-b1b3-941b7b9f540b value: VM >, sharedLocks= ] >2012-08-15 12:38:51,810 INFO [org.ovirt.engine.core.bll.HotPlugUnplugVmNicCommand] (pool-4-thread-45) [6ec514b4] Running command: HotPlugUnplugVmNicCommand internal: false. Entities affected : ID: 5f196943-28c0-4b92-b1b3-941b7b9f540b Type: VM >2012-08-15 12:38:51,824 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HotUnplugNicVDSCommand] (pool-4-thread-45) [6ec514b4] START, HotUnplugNicVDSCommand(vdsId = f7ea1dc6-e6a1-11e1-bc36-001a4a169777), log id: 6ae21da0 >2012-08-15 12:38:52,263 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.HotUnplugNicVDSCommand] (pool-4-thread-45) [6ec514b4] FINISH, HotUnplugNicVDSCommand, log id: 6ae21da0 >2012-08-15 12:38:52,448 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (pool-4-thread-45) [6ec514b4] No string for UNASSIGNED type. Use default Log >2012-08-15 12:39:04,428 INFO [org.ovirt.engine.core.bll.RemoveVmInterfaceCommand] (pool-4-thread-50) [698e68ab] Running command: RemoveVmInterfaceCommand internal: false. Entities affected : ID: 5f196943-28c0-4b92-b1b3-941b7b9f540b Type: VM >2012-08-15 12:39:04,431 INFO [org.ovirt.engine.core.bll.MacPoolManager] (pool-4-thread-50) [698e68ab] MacPoolManager::freeMac(mac = 00:1a:4a:23:61:67) - entered >2012-08-15 12:39:09,203 INFO [org.ovirt.engine.core.bll.ShutdownVmCommand] (pool-4-thread-45) [1d2e0937] Running command: ShutdownVmCommand internal: false. Entities affected : ID: 5f196943-28c0-4b92-b1b3-941b7b9f540b Type: VM >2012-08-15 12:39:09,204 INFO [org.ovirt.engine.core.bll.ShutdownVmCommand] (pool-4-thread-45) [1d2e0937] VmHandler.cs:ShutdownVmCommand:Perform: entered (Desktop VM1). >2012-08-15 12:39:09,205 INFO [org.ovirt.engine.core.bll.ShutdownVmCommand] (pool-4-thread-45) [1d2e0937] VmHandler.cs:ShutdownVmCommand:Perform: sending shutdown command for Desktop VM1. >2012-08-15 12:39:09,206 INFO [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (pool-4-thread-45) [1d2e0937] START, DestroyVmVDSCommand(vdsId = f7ea1dc6-e6a1-11e1-bc36-001a4a169777, vmId=5f196943-28c0-4b92-b1b3-941b7b9f540b, force=false, secondsToWait=30, gracefully=true), log id: 5bba328d >2012-08-15 12:39:09,209 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (pool-4-thread-45) [1d2e0937] START, DestroyVDSCommand(vdsId = f7ea1dc6-e6a1-11e1-bc36-001a4a169777, vmId=5f196943-28c0-4b92-b1b3-941b7b9f540b, force=false, secondsToWait=30, gracefully=true), log id: 1c608223 >2012-08-15 12:39:09,277 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (pool-4-thread-45) [1d2e0937] FINISH, DestroyVDSCommand, log id: 1c608223 >2012-08-15 12:39:09,292 INFO [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (pool-4-thread-45) [1d2e0937] FINISH, DestroyVmVDSCommand, return: PoweringDown, log id: 5bba328d >2012-08-15 12:39:24,495 INFO [org.ovirt.engine.core.bll.ShutdownVmCommand] (pool-4-thread-50) [469417c7] Running command: ShutdownVmCommand internal: false. Entities affected : ID: 5f196943-28c0-4b92-b1b3-941b7b9f540b Type: VM >2012-08-15 12:39:24,497 INFO [org.ovirt.engine.core.bll.ShutdownVmCommand] (pool-4-thread-50) [469417c7] VmHandler.cs:ShutdownVmCommand:Perform: entered (Desktop VM1). >2012-08-15 12:39:24,497 INFO [org.ovirt.engine.core.bll.ShutdownVmCommand] (pool-4-thread-50) [469417c7] VmHandler.cs:ShutdownVmCommand:Perform: Cannot shutdown Desktop VM1, status is not up. Stopping instead. >2012-08-15 12:39:24,510 INFO [org.ovirt.engine.core.bll.StopVmCommand] (pool-4-thread-50) [11900d4c] Running command: StopVmCommand internal: true. Entities affected : ID: 5f196943-28c0-4b92-b1b3-941b7b9f540b Type: VM >2012-08-15 12:39:24,512 INFO [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (pool-4-thread-50) [11900d4c] START, DestroyVmVDSCommand(vdsId = f7ea1dc6-e6a1-11e1-bc36-001a4a169777, vmId=5f196943-28c0-4b92-b1b3-941b7b9f540b, force=false, secondsToWait=0, gracefully=false), log id: 7eb89a2e >2012-08-15 12:39:24,517 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (pool-4-thread-50) [11900d4c] START, DestroyVDSCommand(vdsId = f7ea1dc6-e6a1-11e1-bc36-001a4a169777, vmId=5f196943-28c0-4b92-b1b3-941b7b9f540b, force=false, secondsToWait=0, gracefully=false), log id: 23882756 >2012-08-15 12:39:24,994 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (pool-4-thread-50) [11900d4c] FINISH, DestroyVDSCommand, log id: 23882756 >2012-08-15 12:39:25,029 INFO [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (pool-4-thread-50) [11900d4c] FINISH, DestroyVmVDSCommand, return: Down, log id: 7eb89a2e >2012-08-15 12:39:41,130 INFO [org.ovirt.engine.core.bll.UpdateVmInterfaceCommand] (ajp-/127.0.0.1:8009-5) [448e69b1] Running command: UpdateVmInterfaceCommand internal: false. Entities affected : ID: 5f196943-28c0-4b92-b1b3-941b7b9f540b Type: VM >2012-08-15 12:39:45,432 INFO [org.ovirt.engine.core.bll.RunVmCommand] (ajp-/127.0.0.1:8009-6) Lock Acquired to object EngineLock [exclusiveLocks= key: 5f196943-28c0-4b92-b1b3-941b7b9f540b value: VM >, sharedLocks= ] >2012-08-15 12:39:45,445 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (ajp-/127.0.0.1:8009-6) START, IsValidVDSCommand(storagePoolId = 35352af8-fb85-46f7-8433-ee6540b14a47, ignoreFailoverLimit = false, compatabilityVersion = null), log id: c8af917 >2012-08-15 12:39:45,446 INFO [org.ovirt.engine.core.vdsbroker.irsbroker.IsValidVDSCommand] (ajp-/127.0.0.1:8009-6) FINISH, IsValidVDSCommand, return: true, log id: c8af917 >2012-08-15 12:39:45,496 INFO [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp-/127.0.0.1:8009-6) START, IsVmDuringInitiatingVDSCommand(vmId = 5f196943-28c0-4b92-b1b3-941b7b9f540b), log id: 28b9debe >2012-08-15 12:39:45,496 INFO [org.ovirt.engine.core.vdsbroker.IsVmDuringInitiatingVDSCommand] (ajp-/127.0.0.1:8009-6) FINISH, IsVmDuringInitiatingVDSCommand, return: false, log id: 28b9debe >2012-08-15 12:39:45,589 INFO [org.ovirt.engine.core.bll.RunVmCommand] (pool-4-thread-45) [6f6513cd] Running command: RunVmCommand internal: false. Entities affected : ID: 5f196943-28c0-4b92-b1b3-941b7b9f540b Type: VM >2012-08-15 12:39:45,615 INFO [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (pool-4-thread-45) [6f6513cd] START, CreateVmVDSCommand(vdsId = f7ea1dc6-e6a1-11e1-bc36-001a4a169777, vmId=5f196943-28c0-4b92-b1b3-941b7b9f540b, vm=org.ovirt.engine.core.common.businessentities.VM@5f2c9dda), log id: 7ef07f29 >2012-08-15 12:39:45,618 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (pool-4-thread-45) [6f6513cd] START, CreateVDSCommand(vdsId = f7ea1dc6-e6a1-11e1-bc36-001a4a169777, vmId=5f196943-28c0-4b92-b1b3-941b7b9f540b, vm=org.ovirt.engine.core.common.businessentities.VM@5f2c9dda), log id: 69d6e2db >2012-08-15 12:39:45,700 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (pool-4-thread-45) [6f6513cd] org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand spiceSslCipherSuite=DEFAULT,memSize=512,kvmEnable=true,smp=1,emulatedMachine=rhel6.3.0,vmType=kvm,keyboardLayout=en-us,nice=0,display=qxl,smpCoresPerSocket=1,spiceSecureChannels=smain,sinputs,timeOffset=0,transparentHugePages=true,vmId=5f196943-28c0-4b92-b1b3-941b7b9f540b,devices=[Ljava.util.Map;@78100830,acpiEnable=true,vmName=VM1,cpuType=Conroe,custom={device_de943e9f-8e02-4c63-9949-3da89905f81cdevice_134be18f-4809-4870-a8af-583767144561device_a4114789-866d-4d5e-8dc4-4c8a69d59c59=VmId=5f196943-28c0-4b92-b1b3-941b7b9f540b,DeviceId=a4114789-866d-4d5e-8dc4-4c8a69d59c59,Device=virtio-serial,Type=controller,BootOrder=0,SpecParams={},Address={bus=0x00, domain=0x0000, type=pci, slot=0x05, function=0x0},IsManaged=false,IsPlugged=true,IsReadOnly=false,alias=virtio-serial0, device_de943e9f-8e02-4c63-9949-3da89905f81c=VmId=5f196943-28c0-4b92-b1b3-941b7b9f540b,DeviceId=de943e9f-8e02-4c63-9949-3da89905f81c,Device=usb,Type=controller,BootOrder=0,SpecParams={},Address={bus=0x00, domain=0x0000, type=pci, slot=0x01, function=0x2},IsManaged=false,IsPlugged=true,IsReadOnly=false,alias=usb0, device_de943e9f-8e02-4c63-9949-3da89905f81cdevice_134be18f-4809-4870-a8af-583767144561device_a4114789-866d-4d5e-8dc4-4c8a69d59c59device_3e3e9385-991c-42bd-a2f4-965369ea7e3c=VmId=5f196943-28c0-4b92-b1b3-941b7b9f540b,DeviceId=3e3e9385-991c-42bd-a2f4-965369ea7e3c,Device=unix,Type=channel,BootOrder=0,SpecParams={},Address={port=1, bus=0, controller=0, type=virtio-serial},IsManaged=false,IsPlugged=true,IsReadOnly=false,alias=channel0, device_de943e9f-8e02-4c63-9949-3da89905f81cdevice_134be18f-4809-4870-a8af-583767144561=VmId=5f196943-28c0-4b92-b1b3-941b7b9f540b,DeviceId=134be18f-4809-4870-a8af-583767144561,Device=ide,Type=controller,BootOrder=0,SpecParams={},Address={bus=0x00, domain=0x0000, type=pci, slot=0x01, function=0x1},IsManaged=false,IsPlugged=true,IsReadOnly=false,alias=ide0, device_de943e9f-8e02-4c63-9949-3da89905f81cdevice_134be18f-4809-4870-a8af-583767144561device_a4114789-866d-4d5e-8dc4-4c8a69d59c59device_3e3e9385-991c-42bd-a2f4-965369ea7e3cdevice_3c42108c-7046-4251-b78d-896a73ae79c8=VmId=5f196943-28c0-4b92-b1b3-941b7b9f540b,DeviceId=3c42108c-7046-4251-b78d-896a73ae79c8,Device=spicevmc,Type=channel,BootOrder=0,SpecParams={},Address={port=2, bus=0, controller=0, type=virtio-serial},IsManaged=false,IsPlugged=true,IsReadOnly=false,alias=channel1} >2012-08-15 12:39:45,701 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.CreateVDSCommand] (pool-4-thread-45) [6f6513cd] FINISH, CreateVDSCommand, log id: 69d6e2db >2012-08-15 12:39:45,710 INFO [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (pool-4-thread-45) [6f6513cd] IncreasePendingVms::CreateVmIncreasing vds orchid-vds2 pending vcpu count, now 1. Vm: VM1 >2012-08-15 12:39:45,746 INFO [org.ovirt.engine.core.vdsbroker.CreateVmVDSCommand] (pool-4-thread-45) [6f6513cd] FINISH, CreateVmVDSCommand, return: WaitForLaunch, log id: 7ef07f29 >2012-08-15 12:39:45,746 INFO [org.ovirt.engine.core.bll.RunVmCommand] (pool-4-thread-45) [6f6513cd] Lock freed to object EngineLock [exclusiveLocks= key: 5f196943-28c0-4b92-b1b3-941b7b9f540b value: VM >, sharedLocks= ] >2012-08-15 12:39:47,582 INFO [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-3) [71e691f6] VM VM1 5f196943-28c0-4b92-b1b3-941b7b9f540b moved from WaitForLaunch --> PoweringUp >2012-08-15 12:39:47,585 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (QuartzScheduler_Worker-3) [71e691f6] START, FullListVdsCommand(vdsId = f7ea1dc6-e6a1-11e1-bc36-001a4a169777, vds=null, vmIds=[5f196943-28c0-4b92-b1b3-941b7b9f540b]), log id: 22d804c0 >2012-08-15 12:39:47,612 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand] (QuartzScheduler_Worker-3) [71e691f6] FINISH, FullListVdsCommand, return: [Lorg.ovirt.engine.core.vdsbroker.xmlrpc.XmlRpcStruct;@2be10a34, log id: 22d804c0 >2012-08-15 12:39:55,664 INFO [org.ovirt.engine.core.bll.HotPlugUnplugVmNicCommand] (pool-4-thread-45) [14f76aaf] Running command: HotPlugUnplugVmNicCommand internal: false. Entities affected : ID: 5f196943-28c0-4b92-b1b3-941b7b9f540b Type: VM >2012-08-15 12:39:56,033 INFO [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (pool-4-thread-45) [14f76aaf] No string for UNASSIGNED type. Use default Log >2012-08-15 12:40:00,000 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-16) Autorecovering hosts is disabled, skipping >2012-08-15 12:40:00,000 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-16) Autorecovering storage domains is disabled, skipping >2012-08-15 12:40:23,974 INFO [org.ovirt.engine.core.bll.ShutdownVmCommand] (pool-4-thread-46) [2a64c9ea] Running command: ShutdownVmCommand internal: false. Entities affected : ID: 5f196943-28c0-4b92-b1b3-941b7b9f540b Type: VM >2012-08-15 12:40:23,975 INFO [org.ovirt.engine.core.bll.ShutdownVmCommand] (pool-4-thread-46) [2a64c9ea] VmHandler.cs:ShutdownVmCommand:Perform: entered (Desktop VM1). >2012-08-15 12:40:23,975 INFO [org.ovirt.engine.core.bll.ShutdownVmCommand] (pool-4-thread-46) [2a64c9ea] VmHandler.cs:ShutdownVmCommand:Perform: Cannot shutdown Desktop VM1, status is not up. Stopping instead. >2012-08-15 12:40:23,979 INFO [org.ovirt.engine.core.bll.StopVmCommand] (pool-4-thread-46) [560154f0] Running command: StopVmCommand internal: true. Entities affected : ID: 5f196943-28c0-4b92-b1b3-941b7b9f540b Type: VM >2012-08-15 12:40:23,980 INFO [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (pool-4-thread-46) [560154f0] START, DestroyVmVDSCommand(vdsId = f7ea1dc6-e6a1-11e1-bc36-001a4a169777, vmId=5f196943-28c0-4b92-b1b3-941b7b9f540b, force=false, secondsToWait=0, gracefully=false), log id: 376e38f2 >2012-08-15 12:40:24,000 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (pool-4-thread-46) [560154f0] START, DestroyVDSCommand(vdsId = f7ea1dc6-e6a1-11e1-bc36-001a4a169777, vmId=5f196943-28c0-4b92-b1b3-941b7b9f540b, force=false, secondsToWait=0, gracefully=false), log id: 1af45964 >2012-08-15 12:40:24,436 INFO [org.ovirt.engine.core.vdsbroker.vdsbroker.DestroyVDSCommand] (pool-4-thread-46) [560154f0] FINISH, DestroyVDSCommand, log id: 1af45964 >2012-08-15 12:40:24,481 INFO [org.ovirt.engine.core.vdsbroker.DestroyVmVDSCommand] (pool-4-thread-46) [560154f0] FINISH, DestroyVmVDSCommand, return: Down, log id: 376e38f2 >2012-08-15 12:41:03,084 INFO [org.ovirt.engine.core.bll.RemoveVmTemplateInterfaceCommand] (pool-4-thread-46) [121079cb] Running command: RemoveVmTemplateInterfaceCommand internal: false. Entities affected : ID: 6a5577ae-ed54-44a7-8ad6-031d151734c8 Type: VmTemplate >2012-08-15 12:41:12,239 INFO [org.ovirt.engine.core.bll.UpdateVmTemplateInterfaceCommand] (pool-4-thread-46) [4a187209] Running command: UpdateVmTemplateInterfaceCommand internal: false. Entities affected : ID: 6a5577ae-ed54-44a7-8ad6-031d151734c8 Type: VmTemplate >2012-08-15 12:41:25,605 INFO [org.ovirt.engine.core.bll.AddVmTemplateInterfaceCommand] (pool-4-thread-46) [2c418eef] Running command: AddVmTemplateInterfaceCommand internal: false. Entities affected : ID: 6a5577ae-ed54-44a7-8ad6-031d151734c8 Type: VmTemplate >2012-08-15 12:45:00,000 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-19) Autorecovering hosts is disabled, skipping >2012-08-15 12:45:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-19) Autorecovering storage domains is disabled, skipping >2012-08-15 12:50:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-17) Autorecovering hosts is disabled, skipping >2012-08-15 12:50:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-17) Autorecovering storage domains is disabled, skipping >2012-08-15 12:55:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-19) Autorecovering hosts is disabled, skipping >2012-08-15 12:55:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-19) Autorecovering storage domains is disabled, skipping >2012-08-15 13:00:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-19) Autorecovering hosts is disabled, skipping >2012-08-15 13:00:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-19) Autorecovering storage domains is disabled, skipping >2012-08-15 13:05:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-19) Autorecovering hosts is disabled, skipping >2012-08-15 13:05:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-19) Autorecovering storage domains is disabled, skipping >2012-08-15 13:10:00,000 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-10) Autorecovering hosts is disabled, skipping >2012-08-15 13:10:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-10) Autorecovering storage domains is disabled, skipping >2012-08-15 13:15:00,004 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-10) Autorecovering hosts is disabled, skipping >2012-08-15 13:15:00,004 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-10) Autorecovering storage domains is disabled, skipping >2012-08-15 13:20:00,000 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-10) Autorecovering hosts is disabled, skipping >2012-08-15 13:20:00,000 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-10) Autorecovering storage domains is disabled, skipping >2012-08-15 13:21:04,194 INFO [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-98) DbUserCacheManager::refreshAllUserData() - entered >2012-08-15 13:21:07,557 INFO [org.ovirt.engine.core.bll.LogoutUserCommand] (ajp-/127.0.0.1:8009-1) [49ec9bf1] Running command: LogoutUserCommand internal: false. >2012-08-15 13:24:01,261 ERROR [org.ovirt.engine.core.bll.LoginAdminUserCommand] (ajp-/127.0.0.1:8009-9) USER_FAILED_TO_AUTHENTICATE : admin >2012-08-15 13:24:01,261 WARN [org.ovirt.engine.core.bll.LoginAdminUserCommand] (ajp-/127.0.0.1:8009-9) CanDoAction of action LoginAdminUser failed. Reasons:USER_FAILED_TO_AUTHENTICATE >2012-08-15 13:24:08,683 INFO [org.ovirt.engine.core.bll.LoginAdminUserCommand] (ajp-/127.0.0.1:8009-4) Checking if user admin@internal is an admin, result true >2012-08-15 13:24:08,684 INFO [org.ovirt.engine.core.bll.LoginAdminUserCommand] (ajp-/127.0.0.1:8009-4) Running command: LoginAdminUserCommand internal: false. >2012-08-15 13:24:19,409 ERROR [org.ovirt.engine.core.ServletUtils] (ajp-/127.0.0.1:8009-7) Can't read file "/usr/share/ovirt-engine/docs/en-US/DocumentationPath.csv" for request "/docs/en-US/DocumentationPath.csv", will send a 404 error response. >2012-08-15 13:24:31,150 INFO [org.ovirt.engine.core.bll.storage.AddNetworkCommand] (ajp-/127.0.0.1:8009-5) [3a09ca8c] Running command: AddNetworkCommand internal: false. Entities affected : ID: 35352af8-fb85-46f7-8433-ee6540b14a47 Type: StoragePool >2012-08-15 13:24:31,558 INFO [org.ovirt.engine.core.bll.AttachNetworkToVdsGroupCommand] (pool-4-thread-45) [179c4804] Running command: AttachNetworkToVdsGroupCommand internal: false. Entities affected : ID: d3e09392-e6a1-11e1-9a04-001a4a169777 Type: VdsGroups >2012-08-15 13:25:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-20) Autorecovering hosts is disabled, skipping >2012-08-15 13:25:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-20) Autorecovering storage domains is disabled, skipping >2012-08-15 13:25:14,257 INFO [org.ovirt.engine.core.bll.LoginAdminUserCommand] (ajp-/127.0.0.1:8009-10) Checking if user admin@internal is an admin, result true >2012-08-15 13:25:14,257 INFO [org.ovirt.engine.core.bll.LoginAdminUserCommand] (ajp-/127.0.0.1:8009-10) Running command: LoginAdminUserCommand internal: false. >2012-08-15 13:25:15,572 ERROR [org.ovirt.engine.core.ServletUtils] (ajp-/127.0.0.1:8009-1) Can't read file "/usr/share/ovirt-engine/docs/en-US/DocumentationPath.csv" for request "/docs/en-US/DocumentationPath.csv", will send a 404 error response. >2012-08-15 13:30:00,000 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-20) Autorecovering hosts is disabled, skipping >2012-08-15 13:30:00,000 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-20) Autorecovering storage domains is disabled, skipping >2012-08-15 13:35:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-16) Autorecovering hosts is disabled, skipping >2012-08-15 13:35:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-16) Autorecovering storage domains is disabled, skipping >2012-08-15 13:40:00,000 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-21) Autorecovering hosts is disabled, skipping >2012-08-15 13:40:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-21) Autorecovering storage domains is disabled, skipping >2012-08-15 13:45:00,000 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-23) Autorecovering hosts is disabled, skipping >2012-08-15 13:45:00,000 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-23) Autorecovering storage domains is disabled, skipping >2012-08-15 13:50:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-23) Autorecovering hosts is disabled, skipping >2012-08-15 13:50:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-23) Autorecovering storage domains is disabled, skipping >2012-08-15 13:55:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-23) Autorecovering hosts is disabled, skipping >2012-08-15 13:55:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-23) Autorecovering storage domains is disabled, skipping >2012-08-15 14:00:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-23) Autorecovering hosts is disabled, skipping >2012-08-15 14:00:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-23) Autorecovering storage domains is disabled, skipping >2012-08-15 14:05:00,000 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-26) Autorecovering hosts is disabled, skipping >2012-08-15 14:05:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-26) Autorecovering storage domains is disabled, skipping >2012-08-15 14:10:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-17) Autorecovering hosts is disabled, skipping >2012-08-15 14:10:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-17) Autorecovering storage domains is disabled, skipping >2012-08-15 14:15:00,000 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-28) Autorecovering hosts is disabled, skipping >2012-08-15 14:15:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-28) Autorecovering storage domains is disabled, skipping >2012-08-15 14:20:00,002 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-5) Autorecovering hosts is disabled, skipping >2012-08-15 14:20:00,002 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-5) Autorecovering storage domains is disabled, skipping >2012-08-15 14:21:04,197 INFO [org.ovirt.engine.core.bll.DbUserCacheManager] (QuartzScheduler_Worker-10) DbUserCacheManager::refreshAllUserData() - entered >2012-08-15 14:21:05,515 INFO [org.ovirt.engine.core.bll.LogoutUserCommand] (ajp-/127.0.0.1:8009-8) [7c5d2e9e] Running command: LogoutUserCommand internal: false. >2012-08-15 14:25:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-22) Autorecovering hosts is disabled, skipping >2012-08-15 14:25:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-22) Autorecovering storage domains is disabled, skipping >2012-08-15 14:30:00,004 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-32) Autorecovering hosts is disabled, skipping >2012-08-15 14:30:00,004 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-32) Autorecovering storage domains is disabled, skipping >2012-08-15 14:35:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-32) Autorecovering hosts is disabled, skipping >2012-08-15 14:35:00,001 INFO [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-32) Autorecovering storage domains is disabled, skipping
<b>You cannot view the attachment while viewing its details because your browser does not support IFRAMEs. <a href="attachment.cgi?id=604581">View the attachment on a separate page</a>.</b>
View Attachment As Raw
Actions:
View
Attachments on
bug 848362
: 604581 |
604584