Attachment 1447980 Details for Bug 1584237 – engine.log

engine.log

engine.log (text/plain), 27.07 KB, created by Sven Vogel on 2018-06-05 20:12:21 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Sven Vogel

Created: 2018-06-05 20:12:21 UTC

Size: 27.07 KB

patch

obsolete

>
>2018-06-05 21:58:14,184+02 INFO  [org.ovirt.engine.core.bll.utils.ThreadPoolMonitoringService] (EE-ManagedThreadFactory-engineThreadMonitoring-Thread-1) [] Thread pool 'default' is using 0 threads out of 1, 5 threads waiting for tasks.
>2018-06-05 21:58:14,184+02 INFO  [org.ovirt.engine.core.bll.utils.ThreadPoolMonitoringService] (EE-ManagedThreadFactory-engineThreadMonitoring-Thread-1) [] Thread pool 'engine' is using 0 threads out of 500, 9 threads waiting for tasks and 0 tasks in queue.
>2018-06-05 21:58:14,184+02 INFO  [org.ovirt.engine.core.bll.utils.ThreadPoolMonitoringService] (EE-ManagedThreadFactory-engineThreadMonitoring-Thread-1) [] Thread pool 'engineScheduled' is using 0 threads out of 100, 100 threads waiting for tasks.
>2018-06-05 21:58:14,184+02 INFO  [org.ovirt.engine.core.bll.utils.ThreadPoolMonitoringService] (EE-ManagedThreadFactory-engineThreadMonitoring-Thread-1) [] Thread pool 'engineThreadMonitoring' is using 1 threads out of 1, 0 threads waiting for tasks.
>2018-06-05 21:58:14,184+02 INFO  [org.ovirt.engine.core.bll.utils.ThreadPoolMonitoringService] (EE-ManagedThreadFactory-engineThreadMonitoring-Thread-1) [] Thread pool 'hostUpdatesChecker' is using 0 threads out of 5, 5 threads waiting for tasks.
>2018-06-05 21:59:06,046+02 INFO  [org.ovirt.engine.core.bll.provider.network.SyncNetworkProviderCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-57) [48bb4d8c] Lock Acquired to object 'EngineLock:{exclusiveLocks='[71c4e6ee-25f7-401c-a1af-f9e74e05c636=PROVIDER]', sharedLocks=''}'
>2018-06-05 21:59:06,051+02 INFO  [org.ovirt.engine.core.bll.provider.network.SyncNetworkProviderCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-57) [48bb4d8c] Running command: SyncNetworkProviderCommand internal: true.
>2018-06-05 21:59:06,134+02 INFO  [org.ovirt.engine.core.sso.utils.AuthenticationUtils] (default task-53) [] User admin@internal successfully logged in with scopes: ovirt-app-api ovirt-ext=token-info:authz-search ovirt-ext=token-info:public-authz-search ovirt-ext=token-info:validate ovirt-ext=token:password-access
>2018-06-05 21:59:06,233+02 INFO  [org.ovirt.engine.core.bll.provider.network.SyncNetworkProviderCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-57) [48bb4d8c] Lock freed to object 'EngineLock:{exclusiveLocks='[71c4e6ee-25f7-401c-a1af-f9e74e05c636=PROVIDER]', sharedLocks=''}'
>2018-06-05 22:04:06,240+02 INFO  [org.ovirt.engine.core.bll.provider.network.SyncNetworkProviderCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-39) [78303b55] Lock Acquired to object 'EngineLock:{exclusiveLocks='[71c4e6ee-25f7-401c-a1af-f9e74e05c636=PROVIDER]', sharedLocks=''}'
>2018-06-05 22:04:06,244+02 INFO  [org.ovirt.engine.core.bll.provider.network.SyncNetworkProviderCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-39) [78303b55] Running command: SyncNetworkProviderCommand internal: true.
>2018-06-05 22:04:06,328+02 INFO  [org.ovirt.engine.core.sso.utils.AuthenticationUtils] (default task-51) [] User admin@internal successfully logged in with scopes: ovirt-app-api ovirt-ext=token-info:authz-search ovirt-ext=token-info:public-authz-search ovirt-ext=token-info:validate ovirt-ext=token:password-access
>2018-06-05 22:04:06,429+02 INFO  [org.ovirt.engine.core.bll.provider.network.SyncNetworkProviderCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-39) [78303b55] Lock freed to object 'EngineLock:{exclusiveLocks='[71c4e6ee-25f7-401c-a1af-f9e74e05c636=PROVIDER]', sharedLocks=''}'
>2018-06-05 22:04:55,366+02 INFO  [org.ovirt.engine.core.bll.hostdeploy.AddVdsCommand] (default task-19) [e62495a7-f317-43b5-a4e3-817932196d72] Running command: AddVdsCommand internal: false. Entities affected :  ID: 1ef55f8e-6383-11e8-961f-00163e1ef844 Type: ClusterAction group CREATE_HOST with role type ADMIN
>2018-06-05 22:04:55,394+02 INFO  [org.ovirt.engine.core.bll.AddVdsSpmIdCommand] (default task-19) [f02114f] Before acquiring and wait lock 'EngineLock:{exclusiveLocks='[1ef4518e-6383-11e8-83ff-00163e1ef844=REGISTER_VDS]', sharedLocks=''}'
>2018-06-05 22:04:55,394+02 INFO  [org.ovirt.engine.core.bll.AddVdsSpmIdCommand] (default task-19) [f02114f] Lock-wait acquired to object 'EngineLock:{exclusiveLocks='[1ef4518e-6383-11e8-83ff-00163e1ef844=REGISTER_VDS]', sharedLocks=''}'
>2018-06-05 22:04:55,396+02 INFO  [org.ovirt.engine.core.bll.AddVdsSpmIdCommand] (default task-19) [f02114f] Running command: AddVdsSpmIdCommand internal: true. Entities affected :  ID: 01b668c5-069c-448f-be05-a16175a27c6b Type: VDS
>2018-06-05 22:04:55,411+02 INFO  [org.ovirt.engine.core.bll.AddVdsSpmIdCommand] (default task-19) [f02114f] Lock freed to object 'EngineLock:{exclusiveLocks='[1ef4518e-6383-11e8-83ff-00163e1ef844=REGISTER_VDS]', sharedLocks=''}'
>2018-06-05 22:04:55,413+02 INFO  [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (default task-19) [f02114f] START, RemoveVdsVDSCommand(HostName = kvm02.qform.de, RemoveVdsVDSCommandParameters:{hostId='01b668c5-069c-448f-be05-a16175a27c6b'}), log id: 65b2a8a6
>2018-06-05 22:04:55,413+02 INFO  [org.ovirt.engine.core.vdsbroker.RemoveVdsVDSCommand] (default task-19) [f02114f] FINISH, RemoveVdsVDSCommand, log id: 65b2a8a6
>2018-06-05 22:04:55,416+02 INFO  [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (default task-19) [f02114f] START, AddVdsVDSCommand(HostName = kvm02.qform.de, AddVdsVDSCommandParameters:{hostId='01b668c5-069c-448f-be05-a16175a27c6b'}), log id: 4c9e5cf
>2018-06-05 22:04:55,416+02 INFO  [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (default task-19) [f02114f] AddVds - entered , starting logic to add VDS '01b668c5-069c-448f-be05-a16175a27c6b'
>2018-06-05 22:04:55,417+02 INFO  [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (default task-19) [f02114f] AddVds - VDS '01b668c5-069c-448f-be05-a16175a27c6b' was added, will try to add it to the resource manager
>2018-06-05 22:04:55,417+02 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (default task-19) [f02114f] Entered VdsManager constructor
>2018-06-05 22:04:55,422+02 INFO  [org.ovirt.engine.core.vdsbroker.VdsManager] (default task-19) [f02114f] Initialize vdsBroker 'kvm02.qform.de:54321'
>2018-06-05 22:04:55,425+02 INFO  [org.ovirt.engine.core.vdsbroker.ResourceManager] (default task-19) [f02114f] VDS '01b668c5-069c-448f-be05-a16175a27c6b' was added to the Resource Manager
>2018-06-05 22:04:55,425+02 INFO  [org.ovirt.engine.core.vdsbroker.AddVdsVDSCommand] (default task-19) [f02114f] FINISH, AddVdsVDSCommand, log id: 4c9e5cf
>2018-06-05 22:04:55,443+02 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (default task-19) [f02114f] EVENT_ID: VDS_ALERT_FENCE_IS_NOT_CONFIGURED(9,000), Failed to verify Power Management configuration for Host kvm02.qform.de.
>2018-06-05 22:04:55,468+02 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (default task-19) [f02114f] EVENT_ID: USER_ADD_VDS(42), Host kvm02.qform.de was added by admin@internal-authz.
>2018-06-05 22:04:55,477+02 INFO  [org.ovirt.engine.core.bll.hostdeploy.InstallVdsInternalCommand] (EE-ManagedThreadFactory-engine-Thread-143307) [4f048fb6] Lock Acquired to object 'EngineLock:{exclusiveLocks='[01b668c5-069c-448f-be05-a16175a27c6b=VDS]', sharedLocks=''}'
>2018-06-05 22:04:55,496+02 INFO  [org.ovirt.engine.core.bll.hostdeploy.InstallVdsInternalCommand] (EE-ManagedThreadFactory-engine-Thread-143307) [4f048fb6] Running command: InstallVdsInternalCommand internal: true. Entities affected :  ID: 01b668c5-069c-448f-be05-a16175a27c6b Type: VDS
>2018-06-05 22:04:55,499+02 INFO  [org.ovirt.engine.core.bll.hostdeploy.InstallVdsInternalCommand] (EE-ManagedThreadFactory-engine-Thread-143307) [4f048fb6] Before Installation host 01b668c5-069c-448f-be05-a16175a27c6b, kvm02.qform.de
>2018-06-05 22:04:55,512+02 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (EE-ManagedThreadFactory-engine-Thread-143307) [4f048fb6] START, SetVdsStatusVDSCommand(HostName = kvm02.qform.de, SetVdsStatusVDSCommandParameters:{hostId='01b668c5-069c-448f-be05-a16175a27c6b', status='Installing', nonOperationalReason='NONE', stopSpmFailureLogged='false', maintenanceReason='null'}), log id: 549bf0c4
>2018-06-05 22:04:55,521+02 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (EE-ManagedThreadFactory-engine-Thread-143307) [4f048fb6] FINISH, SetVdsStatusVDSCommand, log id: 549bf0c4
>2018-06-05 22:04:55,607+02 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (EE-ManagedThreadFactory-engine-Thread-143307) [4f048fb6] EVENT_ID: VDS_INSTALL_IN_PROGRESS(509), Installing Host kvm02.qform.de. Connected to host kvm02.qform.de with SSH key fingerprint: SHA256:uYtEU2zguRbDciGIrU2lZvuzemay7F4XvBUJugVMuOk.
>2018-06-05 22:04:55,679+02 INFO  [org.ovirt.engine.core.bll.hostdeploy.VdsDeployBase] (EE-ManagedThreadFactory-engine-Thread-143307) [4f048fb6] Installation of kvm02.qform.de. Executing command via SSH umask 0077; MYTMP="$(TMPDIR="${OVIRT_TMPDIR}" mktemp -d -t ovirt-XXXXXXXXXX)"; trap "chmod -R u+rwX \"${MYTMP}\" > /dev/null 2>&1; rm -fr \"${MYTMP}\" > /dev/null 2>&1" 0; tar --warning=no-timestamp -C "${MYTMP}" -x &&  "${MYTMP}"/ovirt-host-deploy DIALOG/dialect=str:machine DIALOG/customization=bool:True < /var/cache/ovirt-engine/ovirt-host-deploy.tar
>2018-06-05 22:04:55,679+02 INFO  [org.ovirt.engine.core.utils.archivers.tar.CachedTar] (EE-ManagedThreadFactory-engine-Thread-143307) [4f048fb6] Tarball '/var/cache/ovirt-engine/ovirt-host-deploy.tar' refresh
>2018-06-05 22:04:55,705+02 INFO  [org.ovirt.engine.core.uutils.ssh.SSHDialog] (EE-ManagedThreadFactory-engine-Thread-143307) [4f048fb6] SSH execute 'root@kvm02.qform.de' 'umask 0077; MYTMP="$(TMPDIR="${OVIRT_TMPDIR}" mktemp -d -t ovirt-XXXXXXXXXX)"; trap "chmod -R u+rwX \"${MYTMP}\" > /dev/null 2>&1; rm -fr \"${MYTMP}\" > /dev/null 2>&1" 0; tar --warning=no-timestamp -C "${MYTMP}" -x &&  "${MYTMP}"/ovirt-host-deploy DIALOG/dialect=str:machine DIALOG/customization=bool:True'
>2018-06-05 22:04:56,553+02 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) [4f048fb6] EVENT_ID: VDS_INSTALL_IN_PROGRESS(509), Installing Host kvm02.qform.de. Stage: Initializing.
>2018-06-05 22:04:56,602+02 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) [4f048fb6] EVENT_ID: VDS_INSTALL_IN_PROGRESS(509), Installing Host kvm02.qform.de. Stage: Environment setup.
>2018-06-05 22:04:56,632+02 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) [4f048fb6] EVENT_ID: VDS_INSTALL_IN_PROGRESS(509), Installing Host kvm02.qform.de. Stage: Environment packages setup.
>2018-06-05 22:04:56,865+02 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) [4f048fb6] EVENT_ID: VDS_INSTALL_IN_PROGRESS(509), Installing Host kvm02.qform.de. Stage: Programs detection.
>2018-06-05 22:04:56,913+02 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) [4f048fb6] EVENT_ID: VDS_INSTALL_IN_PROGRESS(509), Installing Host kvm02.qform.de. Stage: Environment customization.
>2018-06-05 22:05:16,056+02 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) [4f048fb6] EVENT_ID: VDS_INSTALL_IN_PROGRESS(509), Installing Host kvm02.qform.de. Kdump supported.
>2018-06-05 22:05:16,270+02 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) [4f048fb6] EVENT_ID: VDS_INSTALL_IN_PROGRESS(509), Installing Host kvm02.qform.de. Logs at host located at: '/tmp/ovirt-host-deploy-20180605220456-hf96d0.log'.
>2018-06-05 22:05:16,301+02 INFO  [org.ovirt.engine.core.bll.hostdeploy.VdsDeployVdsmUnit] (VdsDeploy) [4f048fb6] Host kvm02.qform.de reports unique id 365E6DB8-CACD-4A7E-9AFF-D45DDF135A57
>2018-06-05 22:05:16,310+02 INFO  [org.ovirt.engine.core.bll.hostdeploy.VdsDeployVdsmUnit] (VdsDeploy) [4f048fb6] Assigning unique id 365E6DB8-CACD-4A7E-9AFF-D45DDF135A57 to Host kvm02.qform.de
>2018-06-05 22:05:16,354+02 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) [4f048fb6] EVENT_ID: VDS_INSTALL_IN_PROGRESS(509), Installing Host kvm02.qform.de. Disabling Kdump integration.
>2018-06-05 22:05:16,406+02 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) [4f048fb6] EVENT_ID: VDS_INSTALL_IN_PROGRESS(509), Installing Host kvm02.qform.de. Stage: Setup validation.
>2018-06-05 22:05:16,664+02 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) [4f048fb6] EVENT_ID: VDS_INSTALL_IN_PROGRESS(509), Installing Host kvm02.qform.de. Hardware supports virtualization.
>2018-06-05 22:05:16,874+02 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) [4f048fb6] EVENT_ID: VDS_INSTALL_IN_PROGRESS(509), Installing Host kvm02.qform.de. Stage: Transaction setup.
>2018-06-05 22:05:16,918+02 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) [4f048fb6] EVENT_ID: VDS_INSTALL_IN_PROGRESS(509), Installing Host kvm02.qform.de. Stage: Misc configuration.
>2018-06-05 22:05:16,926+02 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) [4f048fb6] EVENT_ID: VDS_INSTALL_IN_PROGRESS(509), Installing Host kvm02.qform.de. Stage: Package installation.
>2018-06-05 22:05:29,071+02 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) [4f048fb6] EVENT_ID: VDS_INSTALL_IN_PROGRESS(509), Installing Host kvm02.qform.de. Stage: Misc configuration.
>2018-06-05 22:05:31,005+02 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) [4f048fb6] EVENT_ID: VDS_INSTALL_IN_PROGRESS(509), Installing Host kvm02.qform.de. Enrolling certificate.
>2018-06-05 22:05:32,175+02 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) [4f048fb6] EVENT_ID: VDS_INSTALL_IN_PROGRESS(509), Installing Host kvm02.qform.de. Enrolling serial console certificate.
>2018-06-05 22:05:34,206+02 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) [4f048fb6] EVENT_ID: VDS_INSTALL_IN_PROGRESS(509), Installing Host kvm02.qform.de. Stage: Transaction commit.
>2018-06-05 22:05:34,256+02 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) [4f048fb6] EVENT_ID: VDS_INSTALL_IN_PROGRESS(509), Installing Host kvm02.qform.de. Setting kernel arguments.
>2018-06-05 22:05:34,322+02 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) [4f048fb6] EVENT_ID: VDS_INSTALL_IN_PROGRESS(509), Installing Host kvm02.qform.de. Stage: Closing up.
>2018-06-05 22:05:35,670+02 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) [4f048fb6] EVENT_ID: VDS_INSTALL_IN_PROGRESS(509), Installing Host kvm02.qform.de. Starting Cockpit.
>2018-06-05 22:05:35,998+02 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) [4f048fb6] EVENT_ID: VDS_INSTALL_IN_PROGRESS(509), Installing Host kvm02.qform.de. Restarting libvirtd.
>2018-06-05 22:05:36,571+02 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) [4f048fb6] EVENT_ID: VDS_INSTALL_IN_PROGRESS(509), Installing Host kvm02.qform.de. Starting vdsm.
>2018-06-05 22:05:49,283+02 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) [4f048fb6] EVENT_ID: VDS_INSTALL_IN_PROGRESS(509), Installing Host kvm02.qform.de. Starting ovirt-vmconsole-host-sshd.
>2018-06-05 22:05:49,487+02 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) [4f048fb6] EVENT_ID: VDS_INSTALL_IN_PROGRESS(509), Installing Host kvm02.qform.de. Stage: Clean up.
>2018-06-05 22:05:49,496+02 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) [4f048fb6] EVENT_ID: VDS_INSTALL_IN_PROGRESS(509), Installing Host kvm02.qform.de. Stage: Pre-termination.
>2018-06-05 22:05:49,512+02 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) [4f048fb6] EVENT_ID: VDS_INSTALL_IN_PROGRESS(509), Installing Host kvm02.qform.de. Retrieving installation logs to: '/var/log/ovirt-engine/host-deploy/ovirt-host-deploy-20180605220549-kvm02.qform.de-4f048fb6.log'.
>2018-06-05 22:05:49,812+02 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (VdsDeploy) [4f048fb6] EVENT_ID: VDS_INSTALL_IN_PROGRESS(509), Installing Host kvm02.qform.de. Stage: Termination.
>2018-06-05 22:05:49,886+02 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (EE-ManagedThreadFactory-engine-Thread-143307) [4f048fb6] EVENT_ID: VDS_ANSIBLE_INSTALL_STARTED(560), Ansible host-deploy playbook execution has started on host kvm02.qform.de.
>2018-06-05 22:05:49,887+02 INFO  [org.ovirt.engine.core.common.utils.ansible.AnsibleExecutor] (EE-ManagedThreadFactory-engine-Thread-143307) [4f048fb6] Executing Ansible command:  [/usr/bin/ansible-playbook, --ssh-common-args=-F /var/lib/ovirt-engine/.ssh/config, -v, --private-key=/etc/pki/ovirt-engine/keys/engine_id_rsa, --inventory=/tmp/ansible-inventory244955065887788675, --extra-vars=host_deploy_cluster_version=4.2, --extra-vars=host_deploy_cluster_name=Default, --extra-vars=host_deploy_gluster_enabled=false, --extra-vars=host_deploy_virt_enabled=true, --extra-vars=host_deploy_vdsm_port=54321, --extra-vars=host_deploy_override_firewall=true, --extra-vars=host_deploy_firewall_type=FIREWALLD, --extra-vars=ansible_port=22, --extra-vars=host_deploy_post_tasks=/etc/ovirt-engine/ansible/ovirt-host-deploy-post-tasks.yml, --extra-vars=host_deploy_ovn_tunneling_interface=192.168.102.94, --extra-vars=host_deploy_ovn_central=192.168.102.81, /usr/share/ovirt-engine/playbooks/ovirt-host-deploy.yml] [Logfile: /var/log/ovirt-engine/host-deploy/ovirt-host-deploy-ansible-20180605220549-kvm02.qform.de-4f048fb6.log]
>2018-06-05 22:05:55,746+02 INFO  [org.ovirt.engine.core.bll.ClearAllAuditLogAlertsCommand] (default task-42) [fa6e7712-2378-4f17-b442-ce88f64f0203] Running command: ClearAllAuditLogAlertsCommand internal: false. Entities affected :  ID: aaa00000-0000-0000-0000-123456789aaa Type: SystemAction group AUDIT_LOG_MANAGEMENT with role type ADMIN
>2018-06-05 22:05:55,775+02 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (default task-42) [fa6e7712-2378-4f17-b442-ce88f64f0203] EVENT_ID: USER_CLEAR_ALL_AUDIT_LOG_ALERTS(10,606), All alerts were removed. (User: admin@internal-authz)
>2018-06-05 22:06:02,681+02 INFO  [org.ovirt.engine.core.common.utils.ansible.AnsibleExecutor] (EE-ManagedThreadFactory-engine-Thread-143307) [4f048fb6] Ansible playbook command has exited with value: 0
>2018-06-05 22:06:02,690+02 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (EE-ManagedThreadFactory-engine-Thread-143307) [4f048fb6] EVENT_ID: VDS_ANSIBLE_INSTALL_FINISHED(561), Ansible host-deploy playbook execution has successfully finished on host kvm02.qform.de.
>2018-06-05 22:06:02,696+02 INFO  [org.ovirt.vdsm.jsonrpc.client.reactors.ReactorClient] (SSL Stomp Reactor) [] Connecting to kvm02.qform.de/192.168.102.94
>2018-06-05 22:06:02,791+02 INFO  [org.ovirt.engine.core.bll.host.HostConnectivityChecker] (EE-ManagedThreadFactory-engine-Thread-143307) [4f048fb6] Engine managed to communicate with VDSM agent on host 'kvm02.qform.de' with address 'kvm02.qform.de' ('01b668c5-069c-448f-be05-a16175a27c6b')
>2018-06-05 22:06:03,995+02 INFO  [org.ovirt.engine.core.bll.network.NetworkConfigurator] (EE-ManagedThreadFactory-engine-Thread-143307) [4f048fb6] The management network 'ovirtmgmt' is already configured on host 'kvm02.qform.de'
>2018-06-05 22:06:03,998+02 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (EE-ManagedThreadFactory-engine-Thread-143307) [4f048fb6] START, SetVdsStatusVDSCommand(HostName = kvm02.qform.de, SetVdsStatusVDSCommandParameters:{hostId='01b668c5-069c-448f-be05-a16175a27c6b', status='Initializing', nonOperationalReason='NONE', stopSpmFailureLogged='false', maintenanceReason='null'}), log id: 5508e0fb
>2018-06-05 22:06:04,009+02 INFO  [org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (EE-ManagedThreadFactory-engine-Thread-143307) [4f048fb6] FINISH, SetVdsStatusVDSCommand, log id: 5508e0fb
>2018-06-05 22:06:04,009+02 INFO  [org.ovirt.engine.core.bll.hostdeploy.InstallVdsInternalCommand] (EE-ManagedThreadFactory-engine-Thread-143307) [4f048fb6] After Installation host kvm02.qform.de, VDS
>2018-06-05 22:06:04,020+02 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (EE-ManagedThreadFactory-engine-Thread-143307) [4f048fb6] EVENT_ID: VDS_INSTALL(504), Host kvm02.qform.de installed
>2018-06-05 22:06:04,029+02 INFO  [org.ovirt.engine.core.bll.hostdeploy.InstallVdsInternalCommand] (EE-ManagedThreadFactory-engine-Thread-143307) [4f048fb6] Lock freed to object 'EngineLock:{exclusiveLocks='[01b668c5-069c-448f-be05-a16175a27c6b=VDS]', sharedLocks=''}'
>2018-06-05 22:06:04,943+02 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoAsyncVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-39) [] START, GetHardwareInfoAsyncVDSCommand(HostName = kvm02.qform.de, VdsIdAndVdsVDSCommandParametersBase:{hostId='01b668c5-069c-448f-be05-a16175a27c6b', vds='Host[kvm02.qform.de,01b668c5-069c-448f-be05-a16175a27c6b]'}), log id: 5c2e6a48
>2018-06-05 22:06:04,943+02 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoAsyncVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-39) [] FINISH, GetHardwareInfoAsyncVDSCommand, log id: 5c2e6a48
>2018-06-05 22:06:04,980+02 INFO  [org.ovirt.engine.core.bll.HandleVdsCpuFlagsOrClusterChangedCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-39) [360d6aa8] Running command: HandleVdsCpuFlagsOrClusterChangedCommand internal: true. Entities affected :  ID: 01b668c5-069c-448f-be05-a16175a27c6b Type: VDS
>2018-06-05 22:06:05,472+02 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoAsyncVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-92) [] START, GetHardwareInfoAsyncVDSCommand(HostName = kvm02.qform.de, VdsIdAndVdsVDSCommandParametersBase:{hostId='01b668c5-069c-448f-be05-a16175a27c6b', vds='Host[kvm02.qform.de,01b668c5-069c-448f-be05-a16175a27c6b]'}), log id: 675e1aee
>2018-06-05 22:06:05,472+02 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoAsyncVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-92) [] FINISH, GetHardwareInfoAsyncVDSCommand, log id: 675e1aee
>2018-06-05 22:06:05,568+02 INFO  [org.ovirt.engine.core.bll.InitVdsOnUpCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-92) [328921a] Running command: InitVdsOnUpCommand internal: true. Entities affected :  ID: 1ef4518e-6383-11e8-83ff-00163e1ef844 Type: StoragePool
>2018-06-05 22:06:05,611+02 INFO  [org.ovirt.engine.core.bll.storage.pool.ConnectHostToStoragePoolServersCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-92) [3820ff25] Running command: ConnectHostToStoragePoolServersCommand internal: true. Entities affected :  ID: 1ef4518e-6383-11e8-83ff-00163e1ef844 Type: StoragePool
>2018-06-05 22:06:05,617+02 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-92) [3820ff25] START, ConnectStorageServerVDSCommand(HostName = kvm02.qform.de, StorageServerConnectionManagementVDSParameters:{hostId='01b668c5-069c-448f-be05-a16175a27c6b', storagePoolId='1ef4518e-6383-11e8-83ff-00163e1ef844', storageType='NFS', connectionList='[StorageServerConnections:{id='5716c871-7554-446a-b639-3f71208319ae', connection='ceph02:/cephfile', iqn='null', vfsType='null', mountOptions='', nfsVersion='V3', nfsRetrans='null', nfsTimeo='null', iface='null', netIfaceName='null'}]'}), log id: 1c24605d
>2018-06-05 22:06:05,747+02 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStorageServerVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-92) [3820ff25] FINISH, ConnectStorageServerVDSCommand, return: {5716c871-7554-446a-b639-3f71208319ae=0}, log id: 1c24605d
>2018-06-05 22:06:05,749+02 INFO  [org.ovirt.engine.core.bll.storage.pool.ConnectHostToStoragePoolServersCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-92) [3820ff25] Host 'kvm02.qform.de' storage connection was succeeded 
>2018-06-05 22:06:05,764+02 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (EE-ManagedThreadFactory-engine-Thread-143320) [3820ff25] START, ConnectStoragePoolVDSCommand(HostName = kvm02.qform.de, ConnectStoragePoolVDSCommandParameters:{hostId='01b668c5-069c-448f-be05-a16175a27c6b', vdsId='01b668c5-069c-448f-be05-a16175a27c6b', storagePoolId='1ef4518e-6383-11e8-83ff-00163e1ef844', masterVersion='1'}), log id: aac1598
>2018-06-05 22:06:05,764+02 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (EE-ManagedThreadFactory-engine-Thread-143320) [3820ff25] Executing with domain map: {b8c8bd16-ccea-4c58-96f2-0f981f933ba8=active}
>2018-06-05 22:06:05,967+02 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.ConnectStoragePoolVDSCommand] (EE-ManagedThreadFactory-engine-Thread-143320) [3820ff25] FINISH, ConnectStoragePoolVDSCommand, log id: aac1598
>2018-06-05 22:06:05,986+02 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (EE-ManagedThreadFactory-engine-Thread-143320) [3820ff25] EVENT_ID: NO_FAULTY_MULTIPATHS_ON_HOST(11,501), No faulty multipath paths on host kvm02.qform.de
>2018-06-05 22:06:06,005+02 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.SetMOMPolicyParametersVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-92) [3820ff25] START, SetMOMPolicyParametersVDSCommand(HostName = kvm02.qform.de, MomPolicyVDSParameters:{hostId='01b668c5-069c-448f-be05-a16175a27c6b'}), log id: 225fed9c
>2018-06-05 22:06:06,033+02 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.SetMOMPolicyParametersVDSCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-92) [3820ff25] FINISH, SetMOMPolicyParametersVDSCommand, log id: 225fed9c
>2018-06-05 22:06:06,123+02 INFO  [org.ovirt.engine.core.bll.hostdev.RefreshHostDevicesCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-92) [2a41c61d] Running command: RefreshHostDevicesCommand internal: true. Entities affected :  ID: 01b668c5-069c-448f-be05-a16175a27c6b Type: VDSAction group MANIPULATE_HOST with role type ADMIN
>2018-06-05 22:06:06,412+02 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (EE-ManagedThreadFactory-engineScheduled-Thread-92) [2a41c61d] EVENT_ID: VDS_DETECTED(13), Status of host kvm02.qform.de was set to Up.
>2018-06-05 22:06:06,421+02 WARN  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (EE-ManagedThreadFactory-engineScheduled-Thread-92) [2a41c61d] EVENT_ID: VDS_ALERT_FENCE_IS_NOT_CONFIGURED(9,000), Failed to verify Power Management configuration for Host kvm02.qform.de.
>2018-06-05 22:06:06,482+02 INFO  [org.ovirt.engine.core.bll.HandleVdsCpuFlagsOrClusterChangedCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-92) [50a41d3] Running command: HandleVdsCpuFlagsOrClusterChangedCommand internal: true. Entities affected :  ID: 01b668c5-069c-448f-be05-a16175a27c6b Type: VDS
>2018-06-05 22:06:06,486+02 INFO  [org.ovirt.engine.core.bll.HandleVdsVersionCommand] (EE-ManagedThreadFactory-engineScheduled-Thread-92) [106618aa] Running command: HandleVdsVersionCommand internal: true. Entities affected :  ID: 01b668c5-069c-448f-be05-a16175a27c6b Type: VDS
>2018-06-05 22:06:10,430+02 INFO  [org.ovirt.engine.core.vdsbroker.monitoring.VmsStatisticsFetcher] (EE-ManagedThreadFactory-engineScheduled-Thread-49) [] Fetched 0 VMs from VDS '01b668c5-069c-448f-be05-a16175a27c6b'

Actions: View

Attachments on bug 1584237: 1445912 | 1447979 | 1447980 | 1449916 | 1451950 | 1451951 | 1451953 | 1454140 | 1454141