CRON fails to run and graphs have no data.

rawn027 · Post by **rawn027** » Sat Feb 02, 2008 5:26 pm

The graphs were able to display data once. My permissions on the files are fine. I have a crontab entry for cacti user in the users on /var/cron/tabs/cacti directory added via "sudo -u cacti crontab -e" command.

When I run from the command line I get this error...'

nagios# sudo -u cacti php /usr/local/share/cacti/poller.php
02/02/2008 05:24:01 PM - POLLER: Poller[0] WARNING: Cron is out of sync with the Poller Interval! The Poller Interval is '300' seconds, with a maximum of a '300' second Cron, but 1207 seconds have passed since the last poll!

Which means cron is not running the command. What could the issue be?

Post by **gandalf** » Sat Feb 02, 2008 5:55 pm

To force an out-of-interval run, please add the option --force. For additional debug output, please add --debug
Reinhard

rawn027 · Post by **rawn027** » Mon Feb 04, 2008 9:31 am

Here is the output when I run that command. Nothing of use really. And my graphs are still empty.

nagios# sudo -u cacti php /usr/local/share/cacti/poller.php --force --debug
02/04/2008 09:31:35 AM - POLLER: Poller[0] WARNING: Cron is out of sync with the Poller Interval! The Poller Interval is '300' seconds, with a maximum of a '300' second Cron, but 143621 seconds have passed since the last poll!
OK u:0.01 s:0.00 r:0.85
OK u:0.01 s:0.00 r:0.86
OK u:0.01 s:0.00 r:1.87
OK u:0.01 s:0.00 r:1.87
OK u:0.01 s:0.00 r:1.87
02/04/2008 09:31:37 AM - SYSTEM STATS: Time:2.1569 Method:cmd.php Processes:1 Threads:N/A Hosts:3 HostsPerProcess:3 DataSources:7 RRDsProcessed:5

rawn027 · Post by **rawn027** » Mon Feb 04, 2008 11:51 am

If I use spine I get this output...

nagios# /usr/local/bin/spine
SPINE: Using spine config file [/usr/local/etc/spine.conf]
SPINE: Version 0.8.7b starting
02/04/2008 11:52:08 AM - SPINE: Poller[0] Host[1] DS[5] SCRIPT: perl /usr/local/share/cacti/scripts/loadavg_multi.pl, output: 1min:0.08 5min:0.10 10min:0.04
02/04/2008 11:52:08 AM - SPINE: Poller[0] Host[1] DS[6] SCRIPT: perl /usr/local/share/cacti/scripts/unix_users.pl , output: 2
02/04/2008 11:52:08 AM - SPINE: Poller[0] Host[1] DS[7] SCRIPT: perl /usr/local/share/cacti/scripts/unix_processes.pl, output: 80
02/04/2008 11:52:08 AM - SPINE: Poller[0] Time: 0.2109 s, Threads: 1, Hosts: 3
nagios#

rawn027 · Post by **rawn027** » Mon Feb 04, 2008 11:58 am

I dont know if this helps but these keep showing up...

Fatal error: Call to undefined function xml_parser_create() in /usr/local/share/cacti/lib/xml.php on line 29

Post by **gandalf** » Mon Feb 04, 2008 1:17 pm

Please run

Code: Select all

php -m|grep -i xml

from command line and post results
Reinhard

rawn027 · Post by **rawn027** » Mon Feb 04, 2008 2:19 pm

nagios# php -m | grep -i xml
libxml
SimpleXML
xmlreader
xmlrpc
xmlwriter

Post by **gandalf** » Mon Feb 04, 2008 3:47 pm

I've got xml as an additional php module. May you please try to install it? I never checked, which exact modules are required, sorry
Reinhard

rawn027 · Post by **rawn027** » Wed Feb 06, 2008 11:37 pm

I got the xml issue sorted out, the problem still is that the graphs are empty.

I am running FreeBSD 6.3 with net-snmp 5.3.2 and php 5.2.5 with suhosin security patch.

Any ideas?

rawn027 · Post by **rawn027** » Wed Feb 06, 2008 11:48 pm

The graphs are reporting nan yet none of the checks in your pdf look bad.

-Ron

rawn027 · Post by **rawn027** » Thu Feb 07, 2008 12:15 am

Here is the contents of my log file

02/07/2008 12:13:47 AM - CMDPHP: Poller[0] Host[1] DS[3] WARNING: Result from CMD not valid. Partial Result:
02/07/2008 12:13:47 AM - CMDPHP: Poller[0] Host[1] DS[4] WARNING: Result from CMD not valid. Partial Result:
02/07/2008 12:13:47 AM - CMDPHP: Poller[0] Host[2] DS[8] WARNING: Result from SNMP not valid. Partial Result:
02/07/2008 12:13:48 AM - SYSTEM STATS: Time:1.1245 Method:cmd.php Processes:1 Threads:N/A Hosts:3 HostsPerProcess:3 DataSources:8 RRDsProcessed:7

The first two errors is because FreeBSD doesnt have /proc/meminfo so thats not big deal. Still all graphs are NaN and snmpget on that interface stats work fine. Yet all my rra's have this response...

[root@ /usr/ports/lang/php5-extensions]# sudo -u cacti php /opt/lsws/cacti/poller.php --force --debug
cat: /proc/meminfo: No such file or directory
cat: /proc/meminfo: No such file or directory
OK u:0.00 s:0.01 r:1.02
OK u:0.00 s:0.01 r:1.03
OK u:0.00 s:0.01 r:1.03
OK u:0.00 s:0.01 r:1.03
OK u:0.00 s:0.01 r:1.03
OK u:0.00 s:0.01 r:1.03
OK u:0.00 s:0.01 r:1.03
02/07/2008 12:09:25 AM - SYSTEM STATS: Time:1.1389 Method:cmd.php Processes:1 Threads:N/A Hosts:3 HostsPerProcess:3 DataSources:8 RRDsProcessed:7

rawn027 · Post by **rawn027** » Thu Feb 07, 2008 12:19 am

Here is my cacti.log with poller.php is on debug

[root@ /opt/lsws/cacti/log]# cat cacti.log

02/07/2008 12:18:44 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select count(*) from poller_item where rrd_next_step<=0"
02/07/2008 12:18:44 AM - POLLER: Poller[0] NOTE: Poller Int: '300', Cron Int: '300', Time Since Last: '297', Max Runtime '298', Poller Runs: '1'
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "replace into settings (name,value) values ('poller_lastrun',1202361524)"
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select id from host where disabled = '' order by id"
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "replace into settings (name,value) values ('path_webroot','/opt/lsws/cacti')"
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "TRUNCATE TABLE poller_time"
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "SELECT local_data_id, rrd_name FROM poller_output"
02/07/2008 12:18:44 AM - POLLER: Poller[0] DEBUG: About to Spawn a Remote Process [CMD: /usr/local/bin/php, ARGS: -q /opt/lsws/cacti/cmd.php 0 2]
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "replace into settings (name,value) values ('date',NOW())"
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select poller_id,end_time from poller_time where poller_id=0"
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select poller_output.output, poller_output.time, poller_output.local_data_id, poller_item.rrd_path, poller_item.rrd_name, poller_item.rrd_num from (poller_output,poller_item) where (poller_output.local_data_id=poller_item.local_data_id and poller_output.rrd_name=poller_item.rrd_name) LIMIT 10000"
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: " SELECT * FROM host WHERE (disabled = '' AND id >= 0 AND id <= 2) ORDER by id"
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "SELECT * FROM poller_item WHERE (host_id >= 0 AND host_id <= 2 AND rrd_next_step <= 0) ORDER by host_id"
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "SELECT count(*) FROM poller_item WHERE (action=2 AND host_id >= 0 AND host_id <= 2 AND rrd_next_step <= 0)"
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "UPDATE poller_item SET rrd_next_step = rrd_next_step - 300 WHERE (host_id >= 0 AND host_id <= 2)"
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "UPDATE poller_item SET rrd_next_step = rrd_step - 300 WHERE (rrd_next_step < 0 AND host_id >= 0 AND host_id <= 2)"
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] Host[1] PING: Host is alive
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "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 = '0.13000', max_time = '1.05000', cur_time = '0.16', avg_time = '0.283003', total_polls = '10', failed_polls = '0', availability = '100' where hostname = '127.0.0.1'"
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select poller_reindex.data_query_id, poller_reindex.action, poller_reindex.op, poller_reindex.assert_value, poller_reindex.arg1 from poller_reindex where poller_reindex.host_id=1"
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] Host[1] DS[3] WARNING: Result from CMD not valid. Partial Result:
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] Host[1] DS[3] CMD: perl /opt/lsws/cacti/scripts/linux_memory.pl MemFree:, output: U
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_output (local_data_id, rrd_name, time, output) values (3, 'mem_buffers', '2008-02-07 00:18:44', 'U')"
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] Host[1] DS[4] WARNING: Result from CMD not valid. Partial Result:
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] Host[1] DS[4] CMD: perl /opt/lsws/cacti/scripts/linux_memory.pl SwapFree:, output: U
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_output (local_data_id, rrd_name, time, output) values (4, 'mem_swap', '2008-02-07 00:18:44', 'U')"
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] Host[1] DS[5] CMD: perl /opt/lsws/cacti/scripts/loadavg_multi.pl, output: 1min:0.00 5min:0.07 10min:0.31
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_output (local_data_id, rrd_name, time, output) values (5, '', '2008-02-07 00:18:44', '1min:0.00 5min:0.07 10min:0.31')"
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] Host[1] DS[6] CMD: perl /opt/lsws/cacti/scripts/unix_users.pl , output: 2
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_output (local_data_id, rrd_name, time, output) values (6, 'users', '2008-02-07 00:18:44', '2')"
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] Host[1] DS[7] CMD: perl /opt/lsws/cacti/scripts/unix_processes.pl, output: 85
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_output (local_data_id, rrd_name, time, output) values (7, 'proc', '2008-02-07 00:18:44', '85')"
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] Host[2] SNMP: Host responded to SNMP
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "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 = '1.76000', max_time = '2.06', cur_time = '2.06', avg_time = '1.8666666666667', total_polls = '6', failed_polls = '0', availability = '100' where hostname = '192.168.248.128'"
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select poller_reindex.data_query_id, poller_reindex.action, poller_reindex.op, poller_reindex.assert_value, poller_reindex.arg1 from poller_reindex where poller_reindex.host_id=2"
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] Host[2] RECACHE: Processing 3 items in the auto reindex cache for '192.168.248.128'.
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "update poller_reindex set assert_value='85496' where host_id='2' and data_query_id='1' and arg1='.1.3.6.1.2.1.1.3.0'"
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "update poller_reindex set assert_value='85496' where host_id='2' and data_query_id='8' and arg1='.1.3.6.1.2.1.1.3.0'"
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "update poller_reindex set assert_value='85496' where host_id='2' and data_query_id='9' and arg1='.1.3.6.1.2.1.1.3.0'"
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] Host[2] DS[8] WARNING: Result from SNMP not valid. Partial Result:
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] Host[2] DS[8] SNMP: v2: 192.168.248.128, dsname: snmp_oid, oid: , output: U
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_output (local_data_id, rrd_name, time, output) values (8, 'snmp_oid', '2008-02-07 00:18:44', 'U')"
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] Host[2] DS[9] SNMP: v2: 192.168.248.128, dsname: traffic_in, oid: .1.3.6.1.2.1.2.2.1.10.1, output: 198925127
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_output (local_data_id, rrd_name, time, output) values (9, 'traffic_in', '2008-02-07 00:18:44', '198925127')"
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] Host[2] DS[9] SNMP: v2: 192.168.248.128, dsname: traffic_out, oid: .1.3.6.1.2.1.2.2.1.16.1, output: 19480589
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_output (local_data_id, rrd_name, time, output) values (9, 'traffic_out', '2008-02-07 00:18:44', '19480589')"
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] Time: 0.1089 s, Theads: N/A, Hosts: 2
02/07/2008 12:18:44 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_time (poller_id, start_time, end_time) values (0, NOW(), NOW())"
02/07/2008 12:18:45 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select poller_id,end_time from poller_time where poller_id=0"
02/07/2008 12:18:45 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select poller_output.output, poller_output.time, poller_output.local_data_id, poller_item.rrd_path, poller_item.rrd_name, poller_item.rrd_num from (poller_output,poller_item) where (poller_output.local_data_id=poller_item.local_data_id and poller_output.rrd_name=poller_item.rrd_name) "
02/07/2008 12:18:45 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select data_template_rrd.data_source_name, data_input_fields.data_name from (data_template_rrd,data_input_fields) where data_template_rrd.data_input_field_id=data_input_fields.id and data_template_rrd.local_data_id=5"
02/07/2008 12:18:45 AM - POLLER: Poller[0] Parsed MULTI output field '1min:0.00' [map 1min->load_1min]
02/07/2008 12:18:45 AM - POLLER: Poller[0] Parsed MULTI output field '5min:0.07' [map 5min->load_5min]
02/07/2008 12:18:45 AM - POLLER: Poller[0] Parsed MULTI output field '10min:0.31' [map 10min->load_15min]
02/07/2008 12:18:45 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='9' and rrd_name='traffic_out' and time='2008-02-07 00:18:44'"
02/07/2008 12:18:45 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='9' and rrd_name='traffic_in' and time='2008-02-07 00:18:44'"
02/07/2008 12:18:45 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='8' and rrd_name='snmp_oid' and time='2008-02-07 00:18:44'"
02/07/2008 12:18:45 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='7' and rrd_name='proc' and time='2008-02-07 00:18:44'"
02/07/2008 12:18:45 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='6' and rrd_name='users' and time='2008-02-07 00:18:44'"
02/07/2008 12:18:45 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='5' and rrd_name='' and time='2008-02-07 00:18:44'"
02/07/2008 12:18:45 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='4' and rrd_name='mem_swap' and time='2008-02-07 00:18:44'"
02/07/2008 12:18:45 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='3' and rrd_name='mem_buffers' and time='2008-02-07 00:18:44'"
02/07/2008 12:18:45 AM - POLLER: Poller[0] CACTI2RRD: /usr/local/bin/rrdtool update /opt/lsws/cacti/rra/freebsd_server_traffic_in_9.rrd --template traffic_out:traffic_in 1202361524:19480589:198925127
02/07/2008 12:18:45 AM - POLLER: Poller[0] CACTI2RRD: /usr/local/bin/rrdtool update /opt/lsws/cacti/rra/freebsd_server_snmp_oid_8.rrd --template snmp_oid 1202361524:U
02/07/2008 12:18:45 AM - POLLER: Poller[0] CACTI2RRD: /usr/local/bin/rrdtool update /opt/lsws/cacti/rra/localhost_proc_7.rrd --template proc 1202361524:85
02/07/2008 12:18:45 AM - POLLER: Poller[0] CACTI2RRD: /usr/local/bin/rrdtool update /opt/lsws/cacti/rra/localhost_users_6.rrd --template users 1202361524:2
02/07/2008 12:18:45 AM - POLLER: Poller[0] CACTI2RRD: /usr/local/bin/rrdtool update /opt/lsws/cacti/rra/localhost_load_1min_5.rrd --template load_1min:load_5min:load_15min 1202361524:0.00:0.07:0.31
02/07/2008 12:18:45 AM - POLLER: Poller[0] CACTI2RRD: /usr/local/bin/rrdtool update /opt/lsws/cacti/rra/localhost_mem_swap_4.rrd --template mem_swap 1202361524:U
02/07/2008 12:18:45 AM - POLLER: Poller[0] CACTI2RRD: /usr/local/bin/rrdtool update /opt/lsws/cacti/rra/localhost_mem_buffers_3.rrd --template mem_buffers 1202361524:U
02/07/2008 12:18:45 AM - SYSTEM STATS: Time:1.1311 Method:cmd.php Processes:1 Threads:N/A Hosts:3 HostsPerProcess:3 DataSources:8 RRDsProcessed:7
02/07/2008 12:18:45 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "replace into settings (name,value) values ('stats_poller','Time:1.1311 Method:cmd.php Processes:1 Threads:N/A Hosts:3 HostsPerProcess:3 DataSources:8 RRDsProcessed:7')"
02/07/2008 12:18:45 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select count(*) from poller_command"

Post by **gandalf** » Thu Feb 07, 2008 4:08 pm

rawn027 wrote:02/07/2008 12:18:45 AM - POLLER: Poller[0] CACTI2RRD: /usr/local/bin/rrdtool update /opt/lsws/cacti/rra/freebsd_server_traffic_in_9.rrd --template traffic_out:traffic_in 1202361524:19480589:198925127
02/07/2008 12:18:45 AM - POLLER: Poller[0] CACTI2RRD: /usr/local/bin/rrdtool update /opt/lsws/cacti/rra/freebsd_server_snmp_oid_8.rrd --template snmp_oid 1202361524:U
02/07/2008 12:18:45 AM - POLLER: Poller[0] CACTI2RRD: /usr/local/bin/rrdtool update /opt/lsws/cacti/rra/localhost_proc_7.rrd --template proc 1202361524:85
02/07/2008 12:18:45 AM - POLLER: Poller[0] CACTI2RRD: /usr/local/bin/rrdtool update /opt/lsws/cacti/rra/localhost_users_6.rrd --template users 1202361524:2
02/07/2008 12:18:45 AM - POLLER: Poller[0] CACTI2RRD: /usr/local/bin/rrdtool update /opt/lsws/cacti/rra/localhost_load_1min_5.rrd --template load_1min:load_5min:load_15min 1202361524:0.00:0.07:0.31
02/07/2008 12:18:45 AM - POLLER: Poller[0] CACTI2RRD: /usr/local/bin/rrdtool update /opt/lsws/cacti/rra/localhost_mem_swap_4.rrd --template mem_swap 1202361524:U
02/07/2008 12:18:45 AM - POLLER: Poller[0] CACTI2RRD: /usr/local/bin/rrdtool update /opt/lsws/cacti/rra/localhost_mem_buffers_3.rrd --template mem_buffers 1202361524:U
02/07/2008 12:18:45 AM - SYSTEM STATS: Time:1.1311 Method:cmd.php Processes:1 Threads:N/A Hosts:3 HostsPerProcess:3 DataSources:8 RRDsProcessed:7
02/07/2008 12:18:45 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "replace into settings (name,value) values ('stats_poller','Time:1.1311 Method:cmd.php Processes:1 Threads:N/A Hosts:3 HostsPerProcess:3 DataSources:8 RRDsProcessed:7')"
02/07/2008 12:18:45 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select count(*) from poller_command"

7 rrds are processed. Find them above. Even those graphs aren't showing anything? Please post a screenshot from Graph Management for one of them having switched to DEBUG before
Reinhard

rawn027 · Post by **rawn027** » Fri Feb 08, 2008 5:49 pm

Here is the picture of the graphs. That one spike is when I run this manually but I am watching my /var/log/cron and the poller is running every 5 minutes.

Post by **gandalf** » Sat Feb 09, 2008 4:26 am

When running manually, which userid did you use?
Reinhard

Cacti

CRON fails to run and graphs have no data.

CRON fails to run and graphs have no data.

Last Update

Re: Last Update

Who is online