I'm looking for a little assistance debugging some graphs that are not plotting certain (higher) values for Cisco router/switch interface utilization using the default device, data, and graph templates.
This is a relatively new cacti server, installed on Windows Server 2003, using the Cacti installer that's bundled with all the various dependencies. Some data sources plot fine, while others do not. I've verified that spine is getting a valid value back from the router(s) and did a dump of the rrd file, which showed "NAN" for the values in question. I modified the maximum values within the datasource to be within bounds for the value the routers are returning, however the values are still not being plotted.
Any help pointing out what I must be obviously overlooking would be greatly appreciated.
Thanks,
B. J.
Code: Select all
! Spine runs just fine, SNMP good, router provies a value back.
!
$ ./spine -V=6 13 13
NOTE: The Shell Command Exists in the current directory
SPINE: Using spine config file [spine.conf]
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEVDBG: SQL:'SELECT value FROM settings WHERE name = 'path_webroot''
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEVDBG: SQL:'SELECT value FROM settings WHERE name = 'path_cactilog''
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEBUG: The path_php_server variable is g:/Apache2/htdocs/cacti/script_server.php
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEBUG: The path_cactilog variable is G:/Apache2/htdocs/cacti/log/cacti.log
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEVDBG: SQL:'SELECT value FROM settings WHERE name = 'log_destination''
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEBUG: The log_destination variable is 1 (FILE)
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEVDBG: SQL:'SELECT value FROM settings WHERE name = 'path_php_binary''
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEBUG: The path_php variable is g:/php/php.exe
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEVDBG: SQL:'SELECT value FROM settings WHERE name = 'availability_method''
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEBUG: The availability_method variable is 2
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEVDBG: SQL:'SELECT value FROM settings WHERE name = 'ping_recovery_count''
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEBUG: The ping_recovery_count variable is 3
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEVDBG: SQL:'SELECT value FROM settings WHERE name = 'ping_failure_count''
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEBUG: The ping_failure_count variable is 2
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEVDBG: SQL:'SELECT value FROM settings WHERE name = 'ping_method''
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEBUG: The ping_method variable is 2
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEVDBG: SQL:'SELECT value FROM settings WHERE name = 'ping_retries''
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEBUG: The ping_retries variable is 1
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEVDBG: SQL:'SELECT value FROM settings WHERE name = 'ping_timeout''
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEBUG: The ping_timeout variable is 400
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEVDBG: SQL:'SELECT value FROM settings WHERE name = 'snmp_retries''
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEBUG: The snmp_retries variable is 3
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEVDBG: SQL:'SELECT value FROM settings WHERE name = 'log_perror''
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEBUG: The log_perror variable is 1
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEVDBG: SQL:'SELECT value FROM settings WHERE name = 'log_pwarn''
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEBUG: The log_pwarn variable is 0
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEVDBG: SQL:'SELECT value FROM settings WHERE name = 'boost_redirect''
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEBUG: The boost_redirect variable is 0
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEVDBG: SQL:'SELECT value FROM settings WHERE name = 'log_pstats''
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEBUG: The log_pstats variable is 0
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEVDBG: SQL:'SELECT value FROM settings WHERE name = 'max_threads''
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEBUG: The threads variable is 1
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEVDBG: SQL:'SELECT value FROM settings WHERE name = 'poller_interval''
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEBUG: The polling interval is 300 seconds
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEVDBG: SQL:'SELECT value FROM settings WHERE name = 'concurrent_processes''
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEBUG: The number of concurrent processes is 1
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEVDBG: SQL:'SELECT value FROM settings WHERE name = 'script_timeout''
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEBUG: The script timeout is 25
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEVDBG: SQL:'SELECT value FROM settings WHERE name = 'php_servers''
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEBUG: The number of php script servers to run is 1
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEVDBG: SQL:'SELECT action FROM poller_item WHERE action=2 AND host_id BETWEEN 13 AND 13 LIMIT 1'
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEBUG: StartHost='13', EndHost='13', TotalPHPScripts='0'
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEBUG: The PHP Script Server is Not Required
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEVDBG: SQL:'SELECT value FROM settings WHERE name = 'max_get_size''
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEBUG: The Maximum SNMP OID Get Size is 10
SPINE: Version 0.8.7e starting
09/28/2009 10:12:08 AM - SPINE: Poller[0] SPINE: Initializing Net-SNMP API
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEBUG: SNMP Header Version is 5.4.1
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEBUG: SNMP Library Version is 5.4.1
09/28/2009 10:12:08 AM - SPINE: Poller[0] SPINE: Initializing PHP Script Server(s)
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEVDBG: SQL:'SHOW COLUMNS FROM host LIKE 'poller_id''
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEVDBG: SQL:'SELECT id FROM host WHERE disabled='' AND id BETWEEN 13 AND 13 ORDER BY id'
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEBUG: Initial Value of Active Threads is 0
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEBUG: Valid Thread to be Created
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEBUG: In Poller, About to Start Polling of Host
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEBUG: The Value of Active Threads is 1
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEVDBG: SQL:'SELECT snmp_port, count(snmp_port) FROM poller_item WHERE host_id=0 AND rrd_next_step < 0 GROUP BY snmp_port'
09/28/2009 10:12:08 AM - SPINE: Poller[0] 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 FROM poller_item WHERE host_id=0 and rrd_next_step <=0 ORDER by snmp_port'
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEVDBG: SQL:'UPDATE poller_item SET rrd_next_step=rrd_next_step-300 WHERE host_id=0'
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEVDBG: SQL:'UPDATE poller_item SET rrd_next_step=rrd_step-300 WHERE rrd_next_step < 0 and host_id=0'
09/28/2009 10:12:08 AM - SPINE: Poller[0] Host[0] DEBUG: HOST COMPLETE: About to Exit Host Polling Thread Function
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEBUG: The Value of Active Threads is 0
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEBUG: Valid Thread to be Created
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEBUG: In Poller, About to Start Polling of Host
09/28/2009 10:12:08 AM - SPINE: Poller[0] DEBUG: The Value of Active Threads is 1
09/28/2009 10:12:08 AM - SPINE: Poller[0] 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_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 FROM host WHERE id=13'
09/28/2009 10:12:09 AM - SPINE: Poller[0] Host[13] SNMP Result: Host responded to SNMP
09/28/2009 10:12:09 AM - SPINE: Poller[0] DEVDBG: SQL:'UPDATE host SET status='3', status_event_count='0', status_fail_date='0000-00-00 00:00:00', status_rec_date='0000-00-00 00:00:00', status_last_error='', min_time='9.999990', max_time='1308.000090', cur_time='69.999930', avg_time='159.570990', total_polls='1162', failed_polls='0', availability='100.0000'WHERE id='13''
09/28/2009 10:12:09 AM - SPINE: Poller[0] DEVDBG: SQL:'SELECT data_query_id, action, op, assert_value, arg1 FROM poller_reindex WHERE host_id=13'
09/28/2009 10:12:09 AM - SPINE: Poller[0] Host[13] RECACHE: Processing 1 items in the auto reindex cache for '170.206.105.225'
09/28/2009 10:12:09 AM - SPINE: Poller[0] DEVDBG: SQL:'UPDATE poller_reindex SET assert_value='2231006878' WHERE host_id='13' AND data_query_id='1' and arg1='.1.3.6.1.2.1.1.3.0''
09/28/2009 10:12:09 AM - SPINE: Poller[0] DEVDBG: SQL:'SELECT snmp_port, count(snmp_port) FROM poller_item WHERE host_id=13 AND rrd_next_step < 0 GROUP BY snmp_port'
09/28/2009 10:12:09 AM - SPINE: Poller[0] 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 FROM poller_item WHERE host_id=13 and rrd_next_step <=0 ORDER by snmp_port'
09/28/2009 10:12:09 AM - SPINE: Poller[0] DEVDBG: SQL:'UPDATE poller_item SET rrd_next_step=rrd_next_step-300 WHERE host_id=13'
09/28/2009 10:12:09 AM - SPINE: Poller[0] DEVDBG: SQL:'UPDATE poller_item SET rrd_next_step=rrd_step-300 WHERE rrd_next_step < 0 and host_id=13'
09/28/2009 10:12:09 AM - SPINE: Poller[0] Host[13] NOTE: There are '15' Polling Items for this Host
09/28/2009 10:12:09 AM - SPINE: Poller[0] Host[13] DS[408] SNMP: v2: 170.206.105.225, dsname: traffic_in, oid: .1.3.6.1.2.1.31.1.1.1.6.110, value: 9199459550462
09/28/2009 10:12:09 AM - SPINE: Poller[0] Host[13] DS[408] SNMP: v2: 170.206.105.225, dsname: traffic_out, oid: .1.3.6.1.2.1.31.1.1.1.10.110, value: 3176138558906
09/28/2009 10:12:09 AM - SPINE: Poller[0] Host[13] DS[2189] SNMP: v2: 170.206.105.225, dsname: traffic_in, oid: .1.3.6.1.2.1.31.1.1.1.6.123, value: 9197198143991
09/28/2009 10:12:09 AM - SPINE: Poller[0] Host[13] DS[2189] SNMP: v2: 170.206.105.225, dsname: traffic_out, oid: .1.3.6.1.2.1.31.1.1.1.10.123, value: 3173534756602
09/28/2009 10:12:09 AM - SPINE: Poller[0] Host[13] DS[410] SNMP: v2: 170.206.105.225, dsname: errors_in, oid: .1.3.6.1.2.1.2.2.1.14.110, value: 594
09/28/2009 10:12:09 AM - SPINE: Poller[0] Host[13] DS[410] SNMP: v2: 170.206.105.225, dsname: discards_in, oid: .1.3.6.1.2.1.2.2.1.13.110, value: 862
09/28/2009 10:12:09 AM - SPINE: Poller[0] Host[13] DS[410] SNMP: v2: 170.206.105.225, dsname: discards_out, oid: .1.3.6.1.2.1.2.2.1.19.110, value: 83
09/28/2009 10:12:09 AM - SPINE: Poller[0] Host[13] DS[410] SNMP: v2: 170.206.105.225, dsname: errors_out, oid: .1.3.6.1.2.1.2.2.1.20.110, value: 0
09/28/2009 10:12:09 AM - SPINE: Poller[0] Host[13] DS[411] SNMP: v2: 170.206.105.225, dsname: errors_in, oid: .1.3.6.1.2.1.2.2.1.14.123, value: 1085948
09/28/2009 10:12:09 AM - SPINE: Poller[0] Host[13] DS[411] SNMP: v2: 170.206.105.225, dsname: discards_in, oid: .1.3.6.1.2.1.2.2.1.13.123, value: 0
09/28/2009 10:12:09 AM - SPINE: Poller[0] Host[13] DS[411] SNMP: v2: 170.206.105.225, dsname: discards_out, oid: .1.3.6.1.2.1.2.2.1.19.123, value: 0
09/28/2009 10:12:09 AM - SPINE: Poller[0] Host[13] DS[411] SNMP: v2: 170.206.105.225, dsname: errors_out, oid: .1.3.6.1.2.1.2.2.1.20.123, value: U
09/28/2009 10:12:09 AM - SPINE: Poller[0] Host[13] DS[871] SNMP: v2: 170.206.105.225, dsname: 1min_cpu, oid: .1.3.6.1.4.1.9.2.1.57.0, value: 4
09/28/2009 10:12:09 AM - SPINE: Poller[0] Host[13] DS[872] SNMP: v2: 170.206.105.225, dsname: 5min_cpu, oid: .1.3.6.1.4.1.9.2.1.58.0, value: 4
09/28/2009 10:12:09 AM - SPINE: Poller[0] Host[13] DS[873] SNMP: v2: 170.206.105.225, dsname: 5sec_cpu, oid: .1.3.6.1.4.1.9.2.1.56.0, value: 11
09/28/2009 10:12:09 AM - SPINE: Poller[0] DEVDBG: SQL:'INSERT INTO poller_output (local_data_id, rrd_name, time, output)VALUES (408,'traffic_in','2009-09-28 10:12:08','9199459550462'),(408,'traffic_out','2009-09-28 10:12:08','3176138558906'),(2189,'traffic_in','2009-09-28 10:12:08','9197198143991'),(2189,'traffic_out','2009-09-28 10:12:08','3173534756602'),(410,'errors_in','2009-09-28 10:12:08','594'),(410,'discards_in','2009-09-28 10:12:08','862'),(410,'discards_out','2009-09-28 10:12:08','83'),(410,'errors_out','2009-09-28 10:12:08','0'),(411,'errors_in','2009-09-28 10:12:08','1085948'),(411,'discards_in','2009-09-28 10:12:08','0'),(411,'discards_out','2009-09-28 10:12:08','0'),(411,'errors_out','2009-09-2810:12:08','U'),(871,'1min_cpu','2009-09-28 10:12:08','4'),(872,'5min_cpu','2009-09-28 10:12:08','4'),(873,'5sec_cpu','2009-09-28 10:12:08','11')'
09/28/2009 10:12:09 AM - SPINE: Poller[0] Host[13] DEBUG: HOST COMPLETE: About to Exit Host Polling Thread Function
09/28/2009 10:12:09 AM - SPINE: Poller[0] DEBUG: The Value of Active Threads is 0
09/28/2009 10:12:09 AM - SPINE: Poller[0] DEVDBG: SQL:'replace into settings (name,value) values ('date',NOW())'
09/28/2009 10:12:09 AM - SPINE: Poller[0] DEVDBG: SQL:'insert into poller_time (poller_id, start_time, end_time) values(0, NOW(), NOW())'
09/28/2009 10:12:09 AM - SPINE: Poller[0] DEBUG: Thread Cleanup Complete
09/28/2009 10:12:09 AM - SPINE: Poller[0] DEBUG: PHP Script Server Pipes Closed
09/28/2009 10:12:09 AM - SPINE: Poller[0] DEBUG: Allocated Variable Memory Freed
09/28/2009 10:12:09 AM - SPINE: Poller[0] DEBUG: MYSQL Free & Close Completed
09/28/2009 10:12:09 AM - SPINE: Poller[0] Time: 0.5200 s, Threads: 1, Hosts: 2
!RRDTool reports NaN
!
<!-- 2009-09-28 10:10:00 Central Daylight Time / 1254150600 --> <row><v> NaN </v><v> 1.0423036885e+005 </v></row>
<!-- 2009-09-28 10:15:00 Central Daylight Time / 1254150900 --> <row><v> NaN </v><v> 1.0513497117e+005 </v></row>