Upgraded to 0.8.8b - Data is NaN

Hi all,

I have recently upgraded to the latest Cacti and Spine, version 0.8.8b
for both. Since then a script that feeds data into Cacti is no longer
working (other scripts are though). The graph in Cacti is now
reporting 'nan' for the values. Running the script by hand ofcourse
produces the desired output. Also the script or data it pulls hasn't
been modified;

$ ./monitor_tdm_channels.sh
TDMMIRAGO1OUT:0 TDMMIRAGO2OUT:0 TDMTHNOUT:0 TDMALLOUT:0 TDMMIRAGO1IN:0
TDMMIRAGO2IN:0 TDMTHNIN:0 TDMALLIN:0

I have turned the cacti.log verbosity level up to Developer (the most
verbose option) and have the following output. I am also using Boost
(the newest version) since I updated Cacti about a week ago, if that
makes any difference.

Below are the relevant parts from log file. This is host ID 79. The
script is called twice, part of it's output is written to data source
ID 14282, part of it's output is written to data source ID 14022.
Someone has set up the same data source twice (so the script is called
twice) to have two separate graphs, which is a separate issue in it's
own. Naughty users!

If anyone can see what is wrong here I'd really like to know!

I've been trying to find out what this means, but I'm not having any
luck: The NIFTY POPEN returned the following File Descriptor 7

Many thanks,
James



09/05/2013 03:05:09 PM - SPINE: Poller[0] Host[79] TH[1] DS[14282]
SCRIPT: /root/scripts/monitor_tdm_channels.sh, output: TDMMIRAGO1OUT:0
TDMMIRAGO2OUT:0 TDMTHNOUT:0 TDMALLOUT:0 TDMMIRAGO1IN:0 TDMMIRAGO2IN:0
TDMTHNIN:0 TDMALLIN:0

09/05/2013 03:05:09 PM - SPINE: Poller[0] Host[79] DEBUG: The NIFTY
POPEN returned the following File Descriptor 7

09/05/2013 03:05:15 PM - SPINE: Poller[0] Host[79] TH[1] DS[2005]
SCRIPT: /root/scripts/monitor_registred_phones.php, output: 741

09/05/2013 03:05:15 PM - SPINE: Poller[0] DEVDBG: SQL:'INSERT INTO
poller_output (local_data_id, rrd_name, time, output) VALUES
(2495,'phones','2013-09-05 15:05:01','741
'),(2499,'','2013-09-05 15:05:01','ambient_temp1:23
ambient_temp2:16'),(14022,'','2013-09-05 15:05:01','TDMMIRAGO1OUT:0
TDMMIRAGO2OUT:0 TDMTHNOUT:0 TDMALLOUT:0 TDMMIRAGO1IN:0 TDMMIRAGO2IN:0
TDMTHNIN:0 TDMALLIN:0'),(14282,'','2013-09-05
15:05:01','TDMMIRAGO1OUT:0 TDMMIRAGO2OUT:0 TDMTHNOUT:0 TDMALLOUT:0
TDMMIRAGO1IN:0 TDMMIRAGO2IN:0 TDMTHNIN:0
TDMALLIN:0'),(3531,'','2013-09-05 15:05:01','IN: OUT:17
ALL:17'),(2005,'phones','2013-09-05 15:05:01','741
') ON DUPLICATE KEY UPDATE output=VALUES(output)'09/05/2013 03:05:15
PM - CMDPHP: Poller[0] DEVEL: SQL Cell: "SELECT count(*) FROM
poller_time WHERE poller_id=0 AND end_time>'0000-00-00 00:00:00'"

09/05/2013 03:05:15 PM - SPINE: Poller[0] DEVDBG: SQL:'INSERT INTO
poller_output_boost (local_data_id, rrd_name, time, output) VALUES
(2495,'phones','2013-09-05 15:05:01','741
'),(2499,'','2013-09-05 15:05:01','ambient_temp1:23
ambient_temp2:16'),(14022,'','2013-09-05 15:05:01','TDMMIRAGO1OUT:0
TDMMIRAGO2OUT:0 TDMTHNOUT:0 TDMALLOUT:0 TDMMIRAGO1IN:0 TDMMIRAGO2IN:0
TDMTHNIN:0 TDMALLIN:0'),(14282,'','2013-09-05
15:05:01','TDMMIRAGO1OUT:0 TDMMIRAGO2OUT:0 TDMTHNOUT:0 TDMALLOUT:0
TDMMIRAGO1IN:0 TDMMIRAGO2IN:0 TDMTHNIN:0
TDMALLIN:0'),(3531,'','2013-09-05 15:05:01','IN: OUT:17
ALL:17'),(2005,'phones','2013-09-05 15:05:01','741
') ON DUPLICATE KEY UPDATE output=VALUES(output)'


09/05/2013 03:05:15 PM - CMDPHP: Poller[0] DEVEL: SQL Assoc: "select
poller_output.output,  poller_output.time,
UNIX_TIMESTAMP(poller_output.time) as unix_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"

09/05/2013 03:05:15 PM - SPINE: Poller[0] DEVDBG: SQL:'UPDATE
poller_item SET rrd_next_step=IF((rrd_next_step-300)>=0,
(rrd_next_step-300), (rrd_step-300)) WHERE host_id=79'
09/05/2013 03:05:15 PM - SPINE: Poller[0] Host[79] TH[1] Total Time:
 14 Seconds
09/05/2013 03:05:15 PM - SPINE: Poller[0] Host[79] TH[1] DEBUG: HOST
COMPLETE: About to Exit Host Polling Thread Function
09/05/2013 03:05:15 PM - SPINE: Poller[0] DEBUG: The Value of Active
Threads is 0
09/05/2013 03:05:15 PM - SPINE: Poller[0] DEVDBG: SQL:'replace into
settings (name,value) values ('date',NOW())'
09/05/2013 03:05:15 PM - CMDPHP: Poller[0] DEVEL: 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=14022"
09/05/2013 03:05:15 PM - POLLER: Poller[0] Parsed MULTI output field
'TDMMIRAGO1OUT:0' [map TDMMIRAGO1OUT->MIRAGO1OUT]
09/05/2013 03:05:15 PM - POLLER: Poller[0] Parsed MULTI output field
'TDMMIRAGO2OUT:0' [map TDMMIRAGO2OUT->MIRAGO2OUT]
09/05/2013 03:05:15 PM - POLLER: Poller[0] Parsed MULTI output field
'TDMTHNOUT:0' [map TDMTHNOUT->THNOUT]
09/05/2013 03:05:15 PM - POLLER: Poller[0] Parsed MULTI output field
'TDMMIRAGO1IN:0' [map TDMMIRAGO1IN->MIRAGO1IN]
09/05/2013 03:05:15 PM - POLLER: Poller[0] Parsed MULTI output field
'TDMMIRAGO2IN:0' [map TDMMIRAGO2IN->MIRAGO2IN]
09/05/2013 03:05:15 PM - POLLER: Poller[0] Parsed MULTI output field
'TDMTHNIN:0' [map TDMTHNIN->THNIN]
09/05/2013 03:05:15 PM - CMDPHP: Poller[0] DEVEL: 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=3531"
09/05/2013 03:05:15 PM - POLLER: Poller[0] Parsed MULTI output field
'IN:' [map IN->IN]
09/05/2013 03:05:15 PM - POLLER: Poller[0] Parsed MULTI output field
'OUT:17' [map OUT->OUT]
09/05/2013 03:05:15 PM - POLLER: Poller[0] Parsed MULTI output field
'ALL:17' [map ALL->ALL]
09/05/2013 03:05:15 PM - CMDPHP: Poller[0] DEVEL: 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=2499"
09/05/2013 03:05:15 PM - POLLER: Poller[0] Parsed MULTI output field
'ambient_temp1:23' [map ambient_temp1->ambient_temp1]
09/05/2013 03:05:15 PM - POLLER: Poller[0] Parsed MULTI output field
'ambient_temp2:16' [map ambient_temp2->ambient_temp2]
09/05/2013 03:05:15 PM - CMDPHP: Poller[0] DEVEL: 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=14282"
09/05/2013 03:05:15 PM - POLLER: Poller[0] Parsed MULTI output field
'TDMMIRAGO1OUT:0' [map TDMMIRAGO1OUT->MIRAGO1OUT]
09/05/2013 03:05:15 PM - POLLER: Poller[0] Parsed MULTI output field
'TDMMIRAGO2OUT:0' [map TDMMIRAGO2OUT->MIRAGO2OUT]
09/05/2013 03:05:15 PM - POLLER: Poller[0] Parsed MULTI output field
'TDMTHNOUT:0' [map TDMTHNOUT->THNOUT]
09/05/2013 03:05:15 PM - POLLER: Poller[0] Parsed MULTI output field
'TDMMIRAGO1IN:0' [map TDMMIRAGO1IN->MIRAGO1IN]
09/05/2013 03:05:15 PM - POLLER: Poller[0] Parsed MULTI output field
'TDMMIRAGO2IN:0' [map TDMMIRAGO2IN->MIRAGO2IN]
09/05/2013 03:05:15 PM - POLLER: Poller[0] Parsed MULTI output field
'TDMTHNIN:0' [map TDMTHNIN->THNIN]
09/05/2013 03:05:15 PM - CMDPHP: Poller[0] DEVEL: SQL Exec: "DELETE
FROM poller_output WHERE local_data_id IN
(14022,3531,2499,14282,2495,2005)"
09/05/2013 03:05:15 PM - CMDPHP: Poller[0] DEVEL: SQL Assoc: "SELECT 1
AS 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 = 'poller_output'  AND ph.name IN ('settings', 'boost',
'dsstats')  UNION  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 = 'poller_output'
AND ph.name NOT IN ('settings', 'boost', 'dsstats')  ORDER BY id ASC"
09/05/2013 03:05:15 PM - CMDPHP: Poller[0] DEVEL: SQL Assoc: "SELECT
thold_data.percent_ds, thold_data.data_type, thold_data.cdef,
thold_data.rra_id, thold_data.data_id, thold_data.lastread,
thold_data.oldvalue, data_template_rrd.data_source_name as name,
data_template_rrd.data_source_type_id, data_template_data.rrd_step
  FROM thold_data       LEFT JOIN data_template_rrd on
(data_template_rrd.id = thold_data.data_id)       LEFT JOIN
data_template_data ON ( data_template_data.local_data_id =
thold_data.rra_id )       WHERE data_template_rrd.data_source_name !=
'' AND thold_data.rra_id = 14022 OR thold_data.rra_id = 3531 OR
thold_data.rra_id = 2499 OR thold_data.rra_id = 14282 OR
thold_data.rra_id = 2495 OR thold_data.rra_id = 2005"

------------------------------------------------------------------------------
Learn the latest--Visual Studio 2012, SharePoint 2013, SQL 2012, more!
Discover the easy way to master current and previous Microsoft technologies
and advance your career. Get an incredible 1,500+ hours of step-by-step
tutorial videos with LearnDevNow. Subscribe today and save!
http://pubads.g.doubleclick.net/gampad/clk?id=58041391&iu=/4140/ostg.clktrk