Spine does not collect data, cmd.php works fine.

tims300 · Post by **tims300** » Thu Jun 05, 2008 10:52 am

Hi..

I have Cacti 0.8.7b and Spine 0.8.7a installed on CentOS. If I set the poller to cmd.php, all works fine and dandy. If I change to spine, then I get nothing in my graphs. I've gone through the NaN debug steps and from that I found that when I use Spine, nothing gets put into RRD.

When I do: rrdtool info filename.rrd all the .value fields are NaN.

When I run Spine manually I get the following output:

Code: Select all

06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: SQL:'SELECT value FROM settings WHERE name = 'path_php_binary''
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: The path_php variable is /usr/bin/php
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: SQL:'SELECT value FROM settings WHERE name = 'availability_method''
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: The availability_method variable is 2
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: SQL:'SELECT value FROM settings WHERE name = 'ping_recovery_count''
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: The ping_recovery_count variable is 3
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: SQL:'SELECT value FROM settings WHERE name = 'ping_failure_count''
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: The ping_failure_count variable is 2
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: SQL:'SELECT value FROM settings WHERE name = 'ping_method''
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: The ping_method variable is 2
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: SQL:'SELECT value FROM settings WHERE name = 'ping_retries''
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: The ping_retries variable is 1
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: SQL:'SELECT value FROM settings WHERE name = 'ping_timeout''
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: The ping_timeout variable is 400
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: SQL:'SELECT value FROM settings WHERE name = 'log_perror''
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: The log_perror variable is 1
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: SQL:'SELECT value FROM settings WHERE name = 'log_pwarn''
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: The log_pwarn variable is 0
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: SQL:'SELECT value FROM settings WHERE name = 'log_pstats''
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: The log_pstats variable is 1
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: SQL:'SELECT value FROM settings WHERE name = 'max_threads''
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: The threads variable is 4
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: SQL:'SELECT value FROM settings WHERE name = 'poller_interval''
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: The polling interval is 300 seconds
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: SQL:'SELECT value FROM settings WHERE name = 'concurrent_processes''
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: The number of concurrent processes is 20
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: SQL:'SELECT value FROM settings WHERE name = 'script_timeout''
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: The script timeout is 25
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: SQL:'SELECT value FROM settings WHERE name = 'php_servers''
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: The number of php script servers to run is 4
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: SQL:'SELECT action FROM poller_item WHERE action=2 AND host_id BETWEEN 30 AND 30 LIMIT 1'
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: StartHost='30', EndHost='30', TotalPHPScripts='0'
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: The PHP Script Server is Not Required
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: SQL:'SELECT value FROM settings WHERE name = 'max_get_size''
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: The Maximum SNMP OID Get Size is 10
06/05/2008 10:37:32 AM - SPINE: Poller[0] Version 0.8.7b starting
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: MySQL is Thread Safe!
06/05/2008 10:37:32 AM - SPINE: Poller[0] SPINE: Initializing Net-SNMP API
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: SNMP Header Version is 5.3.1
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: SNMP Library Version is 5.3.1
06/05/2008 10:37:32 AM - SPINE: Poller[0] SPINE: Initializing PHP Script Server(s)
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: SQL:'SELECT id FROM host WHERE disabled='' AND id BETWEEN 30 AND 30 ORDER BY id'
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: Initial Value of Active Threads is 0
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: Valid Thread to be Created
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: The Value of Active Threads is 1
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: In Poller, About to Start Polling of Host
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: SQL:'SELECT snmp_port, count(snmp_port) FROM poller_item WHERE host_id=0 AND rrd_next_step < 0 GROUP BY snmp_
port'
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: 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_it
em WHERE host_id=0 and rrd_next_step <=0 ORDER by snmp_port'
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: SQL:'UPDATE poller_item SET rrd_next_step=rrd_next_step-300 WHERE host_id=0'
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: SQL:'UPDATE poller_item SET rrd_next_step=rrd_step-300 WHERE rrd_next_step < 0 and host_id=0'
06/05/2008 10:37:32 AM - SPINE: Poller[0] Host[0] DEBUG: HOST COMPLETE: About to Exit Host Polling Thread Function
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: Valid Thread to be Created
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: The Value of Active Threads is 2
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: In Poller, About to Start Polling of Host
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: SQL:'SELECT id, hostname, snmp_community, snmp_version, snmp_username, snmp_password, snmp_auth_protocol, snm
p_priv_passphrase, snmp_priv_protocol, snmp_context, snmp_port, snmp_timeout, max_oids, availability_method, ping_method, ping_port, ping_timeout, ping_retrie
s, 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, availa
bility  FROM host WHERE id=30'
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: The Value of Active Threads is 1
06/05/2008 10:37:32 AM - SPINE: Poller[0] Host[30] SNMP Result: Host responded to SNMP
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: SQL:'UPDATE host SET status='3', status_event_count='0', status_fail_date='2008-06-01 04:55:54', status_rec_d
ate='0000-00-00 00:00:00', status_last_error='Ping not performed due to setting.', min_time='4.120000', max_time='631.130000', cur_time='13.532160', avg_time=
'48.115084', total_polls='58988', failed_polls='5', availability='99.9915' WHERE id='30''
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: SQL:'SELECT data_query_id, action, op, assert_value, arg1 FROM poller_reindex WHERE host_id=30'
06/05/2008 10:37:32 AM - SPINE: Poller[0] Host[30] RECACHE: Processing 1 items in the auto reindex cache for '10.50.224.1'
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: SQL:'UPDATE poller_reindex SET assert_value='1569832761' WHERE host_id='30' AND data_query_id='1' and arg1='.
1.3.6.1.2.1.1.3.0''
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: SQL:'SELECT snmp_port, count(snmp_port) FROM poller_item WHERE host_id=30 AND rrd_next_step < 0 GROUP BY snmp
_port'
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: 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_it
em WHERE host_id=30 and rrd_next_step <=0 ORDER by snmp_port'
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: SQL:'UPDATE poller_item SET rrd_next_step=rrd_next_step-300 WHERE host_id=30'
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: SQL:'UPDATE poller_item SET rrd_next_step=rrd_step-300 WHERE rrd_next_step < 0 and host_id=30'
06/05/2008 10:37:32 AM - SPINE: Poller[0] Host[30] DS[928] SNMP: v1: 10.50.224.1, dsname: vpn5min_cpu, oid: .1.3.6.1.4.1.3076.2.1.2.25.1.2.0, value: 49
06/05/2008 10:37:32 AM - SPINE: Poller[0] Host[30] DS[929] SNMP: v1: 10.50.224.1, dsname: cvpn_tempcur, oid: .1.3.6.1.4.1.3076.2.1.2.22.1.33.0, value: 33
06/05/2008 10:37:32 AM - SPINE: Poller[0] Host[30] DS[930] SNMP: v1: 10.50.224.1, dsname: alactiv_count, oid: .1.3.6.1.4.1.3076.2.1.2.17.1.1.0, value: 245
06/05/2008 10:37:32 AM - SPINE: Poller[0] Host[30] DS[931] SNMP: v1: 10.50.224.1, dsname: alactive_countmax, oid: .1.3.6.1.4.1.3076.2.1.2.17.1.6.0, value: 530
06/05/2008 10:37:32 AM - SPINE: Poller[0] Host[30] DS[932] SNMP: v1: 10.50.224.1, dsname: traffic_in, oid: .1.3.6.1.2.1.2.2.1.10.1, value: 3818754217
06/05/2008 10:37:32 AM - SPINE: Poller[0] Host[30] DS[932] SNMP: v1: 10.50.224.1, dsname: traffic_out, oid: .1.3.6.1.2.1.2.2.1.16.1, value: 404608210
06/05/2008 10:37:32 AM - SPINE: Poller[0] Host[30] DS[933] SNMP: v1: 10.50.224.1, dsname: traffic_in, oid: .1.3.6.1.2.1.2.2.1.10.2, value: 659976992
06/05/2008 10:37:32 AM - SPINE: Poller[0] Host[30] DS[933] SNMP: v1: 10.50.224.1, dsname: traffic_out, oid: .1.3.6.1.2.1.2.2.1.16.2, value: 3787310699
06/05/2008 10:37:32 AM - SPINE: Poller[0] Host[30] DS[934] SNMP: v1: 10.50.224.1, dsname: traffic_in, oid: .1.3.6.1.2.1.2.2.1.10.3, value: 0
06/05/2008 10:37:32 AM - SPINE: Poller[0] Host[30] DS[934] SNMP: v1: 10.50.224.1, dsname: traffic_out, oid: .1.3.6.1.2.1.2.2.1.16.3, value: 0
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: SQL:'INSERT INTO poller_output (local_data_id, rrd_name, time, output) VALUES (928,'vpn5min_cpu','2008-06-05
10:37:32','49'),(929,'cvpn_tempcur','2008-06-05 10:37:32','33'),(930,'alactiv_count','2008-06-05 10:37:32','245'),(931,'alactive_countmax','2008-06-05 10:37:3
2','530'),(932,'traffic_in','2008-06-05 10:37:32','3818754217'),(932,'traffic_out','2008-06-05 10:37:32','404608210'),(933,'traffic_in','2008-06-05 10:37:32',
'659976992'),(933,'traffic_out','2008-06-05 10:37:32','3787310699'),(934,'traffic_in','2008-06-05 10:37:32','0'),(934,'traffic_out','2008-06-05 10:37:32','0')
'
06/05/2008 10:37:32 AM - SPINE: Poller[0] Host[30] DEBUG: HOST COMPLETE: About to Exit Host Polling Thread Function
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: The Value of Active Threads is 0
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: SQL:'replace into settings (name,value) values ('date',NOW())'
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: SQL:'insert into poller_time (poller_id, start_time, end_time) values (0, NOW(), NOW())'
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: Thread Cleanup Complete
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: PHP Script Server Pipes Closed
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: Allocated Variable Memory Freed
06/05/2008 10:37:32 AM - SPINE: Poller[0] SPINE: Net-SNMP API Shutdown Completed
06/05/2008 10:37:32 AM - SPINE: Poller[0] DEBUG: MYSQL Free & Close Completed
06/05/2008 10:37:32 AM - SPINE: Poller[0] Time: 0.3134 s, Threads: 4, Hosts: 2

When I run cmd.php manually, I get the following:

Code: Select all

06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "SELECT name, file, function FROM plugin_hooks WHERE status = 1 AND hook = 'config_arrays'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "SELECT * FROM plugin_realms"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "SELECT name, file, function FROM plugin_hooks WHERE status = 1 AND hook = 'config_settings'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "SELECT name, file, function FROM plugin_hooks WHERE status = 1 AND hook = 'valid_host_fields'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "SELECT name, file, function FROM plugin_hooks WHERE status = 1 AND hook = 'config_form'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select cacti from version"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "SELECT name, file, function FROM plugin_hooks WHERE status = 1 AND hook = 'auth_alternate_realms
'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select id from user_auth where username='guest'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select   user_auth_realm.realm_id   from   user_auth_realm   where user_auth_realm.user_id='1'
 and user_auth_realm.realm_id='15'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "SELECT name, file, function FROM plugin_hooks WHERE status = 1 AND hook = 'top_header'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "SELECT name, file, function FROM plugin_hooks WHERE status = 1 AND hook = 'page_title'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "SELECT name, file, function FROM plugin_hooks WHERE status = 1 AND hook = 'page_head'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "SELECT name, file, function FROM plugin_hooks WHERE status = 1 AND hook = 'top_header_tabs'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select realm_id from user_auth_realm where user_id=1"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select user_auth_realm.realm_id from user_auth_realm where user_auth_realm.user_id='1' and user_
auth_realm.realm_id='43'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "show tables"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "update weathermap_maps set sortorder=id where sortorder is null;"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "update weathermap_maps set sortorder=id where sortorder is null or sortorder=0;"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select user_auth_realm.realm_id  from user_auth_realm where user_auth_realm.user_id='1'  and use
r_auth_realm.realm_id='20'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "SELECT name, file, function FROM plugin_hooks WHERE status = 1 AND hook = 'draw_navigation_text'
"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select username from user_auth where id=1"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "SELECT name, file, function FROM plugin_hooks WHERE status = 1 AND hook = 'config_arrays'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "SELECT * FROM plugin_realms"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select realm_id from user_auth_realm where user_id=1"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "SELECT name, file, function FROM plugin_hooks WHERE status = 1 AND hook = 'config_arrays'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "SELECT * FROM plugin_realms"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select value from settings where name='logging_header'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select value from settings where name='logging_header'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select value from settings where name='log_destination'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select value from settings where name='log_destination'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select value from settings where name='log_snmp'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select value from settings where name='log_snmp'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select value from settings where name='log_graph'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select value from settings where name='log_graph'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select value from settings where name='log_export'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select value from settings where name='log_export'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select value from settings where name='poller_header'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select value from settings where name='poller_header'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select value from settings where name='log_verbosity'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select value from settings where name='log_verbosity'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select value from settings where name='log_pstats'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select value from settings where name='log_pstats'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select value from settings where name='log_pwarn'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select value from settings where name='log_pwarn'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select value from settings where name='log_perror'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select value from settings where name='log_perror'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select value from settings where name='versions_header'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select value from settings where name='versions_header'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select value from settings where name='snmp_version'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select value from settings where name='snmp_version'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select value from settings where name='rrdtool_version'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select value from settings where name='rrdtool_version'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select value from settings where name='snmp_header'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select value from settings where name='snmp_header'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select value from settings where name='snmp_ver'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select value from settings where name='snmp_ver'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select value from settings where name='snmp_community'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select value from settings where name='snmp_community'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select value from settings where name='snmp_username'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select value from settings where name='snmp_username'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select value from settings where name='snmp_password'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select value from settings where name='snmp_password'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select value from settings where name='snmp_auth_protocol'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select value from settings where name='snmp_auth_protocol'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select value from settings where name='snmp_priv_passphrase'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select value from settings where name='snmp_priv_passphrase'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select value from settings where name='snmp_priv_protocol'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select value from settings where name='snmp_priv_protocol'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select value from settings where name='snmp_timeout'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select value from settings where name='snmp_timeout'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select value from settings where name='snmp_port'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select value from settings where name='snmp_port'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select value from settings where name='snmp_retries'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select value from settings where name='snmp_retries'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select value from settings where name='other_header'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select value from settings where name='other_header'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select value from settings where name='remove_verification'"
06/05/2008 11:38:14 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select value from settings where name='remove_verification'"
06/05/2008 11:38:21 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "SELECT name, file, function FROM plugin_hooks WHERE status = 1 AND hook = 'config_arrays'"
06/05/2008 11:38:21 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "SELECT * FROM plugin_realms"
06/05/2008 11:38:21 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "SELECT name, file, function FROM plugin_hooks WHERE status = 1 AND hook = 'config_settings'"
06/05/2008 11:38:21 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "SELECT name, file, function FROM plugin_hooks WHERE status = 1 AND hook = 'valid_host_fields'"
06/05/2008 11:38:21 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "SELECT name, file, function FROM plugin_hooks WHERE status = 1 AND hook = 'config_form'"
06/05/2008 11:38:21 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "     SELECT * FROM host     WHERE (disabled = ''     AND id >= 30     AND id <= 30)     ORDER by
 id"
06/05/2008 11:38:21 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "SELECT *     FROM poller_item     WHERE (host_id >= 30     AND host_id <= 30     AND rrd_next_st
ep <= 0)     ORDER by host_id"
06/05/2008 11:38:21 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "SELECT count(*)     FROM poller_item     WHERE (action=2     AND host_id >= 30     AND host_id <=
 30     AND rrd_next_step <= 0)"
06/05/2008 11:38:21 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "UPDATE poller_item     SET rrd_next_step = rrd_next_step - 300     WHERE (host_id >= 30     AND h
ost_id <= 30)"
06/05/2008 11:38:21 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "UPDATE poller_item     SET rrd_next_step = rrd_step - 300     WHERE (rrd_next_step < 0     AND ho
st_id >= 30     AND host_id <= 30)"
06/05/2008 11:38:21 AM - CMDPHP: Poller[0] Host[30] SNMP: Host responded to SNMP
06/05/2008 11:38:21 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "update host set  status = '3',  status_event_count = '0',  status_fail_date = '2008-06-01 04:55:5
4',  status_rec_date = '0000-00-00 00:00:00',  status_last_error = 'Ping not performed due to setting.',  min_time = '4.12000',  max_time = '631.13000',  cur_
time = '15',  avg_time = '48.114518575207',  total_polls = '58989',  failed_polls = '5',  availability = '99.991523843428'  where hostname = '10.50.224.1'"
06/05/2008 11:38:21 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select     poller_reindex.data_query_id,     poller_reindex.action,     poller_reindex.op,     p
oller_reindex.assert_value,     poller_reindex.arg1     from poller_reindex     where poller_reindex.host_id=30"
06/05/2008 11:38:21 AM - CMDPHP: Poller[0] Host[30] RECACHE: Processing 1 items in the auto reindex cache for '10.50.224.1'.
06/05/2008 11:38:21 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "update poller_reindex set assert_value='1569837681' where host_id='30' and data_query_id='1' and
arg1='.1.3.6.1.2.1.1.3.0'"
06/05/2008 11:38:21 AM - CMDPHP: Poller[0] Host[30] DS[928] SNMP: v1: 10.50.224.1, dsname: vpn5min_cpu, oid: .1.3.6.1.4.1.3076.2.1.2.25.1.2.0, output: 41
06/05/2008 11:38:21 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_output (local_data_id, rrd_name, time, output) values (928, 'vpn5min_cpu', '20
08-06-05 11:38:21', '41')"
06/05/2008 11:38:21 AM - CMDPHP: Poller[0] Host[30] DS[929] SNMP: v1: 10.50.224.1, dsname: cvpn_tempcur, oid: .1.3.6.1.4.1.3076.2.1.2.22.1.33.0, output: 33
06/05/2008 11:38:21 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_output (local_data_id, rrd_name, time, output) values (929, 'cvpn_tempcur', '2
008-06-05 11:38:21', '33')"
06/05/2008 11:38:21 AM - CMDPHP: Poller[0] Host[30] DS[930] SNMP: v1: 10.50.224.1, dsname: alactiv_count, oid: .1.3.6.1.4.1.3076.2.1.2.17.1.1.0, output: 244
06/05/2008 11:38:21 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_output (local_data_id, rrd_name, time, output) values (930, 'alactiv_count', '
2008-06-05 11:38:21', '244')"
06/05/2008 11:38:22 AM - CMDPHP: Poller[0] Host[30] DS[931] SNMP: v1: 10.50.224.1, dsname: alactive_countmax, oid: .1.3.6.1.4.1.3076.2.1.2.17.1.6.0, output: 5
30
06/05/2008 11:38:22 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_output (local_data_id, rrd_name, time, output) values (931, 'alactive_countmax
', '2008-06-05 11:38:21', '530')"
06/05/2008 11:38:22 AM - CMDPHP: Poller[0] Host[30] DS[932] SNMP: v1: 10.50.224.1, dsname: traffic_in, oid: .1.3.6.1.2.1.2.2.1.10.1, output: 3894241882
06/05/2008 11:38:22 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_output (local_data_id, rrd_name, time, output) values (932, 'traffic_in', '200
8-06-05 11:38:21', '3894241882')"
06/05/2008 11:38:22 AM - CMDPHP: Poller[0] Host[30] DS[932] SNMP: v1: 10.50.224.1, dsname: traffic_out, oid: .1.3.6.1.2.1.2.2.1.16.1, output: 448086199
06/05/2008 11:38:22 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_output (local_data_id, rrd_name, time, output) values (932, 'traffic_out', '20
08-06-05 11:38:21', '448086199')"
06/05/2008 11:38:22 AM - CMDPHP: Poller[0] Host[30] DS[933] SNMP: v1: 10.50.224.1, dsname: traffic_in, oid: .1.3.6.1.2.1.2.2.1.10.2, output: 713074545
06/05/2008 11:38:22 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_output (local_data_id, rrd_name, time, output) values (933, 'traffic_in', '200
8-06-05 11:38:21', '713074545')"
06/05/2008 11:38:22 AM - CMDPHP: Poller[0] Host[30] DS[933] SNMP: v1: 10.50.224.1, dsname: traffic_out, oid: .1.3.6.1.2.1.2.2.1.16.2, output: 3870965919
06/05/2008 11:38:22 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_output (local_data_id, rrd_name, time, output) values (933, 'traffic_out', '20
08-06-05 11:38:21', '3870965919')"
06/05/2008 11:38:22 AM - CMDPHP: Poller[0] Host[30] DS[934] SNMP: v1: 10.50.224.1, dsname: traffic_in, oid: .1.3.6.1.2.1.2.2.1.10.3, output: 0
06/05/2008 11:38:22 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_output (local_data_id, rrd_name, time, output) values (934, 'traffic_in', '200
8-06-05 11:38:21', '0')"
06/05/2008 11:38:22 AM - CMDPHP: Poller[0] Host[30] DS[934] SNMP: v1: 10.50.224.1, dsname: traffic_out, oid: .1.3.6.1.2.1.2.2.1.16.3, output: 0
06/05/2008 11:38:22 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_output (local_data_id, rrd_name, time, output) values (934, 'traffic_out', '20
08-06-05 11:38:21', '0')"
06/05/2008 11:38:22 AM - CMDPHP: Poller[0] Time: 1.2495 s, Theads: N/A, Hosts: 1
06/05/2008 11:38:22 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_time (poller_id, start_time, end_time) values (0, NOW(), NOW())"
06/05/2008 11:38:27 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "SELECT name, file, function FROM plugin_hooks WHERE status = 1 AND hook = 'config_arrays'"
06/05/2008 11:38:27 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "SELECT * FROM plugin_realms"
06/05/2008 11:38:27 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "SELECT name, file, function FROM plugin_hooks WHERE status = 1 AND hook = 'config_settings'"
06/05/2008 11:38:27 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "SELECT name, file, function FROM plugin_hooks WHERE status = 1 AND hook = 'valid_host_fields'"
06/05/2008 11:38:27 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "SELECT name, file, function FROM plugin_hooks WHERE status = 1 AND hook = 'config_form'"
06/05/2008 11:38:27 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select cacti from version"
06/05/2008 11:38:27 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "SELECT name, file, function FROM plugin_hooks WHERE status = 1 AND hook = 'auth_alternate_realms
'"
06/05/2008 11:38:27 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select id from user_auth where username='guest'"
06/05/2008 11:38:27 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select   user_auth_realm.realm_id   from   user_auth_realm   where user_auth_realm.user_id='1'
 and user_auth_realm.realm_id='15'"
06/05/2008 11:38:27 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "replace into settings (name,value) values ('log_destination', '1')"
06/05/2008 11:38:27 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "replace into settings (name,value) values ('log_snmp', '')"
06/05/2008 11:38:27 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "replace into settings (name,value) values ('log_graph', '')"
06/05/2008 11:38:27 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "replace into settings (name,value) values ('log_export', '')"
06/05/2008 11:38:27 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "replace into settings (name,value) values ('log_verbosity', '1')"
06/05/2008 11:38:27 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "replace into settings (name,value) values ('log_pstats', 'on')"
06/05/2008 11:38:27 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "replace into settings (name,value) values ('log_pwarn', '')"
06/05/2008 11:38:27 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "replace into settings (name,value) values ('log_perror', 'on')"
06/05/2008 11:38:27 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "replace into settings (name,value) values ('snmp_version', 'net-snmp')"
06/05/2008 11:38:27 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "replace into settings (name,value) values ('rrdtool_version', 'rrd-1.2.x')"
06/05/2008 11:38:27 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "replace into settings (name,value) values ('snmp_ver', '2')"
06/05/2008 11:38:27 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "replace into settings (name,value) values ('snmp_community', 'mnstwwro')"
06/05/2008 11:38:27 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "replace into settings (name,value) values ('snmp_username', '')"
06/05/2008 11:38:27 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "replace into settings (name,value) values ('snmp_password', '')"
06/05/2008 11:38:27 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "replace into settings (name,value) values ('snmp_auth_protocol', 'MD5')"
06/05/2008 11:38:27 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "replace into settings (name,value) values ('snmp_priv_passphrase', '')"
06/05/2008 11:38:27 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "replace into settings (name,value) values ('snmp_priv_protocol', 'DES')"
06/05/2008 11:38:27 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "replace into settings (name,value) values ('snmp_timeout', '500')"
06/05/2008 11:38:27 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "replace into settings (name,value) values ('snmp_port', '161')"
06/05/2008 11:38:27 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "replace into settings (name,value) values ('snmp_retries', '3')"
06/05/2008 11:38:27 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "replace into settings (name,value) values ('remove_verification', 'on')"

Any ideas why Spine doesn't work?

Post by **gandalf** » Thu Jun 05, 2008 1:53 pm

Please tell us how you changed from cmd.php to spine
Reinhard

tims300 · Post by **tims300** » Fri Jun 06, 2008 6:35 am

from in the gui. console -> settings -> poller -> poller type dropdown box.

Post by **gandalf** » Sun Jun 08, 2008 2:56 am

That's the way it's supposed to work.
When running spine manually as cactiuser, what do you receive? You may not see any rrdtool updates, but that's on purpose (spine just like cmd.php does only update mysql tables)
Reinhard

tims300 · Post by **tims300** » Mon Jun 09, 2008 6:55 am

the above logs are from running spine manually as cactiuser. the problem is that regardless of whether i run spine automatically (through cron) or manually, it doesn't update the graphs. from the logs it looks like spine collects the data, but my graphs are empty. as soon as i switch to cmd.php the graphs start showing again.

tims300 · Post by **tims300** » Mon Jun 09, 2008 7:30 am

additional info...

i just noticed in the output from spine:

ERROR: illegal attempt to update using time 1212594596 when last update time is 1212597653 (minimum one second step)

this happens every time i switch from cmd.php to spine. however, even after waiting the ~50 minutes, my graphs still don't have anything.

also, during this time (after the 50 minutes, when i'm no longer getting the illegal attempt messages, but no data is showing in the graph), weathermap reports the following:

WEATHERMAP: Poller[0] ReadData: LINK xxx-prod2, target: /var/www/cacti/rra/xxx_traffic_in_4443.rrd on config line 231 had no valid data, according to WeatherMapDataSource_rrd

Post by **gandalf** » Wed Jun 11, 2008 2:19 pm

This error is encountered especially in cases of a "double poller issue", see 2nd link of my sig
Reinhard

tims300 · Post by **tims300** » Wed Jun 11, 2008 2:50 pm

i have doubled checked and the poller is only being run by one crontab.

hobi · Post by **hobi** » Fri Sep 16, 2011 5:37 am

hi ,i came across this issue as well,and no other cron, and also wait 50mins but still not update the graph...would you please share your solution...appreciate very much!!!

Cacti

Spine does not collect data, cmd.php works fine.

Spine does not collect data, cmd.php works fine.

Re: Spine does not collect data, cmd.php works fine.

Who is online