----- Original Message -----
From: "Sandro Bonazzola" <sbonazzo(a)redhat.com>
To: devel(a)ovirt.org
Sent: Friday, April 18, 2014 5:03:00 PM
Subject: [ovirt-devel] Help debugging engine.log
the following lines appear after adding the first host to an engine running
inside Hosted Engine VM.
Can anybody tell what the following lines are meaning?
2014-04-18 17:21:57,223 INFO
[org.ovirt.engine.core.vdsbroker.SetVdsStatusVDSCommand] (pool-4-thread-5)
[751509c7] FINISH, SetVdsStatusVDSCommand,
log id: 6bb07f05
2014-04-18 17:21:57,223 INFO [org.ovirt.engine.core.bll.InstallVdsCommand]
(pool-4-thread-5) [751509c7] After Installation host hosted_engine_1, VDS
2014-04-18 17:21:57,243 INFO
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(pool-4-thread-5) [751509c7] Correlation ID:
751509c7, Job ID: c7abd67c-5655-4ea8-8226-c51eda9c9675, Call Stac
k: null, Custom Event ID: -1, Message: Host hosted_engine_1 installed
The hosted_engine_1 host has been bootstrapped.
2014-04-18 17:21:57,255 INFO
[org.ovirt.engine.core.bll.InstallVdsCommand]
(pool-4-thread-5) [751509c7] Lock freed to object EngineLock
[exclusiveLocks= key: f609e5f8-1676-4e87-98ec-9477afc27557 value: VDS
, sharedLocks= ]
2014-04-18 17:21:58,764 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand]
(DefaultQuartzScheduler_Worker-3) START,
GetHardwareInfoVDSCommand(HostName = hosted_engine_1, HostId = f609e5f
8-1676-4e87-98ec-9477afc27557, vds=Host[hosted_engine_1]), log id: 496d80d8
2014-04-18 17:21:58,834 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand]
(DefaultQuartzScheduler_Worker-3) FINISH,
GetHardwareInfoVDSCommand, log id: 496d80d8
2014-04-18 17:21:58,944 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand]
(DefaultQuartzScheduler_Worker-3) START,
GetHardwareInfoVDSCommand(HostName = hosted_engine_1, HostId =
f609e5f8-1676-4e87-98ec-9477afc27557, vds=Host[hosted_engine_1]), log id:
36957393
2014-04-18 17:21:58,971 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand]
(DefaultQuartzScheduler_Worker-3) FINISH,
GetHardwareInfoVDSCommand, log id: 36957393
Getting the hardware info finished.
2014-04-18 17:21:58,989 INFO
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(DefaultQuartzScheduler_Worker-3) Correlation ID:
null, Call Stack: null, Custom Event ID: -1, Message: State was set to Up for
host hosted_engine_1.
Host status was set to up.
2014-04-18 17:21:59,058 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand]
(DefaultQuartzScheduler_Worker-3) START,
FullListVdsCommand(HostName = hosted_engine_1, HostId =
f609e5f8-1676-4e87-98ec-9477afc27557, vds=Host[hosted_engine_1],
vmIds=[950f8a3a-82af-478e-8f99-9cd0556b9cfc]), log id: 16b792bc
2014-04-18 17:21:59,095 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand]
(DefaultQuartzScheduler_Worker-3) FINISH,
FullListVdsCommand, return: [Ljava.util.HashMap;@3a6e1bc2, log id: 16b792bc
Listing VMs on the host.
2014-04-18 17:21:59,096 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-3) Importing VM HostedEngine as
HostedEngine, as it is running on the on Host, but does not exist in the
engine.
2014-04-18 17:21:59,122 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-3) VDS::UpdateVmRunTimeInfo Error:
found VM on a VDS that is not in the database!
2014-04-18 17:21:59,137 INFO [org.ovirt.engine.core.bll.InitVdsOnUpCommand]
(DefaultQuartzScheduler_Worker-3) [3a5f7d19] Running command:
InitVdsOnUpCommand internal: true. Entities affected : ID:
5849b030-626e-47cb-ad90-3ce782d831b3 Type: StoragePool
2014-04-18 17:21:59,144 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.SetMOMPolicyParametersVDSCommand]
(DefaultQuartzScheduler_Worker-3)
[3a5f7d19] START, SetMOMPolicyParametersVDSCommand(HostName =
hosted_engine_1, HostId = f609e5f8-1676-4e87-98ec-9477afc27557), log id:
36c8962e
Identified the hosted engine VM, and importing it.
2014-04-18 17:21:59,223 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.SetMOMPolicyParametersVDSCommand]
(DefaultQuartzScheduler_Worker-3)
[3a5f7d19] FINISH, SetMOMPolicyParametersVDSCommand, log id: 36c8962e
Don't know about that.
2014-04-18 17:21:59,228 INFO
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(DefaultQuartzScheduler_Worker-3) [3a5f7d19]
Correlation ID: null, Call Stack: null, Custom Event ID: -1, Message: Failed
to verify Power Management configuration for Host hosted_engine_1.
You didn't define power management for this host, so it warns about that.
2014-04-18 17:21:59,389 INFO
[org.ovirt.engine.core.bll.AddVmFromScratchCommand]
(DefaultQuartzScheduler_Worker-3) [266af7f3] Lock Acquired to object
EngineLock [exclusiveLocks= key: HostedEngine value: VM_NAME
, sharedLocks= ]
2014-04-18 17:21:59,453 INFO
[org.ovirt.engine.core.bll.AddVmFromScratchCommand]
(DefaultQuartzScheduler_Worker-3) [266af7f3] Running command:
AddVmFromScratchCommand internal: true. Entities affected : ID:
99408929-82cf-4dc7-a532-9d998063fa95 Type: VdsGroups
2014-04-18 17:21:59,707 INFO
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(DefaultQuartzScheduler_Worker-3) [266af7f3]
Correlation ID: 266af7f3, Job ID: 2c46a619-bc01-4b85-96bd-d3fc5e073901, Call
Stack: null, Custom Event ID: -1, Message: VM HostedEngine was created by
<UNKNOWN>.
2014-04-18 17:21:59,714 INFO
[org.ovirt.engine.core.bll.AddVmFromScratchCommand]
(DefaultQuartzScheduler_Worker-3) [266af7f3] Lock freed to object
EngineLock [exclusiveLocks= key: HostedEngine value: VM_NAME
, sharedLocks= ]
2014-04-18 17:21:59,718 INFO
[org.ovirt.engine.core.bll.HandleVdsCpuFlagsOrClusterChangedCommand]
(DefaultQuartzScheduler_Worker-3) [5e0dc4d1]
Running command: HandleVdsCpuFlagsOrClusterChangedCommand internal: true.
Entities affected : ID: f609e5f8-1676-4e87-98ec-9477afc27557 Type: VDS
iirc, importing the existing VM is like adding a new one in some way, so that's what
you see here.
2014-04-18 17:21:59,766 INFO
[org.ovirt.engine.core.bll.UpdateVdsGroupCommand]
(DefaultQuartzScheduler_Worker-3) [32fd282a] Running command:
UpdateVdsGroupCommand internal: true. Entities affected : ID:
99408929-82cf-4dc7-a532-9d998063fa95 Type: VdsGroups
2014-04-18 17:22:00,015 INFO
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(DefaultQuartzScheduler_Worker-3) [32fd282a]
Correlation ID: 32fd282a, Call Stack: null, Custom Event ID: -1, Message:
Host cluster Default was updated by system
2014-04-18 17:22:00,032 INFO
[org.ovirt.engine.core.bll.HandleVdsVersionCommand]
(DefaultQuartzScheduler_Worker-3) [4f38fad3] Running command:
HandleVdsVersionCommand internal: true. Entities affected : ID:
f609e5f8-1676-4e87-98ec-9477afc27557 Type: VDS
2014-04-18 17:22:03,100 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand]
(DefaultQuartzScheduler_Worker-6) START,
FullListVdsCommand(HostName = hosted_engine_1, HostId =
f609e5f8-1676-4e87-98ec-9477afc27557, vds=Host[hosted_engine_1],
vmIds=[950f8a3a-82af-478e-8f99-9cd0556b9cfc]), log id: 4148c04c
2014-04-18 17:22:03,129 INFO
[org.ovirt.engine.core.vdsbroker.vdsbroker.FullListVdsCommand]
(DefaultQuartzScheduler_Worker-6) FINISH,
FullListVdsCommand, return: [Ljava.util.HashMap;@5f864ed0, log id: 4148c04c
2014-04-18 17:22:03,131 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-6) Received a console Device
without an address when processing VM 950f8a3a-82af-478e-8f99-9cd0556b9cfc
devices, skipping device: {alias=console0, specParams={}, device=console,
type=console, deviceId=c627f372-2893-4b79-b534-f51c86374d1b}
2014-04-18 17:22:03,131 INFO
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-6) Received a memballoon Device
without an address when processing VM 950f8a3a-82af-478e-8f99-9cd0556b9cfc
devices, skipping device: {alias=balloon0, specParams={model=none},
device=memballoon, type=balloon}
2014-04-18 17:22:03,132 ERROR
[org.ovirt.engine.core.vdsbroker.VdsUpdateRunTimeInfo]
(DefaultQuartzScheduler_Worker-6) Empty or NULL values were
passed for a VM 950f8a3a-82af-478e-8f99-9cd0556b9cfc device, Device is
skipped
2014-04-18 17:53:16,713 INFO [org.ovirt.engine.core.bll.LogoutUserCommand]
(ajp-/127.0.0.1:8702-5) [3829e38e] Running command: LogoutUserCommand
internal: false.
2014-04-18 17:53:16,725 INFO
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(ajp-/127.0.0.1:8702-5) [3829e38e] Correlation
ID: 3829e38e, Call Stack: null, Custom Event ID: -1, Message: User
admin@internal logged out.
2014-04-18 17:54:28,145 INFO
[org.ovirt.engine.core.bll.LoginAdminUserCommand] (ajp-/127.0.0.1:8702-4)
Running command: LoginAdminUserCommand
internal: false.
2014-04-18 17:54:28,149 INFO
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(ajp-/127.0.0.1:8702-4) Correlation ID: null,
Call Stack: null, Custom Event ID: -1, Message: User admin@internal logged
in.
2014-04-18 17:54:28,207 INFO [org.ovirt.engine.core.bll.LoginUserCommand]
(ajp-/127.0.0.1:8702-8) Running command: LoginUserCommand internal: false.
2014-04-18 17:54:28,212 INFO
[org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector]
(ajp-/127.0.0.1:8702-8) Correlation ID: null,
Call Stack: null, Custom Event ID: -1, Message: User admin@internal logged
in.
Other messages show you updated the cluster, did some logging in and out,
VdsUpdateRunTimeInfo was run several times (to get the updated host info, vms, status,
etc.).
Hope I was helpful,
Oved
Thanks,
--
Sandro Bonazzola
Better technology. Faster innovation. Powered by community collaboration.
See how it works at
redhat.com
_______________________________________________
Devel mailing list
Devel(a)ovirt.org
http://lists.ovirt.org/mailman/listinfo/devel