The version I am using is 2.12.3.
root@icinga-qa:~>>icinga2 --version
icinga2 - The Icinga 2 network monitoring daemon (version: 2.12.3)
Copyright (c) 2012-2021 Icinga GmbH (https://icinga.com/)
License GPLv2+: GNU GPL version 2 or later <http://gnu.org/licenses/gpl2.html>
This is free software: you are free to change and redistribute it.
There is NO WARRANTY, to the extent permitted by law.
System information:
Platform: Red Hat Enterprise Linux Server
Platform version: 7.8 (Maipo)
Kernel: Linux
Kernel version: 3.10.0-1127.10.1.el7.x86_64
Architecture: x86_64
Build information:
Compiler: GNU 4.8.5
Build host: runner-hh8q3bz2-project-322-concurrent-0
OpenSSL version: OpenSSL 1.0.2k-fips 26 Jan 2017
Application information:
General paths:
Config directory: /etc/icinga2
Data directory: /var/lib/icinga2
Log directory: /var/log/icinga2
Cache directory: /var/cache/icinga2
Spool directory: /var/spool/icinga2
Run directory: /run/icinga2
Old paths (deprecated):
Installation root: /usr
Sysconf directory: /etc
Run directory (base): /run
Local state directory: /var
Internal paths:
Package data directory: /usr/share/icinga2
State path: /var/lib/icinga2/icinga2.state
Modified attributes path: /var/lib/icinga2/modified-attributes.conf
Objects path: /var/cache/icinga2/icinga2.debug
Vars path: /var/cache/icinga2/icinga2.vars
PID path: /run/icinga2/icinga2.pid
This is the part of the debug.log when the service check “test_ping” on “Test 192.168.1.22” is manually triggered:
[2021-03-26 11:15:26 +0100] debug/IdoMysqlConnection: Query: COMMIT
[2021-03-26 11:15:26 +0100] debug/IdoMysqlConnection: Query: BEGIN
[2021-03-26 11:15:27 +0100] debug/IdoMysqlConnection: Query: COMMIT
[2021-03-26 11:15:27 +0100] debug/IdoMysqlConnection: Query: BEGIN
[2021-03-26 11:15:28 +0100] debug/IdoMysqlConnection: Query: COMMIT
[2021-03-26 11:15:28 +0100] debug/IdoMysqlConnection: Query: BEGIN
[2021-03-26 11:15:28 +0100] information/ExternalCommandListener: Executing external command: [1616753728] SCHEDULE_FORCED_SVC_CHECK;Test 192.168.1.22;test_ping;1616753728
[2021-03-26 11:15:28 +0100] notice/ExternalCommandProcessor: Rescheduling next check for service 'test_ping'
[2021-03-26 11:15:28 +0100] notice/ApiListener: Relaying 'event::SetForceNextCheck' message
[2021-03-26 11:15:28 +0100] notice/ApiListener: Sending message 'event::SetForceNextCheck' to 'icinga-qa-satellite-01.domain'
[2021-03-26 11:15:28 +0100] notice/ApiListener: Relaying 'event::SetNextCheck' message
[2021-03-26 11:15:28 +0100] notice/ApiListener: Sending message 'event::SetNextCheck' to 'icinga-qa-satellite-01.domain'
[2021-03-26 11:15:29 +0100] debug/IdoMysqlConnection: Query: UPDATE icinga_servicestatus SET next_check = FROM_UNIXTIME(1616753728) WHERE service_object_id = 4856
[2021-03-26 11:15:29 +0100] debug/IdoMysqlConnection: Query: COMMIT
[2021-03-26 11:15:29 +0100] debug/IdoMysqlConnection: Query: BEGIN
[2021-03-26 11:15:29 +0100] notice/CheckerComponent: Pending checkables: 0; Idle checkables: 189; Checks/s: 0.483333
[2021-03-26 11:15:30 +0100] debug/InfluxdbWriter: Timer expired writing 0 data points
[2021-03-26 11:15:30 +0100] notice/DbConnection: Updating programstatus table.
[2021-03-26 11:15:30 +0100] debug/ApiListener: Not connecting to Endpoint 'icinga-qa-satellite-01.domain' because we're already connected to it.
[2021-03-26 11:15:30 +0100] debug/ApiListener: Not connecting to Endpoint 'icinga-qa.domain' because that's us.
[2021-03-26 11:15:30 +0100] notice/ApiListener: Current zone master: icinga-qa.domain
[2021-03-26 11:15:30 +0100] notice/ApiListener: Connected endpoints: icinga-qa-satellite-01.domain (1)
[2021-03-26 11:15:30 +0100] notice/ApiListener: Updating object authority for objects at endpoint 'icinga-qa.domain'.
[2021-03-26 11:15:30 +0100] debug/IdoMysqlConnection: Query: DELETE FROM icinga_programstatus WHERE instance_id = 1
[2021-03-26 11:15:30 +0100] debug/IdoMysqlConnection: Query: INSERT INTO icinga_programstatus (active_host_checks_enabled, active_service_checks_enabled, daemon_mode, endpoint_name, event_handlers_enabled, flap_detection_enabled, instance_id, is_currently_running, last_command_check, notifications_enabled, passive_host_checks_enabled, passive_service_checks_enabled, process_id, process_performance_data, program_start_time, program_version, status_update_time) VALUES ('1', '1', '1', 'icinga-qa.domain', '1', '1', 1, '1', FROM_UNIXTIME(1616753730), '1', '1', '1', '12904', '1', FROM_UNIXTIME(1616734835), '2.12.3', FROM_UNIXTIME(1616753730))
[2021-03-26 11:15:30 +0100] debug/IdoMysqlConnection: Query: COMMIT
[2021-03-26 11:15:30 +0100] debug/IdoMysqlConnection: Query: BEGIN
[2021-03-26 11:15:30 +0100] debug/IdoMysqlConnection: Query: DELETE FROM icinga_runtimevariables WHERE instance_id = 1
[2021-03-26 11:15:30 +0100] debug/IdoMysqlConnection: Query: INSERT INTO icinga_runtimevariables (instance_id, varname, varvalue) VALUES (1, 'total_services', '150')
[2021-03-26 11:15:30 +0100] debug/IdoMysqlConnection: Query: INSERT INTO icinga_runtimevariables (instance_id, varname, varvalue) VALUES (1, 'total_scheduled_services', '150')
[2021-03-26 11:15:30 +0100] debug/IdoMysqlConnection: Query: INSERT INTO icinga_runtimevariables (instance_id, varname, varvalue) VALUES (1, 'total_hosts', '55')
[2021-03-26 11:15:30 +0100] debug/IdoMysqlConnection: Query: INSERT INTO icinga_runtimevariables (instance_id, varname, varvalue) VALUES (1, 'total_scheduled_hosts', '55')
[2021-03-26 11:15:30 +0100] debug/IdoMysqlConnection: Query: COMMIT
[2021-03-26 11:15:30 +0100] debug/IdoMysqlConnection: Query: BEGIN
...
##############
...
[2021-03-26 11:16:14 +0100] information/ExternalCommandListener: Executing external command: [1616753774] SCHEDULE_FORCED_SVC_CHECK;Test 192.168.1.22;test_ping;1616753774
[2021-03-26 11:16:14 +0100] notice/ExternalCommandProcessor: Rescheduling next check for service 'test_ping'
[2021-03-26 11:16:14 +0100] notice/ApiListener: Relaying 'event::SetForceNextCheck' message
[2021-03-26 11:16:14 +0100] notice/ApiListener: Sending message 'event::SetForceNextCheck' to 'icinga-qa-satellite-01.domain'
[2021-03-26 11:16:14 +0100] notice/ApiListener: Relaying 'event::SetNextCheck' message
[2021-03-26 11:16:14 +0100] notice/ApiListener: Sending message 'event::SetNextCheck' to 'icinga-qa-satellite-01.domain'
[2021-03-26 11:16:15 +0100] notice/JsonRpcConnection: Received 'log::SetLogPosition' message from identity 'icinga-qa-satellite-01.domain'.
[2021-03-26 11:16:15 +0100] debug/CheckerComponent: Scheduling info for checkable 'icinga-qa-satellite-01.domain!satellite-zone-health' (2021-03-26 11:16:15 +0100):
Object 'icinga-qa-satellite-01.domain!satellite-zone-health', Next Check: 2021-03-26 11:16:15 +0100(1.61675e+09).
[2021-03-26 11:16:15 +0100] debug/CheckerComponent: Executing check for 'icinga-qa-satellite-01.domain!satellite-zone-health'
[2021-03-26 11:16:15 +0100] debug/Checkable: Update checkable 'icinga-qa-satellite-01.domain!satellite-zone-health' with check interval '30' from last check time at
2021-03-26 11:15:45 +0100 (1.61675e+09) to next check time at 2021-03-26 11:16:45 +0100 (1.61675e+09).
[2021-03-26 11:16:15 +0100] debug/Checkable: Update checkable 'icinga-qa-satellite-01.domain!satellite-zone-health' with check interval '30' from last check time at
2021-03-26 11:16:15 +0100 (1.61675e+09) to next check time at 2021-03-26 11:16:45 +0100 (1.61675e+09).
[2021-03-26 11:16:15 +0100] notice/ApiListener: Relaying 'event::SetLastCheckStarted' message
[2021-03-26 11:16:15 +0100] notice/ApiListener: Relaying 'event::SetNextCheck' message
[2021-03-26 11:16:15 +0100] notice/ApiListener: Relaying 'event::SetNextCheck' message
[2021-03-26 11:16:15 +0100] notice/ApiListener: Relaying 'event::CheckResult' message
[2021-03-26 11:16:15 +0100] debug/CheckerComponent: Check finished for object 'icinga-qa-satellite-01.domain!satellite-zone-health'
[2021-03-26 11:16:15 +0100] debug/InfluxdbWriter: Checkable 'icinga-qa-satellite-01.domain!satellite-zone-health' adds to metric list:'cluster-zone,hostname=icinga-
qa-satellite-01.domain,service=satellite-zone-health,metric=slave_lag unit="s",value=0 1616753775'.
[2021-03-26 11:16:15 +0100] debug/InfluxdbWriter: Checkable 'icinga-qa-satellite-01.domain!satellite-zone-health' adds to metric list:'cluster-zone,hostname=icinga-qa-satellite-01.domain,service=satellite-zone-health,metric=last_messages_sent value=1616753774.958799 1616753775'.
[2021-03-26 11:16:15 +0100] debug/InfluxdbWriter: Checkable 'icinga-qa-satellite-01.domain!satellite-zone-health' adds to metric list:'cluster-zone,hostname=icinga-qa-satellite-01.domain,service=satellite-zone-health,metric=last_messages_received value=1616753775.284045 1616753775'.
[2021-03-26 11:16:15 +0100] debug/InfluxdbWriter: Checkable 'icinga-qa-satellite-01.domain!satellite-zone-health' adds to metric list:'cluster-zone,hostname=icinga-qa-satellite-01.domain,service=satellite-zone-health,metric=sum_messages_sent_per_second value=0.116667 1616753775'.
[2021-03-26 11:16:15 +0100] debug/InfluxdbWriter: Checkable 'icinga-qa-satellite-01.domain!satellite-zone-health' adds to metric list:'cluster-zone,hostname=icinga-qa-satellite-01.domain,service=satellite-zone-health,metric=sum_messages_received_per_second value=0.266667 1616753775'.
[2021-03-26 11:16:15 +0100] debug/InfluxdbWriter: Checkable 'icinga-qa-satellite-01.domain!satellite-zone-health' adds to metric list:'cluster-zone,hostname=icinga-qa-satellite-01.domain,service=satellite-zone-health,metric=sum_bytes_sent_per_second value=14 1616753775'.
[2021-03-26 11:16:15 +0100] debug/InfluxdbWriter: Checkable 'icinga-qa-satellite-01.domain!satellite-zone-health' adds to metric list:'cluster-zone,hostname=icinga-qa-satellite-01.domain,service=satellite-zone-health,metric=sum_bytes_received_per_second value=22.766667 1616753775'.
[2021-03-26 11:16:15 +0100] debug/IdoMysqlConnection: Query: UPDATE icinga_hoststatus SET acknowledgement_type = '0', active_checks_enabled = '1', check_command = 'hostalive', check_source = 'icinga-qa.domain', check_type = '0', current_check_attempt = '1', current_notification_number = '0', current_state = '0', endpoint_object_id = 4101, event_handler_enabled = '1', execution_time = '4.006499', flap_detection_enabled = '0', has_been_checked = '1', host_object_id = 4235, instance_id = 1, is_flapping = '0', is_reachable = '1', last_check = FROM_UNIXTIME(1616753774), last_hard_state = '0', last_hard_state_change = FROM_UNIXTIME(1616692718), last_state_change = FROM_UNIXTIME(1616692718), last_time_down = FROM_UNIXTIME(1616692597), last_time_up = FROM_UNIXTIME(1616753774), latency = '0.000429', long_output = '', max_check_attempts = '10', next_check = FROM_UNIXTIME(1616754073), normal_check_interval = '5', notifications_enabled = '1', original_attributes = 'null', output = 'PING OK - Packet loss = 0%, RTA = 0.69 ms', passive_checks_enabled = '1', percent_state_change = '0', perfdata = 'rta=0.691000ms;3000.000000;5000.000000;0.000000 pl=0%;80;100;0', problem_has_been_acknowledged = '0', process_performance_data = '1', retry_check_interval = '2', scheduled_downtime_depth = '0', should_be_scheduled = '1', state_type = '1', status_update_time = FROM_UNIXTIME(1616753774) WHERE host_object_id = 4235
[2021-03-26 11:16:15 +0100] debug/IdoMysqlConnection: Query: UPDATE icinga_servicestatus SET next_check = FROM_UNIXTIME(1616753774) WHERE service_object_id = 4856
[2021-03-26 11:16:15 +0100] debug/IdoMysqlConnection: Query: UPDATE icinga_servicestatus SET acknowledgement_type = '0', active_checks_enabled = '1', check_command = 'cluster-zone', check_source = 'icinga-qa.domain', check_type = '0', current_check_attempt = '1', current_notification_number = '0', current_state = '0', endpoint_object_id = 4101, event_handler_enabled = '1', execution_time = '0.000115', flap_detection_enabled = '0', has_been_checked = '1', instance_id = 1, is_flapping = '0', is_reachable = '0', last_check = FROM_UNIXTIME(1616753775), last_hard_state = '0', last_hard_state_change = FROM_UNIXTIME(1616747358), last_state_change = FROM_UNIXTIME(1616747358), last_time_critical = FROM_UNIXTIME(1616747349), last_time_ok = FROM_UNIXTIME(1616753775), latency = '0.000101', long_output = '', max_check_attempts = '3', next_check = FROM_UNIXTIME(1616753805), normal_check_interval = '0.500000', notifications_enabled = '1', original_attributes = 'null', output = 'Zone \'icinga-qa-satellite-01.domain\' is connected. Log lag: less than 1 millisecond', passive_checks_enabled = '1', percent_state_change = '0', perfdata = 'slave_lag=0;0;0 last_messages_sent=1616753774.958799 last_messages_received=1616753775.284045 sum_messages_sent_per_second=0.116667 sum_messages_received_per_second=0.266667 sum_bytes_sent_per_second=14 sum_bytes_received_per_second=22.766667', problem_has_been_acknowledged = '0', process_performance_data = '1', retry_check_interval = '0.166667', scheduled_downtime_depth = '0', service_object_id = 4866, should_be_scheduled = '1', state_type = '1', status_update_time = FROM_UNIXTIME(1616753775) WHERE service_object_id = 4866
[2021-03-26 11:16:15 +0100] debug/IdoMysqlConnection: Query: COMMIT
[2021-03-26 11:16:15 +0100] debug/IdoMysqlConnection: Query: BEGIN
[2021-03-26 11:16:16 +0100] debug/IdoMysqlConnection: Query: COMMIT
[2021-03-26 11:16:16 +0100] debug/IdoMysqlConnection: Query: BEGIN
[2021-03-26 11:16:17 +0100] debug/IdoMysqlConnection: Query: COMMIT
[2021-03-26 11:16:17 +0100] debug/IdoMysqlConnection: Query: BEGIN
[2021-03-26 11:16:18 +0100] debug/IdoMysqlConnection: Query: COMMIT
[2021-03-26 11:16:18 +0100] debug/IdoMysqlConnection: Query: BEGIN
[2021-03-26 11:16:19 +0100] notice/CheckerComponent: Pending checkables: 0; Idle checkables: 189; Checks/s: 0.516667
[2021-03-26 11:16:19 +0100] debug/IdoMysqlConnection: Query: COMMIT
[2021-03-26 11:16:19 +0100] debug/IdoMysqlConnection: Query: BEGIN
[2021-03-26 11:16:20 +0100] information/WorkQueue: #8 (InfluxdbWriter, influxdb) items: 1, rate: 0.6/s (36/min 226/5min 680/15min); empty in infinite time, your task handler isn't able to keep up
[2021-03-26 11:16:20 +0100] debug/InfluxdbWriter: Timer expired writing 9 data points
[2021-03-26 11:16:20 +0100] debug/InfluxdbWriter: Flushing data buffer to InfluxDB.
[2021-03-26 11:16:20 +0100] notice/InfluxdbWriter: Reconnecting to InfluxDB on host '127.0.0.1' port '8086'.
[2021-03-26 11:16:20 +0100] notice/DbConnection: Updating programstatus table.
[2021-03-26 11:16:20 +0100] notice/ApiListener: Updating object authority for objects at endpoint 'icinga-qa.domain'.
[2021-03-26 11:16:20 +0100] debug/ApiListener: Not connecting to Endpoint 'icinga-qa-satellite-01.domain' because we're already connected to it.
[2021-03-26 11:16:20 +0100] debug/ApiListener: Not connecting to Endpoint 'icinga-qa.domain' because that's us.
[2021-03-26 11:16:20 +0100] notice/ApiListener: Current zone master: icinga-qa.domain
[2021-03-26 11:16:20 +0100] notice/ApiListener: Connected endpoints: icinga-qa-satellite-01.domain (1)
[2021-03-26 11:16:20 +0100] notice/JsonRpcConnection: Received 'log::SetLogPosition' message from identity 'icinga-qa-satellite-01.domain'.
[2021-03-26 11:16:20 +0100] debug/IdoMysqlConnection: Query: DELETE FROM icinga_programstatus WHERE instance_id = 1
[2021-03-26 11:16:20 +0100] debug/IdoMysqlConnection: Query: INSERT INTO icinga_programstatus (active_host_checks_enabled, active_service_checks_enabled, daemon_mode, endpoint_name, event_handlers_enabled, flap_detection_enabled, instance_id, is_currently_running, last_command_check, notifications_enabled, passive_host_checks_enabled, passive_service_checks_enabled, process_id, process_performance_data, program_start_time, program_version, status_update_time) VALUES ('1', '1', '1', 'icinga-qa.domain', '1', '1', 1, '1', FROM_UNIXTIME(1616753780), '1', '1', '1', '12904', '1', FROM_UNIXTIME(1616734835), '2.12.3', FROM_UNIXTIME(1616753780))
[2021-03-26 11:16:20 +0100] debug/IdoMysqlConnection: Query: COMMIT
[2021-03-26 11:16:20 +0100] debug/IdoMysqlConnection: Query: BEGIN
[2021-03-26 11:16:20 +0100] debug/IdoMysqlConnection: Query: DELETE FROM icinga_runtimevariables WHERE instance_id = 1
[2021-03-26 11:16:20 +0100] debug/IdoMysqlConnection: Query: INSERT INTO icinga_runtimevariables (instance_id, varname, varvalue) VALUES (1, 'total_services', '150')
[2021-03-26 11:16:20 +0100] debug/IdoMysqlConnection: Query: INSERT INTO icinga_runtimevariables (instance_id, varname, varvalue) VALUES (1, 'total_scheduled_services', '150')
[2021-03-26 11:16:20 +0100] debug/IdoMysqlConnection: Query: INSERT INTO icinga_runtimevariables (instance_id, varname, varvalue) VALUES (1, 'total_hosts', '55')
[2021-03-26 11:16:20 +0100] debug/IdoMysqlConnection: Query: INSERT INTO icinga_runtimevariables (instance_id, varname, varvalue) VALUES (1, 'total_scheduled_hosts', '55')
[2021-03-26 11:16:20 +0100] debug/IdoMysqlConnection: Query: COMMIT
[2021-03-26 11:16:20 +0100] debug/IdoMysqlConnection: Query: BEGIN
[2021-03-26 11:16:21 +0100] debug/IdoMysqlConnection: Query: COMMIT
[2021-03-26 11:16:21 +0100] debug/IdoMysqlConnection: Query: BEGIN
[2021-03-26 11:16:22 +0100] debug/IdoMysqlConnection: Query: COMMIT
[2021-03-26 11:16:22 +0100] debug/IdoMysqlConnection: Query: BEGIN