Asterik IP-PBX Statistics (SIP/IAX2 protocols)

[gandalf]

Even if we're going to install some few thousands of asterisk devices, personally I have NO idea how they work.
So my only advice is to run the failing scripts as cactiuser manually to see why they fail.
Reinhard

[cyberwar]

Hallo Guys,

This is a GREAT script and thanks allot for everyone's hard work to make this work!

I do however experience a problem getting the graphs to update and get these errors:

WARNING: Result from CMD not valid. Partial Result: action: login

I cant seem to solve this and would really appreciate any assistance.

Here is a full debug:

06/15/2008 05:33:01 PM - CMDPHP: Poller[0] Host[5] DS[30] SNMP: v2: 196.30.144.180, dsname: traffic_out, oid: .1.3.6.1.2.1.2.2.1.16.3, output: 3654052893
06/15/2008 05:33:01 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_output (local_data_id, rrd_name, time, output) values (30, 'traffic_out', '2008-06-15 17:33:01', '3654052893')"
06/15/2008 05:33:01 PM - PHPSVR: Poller[0] DEBUG: INC: '/usr/local/groundwork/apache2/htdocs/cacti/scripts/ss_host_disk.php' FUNC: 'ss_host_disk' PARMS: '196.30.144.180 5 2:161:500:public:::MD5::DES: get used 4'
06/15/2008 05:33:01 PM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select field_value from host_snmp_cache where host_id=5 and field_name='hrStorageAllocationUnits' and snmp_index='4'"
06/15/2008 05:33:01 PM - CMDPHP: Poller[0] Host[5] DS[27] SERVER: /usr/local/groundwork/apache2/htdocs/cacti/scripts/ss_host_disk.php ss_host_disk 196.30.144.180 5 2:161:500:public:::MD5::DES: get used 4, output: 5249826816
06/15/2008 05:33:01 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_output (local_data_id, rrd_name, time, output) values (27, 'hdd_used', '2008-06-15 17:33:01', '5249826816')"
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] Host[5] DS[38] WARNING: Result from CMD not valid. Partial Result: action: login
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] Host[5] DS[38] CMD: /usr/local/groundwork/apache2/htdocs/cacti/scripts/asx-stats.py 0 sip.peers.online, output: U
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_output (local_data_id, rrd_name, time, output) values (38, 'sip_peers_online', '2008-06-15 17:33:01', 'U')"
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] Host[5] DS[39] WARNING: Result from CMD not valid. Partial Result: action: login
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] Host[5] DS[39] CMD: /usr/local/groundwork/apache2/htdocs/cacti/scripts/asx-stats.py 0 sip.peers.total, output: U
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_output (local_data_id, rrd_name, time, output) values (39, 'sip_peers_total', '2008-06-15 17:33:01', 'U')"
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] Host[5] DS[51] WARNING: Result from CMD not valid. Partial Result: action: login
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] Host[5] DS[51] CMD: /usr/local/groundwork/apache2/htdocs/cacti/scripts/asx-stats.py 0 sip.users.total, output: U
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_output (local_data_id, rrd_name, time, output) values (51, 'sip_users_total', '2008-06-15 17:33:01', 'U')"
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] Host[5] DS[41] WARNING: Result from CMD not valid. Partial Result: action: login
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] Host[5] DS[41] CMD: /usr/local/groundwork/apache2/htdocs/cacti/scripts/asx-stats.py 0 iax.channels, output: U
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_output (local_data_id, rrd_name, time, output) values (41, 'iax_channels', '2008-06-15 17:33:01', 'U')"
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] Host[5] DS[42] WARNING: Result from CMD not valid. Partial Result: action: login
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] Host[5] DS[42] CMD: /usr/local/groundwork/apache2/htdocs/cacti/scripts/asx-stats.py 0 iax.peers.online, output: U
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_output (local_data_id, rrd_name, time, output) values (42, 'iax_peers_online', '2008-06-15 17:33:01', 'U')"
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] Host[5] DS[43] WARNING: Result from CMD not valid. Partial Result: action: login
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] Host[5] DS[43] CMD: /usr/local/groundwork/apache2/htdocs/cacti/scripts/asx-stats.py 0 iax.peers.total, output: U
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_output (local_data_id, rrd_name, time, output) values (43, 'iax_peers_total', '2008-06-15 17:33:01', 'U')"
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] Host[5] DS[49] WARNING: Result from CMD not valid. Partial Result: action: login
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] Host[5] DS[49] CMD: /usr/local/groundwork/apache2/htdocs/cacti/scripts/asx-stats.py 0 iax.users.total, output: U
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_output (local_data_id, rrd_name, time, output) values (49, 'iax_users_total', '2008-06-15 17:33:01', 'U')"
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] Host[5] DS[50] WARNING: Result from CMD not valid. Partial Result: action: login
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] Host[5] DS[50] CMD: /usr/local/groundwork/apache2/htdocs/cacti/scripts/asx-stats.py 0 sip.channels, output: U
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_output (local_data_id, rrd_name, time, output) values (50, 'sip_channels', '2008-06-15 17:33:01', 'U')"
06/15/2008 05:33:02 PM - PHPSVR: Poller[0] DEBUG: PHP Script Server Shutdown request received, exiting
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] Time: 2.8925 s, Theads: N/A, Hosts: 2
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "insert into poller_time (poller_id, start_time, end_time) values (0, NOW(), NOW())"
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] DEBUG: SQL Assoc: "select poller_id,end_time from poller_time where poller_id=0"
06/15/2008 05:33: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) "
06/15/2008 05:33:02 PM - 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=31"
06/15/2008 05:33:02 PM - POLLER: Poller[0] Parsed MULTI output field '1min:0.23' [map 1min->load_1min]
06/15/2008 05:33:02 PM - POLLER: Poller[0] Parsed MULTI output field '5min:0.22' [map 5min->load_5min]
06/15/2008 05:33:02 PM - POLLER: Poller[0] Parsed MULTI output field '10min:0.14' [map 10min->load_15min]
06/15/2008 05:33:02 PM - 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=9"
06/15/2008 05:33:02 PM - POLLER: Poller[0] Parsed MULTI output field '1min:0.23' [map 1min->load_1min]
06/15/2008 05:33:02 PM - POLLER: Poller[0] Parsed MULTI output field '5min:0.22' [map 5min->load_5min]
06/15/2008 05:33:02 PM - POLLER: Poller[0] Parsed MULTI output field '10min:0.14' [map 10min->load_15min]
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='50' and rrd_name='sip_channels' and time='2008-06-15 17:33:01'"
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='49' and rrd_name='iax_users_total' and time='2008-06-15 17:33:01'"
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='43' and rrd_name='iax_peers_total' and time='2008-06-15 17:33:01'"
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='42' and rrd_name='iax_peers_online' and time='2008-06-15 17:33:01'"
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='41' and rrd_name='iax_channels' and time='2008-06-15 17:33:01'"
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='51' and rrd_name='sip_users_total' and time='2008-06-15 17:33:01'"
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='39' and rrd_name='sip_peers_total' and time='2008-06-15 17:33:01'"
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='38' and rrd_name='sip_peers_online' and time='2008-06-15 17:33:01'"
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='27' and rrd_name='hdd_used' and time='2008-06-15 17:33:01'"
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='30' and rrd_name='traffic_out' and time='2008-06-15 17:33:01'"
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='28' and rrd_name='traffic_in' and time='2008-06-15 17:33:01'"
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='30' and rrd_name='traffic_in' and time='2008-06-15 17:33:01'"
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='28' and rrd_name='traffic_out' and time='2008-06-15 17:33:01'"
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='27' and rrd_name='hdd_total' and time='2008-06-15 17:33:01'"
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='31' and rrd_name='' and time='2008-06-15 17:33:01'"
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='33' and rrd_name='proc' and time='2008-06-15 17:33:01'"
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='32' and rrd_name='users' and time='2008-06-15 17:33:01'"
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='34' and rrd_name='mem_buffers' and time='2008-06-15 17:33:01'"
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='35' and rrd_name='mem_swap' and time='2008-06-15 17:33:01'"
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='10' and rrd_name='hdd_used' and time='2008-06-15 17:32:59'"
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='10' and rrd_name='hdd_free' and time='2008-06-15 17:32:59'"
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='9' and rrd_name='' and time='2008-06-15 17:32:59'"
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "delete from poller_output where local_data_id='8' and rrd_name='proc' and time='2008-06-15 17:32:59'"
06/15/2008 05:33:02 PM - POLLER: Poller[0] CACTI2RRD: /usr/local/groundwork/bin/rrdtool update /usr/local/groundwork/apache2/htdocs/cacti/rra/smc_switch_verizon_1_sip_channels_50.rrd --template sip_channels 1213543981:U
06/15/2008 05:33:02 PM - POLLER: Poller[0] CACTI2RRD: /usr/local/groundwork/bin/rrdtool update /usr/local/groundwork/apache2/htdocs/cacti/rra/smc_switch_verizon_1_iax_users_total_49.rrd --template iax_users_total 1213543981:U
06/15/2008 05:33:02 PM - POLLER: Poller[0] CACTI2RRD: /usr/local/groundwork/bin/rrdtool update /usr/local/groundwork/apache2/htdocs/cacti/rra/smc_switch_verizon_1_iax_peers_total_43.rrd --template iax_peers_total 1213543981:U
06/15/2008 05:33:02 PM - POLLER: Poller[0] CACTI2RRD: /usr/local/groundwork/bin/rrdtool update /usr/local/groundwork/apache2/htdocs/cacti/rra/smc_switch_verizon_1_iax_peers_online_42.rrd --template iax_peers_online 1213543981:U
06/15/2008 05:33:02 PM - POLLER: Poller[0] CACTI2RRD: /usr/local/groundwork/bin/rrdtool update /usr/local/groundwork/apache2/htdocs/cacti/rra/smc_switch_verizon_1_iax_channels_41.rrd --template iax_channels 1213543981:U
06/15/2008 05:33:02 PM - POLLER: Poller[0] CACTI2RRD: /usr/local/groundwork/bin/rrdtool update /usr/local/groundwork/apache2/htdocs/cacti/rra/smc_switch_verizon_1_sip_users_total_51.rrd --template sip_users_total 1213543981:U
06/15/2008 05:33:02 PM - POLLER: Poller[0] CACTI2RRD: /usr/local/groundwork/bin/rrdtool update /usr/local/groundwork/apache2/htdocs/cacti/rra/smc_switch_verizon_1_sip_peers_total_39.rrd --template sip_peers_total 1213543981:U
06/15/2008 05:33:02 PM - POLLER: Poller[0] CACTI2RRD: /usr/local/groundwork/bin/rrdtool update /usr/local/groundwork/apache2/htdocs/cacti/rra/smc_switch_verizon_1_sip_peers_online_38.rrd --template sip_peers_online 1213543981:U
06/15/2008 05:33:02 PM - POLLER: Poller[0] CACTI2RRD: /usr/local/groundwork/bin/rrdtool update /usr/local/groundwork/apache2/htdocs/cacti/rra/smc_switch_verizon_1_hdd_used_27.rrd --template hdd_used:hdd_total 1213543981:5249826816:8256487424
06/15/2008 05:33:02 PM - POLLER: Poller[0] CACTI2RRD: /usr/local/groundwork/bin/rrdtool update /usr/local/groundwork/apache2/htdocs/cacti/rra/smc_switch_verizon_1_traffic_in_30.rrd --template traffic_out:traffic_in 1213543981:3654052893:4264374282
06/15/2008 05:33:02 PM - POLLER: Poller[0] CACTI2RRD: /usr/local/groundwork/bin/rrdtool update /usr/local/groundwork/apache2/htdocs/cacti/rra/smc_switch_verizon_1_traffic_in_28.rrd --template traffic_in:traffic_out 1213543981:2585481999:2639329809
06/15/2008 05:33:02 PM - POLLER: Poller[0] CACTI2RRD: /usr/local/groundwork/bin/rrdtool update /usr/local/groundwork/apache2/htdocs/cacti/rra/smc_switch_verizon_1_load_1min_31.rrd --template load_1min:load_5min:load_15min 1213543981:0.23:0.22:0.14
06/15/2008 05:33:02 PM - POLLER: Poller[0] CACTI2RRD: /usr/local/groundwork/bin/rrdtool update /usr/local/groundwork/apache2/htdocs/cacti/rra/smc_switch_verizon_1_proc_33.rrd --template proc 1213543981:93
06/15/2008 05:33:02 PM - POLLER: Poller[0] CACTI2RRD: /usr/local/groundwork/bin/rrdtool update /usr/local/groundwork/apache2/htdocs/cacti/rra/smc_switch_verizon_1_users_32.rrd --template users 1213543981:4
06/15/2008 05:33:02 PM - POLLER: Poller[0] CACTI2RRD: /usr/local/groundwork/bin/rrdtool update /usr/local/groundwork/apache2/htdocs/cacti/rra/smc_switch_verizon_1_mem_buffers_34.rrd --template mem_buffers 1213543981:14412
06/15/2008 05:33:02 PM - POLLER: Poller[0] CACTI2RRD: /usr/local/groundwork/bin/rrdtool update /usr/local/groundwork/apache2/htdocs/cacti/rra/smc_switch_verizon_1_mem_swap_35.rrd --template mem_swap 1213543981:0
06/15/2008 05:33:02 PM - POLLER: Poller[0] CACTI2RRD: /usr/local/groundwork/bin/rrdtool update /usr/local/groundwork/apache2/htdocs/cacti/rra/localhost_hdd_free_10.rrd --template hdd_used:hdd_free 1213543979:3789952:667808
06/15/2008 05:33:02 PM - POLLER: Poller[0] CACTI2RRD: /usr/local/groundwork/bin/rrdtool update /usr/local/groundwork/apache2/htdocs/cacti/rra/localhost_load_1min_9.rrd --template load_1min:load_5min:load_15min 1213543979:0.23:0.22:0.14
06/15/2008 05:33:02 PM - POLLER: Poller[0] CACTI2RRD: /usr/local/groundwork/bin/rrdtool update /usr/local/groundwork/apache2/htdocs/cacti/rra/localhost_proc_8.rrd --template proc 1213543979:U
06/15/2008 05:33:02 PM - SYSTEM STATS: Time:3.1624 Method:cmd.php Processes:1 Threads:N/A Hosts:3 HostsPerProcess:3 DataSources:28 RRDsProcessed:24
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] DEBUG: SQL Exec: "replace into settings (name,value) values ('stats_poller','Time:3.1624 Method:cmd.php Processes:1 Threads:N/A Hosts:3 HostsPerProcess:3 DataSources:28 RRDsProcessed:24')"
06/15/2008 05:33:02 PM - CMDPHP: Poller[0] DEBUG: SQL Cell: "select count(*) from poller_command"

Regards

CW

[itconnection]

Set the variable "verbose" to "1" in the script and try to run this script.

[cyberwar]

Thanks, after many hours of debugging I figured out this is causing the problem

Regards

CW

[gandalf]

Thanks, after many hours of debugging I figured out this is causing the problem

Regards

CW

Would you please share your findings?
Reinhard

[cyberwar]

Sure, my problem was (while trying to get everything working) I had switched on debugging in the script to see where the problem might be. I then FORGOT to switch debugging off again! Remember, cacti looks for a single output value, not a whole sting of characters

Thanks for this great contribution

CW

[Pengu1n]

hi
many thanks for this script
but one problem - sip.peers.online always shows zero
Asterisk version 1.4.1
asx-stats script version 0.1.5.1


mon1:/var/www/cacti/scripts# ./asx-stats.py
Asterisk Call Manager/1.0
Response: Success
Message: Authentication accepted

sip.peers.total:100
sip.peers.online:0
iax.peers.total:1
iax.peers.online:1
sip.users.total:68
iax.users.total:1
sip.channels:67
iax.channels:0
pstn.channels:6
zap.channels:0
agents.loggedin.yes:0
agents.loggedin.no:0
voicemail.all:0
core.threads:28
core.uptime:2338478
iax.peers.peer1.status:0
iax.peers.peer2.status:0


asterisk*CLI> sip show peers
..............
100 sip peers [Monitored: 78 online, 22 offline Unmonitored: 0 online, 0 offline]

[redawg]

Check the script for which command it is running. Then login to your console on asterisk and run the same script. it might just be a command problem, I noticed that if you change the script to use the older command (they work on 1.4 and 1.2) you can get voicemails, and sip.peers.

I had to do this because I have 1.2 and 1.4 asterisk systems running.

[jstjst]

does this script work only if cacti is installed localy or does it fork if it is installed remotely? do I need to open somer ports or install something on trixbox ?

tnx in advance

[DJ Kill]

Asking for help:

Then i start
./asx-stats.py 0 sip.peers.online
got as result
26

But in the CACTI log always
WARNING: Result from CMD not valid. Partial Result:

[gandalf]

In general, you should provide cacti with the explicit interpreter used. Please to not rely on cactiuser's ENVIRONMENT
Reinhard

[DJ Kill]

Sorry... What should I do?

When I try
su cacti -c './asx-stats.py 0 sip.users.total'

I'vw got
This account is currently not available.

Maybe the problem in cacti user? But how can i solve this problem?

[gandalf]

Correct. The cacti user (or sometimes called cactiuser) must be able to run the script successfully. How to achieve this with asterisk is beyond my knowledge
Reinhard

[DJ Kill]

Script starts locally on Cacti. Permissions sets to 777 (allow all for everyone).
You think that problem was in Asterisk server?

[gandalf]

Script starts locally on Cacti. Permissions sets to 777 (allow all for everyone).
You think that problem was in Asterisk server?

Yep.
Reinhard