My apologies for the delays. I was on annual leave and I am back now. There were some upgrades done for freepbx modules before the problem arises.
And here is the log msg I get:
Dec 14 23:16:29 user1 php: A2Billing AGI internal configuration:
Dec 14 23:16:29 user1 php: Array
Dec 14 23:16:29 user1 php: (
Dec 14 23:16:29 user1 php: [debug] => 3
Dec 14 23:16:29 user1 php: [asterisk_version] => 1_2
Dec 14 23:16:29 user1 php: [answer_call] => 1
Dec 14 23:16:29 user1 php: [play_audio] => 1
Dec 14 23:16:29 user1 php: [say_goodbye] =>
Dec 14 23:16:29 user1 php: [play_menulanguage] =>
Dec 14 23:16:29 user1 php: [force_language] =>
Dec 14 23:16:29 user1 php: [intro_prompt] =>
Dec 14 23:16:29 user1 php: [min_credit_2call] => 0
Dec 14 23:16:29 user1 php: [min_duration_2bill] => 0
Dec 14 23:16:29 user1 php: [notenoughcredit_cardnumber] => 1
Dec 14 23:16:29 user1 php: [notenoughcredit_assign_newcardnumber_cid] => 1
Dec 14 23:16:29 user1 php: [use_dnid] => 1
Dec 14 23:16:29 user1 php: [no_auth_dnid] => Array
Dec 14 23:16:29 user1 php: (
Dec 14 23:16:29 user1 php: [0] => 2400
Dec 14 23:16:29 user1 php: [1] => 2300
Dec 14 23:16:29 user1 php: )
Dec 14 23:16:29 user1 php:
Dec 14 23:16:29 user1 php: [number_try] => 3
Dec 14 23:16:29 user1 php: [force_callplan_id] =>
Dec 14 23:16:29 user1 php: [say_balance_after_auth] => 1
Dec 14 23:16:29 user1 php: [say_balance_after_call] =>
Dec 14 23:16:29 user1 php: [say_rateinitial] =>
Dec 14 23:16:29 user1 php: [say_timetocall] => 1
Dec 14 23:16:29 user1 php: [auto_setcallerid] => 1
Dec 14 23:16:29 user1 php: [force_callerid] =>
Dec 14 23:16:29 user1 php: [cid_sanitize] =>
Dec 14 23:16:29 user1 php: [cid_enable] => 1
Dec 14 23:16:29 user1 php: [cid_askpincode_ifnot_callerid] => 1
Dec 14 23:16:29 user1 php: [cid_auto_assign_card_to_cid] => 1
Dec 14 23:16:29 user1 php: [cid_auto_create_card] => 1
Dec 14 23:16:29 user1 php: [cid_auto_create_card_len] => 10
Dec 14 23:16:29 user1 php: [cid_auto_create_card_typepaid] => PREPAY
Dec 14 23:16:29 user1 php: [cid_auto_create_card_credit] => 0
Dec 14 23:16:29 user1 php: [cid_auto_create_card_credit_limit] => 1000
Dec 14 23:16:29 user1 php: [cid_auto_create_card_tariffgroup] => 6
Dec 14 23:16:29 user1 php: [callerid_authentication_over_cardnumber] =>
Dec 14 23:16:29 user1 php: [sip_iax_friends] => 1
Dec 14 23:16:29 user1 php: [sip_iax_pstn_direct_call_prefix] => 555
Dec 14 23:16:29 user1 php: [sip_iax_pstn_direct_call] => 1
Dec 14 23:16:29 user1 php: [ivr_voucher] =>
Dec 14 23:16:29 user1 php: [ivr_voucher_prefix] => 8
Dec 14 23:16:29 user1 php: [jump_voucher_if_min_credit] => 1
Dec 14 23:16:29 user1 php: [extracharge_did] => Array
Dec 14 23:16:29 user1 php: (
Dec 14 23:16:29 user1 php: [0] =>
Dec 14 23:16:29 user1 php: )
Dec 14 23:16:29 user1 php:
Dec 14 23:16:29 user1 php: [extracharge_fee] => Array
Dec 14 23:16:29 user1 php: (
Dec 14 23:16:29 user1 php: [0] =>
Dec 14 23:16:29 user1 php: )
Dec 14 23:16:29 user1 php:
Dec 14 23:16:29 user1 php: [dialcommand_param] => |60|HRrL(%timeout%:61000:30000)
Dec 14 23:16:29 user1 php: [dialcommand_param_sipiax_friend] => |60|HL(3600000:61000:30000)
Dec 14 23:16:29 user1 php: [switchdialcommand] =>
Dec 14 23:16:29 user1 php: [failover_recursive_limit] => 2
Dec 14 23:16:29 user1 php: [maxtime_tocall_negatif_free_route] => 6000
Dec 14 23:16:29 user1 php: [send_reminder] => 1
Dec 14 23:16:29 user1 php: [record_call] =>
Dec 14 23:16:29 user1 php: [monitor_formatfile] => gsm
Dec 14 23:16:29 user1 php: [agi_force_currency] =>
Dec 14 23:16:29 user1 php: [currency_association] => Array
Dec 14 23:16:29 user1 php: (
Dec 14 23:16:29 user1 php: [0] => usd:dollars
Dec 14 23:16:29 user1 php: [1] => mxn:pesos
Dec 14 23:16:29 user1 php: [2] => eur:euros
Dec 14 23:16:29 user1 php: [3] => all:credit
Dec 14 23:16:29 user1 php: )
Dec 14 23:16:29 user1 php:
Dec 14 23:16:29 user1 php: [file_conf_enter_destination] => prepaid-enter-dest
Dec 14 23:16:29 user1 php: [file_conf_enter_menulang] => prepaid-menulang2
Dec 14 23:16:29 user1 php: [callback_bill_1stleg_ifcall_notconnected] => 1
Dec 14 23:16:29 user1 php: [logger_enable] => 1
Dec 14 23:16:29 user1 php: [log_file] => /tmp/a2billing.log
Dec 14 23:16:29 user1 php: [currency_association_internal] => Array
Dec 14 23:16:29 user1 php: (
Dec 14 23:16:29 user1 php: [usd] => dollars
Dec 14 23:16:29 user1 php: [mxn] => pesos
Dec 14 23:16:29 user1 php: [eur] => euros
Dec 14 23:16:29 user1 php: [all] => credit
Dec 14 23:16:29 user1 php: )
Dec 14 23:16:29 user1 php:
Dec 14 23:16:29 user1 php: [ivr_voucher_prefixe] => 8
Dec 14 23:16:29 user1 php: )
Dec 14 23:16:29 user1 php:
Dec 14 23:16:29 user1 php: file:a2billing.php - line:75 - IDCONFIG : 1
Dec 14 23:16:29 user1 php: file:a2billing.php - line:76 - MODE : standard
Dec 14 23:16:29 user1 php: file:a2billing.php - line:88 - AGI Request:
Dec 14 23:16:29 user1 php: file:a2billing.php - line:89 - Array
Dec 14 23:16:29 user1 php: (
Dec 14 23:16:29 user1 php: [agi_request] => a2billing.php
Dec 14 23:16:29 user1 php: [agi_channel] => SIP/cust_nass-093bcbe8
Dec 14 23:16:29 user1 php: [agi_language] => us
Dec 14 23:16:29 user1 php: [agi_type] => SIP
Dec 14 23:16:29 user1 php: [agi_uniqueid] => 1229318188.135
Dec 14 23:16:29 user1 php: [agi_callerid] => 2140000000
Dec 14 23:16:29 user1 php: [agi_calleridname] => 2140000000
Dec 14 23:16:29 user1 php: [agi_callingpres] => 0
Dec 14 23:16:29 user1 php: [agi_callingani2] => 0
Dec 14 23:16:29 user1 php: [agi_callington] => 0
Dec 14 23:16:29 user1 php: [agi_callingtns] => 0
Dec 14 23:16:29 user1 php: [agi_dnid] => 2142454297
Dec 14 23:16:29 user1 php: [agi_rdnis] => unknown
Dec 14 23:16:29 user1 php: [agi_context] => custom-a2billing
Dec 14 23:16:29 user1 php: [agi_extension] => _X.
Dec 14 23:16:29 user1 php: [agi_priority] => 3
Dec 14 23:16:29 user1 php: [agi_enhanced] => 0.0
Dec 14 23:16:29 user1 php: [agi_accountcode] =>
Dec 14 23:16:29 user1 php: )
Dec 14 23:16:29 user1 php:
Dec 14 23:16:29 user1 php: file:Class.A2Billing.php - line:602 - get_agi_request_parameter = 2140000000 ; SIP/cust_nass-093bcbe8 ; 1229318188.135 ; ; _X.
Dec 14 23:16:29 user1 php: file:a2billing.php - line:138 - [ANSWER CALL]
Dec 14 23:16:29 user1 php: file:Class.A2Billing.php - line:1467 - [CID_ENABLE - CID_CONTROL - CID:2140000000]
Dec 14 23:16:29 user1 php: file:Class.A2Billing.php - line:1484 - 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='2140000000'
Dec 14 23:16:29 user1 php: RESULT : Array
Dec 14 23:16:29 user1 php: (
Dec 14 23:16:29 user1 php: [0] => Array
Dec 14 23:16:29 user1 php: (
Dec 14 23:16:29 user1 php: [0] => 2140000000
Dec 14 23:16:29 user1 php: [cid] => 2140000000
Dec 14 23:16:29 user1 php: [1] => 1
Dec 14 23:16:29 user1 php: [id_cc_card] => 1
Dec 14 23:16:29 user1 php: [2] => t
Dec 14 23:16:29 user1 php: [activated] => t
Dec 14 23:16:29 user1 php: [3] => 4.44000
Dec 14 23:16:29 user1 php: [credit] => 4.44000
Dec 14 23:16:29 user1 php: [4] => 1
Dec 14 23:16:29 user1 php: [tariff] => 1
Dec 14 23:16:29 user1 php: [5] => t
Dec 14 23:16:29 user1 php: [6] => 0
Dec 14 23:16:29 user1 php: [inuse] => 0
Dec 14 23:16:29 user1 php: [7] => 0
Dec 14 23:16:29 user1 php: [simultaccess] => 0
Dec 14 23:16:29 user1 php: [8] => 0
Dec 14 23:16:29 user1 php: [typepaid] => 0
Dec 14 23:16:29 user1 php: [9] =>
Dec 14 23:16:29 user1 php: [creditlimit] =>
Dec 14 23:16:29 user1 php: [10] => en
Dec 14 23:16:29 user1 php: [language] => en
Dec 14 23:16:29 user1 php: [11] => 1887297818
Dec 14 23:16:29 user1 php: [username] => 1887297818
Dec 14 23:16:29 user1 php: [12] => 0
Dec 14 23:16:29 user1 php: [removeinterprefix] => 0
Dec 14 23:16:29 user1 php: [13] => 12140000000
Dec 14 23:16:29 user1 php: [redial] => 12140000000
Dec 14 23:16:29 user1 php: [14] => 0
Dec 14 23:16:29 user1 php: [enableexpire] => 0
Dec 14 23:16:29 user1 php: [15] => 1535543732
Dec 14 23:16:29 user1 php: [UNIX_TIMESTAMP(expirationdate)] => 1535543732
Dec 14 23:16:29 user1 php: [16] => 0
Dec 14 23:16:29 user1 php: [expiredays] => 0
Dec 14 23:16:29 user1 php: [17] => 117
Dec 14 23:16:29 user1 php: [nbused] => 117
Dec 14 23:16:29 user1 php: [18] => 1220266293
Dec 14 23:16:29 user1 php: [UNIX_TIMESTAMP(firstusedate)] => 1220266293
Dec 14 23:16:29 user1 php: [19] => 1220011132
Dec 14 23:16:29 user1 php: [UNIX_TIMESTAMP(cc_card.creationdate)] => 1220011132
Dec 14 23:16:29 user1 php: [20] => USD
Dec 14 23:16:29 user1 php: [currency] => USD
Dec 14 23:16:29 user1 php: [21] => Ali
Dec 14 23:16:29 user1 php: [lastname] => Ali
Dec 14 23:16:29 user1 php: [22] => Nasser
Dec 14 23:16:29 user1 php: [firstname] => Nasser
Dec 14 23:16:29 user1 php: [23] =>
[email protected]
Dec 14 23:16:29 user1 php: [email] =>
[email protected]
Dec 14 23:16:29 user1 php: [24] => 111111
Dec 14 23:16:29 user1 php: [uipass] => 111111
Dec 14 23:16:29 user1 php: [25] => -1
Dec 14 23:16:29 user1 php: [id_campaign] => -1
Dec 14 23:16:29 user1 php: [26] => 1
Dec 14 23:16:29 user1 php: [id] => 1
Dec 14 23:16:29 user1 php: [27] => 729092322540261
Dec 14 23:16:29 user1 php: [useralias] => 729092322540261
Dec 14 23:16:29 user1 php: )
Dec 14 23:16:29 user1 php:
Dec 14 23:16:29 user1 php: )
Dec 14 23:16:29 user1 php:
Dec 14 23:16:29 user1 php: file:Class.A2Billing.php - line:1743 - [SET LANGUAGE() en]
Dec 14 23:16:29 user1 php: file:Class.A2Billing.php - line:635 - [CARD STATUS UPDATE : UPDATE cc_card SET inuse=inuse+1 WHERE username='1887297818']
Dec 14 23:16:29 user1 php: file:Class.A2Billing.php - line:1970 - [A2Billing] SAY BALANCE : 4.44000
Dec 14 23:16:29 user1 php:
Dec 14 23:16:29 user1 php: file:Class.A2Billing.php - line:1123 - [CURRENCY : USD]
Dec 14 23:16:34 user1 php: file:Class.A2Billing.php - line:1428 - [AUTO SetCallerID]
Dec 14 23:16:34 user1 php: file:Class.A2Billing.php - line:1434 - [REQUESTED SetCallerID : 2140000000]
Dec 14 23:16:34 user1 php: file:Class.A2Billing.php - line:1445 - [EXEC SetCallerID : 2140000000]
Dec 14 23:16:34 user1 php: file:a2billing.php - line:169 - [CHANNEL STATUS : 6 = Line is up]
Dec 14 23:16:34 user1 php: file:a2billing.php - line:170 - [CREDIT : 4.44000][CREDIT MIN_CREDIT_2CALL : 0]
Dec 14 23:16:34 user1 php: file:a2billing.php - line:275 - TRUNK - dnid : _X. (1)
Dec 14 23:16:34 user1 php: file:Class.A2Billing.php - line:657 - 1 && && 3&& 0
Dec 14 23:16:34 user1 php: file:Class.A2Billing.php - line:682 - DESTINATION ::> _X.
Dec 14 23:16:34 user1 php: file:Class.A2Billing.php - line:684 - RULES APPLY ON DESTINATION ::> _X.
Dec 14 23:16:34 user1 php: file:a2billing.php - line:169 - [CHANNEL STATUS : 6 = Line is up]
Dec 14 23:16:34 user1 php: file:a2billing.php - line:170 - [CREDIT : 4.44000][CREDIT MIN_CREDIT_2CALL : 0]
Dec 14 23:16:47 user1 php: file:a2billing.php - line:261 - RES sip_iax_pstndirect_call DTMF : 12144266262
Dec 14 23:16:47 user1 php: file:a2billing.php - line:275 - TRUNK - dnid : 12144266262 (1)
Dec 14 23:16:47 user1 php: file:Class.A2Billing.php - line:657 - 1 && && 11&& 1
Dec 14 23:16:57 user1 php: file:Class.A2Billing.php - line:664 - RES DTMF : 12144266262
Dec 14 23:16:57 user1 php: file:Class.A2Billing.php - line:682 - DESTINATION ::> 12144266262
Dec 14 23:16:57 user1 php: file:Class.A2Billing.php - line:684 - RULES APPLY ON DESTINATION ::> 12144266262
Dec 14 23:16:57 user1 php: file:Class.A2Billing.php - line:722 - OK - RESFINDRATE::> 1
Dec 14 23:16:57 user1 php: /var/lib/asterisk/agi-bin/libs_a2billing/Class.RateEngine.php[293]: Undefined variable: agi
Dec 14 23:16:57 user1 php: /var/lib/asterisk/agi-bin/libs_a2billing/Class.RateEngine.php[299]: Undefined variable: agi
Dec 14 23:16:57 user1 php: file:Class.A2Billing.php - line:744 - RES_ALL_CALCULTIMEOUT ::> 1
Dec 14 23:16:57 user1 php: file:Class.A2Billing.php - line:761 - TIMEOUT::> 6000 : minutes=100 - seconds=0
Dec 14 23:17:00 user1 php: file:Class.RateEngine.php - line:960 - app_callingcard: Dialing 'SIP/ic1/12144266262|60|HRrL(6000000:61000:30000)' with timeout of '6000'.
Dec 14 23:17:00 user1 php:
Dec 14 23:17:00 user1 php: file:Class.RateEngine.php - line:985 - app_callingcard: CIDGROUPID='-1' OUTBOUND CID SELECTED IS '0'.
Dec 14 23:17:21 user1 php: file:Class.RateEngine.php - line:1086 - -> dialstatus : ANSWER, answered time is 20
Dec 14 23:17:21 user1 php:
Dec 14 23:17:21 user1 php: file:Class.RateEngine.php - line:1090 - [USEDRATECARD=0]
Dec 14 23:17:21 user1 php: file:Class.RateEngine.php - line:777 - ft2c_package_offer : 1 ; ; 0
Dec 14 23:17:21 user1 php: /var/lib/asterisk/agi-bin/libs_a2billing/Class.RateEngine.php[607]: Undefined variable: agi
Dec 14 23:17:21 user1 php: /var/lib/asterisk/agi-bin/libs_a2billing/Class.RateEngine.php[642]: Undefined variable: agi
Dec 14 23:17:21 user1 php: /var/lib/asterisk/agi-bin/libs_a2billing/Class.RateEngine.php[719]: Undefined variable: agi
Dec 14 23:17:21 user1 php: file:Class.RateEngine.php - line:848 - [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 ('2229318188.135', 'SIP/cust_nass-093bcbe8', '1887297818', '', CURRENT_TIMESTAMP - INTERVAL 20 SECOND , '20', '12144266262', 'ANSWER', now(), '0', '-0', '', '', '12144266262', '1', '1', '13270', '2', '2140000000', '0', '0.05', '0.025', '0')]
Dec 14 23:17:21 user1 php: file:Class.RateEngine.php - line:851 - [CC_asterisk_stop 1.1: SQL: DONE : result=1]
Dec 14 23:17:21 user1 php: file:Class.RateEngine.php - line:869 - [CC_asterisk_stop 1.2: SQL: UPDATE cc_card SET credit= credit+0 , redial='12144266262' , lastuse=now(), nbused=nbused+1 WHERE username='1887297818']
Dec 14 23:17:21 user1 php: file:Class.RateEngine.php - line:874 - UPDATE cc_trunk SET secondusedreal = secondusedreal + 20 WHERE id_trunk='2'
Dec 14 23:17:21 user1 php: file:Class.RateEngine.php - line:878 - UPDATE cc_tariffplan SET secondusedreal = secondusedreal + 20 WHERE id='1'
Dec 14 23:17:21 user1 php: file:a2billing.php - line:309 - [a2billing account stop]
Dec 14 23:17:21 user1 php: file:a2billing.php - line:169 - [CHANNEL STATUS : 6 = Line is up]
Dec 14 23:17:21 user1 php: file:a2billing.php - line:170 - [CREDIT : 4.44][CREDIT MIN_CREDIT_2CALL : 0]
Dec 14 23:17:21 user1 php: file:a2billing.php - line:261 - RES sip_iax_pstndirect_call DTMF : -1
Dec 14 23:17:21 user1 php: file:a2billing.php - line:275 - TRUNK - dnid : -1 (1)
Dec 14 23:17:21 user1 php: file:Class.A2Billing.php - line:657 - 1 && && 2&& 2
Dec 14 23:17:21 user1 php: file:Class.A2Billing.php - line:664 - RES DTMF : -1
Dec 14 23:17:21 user1 php: file:Class.A2Billing.php - line:682 - DESTINATION ::> -1
Dec 14 23:17:21 user1 php: file:Class.A2Billing.php - line:684 - RULES APPLY ON DESTINATION ::> -1
Dec 14 23:17:21 user1 php: file:Class.A2Billing.php - line:635 - [CARD STATUS UPDATE : UPDATE cc_card SET inuse=inuse-1 WHERE username='1887297818']