[ovirt-devel] Help debugging engine.log

Mon Apr 28 08:38:44 UTC 2014

Il 18/04/2014 16:03, Sandro Bonazzola ha scritto:
> 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?

Any hint?

> 
> 
> 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
> 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
> 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.
> 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
> 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
> 2014-04-18 17:21:59,223 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.SetMOMPolicyParametersVDSCommand] (DefaultQuartzScheduler_Worker-3)
> [3a5f7d19] FINISH, SetMOMPolicyParametersVDSCommand, log id: 36c8962e
> 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.
> 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
> 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 at 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 at 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 at internal logged in.
> 
> 
> Thanks,
> 
> 

-- 
Sandro Bonazzola
Better technology. Faster innovation. Powered by community collaboration.
See how it works at redhat.com