Bug 1373456
| Summary: | Debug message for start and end of DWH heartbeat | ||
|---|---|---|---|
| Product: | [oVirt] ovirt-engine | Reporter: | Shirly Radco <sradco> |
| Component: | Database.Core | Assignee: | Eli Mesika <emesika> |
| Status: | CLOSED CURRENTRELEASE | QA Contact: | Lukas Svaty <lsvaty> |
| Severity: | high | Docs Contact: | |
| Priority: | high | ||
| Version: | 4.1.0 | CC: | bugs, mgoldboi, mperina, oourfali, pstehlik |
| Target Milestone: | ovirt-4.0.5 | Flags: | rule-engine:
ovirt-4.0.z+
mgoldboi: planning_ack+ rule-engine: devel_ack+ pstehlik: testing_ack+ |
| Target Release: | 4.0.5 | ||
| Hardware: | Unspecified | ||
| OS: | Unspecified | ||
| Whiteboard: | |||
| Fixed In Version: | Doc Type: | If docs needed, set a value | |
| Doc Text: | Story Points: | --- | |
| Clone Of: | 1371111 | Environment: | |
| Last Closed: | 2017-01-18 07:39:53 UTC | Type: | Bug |
| Regression: | --- | Mount Type: | --- |
| Documentation: | --- | CRM: | |
| Verified Versions: | Category: | --- | |
| oVirt Team: | Infra | RHEL 7.3 requirements from Atomic Host: | |
| Cloudforms Team: | --- | Target Upstream Version: | |
| Embargoed: | |||
| Bug Depends On: | 1371111, 1430666 | ||
| Bug Blocks: | |||
|
Description
Shirly Radco
2016-09-06 10:36:58 UTC
This is a normal scheduler job in DwhHeartBeat.java
I checked the engine log in rhev-tlv today and did not found this issue
Anyway, there are two options here
Either it is a scheduler issue or it is a DB issue
I recommend to add some logging in order to determine who is responsible for that delay
The code is :
public void engineIsRunningNotification() {
try {
// TODO : add logging here
heartBeatVar.setDateTime(new Date());
dwhHistoryTimekeepingDao.save(heartBeatVar);
// TODO : add logging here
} catch (Exception ex) {
log.error("Error updating DWH Heart Beat: {}", ex.getMessage());
log.debug("Exception", ex);
}
}
}
See my TODO comments inside the code, this will enable us at least to track the log and see the invocation time as well as the time this change was saved to the database
Reducing severity, as it will be treated also via Bug #1371111. I agree with Eli that logging will indeed help here analyze the issue. (In reply to Eli Mesika from comment #1) > This is a normal scheduler job in DwhHeartBeat.java > I checked the engine log in rhev-tlv today and did not found this issue It happens several times a day in RHEV.TLV. We have added additional logging to find out what causes the issue/ (In reply to Martin Perina from comment #4) > We have added additional logging to find out what causes the issue/ Please note that new log messages are DEBUG messages , so you have to turn on the DEBUG logging mode verified in ovirt-engine-4.0.5-0.1.el7ev.noarch 2016-10-13 10:47:18,627 INFO [org.ovirt.engine.core.bll.dwh.DwhHeartBeat] (ServerService Thread Pool -- 67) [] Initializing DWH Heart Beat 2016-10-13 10:47:18,628 INFO [org.ovirt.engine.core.bll.dwh.DwhHeartBeat] (ServerService Thread Pool -- 67) [] DWH Heart Beat initialized 2016-10-13 10:47:18,628 INFO [org.ovirt.engine.core.bll.InitBackendServicesOnStartupBean] (ServerService Thread Pool -- 67) [] Start org.ovirt.engine.core.bll.dwh.DwhHeartBeat@31cb7143 2016-10-13 10:47:18,650 DEBUG [org.ovirt.engine.core.bll.dwh.DwhHeartBeat] (DefaultQuartzScheduler4) [] DWH Heart Beat - Start 2016-10-13 10:47:18,696 DEBUG [org.ovirt.engine.core.bll.dwh.DwhHeartBeat] (DefaultQuartzScheduler4) [] DWH Heart Beat - End 2016-10-13 10:47:33,697 DEBUG [org.ovirt.engine.core.bll.dwh.DwhHeartBeat] (DefaultQuartzScheduler8) [] DWH Heart Beat - Start 2016-10-13 10:47:33,703 DEBUG [org.ovirt.engine.core.bll.dwh.DwhHeartBeat] (DefaultQuartzScheduler8) [] DWH Heart Beat - End 2016-10-13 10:47:48,705 DEBUG [org.ovirt.engine.core.bll.dwh.DwhHeartBeat] (DefaultQuartzScheduler2) [] DWH Heart Beat - Start 2016-10-13 10:47:48,712 DEBUG [org.ovirt.engine.core.bll.dwh.DwhHeartBeat] (DefaultQuartzScheduler2) [] DWH Heart Beat - End |