Attachment 615408 Details for Bug 849315 – attached engine.log

attached engine.log

engine.log (text/x-log), 26.53 KB, created by haiyang,dong on 2012-09-21 12:05:08 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: haiyang,dong

Created: 2012-09-21 12:05:08 UTC

Size: 26.53 KB

patch

obsolete

> 
>2012-09-21 19:36:02,519 INFO  [org.ovirt.engine.core.bll.storage.AddEmptyStoragePoolCommand] (ajp-/127.0.0.1:8009-2) [7353a077] Running command: AddEmptyStoragePoolCommand internal: false. Entities affected :  ID: aaa00000-0000-0000-0000-123456789aaa Type: System
>2012-09-21 19:36:12,349 INFO  [org.ovirt.engine.core.bll.AddVdsGroupCommand] (ajp-/127.0.0.1:8009-5) [27028310] Running command: AddVdsGroupCommand internal: false. Entities affected :  ID: d429559c-20ea-4f1f-a17f-eae80ee9ebbe Type: StoragePool
>2012-09-21 19:38:14,041 INFO  [org.ovirt.engine.core.bll.RegisterVdsQuery] (ajp-/127.0.0.1:8009-6) Running Command: RegisterVds
>2012-09-21 19:38:14,053 WARN  [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (ajp-/127.0.0.1:8009-6) Adding new value to configuration cache.
>2012-09-21 19:38:14,053 WARN  [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (ajp-/127.0.0.1:8009-6) Didnt find the value of LogVdsRegistration in DB for version general - using default: false
>2012-09-21 19:38:14,344 INFO  [org.ovirt.engine.core.bll.AddVdsCommand] (pool-4-thread-3) [1d7bea56] Running command: AddVdsCommand internal: true. Entities affected :  ID: 99408929-82cf-4dc7-a532-9d998063fa95 Type: VdsGroups
>2012-09-21 19:38:14,535 INFO  [org.ovirt.engine.core.bll.AddVdsSpmIdCommand] (pool-4-thread-3) [141e8da6] Running command: AddVdsSpmIdCommand internal: true. Entities affected :  ID: d447ad5a-03e0-11e2-8978-001a4a420b02 Type: VDS
>2012-09-21 19:38:14,566 INFO  [org.ovirt.engine.core.bll.AddVdsSpmIdCommand] (pool-4-thread-3) [141e8da6] Lock freed to object EngineLock [exclusiveLocks= key: c2aa89b6-03de-11e2-8b12-001a4a420b02 value: REGISTER_VDS
>, sharedLocks= ]
>2012-09-21 19:38:14,581 ERROR [org.ovirt.engine.core.vdsbroker.ResourceManager] (pool-4-thread-3) [141e8da6] Cannot get vdsManager for vdsid=d447ad5a-03e0-11e2-8978-001a4a420b02
>2012-09-21 19:38:14,585 INFO  [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (pool-4-thread-3) [141e8da6] START, RemoveVdsVDSCommand(vdsId = d447ad5a-03e0-11e2-8978-001a4a420b02), log id: 72ed7a50
>2012-09-21 19:38:14,586 ERROR [org.ovirt.engine.core.vdsbroker.ResourceManager] (pool-4-thread-3) [141e8da6] Cannot get vdsManager for vdsid=d447ad5a-03e0-11e2-8978-001a4a420b02
>2012-09-21 19:38:14,586 INFO  [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (pool-4-thread-3) [141e8da6] FINISH, RemoveVdsVDSCommand, log id: 72ed7a50
>2012-09-21 19:38:14,588 ERROR [org.ovirt.engine.core.vdsbroker.ResourceManager] (pool-4-thread-3) [141e8da6] Cannot get vdsManager for vdsid=d447ad5a-03e0-11e2-8978-001a4a420b02
>2012-09-21 19:38:14,588 INFO  [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (pool-4-thread-3) [141e8da6] START, AddVdsVDSCommand(vdsId = d447ad5a-03e0-11e2-8978-001a4a420b02), log id: 5575ed44
>2012-09-21 19:38:14,588 INFO  [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (pool-4-thread-3) [141e8da6] AddVds - entered , starting logic to add VDS d447ad5a-03e0-11e2-8978-001a4a420b02
>2012-09-21 19:38:14,602 INFO  [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (pool-4-thread-3) [141e8da6] AddVds - VDS d447ad5a-03e0-11e2-8978-001a4a420b02 was added, will try to add it to the resource manager
>2012-09-21 19:38:14,613 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (pool-4-thread-3) [141e8da6] Eneterd VdsManager:constructor
>2012-09-21 19:38:14,613 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (pool-4-thread-3) [141e8da6] vdsBroker(10.66.11.139,54,321)
>2012-09-21 19:38:14,874 INFO  [org.ovirt.engine.core.vdsbroker.ResourceManager] (pool-4-thread-3) [141e8da6] ResourceManager::AddVds - VDS d447ad5a-03e0-11e2-8978-001a4a420b02 was added to the Resource Manager
>2012-09-21 19:38:14,875 INFO  [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (pool-4-thread-3) [141e8da6] FINISH, AddVdsVDSCommand, log id: 5575ed44
>2012-09-21 19:38:14,917 INFO  [org.ovirt.engine.core.bll.RegisterVdsQuery] (ajp-/127.0.0.1:8009-6) RegisterVdsQuery::Register - Registered a new VDS pending approval - Name: localhost.localdomain, Hostname: 10.66.11.139, UniqueID: 44454C4C-5000-1039-8051-CAC04F4C3258_00:23:ae:77:13:4b
>2012-09-21 19:38:14,957 INFO  [org.ovirt.engine.core.register.RegisterServlet] (ajp-/127.0.0.1:8009-6) Succeeded to run RegisterVds.
>2012-09-21 19:38:42,970 INFO  [org.ovirt.engine.core.bll.ChangeVDSClusterCommand] (ajp-/127.0.0.1:8009-4) [2d1a6e6e] Running command: ChangeVDSClusterCommand internal: false. Entities affected :  ID: d447ad5a-03e0-11e2-8978-001a4a420b02 Type: VDS,  ID: 8b90a7ba-03e0-11e2-b35f-001a4a420b02 Type: VdsGroups
>2012-09-21 19:38:43,063 INFO  [org.ovirt.engine.core.bll.RemoveVdsSpmIdCommand] (ajp-/127.0.0.1:8009-4) [2d1a6e6e] Running command: RemoveVdsSpmIdCommand internal: true. Entities affected :  ID: d447ad5a-03e0-11e2-8978-001a4a420b02 Type: VDS
>2012-09-21 19:38:43,091 INFO  [org.ovirt.engine.core.bll.AddVdsSpmIdCommand] (ajp-/127.0.0.1:8009-4) [2d1a6e6e] Running command: AddVdsSpmIdCommand internal: true. Entities affected :  ID: d447ad5a-03e0-11e2-8978-001a4a420b02 Type: VDS
>2012-09-21 19:38:43,113 INFO  [org.ovirt.engine.core.bll.AddVdsSpmIdCommand] (ajp-/127.0.0.1:8009-4) [2d1a6e6e] Lock freed to object EngineLock [exclusiveLocks= key: d429559c-20ea-4f1f-a17f-eae80ee9ebbe value: REGISTER_VDS
>, sharedLocks= ]
>2012-09-21 19:38:43,215 INFO  [org.ovirt.engine.core.bll.UpdateVdsCommand] (ajp-/127.0.0.1:8009-9) [292adeaf] Running command: UpdateVdsCommand internal: false. Entities affected :  ID: d447ad5a-03e0-11e2-8978-001a4a420b02 Type: VDS
>2012-09-21 19:38:43,427 INFO  [org.ovirt.engine.core.bll.ApproveVdsCommand] (pool-4-thread-4) [3a722cda] Running command: ApproveVdsCommand internal: false. Entities affected :  ID: d447ad5a-03e0-11e2-8978-001a4a420b02 Type: VDS
>2012-09-21 19:38:43,428 INFO  [org.ovirt.engine.core.bll.InstallVdsCommand] (pool-4-thread-4) [3a722cda] Before Installation pool-4-thread-4, Powerclient/oVirtNode case: setting status to installing
>2012-09-21 19:38:43,434 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-4-thread-4) [3a722cda] START, SetVdsStatusVDSCommand(vdsId = d447ad5a-03e0-11e2-8978-001a4a420b02, status=Installing, nonOperationalReason=NONE), log id: 5cc5751b
>2012-09-21 19:38:43,472 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-4-thread-4) [3a722cda] FINISH, SetVdsStatusVDSCommand, log id: 5cc5751b
>2012-09-21 19:38:43,487 WARN  [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (pool-4-thread-4) [3a722cda] Adding new value to configuration cache.
>2012-09-21 19:38:43,488 WARN  [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (pool-4-thread-4) [3a722cda] Didnt find the value of BootstrapCommand in DB for version general - using default: umask 0077; MYTMP="$(mktemp -t ovirt-XXXXXXXXXX)"; trap "chmod -R u+rwX \"${MYTMP}\" > /dev/null 2>&1; rm -fr \"${MYTMP}\" > /dev/null 2>&1" 0; rm -fr "${MYTMP}" && mkdir "${MYTMP}" && tar -C "${MYTMP}" --no-same-permissions -o -x && "${MYTMP}"/setup -c 'ssl={server_SSL_enabled};management_port={management_port}' -O '{OrganizationName}' -t {utc_time} {OverrideFirewall} -S {SSHKey} {EnginePort} -b {virt-placeholder} {gluster-placeholder} {URL1} {URL1} {vds-server} {GUID} {RunFlag}
>2012-09-21 19:38:43,490 WARN  [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (pool-4-thread-4) [3a722cda] Adding new value to configuration cache.
>2012-09-21 19:38:43,490 WARN  [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (pool-4-thread-4) [3a722cda] Didnt find the value of BootstrapPackageName in DB for version general - using default: vdsm-bootstrap-2.tar
>2012-09-21 19:38:43,490 WARN  [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (pool-4-thread-4) [3a722cda] Adding new value to configuration cache.
>2012-09-21 19:38:43,491 WARN  [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (pool-4-thread-4) [3a722cda] Didnt find the value of BootstrapPackageDirectory in DB for version general - using default: /usr/share/vdsm-bootstrap/interface-2
>2012-09-21 19:38:43,491 WARN  [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (pool-4-thread-4) [3a722cda] Adding new value to configuration cache.
>2012-09-21 19:38:43,491 WARN  [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (pool-4-thread-4) [3a722cda] Didnt find the value of BootstrapCacheRefreshInterval in DB for version general - using default: 10,000
>2012-09-21 19:38:43,505 INFO  [org.ovirt.engine.core.bll.InstallVdsCommand] (pool-4-thread-4) [3a722cda] Before Installation pool-4-thread-4
>2012-09-21 19:38:43,506 INFO  [org.ovirt.engine.core.bll.OVirtInstaller] (pool-4-thread-4) [3a722cda] Installation of 10.66.11.139. Executing oVirt installation stage. (Stage: Starting Host installation)
>2012-09-21 19:38:43,507 INFO  [org.ovirt.engine.core.bll.OVirtInstaller] (pool-4-thread-4) [3a722cda] Installation of 10.66.11.139. Executing oVirt installation stage. (Stage: Connecting to Host)
>2012-09-21 19:38:43,872 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [3a722cda] Installation of 10.66.11.139. Received message: <BSTRAP component='RHEV_INSTALL' status='OK' message='Connected to Host 10.66.11.139 with SSH key fingerprint: 82:8c:77:72:9a:5e:19:d0:d5:e9:95:9e:84:87:94:ec'/>. FYI. (Stage: Connecting to Host)
>2012-09-21 19:38:44,307 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [3a722cda] Installation of 10.66.11.139. Successfully connected to server ssh. (Stage: Connecting to Host)
>2012-09-21 19:38:44,307 INFO  [org.ovirt.engine.core.bll.OVirtInstaller] (pool-4-thread-4) [3a722cda] Installation of 10.66.11.139. Executing oVirt installation stage. (Stage: Running first installation script on Host)
>2012-09-21 19:38:44,308 INFO  [org.ovirt.engine.core.utils.hostinstall.VdsInstallerSSH] (pool-4-thread-4) SSH execute 10.66.11.139 '/usr/share/vdsm-reg/vdsm-gen-cert -O "rhevm.englab.nay.redhat.com" 10.66.11.139 1895781e-7adb-4eee-98b6-061af6948dac'
>2012-09-21 19:38:44,970 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (QuartzScheduler_Worker-27) Initializing Host: localhost.localdomain
>2012-09-21 19:38:47,519 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (NioProcessor-2) Installation of 10.66.11.139. Received message: <BSTRAP component='Encryption setup' status='OK'/>. FYI. (Stage: Running first installation script on Host)
>2012-09-21 19:38:47,540 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (NioProcessor-2) Installation of 10.66.11.139. Received message: <BSTRAP component='RHEV_INSTALL' status='OK'/>. Stage completed. (Stage: Running first installation script on Host)
>2012-09-21 19:38:47,555 INFO  [org.ovirt.engine.core.bll.OVirtInstaller] (pool-4-thread-4) [3a722cda] Installation of 10.66.11.139. Executing oVirt installation stage. (Stage: Downloading certificate request from Host)
>2012-09-21 19:38:47,555 INFO  [org.ovirt.engine.core.utils.hostinstall.VdsInstallerSSH] (pool-4-thread-4) SSH receive 10.66.11.139:'/tmp/cert_1895781e-7adb-4eee-98b6-061af6948dac.req' '/etc/pki/ovirt-engine/requests/cert_1895781e-7adb-4eee-98b6-061af6948dac.req')
>2012-09-21 19:38:47,582 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [3a722cda] Installation of 10.66.11.139. successfully done sftp operation ( Stage: Downloading certificate request from Host)
>2012-09-21 19:38:47,582 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [3a722cda]  DownloadCertificateRequest ended:true
>2012-09-21 19:38:47,583 INFO  [org.ovirt.engine.core.bll.OVirtInstaller] (pool-4-thread-4) [3a722cda] Installation of 10.66.11.139. Executing oVirt installation stage. (Stage: Sign certificate request and generate certificate)
>2012-09-21 19:38:48,605 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [3a722cda]  SignCertificateRequest ended:true
>2012-09-21 19:38:48,605 INFO  [org.ovirt.engine.core.bll.OVirtInstaller] (pool-4-thread-4) [3a722cda] Installation of 10.66.11.139. Executing oVirt installation stage. (Stage: Upload signed sertificate to Host)
>2012-09-21 19:38:48,627 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [3a722cda] Installation of 10.66.11.139. successfully done sftp operation ( Stage: Upload signed sertificate to Host)
>2012-09-21 19:38:48,627 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [3a722cda]  UploadSignedCertificate ended:true
>2012-09-21 19:38:48,628 INFO  [org.ovirt.engine.core.bll.OVirtInstaller] (pool-4-thread-4) [3a722cda] Installation of 10.66.11.139. Executing oVirt installation stage. (Stage: Upload Cerficate Autority to Host)
>2012-09-21 19:38:48,679 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-4) [3a722cda] Installation of 10.66.11.139. successfully done sftp operation ( Stage: Upload Cerficate Autority to Host)
>2012-09-21 19:38:48,679 INFO  [org.ovirt.engine.core.bll.OVirtInstaller] (pool-4-thread-4) [3a722cda] Installation of 10.66.11.139. Executing oVirt installation stage, sending SSH Command /usr/share/vdsm-reg/vdsm-complete -c 'ssl=true' 1895781e-7adb-4eee-98b6-061af6948dac 0. (Stage: Running second installation script on Host)
>2012-09-21 19:38:48,680 INFO  [org.ovirt.engine.core.utils.hostinstall.VdsInstallerSSH] (pool-4-thread-4) SSH execute 10.66.11.139 '/usr/share/vdsm-reg/vdsm-complete -c 'ssl=true' 1895781e-7adb-4eee-98b6-061af6948dac 0'
>2012-09-21 19:38:51,112 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (NioProcessor-2) Installation of 10.66.11.139. Received message: <BSTRAP component='instCert' status='OK'/>. FYI. (Stage: Running second installation script on Host)
>2012-09-21 19:38:51,129 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (NioProcessor-2) Installation of 10.66.11.139. Received message: <BSTRAP component='CoreDump' status='OK'/>. FYI. (Stage: Running second installation script on Host)
>2012-09-21 19:38:51,145 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (NioProcessor-2) Installation of 10.66.11.139. Received message: <BSTRAP component='cleanAll' status='OK'/>. FYI. (Stage: Running second installation script on Host)
>2012-09-21 19:38:51,159 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (NioProcessor-2) Installation of 10.66.11.139. Received message: <BSTRAP component='VDS Configuration' status='OK'/>. FYI. (Stage: Running second installation script on Host)
>2012-09-21 19:38:51,192 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (NioProcessor-2) Installation of 10.66.11.139. Received message: <BSTRAP component='Restart' status='OK' message='Restarting vdsmd service' />. FYI. (Stage: Running second installation script on Host)
>2012-09-21 19:38:51,205 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (NioProcessor-2) Installation of 10.66.11.139. Received message: <BSTRAP component='RHEV_INSTALL' status='OK'/>. Stage completed. (Stage: Running second installation script on Host)
>2012-09-21 19:38:51,226 INFO  [org.ovirt.engine.core.bll.InstallVdsCommand] (pool-4-thread-4) [3a722cda] After Installation pool-4-thread-4
>2012-09-21 19:38:51,229 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-4-thread-4) [3a722cda] START, SetVdsStatusVDSCommand(vdsId = d447ad5a-03e0-11e2-8978-001a4a420b02, status=NonResponsive, nonOperationalReason=NONE), log id: a6f371a
>2012-09-21 19:38:51,252 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-4-thread-4) [3a722cda] FINISH, SetVdsStatusVDSCommand, log id: a6f371a
>2012-09-21 19:38:51,253 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-4-thread-4) [3a722cda] START, SetVdsStatusVDSCommand(vdsId = d447ad5a-03e0-11e2-8978-001a4a420b02, status=Unassigned, nonOperationalReason=NONE), log id: 7309341d
>2012-09-21 19:38:51,271 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-4-thread-4) [3a722cda] FINISH, SetVdsStatusVDSCommand, log id: 7309341d
>2012-09-21 19:38:59,973 INFO  [org.ovirt.engine.core.bll.InitVdsOnUpCommand] (QuartzScheduler_Worker-33) [126527b2] Running command: InitVdsOnUpCommand internal: true.
>2012-09-21 19:39:00,009 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (QuartzScheduler_Worker-33) [126527b2] No string for UNASSIGNED type. Use default Log
>2012-09-21 19:39:00,035 INFO  [org.ovirt.engine.core.bll.MultipleActionsRunner] (pool-4-thread-8) [126527b2] MultipleActionsRunner of type MigrateVmToServer invoked with no actions
>2012-09-21 19:39:00,098 INFO  [org.ovirt.engine.core.bll.HandleVdsCpuFlagsOrClusterChangedCommand] (QuartzScheduler_Worker-33) [52164e77] Running command: HandleVdsCpuFlagsOrClusterChangedCommand internal: true. Entities affected :  ID: d447ad5a-03e0-11e2-8978-001a4a420b02 Type: VDS
>2012-09-21 19:39:00,120 INFO  [org.ovirt.engine.core.bll.HandleVdsVersionCommand] (QuartzScheduler_Worker-33) [12fc56cf] Running command: HandleVdsVersionCommand internal: true. Entities affected :  ID: d447ad5a-03e0-11e2-8978-001a4a420b02 Type: VDS
>2012-09-21 19:40:00,002 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-82) Autorecovering hosts is disabled, skipping
>2012-09-21 19:40:00,003 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-82) Autorecovering storage domains is disabled, skipping
>2012-09-21 19:42:59,718 INFO  [org.ovirt.engine.core.bll.MaintananceNumberOfVdssCommand] (pool-4-thread-50) [598561f7] Running command: MaintananceNumberOfVdssCommand internal: false. Entities affected :  ID: d447ad5a-03e0-11e2-8978-001a4a420b02 Type: VDS
>2012-09-21 19:42:59,722 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-4-thread-50) [598561f7] START, SetVdsStatusVDSCommand(vdsId = d447ad5a-03e0-11e2-8978-001a4a420b02, status=PreparingForMaintenance, nonOperationalReason=NONE), log id: 13e7e272
>2012-09-21 19:42:59,744 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-4-thread-50) [598561f7] FINISH, SetVdsStatusVDSCommand, log id: 13e7e272
>2012-09-21 19:42:59,796 INFO  [org.ovirt.engine.core.bll.MaintananceVdsCommand] (pool-4-thread-50) [598561f7] Running command: MaintananceVdsCommand internal: true. Entities affected :  ID: d447ad5a-03e0-11e2-8978-001a4a420b02 Type: VDS
>2012-09-21 19:42:59,831 WARN  [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (pool-4-thread-50) [598561f7] Adding new value to configuration cache.
>2012-09-21 19:42:59,831 WARN  [org.ovirt.engine.core.dal.dbbroker.generic.DBConfigUtils] (pool-4-thread-50) [598561f7] Didnt find the value of DBI18NPrefix in DB for version general - using default: 
>2012-09-21 19:43:00,430 INFO  [org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo] (QuartzScheduler_Worker-16) vds::Updated vds status from Preparing for Maintenance to Maintenance in database,  vds = d447ad5a-03e0-11e2-8978-001a4a420b02 : localhost.localdomain
>2012-09-21 19:43:12,165 INFO  [org.ovirt.engine.core.bll.UpdateVdsCommand] (ajp-/127.0.0.1:8009-1) [49ac0690] Running command: UpdateVdsCommand internal: false. Entities affected :  ID: d447ad5a-03e0-11e2-8978-001a4a420b02 Type: VDS
>2012-09-21 19:43:12,189 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (ajp-/127.0.0.1:8009-1) [49ac0690] START, SetVdsStatusVDSCommand(vdsId = d447ad5a-03e0-11e2-8978-001a4a420b02, status=Installing, nonOperationalReason=NONE), log id: 3362f6eb
>2012-09-21 19:43:12,193 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (ajp-/127.0.0.1:8009-1) [49ac0690] FINISH, SetVdsStatusVDSCommand, log id: 3362f6eb
>2012-09-21 19:43:12,200 INFO  [org.ovirt.engine.core.bll.InstallVdsCommand] (pool-4-thread-50) [3b8de058] Running command: InstallVdsCommand internal: true. Entities affected :  ID: d447ad5a-03e0-11e2-8978-001a4a420b02 Type: VDS
>2012-09-21 19:43:12,201 INFO  [org.ovirt.engine.core.bll.InstallVdsCommand] (pool-4-thread-50) [3b8de058] Before Installation pool-4-thread-50, Powerclient/oVirtNode case: setting status to installing
>2012-09-21 19:43:12,201 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-4-thread-50) [3b8de058] START, SetVdsStatusVDSCommand(vdsId = d447ad5a-03e0-11e2-8978-001a4a420b02, status=Installing, nonOperationalReason=NONE), log id: 4d8fdec7
>2012-09-21 19:43:12,220 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-4-thread-50) [3b8de058] FINISH, SetVdsStatusVDSCommand, log id: 4d8fdec7
>2012-09-21 19:43:12,221 INFO  [org.ovirt.engine.core.bll.InstallVdsCommand] (pool-4-thread-50) [3b8de058] Before Installation pool-4-thread-50
>2012-09-21 19:43:12,222 INFO  [org.ovirt.engine.core.bll.OVirtUpgrader] (pool-4-thread-50) [3b8de058] Installation of 10.66.11.139. Executing oVirt reinstall/upgrade stage. (Stage: Starting Host installation)
>2012-09-21 19:43:12,222 INFO  [org.ovirt.engine.core.bll.OVirtUpgrader] (pool-4-thread-50) [3b8de058] Installation of 10.66.11.139. Executing oVirt reinstall/upgrade stage. (Stage: Connecting to Host)
>2012-09-21 19:43:12,302 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-50) [3b8de058] Installation of 10.66.11.139. Received message: <BSTRAP component='RHEV_INSTALL' status='OK' message='Connected to Host 10.66.11.139 with SSH key fingerprint: 82:8c:77:72:9a:5e:19:d0:d5:e9:95:9e:84:87:94:ec'/>. FYI. (Stage: Connecting to Host)
>2012-09-21 19:43:12,950 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-50) [3b8de058] Installation of 10.66.11.139. Successfully connected to server ssh. (Stage: Connecting to Host)
>2012-09-21 19:43:12,950 INFO  [org.ovirt.engine.core.bll.OVirtUpgrader] (pool-4-thread-50) [3b8de058] Installation of 10.66.11.139. Executing oVirt reinstall/upgrade stage. (Stage: Upload upgrade ISO to oVirt Node)
>2012-09-21 19:43:55,062 INFO  [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-50) [3b8de058] Installation of 10.66.11.139. successfully done sftp operation ( Stage: Upload upgrade ISO to oVirt Node)
>2012-09-21 19:43:55,062 INFO  [org.ovirt.engine.core.bll.OVirtUpgrader] (pool-4-thread-50) [3b8de058] Installation of 10.66.11.139. Executing oVirt reinstall/upgrade stage. (Stage: Running upgrade / reinstall script on oVirt Node)
>2012-09-21 19:43:55,062 INFO  [org.ovirt.engine.core.utils.hostinstall.VdsInstallerSSH] (pool-4-thread-50) SSH execute 10.66.11.139 '/usr/share/vdsm-reg/vdsm-upgrade'
>2012-09-21 19:45:00,001 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-7) Autorecovering hosts is disabled, skipping
>2012-09-21 19:45:00,001 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-7) Autorecovering storage domains is disabled, skipping
>2012-09-21 19:45:47,517 ERROR [org.ovirt.engine.core.bll.RegisterVdsQuery] (ajp-/127.0.0.1:8009-10) Query execution failed due to invalid inputs. VDS_STATUS_NOT_VALID_FOR_UPDATE
>2012-09-21 19:45:47,519 INFO  [org.ovirt.engine.core.register.RegisterServlet] (ajp-/127.0.0.1:8009-10) Succeeded to run RegisterVds.
>2012-09-21 19:50:00,001 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-35) Autorecovering hosts is disabled, skipping
>2012-09-21 19:50:00,001 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-35) Autorecovering storage domains is disabled, skipping
>2012-09-21 19:53:55,065 ERROR [org.ovirt.engine.core.utils.hostinstall.VdsInstallerSSH] (pool-4-thread-50) SSH error running command 10.66.11.139:'/usr/share/vdsm-reg/vdsm-upgrade': javax.naming.TimeLimitExceededException: SSH session hard timeout host '10.66.11.139:22'
>	at org.ovirt.engine.core.utils.ssh.SSHClient.executeCommand(SSHClient.java:402) [engine-utils.jar:]
>	at org.ovirt.engine.core.utils.hostinstall.VdsInstallerSSH.executeCommand(VdsInstallerSSH.java:387) [engine-utils.jar:]
>	at org.ovirt.engine.core.utils.hostinstall.VdsInstallerSSH.executeCommand(VdsInstallerSSH.java:426) [engine-utils.jar:]
>	at org.ovirt.engine.core.bll.OVirtUpgrader.RunStage(OVirtUpgrader.java:54) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.VdsInstaller.Install(VdsInstaller.java:275) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.InstallVdsCommand.executeCommand(InstallVdsCommand.java:110) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.CommandBase.ExecuteWithoutTransaction(CommandBase.java:819) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.CommandBase.executeActionInTransactionScope(CommandBase.java:910) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.CommandBase.runInTransaction(CommandBase.java:1269) [engine-bll.jar:]
>	at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInSuppressed(TransactionSupport.java:168) [engine-utils.jar:]
>	at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInScope(TransactionSupport.java:107) [engine-utils.jar:]
>	at org.ovirt.engine.core.bll.CommandBase.Execute(CommandBase.java:925) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.CommandBase.ExecuteAction(CommandBase.java:275) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.MultipleActionsRunner.executeValidatedCommands(MultipleActionsRunner.java:182) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.MultipleActionsRunner.RunCommands(MultipleActionsRunner.java:162) [engine-bll.jar:]
>	at org.ovirt.engine.core.bll.MultipleActionsRunner$1.run(MultipleActionsRunner.java:84) [engine-bll.jar:]
>	at org.ovirt.engine.core.utils.threadpool.ThreadPoolUtil$InternalWrapperRunnable.run(ThreadPoolUtil.java:64) [engine-utils.jar:]
>	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [rt.jar:1.7.0_05-icedtea]
>	at java.util.concurrent.FutureTask$Sync.innerRun(FutureTask.java:334) [rt.jar:1.7.0_05-icedtea]
>	at java.util.concurrent.FutureTask.run(FutureTask.java:166) [rt.jar:1.7.0_05-icedtea]
>	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1110) [rt.jar:1.7.0_05-icedtea]
>	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:603) [rt.jar:1.7.0_05-icedtea]
>	at java.lang.Thread.run(Thread.java:722) [rt.jar:1.7.0_05-icedtea]
>
>2012-09-21 19:53:55,069 ERROR [org.ovirt.engine.core.bll.VdsInstaller] (pool-4-thread-50) [3b8de058] Installation of 10.66.11.139 has failed. Failure details: SSH command failed while executing at host '10.66.11.139', refer to logs for further information. (Stage: Running upgrade / reinstall script on oVirt Node)
>2012-09-21 19:53:55,069 INFO  [org.ovirt.engine.core.bll.InstallerMessages] (pool-4-thread-50) [3b8de058] VDS message: SSH command failed while executing at host '10.66.11.139', refer to logs for further information
>2012-09-21 19:53:55,072 INFO  [org.ovirt.engine.core.bll.InstallVdsCommand] (pool-4-thread-50) [3b8de058] After Installation pool-4-thread-50
>2012-09-21 19:53:55,072 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-4-thread-50) [3b8de058] START, SetVdsStatusVDSCommand(vdsId = d447ad5a-03e0-11e2-8978-001a4a420b02, status=InstallFailed, nonOperationalReason=NONE), log id: 19a6ebef
>2012-09-21 19:53:55,085 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-4-thread-50) [3b8de058] FINISH, SetVdsStatusVDSCommand, log id: 19a6ebef
>2012-09-21 19:55:00,003 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-63) Autorecovering hosts is disabled, skipping
>2012-09-21 19:55:00,004 INFO  [org.ovirt.engine.core.bll.AutoRecoveryManager] (QuartzScheduler_Worker-63) Autorecovering storage domains is disabled, skipping

Actions: View

Attachments on bug 849315: 615408