Graphs won't update

Bombe · Post by **Bombe** » Mon Apr 30, 2007 4:03 pm

All the graphs stopped updating at the same time. The only thing I can find is an error in the debug out that the host did not respond to SNMP. There's only 1 host. The local host. It does respond to SNMP.

Cacti beta installer, cacti-0.8.6j.1.5.exe on win 2003 server.

php is rolled back to 5.2.0.

SNMP Header and verbose query from the device.

Code: Select all

SNMP Information
System: Hardware: x86 Family 15 Model 2 Stepping 7 AT/AT COMPATIBLE - Software: Windows Version 5.2 (Build 3790 Multiprocessor Free)
Uptime: 400566 (0 days, 1 hours, 6 minutes)
Hostname: **************
Location: ***************
Contact: **************

+ Running data query [8].
+ Found type = '6 '[script query].
+ Found data query XML file at 'C:/Inetpub/wwwroot/cacti/resource/script_server/host_disk.xml'
+ XML file parsed ok.
+ Executing script for list of indexes 'c:\php\php.exe -q C:\Inetpub\wwwroot\cacti\scripts\ss_host_disk.php 143.91.64.187 2 1:161:500:ins-sec-ips:::MD5::[None] index'
+ Executing script query 'c:\php\php.exe -q C:\Inetpub\wwwroot\cacti\scripts\ss_host_disk.php 143.91.64.187 2 1:161:500:ins-sec-ips:::MD5::[None] query index'
+ Found item [hrStorageIndex='1'] index: 1
+ Found item [hrStorageIndex='2'] index: 2
+ Found item [hrStorageIndex='3'] index: 3
+ Found item [hrStorageIndex='4'] index: 4
+ Found item [hrStorageIndex='5'] index: 5
+ Executing script query 'c:\php\php.exe -q C:\Inetpub\wwwroot\cacti\scripts\ss_host_disk.php 143.91.64.187 2 1:161:500:ins-sec-ips:::MD5::[None] query description'
+ Found item [hrStorageDescr='A:'] index: 1
+ Found item [hrStorageDescr='C: Label: Serial Number 9c3d693'] index: 2
+ Found item [hrStorageDescr='D:'] index: 3
+ Found item [hrStorageDescr='Virtual Memory'] index: 4
+ Found item [hrStorageDescr='Physical Memory'] index: 5
+ Executing script query 'c:\php\php.exe -q C:\Inetpub\wwwroot\cacti\scripts\ss_host_disk.php 143.91.64.187 2 1:161:500:ins-sec-ips:::MD5::[None] query sau'
+ Found item [hrStorageAllocationUnits='0'] index: 1
+ Found item [hrStorageAllocationUnits='4096'] index: 2
+ Found item [hrStorageAllocationUnits='0'] index: 3
+ Found item [hrStorageAllocationUnits='65536'] index: 4
+ Found item [hrStorageAllocationUnits='65536'] index: 5
+ Found data query XML file at 'C:/Inetpub/wwwroot/cacti/resource/script_server/host_disk.xml'
+ Found data query XML file at 'C:/Inetpub/wwwroot/cacti/resource/script_server/host_disk.xml'
+ Found data query XML file at 'C:/Inetpub/wwwroot/cacti/resource/script_server/host_disk.xml'
+ Found data query XML file at 'C:/Inetpub/wwwroot/cacti/resource/script_server/host_disk.xml'
+ Found data query XML file at 'C:/Inetpub/wwwroot/cacti/resource/script_server/host_disk.xml'
+ Found data query XML file at 'C:/Inetpub/wwwroot/cacti/resource/script_server/host_disk.xml'

hand run poller output.

Code: Select all

C:\Documents and Settings\Administrator>\Inetpub\wwwroot\cacti\poller.php
04/30/2007 10:01:51 PM - SYSTEM STATS: Time:2.0739 Method:cactid Processes:1 Thr
eads:1 Hosts:2 HostsPerProcess:2 DataSources:10 RRDsProcessed:6
OK u:0.01 s:0.06 r:0.98
OK u:0.01 s:0.06 r:0.98
OK u:0.01 s:0.06 r:0.98
OK u:0.01 s:0.06 r:0.98
OK u:0.01 s:0.08 r:1.00
OK u:0.01 s:0.08 r:1.00

C:\Documents and Settings\Administrator>

Log in debug:

Code: Select all

04/30/2007 09:45:00 PM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select count(*) from poller_item"
04/30/2007 09:45:00 PM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select id from host where disabled = '' order by id"
04/30/2007 09:45:00 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "replace into settings (name,value) values ('path_webroot','c:/Inetpub/wwwroot/cacti')"
04/30/2007 09:45:00 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "truncate table poller_time"
04/30/2007 09:45:00 PM - POLLER: Poller[0] DEBUG: About to Spawn a Remote Process [CMD: c:/cactid/cactid.exe, ARGS:  0 2]
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '4': 'SELECT value FROM settings WHERE name = 'path_php_binary''
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '4': OK
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: The path_php variable is c:/php/php.exe
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '5': 'SELECT value FROM settings WHERE name = 'availability_method''
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '5': OK
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: The availability_method variable is 2
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '6': 'SELECT value FROM settings WHERE name = 'ping_recovery_count''
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '6': OK
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: The ping_recovery_count variable is 3
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '7': 'SELECT value FROM settings WHERE name = 'ping_failure_count''
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '7': OK
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: The ping_failure_count variable is 2
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '8': 'SELECT value FROM settings WHERE name = 'ping_method''
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '8': OK
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: The ping_method variable is 1
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '9': 'SELECT value FROM settings WHERE name = 'ping_retries''
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '9': OK
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: The ping_retries variable is 1
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '10': 'SELECT value FROM settings WHERE name = 'ping_timeout''
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '10': OK
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: The ping_timeout variable is 400
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '11': 'SELECT value FROM settings WHERE name = 'log_perror''
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '11': OK
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: The log_perror variable is 1
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '12': 'SELECT value FROM settings WHERE name = 'log_pwarn''
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '12': OK
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: The log_pwarn variable is 1
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '13': 'SELECT value FROM settings WHERE name = 'log_pstats''
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '13': OK
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: The log_pstats variable is 1
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '14': 'SELECT value FROM settings WHERE name = 'max_threads''
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '14': OK
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: The threads variable is 1
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '15': 'SELECT value FROM settings WHERE name = 'poller_interval''
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '15': OK
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: The polling interval is the system default
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '16': 'SELECT value FROM settings WHERE name = 'concurrent_processes''
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '16': OK
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: The number of concurrent processes is 1
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '17': 'SELECT value FROM settings WHERE name = 'script_timeout''
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '17': OK
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: The script timeout is 25
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '18': 'SELECT value FROM settings WHERE name = 'php_servers''
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '18': OK
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: The number of php script servers to run is 1
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '19': 'SELECT action FROM poller_item WHERE action=2 AND host_id BETWEEN 0 AND 2 LIMIT 1'
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '19': OK
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: StartHost='0', EndHost='2', TotalPHPScripts='1'
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: The PHP Script Server is Required
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '20': 'SELECT value FROM settings WHERE name = 'max_get_size''
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '20': OK
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: The Maximum SNMP OID Get Size is 10
04/30/2007 08:45:01 PM - CACTID: Poller[0] CACTID: Version 0.8.6i starting
04/30/2007 08:45:01 PM - CACTID: Poller[0] MYSQL: Connecting to MySQL database 'cacti' on '127.0.0.1'...
04/30/2007 08:45:01 PM - CACTID: Poller[0] MYSQL: Connected to MySQL database 'cacti' on '127.0.0.1'...
04/30/2007 08:45:01 PM - CACTID: Poller[0] CACTID: Initializing Net-SNMP API
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: SNMP Header Version is 5.1.4
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: SNMP Library Version is 5.1.4
04/30/2007 08:45:01 PM - CACTID: Poller[0] CACTID: Initializing PHP Script Server(s)
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: SS[0] PHP Script Server Routine Starting
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: SS[0] PHP Script Server About to FORK Child Process
04/30/2007 09:45:01 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "replace into settings (name,value) values ('date',NOW())"
04/30/2007 09:45:01 PM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select poller_id,end_time from poller_time where poller_id = 0"
04/30/2007 09:45:01 PM - 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"
04/30/2007 08:45:01 PM - CACTID: Poller[0] DEBUG: SS[0] PHP Script Server Child FORK Success
04/30/2007 09:45:02 PM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select count(*) from poller_item"
04/30/2007 09:45:02 PM - PHPSVR: Poller[0] DEBUG: SERVER: cactid
04/30/2007 09:45:02 PM - PHPSVR: Poller[0] DEBUG: GETCWD: c:/cactid
04/30/2007 09:45:02 PM - PHPSVR: Poller[0] DEBUG: DIRNAM: c:/inetpub/wwwroot/cacti
04/30/2007 09:45:02 PM - PHPSVR: Poller[0] DEBUG: FILENM: C:\Inetpub\wwwroot\cacti\script_server.php
04/30/2007 09:45:02 PM - PHPSVR: Poller[0] PHP Script Server has Started - Parent is cactid
04/30/2007 08:45:02 PM - CACTID: Poller[0] DEBUG: SS[0] Confirmed PHP Script Server running
04/30/2007 08:45:02 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '21': 'SELECT id FROM host WHERE disabled='' AND id BETWEEN 0 AND 2 ORDER BY id'
04/30/2007 08:45:02 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '21': OK
04/30/2007 08:45:02 PM - CACTID: Poller[0] DEBUG: Initial Value of Active Threads is 0
04/30/2007 08:45:02 PM - CACTID: Poller[0] DEBUG: Valid Thread to be Created
04/30/2007 08:45:02 PM - CACTID: Poller[0] DEBUG: In Poller, About to Start Polling of Host
04/30/2007 08:45:02 PM - CACTID: Poller[0] DEBUG: The Value of Active Threads is 1
04/30/2007 08:45:02 PM - CACTID: Poller[0] MYSQL: Connecting to MySQL database 'cacti' on '127.0.0.1'...
04/30/2007 08:45:02 PM - CACTID: Poller[0] MYSQL: Connected to MySQL database 'cacti' on '127.0.0.1'...
04/30/2007 08:45:02 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '22': '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 FROM poller_item WHERE host_id=0 ORDER BY arg1'
04/30/2007 08:45:02 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '22': OK
04/30/2007 08:45:02 PM - CACTID: Poller[0] Host[0] DEBUG: HOST COMPLETE: About to Exit Host Polling Thread Function
04/30/2007 08:45:02 PM - CACTID: Poller[0] DEBUG: The Value of Active Threads is 0
04/30/2007 08:45:02 PM - CACTID: Poller[0] DEBUG: In Poller, About to Start Polling of Host
04/30/2007 08:45:02 PM - CACTID: Poller[0] DEBUG: Valid Thread to be Created
04/30/2007 08:45:02 PM - CACTID: Poller[0] MYSQL: Connecting to MySQL database 'cacti' on '127.0.0.1'...
04/30/2007 08:45:02 PM - CACTID: Poller[0] DEBUG: The Value of Active Threads is 1
04/30/2007 08:45:02 PM - CACTID: Poller[0] MYSQL: Connected to MySQL database 'cacti' on '127.0.0.1'...
04/30/2007 08:45:02 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '23': 'SELECT id, hostname, snmp_community, snmp_username, snmp_password, snmp_version, snmp_port, snmp_timeout, 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=2'
04/30/2007 08:45:02 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '23': OK
04/30/2007 08:45:02 PM - CACTID: Poller[0] Host[2] SNMP Result: Host responded to SNMP
04/30/2007 08:45:02 PM - CACTID: Poller[0] DEBUG: MySQL Insert ID '0': 'update host set status='3', status_event_count='0', status_fail_date='2007-04-30 07:40:06', status_rec_date='2007-04-30 07:50:02', status_last_error='Host did not respond to SNMP', min_time='0.000000', max_time='171.999930', cur_time='0.000000', avg_time='25.953957', total_polls='70', failed_polls='2', availability='97.1429' where id='2''
04/30/2007 08:45:02 PM - CACTID: Poller[0] DEBUG: MySQL Insert ID '0': OK
04/30/2007 08:45:02 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '24': 'SELECT data_query_id, action, op, assert_value, arg1 FROM poller_reindex WHERE host_id=2'
04/30/2007 08:45:02 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '24': OK
04/30/2007 08:45:02 PM - CACTID: Poller[0] Host[2] RECACHE: Processing 3 items in the auto reindex cache for '143.91.64.187'
04/30/2007 08:45:02 PM - CACTID: Poller[0] DEBUG: MySQL Insert ID '1': 'update poller_reindex set assert_value='341185' where host_id='2' and data_query_id='1' and arg1='.1.3.6.1.2.1.1.3.0''
04/30/2007 08:45:02 PM - CACTID: Poller[0] DEBUG: MySQL Insert ID '1': OK
04/30/2007 08:45:02 PM - CACTID: Poller[0] DEBUG: MySQL Insert ID '2': 'update poller_reindex set assert_value='341185' where host_id='2' and data_query_id='8' and arg1='.1.3.6.1.2.1.1.3.0''
04/30/2007 08:45:02 PM - CACTID: Poller[0] DEBUG: MySQL Insert ID '2': OK
04/30/2007 08:45:02 PM - CACTID: Poller[0] DEBUG: MySQL Insert ID '3': 'update poller_reindex set assert_value='341185' where host_id='2' and data_query_id='9' and arg1='.1.3.6.1.2.1.1.3.0''
04/30/2007 08:45:02 PM - CACTID: Poller[0] DEBUG: MySQL Insert ID '3': OK
04/30/2007 08:45:02 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '25': '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 FROM poller_item WHERE host_id=2 ORDER BY arg1'
04/30/2007 08:45:02 PM - CACTID: Poller[0] DEBUG: MySQL Query ID '25': OK
04/30/2007 09:45:02 PM - PHPSVR: Poller[0] DEBUG: INC: 'C:\Inetpub\wwwroot\cacti\scripts\ss_host_cpu.php' FUNC: 'ss_host_cpu' PARMS: '143.91.64.187 2 1:161:500:ins-sec-ips:::MD5::[None] get usage 0'
04/30/2007 08:45:02 PM - CACTID: Poller[0] Host[2] DS[13] SS[0] SERVER: C:\Inetpub\wwwroot\cacti\scripts\ss_host_cpu.php ss_host_cpu 143.91.64.187 2 1:161:500:ins-sec-ips:::MD5::[None] get usage 0, output: 0
04/30/2007 09:45:02 PM - PHPSVR: Poller[0] DEBUG: INC: 'C:\Inetpub\wwwroot\cacti\scripts\ss_host_cpu.php' FUNC: 'ss_host_cpu' PARMS: '143.91.64.187 2 1:161:500:ins-sec-ips:::MD5::[None] get usage 1'
04/30/2007 08:45:02 PM - CACTID: Poller[0] Host[2] DS[14] SS[0] SERVER: C:\Inetpub\wwwroot\cacti\scripts\ss_host_cpu.php ss_host_cpu 143.91.64.187 2 1:161:500:ins-sec-ips:::MD5::[None] get usage 1, output: 0
04/30/2007 09:45:02 PM - PHPSVR: Poller[0] DEBUG: INC: 'C:\Inetpub\wwwroot\cacti\scripts\ss_host_disk.php' FUNC: 'ss_host_disk' PARMS: '143.91.64.187 2 1:161:500:ins-sec-ips:::MD5::[None] get total 2'
04/30/2007 09:45:02 PM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select field_value from host_snmp_cache where host_id=2 and field_name='hrStorageAllocationUnits' and snmp_index='2'"
04/30/2007 09:45:02 PM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select poller_id,end_time from poller_time where poller_id = 0"
04/30/2007 09:45:02 PM - 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"
04/30/2007 09:45:03 PM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select poller_id,end_time from poller_time where poller_id = 0"
04/30/2007 08:45:03 PM - CACTID: Poller[0] Host[2] DS[8] SS[0] SERVER: C:\Inetpub\wwwroot\cacti\scripts\ss_host_disk.php ss_host_disk 143.91.64.187 2 1:161:500:ins-sec-ips:::MD5::[None] get total 2, output: 20012072960
04/30/2007 09:45:03 PM - PHPSVR: Poller[0] DEBUG: INC: 'C:\Inetpub\wwwroot\cacti\scripts\ss_host_disk.php' FUNC: 'ss_host_disk' PARMS: '143.91.64.187 2 1:161:500:ins-sec-ips:::MD5::[None] get total 4'
04/30/2007 09:45:03 PM - 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"
04/30/2007 09:45:03 PM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select field_value from host_snmp_cache where host_id=2 and field_name='hrStorageAllocationUnits' and snmp_index='4'"
04/30/2007 08:45:03 PM - CACTID: Poller[0] Host[2] DS[11] SS[0] SERVER: C:\Inetpub\wwwroot\cacti\scripts\ss_host_disk.php ss_host_disk 143.91.64.187 2 1:161:500:ins-sec-ips:::MD5::[None] get total 4, output: 2593652736
04/30/2007 09:45:03 PM - PHPSVR: Poller[0] DEBUG: INC: 'C:\Inetpub\wwwroot\cacti\scripts\ss_host_disk.php' FUNC: 'ss_host_disk' PARMS: '143.91.64.187 2 1:161:500:ins-sec-ips:::MD5::[None] get total 5'
04/30/2007 09:45:03 PM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select field_value from host_snmp_cache where host_id=2 and field_name='hrStorageAllocationUnits' and snmp_index='5'"
04/30/2007 08:45:03 PM - CACTID: Poller[0] Host[2] DS[12] SS[0] SERVER: C:\Inetpub\wwwroot\cacti\scripts\ss_host_disk.php ss_host_disk 143.91.64.187 2 1:161:500:ins-sec-ips:::MD5::[None] get total 5, output: 1073152000
04/30/2007 09:45:03 PM - PHPSVR: Poller[0] DEBUG: INC: 'C:\Inetpub\wwwroot\cacti\scripts\ss_host_disk.php' FUNC: 'ss_host_disk' PARMS: '143.91.64.187 2 1:161:500:ins-sec-ips:::MD5::[None] get used 2'
04/30/2007 09:45:03 PM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select field_value from host_snmp_cache where host_id=2 and field_name='hrStorageAllocationUnits' and snmp_index='2'"
04/30/2007 08:45:03 PM - CACTID: Poller[0] Host[2] DS[8] SS[0] SERVER: C:\Inetpub\wwwroot\cacti\scripts\ss_host_disk.php ss_host_disk 143.91.64.187 2 1:161:500:ins-sec-ips:::MD5::[None] get used 2, output: 5700546560
04/30/2007 09:45:03 PM - PHPSVR: Poller[0] DEBUG: INC: 'C:\Inetpub\wwwroot\cacti\scripts\ss_host_disk.php' FUNC: 'ss_host_disk' PARMS: '143.91.64.187 2 1:161:500:ins-sec-ips:::MD5::[None] get used 4'
04/30/2007 09:45:03 PM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select field_value from host_snmp_cache where host_id=2 and field_name='hrStorageAllocationUnits' and snmp_index='4'"
04/30/2007 08:45:03 PM - CACTID: Poller[0] Host[2] DS[11] SS[0] SERVER: C:\Inetpub\wwwroot\cacti\scripts\ss_host_disk.php ss_host_disk 143.91.64.187 2 1:161:500:ins-sec-ips:::MD5::[None] get used 4, output: 249626624
04/30/2007 09:45:03 PM - PHPSVR: Poller[0] DEBUG: INC: 'C:\Inetpub\wwwroot\cacti\scripts\ss_host_disk.php' FUNC: 'ss_host_disk' PARMS: '143.91.64.187 2 1:161:500:ins-sec-ips:::MD5::[None] get used 5'
04/30/2007 09:45:03 PM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select field_value from host_snmp_cache where host_id=2 and field_name='hrStorageAllocationUnits' and snmp_index='5'"
04/30/2007 08:45:03 PM - CACTID: Poller[0] Host[2] DS[12] SS[0] SERVER: C:\Inetpub\wwwroot\cacti\scripts\ss_host_disk.php ss_host_disk 143.91.64.187 2 1:161:500:ins-sec-ips:::MD5::[None] get used 5, output: 274333696
04/30/2007 08:45:03 PM - CACTID: Poller[0] Host[2] DS[9] SNMP: v1: 143.91.64.187, dsname: traffic_in, oid: .1.3.6.1.2.1.2.2.1.10.65539, value: 3428453
04/30/2007 08:45:03 PM - CACTID: Poller[0] Host[2] DS[9] SNMP: v1: 143.91.64.187, dsname: traffic_out, oid: .1.3.6.1.2.1.2.2.1.16.65539, value: 9625066
04/30/2007 08:45:03 PM - CACTID: Poller[0] DEBUG: MySQL Insert ID '4': 'INSERT INTO poller_output (local_data_id, rrd_name, time, output) VALUES (9,'traffic_in','2007-04-30 20:45:02','3428453'),(9,'traffic_out','2007-04-30 20:45:02','9625066'),(13,'cpu','2007-04-30 20:45:02','0'),(14,'cpu','2007-04-30 20:45:02','0'),(8,'hdd_total','2007-04-30 20:45:02','20012072960'),(11,'hdd_total','2007-04-30 20:45:02','2593652736'),(12,'hdd_total','2007-04-30 20:45:02','1073152000'),(8,'hdd_used','2007-04-30 20:45:02','5700546560'),(11,'hdd_used','2007-04-30 20:45:02','249626624'),(12,'hdd_used','2007-04-30 20:45:02','274333696')'
04/30/2007 08:45:03 PM - CACTID: Poller[0] DEBUG: MySQL Insert ID '4': OK
04/30/2007 08:45:03 PM - CACTID: Poller[0] Host[2] DEBUG: HOST COMPLETE: About to Exit Host Polling Thread Function
04/30/2007 08:45:03 PM - CACTID: Poller[0] DEBUG: The Value of Active Threads is 0
04/30/2007 08:45:04 PM - CACTID: Poller[0] DEBUG: MySQL Insert ID '5': 'replace into settings (name,value) values ('date',NOW())'
04/30/2007 08:45:04 PM - CACTID: Poller[0] DEBUG: MySQL Insert ID '5': OK
04/30/2007 08:45:04 PM - CACTID: Poller[0] DEBUG: MySQL Insert ID '6': 'insert into poller_time (poller_id, start_time, end_time) values (0, NOW(), NOW())'
04/30/2007 08:45:04 PM - CACTID: Poller[0] DEBUG: MySQL Insert ID '6': OK
04/30/2007 08:45:04 PM - CACTID: Poller[0] DEBUG: Thread Cleanup Complete
04/30/2007 08:45:04 PM - CACTID: Poller[0] DEBUG: SS[0] Script Server Shutdown Started
04/30/2007 09:45:04 PM - PHPSVR: Poller[0] DEBUG: PHP Script Server Shutdown request received, exiting
04/30/2007 08:45:04 PM - CACTID: Poller[0] DEBUG: PHP Script Server Pipes Closed
04/30/2007 08:45:04 PM - CACTID: Poller[0] DEBUG: Allocated Variable Memory Freed
04/30/2007 08:45:04 PM - CACTID: Poller[0] CACTID: Net-SNMP API Shutdown Completed
04/30/2007 08:45:04 PM - CACTID: Poller[0] DEBUG: MYSQL Free & Close Completed
04/30/2007 08:45:04 PM - CACTID: Poller[0] Time: 3.2180 s, Threads: 1, Hosts: 2
04/30/2007 09:45:04 PM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select poller_id,end_time from poller_time where poller_id = 0"
04/30/2007 09:45:04 PM - 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)  "
04/30/2007 09:45:04 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='9' and rrd_name='traffic_in' and time='2007-04-30 20:45:02'"
04/30/2007 09:45:04 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='9' and rrd_name='traffic_out' and time='2007-04-30 20:45:02'"
04/30/2007 09:45:04 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='13' and rrd_name='cpu' and time='2007-04-30 20:45:02'"
04/30/2007 09:45:04 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='14' and rrd_name='cpu' and time='2007-04-30 20:45:02'"
04/30/2007 09:45:04 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='8' and rrd_name='hdd_total' and time='2007-04-30 20:45:02'"
04/30/2007 09:45:04 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='11' and rrd_name='hdd_total' and time='2007-04-30 20:45:02'"
04/30/2007 09:45:04 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='12' and rrd_name='hdd_total' and time='2007-04-30 20:45:02'"
04/30/2007 09:45:04 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='8' and rrd_name='hdd_used' and time='2007-04-30 20:45:02'"
04/30/2007 09:45:04 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='11' and rrd_name='hdd_used' and time='2007-04-30 20:45:02'"
04/30/2007 09:45:04 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='12' and rrd_name='hdd_used' and time='2007-04-30 20:45:02'"
04/30/2007 09:45:04 PM - POLLER: Poller[0] CACTI2RRD: c:/rrdtool/rrdtool.exe update C:\Inetpub\wwwroot\cacti\rra\localhost_traffic_in_9.rrd --template traffic_in:traffic_out 1177962302:3428453:9625066
04/30/2007 09:45:04 PM - POLLER: Poller[0] CACTI2RRD: c:/rrdtool/rrdtool.exe update C:\Inetpub\wwwroot\cacti\rra\localhost_cpu_13.rrd --template cpu 1177962302:0
04/30/2007 09:45:04 PM - POLLER: Poller[0] CACTI2RRD: c:/rrdtool/rrdtool.exe update C:\Inetpub\wwwroot\cacti\rra\localhost_cpu_14.rrd --template cpu 1177962302:0
04/30/2007 09:45:04 PM - POLLER: Poller[0] CACTI2RRD: c:/rrdtool/rrdtool.exe update C:\Inetpub\wwwroot\cacti\rra\localhost_hdd_total_8.rrd --template hdd_total:hdd_used 1177962302:20012072960:5700546560
04/30/2007 09:45:04 PM - POLLER: Poller[0] CACTI2RRD: c:/rrdtool/rrdtool.exe update C:\Inetpub\wwwroot\cacti\rra\localhost_hdd_total_11.rrd --template hdd_total:hdd_used 1177962302:2593652736:249626624
04/30/2007 09:45:04 PM - POLLER: Poller[0] CACTI2RRD: c:/rrdtool/rrdtool.exe update C:\Inetpub\wwwroot\cacti\rra\localhost_hdd_total_12.rrd --template hdd_total:hdd_used 1177962302:1073152000:274333696
04/30/2007 09:45:04 PM - SYSTEM STATS: Time:4.0951 Method:cactid Processes:1 Threads:1 Hosts:2 HostsPerProcess:2 DataSources:10 RRDsProcessed:6
04/30/2007 09:45:04 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "replace into settings (name,value) values ('stats_poller','Time:4.0951 Method:cactid Processes:1 Threads:1 Hosts:2 HostsPerProcess:2 DataSources:10 RRDsProcessed:6')"
04/30/2007 09:45:04 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "truncate table poller_output"
04/30/2007 09:45:04 PM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select count(*) from poller_command"

Bombe · Post by **Bombe** » Mon Apr 30, 2007 6:52 pm

This is really strange. The data is either being plotted an hour after it's been collected, or it's being plotted an hour late. The server is running in GMT. This screen shot was taken at 23:45 GMT right after an update and refresh.

Anyone have any ideas at all?

DrivesMeCrazy · Post by **DrivesMeCrazy** » Tue May 01, 2007 5:54 am

Not sure of your problem.

But maybe you can check scheduler whether the job is running successfully
(maybe due to password incorrect, etc).
Another thing is whether snmp service is running on the host.

Bombe · Post by **Bombe** » Tue May 01, 2007 9:27 am

Finally figured it out.

Started chasing dates and times through the system. Logs were showing an hour ahead of system time. Graphs were showing an hour behind system time.

php 5.2.0:
php.ini will not honor date.timezone = GMT/GMT0/GMT+0/GMT-0.
I had to use the string value of UTC to get it to work.

Cacti

Graphs won't update

Graphs won't update

Update

Solved

Who is online