Poller Spine does not update graphics

Post general support questions here that do not specifically fall into the Linux or Windows categories.

Moderators: Developers, Moderators

Post Reply
bredis
Posts: 4
Joined: Tue Nov 05, 2019 12:13 am

Poller Spine does not update graphics

Post by bredis »

I installed debian 10 cacti 1.2.2.
poller cmd.php receives data, but "U" is written to the rrd file

Code: Select all

2019/11/05 11:03:04 - POLLER: Poller[Main Poller] CACTI2RRD: /usr/bin/rrdtool update /usr/share/cacti/site/rra/extra-2_bikovo-17_home10161_connections_264.rrd --skip-past-updates --template conn 1572940982:U
2019/11/05 11:03:04 - POLLER: Poller[Main Poller] CACTI2RRD: /usr/bin/rrdtool update /usr/share/cacti/site/rra/extra-2_bikovo-17_home10161_cpu_75.rrd --skip-past-updates --template CPU 1572940982:3
2019/11/05 11:03:04 - POLLER: Poller[Main Poller] Parsed MULTI output field 'CPU:3' [map CPU->CPU]
2019/11/05 11:03:03 - POLLER: Poller[Main Poller] Time: 1.0235 s, Poller: , Theads: N/A, Devices: 1
2019/11/05 11:03:03 - POLLER: Poller[Main Poller] Device[extra-2.bikovo-17.home:10161] DS[extra-2.bikovo-17.home:10161 - CPU Usage Percent] Graphs[extra-2.bikovo-17.home:10161 - Keenetic CPU Usage Percent] CMD: /usr/bin/php /usr/share/cacti/site/scripts/query_keenetic_memory.php 'extra-2.bikovo-17.home' '10161' 'public' , output: TOTAL:131072 USAGE:40120 PERCENT:30 CPU:3
2019/11/05 11:03:03 - POLLER: Poller[Main Poller] Device[extra-2.bikovo-17.home:10161] DS[extra-2.bikovo-17.home:10161 - Keenetic - Connections] Graphs[extra-2.bikovo-17.home:10161 - Keenetic - Connections] CMD: /usr/bin/php /usr/share/cacti/site/scripts/query_keenetic_connections.php 'extra-2.bikovo-17.home' '10161' 'public' , output: CONNECTIONS:9
2019/11/05 11:03:02 - POLLER: Poller[Main Poller] Device[extra-2.bikovo-17.home:10161] STATUS: Device 'extra-2.bikovo-17.home' is UP.
2019/11/05 11:03:02 - PING Device[extra-2.bikovo-17.home:10161] PING: ICMP Ping Success (3.789 ms)
2019/11/05 11:03:02 - POLLER: Poller[Main Poller] NOTE: There are no items in your poller for this polling cycle!
On version 0.8.8, everything worked perfectly.

The log for creating the rrd file.

Code: Select all

2019/11/05 10:59:04 - POLLER: Poller[Main Poller] CACTI2RRD: /usr/bin/rrdtool create /usr/share/cacti/site/rra/extra-2_bikovo-17_home10161_connections_264.rrd --start 1572940442 --step 60 DS:conn:GAUGE:600:0:10000 RRA:AVERAGE:0.5:1:10080 RRA:AVERAGE:0.5:5:8640 RRA:AVERAGE:0.5:30:4320 RRA:AVERAGE:0.5:120:2160 RRA:AVERAGE:0.5:1440:1461 RRA:MIN:0.5:1:10080 RRA:MIN:0.5:5:8640 RRA:MIN:0.5:30:4320 RRA:MIN:0.5:120:2160 RRA:MIN:0.5:1440:1461 RRA:MAX:0.5:1:10080 RRA:MAX:0.5:5:8640 RRA:MAX:0.5:30:4320 RRA:MAX:0.5:120:2160 RRA:MAX:0.5:1440:1461 RRA:LAST:0.5:1:10080 RRA:LAST:0.5:5:8640 RRA:LAST:0.5:30:4320 RRA:LAST:0.5:120:2160 RRA:LAST:0.5:1440:1461

The problem with poller Spine, since when cmd.php everything works.
In the log, the difference in the bottom line:
cmd.php string method

Code: Select all

2019/11/05 11:23:04 - POLLER: Poller[Main Poller] Parsed MULTI output field 'CONNECTIONS:11' [map CONNECTIONS->connections]
is present
At poler Spine it is not.
Below is a piece of log that would be more understandable.
(I will highlight the necessary lines)

Code: Select all

2019/11/05 11:24:03 - SYSTEM STATS: Time:2.3771 Method:spine Processes:1 Threads:1 Hosts:1 HostsPerProcess:1 DataSources:2 RRDsProcessed:2
2019/11/05 11:24:02 - SPINE: Poller[Main Poller] Time: 1.0938 s, Threads: 1, Devices: 1
2019/11/05 11:24:02 - SPINE: Poller[Main Poller] DEBUG: Net-SNMP Close Completed
2019/11/05 11:24:02 - SPINE: Poller[Main Poller] DEBUG: MYSQL Free & Close Completed
2019/11/05 11:24:02 - SPINE: Poller[Main Poller] DEBUG: Allocated Variable Memory Freed
2019/11/05 11:24:02 - SPINE: Poller[Main Poller] DEBUG: PHP Script Server Pipes Closed
2019/11/05 11:24:02 - SPINE: Poller[Main Poller] DEBUG: Thread Cleanup Complete
2019/11/05 11:24:02 - POLLER: Poller[Main Poller] CACTI2RRD: /usr/bin/rrdtool update /usr/share/cacti/site/rra/extra-2_bikovo-17_home10161_connections_264.rrd --skip-past-updates --template CONNECTIONS 1572942241:U
2019/11/05 11:24:02 - POLLER: Poller[Main Poller] CACTI2RRD: /usr/bin/rrdtool update /usr/share/cacti/site/rra/extra-2_bikovo-17_home10161_cpu_75.rrd --skip-past-updates --template CPU 1572942241:3

2019/11/05 11:24:02 - POLLER: Poller[Main Poller] Parsed MULTI output field 'CPU:3' [map CPU->CPU]

2019/11/05 11:24:02 - SPINE: Poller[Main Poller] SPINE: The Final Value of Threads is 0
2019/11/05 11:24:02 - SPINE: Poller[Main Poller] POLLER: Active Threads is 0, Pending is 0
2019/11/05 11:24:02 - SPINE: Poller[Main Poller] DEBUG: The Value of Active Threads is 0 for Device ID 8
2019/11/05 11:24:02 - SPINE: Poller[Main Poller] Device[extra-2.bikovo-17.home:10161] HT[1] DEBUG: HOST COMPLETE: About to Exit Device Polling Thread Function
2019/11/05 11:24:02 - SPINE: Poller[Main Poller] Device[extra-2.bikovo-17.home:10161] HT[1] Total Time: 0.56 Seconds
2019/11/05 11:24:02 - SPINE: Poller[Main Poller] Device[extra-2.bikovo-17.home:10161] HT[1] DS[extra-2.bikovo-17.home:10161 - CPU Usage Percent] Graphs[extra-2.bikovo-17.home:10161 - Keenetic CPU Usage Percent] SCRIPT: /usr/bin/php /usr/share/cacti/site/scripts/query_keenetic_memory.php 'extra-2.bikovo-17.home' '10161' 'public' , output: "TOTAL:131072 USAGE:40120 PERCENT:30 CPU:3
2019/11/05 11:24:02 - SPINE: Poller[Main Poller] Device[extra-2.bikovo-17.home:10161] DEBUG: The NIFTY POPEN returned the following File Descriptor 8
2019/11/05 11:24:02 - SPINE: Poller[Main Poller] Device[extra-2.bikovo-17.home:10161] HT[1] DS[extra-2.bikovo-17.home:10161 - Keenetic - Connections] Graphs[extra-2.bikovo-17.home:10161 - Keenetic - Connections] SCRIPT: /usr/bin/php /usr/share/cacti/site/scripts/query_keenetic_connections.php 'extra-2.bikovo-17.home' '10161' 'public' , output: "CONNECTIONS:6
2019/11/05 11:24:02 - SPINE: Poller[Main Poller] Device[extra-2.bikovo-17.home:10161] DEBUG: The NIFTY POPEN returned the following File Descriptor 8
2019/11/05 11:24:02 - SPINE: Poller[Main Poller] Device[extra-2.bikovo-17.home:10161] HT[1] NOTE: There are '2' Polling Items for this Device
2019/11/05 11:24:01 - SPINE: Poller[Main Poller] Device[extra-2.bikovo-17.home:10161] HT[1] Device has no information for recache.
2019/11/05 11:24:01 - SPINE: Poller[Main Poller] Updating Full System Information Table
2019/11/05 11:24:01 - SPINE: Poller[Main Poller] Device[extra-2.bikovo-17.home:10161] DEBUG: Entering ICMP Ping
2019/11/05 11:24:01 - POLLER: Poller[Main Poller] DEBUG: About to Spawn a Remote Process [CMD: /usr/sbin/spine, ARGS: -C /etc/cacti/spine.conf --poller=1 --first=0 --last=0 --mibs]
2019/11/05 11:24:01 - POLLER: Poller[Main Poller] NOTE: Poller Int: '60', Cron Int: '60', Time Since Last: '60.44', Max Runtime '58', Poller Runs: '1'
2019/11/05 11:23:06 - POLLER: Poller[Main Poller] DEBUG: About to Spawn a Remote Process [CMD: /usr/bin/php, ARGS: -q /usr/share/cacti/site/poller_automation.php --poller=1 --network=1]
2019/11/05 11:23:04 - SYSTEM STATS: Time:2.4673 Method:cmd.php Processes:1 Threads:N/A Hosts:1 HostsPerProcess:1 DataSources:2 RRDsProcessed:2
2019/11/05 11:23:04 - POLLER: Poller[Main Poller] CACTI2RRD: /usr/bin/rrdtool update /usr/share/cacti/site/rra/extra-2_bikovo-17_home10161_connections_264.rrd --skip-past-updates --template connections 1572942182:11
2019/11/05 11:23:04 - POLLER: Poller[Main Poller] CACTI2RRD: /usr/bin/rrdtool update /usr/share/cacti/site/rra/extra-2_bikovo-17_home10161_cpu_75.rrd --skip-past-updates --template CPU 1572942182:3

2019/11/05 11:23:04 - POLLER: Poller[Main Poller] Parsed MULTI output field 'CONNECTIONS:11' [map CONNECTIONS->connections]
2019/11/05 11:23:04 - POLLER: Poller[Main Poller] Parsed MULTI output field 'CPU:3' [map CPU->CPU]

2019/11/05 11:23:04 - POLLER: Poller[Main Poller] Time: 1.3466 s, Poller: , Theads: N/A, Devices: 1
2019/11/05 11:23:04 - POLLER: Poller[Main Poller] Device[extra-2.bikovo-17.home:10161] DS[extra-2.bikovo-17.home:10161 - CPU Usage Percent] Graphs[extra-2.bikovo-17.home:10161 - Keenetic CPU Usage Percent] CMD: /usr/bin/php /usr/share/cacti/site/scripts/query_keenetic_memory.php 'extra-2.bikovo-17.home' '10161' 'public' , output: TOTAL:131072 USAGE:40120 PERCENT:30 CPU:3
2019/11/05 11:23:03 - POLLER: Poller[Main Poller] Device[extra-2.bikovo-17.home:10161] DS[extra-2.bikovo-17.home:10161 - Keenetic - Connections] Graphs[extra-2.bikovo-17.home:10161 - Keenetic - Connections] CMD: /usr/bin/php /usr/share/cacti/site/scripts/query_keenetic_connections.php 'extra-2.bikovo-17.home' '10161' 'public' , output: CONNECTIONS:11
2019/11/05 11:23:03 - POLLER: Poller[Main Poller] Device[extra-2.bikovo-17.home:10161] STATUS: Device 'extra-2.bikovo-17.home' is UP.
2019/11/05 11:23:02 - PING Device[extra-2.bikovo-17.home:10161] PING: ICMP Ping Success (3.381 ms)
2019/11/05 11:23:02 - POLLER: Poller[Main Poller] NOTE: There are no items in your poller for this polling cycle!
Top
bredis
Posts: 4
Joined: Tue Nov 05, 2019 12:13 am

Re: Poller Spine does not update graphics

Post by bredis »

I upgraded cacti to 1.2.12 and spine to 1.2.12. The problem persists. Poller cmd.php graph draws spine no. Poller spine displays data only in "cacti graph real time". rrdtool version 1.7.1.
log cacti

Code: Select all

2020/05/23 16:45:00 - POLLER: Poller[Main Poller] DEBUG: About to Spawn a Remote Process [CMD: /usr/bin/php, ARGS: -q '/usr/share/cacti/poller_maintenance.php']
2020/05/23 16:45:00 - POLLER: Poller[Main Poller] DEBUG: About to Spawn a Remote Process [CMD: /usr/bin/php, ARGS: -q '/usr/share/cacti/poller_automation.php' -M]
2020/05/23 16:45:00 - POLLER: Poller[Main Poller] DEBUG: About to Spawn a Remote Process [CMD: /usr/bin/php, ARGS: -q '/usr/share/cacti/poller_spikekill.php']
2020/05/23 16:45:00 - POLLER: Poller[Main Poller] DEBUG: About to Spawn a Remote Process [CMD: /usr/bin/php, ARGS: -q /usr/share/cacti/poller_reports.php]
2020/05/23 16:45:00 - SYSTEM STATS: Time:1.2275 Method:spine Processes:1 Threads:1 Hosts:1 HostsPerProcess:1 DataSources:1 RRDsProcessed:1
2020/05/23 16:44:59 - SPINE: Poller[Main Poller] PID[16116] Time: 0.2154 s, Threads: 1, Devices: 2
2020/05/23 16:44:59 - SPINE: Poller[Main Poller] PID[16116] DEBUG: Net-SNMP Close Completed
2020/05/23 16:44:59 - SPINE: Poller[Main Poller] PID[16116] DEBUG: MYSQL Free & Close Completed
2020/05/23 16:44:59 - SPINE: Poller[Main Poller] PID[16116] DEBUG: Allocated Variable Memory Freed
2020/05/23 16:44:59 - SPINE: Poller[Main Poller] PID[16116] DEBUG: PHP Script Server Pipes Closed
2020/05/23 16:44:59 - SPINE: Poller[Main Poller] PID[16116] DEBUG: Thread Cleanup Complete
2020/05/23 16:44:59 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:UPDATE poller_time SET end_time=NOW() WHERE poller_id=1 AND pid=16116
2020/05/23 16:44:59 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:REPLACE INTO settings (name,value) VALUES ('date',NOW())
2020/05/23 16:44:59 - SPINE: Poller[Main Poller] PID[16116] SPINE: The Final Value of Threads is 0
2020/05/23 16:44:59 - SPINE: Poller[Main Poller] PID[16116] POLLER: Active Threads is 0, Pending is 0
2020/05/23 16:44:59 - SPINE: Poller[Main Poller] PID[16116] DEBUG: The Value of Active Threads is 0 for Device ID 27
2020/05/23 16:44:59 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] HT[1] DEBUG: HOST COMPLETE: About to Exit Device Polling Thread Function
2020/05/23 16:44:59 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:UPDATE host SET polling_time=1590241499.077 - 1590241498.936 WHERE id=27
2020/05/23 16:44:59 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] HT[1] Total Time: 0.14 Seconds
2020/05/23 16:44:59 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:UPDATE poller_item SET rrd_next_step=IF((rrd_next_step-60)>=0, (rrd_next_step-60), (rrd_step-60)) WHERE host_id=27 AND poller_id=1
2020/05/23 16:44:59 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:INSERT INTO poller_output (local_data_id, rrd_name, time, output) VALUES (1950,'CONNECTIONS','2020-05-23 16:44:58','"CONNECTIONS:8') ON DUPLICATE KEY UPDATE output=VALUES(output)
2020/05/23 16:44:59 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] HT[1] DS[extra-2.bikovo-17.home:10161 - Keenetic Connections] Graphs[extra-2.bikovo-17.home:10161 - Keenetic Connections] SCRIPT: /usr/bin/php /usr/share/cacti/scripts/query_keenetic_connections.php 'extra-2.bikovo-17.home' '10161' 'public' , output: "CONNECTIONS:8
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] DEBUG: The NIFTY POPEN returned the following File Descriptor 8
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] HT[1] NOTE: There are '1' Polling Items for this Device
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SELECT action, hostname, snmp_community, snmp_version, snmp_username, snmp_password, rrd_name, rrd_path, arg1, arg2, arg3, local_data_id, rrd_num, snmp_port, snmp_timeout, snmp_auth_protocol, snmp_priv_passphrase, snmp_priv_protocol, snmp_context, snmp_engine_id FROM poller_item WHERE host_id=27 AND rrd_next_step <=0 AND poller_id=1 ORDER by snmp_port
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SELECT snmp_port, count(snmp_port) FROM poller_item WHERE host_id=27 AND rrd_next_step <=0 AND poller_id=1 GROUP BY snmp_port
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] HT[1] Device has no information for recache.
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SELECT data_query_id, action, op, assert_value, arg1 FROM poller_reindex WHERE host_id=27
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:UPDATE host SET status='3', status_event_count='0', status_fail_date='2019-09-20 10:38:00', status_rec_date='2019-09-07 08:38:00', status_last_error='Host did not respond to SNMP, UDP: Ping timed out', min_time='0.000000', max_time='344.880100', cur_time='6.765840', avg_time='4.720173', total_polls='709597', failed_polls='7955', availability='98.8789', snmp_sysDescr='Linux Extra-II-AP 4.9-ndm-4 #2 Fri May 22 22:06:31 MSK 2020 mips', snmp_sysObjectID='iso.3.6.1.4.1.8072.3.2.10', snmp_sysUpTimeInstance='2998109', snmp_sysContact='info@bredis.ru', snmp_sysName='Extra-II-AP', snmp_sysLocation='room' WHERE id='27'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: Device [27] poll_result = snmp_get(host, '.1.3.6.1.2.1.1.6.0'); [complete]
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_sess_sync_response(.1.3.6.1.2.1.1.6.0) [complete]
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_sess_sync_response(.1.3.6.1.2.1.1.6.0)
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_add_null_var(.1.3.6.1.2.1.1.6.0) [complete]
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_add_null_var(.1.3.6.1.2.1.1.6.0)
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_parse_oid(.1.3.6.1.2.1.1.6.0) [complete]
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_parse_oid(.1.3.6.1.2.1.1.6.0)
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_pdu_create(.1.3.6.1.2.1.1.6.0) [complete]
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_pdu_create(.1.3.6.1.2.1.1.6.0)
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: Device [27] poll_result = snmp_get(host, '.1.3.6.1.2.1.1.6.0');
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: Device [27] poll_result = snmp_get(host, '.1.3.6.1.2.1.1.5.0'); [complete]
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_sess_sync_response(.1.3.6.1.2.1.1.5.0) [complete]
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_sess_sync_response(.1.3.6.1.2.1.1.5.0)
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_add_null_var(.1.3.6.1.2.1.1.5.0) [complete]
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_add_null_var(.1.3.6.1.2.1.1.5.0)
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_parse_oid(.1.3.6.1.2.1.1.5.0) [complete]
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_parse_oid(.1.3.6.1.2.1.1.5.0)
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_pdu_create(.1.3.6.1.2.1.1.5.0) [complete]
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_pdu_create(.1.3.6.1.2.1.1.5.0)
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: Device [27] poll_result = snmp_get(host, '.1.3.6.1.2.1.1.5.0');
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: Device [27] poll_result = snmp_get(host, '.1.3.6.1.2.1.1.4.0'); [complete]
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_sess_sync_response(.1.3.6.1.2.1.1.4.0) [complete]
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_sess_sync_response(.1.3.6.1.2.1.1.4.0)
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_add_null_var(.1.3.6.1.2.1.1.4.0) [complete]
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_add_null_var(.1.3.6.1.2.1.1.4.0)
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_parse_oid(.1.3.6.1.2.1.1.4.0) [complete]
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_parse_oid(.1.3.6.1.2.1.1.4.0)
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_pdu_create(.1.3.6.1.2.1.1.4.0) [complete]
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_pdu_create(.1.3.6.1.2.1.1.4.0)
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: Device [27] poll_result = snmp_get(host, '.1.3.6.1.2.1.1.4.0');
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDGB: Device[extra-2.bikovo-17.home:10161] poll_result = snmp_get(host, '.1.3.6.1.2.1.1.3.0'); [complete]
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_sess_sync_response(.1.3.6.1.2.1.1.3.0) [complete]
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_sess_sync_response(.1.3.6.1.2.1.1.3.0)
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_add_null_var(.1.3.6.1.2.1.1.3.0) [complete]
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_add_null_var(.1.3.6.1.2.1.1.3.0)
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_parse_oid(.1.3.6.1.2.1.1.3.0) [complete]
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_parse_oid(.1.3.6.1.2.1.1.3.0)
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_pdu_create(.1.3.6.1.2.1.1.3.0) [complete]
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_pdu_create(.1.3.6.1.2.1.1.3.0)
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: Device[extra-2.bikovo-17.home:10161] poll_result = snmp_get(host, '.1.3.6.1.2.1.1.3.0');
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: Device[extra-2.bikovo-17.home:10161] poll_result = snmp_get(host, '.1.3.6.1.2.1.1.2.0'); [complete]
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_sess_sync_response(.1.3.6.1.2.1.1.2.0) [complete]
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_sess_sync_response(.1.3.6.1.2.1.1.2.0)
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_add_null_var(.1.3.6.1.2.1.1.2.0) [complete]
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_add_null_var(.1.3.6.1.2.1.1.2.0)
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_parse_oid(.1.3.6.1.2.1.1.2.0) [complete]
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_parse_oid(.1.3.6.1.2.1.1.2.0)
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_pdu_create(.1.3.6.1.2.1.1.2.0) [complete]
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_pdu_create(.1.3.6.1.2.1.1.2.0)
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: Device[extra-2.bikovo-17.home:10161] poll_result = snmp_get(host, '.1.3.6.1.2.1.1.2.0');
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: Device[extra-2.bikovo-17.home:10161] poll_result = snmp_get(host, '.1.3.6.1.2.1.1.1.0'); [complete]
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_sess_sync_response(.1.3.6.1.2.1.1.1.0) [complete]
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_sess_sync_response(.1.3.6.1.2.1.1.1.0)
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_add_null_var(.1.3.6.1.2.1.1.1.0) [complete]
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_add_null_var(.1.3.6.1.2.1.1.1.0)
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_parse_oid(.1.3.6.1.2.1.1.1.0) [complete]
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_parse_oid(.1.3.6.1.2.1.1.1.0)
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_pdu_create(.1.3.6.1.2.1.1.1.0) [complete]
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] WARNING: snmp_pdu_create(.1.3.6.1.2.1.1.1.0)
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: Device[extra-2.bikovo-17.home:10161] poll_result = snmp_get(host, '.1.3.6.1.2.1.1.1.0');
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] NOTE: Device[extra-2.bikovo-17.home:10161] Updating Full System Information Table
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] NOTE: Device[extra-2.bikovo-17.home:10161] Checking for System Information Update
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] SNMP Result: SNMP not performed due to setting or ping result
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] PING Result: UDP: Device is Alive
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] DEBUG: UDP Device Alive, Try Count:1, Time:6.7658 ms
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] DEBUG: Entering UDP Ping
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] IPv4 address 192.168.31.3 (extra-2.bikovo-17.home)
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[extra-2.bikovo-17.home:10161] INFO: SNMP Device 'extra-2.bikovo-17.home' has timeout 500000 (500), retries 3
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SELECT id, hostname, snmp_community, snmp_version, snmp_username, snmp_password, snmp_auth_protocol, snmp_priv_passphrase, snmp_priv_protocol, snmp_context, snmp_engine_id, snmp_port, snmp_timeout, max_oids, availability_method, ping_method, ping_port, ping_timeout, ping_retries, status, status_event_count, status_fail_date, status_rec_date, status_last_error, min_time, max_time, cur_time, avg_time, total_polls, failed_polls, availability, snmp_sysUpTimeInstance, snmp_sysDescr, snmp_sysObjectID, snmp_sysContact, snmp_sysName, snmp_sysLocation FROM host WHERE id=27 AND deleted = ''
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'STRICT_TRANS_TABLES', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'STRICT_ALL_TABLES', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'TRADITIONAL', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'NO_AUTO_VALUE_ON_ZERO', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'ONLY_FULL_GROUP_BY', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'NO_ZERO_IN_DATE', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'NO_ZERO_DATE', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'STRICT_TRANS_TABLES', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'STRICT_ALL_TABLES', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] Time: 0.1173 s, Threads: 1, Devices: 1
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'TRADITIONAL', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEBUG: Net-SNMP Close Completed
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'NO_AUTO_VALUE_ON_ZERO', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'ONLY_FULL_GROUP_BY', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'NO_ZERO_IN_DATE', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEBUG: MYSQL Free & Close Completed
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEBUG: Allocated Variable Memory Freed
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'NO_ZERO_DATE', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEBUG: PHP Script Server Pipes Closed
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEBUG: Thread Cleanup Complete
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:UPDATE poller_time SET end_time=NOW() WHERE poller_id=1 AND pid=16113
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] INFO: DTS: device = 1, host_id = 27, host_thread = 1, last_host_thread = 1, host_data_ids = 0, complete = 0
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEBUG: In Poller, About to Start Polling of Device for Device ID 27
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] SPINE: Active Threads is 1, Pending is 1
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEBUG: Valid Thread to be Created
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] POLLER: Active Threads is 0, Pending is 0
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEBUG: The Value of Active Threads is 0 for Device ID 0
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[0] HT[1] DEBUG: HOST COMPLETE: About to Exit Device Polling Thread Function
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:UPDATE host SET polling_time=1590241498.940 - 1590241498.936 WHERE id=0
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] Device[0] HT[1] Total Time: 0.0035 Seconds
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SELECT action, hostname, snmp_community, snmp_version, snmp_username, snmp_password, rrd_name, rrd_path, arg1, arg2, arg3, local_data_id, rrd_num, snmp_port, snmp_timeout, snmp_auth_protocol, snmp_priv_passphrase, snmp_priv_protocol, snmp_context, snmp_engine_id FROM poller_item WHERE host_id=0 AND rrd_next_step <=0 AND poller_id=1 ORDER by snmp_port
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SELECT snmp_port, count(snmp_port) FROM poller_item WHERE host_id=0 AND rrd_next_step <=0 AND poller_id=1 GROUP BY snmp_port
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:REPLACE INTO settings (name,value) VALUES ('date',NOW())
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] SPINE: The Final Value of Threads is 0
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'STRICT_TRANS_TABLES', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'STRICT_ALL_TABLES', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'TRADITIONAL', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'NO_AUTO_VALUE_ON_ZERO', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'ONLY_FULL_GROUP_BY', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'NO_ZERO_IN_DATE', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'NO_ZERO_DATE', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'STRICT_TRANS_TABLES', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'STRICT_ALL_TABLES', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'TRADITIONAL', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'NO_AUTO_VALUE_ON_ZERO', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'ONLY_FULL_GROUP_BY', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'NO_ZERO_IN_DATE', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'NO_ZERO_DATE', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEBUG: In Poller, About to Start Polling of Device for Device ID 0
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SELECT COUNT(*) FROM poller_item WHERE host_id=27 AND rrd_next_step <=0
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] INFO: DTS: device = 0, host_id = 0, host_thread = 1, last_host_thread = 0, host_data_ids = 0, complete = 0
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] SPINE: Active Threads is 1, Pending is 1
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEBUG: Valid Thread to be Created
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SELECT COUNT(*) FROM poller_item WHERE host_id=0 AND rrd_next_step <=0
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEBUG: Initial Value of Active Threads is 0
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:INSERT INTO poller_time (poller_id, pid, start_time, end_time) VALUES (1, 16116, NOW(), '0000-00-00 00:00:00')
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SELECT id, device_threads FROM host WHERE disabled='' AND id BETWEEN 27 AND 27 AND host.poller_id=1 ORDER BY polling_time DESC
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] NOTE: Spine will support multithread device polling.
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SHOW COLUMNS FROM host LIKE 'device_threads'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SHOW COLUMNS FROM host LIKE 'poller_id'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] SPINE: Initializing PHP Script Server(s)
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEBUG: Issues with SNMP Header Version information, assuming old version of Net-SNMP.
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] SPINE: Initializing Net-SNMP API
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEBUG: Spine has got ICMP
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEBUG: Spine is running as root.
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEBUG: Spine running as 0 UID, 0 EUID
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'ONLY_FULL_GROUP_BY', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'NO_ZERO_DATE', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEBUG: The Maximum SNMP OID Get Size is 10
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'max_get_size'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEBUG: The PHP Script Server is Not Required
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEBUG: StartDevice='27', EndDevice='27', TotalPHPScripts='0'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SELECT action FROM poller_item WHERE action=2 AND host_id BETWEEN 27 AND 27 LIMIT 1
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEBUG: The number of php script servers to run is 5
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'php_servers'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEBUG: The spine_log_level variable is 0
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'spine_log_level'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEBUG: The selective_device_debug variable is
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'selective_device_debug'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEBUG: The script timeout is 50
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'script_timeout'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEBUG: The number of concurrent processes is 1
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'concurrent_processes'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEBUG: The polling interval is 60 seconds
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'poller_interval'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEBUG: The threads variable is 1
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SELECT threads FROM poller WHERE id = '1'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEBUG: The log_pstats variable is 1
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'log_pstats'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEBUG: The boost_rrd_update_enable variable is 0
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'boost_rrd_update_enable'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEBUG: The boost_redirect variable is 1
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'boost_redirect'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEBUG: The log_pwarn variable is 1
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'log_pwarn'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEBUG: The log_perror variable is 1
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'log_perror'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEBUG: The snmp_retries variable is 3
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'snmp_retries'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEBUG: The ping_timeout variable is 400
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'ping_timeout'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEBUG: The ping_retries variable is 1
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'ping_retries'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEBUG: The ping_method variable is 2
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'ping_method'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEBUG: The ping_failure_count variable is 2
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'ping_failure_count'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEBUG: The ping_recovery_count variable is 3
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'ping_recovery_count'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEBUG: The availability_method variable is 2
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'availability_method'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEBUG: The path_php variable is /usr/bin/php
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16116] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'path_php_binary'
2020/05/23 16:44:58 - POLLER: Poller[Main Poller] DEBUG: About to Spawn a Remote Process [CMD: /usr/local/spine/bin/spine, ARGS: --poller=1 --first=27 --last=27 --mibs]
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] POLLER: Active Threads is 0, Pending is 0
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEBUG: The Value of Active Threads is 0 for Device ID 0
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] Device[0] HT[1] DEBUG: HOST COMPLETE: About to Exit Device Polling Thread Function
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:UPDATE host SET polling_time=1590241498.841 - 1590241498.838 WHERE id=0
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] Device[0] HT[1] Total Time: 0.0028 Seconds
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SELECT action, hostname, snmp_community, snmp_version, snmp_username, snmp_password, rrd_name, rrd_path, arg1, arg2, arg3, local_data_id, rrd_num, snmp_port, snmp_timeout, snmp_auth_protocol, snmp_priv_passphrase, snmp_priv_protocol, snmp_context, snmp_engine_id FROM poller_item WHERE host_id=0 AND rrd_next_step <=0 AND poller_id=1 ORDER by snmp_port
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SELECT snmp_port, count(snmp_port) FROM poller_item WHERE host_id=0 AND rrd_next_step <=0 AND poller_id=1 GROUP BY snmp_port
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'STRICT_TRANS_TABLES', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'STRICT_ALL_TABLES', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'TRADITIONAL', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'NO_AUTO_VALUE_ON_ZERO', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'ONLY_FULL_GROUP_BY', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'NO_ZERO_IN_DATE', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'NO_ZERO_DATE', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'STRICT_TRANS_TABLES', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'STRICT_ALL_TABLES', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'TRADITIONAL', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'NO_AUTO_VALUE_ON_ZERO', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'ONLY_FULL_GROUP_BY', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'NO_ZERO_IN_DATE', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'NO_ZERO_DATE', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] INFO: DTS: device = 0, host_id = 0, host_thread = 1, last_host_thread = 0, host_data_ids = 0, complete = 0
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] SPINE: Active Threads is 1, Pending is 1
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEBUG: In Poller, About to Start Polling of Device for Device ID 0
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEBUG: Valid Thread to be Created
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SELECT COUNT(*) FROM poller_item WHERE host_id=0 AND rrd_next_step <=0
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEBUG: Initial Value of Active Threads is 0
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:INSERT INTO poller_time (poller_id, pid, start_time, end_time) VALUES (1, 16113, NOW(), '0000-00-00 00:00:00')
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SELECT id, device_threads FROM host WHERE disabled='' AND id BETWEEN 0 AND 0 AND host.poller_id=1 ORDER BY polling_time DESC
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] NOTE: Spine will support multithread device polling.
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SHOW COLUMNS FROM host LIKE 'device_threads'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SHOW COLUMNS FROM host LIKE 'poller_id'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] SPINE: Initializing PHP Script Server(s)
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEBUG: Issues with SNMP Header Version information, assuming old version of Net-SNMP.
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] SPINE: Initializing Net-SNMP API
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEBUG: Spine has got ICMP
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEBUG: Spine is running as root.
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEBUG: Spine running as 0 UID, 0 EUID
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'ONLY_FULL_GROUP_BY', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SET SESSION sql_mode = (SELECT REPLACE(@@sql_mode,'NO_ZERO_DATE', ''))
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEBUG: The Maximum SNMP OID Get Size is 10
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'max_get_size'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEBUG: The PHP Script Server is Not Required
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEBUG: StartDevice='0', EndDevice='0', TotalPHPScripts='0'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SELECT action FROM poller_item WHERE action=2 AND host_id BETWEEN 0 AND 0 LIMIT 1
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEBUG: The number of php script servers to run is 5
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'php_servers'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEBUG: The spine_log_level variable is 0
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'spine_log_level'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEBUG: The selective_device_debug variable is
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'selective_device_debug'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEBUG: The script timeout is 50
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'script_timeout'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEBUG: The number of concurrent processes is 1
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'concurrent_processes'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEBUG: The polling interval is 60 seconds
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'poller_interval'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEBUG: The threads variable is 1
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SELECT threads FROM poller WHERE id = '1'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEBUG: The log_pstats variable is 1
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'log_pstats'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEBUG: The boost_rrd_update_enable variable is 0
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'boost_rrd_update_enable'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEBUG: The boost_redirect variable is 1
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'boost_redirect'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEBUG: The log_pwarn variable is 1
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'log_pwarn'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEBUG: The log_perror variable is 1
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'log_perror'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEBUG: The snmp_retries variable is 3
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'snmp_retries'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEBUG: The ping_timeout variable is 400
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'ping_timeout'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEBUG: The ping_retries variable is 1
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'ping_retries'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEBUG: The ping_method variable is 2
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'ping_method'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEBUG: The ping_failure_count variable is 2
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'ping_failure_count'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEBUG: The ping_recovery_count variable is 3
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'ping_recovery_count'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEBUG: The availability_method variable is 2
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'availability_method'
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEBUG: The path_php variable is /usr/bin/php
2020/05/23 16:44:58 - SPINE: Poller[Main Poller] PID[16113] DEVDBG: SQL:SELECT value FROM settings WHERE name = 'path_php_binary'
2020/05/23 16:44:58 - POLLER: Poller[Main Poller] DEBUG: About to Spawn a Remote Process [CMD: /usr/local/spine/bin/spine, ARGS: --poller=1 --first=0 --last=0 --mibs]
2020/05/23 16:44:58 - POLLER: Poller[Main Poller] NOTE: Poller Int: '60', Cron Int: '60', Time Since Last: '56.8', Max Runtime '58', Poller Runs: '1'
Top
Post Reply

Return to “Help: General”

Who is online

Users browsing this forum: No registered users and 0 guests