provided by Star2Billing S.L.

I have Freepbx2.3.1 (pbxinaflash) and A2Billing 1.3.1. I have searched the forums for possible clues to my problem. But I am still stuck with the following error Originate FAiled. and got no clues why.

I have a "DID that triggers the custom-a2billing-all-callback context. When I call this number, it answers and then hungs up. After that , I get the error message as seen in the /var/log/asterisk/a2billing-callback-daemon.log


** Number of PENDING callbacks 1 **
UPDATE cc_callback_spool SET status='PROCESSING' WHERE id='25'

WE ARE IN THE PARENT PROCESS -> CHILD PID = 3346 -> RECORD ID = 25

WE ARE IN THE CHILD PROCESS
SQL =SELECT id, server_ip, manager_host, manager_username, manager_secret, lasttime_used FROM cc_server_manager WHERE id_group='1' ORDER BY lasttime_used ASC LIMIT 1 OFFSET 0

[2008-03-26 20:33:07]
[
[2008-03-26 20:33:07]]
::>>> IO::Socket::INET=GLOB(0x9be0614)
[
[2008-03-26 20:33:37]]Response - Error - Message - Originate failed
[
[2008-03-26 20:33:37]]

## CALLBACK FAIL
UPDATE cc_callback_spool SET status='ERROR', manager_result='Error-Originate failed', num_attempt='1', last_attempt_time=now(), id_server='1' WHERE id='25'[
[2008-03-26 20:33:37]]
UPDATE cc_server_manager SET lasttime_used=current_timestamp WHERE id ='1'[
[2008-03-26 20:33:37]]
[END SELECT]




Here;s the output of my CLI as seen in /tmp/a2billing_agi.log when I call the DID number. I don't see any reference to the custom-a2billing-callback context that I use for callback so I don't know if this is useful at all, but I am including it anyway.


--------------------- display of /tmp/a2billing_agi.log
[26/03/2008 20:32:51]:[file:a2billing.php - line:76]:[CallerID:]:[CN:]:[IDCONFIG : 2]
[26/03/2008 20:32:51]:[file:a2billing.php - line:77]:[CallerID:]:[CN:]:[MODE : all-callback]
[26/03/2008 20:32:51]:[file:Class.A2Billing.php - line:616]:[CallerID:4162732358]:[CN:]:[ get_agi_request_parameter = 4162732358 ; SIP/1513520413-09167a28 ; 1206577969.0 ; ; 16474762108]
[26/03/2008 20:32:51]:[file:a2billing.php - line:555]:[CallerID:4162732358]:[CN:]:[[MODE : ALL-CALLBACK - 4162732358]]
[26/03/2008 20:32:51]:[file:a2billing.php - line:559]:[CallerID:4162732358]:[CN:]:[[HANGUP ALL CALLBACK TRIGGER]]
[26/03/2008 20:32:51]:[file:Class.RateEngine.php - line:73]:[CallerID:4162732358]:[CN:]:[[CC_asterisk_rate-engine: (1, 14162732358)]]
[26/03/2008 20:32:51]:[file:Class.RateEngine.php - line:122]:[CallerID:4162732358]:[CN:]:[[CC_asterisk_rate-engine: CALLERID]
4162732358
]
[26/03/2008 20:32:51]:[file:Class.RateEngine.php - line:136]:[CallerID:4162732358]:[CN:]:[[CALLERID_QUERY]
SELECT count(calleridprefix) FROM cc_tariffplan RIGHT JOIN cc_tariffgroup_plan ON cc_tariffgroup_plan.idtariffgroup=1 WHERE calleridprefix=SUBSTRING('4162732358',1,length(calleridprefix))
[RESULT]Array
(
[0] => Array
(
[0] => 0
[count(calleridprefix)] => 0
)

)

]
[26/03/2008 20:32:51]:[file:Class.RateEngine.php - line:182]:[CallerID:4162732358]:[CN:]:[[RATE ENGINE QUERY]
SELECT tariffgroupname, lcrtype, idtariffgroup, cc_tariffgroup_plan.idtariffplan, tariffname, destination,

cc_ratecard.id, dialprefix, destination, buyrate, buyrateinitblock, buyrateincrement, rateinitial, initblock, billingblock,
connectcharge, disconnectcharge, stepchargea, chargea, timechargea, billingblocka, stepchargeb, chargeb,
timechargeb, billingblockb, stepchargec, chargec, timechargec, billingblockc,
cc_tariffplan.id_trunk AS tp_id_trunk, tp_trunk.trunkprefix AS tp_trunk, tp_trunk.providertech AS tp_providertech,
tp_trunk.providerip AS tp_providerip, tp_trunk.removeprefix AS tp_removeprefix,
cc_ratecard.id_trunk AS rc_id_trunk, rt_trunk.trunkprefix AS rc_trunkprefix, rt_trunk.providertech AS rc_providertech,
rt_trunk.providerip AS rc_providerip, rt_trunk.removeprefix AS rc_removeprefix,
musiconhold,
tp_trunk.failover_trunk AS tp_failover_trunk,
rt_trunk.failover_trunk AS rt_failover_trunk,
tp_trunk.addparameter AS tp_addparameter_trunk,
rt_trunk.addparameter AS rt_addparameter_trunk,
id_outbound_cidgroup,
freetimetocall_package_offer, freetimetocall, packagetype, billingtype, startday, id_cc_package_offer


FROM cc_tariffgroup
RIGHT JOIN cc_tariffgroup_plan ON cc_tariffgroup.id=1

INNER JOIN cc_tariffplan ON (cc_tariffplan.id=cc_tariffgroup_plan.idtariffplan )

LEFT JOIN cc_ratecard ON cc_ratecard.idtariffplan=cc_tariffplan.id
LEFT JOIN cc_trunk AS rt_trunk ON cc_ratecard.id_trunk=rt_trunk.id_trunk
LEFT JOIN cc_trunk AS tp_trunk ON cc_tariffplan.id_trunk=tp_trunk.id_trunk
LEFT JOIN cc_package_offer ON cc_package_offer.id=cc_tariffgroup.id_cc_package_offer

WHERE (dialprefix=SUBSTRING('14162732358',1,length(dialprefix)) OR dialprefix='defaultprefix')
AND startingdate<= CURRENT_TIMESTAMP AND (expirationdate > CURRENT_TIMESTAMP OR expirationdate IS NULL OR LENGTH(expirationdate)<5)
AND startdate<= CURRENT_TIMESTAMP AND (stopdate > CURRENT_TIMESTAMP OR stopdate IS NULL OR LENGTH(stopdate)<5)
AND (starttime <= 4112 AND endtime >=4112)
AND idtariffgroup='1'
AND ( dnidprefix=SUBSTRING('14162732358',1,length(dnidprefix)) OR (dnidprefix='all' AND 0 = 0))
AND ( calleridprefix=SUBSTRING('4162732358',1,length(calleridprefix)) OR (calleridprefix='all' AND 0 = 0))
ORDER BY LENGTH(dialprefix) DESC
]
[26/03/2008 20:32:51]:[file:Class.RateEngine.php - line:192]:[CallerID:4162732358]:[CN:]:[[rate-engine: Count Total result 1]]
[26/03/2008 20:32:51]:[file:Class.RateEngine.php - line:281]:[CallerID:4162732358]:[CN:]:[[CC_asterisk_rate-engine: Count Total result 1]]
[26/03/2008 20:32:51]:[file:Class.RateEngine.php - line:282]:[CallerID:4162732358]:[CN:]:[[CC_asterisk_rate-engine: number_trunk 1]]
[26/03/2008 20:32:51]:[file:Class.RateEngine.php - line:295]:[CallerID:4162732358]:[CN:]:[[CC_RATE_ENGINE_ALL_CALCULTIMEOUT (1000)]]
[26/03/2008 20:32:51]:[file:Class.RateEngine.php - line:301]:[CallerID:4162732358]:[CN:]:[[CC_RATE_ENGINE_ALL_CALCULTIMEOUT: k=0 - res_calcultimeout:6000000]]
[26/03/2008 20:32:51]:[file:a2billing.php - line:665]:[CallerID:4162732358]:[CN:]:[[CALLBACK-ALL : INSERT CALLBACK REQUEST IN SPOOL : QUERY= INSERT INTO cc_callback_spool (uniqueid, status, server_ip, num_attempt, channel, exten, context, priority, variable, id_server_group, callback_time, account, callerid, timeout ) VALUES ('11624-wanseqh', 'PENDING', 'localhost', '0', 'SIP/Callcentric/14162732358', '1000', 'a2billing-callback', '1', 'CALLED=14162732358|MODE=ALL|CBID=11624-wanseqh|TARIFF=1|LEG=', '1', ADDDATE( CURRENT_TIMESTAMP, INTERVAL 10 SECOND ), '', '123456', '30000')]]
[26/03/2008 20:32:51]:[CallerID:4162732358]:[CN:]:[[exit]]


and here's my extensions_a2billing.conf


[custom-a2billing]
exten => _X.,1,Answer
exten => _X.,n,Wait(1)
exten => _X.,n,DeadAGI(a2billing.php|1)
exten => _X.,n,Hangup

[custom-a2billing-callback]
exten => _X.,1,DeadAGI(a2billing.php|3|callback)
exten => _X.,n,Hangup

[a2billing-cid-callback]
exten => _X.,1,DeadAGI(a2billing.php|1|cid-callback|1) ;last parameter is the callback area code
exten => _X.,n,Hangup

[custom-a2billing-all-callback]
exten => _X.,1,Answer
exten => _X.,n,Wait(1)
exten => _X.,n,DeadAGI(a2billing.php|2|all-callback|1) ;last parameter is the callback area code
;exten => _X.,n,Hangup
exten => _X.,n,Wait(60)

[a2billing-did]
exten => _X.,1,DeadAGI(a2billing.php|1|did)
exten => _X.,2,Hangup

[a2billing-voucher]
exten => _X.,1,DeadAGI(a2billing.php|1|voucher)
exten => _X.,n,Hangup


in a2billing.conf, I am using extension 1000 to handle the call. I use the context custom-a2billing-callback for this extension. when I call this extension, I was able to callout the trunk, so I know my trunk is working.

Any other information that I need to setup to get this callback function to work???

Many thanks in advance.

Hi

Things to check:-

You still need an a2billing-callback context as defined in a2billing.conf

[a2billing-callback]
exten => _X.,1,DeadAGI(a2billing.php|1|callback)
exten => _X.,n,Hangup

This is the context it uses for the return leg.

Additionally check that the manager parameters (default myasterisk / mycode ) are present in manager_custom.conf and match with what you have under callback | Servers in the UI.

Joe

Joe's suggestion about the context name seems to be the root of your problem. If you would prefer the context to be called 'custom-a2billing-callback' rather 'a2billing-callback' change the setting in a2billing.conf to reflect this:Contrary to the remarks, I believe this affects all callbacks not just web callbacks.

Thank you for your suggestions.


I checked my manager_custom.conf file and it contains the following:

[admin]
secret = amp111
deny=0.0.0.0/0.0.0.0
permit=127.0.0.1/255.255.255.0
read = system,call,log,verbose,command,agent,user
write = system,call,log,verbose,command,agent,user


in the Web Server UI for callback, i have server hostname = localhost, manager host = localhost, manager username = admin, manager secret =amp111

one thing I noticed, in the Show callback windows, the Server column is blank. Is this expected?


Regarding stavros' comments:


I actually have the following context in a2billing.conf

context_callback = custom-a2billing-callback



here's the relevant sections of my a2billing.conf




; Global Database Setup - select the database type and authentication as required.

[database]
hostname = localhost
port = 5432
user = a2billinguser
password = a2billing
dbname = mya2billing
;dbtype = postgres
dbtype = mysql


[global]
; len_cardnumber is removed
; interval for the length of the cardnumber (number of digits), minimum lenght is 4
; ie: 10-15 (cardnumber authorised 10, 11, 12, 13, 14, 15) ; 10,12,14 (cardnumber authorised 10, 12, 14)
interval_len_cardnumber = 10-15

; Alias-Card length
len_aliasnumber = 15

; Voucher length
len_voucher = 15

;base currency define the default currency that you want to use to setup your system (see the currency table to know the currency code)
base_currency = usd

; filename of the image that will be display at the top of the invoice (if not defined no image will appear ; path to place the image templates/default/images/)
; the type of file have to be a jpeg/jpg
invoice_image = asterisk01.jpg

; DID Billing - amount of day before the end of the monthly reservation to bill the customer to for the DID use
; if the user dont have enough credit he will get an email asking him to refill
didbilling_daytopay = 5


; MANAGER CONNECTION PARAMETERS
manager_host = localhost
;manager_username = a2billinguser
manager_username = admin
;manager_secret = a2billing
manager_secret = amp111


; CALL-BACK
[callback]
; When web call-back is enabled this is the context to sent the call.
context_callback = custom-a2billing-callback

; this is the Extension to redirect the call when the web callback is returned
extension = 1000

; this is the number of seconds to wait before initiating the call back.
sec_wait_before_callback = 10

;Number of seconds before the call-back can be re-initiated from the web page
; to prevent repeated and unwanted calls.
sec_avoid_repeate = 30

; if the callback doesnt succeed within the value below, then the call is deemed to have failed.
timeout = 20

; if we want to manage the answer on the call
; Disabling this for callback trigger numbers makes it ring not hang up.
answer_call = yes


; PREDICTIVE DIALER
; number of calls an agent will do when the call button is clicked
nb_predictive_call = 10

; Number of days to wait before the number becomes available to call again.
nb_day_wait_before_retry = 1

; The context to redirect the call for the predictive dialer
context_preditctivedialer = a2billing-predictivedialer


; When a call is made we need to limit the call duration : amount in seconds
predictivedialer_maxtime_tocall = 5400

; set the callerID for the predictive dialer and call-back
callerid = 123456

; ID Call Plan to use when you use the all-callback mode, check the ID in the "list Call Plan" - WebUI
all_callback_tariff = 1

; Define the group of servers that are going to be used by the callback
id_server_group = 1

; Audio intro message when the callback is initiate
callback_audio_intro = prepaid-callback_intro




; configuration for the AGI, different configuration can be defined, ie "agi-conf1", "agi-conf2", etc...
; the groupid parameter will define which process_sections to use. Usage : DeadAGI(a2billing.php|%groupid%)
; by default agi-conf1 is used
[agi-conf1]
debug = 1
asterisk_version = 1_2
answer_call = YES
play_audio = YES
say_goodbye = NO
play_menulanguage = NO
force_language =
intro_prompt =
min_credit_2call = 0
min_duration_2bill = 0
notenoughcredit_cardnumber = YES
notenoughcredit_assign_newcardnumber_cid = YES
use_dnid = NO
no_auth_dnid = 2400,2300
number_try = 3
force_callplan_id =
say_balance_after_auth = YES
say_balance_after_call = NO
say_rateinitial = NO
say_timetocall = YES
auto_setcallerid = YES
force_callerid =
cid_sanitize = NO

cid_enable =YES
cid_askpincode_ifnot_callerid = YES
cid_auto_assign_card_to_cid = YES
cid_auto_create_card = NO
cid_auto_create_card_len = 10
cid_auto_create_card_typepaid = POSTPAY
cid_auto_create_card_credit = 0
cid_auto_create_card_credit_limit = 1000
cid_auto_create_card_tariffgroup = 6
callerid_authentication_over_cardnumber = NO

sip_iax_friends = NO
sip_iax_pstn_direct_call_prefix = 555
sip_iax_pstn_direct_call = NO
ivr_voucher = NO
ivr_voucher_prefix = 8
jump_voucher_if_min_credit = NO

extracharge_did =
extracharge_fee =
international_prefixes = 011,00,09,1
dialcommand_param = "|60|HRgrL(%timeout%:61000:30000)"
dialcommand_param_sipiax_friend = "|60|HRgrL(3600000:61000:30000)"
switchdialcommand = NO
failover_recursive_limit = 2
maxtime_tocall_negatif_free_route = 5400
send_reminder = NO
record_call = NO
monitor_formatfile = gsm

agi_force_currency =
currency_association = usd:dollars,mxn:pesos,eur:euros,all:credit
file_conf_enter_destination = prepaid-enter-dest
file_conf_enter_menulang = prepaid-menulang2
callback_bill_1stleg_ifcall_notconnected = YES
;a2billing-all-callback


[agi-conf2]

debug = 3

asterisk_version = 1_2
answer_call = YES
play_audio = YES
say_goodbye = NO
play_menulanguage = NO
force_language =
intro_prompt =
min_credit_2call = 0
min_duration_2bill = 0
notenoughcredit_cardnumber = YES
notenoughcredit_assign_newcardnumber_cid = YES
use_dnid = NO
no_auth_dnid = 2400,2300
number_try = 3
force_callplan_id =
say_balance_after_auth = YES
say_balance_after_call = NO
say_rateinitial = NO
say_timetocall = YES
auto_setcallerid = YES
force_callerid =

cid_sanitize = NO
cid_enable =YES
cid_askpincode_ifnot_callerid = YES
cid_auto_assign_card_to_cid = YES
cid_auto_create_card = NO
cid_auto_create_card_len = 10
cid_auto_create_card_typepaid = POSTPAY
cid_auto_create_card_credit = 0
cid_auto_create_card_credit_limit = 1000
cid_auto_create_card_tariffgroup = 6
callerid_authentication_over_cardnumber = NO
sip_iax_friends = NO
sip_iax_pstn_direct_call_prefix = 555
sip_iax_pstn_direct_call = NO
ivr_voucher = NO
ivr_voucher_prefix = 8
jump_voucher_if_min_credit = NO
extracharge_did =
;extracharge_fee = 0.02,0.03
extracharge_fee =
international_prefixes = 011,00,09,1

dialcommand_param = "|60|HRgrL(%timeout%:61000:30000)"
dialcommand_param_sipiax_friend = "|60|HRgrL(3600000:61000:30000)"
switchdialcommand = NO
failover_recursive_limit = 2
maxtime_tocall_negatif_free_route = 5400
send_reminder = NO
record_call = NO
monitor_formatfile = gsm
currency_association = usd:dollars,mxn:pesos,eur:euros,all:credit

file_conf_enter_destination = prepaid-enter-dest
file_conf_enter_menulang = prepaid-menulang2
callback_bill_1stleg_ifcall_notconnected = YES

;a2billing-callback
[agi-conf3]
debug = 3
asterisk_version = 1_2
answer_call = YES
play_audio = YES
say_goodbye = NO
play_menulanguage = NO
force_language =
intro_prompt =
min_credit_2call = 0
min_duration_2bill = 0
notenoughcredit_cardnumber = YES
notenoughcredit_assign_newcardnumber_cid = YES

use_dnid = NO
no_auth_dnid = 2400,2300
number_try = 3
force_callplan_id =
say_balance_after_auth = YES
say_balance_after_call = NO
say_rateinitial = NO
say_timetocall = YES
auto_setcallerid = YES
force_callerid =
cid_sanitize = NO
cid_enable =YES
cid_askpincode_ifnot_callerid = YES
cid_auto_assign_card_to_cid = YES
cid_auto_create_card = NO
cid_auto_create_card_len = 10
cid_auto_create_card_typepaid = POSTPAY
cid_auto_create_card_credit = 0
cid_auto_create_card_credit_limit = 1000
cid_auto_create_card_tariffgroup = 6
callerid_authentication_over_cardnumber = NO
sip_iax_friends = NO
sip_iax_pstn_direct_call_prefix = 555
sip_iax_pstn_direct_call = NO
ivr_voucher = NO
ivr_voucher_prefix = 8
jump_voucher_if_min_credit = NO
extracharge_did =
extracharge_fee =
international_prefixes = 011,00,09,1


dialcommand_param = "|60|HRgrL(%timeout%:61000:30000)"
dialcommand_param_sipiax_friend = "|60|HRgrL(3600000:61000:30000)"
switchdialcommand = NO
failover_recursive_limit = 2
maxtime_tocall_negatif_free_route = 5400
send_reminder = NO
record_call = NO
monitor_formatfile = gsm
agi_force_currency =
currency_association = usd:dollars,mxn:pesos,eur:euros,all:credit
file_conf_enter_destination = prepaid-enter-dest
file_conf_enter_menulang = prepaid-menulang2
callback_bill_1stleg_ifcall_notconnected = YES

Can you check it's actually being sourced. Does the 'admin' user appear in the output of 'manager show users'?
If you're on Asterisk v1.4 you may also need ',config'.
No. Are you sure 'localhost' resolves to 127.0.0.1? It would be safe to put 127.0.0.1 in that field too.
Then perhaps the comments in the config file regarding it only being applicable to web-callback are true. Your earlier debug showed it would try to use the 'a2billing-callback' context:

Yes, admin appears in manager show users:

pbx*CLI> manager show users
pbx*CLI>
username
--------
admin
socjcare
-------------------
2 manager users configured.


I added ,config, as

[admin]
secret = amp111
deny=0.0.0.0/0.0.0.0
permit=127.0.0.1/255.255.255.0
read = system,call,log,verbose,command,agent,user,config
write = system,call,log,verbose,command,agent,user,config


still no luck.

I guess localhost is 127.0.0.1 as seen by pinging.

ping localhost
PING pbx.local (127.0.0.1) 56(84) bytes of data.
64 bytes from pbx.local (127.0.0.1): icmp_seq=1 ttl=64 time=0.134 ms


I was wondering if you know which module/function/routine generates this error message below so I can put some printable debugging info to help me identify where the problem lies.

************************************************
. WE ARE IN THE CHILD PROCESS
SQL =SELECT id, server_ip, manager_host, manager_username, manager_secret, lasttime_used FROM cc_server_manager WHERE id_group='1' ORDER BY lasttime_used ASC LIMIT 1 OFFSET 0

[2008-03-28 21:47:30]
[
[2008-03-28 21:47:30]]
::>>> IO::Socket::INET=GLOB(0x84f45e4)
[
[2008-03-28 21:48:00]]Response - Error - Message - Originate failed
[
[2008-03-28 21:48:00]]

## CALLBACK FAIL


Thanks a lot.

Well that's output from the callback daemon, which is probably installed in /etc/rc.d/ or somewhere nearby depending on your distro.
You might also 'set verbose 15' and watch the Asterisk console whilst the callback daemon tries to initiate a call; it may give a clue as to what's wrong. Make sure 'display_connects = yes' in manager.conf.

I set 'set verbose 15' and set 'display_connects = yes in manager.conf

I watched he Asterisk console while the callback daemon tries to initiate a call but did not display anything. related to the problem.

I did find the callback-daemon scripts and Asterisk Manager related libraries. so I will try to see if I can understand how they work.

Many thanks to your help, stavros and jroper. I hope I can find a solution to my problem and will post it here for others who might be encountering the same thing.

A quick way to test if AMI is listening is:

CentOS reminder
Some processes, apache being one, are prohibited from opening TCP to ports but their own; including any local connections to 127.0.0.1 ...
That must be explicitly allowed in SELinux policies.




Edit:

If you think about it, those policies are more strict than virtualization isolation would grant you!

I checked and yes, AMI is listening:

root@pbx:~ $telnet localhost 5038
Trying 127.0.0.1...
Connected to localhost.localdomain (127.0.0.1).
Escape character is '^]'.
Asterisk Call Manager/1.0

With regards to SELinux policy: I found this on Internet:

if /etc/selinux/config specifies that SELINUX=disabled, the boot process proceeds with a non-SELinux system.

On my system, SELINUX=disabled