Bug 1321064 - repeatedly calling GetHardwareInfoVDSCommand() for a host that is up and not changing (every 15 seconds)
Summary: repeatedly calling GetHardwareInfoVDSCommand() for a host that is up and not ...
Keywords:
Status: CLOSED NOTABUG
Alias: None
Product: ovirt-engine
Classification: oVirt
Component: BLL.Infra
Version: 4.0.0
Hardware: Unspecified
OS: Unspecified
unspecified
high vote
Target Milestone: ---
: ---
Assignee: Oved Ourfali
QA Contact: Pavel Stehlik
URL:
Whiteboard:
Depends On:
Blocks:
TreeView+ depends on / blocked
 
Reported: 2016-03-24 15:18 UTC by Yaniv Kaul
Modified: 2016-04-13 13:17 UTC (History)
5 users (show)

Fixed In Version:
Doc Type: Bug Fix
Doc Text:
Clone Of:
Environment:
Last Closed: 2016-04-13 13:17:47 UTC
oVirt Team: Infra
rule-engine: planning_ack?
rule-engine: devel_ack?
rule-engine: testing_ack?


Attachments (Terms of Use)

Description Yaniv Kaul 2016-03-24 15:18:49 UTC
Description of problem:
I've installed a host, and didn't do much with it, but I see it's continuously asked:

2016-03-24 08:55:21,436 INFO  [org.ovirt.engine.core.dal.dbbroker.auditloghandling.AuditLogDirector] (DefaultQuartzScheduler_Worker-93) [c51fd9c] Correlation ID: null, Call Stack: null, Custom Event ID: -1, Mess
age: Status of host lago_basic_suite_master_host1 was set to Up.

...


2016-03-24 09:38:22,659 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (ForkJoinPool-1-worker-1) [4caedb42] START, GetHardwareInfoVDSCommand(HostName = lago_basic_suite_master_host1, VdsIdAndVdsVDSCommandParametersBase:{runAsync='true', hostId='4e27eade-5df5-4eab-8b43-ab9b6227210e', vds='Host[lago_basic_suite_master_host1,4e27eade-5df5-4eab-8b43-ab9b6227210e]'}), log id: 16795e53
2016-03-24 09:38:23,663 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (ForkJoinPool-1-worker-1) [4caedb42] FINISH, GetHardwareInfoVDSCommand, log id: 16795e53
2016-03-24 09:38:23,673 INFO  [org.ovirt.engine.core.bll.HandleVdsCpuFlagsOrClusterChangedCommand] (ForkJoinPool-1-worker-1) [47558107] Running command: HandleVdsCpuFlagsOrClusterChangedCommand internal: true. Entities affected :  ID: 4e27eade-5df5-4eab-8b43-ab9b6227210e Type: VDS
2016-03-24 09:38:23,675 INFO  [org.ovirt.engine.core.bll.HandleVdsVersionCommand] (ForkJoinPool-1-worker-1) [5b4c0d3c] Running command: HandleVdsVersionCommand internal: true. Entities affected :  ID: 4e27eade-5df5-4eab-8b43-ab9b6227210e Type: VDS
2016-03-24 09:38:37,665 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (ForkJoinPool-1-worker-1) [5b4c0d3c] START, GetHardwareInfoVDSCommand(HostName = lago_basic_suite_master_host1, VdsIdAndVdsVDSCommandParametersBase:{runAsync='true', hostId='4e27eade-5df5-4eab-8b43-ab9b6227210e', vds='Host[lago_basic_suite_master_host1,4e27eade-5df5-4eab-8b43-ab9b6227210e]'}), log id: 2f053b3c
2016-03-24 09:38:37,668 INFO  [org.ovirt.engine.core.vdsbroker.vdsbroker.GetHardwareInfoVDSCommand] (ForkJoinPool-1-worker-1) [5b4c0d3c] FINISH, GetHardwareInfoVDSCommand, log id: 2f053b3c
2016-03-24 09:38:37,681 INFO  [org.ovirt.engine.core.bll.HandleVdsCpuFlagsOrClusterChangedCommand] (ForkJoinPool-1-worker-1) [6fee838b] Running command: HandleVdsCpuFlagsOrClusterChangedCommand internal: true. Entities affected :  ID: 4e27eade-5df5-4eab-8b43-ab9b6227210e Type: VDS
2016-03-24 09:38:37,684 INFO  [org.ovirt.engine.core.bll.HandleVdsVersionCommand] (ForkJoinPool-1-worker-1) [182f10d4] Running command: HandleVdsVersionCommand internal: true. Entities affected :  ID: 4e27eade-5df5-4eab-8b43-ab9b6227210e Type: VDS 


Version-Release number of selected component (if applicable):
ovirt-engine-4.0.0-0.0.master.20160323171719.git71fa3c8.el7.centos.noarch

How reproducible:
Did it via Lago, not sure how - can try later.

Steps to Reproduce:
1.
2.
3.

Actual results:


Expected results:


Additional info:

Comment 1 Dan Kenigsberg 2016-03-27 07:00:32 UTC
GetHardwareInfoVDSCommand is more of an infra verb. Bronheim, can you tell why is it called so often?

I do not think that it is related to https://gerrit.ovirt.org/#/c/54644/

Comment 2 Yaniv Bronhaim 2016-03-27 08:45:44 UTC
Yes this is infra. I see that nothing changed regarding who uses this call - only in VdsManager::refreshCaps triggers it every 15sec indeed. Do you want to change this behavior?

Comment 3 Yaniv Kaul 2016-03-27 09:01:37 UTC
(In reply to Yaniv Bronhaim from comment #2)
> Yes this is infra. I see that nothing changed regarding who uses this call -
> only in VdsManager::refreshCaps triggers it every 15sec indeed. Do you want
> to change this behavior?

Any reason to call it every 15 seconds? It floods the logs, and I see no value in it.

Comment 4 Moti Asayag 2016-03-27 11:57:01 UTC
Please provide the entire engine.log.

The GetHardwareInfoVDSCommand verb is called only as part of refreshCapibilities which has no periodic scheduled job - it is supposed to be called only on certain cases.

Comment 5 Yaniv Bronhaim 2016-03-28 14:21:11 UTC
It is under the interval part but not always the refresh calls to getCaps which calls to getHardwareInfo, there are some statuses that refresh doesn't check the capabilities such as UP and Maintenance. 
Logs both from engine and vdsm can assist to understand

Comment 6 Martin Perina 2016-04-13 13:16:33 UTC
Yaniv, could you please logs so we can investigate the issue?

Comment 7 Yaniv Kaul 2016-04-13 13:17:47 UTC
Will re-open if I see it again. Failed to even install hosts on master for way too long :(


Note You need to log in before you can comment on or make changes to this bug.