No Graphs, but RRDs

[MrBugSir]

Hello. Sorry to burden you with another graph problem, but I have been trying to work out the problem for a week. I have viewed the FAQ, used FileMon, described below. I installed Cacti and all its other parts, and everything seems to work OK, except it doesn't actually display the graphs. It does indeed create the *.rrd files in the rrd directory.

Here is the debug from one cycle:

06/27/2006 08:02:01 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select count(*) from poller_item"
06/27/2006 08:02:01 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select id from host where disabled = '' order by id"
06/27/2006 08:02:01 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "replace into settings (name,value) values ('path_webroot','c:/Servers/Cacti/WebSite')"
06/27/2006 08:02:01 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "truncate table poller_time"
06/27/2006 08:02:01 AM - POLLER: Poller[0] DEBUG: About to Spawn a Remote Process [CMD: C:/Servers/php/php.exe, ARGS: -q c:/servers/cacti/website/cmd.php 0 3]
06/27/2006 08:02:02 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "replace into settings (name,value) values ('date',NOW())"
06/27/2006 08:02:02 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select poller_id,end_time from poller_time where poller_id = 0"
06/27/2006 08:02:02 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)"
06/27/2006 08:02:03 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select * from host where (disabled = '' and id >= 0 and id <= 3) ORDER by id"
06/27/2006 08:02:03 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "SELECT * from poller_item WHERE (host_id >= 0 and host_id <= 3) ORDER by host_id"
06/27/2006 08:02:03 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "SELECT count(*) from poller_item WHERE (action=2 AND (host_id >= 0 and host_id <= 3))"
06/27/2006 08:02:03 AM - CMDPHP: Poller[0] Host[2] SNMP: Host responded to SNMP
06/27/2006 08:02:03 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "update host set status = '3', status_event_count = '0', status_fail_date = '2006-06-26 11:37:04', status_rec_date = '0000-00-00 00:00:00', status_last_error = 'Host did not respond to SNMP', min_time = '9.99999', max_time = '556.69000', cur_time = '53.95', avg_time = '57.9491276739', total_polls = '1425', failed_polls = '2', availability = '99.8596491228' where hostname = '207.55.226.212'"
06/27/2006 08:02:03 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"
06/27/2006 08:02:03 AM - CMDPHP: Poller[0] Host[2] RECACHE: Processing 1 items in the auto reindex cache for '207.55.226.212'.
06/27/2006 08:02:03 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "update poller_reindex set assert_value='52309200' where host_id='2' and data_query_id='1' and arg1='.1.3.6.1.2.1.1.3.0'"
06/27/2006 08:02:03 AM - CMDPHP: Poller[0] Host[2] DS[8] SNMP: v1: 207.55.226.212, dsname: traffic_in, oid: .1.3.6.1.2.1.2.2.1.10.1, output: 1689112797
06/27/2006 08:02:03 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_output (local_data_id,rrd_name,time,output) values (8,'traffic_in','2006-06-27 08:02:02','1689112797')"
06/27/2006 08:02:03 AM - CMDPHP: Poller[0] Host[2] DS[8] SNMP: v1: 207.55.226.212, dsname: traffic_out, oid: .1.3.6.1.2.1.2.2.1.16.1, output: 968594747
06/27/2006 08:02:03 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_output (local_data_id,rrd_name,time,output) values (8,'traffic_out','2006-06-27 08:02:02','968594747')"
06/27/2006 08:02:03 AM - CMDPHP: Poller[0] Host[3] SNMP: Host responded to SNMP
06/27/2006 08:02:03 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 = '9.99999', max_time = '95.49000', cur_time = '94.95', avg_time = '95.164995', total_polls = '4', failed_polls = '0', availability = '100' where hostname = '209.63.81.14'"
06/27/2006 08:02:03 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=3"
06/27/2006 08:02:03 AM - CMDPHP: Poller[0] Host[3] RECACHE: Processing 1 items in the auto reindex cache for '209.63.81.14'.
06/27/2006 08:02:03 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "update poller_reindex set assert_value='888523685' where host_id='3' and data_query_id='1' and arg1='.1.3.6.1.2.1.1.3.0'"
06/27/2006 08:02:03 AM - CMDPHP: Poller[0] Host[3] DS[9] SNMP: v1: 209.63.81.14, dsname: traffic_in, oid: .1.3.6.1.2.1.2.2.1.10.1, output: 3590692094
06/27/2006 08:02:03 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_output (local_data_id,rrd_name,time,output) values (9,'traffic_in','2006-06-27 08:02:02','3590692094')"
06/27/2006 08:02:03 AM - CMDPHP: Poller[0] Host[3] DS[9] SNMP: v1: 209.63.81.14, dsname: traffic_out, oid: .1.3.6.1.2.1.2.2.1.16.1, output: 3658386287
06/27/2006 08:02:03 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_output (local_data_id,rrd_name,time,output) values (9,'traffic_out','2006-06-27 08:02:02','3658386287')"
06/27/2006 08:02:03 AM - CMDPHP: Poller[0] Time: 0.7590 s, Theads: N/A, Hosts: 2
06/27/2006 08:02:03 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_time (poller_id, start_time, end_time) values (0, NOW(), NOW())"
06/27/2006 08:02:03 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select poller_id,end_time from poller_time where poller_id = 0"
06/27/2006 08:02:03 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)"
06/27/2006 08:02:03 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='8' and rrd_name='traffic_in' and time='2006-06-27 08:02:02'"
06/27/2006 08:02:04 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='8' and rrd_name='traffic_out' and time='2006-06-27 08:02:02'"
06/27/2006 08:02:04 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='9' and rrd_name='traffic_in' and time='2006-06-27 08:02:02'"
06/27/2006 08:02:04 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='9' and rrd_name='traffic_out' and time='2006-06-27 08:02:02'"
06/27/2006 08:02:04 AM - POLLER: Poller[0] CACTI2RRD: C:/Servers/Cacti/rrdtool/rrdtool.exe update C:\Servers\Cacti\WebSite\rra\medford_firewall_traffic_in_8.rrd --template traffic_in:traffic_out 1151420522:1689112797:968594747
06/27/2006 08:02:04 AM - POLLER: Poller[0] CACTI2RRD: C:/Servers/Cacti/rrdtool/rrdtool.exe update C:\Servers\Cacti\WebSite\rra\bethlehem_router_traffic_in_9.rrd --template traffic_in:traffic_out 1151420522:3590692094:3658386287
06/27/2006 08:02:04 AM - SYSTEM STATS: Time:2.2654 Method:cmd.php Processes:1 Threads:N/A Hosts:3 HostsPerProcess:3 DataSources:4 RRDsProcessed:2
06/27/2006 08:02:04 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "replace into settings (name,value) values ('stats_poller','Time:2.2654 Method:cmd.php Processes:1 Threads:N/A Hosts:3 HostsPerProcess:3 DataSources:4 RRDsProcessed:2')"
06/27/2006 08:02:04 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "truncate table poller_output"
06/27/2006 08:02:04 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select count(*) from poller_command"
06/27/2006 08:02:18 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select count(*) from poller_item"
06/27/2006 08:02:18 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select id from host where disabled = '' order by id"
06/27/2006 08:02:18 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "replace into settings (name,value) values ('path_webroot','c:/Servers/Cacti/WebSite')"
06/27/2006 08:02:18 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "truncate table poller_time"
06/27/2006 08:02:18 AM - POLLER: Poller[0] DEBUG: About to Spawn a Remote Process [CMD: C:/Servers/php/php.exe, ARGS: -q c:/servers/cacti/website/cmd.php 0 3]
06/27/2006 08:02:19 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select * from host where (disabled = '' and id >= 0 and id <= 3) ORDER by id"
06/27/2006 08:02:19 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "SELECT * from poller_item WHERE (host_id >= 0 and host_id <= 3) ORDER by host_id"
06/27/2006 08:02:19 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "SELECT count(*) from poller_item WHERE (action=2 AND (host_id >= 0 and host_id <= 3))"
06/27/2006 08:02:19 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "replace into settings (name,value) values ('date',NOW())"
06/27/2006 08:02:19 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select poller_id,end_time from poller_time where poller_id = 0"
06/27/2006 08:02:19 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)"
06/27/2006 08:02:19 AM - CMDPHP: Poller[0] Host[2] SNMP: Host responded to SNMP
06/27/2006 08:02:19 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "update host set status = '3', status_event_count = '0', status_fail_date = '2006-06-26 11:37:04', status_rec_date = '0000-00-00 00:00:00', status_last_error = 'Host did not respond to SNMP', min_time = '9.99999', max_time = '556.69000', cur_time = '56.27', avg_time = '57.9479408427', total_polls = '1426', failed_polls = '2', availability = '99.8597475456' where hostname = '207.55.226.212'"
06/27/2006 08:02:19 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"
06/27/2006 08:02:19 AM - CMDPHP: Poller[0] Host[2] RECACHE: Processing 1 items in the auto reindex cache for '207.55.226.212'.
06/27/2006 08:02:19 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "update poller_reindex set assert_value='52310800' where host_id='2' and data_query_id='1' and arg1='.1.3.6.1.2.1.1.3.0'"
06/27/2006 08:02:19 AM - CMDPHP: Poller[0] Host[2] DS[8] SNMP: v1: 207.55.226.212, dsname: traffic_in, oid: .1.3.6.1.2.1.2.2.1.10.1, output: 1689131262
06/27/2006 08:02:19 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_output (local_data_id,rrd_name,time,output) values (8,'traffic_in','2006-06-27 08:02:19','1689131262')"
06/27/2006 08:02:19 AM - CMDPHP: Poller[0] Host[2] DS[8] SNMP: v1: 207.55.226.212, dsname: traffic_out, oid: .1.3.6.1.2.1.2.2.1.16.1, output: 968618099
06/27/2006 08:02:19 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_output (local_data_id,rrd_name,time,output) values (8,'traffic_out','2006-06-27 08:02:19','968618099')"
06/27/2006 08:02:19 AM - CMDPHP: Poller[0] Host[3] SNMP: Host responded to SNMP
06/27/2006 08:02:19 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 = '9.99999', max_time = '95.49000', cur_time = '95.36', avg_time = '95.203992', total_polls = '5', failed_polls = '0', availability = '100' where hostname = '209.63.81.14'"
06/27/2006 08:02:19 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=3"
06/27/2006 08:02:19 AM - CMDPHP: Poller[0] Host[3] RECACHE: Processing 1 items in the auto reindex cache for '209.63.81.14'.
06/27/2006 08:02:20 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "update poller_reindex set assert_value='888525326' where host_id='3' and data_query_id='1' and arg1='.1.3.6.1.2.1.1.3.0'"
06/27/2006 08:02:20 AM - CMDPHP: Poller[0] Host[3] DS[9] SNMP: v1: 209.63.81.14, dsname: traffic_in, oid: .1.3.6.1.2.1.2.2.1.10.1, output: 3590916453
06/27/2006 08:02:20 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_output (local_data_id,rrd_name,time,output) values (9,'traffic_in','2006-06-27 08:02:19','3590916453')"
06/27/2006 08:02:20 AM - CMDPHP: Poller[0] Host[3] DS[9] SNMP: v1: 209.63.81.14, dsname: traffic_out, oid: .1.3.6.1.2.1.2.2.1.16.1, output: 3658413576
06/27/2006 08:02:20 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_output (local_data_id,rrd_name,time,output) values (9,'traffic_out','2006-06-27 08:02:19','3658413576')"
06/27/2006 08:02:20 AM - CMDPHP: Poller[0] Time: 0.6732 s, Theads: N/A, Hosts: 2
06/27/2006 08:02:20 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_time (poller_id, start_time, end_time) values (0, NOW(), NOW())"
06/27/2006 08:02:20 AM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select poller_id,end_time from poller_time where poller_id = 0"
06/27/2006 08:02:20 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)"
06/27/2006 08:02:20 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='8' and rrd_name='traffic_in' and time='2006-06-27 08:02:19'"
06/27/2006 08:02:20 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='8' and rrd_name='traffic_out' and time='2006-06-27 08:02:19'"
06/27/2006 08:02:20 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='9' and rrd_name='traffic_in' and time='2006-06-27 08:02:19'"
06/27/2006 08:02:20 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='9' and rrd_name='traffic_out' and time='2006-06-27 08:02:19'"
06/27/2006 08:02:20 AM - POLLER: Poller[0] CACTI2RRD: C:/Servers/Cacti/rrdtool/rrdtool.exe update C:\Servers\Cacti\WebSite\rra\medford_firewall_traffic_in_8.rrd --template traffic_in:traffic_out 1151420539:1689131262:968618099
06/27/2006 08:02:20 AM - POLLER: Poller[0] CACTI2RRD: C:/Servers/Cacti/rrdtool/rrdtool.exe update C:\Servers\Cacti\WebSite\rra\bethlehem_router_traffic_in_9.rrd --template traffic_in:traffic_out 1151420539:3590916453:3658413576
06/27/2006 08:02:20 AM - SYSTEM STATS: Time:2.2819 Method:cmd.php Processes:1 Threads:N/A Hosts:3 HostsPerProcess:3 DataSources:4 RRDsProcessed:2
06/27/2006 08:02:20 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "replace into settings (name,value) values ('stats_poller','Time:2.2819 Method:cmd.php Processes:1 Threads:N/A Hosts:3 HostsPerProcess:3 DataSources:4 RRDsProcessed:2')"
06/27/2006 08:02:20 AM - CMDPHP: Poller[0] DEBUG: SQL Exec: "truncate table poller_output"
06/27/2006 08:02:20 AM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select count(*) from poller_command"

I was able to detect the following file permission errors.

8:10:37 AM rrdtool.exe:2412 OPEN C:\Servers\Cacti\WebSite\rra\medford_firewall_traffic_in_8.rrd ACCESS DENIED INTREPID\svcCacti

8:28:25 AM CSRSS.EXE:196 OPEN C:\WINNT\system32\cmd.exe ACCESS DENIED INTREPID\IUSR_INTREPID
8:28:25 AM CSRSS.EXE:196 OPEN C:\WINNT\system32\cmd.exe SUCCESS Options: Open Access: All

Curiously, after the DENIED on the CMD.exe, there was an immedidate SUCCESS. Is this normal?

"svcCacti" runs the scheduled task, and has Read/Execute/Write permissions both to the RRDTool and RRA directories, as well as CMD.exe. So does the IUSR account. Any ideas?


Operating System: Windows 2000 SP4
Webserver: IIS 5
Cacti: 0.8.6h
Cactid: 0.8.6g
MySQL: 4.1.14
PHP: 4.4.2.2
RRDTool (CYGWIN version): 1.2

Thanks for your help.

[BSOD2600]

Looks like you're almost there...

While you might have applied NTFS security to the folders, I would double check the *.rrd files, rrdtool.exe, and cmd.exe DO grant rights to INTREPID\IUSR_INTREPID and INTREPID\svcCacti. Some times inheritance is turned off.

[MrBugSir]

Just a happy note back stating I found the problem. This may be a no brainer, but if one runs on Windows 2000, Cacti will default to a bad directory for the fonts: C:\Windows\Fonts.

This was apparent with the use of FileMon, a very handy tool. Seeing lots of "Path Not Found" errors was a clue.

Thanks for you help, and for a lovely monitoring tool.