0.8.8h to 1.0.4 = graphs not updating

Post general support questions here that do not specifically fall into the Linux or Windows categories.

Moderators: Developers, Moderators

Robvil
Posts: 28
Joined: Tue Oct 25, 2016 4:32 am

0.8.8h to 1.0.4 = graphs not updating

Post by Robvil »

Hi,

I upgraded from 0.8.8h to 1.0.4 and it was with success.

But my graphs do not get updated since then, and looking at a graph debug, I see no data in RRD file - from the moment the upgrade was done.
Checking RRD in GUI say rrdtool is okay, and running rrdtool last on /../rra/ i can see the rrd files gets updated.

Checked snmpwalk against hosts and it works fine. Currently using cmd.php as poller, as Spine current time out after upgrading.
2017-03-08 12:20:29 - SYSTEM STATS: Time:28.3977 Method:cmd.php Processes:2 Threads:N/A Hosts:37 HostsPerProcess:19 DataSources:429 RRDsProcessed:237

Only erros i´ll see is (during bootup):

2017-03-08 12:05:32 - DBCALL ERROR: SQL Assoc Failed!, Error: Unknown column 'a.hrStorageTotalCache' in 'where clause'
2017-03-08 12:05:32 - DBCALL ERROR: SQL Assoc Failed!, Error:1054, SQL:"SELECT * FROM (SELECT host_id, snmp_query_id, snmp_index, MAX(CASE WHEN field_name ='hrStorageIndex' THEN field_value ELSE NULL END) AS 'hrStorageIndex', MAX(CASE WHEN field_name ='hrStorageDescr' THEN field_value ELSE NULL END) AS 'hrStorageDescr', MAX(CASE WHEN field_name ='hrStorageAllocationUnits' THEN field_value ELSE NULL END) AS 'hrStorageAllocationUnits' FROM host_snmp_cache AS hsc WHERE snmp_query_id=1 AND host_id=1 GROUP BY snmp_query_id, snmp_index) as a WHERE a.hrStorageTotalCache > '1' AND a.hrStorageDescr NOT LIKE '%/dev/%'"

2017-03-08 12:01:27 - CMDPHP PHP ERROR STRICT Backtrace: (/plugins/boost/boost_server.php: 186 CactiErrorHandler)(/lib/functions.php: 4268 cacti_debug_backtrace)
2017-03-08 12:01:27 - ERROR PHP STRICT in Plugin 'boost': Only variables should be passed by reference in file: /var/www/html/plugins/boost/boost_server.php on line: 186
2017-03-08 12:01:27 - CMDPHP PHP ERROR STRICT Backtrace: (/plugins/boost/boost_server.php: 186 CactiErrorHandler)(/lib/functions.php: 4268 cacti_debug_backtrace)
2017-03-08 12:01:27 - ERROR PHP STRICT in Plugin 'boost': Only variables should be passed by reference in file: /var/www/html/plugins/boost/boost_server.php on line: 186
2017-03-08 12:01:27 - CMDPHP PHP ERROR WARNING Backtrace: (/plugins/boost/boost_server.php: 61 set_error_handler)(CactiErrorHandler)(/lib/functions.php: 4259 cacti_debug_backtrace)
2017-03-08 12:01:27 - ERROR PHP WARNING in Plugin 'boost': set_error_handler() expects the argument (boost_error_handler) to be a valid callback in file: /var/www/html/plugins/boost/boost_server.php on line: 61

Any clue why my graphs (rrd files) do not get populated with data anymore?

Regards
Robert
Robvil
Posts: 28
Joined: Tue Oct 25, 2016 4:32 am

Re: 0.8.8h to 1.0.4 = graphs not updating

Post by Robvil »

Exampel:

hdd_total hdd_used hdd_percent

1488972605: 322122539008 201137258496 201137258496
You have mail in /var/spool/mail/root
[root@cacti ~]# /opt/rrdtool-1.5.6/bin/rrdtool last /var/www/html/rra/dbk01_hdd_total_439.rrd
1488972605
[root@cacti ~]# /opt/rrdtool-1.5.6/bin/rrdtool last /var/www/html/rra/dbk01_hdd_total_439.rrd
1488972605
[root@cacti ~]# /opt/rrdtool-1.5.6/bin/rrdtool last /var/www/html/rra/dbk01_hdd_total_439.rrd
1488972605
[root@cacti ~]# /opt/rrdtool-1.5.6/bin/rrdtool last /var/www/html/rra/dbk01_hdd_total_439.rrd
1488972605
[root@cacti ~]# /opt/rrdtool-1.5.6/bin/rrdtool last /var/www/html/rra/dbk01_hdd_total_439.rrd
1488972905
You have mail in /var/spool/mail/root
[root@cacti ~]# /opt/rrdtool-1.5.6/bin/rrdtool lastupdate /var/www/html/rra/dbk01_hdd_total_439.rrd
hdd_total hdd_used hdd_percent

1488972905: 322122539008 201137258496 201137258496
Robvil
Posts: 28
Joined: Tue Oct 25, 2016 4:32 am

Re: 0.8.8h to 1.0.4 = graphs not updating

Post by Robvil »

I have everything running without errors now. Spine updated to 1.0.4, rrdtool updated to 1.5.6 and php updated to 5.5.

But rrdtool do not create graph with any values ....
Running rrdtool lastupdate shows the rrd file gets updated with new values:
/opt/rrdtool-1.5.6/bin/rrdtool lastupdate /var/www/html/rra/dbk01_cpu_492.rrd
cpu
1489047603: 32

But generating a .PNG file from this timeframe results in a empty graph:
/opt/rrdtool-1.5.6/bin/rrdtool graph /root/test.png -a PNG --title='DBK01 cpu value' --start='1489044240' --end='1489047804' --pango-markup --vertical-label='percent' --slope-mode --base=1000 --height=120 --width=500 --tabwidth '30' --rigid --alt-autoscale-max --lower-limit='0' COMMENT:"From 08/03/2017 08\:24\:00 To 09/03/2017 08\:23\:14\c" --border 1 --slope-mode DEF:a='/var/www/html/rra/dbk01_cpu_492.rrd':'cpu':AVERAGE AREA:a#FF00007F:"" LINE1:a#FF0000FF:'CPU Utilization' GPRINT:a:LAST:'Current\:%8.0lf' GPRINT:a:AVERAGE:'Average\:%8.0lf' GPRINT:a:MAX:'Maximum\:%8.0lf'

Graph attached.

Any clue why rrdtool generates empty graph even though data exist in the database file?
Attachments
test.png
test.png (14.64 KiB) Viewed 2482 times
User avatar
phalek
Developer
Posts: 2838
Joined: Thu Jan 31, 2008 6:39 am
Location: Kressbronn, Germany
Contact:

Re: 0.8.8h to 1.0.4 = graphs not updating

Post by phalek »

Check your timezone settings. There are a few places you need to look for:

PHP:
php.ini

MySQL:
my.cnf


Hint for anyone: You can check the last_update field of your data-sources by enabling the "Turn On Data Source Info Mode" feature.
Goto Console -> Management -> Data Sources.
Click on any data source, then click on the "Turn On Data Source Info Mode" link to the top-right.
Greetings,
Phalek
---
Need more help ? Read the Cacti documentation or my new Cacti 1.x Book
Need on-site support ? Look here Cacti Workshop
Need professional Cacti support ? Look here CereusService
---
Plugins : CereusReporting
Robvil
Posts: 28
Joined: Tue Oct 25, 2016 4:32 am

Re: 0.8.8h to 1.0.4 = graphs not updating

Post by Robvil »

Thank you.

Checked MySQL and PHP timezone and both are +1 europe/copenhagen.

Check lastupdate on this rrd - which is current time 12:35:03 local time
/opt/rrdtool-1.5.6/bin/rrdtool lastupdate /var/www/html/rra/dbk01_cpu_492.rrd
cpu
1489059303: 3

But fetching last 1 hour rrd update gives zero data
/opt/rrdtool-1.5.6/bin/rrdtool fetch /var/www/html/rra/dbk01_cpu_492.rrd AVERAGE -s -1h
cpu
1489055700: -nan
1489055760: -nan
1489055820: -nan
1489055880: -nan
1489055940: -nan
1489056000: -nan
1489056060: -nan
1489056120: -nan
1489056180: -nan
1489056240: -nan
1489056300: -nan
1489056360: -nan
1489056420: -nan
1489056480: -nan
1489056540: -nan
1489056600: -nan
1489056660: -nan
1489056720: -nan
1489056780: -nan
1489056840: -nan
1489056900: -nan
1489056960: -nan
1489057020: -nan
1489057080: -nan
1489057140: -nan
1489057200: -nan
1489057260: -nan
1489057320: -nan
1489055700: -nan
1489055760: -nan
1489055820: -nan
1489055880: -nan
1489055940: -nan
1489056000: -nan
1489056060: -nan
1489056120: -nan
1489056180: -nan
1489056240: -nan
1489056300: -nan
1489056360: -nan
1489056420: -nan
1489056480: -nan
1489056540: -nan
1489056600: -nan
1489056660: -nan
1489056720: -nan
1489056780: -nan
1489056840: -nan
1489056900: -nan
1489056960: -nan
1489057020: -nan
1489057080: -nan
1489057140: -nan
1489057200: -nan
1489057260: -nan
1489057320: -nan

That must be why my graphs are empty. But how is it possible lastupdate shows a valid value - and it changes after each poll - but showing statistics from the rrd file is empty?
User avatar
phalek
Developer
Posts: 2838
Joined: Thu Jan 31, 2008 6:39 am
Location: Kressbronn, Germany
Contact:

Re: 0.8.8h to 1.0.4 = graphs not updating

Post by phalek »

do a xml dump of the rrd file and check what data it really has in it.
Greetings,
Phalek
---
Need more help ? Read the Cacti documentation or my new Cacti 1.x Book
Need on-site support ? Look here Cacti Workshop
Need professional Cacti support ? Look here CereusService
---
Plugins : CereusReporting
Robvil
Posts: 28
Joined: Tue Oct 25, 2016 4:32 am

Re: 0.8.8h to 1.0.4 = graphs not updating

Post by Robvil »

Empty since the upgrade of Cacti.

After upgrade
<rrd>
<version>0003</version>
<step>60</step> <!-- Seconds -->
<lastupdate>1489061103</lastupdate> <!-- 2017-03-09 13:05:03 CET -->

<ds>
<name> cpu </name>
<type> GAUGE </type>
<minimal_heartbeat>120</minimal_heartbeat>
<min>0.0000000000e+00</min>
<max>1.0000000000e+02</max>

<!-- PDP Status -->
<last_ds>11</last_ds>
<value>NaN</value>
<unknown_sec> 3 </unknown_sec>
</ds>

<!-- Round Robin Archives -->
<rra>
<cf>AVERAGE</cf>
<pdp_per_row>1</pdp_per_row> <!-- 60 seconds -->

<params>
<xff>5.0000000000e-01</xff>
</params>
<cdp_prep>
<ds>
<primary_value>NaN</primary_value>
<secondary_value>NaN</secondary_value>
<value>NaN</value>
<unknown_datapoints>0</unknown_datapoints>
</ds>
</cdp_prep>
<database>
<!-- 2017-03-09 03:06:00 CET / 1489025160 --> <row><v>NaN</v></row>
<!-- 2017-03-09 03:07:00 CET / 1489025220 --> <row><v>NaN</v></row>
<!-- 2017-03-09 03:08:00 CET / 1489025280 --> <row><v>NaN</v></row>
<!-- 2017-03-09 03:09:00 CET / 1489025340 --> <row><v>NaN</v></row>
<!-- 2017-03-09 03:10:00 CET / 1489025400 --> <row><v>NaN</v></row>
<!-- 2017-03-09 03:11:00 CET / 1489025460 --> <row><v>NaN</v></row>
<!-- 2017-03-09 03:12:00 CET / 1489025520 --> <row><v>NaN</v></row>
<!-- 2017-03-09 03:13:00 CET / 1489025580 --> <row><v>NaN</v></row>
<!-- 2017-03-09 03:14:00 CET / 1489025640 --> <row><v>NaN</v></row>
<!-- 2017-03-09 03:15:00 CET / 1489025700 --> <row><v>NaN</v></row>
<!-- 2017-03-09 03:16:00 CET / 1489025760 --> <row><v>NaN</v></row>
<!-- 2017-03-09 03:17:00 CET / 1489025820 --> <row><v>NaN</v></row>
<!-- 2017-03-09 03:18:00 CET / 1489025880 --> <row><v>NaN</v></row>
<!-- 2017-03-09 03:19:00 CET / 1489025940 --> <row><v>NaN</v></row>
<!-- 2017-03-09 03:20:00 CET / 1489026000 --> <row><v>NaN</v></row>
<!-- 2017-03-09 03:21:00 CET / 1489026060 --> <row><v>NaN</v></row>
<!-- 2017-03-09 03:22:00 CET / 1489026120 --> <row><v>NaN</v></row>
<!-- 2017-03-09 03:23:00 CET / 1489026180 --> <row><v>NaN</v></row>
<!-- 2017-03-09 03:24:00 CET / 1489026240 --> <row><v>NaN</v></row>
<!-- 2017-03-09 03:25:00 CET / 1489026300 --> <row><v>NaN</v></row>
<!-- 2017-03-09 03:26:00 CET / 1489026360 --> <row><v>NaN</v></row>
<!-- 2017-03-09 03:27:00 CET / 1489026420 --> <row><v>NaN</v></row>
Before upgrade
<rra>
<cf>AVERAGE</cf>
<pdp_per_row>24</pdp_per_row> <!-- 1440 seconds -->

<params>
<xff>5.0000000000e-01</xff>
</params>
<cdp_prep>
<ds>
<primary_value>NaN</primary_value>
<secondary_value>NaN</secondary_value>
<value>0.0000000000e+00</value>
<unknown_datapoints>5</unknown_datapoints>
</ds>
</cdp_prep>
<database>
<!-- 2017-02-24 15:24:00 CET / 1487946240 --> <row><v>7.9263888889e+00</v></row>
<!-- 2017-02-24 15:48:00 CET / 1487947680 --> <row><v>7.3458333333e+00</v></row>
<!-- 2017-02-24 16:12:00 CET / 1487949120 --> <row><v>9.6062500000e+00</v></row>
<!-- 2017-02-24 16:36:00 CET / 1487950560 --> <row><v>7.0513888889e+00</v></row>
<!-- 2017-02-24 17:00:00 CET / 1487952000 --> <row><v>1.2129166667e+01</v></row>
<!-- 2017-02-24 17:24:00 CET / 1487953440 --> <row><v>5.9986111111e+00</v></row>
<!-- 2017-02-24 17:48:00 CET / 1487954880 --> <row><v>1.0963888889e+01</v></row>
<!-- 2017-02-24 18:12:00 CET / 1487956320 --> <row><v>8.9152777778e+00</v></row>
<!-- 2017-02-24 18:36:00 CET / 1487957760 --> <row><v>4.6236111111e+00</v></row>
<!-- 2017-02-24 19:00:00 CET / 1487959200 --> <row><v>6.4423611111e+00</v></row>
<!-- 2017-02-24 19:24:00 CET / 1487960640 --> <row><v>5.3979166667e+00</v></row>
<!-- 2017-02-24 19:48:00 CET / 1487962080 --> <row><v>1.6743055556e+00</v></row>
<!-- 2017-02-24 20:12:00 CET / 1487963520 --> <row><v>1.6875000000e+00</v></row>
<!-- 2017-02-24 20:36:00 CET / 1487964960 --> <row><v>1.2138888889e+00</v></row>
<!-- 2017-02-24 21:00:00 CET / 1487966400 --> <row><v>5.0277777778e-01</v></row>
<!-- 2017-02-24 21:24:00 CET / 1487967840 --> <row><v>8.3333333333e-02</v></row>
<!-- 2017-02-24 21:48:00 CET / 1487969280 --> <row><v>1.8354166667e+00</v></row>
<!-- 2017-02-24 22:12:00 CET / 1487970720 --> <row><v>4.9583333333e-01</v></row>
User avatar
phalek
Developer
Posts: 2838
Joined: Thu Jan 31, 2008 6:39 am
Location: Kressbronn, Germany
Contact:

Re: 0.8.8h to 1.0.4 = graphs not updating

Post by phalek »

i suggest enabling debug loggin and check what rrdtool command is being executed to update that rrd. Then take that command and execute it from the CLI to check for any errors.
Greetings,
Phalek
---
Need more help ? Read the Cacti documentation or my new Cacti 1.x Book
Need on-site support ? Look here Cacti Workshop
Need professional Cacti support ? Look here CereusService
---
Plugins : CereusReporting
Robvil
Posts: 28
Joined: Tue Oct 25, 2016 4:32 am

Re: 0.8.8h to 1.0.4 = graphs not updating

Post by Robvil »

Can´t see any errors:

Snip from the server with the corresponding rrd files - looks fine as far as i can see. Values get collected.

2017-03-09 13:35:09 - SPINE: Poller[1] DEBUG: The Value of Active Threads is 19 for Device ID 34
2017-03-09 13:35:09 - SPINE: Poller[1] Device[34] Description[DBK01] TH[1] DEBUG: HOST COMPLETE: About to Exit Device Polling Thread Function
2017-03-09 13:35:09 - SPINE: Poller[1] Device[34] Description[DBK01] TH[1] Total Time: 5.8 Seconds
2017-03-09 13:35:09 - SPINE: Poller[1] Device[34] Description[DBK01] TH[1] DS[570] Graphs['DBK01 - Disk H: (Percent Usage)'] SNMP: v2: dbk01, dsname: PercentIdleTime, oid: .1.3.6.1.4.1.9600.1.1.1.1.6.2.72.58, value: 99
2017-03-09 13:35:09 - SPINE: Poller[1] Device[34] Description[DBK01] TH[1] DS[570] Graphs['DBK01 - Disk H: (Percent Usage)'] SNMP: v2: dbk01, dsname: PercentFreeSpace, oid: .1.3.6.1.4.1.9600.1.1.1.1.5.2.72.58, value: 37
2017-03-09 13:35:09 - SPINE: Poller[1] Device[34] Description[DBK01] TH[1] DS[570] Graphs['DBK01 - Disk H: (Percent Usage)'] SNMP: v2: dbk01, dsname: PerDiskWriteTime, oid: .1.3.6.1.4.1.9600.1.1.1.1.4.2.72.58, value: 0
2017-03-09 13:35:09 - SPINE: Poller[1] Device[34] Description[DBK01] TH[1] DS[570] Graphs['DBK01 - Disk H: (Percent Usage)'] SNMP: v2: dbk01, dsname: PercentDiskReadTime, oid: .1.3.6.1.4.1.9600.1.1.1.1.2.2.72.58, value: 0
2017-03-09 13:35:09 - SPINE: Poller[1] Device[34] Description[DBK01] TH[1] DS[570] Graphs['DBK01 - Disk H: (Percent Usage)'] SNMP: v2: dbk01, dsname: PercentDiskTime, oid: .1.3.6.1.4.1.9600.1.1.1.1.3.2.72.58, value: 0
2017-03-09 13:35:09 - SPINE: Poller[1] Device[34] Description[DBK01] TH[1] DS[356] Graphs['DBK01 - Traffic - ethernet_10'] SNMP: v2: dbk01, dsname: traffic_out, oid: .1.3.6.1.2.1.2.2.1.16.12, value: 1054478777
2017-03-09 13:35:09 - SPINE: Poller[1] Device[34] Description[DBK01] TH[1] DS[356] Graphs['DBK01 - Traffic - ethernet_10'] SNMP: v2: dbk01, dsname: traffic_in, oid: .1.3.6.1.2.1.2.2.1.10.12, value: 525234467
2017-03-09 13:35:09 - SPINE: Poller[1] Device[34] Description[DBK01] TH[1] DS[494] Graphs['DBK01 - CPU Utilization - CPU3'] SS[3] SERVER: /var/www/html/scripts/ss_host_cpu.php ss_host_cpu dbk01 34 2:161:10000:1:10:public:::::: get usage '3', output: 7
2017-03-09 13:35:08 - SPINE: Poller[1] Device[34] Description[DBK01] DEBUG: The NIFTY POPEN returned the following File Descriptor 6
2017-03-09 13:35:08 - SPINE: Poller[1] Device[34] Description[DBK01] TH[1] DS[439] Graphs['DBK01 - Used Space - H: Label:Fusion'] SS[2] SERVER: /var/www/html/scripts/ss_host_disk.php ss_host_disk dbk01 34 2:161:10000:1:10:public:::::: get used '5', output: 201166356480
2017-03-09 13:35:08 - SPINE: Poller[1] Device[34] Description[DBK01] TH[1] DS[439] Graphs['DBK01 - Used Space - H: Label:Fusion'] SS[1] SERVER: /var/www/html/scripts/ss_host_disk.php ss_host_disk dbk01 34 2:161:10000:1:10:public:::::: get used '5', output: 201166356480
2017-03-09 13:35:08 - SPINE: Poller[1] Device[34] Description[DBK01] TH[1] DS[439] Graphs['DBK01 - Used Space - H: Label:Fusion'] SS[0] SERVER: /var/www/html/scripts/ss_host_disk.php ss_host_disk dbk01 34 2:161:10000:1:10:public:::::: get total '5', output: 322122539008
2017-03-09 13:35:08 - SPINE: Poller[1] Device[34] Description[DBK01] TH[1] DS[438] Graphs['DBK01 - Used Space - G: Label:DumpDi'] SS[2] SERVER: /var/www/html/scripts/ss_host_disk.php ss_host_disk dbk01 34 2:161:10000:1:10:public:::::: get used '4', output: 157095677952
2017-03-09 13:35:06 - SPINE: Poller[1] Device[34] Description[DBK01] TH[1] DS[375] Graphs['DBK01 - Memory Usage'] SCRIPT: /usr/bin/php -q /var/www/html/scripts/wmi.php -h ''dbk01'' -u ''/etc/cacti/cactiwmi.pw'' -w ''Win32_PerfRawData_PerfOS_Memory'' -n '''' -k '''' -v '''' -c ''CommittedBytes,AvailableBytes,CommitLimit'', output: AvailableBytes:28050518016 CommitLimit:39459475456 CommittedBytes:5823983616

2017-03-09 13:25:09 - SPINE: Poller[1] DEBUG: The Value of Active Threads is 19 for Device ID 34
2017-03-09 13:25:09 - SPINE: Poller[1] Device[34] Description[DBK01] TH[1] DEBUG: HOST COMPLETE: About to Exit Device Polling Thread Function

2017-03-09 13:30:03 - SPINE: Poller[1] Device[34] Description[DBK01] DEBUG: Entering SNMP Ping


2017-03-09 13:35:09 - SPINE: Poller[1] DEBUG: Net-SNMP Close Completed
2017-03-09 13:35:09 - SPINE: Poller[1] DEBUG: MYSQL Free & Close Completed
2017-03-09 13:35:09 - SPINE: Poller[1] DEBUG: Allocated Variable Memory Freed
2017-03-09 13:35:09 - SPINE: Poller[1] DEBUG: PHP Script Server Pipes Closed

2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: Valid Thread to be Created
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: Initial Value of Active Threads is 0
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: SS[4] Confirmed PHP Script Server running using readfd[16], writefd[15]
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: SS[3] PHP Script Server Child FORK Success
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: SS[3] PHP Script Server About to FORK Child Process
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: SS[3] PHP Script Server Routine Starting
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: SS[2] Confirmed PHP Script Server running using readfd[12], writefd[11]
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: SS[4] PHP Script Server Child FORK Success
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: SS[4] PHP Script Server About to FORK Child Process
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: SS[4] PHP Script Server Routine Starting
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: SS[3] Confirmed PHP Script Server running using readfd[14], writefd[13]
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: SS[2] PHP Script Server Child FORK Success
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: SS[2] PHP Script Server About to FORK Child Process
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: SS[2] PHP Script Server Routine Starting
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: SS[1] Confirmed PHP Script Server running using readfd[10], writefd[9]
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: SS[3] PHP Script Server Child FORK Success
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: SS[3] PHP Script Server About to FORK Child Process
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: SS[3] PHP Script Server Routine Starting
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: SS[2] Confirmed PHP Script Server running using readfd[12], writefd[11]
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: SS[1] PHP Script Server Child FORK Success
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: SS[1] PHP Script Server About to FORK Child Process
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: SS[1] PHP Script Server Routine Starting
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: SS[0] Confirmed PHP Script Server running using readfd[8], writefd[7]
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: SS[2] PHP Script Server Child FORK Success
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: SS[2] PHP Script Server About to FORK Child Process
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: SS[2] PHP Script Server Routine Starting
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: SS[1] Confirmed PHP Script Server running using readfd[10], writefd[9]
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: SS[0] PHP Script Server Child FORK Success
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: SS[0] PHP Script Server About to FORK Child Process
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: SS[0] PHP Script Server Routine Starting
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: SS[1] PHP Script Server Child FORK Success
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: SS[1] PHP Script Server About to FORK Child Process
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: SS[1] PHP Script Server Routine Starting
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: SS[0] Confirmed PHP Script Server running using readfd[8], writefd[7]
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: Issues with SNMP Header Version information, assuming old version of Net-SNMP.
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: Spine is running asroot.
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: MySQL is Thread Safe!
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The Maximum SNMP OID Get Size is 30
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The PHP Script Server is Required
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: StartDevice='29', EndDevice='48', TotalPHPScripts='1'
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The number of php script servers to run is 5
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The spine_log_level variable is 2
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The selective_device_debug variable is
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The script timeout is 240
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The number of concurrent processes is 2
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The polling interval is 300 seconds
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The threads variable is 20
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The log_pstats variable is 0
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The boost_redirect variable is 0
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The log_pwarn variable is 1
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The log_perror variable is 1
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The snmp_retries variable is 3
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The ping_timeout variable is 400
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The ping_retries variable is 1
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The ping_method variable is 1
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The ping_failure_count variable is 5
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The ping_recovery_count variable is 3
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The availability_method variable is 2
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The path_php variable is /usr/bin/php
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: SS[0] PHP Script Server Child FORK Success
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: SS[0] PHP Script Server About to FORK Child Process
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: SS[0] PHP Script Server Routine Starting
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: Issues with SNMP Header Version information, assuming old version of Net-SNMP.
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: Spine is running asroot.
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: MySQL is Thread Safe!
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The Maximum SNMP OID Get Size is 30
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The PHP Script Server is Required
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: StartDevice='0', EndDevice='28', TotalPHPScripts='1'
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The number of php script servers to run is 5
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The spine_log_level variable is 2
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The selective_device_debug variable is
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The script timeout is 240
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The number of concurrent processes is 2
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The polling interval is 300 seconds
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The threads variable is 20
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The log_pstats variable is 0
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The boost_redirect variable is 0
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The log_pwarn variable is 1
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The log_perror variable is 1
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The snmp_retries variable is 3
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The ping_timeout variable is 400
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The ping_retries variable is 1
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The ping_method variable is 1
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The ping_failure_count variable is 5
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The ping_recovery_count variable is 3
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The availability_method variable is 2
2017-03-09 13:30:02 - SPINE: Poller[1] DEBUG: The path_php variable is /usr/bin/php
2017-03-09 13:25:09 - SPINE: Poller[1] DEBUG: Net-SNMP Close Completed
2017-03-09 13:25:09 - SPINE: Poller[1] DEBUG: MYSQL Free & Close Completed
2017-03-09 13:25:09 - SPINE: Poller[1] DEBUG: Allocated Variable Memory Freed
2017-03-09 13:25:09 - SPINE: Poller[1] DEBUG: PHP Script Server Pipes Closed
2017-03-09 13:25:09 - SPINE: Poller[1] DEBUG: SS[4] Script Server Shutdown Started
2017-03-09 13:25:09 - SPINE: Poller[1] DEBUG: SS[3] Script Server Shutdown Started
2017-03-09 13:25:09 - SPINE: Poller[1] DEBUG: SS[2] Script Server Shutdown Started
2017-03-09 13:25:09 - SPINE: Poller[1] DEBUG: SS[1] Script Server Shutdown Started
2017-03-09 13:25:09 - SPINE: Poller[1] DEBUG: SS[0] Script Server Shutdown Started
User avatar
phalek
Developer
Posts: 2838
Joined: Thu Jan 31, 2008 6:39 am
Location: Kressbronn, Germany
Contact:

Re: 0.8.8h to 1.0.4 = graphs not updating

Post by phalek »

I do not see the rrd update command in there. Can you check?
Greetings,
Phalek
---
Need more help ? Read the Cacti documentation or my new Cacti 1.x Book
Need on-site support ? Look here Cacti Workshop
Need professional Cacti support ? Look here CereusService
---
Plugins : CereusReporting
Robvil
Posts: 28
Joined: Tue Oct 25, 2016 4:32 am

Re: 0.8.8h to 1.0.4 = graphs not updating

Post by Robvil »

i cannot see it/locate it in the log ... how should it look?
Robvil
Posts: 28
Joined: Tue Oct 25, 2016 4:32 am

Re: 0.8.8h to 1.0.4 = graphs not updating

Post by Robvil »

I only see:

2017-03-09 13:50:16 - SYSTEM STATS: Time:14.7460 Method:spine Processes:2 Threads:20 Hosts:37 HostsPerProcess:19 DataSources:441 RRDsProcessed:243
User avatar
phalek
Developer
Posts: 2838
Joined: Thu Jan 31, 2008 6:39 am
Location: Kressbronn, Germany
Contact:

Re: 0.8.8h to 1.0.4 = graphs not updating

Post by phalek »

Then it's in a higher log level ( DEVEL ? ) It should be in the cacti.log at one of these log levels.
Greetings,
Phalek
---
Need more help ? Read the Cacti documentation or my new Cacti 1.x Book
Need on-site support ? Look here Cacti Workshop
Need professional Cacti support ? Look here CereusService
---
Plugins : CereusReporting
Robvil
Posts: 28
Joined: Tue Oct 25, 2016 4:32 am

Re: 0.8.8h to 1.0.4 = graphs not updating

Post by Robvil »

Only see at lot of this:

2017-03-09 14:08:04 - DBCALL DEVEL: SQL Assoc: "SELECT * FROM plugin_realms ORDER BY plugin, display"
2017-03-09 14:08:04 - DBCALL DEVEL: SQL Assoc: "SELECT DISTINCT gt.id, gt.name FROM graph_templates AS gt INNER JOIN graph_templates_item AS gti ON gt.id=gti.graph_template_id INNER JOIN data_template_rrd AS dtr ON gti.task_item_id=dtr.id WHERE gti.local_graph_id=0 AND data_source_type_id IN (3,2) ORDER BY name"
2017-03-09 14:08:04 - DBCALL DEVEL: SQL Assoc: "SELECT directory AS id, name FROM plugin_config WHERE status=1"
2017-03-09 14:08:04 - DBCALL DEVEL: SQL Assoc: "SELECT pc.id, ph.name, ph.file, ph.function FROM plugin_hooks AS ph LEFT JOIN plugin_config AS pc ON pc.directory = ph.name WHERE ph.status = 1 AND hook = ? ORDER BY id ASC"
2017-03-09 14:08:04 - DBCALL DEVEL: SQL Assoc: "SELECT pc.id, ph.name, ph.file, ph.function FROM plugin_hooks AS ph LEFT JOIN plugin_config AS pc ON pc.directory = ph.name WHERE ph.status = 1 AND hook = ? ORDER BY id ASC"
2017-03-09 14:08:04 - DBCALL DEVEL: SQL Assoc: "SELECT pc.id, ph.name, ph.file, ph.function FROM plugin_hooks AS ph LEFT JOIN plugin_config AS pc ON pc.directory = ph.name WHERE ph.status = 1 AND hook = ? ORDER BY id ASC"
2017-03-09 14:08:04 - DBCALL DEVEL: SQL Assoc: "SELECT pc.id, ph.name, ph.file, ph.function FROM plugin_hooks AS ph LEFT JOIN plugin_config AS pc ON pc.directory = ph.name WHERE ph.status = 1 AND hook = ? ORDER BY id ASC"
2017-03-09 14:08:04 - DBCALL DEVEL: SQL Assoc: "SELECT pc.id, ph.name, ph.file, ph.function FROM plugin_hooks AS ph LEFT JOIN plugin_config AS pc ON pc.directory = ph.name WHERE ph.status = 1 AND hook = ? ORDER BY id ASC"
2017-03-09 14:08:04 - DBCALL DEVEL: SQL Cell: "SELECT COUNT(*) FROM ( SELECT realm_id FROM user_auth_realm AS uar WHERE uar.user_id = ? AND uar.realm_id = ? UNION SELECT realm_id FROM user_auth_group_realm AS uagr INNER JOIN user_auth_group_members AS uagm ON uagr.group_id=uagm.group_id INNER JOIN user_auth_group AS uag ON uag.id=uagr.group_id WHERE uag.enabled="on" AND uagm.user_id = ? AND uagr.realm_id = ? ) AS authorized"
2017-03-09 14:08:04 - DBCALL DEVEL: SQL Row: "SELECT * FROM user_auth WHERE id = ?"
2017-03-09 14:08:34 - DBCALL DEVEL: SQL Cell: "SELECT cacti FROM version LIMIT 1"
2017-03-09 14:08:34 - DBCALL DEVEL: SQL Cell: "SHOW TABLES LIKE 'settings_user'"
2017-03-09 14:08:34 - DBCALL DEVEL: SQL Row: "SELECT value FROM settings_user WHERE name = ? AND user_id = ?"
2017-03-09 14:08:34 - DBCALL DEVEL: SQL Assoc: "SELECT * FROM user_domains WHERE enabled="on" ORDER BY domain_name"
2017-03-09 14:08:34 - DBCALL DEVEL: SQL Cell: "SELECT domain_id FROM user_domains WHERE defdomain=1 AND enabled="on""
2017-03-09 14:08:34 - DBCALL DEVEL: SQL Cell: "SHOW TABLES LIKE 'external_links'"
2017-03-09 14:08:34 - DBCALL DEVEL: SQL Assoc: "SELECT id, title, extendedstyle FROM external_links WHERE style="CONSOLE" AND enabled="on" ORDER BY extendedstyle, sortorder, id"
2017-03-09 14:08:34 - DBCALL DEVEL: SQL Cell: "SELECT reset_perms FROM user_auth WHERE id = ?"
2017-03-09 14:08:34 - DBCALL DEVEL: SQL Assoc: "SELECT pc.id, ph.name, ph.file, ph.function FROM plugin_hooks AS ph LEFT JOIN plugin_config AS pc ON pc.directory = ph.name WHERE ph.status = 1 AND hook = ? ORDER BY id ASC"
2017-03-09 14:08:34 - DBCALL DEVEL: SQL Assoc: "SELECT * FROM plugin_realms ORDER BY plugin, display"
2017-03-09 14:08:34 - DBCALL DEVEL: SQL Assoc: "SELECT DISTINCT gt.id, gt.name FROM graph_templates AS gt INNER JOIN graph_templates_item AS gti ON gt.id=gti.graph_template_id INNER JOIN data_template_rrd AS dtr ON gti.task_item_id=dtr.id WHERE gti.local_graph_id=0 AND data_source_type_id IN (3,2) ORDER BY name"
2017-03-09 14:08:34 - DBCALL DEVEL: SQL Assoc: "SELECT directory AS id, name FROM plugin_config WHERE status=1"
2017-03-09 14:08:34 - DBCALL DEVEL: SQL Assoc: "SELECT pc.id, ph.name, ph.file, ph.function FROM plugin_hooks AS ph LEFT JOIN plugin_config AS pc ON pc.directory = ph.name WHERE ph.status = 1 AND hook = ? ORDER BY id ASC"
2017-03-09 14:08:34 - DBCALL DEVEL: SQL Assoc: "SELECT pc.id, ph.name, ph.file, ph.function FROM plugin_hooks AS ph LEFT JOIN plugin_config AS pc ON pc.directory = ph.name WHERE ph.status = 1 AND hook = ? ORDER BY id ASC"
2017-03-09 14:08:34 - DBCALL DEVEL: SQL Assoc: "SELECT pc.id, ph.name, ph.file, ph.function FROM plugin_hooks AS ph LEFT JOIN plugin_config AS pc ON pc.directory = ph.name WHERE ph.status = 1 AND hook = ? ORDER BY id ASC"
2017-03-09 14:08:34 - DBCALL DEVEL: SQL Assoc: "SELECT pc.id, ph.name, ph.file, ph.function FROM plugin_hooks AS ph LEFT JOIN plugin_config AS pc ON pc.directory = ph.name WHERE ph.status = 1 AND hook = ? ORDER BY id ASC"
2017-03-09 14:08:34 - DBCALL DEVEL: SQL Assoc: "SELECT pc.id, ph.name, ph.file, ph.function FROM plugin_hooks AS ph LEFT JOIN plugin_config AS pc ON pc.directory = ph.name WHERE ph.status = 1 AND hook = ? ORDER BY id ASC"
2017-03-09 14:08:34 - DBCALL DEVEL: SQL Cell: "SELECT COUNT(*) FROM ( SELECT realm_id FROM user_auth_realm AS uar WHERE uar.user_id = ? AND uar.realm_id = ? UNION SELECT realm_id FROM user_auth_group_realm AS uagr INNER JOIN user_auth_group_members AS uagm ON uagr.group_id=uagm.group_id INNER JOIN user_auth_group AS uag ON uag.id=uagr.group_id WHERE uag.enabled="on" AND uagm.user_id = ? AND uagr.realm_id = ? ) AS authorized"
2017-03-09 14:08:34 - DBCALL DEVEL: SQL Row: "SELECT * FROM user_auth WHERE id = ?"
2017-03-09 14:09:05 - DBCALL DEVEL: SQL Cell: "SELECT cacti FROM version LIMIT 1"
2017-03-09 14:09:05 - DBCALL DEVEL: SQL Cell: "SHOW TABLES LIKE 'settings_user'"
2017-03-09 14:09:05 - DBCALL DEVEL: SQL Row: "SELECT value FROM settings_user WHERE name = ? AND user_id = ?"
2017-03-09 14:09:05 - DBCALL DEVEL: SQL Assoc: "SELECT * FROM user_domains WHERE enabled="on" ORDER BY domain_name"
2017-03-09 14:09:05 - DBCALL DEVEL: SQL Cell: "SELECT domain_id FROM user_domains WHERE defdomain=1 AND enabled="on""
2017-03-09 14:09:05 - DBCALL DEVEL: SQL Cell: "SHOW TABLES LIKE 'external_links'"
2017-03-09 14:09:05 - DBCALL DEVEL: SQL Assoc: "SELECT id, title, extendedstyle FROM external_links WHERE style="CONSOLE" AND enabled="on" ORDER BY extendedstyle, sortorder, id"
2017-03-09 14:09:05 - DBCALL DEVEL: SQL Cell: "SELECT reset_perms FROM user_auth WHERE id = ?"
2017-03-09 14:09:05 - DBCALL DEVEL: SQL Assoc: "SELECT pc.id, ph.name, ph.file, ph.function FROM plugin_hooks AS ph LEFT JOIN plugin_config AS pc ON pc.directory = ph.name WHERE ph.status = 1 AND hook = ? ORDER BY id ASC"
2017-03-09 14:09:05 - DBCALL DEVEL: SQL Assoc: "SELECT * FROM plugin_realms ORDER BY plugin, display"
2017-03-09 14:09:05 - DBCALL DEVEL: SQL Assoc: "SELECT DISTINCT gt.id, gt.name FROM graph_templates AS gt INNER JOIN graph_templates_item AS gti ON gt.id=gti.graph_template_id INNER JOIN data_template_rrd AS dtr ON gti.task_item_id=dtr.id WHERE gti.local_graph_id=0 AND data_source_type_id IN (3,2) ORDER BY name"
2017-03-09 14:09:05 - DBCALL DEVEL: SQL Assoc: "SELECT directory AS id, name FROM plugin_config WHERE status=1"
2017-03-09 14:09:05 - DBCALL DEVEL: SQL Assoc: "SELECT pc.id, ph.name, ph.file, ph.function FROM plugin_hooks AS ph LEFT JOIN plugin_config AS pc ON pc.directory = ph.name WHERE ph.status = 1 AND hook = ? ORDER BY id ASC"
2017-03-09 14:09:05 - DBCALL DEVEL: SQL Assoc: "SELECT pc.id, ph.name, ph.file, ph.function FROM plugin_hooks AS ph LEFT JOIN plugin_config AS pc ON pc.directory = ph.name WHERE ph.status = 1 AND hook = ? ORDER BY id ASC"
2017-03-09 14:09:05 - DBCALL DEVEL: SQL Assoc: "SELECT pc.id, ph.name, ph.file, ph.function FROM plugin_hooks AS ph LEFT JOIN plugin_config AS pc ON pc.directory = ph.name WHERE ph.status = 1 AND hook = ? ORDER BY id ASC"
2017-03-09 14:09:05 - DBCALL DEVEL: SQL Assoc: "SELECT pc.id, ph.name, ph.file, ph.function FROM plugin_hooks AS ph LEFT JOIN plugin_config AS pc ON pc.directory = ph.name WHERE ph.status = 1 AND hook = ? ORDER BY id ASC"
2017-03-09 14:09:05 - DBCALL DEVEL: SQL Assoc: "SELECT pc.id, ph.name, ph.file, ph.function FROM plugin_hooks AS ph LEFT JOIN plugin_config AS pc ON pc.directory = ph.name WHERE ph.status = 1 AND hook = ? ORDER BY id ASC"
2017-03-09 14:09:05 - DBCALL DEVEL: SQL Cell: "SELECT COUNT(*) FROM ( SELECT realm_id FROM user_auth_realm AS uar WHERE uar.user_id = ? AND uar.realm_id = ? UNION SELECT realm_id FROM user_auth_group_realm AS uagr INNER JOIN user_auth_group_members AS uagm ON uagr.group_id=uagm.group_id INNER JOIN user_auth_group AS uag ON uag.id=uagr.group_id WHERE uag.enabled="on" AND uagm.user_id = ? AND uagr.realm_id = ? ) AS authorized"
2017-03-09 14:09:05 - DBCALL DEVEL: SQL Row: "SELECT * FROM user_auth WHERE id = ?"
Robvil
Posts: 28
Joined: Tue Oct 25, 2016 4:32 am

Re: 0.8.8h to 1.0.4 = graphs not updating

Post by Robvil »

maybe i should try debug poller.php and poller_commands.php files?
Post Reply

Who is online

Users browsing this forum: No registered users and 2 guests