Attachment 934703 Details for Bug 1128033 – attached engine.log

attached engine.log

engine.log (text/plain), 32.48 KB, created by haiyang,dong on 2014-09-05 08:41:08 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: haiyang,dong

Created: 2014-09-05 08:41:08 UTC

Size: 32.48 KB

patch

obsolete

>
>2014-09-05 16:00:14,705 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-6-thread-26) [5ed046b9] START, SetVdsStatusVDSCommand(HostName = localhost, HostId = 392e5448-1406-4ad4-95d7-cd84171cce24, status=InstallFailed, nonOperationalReason=NONE, stopSpmFailureLogged=false), log id: 6dcfe912
>2014-09-05 16:00:14,746 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-6-thread-26) [5ed046b9] FINISH, SetVdsStatusVDSCommand, log id: 6dcfe912
>2014-09-05 16:00:14,771 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-26) [5ed046b9] Correlation ID: 5ed046b9, Job ID: 7c8f76df-042a-414f-a2d2-81d0901d4fda, Call Stack: null, Custom Event ID: -1, Message: Host localhost installation failed. Command returned failure code 1 during SSH session 'root@10.66.11.7'.
>2014-09-05 16:05:02,230 INFO  [org.ovirt.engine.core.services.RegisterServlet] (ajp-/127.0.0.1:8702-4) Registration request: source='10.66.9.232', secured='true', address='10.66.9.232', vdsPort=54321, name='cshao-test', uniqueId='3B1FA7EC-BFD0-DE11-BBD8-217FB7190024_00:24:21:7f:b7:19'
>2014-09-05 16:05:02,233 INFO  [org.ovirt.engine.core.bll.RegisterVdsQuery] (ajp-/127.0.0.1:8702-4) Running Command: RegisterVds
>2014-09-05 16:05:02,270 INFO  [org.ovirt.engine.core.bll.AddVdsCommand] (ajp-/127.0.0.1:8702-4) [371d94fd] Running command: AddVdsCommand internal: true. Entities affected :  ID: 00000001-0001-0001-0001-00000000035b Type: VdsGroupsAction group CREATE_HOST with role type ADMIN
>2014-09-05 16:05:02,293 INFO  [org.ovirt.engine.core.bll.AddVdsSpmIdCommand] (ajp-/127.0.0.1:8702-4) [566d6fbd] Running command: AddVdsSpmIdCommand internal: true. Entities affected :  ID: c56bb1d3-57ab-4494-9b75-54e5af1d4d16 Type: VDS
>2014-09-05 16:05:02,307 INFO  [org.ovirt.engine.core.bll.AddVdsSpmIdCommand] (ajp-/127.0.0.1:8702-4) [566d6fbd] Lock freed to object EngineLock [exclusiveLocks= key: 00000002-0002-0002-0002-000000000187 value: REGISTER_VDS
>, sharedLocks= ]
>2014-09-05 16:05:02,309 INFO  [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (ajp-/127.0.0.1:8702-4) [566d6fbd] START, RemoveVdsVDSCommand(HostName = cshao-test, HostId = c56bb1d3-57ab-4494-9b75-54e5af1d4d16), log id: 1a7c7180
>2014-09-05 16:05:02,309 INFO  [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (ajp-/127.0.0.1:8702-4) [566d6fbd] FINISH, RemoveVdsVDSCommand, log id: 1a7c7180
>2014-09-05 16:05:02,310 INFO  [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (ajp-/127.0.0.1:8702-4) [566d6fbd] START, AddVdsVDSCommand(HostName = cshao-test, HostId = c56bb1d3-57ab-4494-9b75-54e5af1d4d16), log id: 796278ea
>2014-09-05 16:05:02,310 INFO  [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (ajp-/127.0.0.1:8702-4) [566d6fbd] AddVds - entered , starting logic to add VDS c56bb1d3-57ab-4494-9b75-54e5af1d4d16
>2014-09-05 16:05:02,311 INFO  [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (ajp-/127.0.0.1:8702-4) [566d6fbd] AddVds - VDS c56bb1d3-57ab-4494-9b75-54e5af1d4d16 was added, will try to add it to the resource manager
>2014-09-05 16:05:02,311 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (ajp-/127.0.0.1:8702-4) [566d6fbd] Entered VdsManager constructor
>2014-09-05 16:05:02,323 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (ajp-/127.0.0.1:8702-4) [566d6fbd] Initialize vdsBroker (10.66.9.232,54,321)
>2014-09-05 16:05:02,323 INFO  [org.ovirt.engine.core.vdsbroker.ResourceManager] (ajp-/127.0.0.1:8702-4) [566d6fbd] VDS c56bb1d3-57ab-4494-9b75-54e5af1d4d16 was added to the Resource Manager
>2014-09-05 16:05:02,323 INFO  [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (ajp-/127.0.0.1:8702-4) [566d6fbd] FINISH, AddVdsVDSCommand, log id: 796278ea
>2014-09-05 16:05:02,326 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8702-4) [566d6fbd] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Failed to verify Power Management configuration for Host cshao-test.
>2014-09-05 16:05:02,341 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8702-4) [566d6fbd] Correlation ID: 371d94fd, Call Stack: null, Custom Event ID: -1, Message: Host cshao-test was added by <UNKNOWN>.
>2014-09-05 16:05:02,341 INFO  [org.ovirt.engine.core.bll.RegisterVdsQuery] (ajp-/127.0.0.1:8702-4) [566d6fbd] RegisterVdsQuery::Register - Registered a new VDS pending approval - Name: cshao-test, Hostname: 10.66.9.232, UniqueID: 3B1FA7EC-BFD0-DE11-BBD8-217FB7190024
>2014-09-05 16:05:02,349 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8702-4) [566d6fbd] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Host cshao-test registered.
>2014-09-05 16:05:14,932 INFO  [org.ovirt.engine.core.bll.storage.AddEmptyStoragePoolCommand] (ajp-/127.0.0.1:8702-8) [297a2e30] Running command: AddEmptyStoragePoolCommand internal: false. Entities affected :  ID: aaa00000-0000-0000-0000-123456789aaa Type: SystemAction group CREATE_STORAGE_POOL with role type ADMIN
>2014-09-05 16:05:14,961 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8702-8) [297a2e30] Correlation ID: 297a2e30, Call Stack: null, Custom Event ID: -1, Message: Data Center hadong1, Compatibility Version 3.5 and Quota Type DISABLED was added by admin
>2014-09-05 16:05:27,396 INFO  [org.ovirt.engine.core.bll.AddVdsGroupCommand] (ajp-/127.0.0.1:8702-10) [56709718] Running command: AddVdsGroupCommand internal: false. Entities affected :  ID: e2ce0070-a6c7-4594-8c25-94b79c8ee0f1 Type: StoragePoolAction group CREATE_CLUSTER with role type ADMIN
>2014-09-05 16:05:27,423 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8702-10) [56709718] Correlation ID: 56709718, Call Stack: null, Custom Event ID: -1, Message: Cluster hadong1clu was added by admin
>2014-09-05 16:05:43,534 INFO  [org.ovirt.engine.core.bll.ChangeVDSClusterCommand] (ajp-/127.0.0.1:8702-6) [44736214] Running command: ChangeVDSClusterCommand internal: false. Entities affected :  ID: 3465aece-8372-42bd-b483-21dc9b386039 Type: VdsGroupsAction group EDIT_HOST_CONFIGURATION with role type ADMIN
>2014-09-05 16:05:43,542 INFO  [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (ajp-/127.0.0.1:8702-6) [44736214] START, RemoveVdsVDSCommand(HostName = cshao-test, HostId = c56bb1d3-57ab-4494-9b75-54e5af1d4d16), log id: 87a684d
>2014-09-05 16:05:43,542 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (ajp-/127.0.0.1:8702-6) [44736214] vdsManager::disposing
>2014-09-05 16:05:43,542 INFO  [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (ajp-/127.0.0.1:8702-6) [44736214] FINISH, RemoveVdsVDSCommand, log id: 87a684d
>2014-09-05 16:05:43,542 INFO  [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (ajp-/127.0.0.1:8702-6) [44736214] START, AddVdsVDSCommand(HostName = cshao-test, HostId = c56bb1d3-57ab-4494-9b75-54e5af1d4d16), log id: 554d2dca
>2014-09-05 16:05:43,542 INFO  [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (ajp-/127.0.0.1:8702-6) [44736214] AddVds - entered , starting logic to add VDS c56bb1d3-57ab-4494-9b75-54e5af1d4d16
>2014-09-05 16:05:43,543 INFO  [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (ajp-/127.0.0.1:8702-6) [44736214] AddVds - VDS c56bb1d3-57ab-4494-9b75-54e5af1d4d16 was added, will try to add it to the resource manager
>2014-09-05 16:05:43,543 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (ajp-/127.0.0.1:8702-6) [44736214] Entered VdsManager constructor
>2014-09-05 16:05:43,548 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (ajp-/127.0.0.1:8702-6) [44736214] Initialize vdsBroker (10.66.9.232,54,321)
>2014-09-05 16:05:43,548 INFO  [org.ovirt.engine.core.vdsbroker.ResourceManager] (ajp-/127.0.0.1:8702-6) [44736214] VDS c56bb1d3-57ab-4494-9b75-54e5af1d4d16 was added to the Resource Manager
>2014-09-05 16:05:43,548 INFO  [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (ajp-/127.0.0.1:8702-6) [44736214] FINISH, AddVdsVDSCommand, log id: 554d2dca
>2014-09-05 16:05:43,570 INFO  [org.ovirt.engine.core.bll.AddVdsSpmIdCommand] (ajp-/127.0.0.1:8702-6) [578222a7] Running command: AddVdsSpmIdCommand internal: true. Entities affected :  ID: c56bb1d3-57ab-4494-9b75-54e5af1d4d16 Type: VDS
>2014-09-05 16:05:43,583 INFO  [org.ovirt.engine.core.bll.AddVdsSpmIdCommand] (ajp-/127.0.0.1:8702-6) [578222a7] Lock freed to object EngineLock [exclusiveLocks= key: e2ce0070-a6c7-4594-8c25-94b79c8ee0f1 value: REGISTER_VDS
>, sharedLocks= ]
>2014-09-05 16:05:43,639 INFO  [org.ovirt.engine.core.bll.network.host.PersistentSetupNetworksCommand] (org.ovirt.thread.pool-6-thread-27) [4d910c56] Running command: PersistentSetupNetworksCommand internal: true. Entities affected :  ID: c56bb1d3-57ab-4494-9b75-54e5af1d4d16 Type: VDS
>2014-09-05 16:05:43,643 WARN  [org.ovirt.engine.core.bll.network.host.SetupNetworksCommand] (org.ovirt.thread.pool-6-thread-27) [4d910c56] CanDoAction of action SetupNetworks failed. Reasons:VAR__ACTION__SETUP,VAR__TYPE__NETWORKS,VAR__HOST_STATUS__UP_MAINTENANCE_OR_NON_OPERATIONAL,ACTION_TYPE_FAILED_VDS_STATUS_ILLEGAL
>2014-09-05 16:05:43,649 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8702-6) [578222a7] Correlation ID: 44736214, Call Stack: null, Custom Event ID: -1, Message: Host cshao-test configuration was updated by admin.
>2014-09-05 16:05:43,711 WARN  [org.ovirt.engine.core.compat.backendcompat.PropertyInfo] (ajp-/127.0.0.1:8702-7) Unable to get value of property: vdsName for class org.ovirt.engine.core.common.businessentities.VdsStatic
>2014-09-05 16:05:43,711 WARN  [org.ovirt.engine.core.compat.backendcompat.PropertyInfo] (ajp-/127.0.0.1:8702-7) Unable to get value of property: vdsName for class org.ovirt.engine.core.common.businessentities.VdsStatic
>2014-09-05 16:05:43,711 INFO  [org.ovirt.engine.core.bll.UpdateVdsCommand] (ajp-/127.0.0.1:8702-7) [d97a391] Running command: UpdateVdsCommand internal: false. Entities affected :  ID: c56bb1d3-57ab-4494-9b75-54e5af1d4d16 Type: VDSAction group EDIT_HOST_CONFIGURATION with role type ADMIN
>2014-09-05 16:05:43,724 WARN  [org.ovirt.engine.core.compat.backendcompat.PropertyInfo] (ajp-/127.0.0.1:8702-7) Unable to get value of property: vdsName for class org.ovirt.engine.core.common.businessentities.VdsStatic
>2014-09-05 16:05:43,724 WARN  [org.ovirt.engine.core.compat.backendcompat.PropertyInfo] (ajp-/127.0.0.1:8702-7) Unable to get value of property: vdsName for class org.ovirt.engine.core.common.businessentities.VdsStatic
>2014-09-05 16:05:43,727 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8702-7) [d97a391] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Failed to verify Power Management configuration for Host cshao-test.
>2014-09-05 16:05:43,757 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (ajp-/127.0.0.1:8702-7) [d97a391] Correlation ID: d97a391, Call Stack: null, Custom Event ID: -1, Message: Host cshao-test configuration was updated by admin.
>2014-09-05 16:05:44,149 INFO  [org.ovirt.engine.core.bll.ApproveVdsCommand] (org.ovirt.thread.pool-6-thread-28) [135db263] Running command: ApproveVdsCommand internal: false. Entities affected :  ID: c56bb1d3-57ab-4494-9b75-54e5af1d4d16 Type: VDSAction group CREATE_HOST with role type ADMIN
>2014-09-05 16:05:44,150 INFO  [org.ovirt.engine.core.bll.InstallVdsInternalCommand] (org.ovirt.thread.pool-6-thread-28) [135db263] Before Installation host c56bb1d3-57ab-4494-9b75-54e5af1d4d16, cshao-test
>2014-09-05 16:05:44,162 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-6-thread-28) [135db263] START, SetVdsStatusVDSCommand(HostName = cshao-test, HostId = c56bb1d3-57ab-4494-9b75-54e5af1d4d16, status=Installing, nonOperationalReason=NONE, stopSpmFailureLogged=false), log id: 43c3bd9
>2014-09-05 16:05:44,174 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-6-thread-28) [135db263] FINISH, SetVdsStatusVDSCommand, log id: 43c3bd9
>2014-09-05 16:05:44,337 INFO  [org.ovirt.engine.core.bll.InstallerMessages] (org.ovirt.thread.pool-6-thread-28) [135db263] Installation 10.66.9.232: Connected to host 10.66.9.232 with SSH key fingerprint: 72:c3:30:2a:d4:44:c5:b7:3e:c8:41:e0:60:7b:19:07
>2014-09-05 16:05:44,374 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-28) [135db263] Correlation ID: 135db263, Call Stack: null, Custom Event ID: -1, Message: Installing Host cshao-test. Connected to host 10.66.9.232 with SSH key fingerprint: 72:c3:30:2a:d4:44:c5:b7:3e:c8:41:e0:60:7b:19:07.
>2014-09-05 16:05:44,404 INFO  [org.ovirt.engine.core.bll.VdsDeploy] (org.ovirt.thread.pool-6-thread-28) [135db263] Installation of 10.66.9.232. Executing command via SSH umask 0077; MYTMP="$(TMPDIR="${OVIRT_TMPDIR}" 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 --warning=no-timestamp -C "${MYTMP}" -x &&  "${MYTMP}"/setup DIALOG/dialect=str:machine DIALOG/customization=bool:True < /var/cache/ovirt-engine/ovirt-host-deploy.tar
>2014-09-05 16:05:44,408 INFO  [org.ovirt.engine.core.uutils.ssh.SSHDialog] (org.ovirt.thread.pool-6-thread-28) SSH execute root@10.66.9.232 'umask 0077; MYTMP="$(TMPDIR="${OVIRT_TMPDIR}" 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 --warning=no-timestamp -C "${MYTMP}" -x &&  "${MYTMP}"/setup DIALOG/dialect=str:machine DIALOG/customization=bool:True'
>2014-09-05 16:05:45,614 INFO  [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.66.9.232: Stage: Initializing
>2014-09-05 16:05:45,631 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 135db263, Call Stack: null, Custom Event ID: -1, Message: Installing Host cshao-test. Stage: Initializing.
>2014-09-05 16:05:45,651 INFO  [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.66.9.232: Stage: Environment setup
>2014-09-05 16:05:45,664 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 135db263, Call Stack: null, Custom Event ID: -1, Message: Installing Host cshao-test. Stage: Environment setup.
>2014-09-05 16:05:45,664 INFO  [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.66.9.232: Stage: Environment packages setup
>2014-09-05 16:05:45,672 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 135db263, Call Stack: null, Custom Event ID: -1, Message: Installing Host cshao-test. Stage: Environment packages setup.
>2014-09-05 16:05:45,673 INFO  [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.66.9.232: Stage: Programs detection
>2014-09-05 16:05:45,680 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 135db263, Call Stack: null, Custom Event ID: -1, Message: Installing Host cshao-test. Stage: Programs detection.
>2014-09-05 16:05:45,702 INFO  [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.66.9.232: Stage: Environment customization
>2014-09-05 16:05:45,714 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 135db263, Call Stack: null, Custom Event ID: -1, Message: Installing Host cshao-test. Stage: Environment customization.
>2014-09-05 16:05:45,860 INFO  [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.66.9.232: Kdump supported
>2014-09-05 16:05:45,872 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 135db263, Call Stack: null, Custom Event ID: -1, Message: Installing Host cshao-test. Kdump supported.
>2014-09-05 16:05:45,879 INFO  [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.66.9.232: Host is hypervisor
>2014-09-05 16:05:45,889 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 135db263, Call Stack: null, Custom Event ID: -1, Message: Installing Host cshao-test. Host is hypervisor.
>2014-09-05 16:05:45,901 INFO  [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.66.9.232: Logs at host located at: '/tmp/ovirt-host-deploy-20140905080544-bz7dls.log'
>2014-09-05 16:05:45,914 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 135db263, Call Stack: null, Custom Event ID: -1, Message: Installing Host cshao-test. Logs at host located at: '/tmp/ovirt-host-deploy-20140905080544-bz7dls.log'.
>2014-09-05 16:05:45,955 INFO  [org.ovirt.engine.core.bll.VdsDeploy] (VdsDeploy) Host 10.66.9.232 reports unique id 3B1FA7EC-BFD0-DE11-BBD8-217FB7190024
>2014-09-05 16:05:45,957 INFO  [org.ovirt.engine.core.bll.VdsDeploy] (VdsDeploy) Assigning unique id 3B1FA7EC-BFD0-DE11-BBD8-217FB7190024 to Host 10.66.9.232
>2014-09-05 16:05:46,010 INFO  [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.66.9.232: Disabling Kdump integration
>2014-09-05 16:05:46,022 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 135db263, Call Stack: null, Custom Event ID: -1, Message: Installing Host cshao-test. Disabling Kdump integration.
>2014-09-05 16:05:46,040 INFO  [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.66.9.232: Stage: Setup validation
>2014-09-05 16:05:46,047 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 135db263, Call Stack: null, Custom Event ID: -1, Message: Installing Host cshao-test. Stage: Setup validation.
>2014-09-05 16:05:46,061 WARN  [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.66.9.232: Cannot validate host name settings, reason: resolved host does not match any of the local addresses
>2014-09-05 16:05:46,072 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 135db263, Call Stack: null, Custom Event ID: -1, Message: Host cshao-test installation in progress . Cannot validate host name settings, reason: resolved host does not match any of the local addresses.
>2014-09-05 16:05:46,094 INFO  [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.66.9.232: Hardware supports virtualization
>2014-09-05 16:05:46,105 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 135db263, Call Stack: null, Custom Event ID: -1, Message: Installing Host cshao-test. Hardware supports virtualization.
>2014-09-05 16:05:46,105 INFO  [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.66.9.232: Stage: Transaction setup
>2014-09-05 16:05:46,114 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 135db263, Call Stack: null, Custom Event ID: -1, Message: Installing Host cshao-test. Stage: Transaction setup.
>2014-09-05 16:05:46,114 INFO  [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.66.9.232: Stage: Misc configuration
>2014-09-05 16:05:46,127 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 135db263, Call Stack: null, Custom Event ID: -1, Message: Installing Host cshao-test. Stage: Misc configuration.
>2014-09-05 16:05:46,127 INFO  [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.66.9.232: Stage: Package installation
>2014-09-05 16:05:46,138 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 135db263, Call Stack: null, Custom Event ID: -1, Message: Installing Host cshao-test. Stage: Package installation.
>2014-09-05 16:05:46,551 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (DefaultQuartzScheduler_Worker-16) Initializing Host: cshao-test
>2014-09-05 16:05:51,256 INFO  [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.66.9.232: Stage: Misc configuration
>2014-09-05 16:05:51,275 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 135db263, Call Stack: null, Custom Event ID: -1, Message: Installing Host cshao-test. Stage: Misc configuration.
>2014-09-05 16:05:51,304 INFO  [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.66.9.232: Setting time
>2014-09-05 16:05:51,317 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 135db263, Call Stack: null, Custom Event ID: -1, Message: Installing Host cshao-test. Setting time.
>2014-09-05 16:05:53,082 INFO  [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.66.9.232: Enrolling certificate
>2014-09-05 16:05:53,098 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 135db263, Call Stack: null, Custom Event ID: -1, Message: Installing Host cshao-test. Enrolling certificate.
>2014-09-05 16:05:54,346 INFO  [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.66.9.232: Stage: Transaction commit
>2014-09-05 16:05:54,380 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 135db263, Call Stack: null, Custom Event ID: -1, Message: Installing Host cshao-test. Stage: Transaction commit.
>2014-09-05 16:05:54,380 INFO  [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.66.9.232: Stage: Closing up
>2014-09-05 16:05:54,388 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 135db263, Call Stack: null, Custom Event ID: -1, Message: Installing Host cshao-test. Stage: Closing up.
>2014-09-05 16:05:58,311 INFO  [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.66.9.232: Stopping libvirtd
>2014-09-05 16:05:58,375 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 135db263, Call Stack: null, Custom Event ID: -1, Message: Installing Host cshao-test. Stopping libvirtd.
>2014-09-05 16:05:58,428 INFO  [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.66.9.232: Starting vdsm
>2014-09-05 16:05:58,451 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 135db263, Call Stack: null, Custom Event ID: -1, Message: Installing Host cshao-test. Starting vdsm.
>2014-09-05 16:06:16,354 ERROR [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.66.9.232: Failed to execute stage 'Closing up': could not import gobject (could not find _PyGObject_API object)
>2014-09-05 16:06:16,366 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 135db263, Call Stack: null, Custom Event ID: -1, Message: Failed to install Host cshao-test. Failed to execute stage 'Closing up': could not import gobject (could not find _PyGObject_API object).
>2014-09-05 16:06:16,366 INFO  [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.66.9.232: Stage: Pre-termination
>2014-09-05 16:06:16,376 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 135db263, Call Stack: null, Custom Event ID: -1, Message: Installing Host cshao-test. Stage: Pre-termination.
>2014-09-05 16:06:16,388 INFO  [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.66.9.232: Retrieving installation logs to: '/var/log/ovirt-engine/host-deploy/ovirt-20140905160616-10.66.9.232-135db263.log'
>2014-09-05 16:06:16,400 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 135db263, Call Stack: null, Custom Event ID: -1, Message: Installing Host cshao-test. Retrieving installation logs to: '/var/log/ovirt-engine/host-deploy/ovirt-20140905160616-10.66.9.232-135db263.log'.
>2014-09-05 16:06:16,639 INFO  [org.ovirt.engine.core.bll.InstallerMessages] (VdsDeploy) Installation 10.66.9.232: Stage: Termination
>2014-09-05 16:06:16,649 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) Correlation ID: 135db263, Call Stack: null, Custom Event ID: -1, Message: Installing Host cshao-test. Stage: Termination.
>2014-09-05 16:06:16,701 ERROR [org.ovirt.engine.core.uutils.ssh.SSHDialog] (org.ovirt.thread.pool-6-thread-28) SSH error running command root@10.66.9.232:'umask 0077; MYTMP="$(TMPDIR="${OVIRT_TMPDIR}" 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 --warning=no-timestamp -C "${MYTMP}" -x &&  "${MYTMP}"/setup DIALOG/dialect=str:machine DIALOG/customization=bool:True': java.io.IOException: Command returned failure code 1 during SSH session 'root@10.66.9.232'
>	at org.ovirt.engine.core.uutils.ssh.SSHClient.executeCommand(SSHClient.java:527) [uutils.jar:]
>	at org.ovirt.engine.core.uutils.ssh.SSHDialog.executeCommand(SSHDialog.java:318) [uutils.jar:]
>	at org.ovirt.engine.core.bll.VdsDeploy.execute(VdsDeploy.java:1118) [bll.jar:]
>	at org.ovirt.engine.core.bll.InstallVdsInternalCommand.installHost(InstallVdsInternalCommand.java:151) [bll.jar:]
>	at org.ovirt.engine.core.bll.InstallVdsInternalCommand.executeCommand(InstallVdsInternalCommand.java:78) [bll.jar:]
>	at org.ovirt.engine.core.bll.ApproveVdsCommand.executeCommand(ApproveVdsCommand.java:58) [bll.jar:]
>	at org.ovirt.engine.core.bll.CommandBase.executeWithoutTransaction(CommandBase.java:1167) [bll.jar:]
>	at org.ovirt.engine.core.bll.CommandBase.executeActionInTransactionScope(CommandBase.java:1298) [bll.jar:]
>	at org.ovirt.engine.core.bll.CommandBase.runInTransaction(CommandBase.java:1920) [bll.jar:]
>	at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInSuppressed(TransactionSupport.java:174) [utils.jar:]
>	at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInScope(TransactionSupport.java:116) [utils.jar:]
>	at org.ovirt.engine.core.bll.CommandBase.execute(CommandBase.java:1319) [bll.jar:]
>	at org.ovirt.engine.core.bll.CommandBase.executeAction(CommandBase.java:344) [bll.jar:]
>	at org.ovirt.engine.core.bll.MultipleActionsRunner.executeValidatedCommand(MultipleActionsRunner.java:191) [bll.jar:]
>	at org.ovirt.engine.core.bll.MultipleActionsRunner.runCommands(MultipleActionsRunner.java:158) [bll.jar:]
>	at org.ovirt.engine.core.bll.MultipleActionsRunner$2.run(MultipleActionsRunner.java:167) [bll.jar:]
>	at org.ovirt.engine.core.utils.threadpool.ThreadPoolUtil$InternalWrapperRunnable.run(ThreadPoolUtil.java:90) [utils.jar:]
>	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [rt.jar:1.7.0_45]
>	at java.util.concurrent.FutureTask.run(FutureTask.java:262) [rt.jar:1.7.0_45]
>	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_45]
>	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_45]
>	at java.lang.Thread.run(Thread.java:744) [rt.jar:1.7.0_45]
>
>2014-09-05 16:06:16,702 ERROR [org.ovirt.engine.core.bll.VdsDeploy] (org.ovirt.thread.pool-6-thread-28) [135db263] Error during host 10.66.9.232 install: java.io.IOException: Command returned failure code 1 during SSH session 'root@10.66.9.232'
>	at org.ovirt.engine.core.uutils.ssh.SSHClient.executeCommand(SSHClient.java:527) [uutils.jar:]
>	at org.ovirt.engine.core.uutils.ssh.SSHDialog.executeCommand(SSHDialog.java:318) [uutils.jar:]
>	at org.ovirt.engine.core.bll.VdsDeploy.execute(VdsDeploy.java:1118) [bll.jar:]
>	at org.ovirt.engine.core.bll.InstallVdsInternalCommand.installHost(InstallVdsInternalCommand.java:151) [bll.jar:]
>	at org.ovirt.engine.core.bll.InstallVdsInternalCommand.executeCommand(InstallVdsInternalCommand.java:78) [bll.jar:]
>	at org.ovirt.engine.core.bll.ApproveVdsCommand.executeCommand(ApproveVdsCommand.java:58) [bll.jar:]
>	at org.ovirt.engine.core.bll.CommandBase.executeWithoutTransaction(CommandBase.java:1167) [bll.jar:]
>	at org.ovirt.engine.core.bll.CommandBase.executeActionInTransactionScope(CommandBase.java:1298) [bll.jar:]
>	at org.ovirt.engine.core.bll.CommandBase.runInTransaction(CommandBase.java:1920) [bll.jar:]
>	at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInSuppressed(TransactionSupport.java:174) [utils.jar:]
>	at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInScope(TransactionSupport.java:116) [utils.jar:]
>	at org.ovirt.engine.core.bll.CommandBase.execute(CommandBase.java:1319) [bll.jar:]
>	at org.ovirt.engine.core.bll.CommandBase.executeAction(CommandBase.java:344) [bll.jar:]
>	at org.ovirt.engine.core.bll.MultipleActionsRunner.executeValidatedCommand(MultipleActionsRunner.java:191) [bll.jar:]
>	at org.ovirt.engine.core.bll.MultipleActionsRunner.runCommands(MultipleActionsRunner.java:158) [bll.jar:]
>	at org.ovirt.engine.core.bll.MultipleActionsRunner$2.run(MultipleActionsRunner.java:167) [bll.jar:]
>	at org.ovirt.engine.core.utils.threadpool.ThreadPoolUtil$InternalWrapperRunnable.run(ThreadPoolUtil.java:90) [utils.jar:]
>	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [rt.jar:1.7.0_45]
>	at java.util.concurrent.FutureTask.run(FutureTask.java:262) [rt.jar:1.7.0_45]
>	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_45]
>	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_45]
>	at java.lang.Thread.run(Thread.java:744) [rt.jar:1.7.0_45]
>
>2014-09-05 16:06:16,704 ERROR [org.ovirt.engine.core.bll.ApproveVdsCommand] (org.ovirt.thread.pool-6-thread-28) [135db263] Host installation failed for host c56bb1d3-57ab-4494-9b75-54e5af1d4d16, cshao-test.: java.io.IOException: Command returned failure code 1 during SSH session 'root@10.66.9.232'
>	at org.ovirt.engine.core.uutils.ssh.SSHClient.executeCommand(SSHClient.java:527) [uutils.jar:]
>	at org.ovirt.engine.core.uutils.ssh.SSHDialog.executeCommand(SSHDialog.java:318) [uutils.jar:]
>	at org.ovirt.engine.core.bll.VdsDeploy.execute(VdsDeploy.java:1118) [bll.jar:]
>	at org.ovirt.engine.core.bll.InstallVdsInternalCommand.installHost(InstallVdsInternalCommand.java:151) [bll.jar:]
>	at org.ovirt.engine.core.bll.InstallVdsInternalCommand.executeCommand(InstallVdsInternalCommand.java:78) [bll.jar:]
>	at org.ovirt.engine.core.bll.ApproveVdsCommand.executeCommand(ApproveVdsCommand.java:58) [bll.jar:]
>	at org.ovirt.engine.core.bll.CommandBase.executeWithoutTransaction(CommandBase.java:1167) [bll.jar:]
>	at org.ovirt.engine.core.bll.CommandBase.executeActionInTransactionScope(CommandBase.java:1298) [bll.jar:]
>	at org.ovirt.engine.core.bll.CommandBase.runInTransaction(CommandBase.java:1920) [bll.jar:]
>	at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInSuppressed(TransactionSupport.java:174) [utils.jar:]
>	at org.ovirt.engine.core.utils.transaction.TransactionSupport.executeInScope(TransactionSupport.java:116) [utils.jar:]
>	at org.ovirt.engine.core.bll.CommandBase.execute(CommandBase.java:1319) [bll.jar:]
>	at org.ovirt.engine.core.bll.CommandBase.executeAction(CommandBase.java:344) [bll.jar:]
>	at org.ovirt.engine.core.bll.MultipleActionsRunner.executeValidatedCommand(MultipleActionsRunner.java:191) [bll.jar:]
>	at org.ovirt.engine.core.bll.MultipleActionsRunner.runCommands(MultipleActionsRunner.java:158) [bll.jar:]
>	at org.ovirt.engine.core.bll.MultipleActionsRunner$2.run(MultipleActionsRunner.java:167) [bll.jar:]
>	at org.ovirt.engine.core.utils.threadpool.ThreadPoolUtil$InternalWrapperRunnable.run(ThreadPoolUtil.java:90) [utils.jar:]
>	at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:471) [rt.jar:1.7.0_45]
>	at java.util.concurrent.FutureTask.run(FutureTask.java:262) [rt.jar:1.7.0_45]
>	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1145) [rt.jar:1.7.0_45]
>	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:615) [rt.jar:1.7.0_45]
>	at java.lang.Thread.run(Thread.java:744) [rt.jar:1.7.0_45]
>
>2014-09-05 16:06:16,706 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-6-thread-28) [135db263] START, SetVdsStatusVDSCommand(HostName = cshao-test, HostId = c56bb1d3-57ab-4494-9b75-54e5af1d4d16, status=InstallFailed, nonOperationalReason=NONE, stopSpmFailureLogged=false), log id: 55e26260
>2014-09-05 16:06:16,716 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (org.ovirt.thread.pool-6-thread-28) [135db263] FINISH, SetVdsStatusVDSCommand, log id: 55e26260
>2014-09-05 16:06:16,724 ERROR [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (org.ovirt.thread.pool-6-thread-28) [135db263] Correlation ID: 135db263, Job ID: 14cb3197-5731-4225-81a2-04455e95643f, Call Stack: null, Custom Event ID: -1, Message: Host cshao-test installation failed. Command returned failure code 1 during SSH session 'root@10.66.9.232'.

Actions: View

Attachments on bug 1128033: 925116 | 934703 | 948220