provided by Star2Billing S.L.

I have FreePBX and a2billing installed on the same server.

Clients call into a DISA, Dial the number and are either auth by CallID or Card No.

My outgoing trunks are Simply setup as the trunk names with FreePBX

Calls are completed properly and show the proper destinations, there are rates setup but there is no call duration or Buy/Sell Pricing.

Am I missing something?

Hi

You need to pass the call into A2Billing via an A2Billing trunk from FreePBX.

This post tells you how to do that.

http://forum.asterisk2billing.org/viewt ... 7&start=30

Joe

Currently, I'm sending calls from FreePBX using a context within a DISA that I added to my extensions_custom.conf.

The information regarding who I want to call and callID etc is being sent over to a2billing and the call is being terminated based on my calling plans etc

The only problem I'm having now is that after I've made my call there's no duration or buy/sell rate information.

I can change the way that calls get to a2billing if that's the problem, I just don't understand how the incoming trunk is causing this problem based on the fact that everything is working properly in terms of terminating the call.

This is probably caused by one of three things:
1) you're using AGI() instead of DeadAGI() to invoke A2B from the dialplan
2) you're running Asterisk version 1.2.16 to v1.2.23
3) you don't have PHP's pcntl extension installed and working (check with 'php -m')
All these eventualities have been covered repeatedly here. Please search for more info.

Checked all of the above.

1. I'm using DeadAGI()
2. Running asterisk 1.4.21.2.
3. php-pcntl is installed and running.

Any ideas?

Turn up the debug verbosity levels and see if that yields any clues.

I'm not exactly what I should be looking for but I'm also somewhat reluctant to post the debug here with all my trunk names etc.

Use search & replace to mask any sensitive information.

Here's the log. Taking a closer look I see towards the end what appears to be playing invalid digits as if the call isn't being completed but I never hear that and my calls are being completed.

[Nov 17 23:00:52] VERBOSE[19194] logger.c: -- Executing [7054791234@from-trunk:1] Set("SIP/177xxxxxxx-181d09b0", "__FROM_DID=7054791234") in new stack
[Nov 17 23:00:52] VERBOSE[19194] logger.c: -- Executing [7054791234@from-trunk:2] Gosub("SIP/177xxxxxxx-181d09b0", "app-blacklist-check|s|1") in new stack
[Nov 17 23:00:52] VERBOSE[19194] logger.c: -- Executing [s@app-blacklist-check:1] LookupBlacklist("SIP/177xxxxxxx-181d09b0", "") in new stack
[Nov 17 23:00:52] VERBOSE[19194] logger.c: -- Executing [s@app-blacklist-check:2] GotoIf("SIP/177xxxxxxx-181d09b0", "0?blacklisted") in new stack
[Nov 17 23:00:52] VERBOSE[19194] logger.c: -- Executing [s@app-blacklist-check:3] Return("SIP/177xxxxxxx-181d09b0", "") in new stack
[Nov 17 23:00:52] VERBOSE[19194] logger.c: -- Executing [7054791234@from-trunk:3] GotoIf("SIP/177xxxxxxx-181d09b0", "1 ?cidok") in new stack
[Nov 17 23:00:52] VERBOSE[19194] logger.c: -- Goto (from-trunk,7054791234,5)
[Nov 17 23:00:52] VERBOSE[19194] logger.c: -- Executing [7054791234@from-trunk:5] NoOp("SIP/177xxxxxxx-181d09b0", "CallerID is "Sudbury,ON" <17052071234>") in new stack
[Nov 17 23:00:52] VERBOSE[19194] logger.c: -- Executing [7054791234@from-trunk:6] SetMusicOnHold("SIP/177xxxxxxx-181d09b0", "none") in new stack
[Nov 17 23:00:52] VERBOSE[19194] logger.c: -- Executing [7054791234@from-trunk:7] Set("SIP/177xxxxxxx-181d09b0", "__MOHCLASS=none") in new stack
[Nov 17 23:00:52] VERBOSE[19194] logger.c: -- Executing [7054791234@from-trunk:8] Set("SIP/177xxxxxxx-181d09b0", "FAX_RX=disabled") in new stack
[Nov 17 23:00:52] VERBOSE[19194] logger.c: -- Executing [7054791234@from-trunk:9] Set("SIP/177xxxxxxx-181d09b0", "__CALLINGPRES_SV=allowed_not_screened") in new stack
[Nov 17 23:00:52] VERBOSE[19194] logger.c: -- Executing [7054791234@from-trunk:10] SetCallerPres("SIP/177xxxxxxx-181d09b0", "allowed_not_screened") in new stack
[Nov 17 23:00:52] VERBOSE[19194] logger.c: -- Executing [7054791234@from-trunk:11] Goto("SIP/177xxxxxxx-181d09b0", "disa|1|1") in new stack
[Nov 17 23:00:52] VERBOSE[19194] logger.c: -- Goto (disa,1,1)
[Nov 17 23:00:52] VERBOSE[19194] logger.c: -- Executing [1@disa:1] Set("SIP/177xxxxxxx-181d09b0", "_DISA="disa|1|newcall"") in new stack
[Nov 17 23:00:52] VERBOSE[19194] logger.c: -- Executing [1@disa:2] Set("SIP/177xxxxxxx-181d09b0", "_DISACONTEXT=from-internal-accessnumbers") in new stack
[Nov 17 23:00:52] VERBOSE[19194] logger.c: -- Executing [1@disa:3] Set("SIP/177xxxxxxx-181d09b0", "_KEEPCID=TRUE") in new stack
[Nov 17 23:00:52] VERBOSE[19194] logger.c: -- Executing [1@disa:4] Set("SIP/177xxxxxxx-181d09b0", "TIMEOUT(digit)=5") in new stack
[Nov 17 23:00:52] VERBOSE[19194] logger.c: -- Digit timeout set to 5
[Nov 17 23:00:52] VERBOSE[19194] logger.c: -- Executing [1@disa:5] Set("SIP/177xxxxxxx-181d09b0", "TIMEOUT(response)=15") in new stack
[Nov 17 23:00:52] VERBOSE[19194] logger.c: -- Response timeout set to 15
[Nov 17 23:00:52] VERBOSE[19194] logger.c: -- Executing [1@disa:6] DISA("SIP/177xxxxxxx-181d09b0", "no-password|disa-dial") in new stack
[Nov 17 23:00:52] DEBUG[19194] app_disa.c: Digittimeout: 5000
[Nov 17 23:00:52] DEBUG[19194] app_disa.c: Responsetimeout: 15000
[Nov 17 23:00:52] DEBUG[19194] app_disa.c: Mailbox:
[Nov 17 23:00:52] DEBUG[19194] app_disa.c: Context: disa-dial
[Nov 17 23:00:52] DEBUG[19194] app_disa.c: DISA no-password login success
[Nov 17 23:01:07] DEBUG[19194] app_disa.c: DISA extension entry timeout on chan SIP/177xxxxxxx-181d09b0
[Nov 17 23:01:07] VERBOSE[19194] logger.c: -- Executing [17055251234@disa-dial:1] NoOp("SIP/177xxxxxxx-181d09b0", "called 17055251234 in from-internal-accessnumbers by disa|1|newcall") in new stack
[Nov 17 23:01:07] VERBOSE[19194] logger.c: -- Executing [17055251234@disa-dial:2] Dial("SIP/177xxxxxxx-181d09b0", "Local/17055251234@from-internal-accessnumbers|300|") in new stack
[Nov 17 23:01:07] VERBOSE[19194] logger.c: -- Called 17055251234@from-internal-accessnumbers
[Nov 17 23:01:07] VERBOSE[19202] logger.c: -- Executing [17055251234@from-internal-accessnumbers:1] DeadAGI("Local/17055251234@from-internal-accessnumbers-ce33,2", "a2billing.php") in new stack
[Nov 17 23:01:07] WARNING[19202] res_agi.c: Running DeadAGI on a live channel will cause problems, please use AGI
[Nov 17 23:01:07] VERBOSE[19202] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/a2billing.php
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: A2Billing AGI internal configuration:
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: Array
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: (
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [debug] => 1
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [asterisk_version] => 1_2
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [answer_call] => 1
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [play_audio] => 1
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [say_goodbye] =>
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [play_menulanguage] =>
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [force_language] =>
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [intro_prompt] =>
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [min_credit_2call] => 0
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [min_duration_2bill] => 0
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [notenoughcredit_cardnumber] => 1
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [notenoughcredit_assign_newcardnumber_cid] => 1
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [use_dnid] => 1
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [no_auth_dnid] => Array
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: (
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [0] => 2400
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [1] => 2300
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: )
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php:
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [number_try] => 3
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [force_callplan_id] =>
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [say_balance_after_auth] => 1
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [say_balance_after_call] =>
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [say_rateinitial] =>
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [say_timetocall] => 1
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [auto_setcallerid] => 1
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [force_callerid] =>
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [cid_sanitize] =>
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [cid_enable] => 1
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [cid_askpincode_ifnot_callerid] => 1
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [cid_auto_assign_card_to_cid] => 1
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [cid_auto_create_card] =>
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [cid_auto_create_card_len] => 10
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [cid_auto_create_card_typepaid] => POSTPAY
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [cid_auto_create_card_credit] => 0
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [cid_auto_create_card_credit_limit] => 1000
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [cid_auto_create_card_tariffgroup] => 6
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [callerid_authentication_over_cardnumber] =>
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [sip_iax_friends] =>
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [sip_iax_pstn_direct_call_prefix] => 555
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [sip_iax_pstn_direct_call] =>
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [ivr_voucher] =>
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [ivr_voucher_prefix] => 8
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [jump_voucher_if_min_credit] =>
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [extracharge_did] => Array
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: (
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [0] =>
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: )
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php:
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [extracharge_fee] => Array
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: (
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [0] =>
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: )
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php:
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [extracharge_buyfee] => Array
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: (
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [0] =>
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: )
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php:
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [international_prefixes] => Array
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: (
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [0] => 011
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [1] => 00
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [2] => 09
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: )
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php:
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [dialcommand_param] => |60|HRgrL(%timeout%:61000:30000)
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [dialcommand_param_sipiax_friend] => |60|HRgirL(3600000:61000:30000)
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [switchdialcommand] =>
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [failover_recursive_limit] => 2
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [maxtime_tocall_negatif_free_route] => 5400
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [send_reminder] =>
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [record_call] =>
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [monitor_formatfile] => gsm
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [agi_force_currency] =>
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [currency_association] => Array
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: (
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [0] => usd:dollars
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [1] => mxn:pesos
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [2] => eur:euros
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [3] => all:credit
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: )
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php:
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [file_conf_enter_destination] => prepaid-enter-dest
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [file_conf_enter_menulang] => prepaid-menulang2
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [callback_bill_1stleg_ifcall_notconnected] => 1
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [logger_enable] => 1
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [log_file] => /tmp/a2billing.log
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [currency_association_internal] => Array
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: (
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [usd] => dollars
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [mxn] => pesos
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [eur] => euros
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [all] => credit
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: )
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php:
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [ivr_voucher_prefixe] => 8
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: )
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php:
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: file:a2billing.php - line:78 - IDCONFIG : 1
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: file:a2billing.php - line:79 - MODE : standard
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: file:a2billing.php - line:91 - AGI Request:
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: file:a2billing.php - line:92 - Array
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: (
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [agi_request] => a2billing.php
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [agi_channel] => Local/17055251234@from-internal-accessnumbers-ce33,2
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [agi_language] => en
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [agi_type] => Local
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [agi_uniqueid] => 1226980867.85
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [agi_callerid] => 17052071234
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [agi_calleridname] => Sudbury,ON
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [agi_callingpres] => 0
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [agi_callingani2] => 0
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [agi_callington] => 0
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [agi_callingtns] => 0
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [agi_dnid] => unknown
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [agi_rdnis] => unknown
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [agi_context] => from-internal-accessnumbers
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [agi_extension] => 17055251234
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [agi_priority] => 1
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [agi_enhanced] => 0.0
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [agi_accountcode] =>
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: )
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php:
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: file:Class.A2Billing.php - line:621 - get_agi_request_parameter = 17052071234 ; Local/17055251234@from-internal-accessnumbers-ce33,2 ; 1226980867.85 ; ; 17055251234
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: file:a2billing.php - line:141 - [ANSWER CALL]
[Nov 17 23:01:07] VERBOSE[19194] logger.c: -- Local/17055251234@from-internal-accessnumbers-ce33,1 answered SIP/177xxxxxxx-181d09b0
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: file:Class.A2Billing.php - line:1439 - [CID_ENABLE - CID_CONTROL - CID:17052071234]
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: file:Class.A2Billing.php - line:1456 - QUERY = SELECT cc_callerid.cid, cc_callerid.id_cc_card, cc_callerid.activated, cc_card.credit, cc_card.tariff, cc_card.activated, cc_card.inuse, cc_card.simultaccess, cc_card.typepaid, cc_card.creditlimit, cc_card.language, cc_card.username, removeinterprefix, cc_card.redial, enableexpire, UNIX_TIMESTAMP(expirationdate), expiredays, nbused, UNIX_TIMESTAMP(firstusedate), UNIX_TIMESTAMP(cc_card.creationdate), cc_card.currency, cc_card.lastname, cc_card.firstname, cc_card.email, cc_card.uipass, cc_card.id_campaign, cc_card.id, useralias FROM cc_callerid LEFT JOIN cc_card ON cc_callerid.id_cc_card=cc_card.id LEFT JOIN cc_tariffgroup ON cc_card.tariff=cc_tariffgroup.id WHERE cc_callerid.cid='17052071234'
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: RESULT : Array
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: (
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [0] => Array
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: (
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [0] => 17052071234
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [cid] => 17052071234
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [1] => 1
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [id_cc_card] => 1
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [2] => t
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [activated] => t
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [3] => 0.00000
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [credit] => 0.00000
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [4] => 1
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [tariff] => 1
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [5] => t
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [6] => 0
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [inuse] => 0
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [7] => 0
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [simultaccess] => 0
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [8] => 1
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [typepaid] => 1
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [9] => 50
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [creditlimit] => 50
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [10] => en
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [language] => en
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [11] => 1234567890
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [username] => 1234567890
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [12] => 0
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [removeinterprefix] => 0
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [13] =>
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [redial] =>
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [14] => 0
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [enableexpire] => 0
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [15] => 1540007515
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [UNIX_TIMESTAMP(expirationdate)] => 1540007515
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [16] => 0
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [expiredays] => 0
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [17] => 0
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [nbused] => 0
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [18] => 0
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [UNIX_TIMESTAMP(firstusedate)] => 0
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [19] => 1224474715
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [UNIX_TIMESTAMP(cc_card.creationdate)] => 1224474715
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [20] => CAD
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [currency] => CAD
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [21] => Lastname
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [lastname] => Lastname
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [22] => Ed
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [firstname] => Ed
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [23] => [email protected]
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [email] => [email protected]
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [24] => 4603806286
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [uipass] => 4603806286
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [25] => -1
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [id_campaign] => -1
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [26] => 1
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [id] => 1
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [27] => 228828868251010
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: [useralias] => 228828868251010
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: )
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php:
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: )
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php:
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: file:Class.A2Billing.php - line:1714 - [SET LANGUAGE() en]
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: file:Class.A2Billing.php - line:654 - [CARD STATUS UPDATE : UPDATE cc_card SET inuse=inuse+1 WHERE username='1234567890']
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: file:Class.A2Billing.php - line:1969 - [A2Billing] SAY BALANCE : 50
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php:
[Nov 17 23:01:07] VERBOSE[19202] logger.c: a2billing.php: file:Class.A2Billing.php - line:1148 - [CURRENCY : CAD]
[Nov 17 23:01:07] VERBOSE[19202] logger.c: -- Playing 'prepaid-you-have' (escape_digits=#) (sample_offset 0)
[Nov 17 23:01:08] VERBOSE[19202] logger.c: -- <Local/17055251234@from-internal-accessnumbers-ce33,2> Playing 'digits/50' (language 'en')
[Nov 17 23:01:09] VERBOSE[19202] logger.c: -- Playing 'credit' (escape_digits=#) (sample_offset 0)
[Nov 17 23:01:10] VERBOSE[19202] logger.c: a2billing.php: file:Class.A2Billing.php - line:1400 - [AUTO SetCallerID]
[Nov 17 23:01:10] VERBOSE[19202] logger.c: a2billing.php: file:Class.A2Billing.php - line:1406 - [REQUESTED SetCallerID : 17052071234]
[Nov 17 23:01:10] VERBOSE[19202] logger.c: a2billing.php: file:Class.A2Billing.php - line:1417 - [EXEC SetCallerID : 17052071234]
[Nov 17 23:01:10] VERBOSE[19202] logger.c: a2billing.php: file:a2billing.php - line:172 - [CHANNEL STATUS : 6 = Line is up]
[Nov 17 23:01:10] VERBOSE[19202] logger.c: a2billing.php: file:a2billing.php - line:173 - [CREDIT : 50][CREDIT MIN_CREDIT_2CALL : 0]
[Nov 17 23:01:10] VERBOSE[19202] logger.c: a2billing.php: file:Class.A2Billing.php - line:676 - 1 && && 11&& 0
[Nov 17 23:01:10] VERBOSE[19202] logger.c: a2billing.php: file:Class.A2Billing.php - line:701 - DESTINATION ::> 17055251234
[Nov 17 23:01:10] VERBOSE[19202] logger.c: a2billing.php: file:Class.A2Billing.php - line:703 - RULES APPLY ON DESTINATION ::> 17055251234
[Nov 17 23:01:10] VERBOSE[19202] logger.c: a2billing.php: file:Class.A2Billing.php - line:741 - OK - RESFINDRATE::> 1
[Nov 17 23:01:10] VERBOSE[19202] logger.c: a2billing.php: file:Class.A2Billing.php - line:763 - RES_ALL_CALCULTIMEOUT ::> 1
[Nov 17 23:01:10] VERBOSE[19202] logger.c: a2billing.php: file:Class.A2Billing.php - line:780 - TIMEOUT::> 5400 : minutes=90 - seconds=0
[Nov 17 23:01:10] VERBOSE[19202] logger.c: -- Playing 'prepaid-you-have' (escape_digits=#) (sample_offset 0)
[Nov 17 23:01:11] VERBOSE[19202] logger.c: -- <Local/17055251234@from-internal-accessnumbers-ce33,2> Playing 'digits/90' (language 'en')
[Nov 17 23:01:12] VERBOSE[19202] logger.c: -- Playing 'prepaid-minutes' (escape_digits=#) (sample_offset 0)
[Nov 17 23:01:13] VERBOSE[19202] logger.c: a2billing.php: file:Class.RateEngine.php - line:1012 - app_callingcard: Dialing 'SIP/177xxxxxxx/17055251234|60|HRgrL(5400000:61000:30000)' with timeout of '5400'.
[Nov 17 23:01:13] VERBOSE[19202] logger.c: a2billing.php:
[Nov 17 23:01:13] VERBOSE[19202] logger.c: a2billing.php: file:Class.RateEngine.php - line:1037 - app_callingcard: CIDGROUPID='-1' OUTBOUND CID SELECTED IS '0'.
[Nov 17 23:01:13] VERBOSE[19202] logger.c: -- AGI Script Executing Application: (Dial) Options: (SIP/177xxxxxxx/17055251234|60|HRgrL(5400000:61000:30000))
[Nov 17 23:01:13] VERBOSE[19202] logger.c: -- Limit Data for this call:
[Nov 17 23:01:13] VERBOSE[19202] logger.c: > timelimit = 5400000
[Nov 17 23:01:13] VERBOSE[19202] logger.c: > play_warning = 61000
[Nov 17 23:01:13] VERBOSE[19202] logger.c: > play_to_caller = yes
[Nov 17 23:01:13] VERBOSE[19202] logger.c: > play_to_callee = no
[Nov 17 23:01:13] VERBOSE[19202] logger.c: > warning_freq = 30000
[Nov 17 23:01:13] VERBOSE[19202] logger.c: > start_sound = (null)
[Nov 17 23:01:13] VERBOSE[19202] logger.c: > warning_sound = timeleft
[Nov 17 23:01:13] VERBOSE[19202] logger.c: > end_sound = (null)
[Nov 17 23:01:13] VERBOSE[19202] logger.c: -- Called 177xxxxxxx/17055251234
[Nov 17 23:01:21] VERBOSE[19202] logger.c: -- SIP/177xxxxxxx-181ef8f0 is making progress passing it to Local/17055251234@from-internal-accessnumbers-ce33,2
[Nov 17 23:01:27] VERBOSE[19194] logger.c: == Spawn extension (disa-dial, 17055251234, 2) exited non-zero on 'SIP/177xxxxxxx-181d09b0'
[Nov 17 23:01:27] VERBOSE[19194] logger.c: -- Executing [h@disa-dial:1] NoOp("SIP/177xxxxxxx-181d09b0", "called h in from-internal-accessnumbers by disa|1|newcall") in new stack
[Nov 17 23:01:27] VERBOSE[19194] logger.c: -- Executing [h@disa-dial:2] Dial("SIP/177xxxxxxx-181d09b0", "Local/h@from-internal-accessnumbers|300|") in new stack
[Nov 17 23:01:27] NOTICE[19194] chan_local.c: No such extension/context h@from-internal-accessnumbers creating local channel
[Nov 17 23:01:27] WARNING[19194] app_dial.c: Unable to create channel of type 'Local' (cause 0 - Unknown)
[Nov 17 23:01:27] VERBOSE[19194] logger.c: == Everyone is busy/congested at this time (1:0/0/1)
[Nov 17 23:01:27] VERBOSE[19194] logger.c: -- Executing [h@disa-dial:3] Gosub("SIP/177xxxxxxx-181d09b0", "s-CHANUNAVAIL|1") in new stack
[Nov 17 23:01:27] VERBOSE[19194] logger.c: -- Executing [s-CHANUNAVAIL@disa-dial:1] NoOp("SIP/177xxxxxxx-181d09b0", "DISA Dial failed due to CHANUNAVAIL - returning to dial tone") in new stack
[Nov 17 23:01:27] VERBOSE[19194] logger.c: -- Executing [s-CHANUNAVAIL@disa-dial:2] PlayTones("SIP/177xxxxxxx-181d09b0", "congestion") in new stack
[Nov 17 23:01:27] VERBOSE[19194] logger.c: -- Executing [s-CHANUNAVAIL@disa-dial:3] Wait("SIP/177xxxxxxx-181d09b0", "3") in new stack
[Nov 17 23:01:27] VERBOSE[19194] logger.c: == Spawn extension (disa-dial, s-CHANUNAVAIL, 3) exited non-zero on 'SIP/177xxxxxxx-181d09b0'
[Nov 17 23:01:27] VERBOSE[19202] logger.c: a2billing.php: file:Class.RateEngine.php - line:1143 - [USEDRATECARD=0]
[Nov 17 23:01:27] VERBOSE[19202] logger.c: a2billing.php: file:Class.RateEngine.php - line:899 - [CC_asterisk_stop QUERY = INSERT INTO cc_call (uniqueid,sessionid,username,nasipaddress,starttime,sessiontime, calledstation, terminatecause, stoptime, calledrate, sessionbill, calledcountry, calledsub, destination, id_tariffgroup, id_tariffplan, id_ratecard, id_trunk, src, sipiax, buyrate, buycost, id_card_package_offer) VALUES ('1226980867.85', 'Local/17055251234@from-internal-accessnumbers-ce33,2', '1234567890', '', CURRENT_TIMESTAMP - INTERVAL 0 SECOND , '0', '17055251234', 'CANCEL', now(), '0', '-0', '', '', 'ontario canada', '1', '6', '13232', '2', '17052071234', '0', '0.015', '0', '0')]
[Nov 17 23:01:27] VERBOSE[19202] logger.c: a2billing.php: file:Class.RateEngine.php - line:902 - [CC_asterisk_stop 1.1: SQL: DONE : result=1]
[Nov 17 23:01:27] VERBOSE[19202] logger.c: a2billing.php: file:a2billing.php - line:312 - [a2billing account stop]
[Nov 17 23:01:27] VERBOSE[19202] logger.c: a2billing.php: file:a2billing.php - line:172 - [CHANNEL STATUS : 6 = Line is up]
[Nov 17 23:01:27] VERBOSE[19202] logger.c: a2billing.php: file:a2billing.php - line:173 - [CREDIT : 50][CREDIT MIN_CREDIT_2CALL : 0]
[Nov 17 23:01:27] VERBOSE[19202] logger.c: a2billing.php: file:Class.A2Billing.php - line:676 - 0 && && 11&& 1
[Nov 17 23:01:27] WARNING[19202] file.c: Failed to write frame
[Nov 17 23:01:27] VERBOSE[19202] logger.c: -- <Local/17055251234@from-internal-accessnumbers-ce33,2> Playing 'prepaid-enter-dest' (language 'en')
[Nov 17 23:01:27] VERBOSE[19202] logger.c: a2billing.php: file:Class.A2Billing.php - line:683 - RES DTMF : -1
[Nov 17 23:01:27] VERBOSE[19202] logger.c: a2billing.php: file:Class.A2Billing.php - line:701 - DESTINATION ::> -1
[Nov 17 23:01:27] VERBOSE[19202] logger.c: a2billing.php: file:Class.A2Billing.php - line:703 - RULES APPLY ON DESTINATION ::> -1
[Nov 17 23:01:27] VERBOSE[19202] logger.c: -- Playing 'prepaid-invalid-digits' (escape_digits=#) (sample_offset 0)
[Nov 17 23:01:27] WARNING[19202] file.c: Failed to write frame
[Nov 17 23:01:27] VERBOSE[19202] logger.c: a2billing.php: file:a2billing.php - line:172 - [CHANNEL STATUS : 6 = Line is up]
[Nov 17 23:01:27] VERBOSE[19202] logger.c: a2billing.php: file:a2billing.php - line:173 - [CREDIT : 50][CREDIT MIN_CREDIT_2CALL : 0]
[Nov 17 23:01:27] VERBOSE[19202] logger.c: a2billing.php: file:Class.A2Billing.php - line:676 - 0 && && 11&& 2
[Nov 17 23:01:27] WARNING[19202] file.c: Failed to write frame
[Nov 17 23:01:27] VERBOSE[19202] logger.c: -- <Local/17055251234@from-internal-accessnumbers-ce33,2> Playing 'prepaid-enter-dest' (language 'en')
[Nov 17 23:01:27] VERBOSE[19202] logger.c: a2billing.php: file:Class.A2Billing.php - line:683 - RES DTMF : -1
[Nov 17 23:01:27] VERBOSE[19202] logger.c: a2billing.php: file:Class.A2Billing.php - line:701 - DESTINATION ::> -1
[Nov 17 23:01:27] VERBOSE[19202] logger.c: a2billing.php: file:Class.A2Billing.php - line:703 - RULES APPLY ON DESTINATION ::> -1
[Nov 17 23:01:27] VERBOSE[19202] logger.c: -- Playing 'prepaid-invalid-digits' (escape_digits=#) (sample_offset 0)
[Nov 17 23:01:27] WARNING[19202] file.c: Failed to write frame
[Nov 17 23:01:27] VERBOSE[19202] logger.c: a2billing.php: file:Class.A2Billing.php - line:654 - [CARD STATUS UPDATE : UPDATE cc_card SET inuse=inuse-1 WHERE username='1234567890']
[Nov 17 23:01:27] VERBOSE[19202] logger.c: -- AGI Script a2billing.php completed, returning -1

Thanks again for the assistance,

Ed

This is probably a side effect of you using the Local technology to pass the call to A2B.

First, I'd like to thank you for the time you contribute to this forum and to those of us that are just starting out.

I've been talking with a few others who are doing the same in terms of passing there a2billing traffic from FreePBX via DISA and they calm to be doing it exactly as I am so I have to assume it works and that my installation isn't work at this point.

What's involved in reinstalling a2billing without a causing a huge mess and multiple cron jobs etc or is there and upgrade process that I can attempt in order to try to resolve the problem? Or perhaps even send a call directly to a2billing from my trunk to see if that's billed? I'm really kinda lost at this point but I really need to be able to access other areas of FreePBX via these numbers as well (such as voicemail)

Thanks again, I really appreciate your efforts.

Hi

Install PBX in a Flash, update-scripts, update fixes, type install-a2billing and read and follow the instructions on the script.

Joe

I've discovered that a2billing isn't updating the first card use either. First card use is all zeros as well but the card was clearing used in the CDR.

I suspect at this point the system is probably has an issue with time calculations which means i'm looking in a totally different direction than before. Oddly enough though it knows the expiration date for the card and billing rates.

I've installed again and have the same problem. Any ideas where i should be looking?

Hello
I have the exact same problem. I have been diging into it for several weeks. The only thing I could find is that once the call is completed, a2billing is not being notified for some reason and hence cannot calculate the call duration. I haven't find the way to do that though

This is anormal a2billing call termination

a2billing.php|$OUTNUM$: file:Class.RateEngine.php - line:1139 - -> dialstatus : ANSWER, answered time is 93
a2billing.php|$OUTNUM$:
a2billing.php|$OUTNUM$: file:Class.RateEngine.php - line:1143 - [USEDRATECARD=0]
a2billing.php|$OUTNUM$: file:Class.RateEngine.php - line:820 - ft2c_package_offer : 0 ; ; 0
a2billing.php|$OUTNUM$: file:Class.RateEngine.php - line:899 - [CC_asterisk_stop QUERY = INSERT INTO cc_call (uniqueid,sessionid,username,nasipaddress,starttime,sessiontime, calledstation, terminatecause, stoptime, calledrate, sessionbill, calledcountry, calledsub, destination, id_tariffgroup, id_tariffplan, id_ratecard, id_trunk, src, sipiax, buyrate, buycost, id_card_package_offer) VALUES ('1228246980.4100', 'SIP/4366-b7200468', '24289', '', CURRENT_TIMESTAMP - INTERVAL 93 SECOND , '93', '4137812', 'ANSWER', now(), '0', '+1.48', '', '', 'chihuahua', '51', '1', '1', '2', '4366', '0', '0', '0', '0')]
a2billing.php|$OUTNUM$: file:Class.RateEngine.php - line:902 - [CC_asterisk_stop 1.1: SQL: DONE : result=1]
a2billing.php|$OUTNUM$: file:Class.RateEngine.php - line:920 - [CC_asterisk_stop 1.2: SQL: UPDATE cc_card SET credit= credit-1.48 , redial='4137812' , lastuse=now(), nbused=nbused+1 WHERE username='24289']
a2billing.php|$OUTNUM$: file:Class.RateEngine.php - line:925 - UPDATE cc_trunk SET secondusedreal = secondusedreal + 93 WHERE id_trunk='2'
a2billing.php|$OUTNUM$: file:Class.RateEngine.php - line:929 - UPDATE cc_tariffplan SET secondusedreal = secondusedreal + 93 WHERE id='1'
a2billing.php|$OUTNUM$: file:a2billing.php - line:312 - [a2billing account stop]
a2billing.php|$OUTNUM$: file:a2billing.php - line:172 - [CHANNEL STATUS : 6 = Line is up]
a2billing.php|$OUTNUM$: file:a2billing.php - line:173 - [CREDIT : 1961.96][CREDIT MIN_CREDIT_2CALL : 0]
a2billing.php|$OUTNUM$: file:Class.A2Billing.php - line:676 - 0 && && 7&& 1
-- <SIP/4366-b7200468> Playing 'prepaid-enter-dest' (language 'en')
a2billing.php|$OUTNUM$: file:Class.A2Billing.php - line:683 - RES DTMF : -1
a2billing.php|$OUTNUM$: file:Class.A2Billing.php - line:701 - DESTINATION ::> -1
a2billing.php|$OUTNUM$: file:Class.A2Billing.php - line:703 - RULES APPLY ON DESTINATION ::> -1
-- Playing 'prepaid-invalid-digits' (escape_digits=#) (sample_offset 0)
a2billing.php|$OUTNUM$: file:a2billing.php - line:172 - [CHANNEL STATUS : 6 = Line is up]
a2billing.php|$OUTNUM$: file:a2billing.php - line:173 - [CREDIT : 1961.96][CREDIT MIN_CREDIT_2CALL : 0]
a2billing.php|$OUTNUM$: file:Class.A2Billing.php - line:676 - 0 && && 7&& 2
-- <SIP/4366-b7200468> Playing 'prepaid-enter-dest' (language 'en')
a2billing.php|$OUTNUM$: file:Class.A2Billing.php - line:683 - RES DTMF : -1
a2billing.php|$OUTNUM$: file:Class.A2Billing.php - line:701 - DESTINATION ::> -1
a2billing.php|$OUTNUM$: file:Class.A2Billing.php - line:703 - RULES APPLY ON DESTINATION ::> -1
-- Playing 'prepaid-invalid-digits' (escape_digits=#) (sample_offset 0)
a2billing.php|$OUTNUM$: file:Class.A2Billing.php - line:654 - [CARD STATUS UPDATE : UPDATE cc_card SET inuse=inuse-1 WHERE username='24289']
-- AGI Script a2billing.php completed, returning -1
-- Unicall/3 released

This is a Disa call termination. No a2billling.php is invoated anywhere.

Any ideas??

THANKS
CR

== Spawn extension (macro-dial, s, 7) exited non-zero on 'UniCall/4-1' in macro 'dial'
== Spawn extension (macro-dial, s, 7) exited non-zero on 'UniCall/4-1' in macro 'exten-vm'
== Spawn extension (macro-dial, s, 7) exited non-zero on 'UniCall/4-1'
-- Executing [h@macro-dial:1] Macro("UniCall/4-1", "hangupcall") in new stack
-- Executing [s@macro-hangupcall:1] ResetCDR("UniCall/4-1", "w") in new stack
-- Executing [s@macro-hangupcall:2] NoCDR("UniCall/4-1", "") in new stack
-- Executing [s@macro-hangupcall:3] GotoIf("UniCall/4-1", "1?skiprg") in new stack
-- Goto (macro-hangupcall,s,6)
-- Executing [s@macro-hangupcall:6] GotoIf("UniCall/4-1", "1?skipblkvm") in new stack
-- Goto (macro-hangupcall,s,9)
-- Executing [s@macro-hangupcall:9] GotoIf("UniCall/4-1", "1?theend") in new stack
-- Goto (macro-hangupcall,s,11)
-- Executing [s@macro-hangupcall:11] Hangup("UniCall/4-1", "") in new stack
== Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'UniCall/4-1' in macro 'hangupcall'
== Spawn extension (macro-hangupcall, s, 11) exited non-zero on 'UniCall/4-1'
-- Hungup 'UniCall/4-1'
== End MixMonitor Recording UniCall/4-1
-- Channel 0 got hangup
-- Hungup 'UniCall/1-1'
== Spawn extension (disa-dial, 1584204, 2) exited non-zero on 'UniCall/3-1'
-- Executing [h@disa-dial:1] NoOp("UniCall/3-1", "called h in custom-freepbx-a2billing by disa|1|newcall") in new stack
-- Executing [h@disa-dial:2] Dial("UniCall/3-1", "Local/h@custom-freepbx-a2billing|300|") in new stack
== Everyone is busy/congested at this time (1:0/0/1)
-- Executing [h@disa-dial:3] Gosub("UniCall/3-1", "s-CHANUNAVAIL|1") in new stack
-- Executing [s-CHANUNAVAIL@disa-dial:1] NoOp("UniCall/3-1", "DISA Dial failed due to CHANUNAVAIL - returning to dial tone") in new stack
-- Executing [s-CHANUNAVAIL@disa-dial:2] PlayTones("UniCall/3-1", "congestion") in new stack
-- Executing [s-CHANUNAVAIL@disa-dial:3] Wait("UniCall/3-1", "3") in new stack
== Spawn extension (disa-dial, s-CHANUNAVAIL, 3) exited non-zero on 'UniCall/3-1'
-- Hungup 'UniCall/3-1'
-- Unicall/4 released

I am having the same problem too. It happens on a vanilla config. I setup a very minimal config on tribox and on PBX in a flash and i have the exact same problem. The card is not being billed and from my debug it looks like the call duration is being recognized as 0.

Anyone have any ideas? This happens pretty much out of the box on tribox 2.6.1.13 and pbx in a flash. my custom trunk string is local/$OUTNUM$@a2billing .

I have been pulling my hair out for a week now trying to figure this one out.

Thanks,
Ryan