[asterisk-bugs] [JIRA] (ASTERISK-24620) AGI GET VARIABLE ANSWEREDTIME gives ZERO on callback bridge using app
Sid Mason (JIRA)
noreply at issues.asterisk.org
Mon Dec 15 11:16:01 CST 2014
[ https://issues.asterisk.org/jira/browse/ASTERISK-24620?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=224007#comment-224007 ]
Sid Mason edited comment on ASTERISK-24620 at 12/15/14 11:14 AM:
-----------------------------------------------------------------
I have attached the file 2014-12-16 - full_debug.txt
(Restricted to Users role)
was (Author: moontius):
Below is the log as per your requested.
callback01my*CLI> logger add channel myDebugLog notice,warning,error,debug,verbose,dtmf
== Manager 'myasterisk' logged on from 127.0.0.1
== Using SIP RTP TOS bits 8
== Using SIP RTP CoS mark 5
Audio is at 15718
Adding codec alaw to SDP
Adding non-codec 0x1 (telephone-event) to SDP
Reliably Transmitting (NAT) to 203.XXX.XXX.6:5060:
INVITE sip:6012XXXXXXX at 203.XXX.XXX.6 SIP/2.0
Via: SIP/2.0/UDP 103.XXX.XXX.21:5060;branch=z9hG4bK6758fca8;rport
Max-Forwards: 70
From: "11352694" <sip:anonymous at 103.XXX.XXX.21>;tag=as245c636c
To: <sip:6012XXXXXXX at 203.XXX.XXX.6>
Contact: <sip:anonymous at 103.XXX.XXX.21:5060>
Call-ID: 592f2b224bcf2e1f42b76ce87959f918 at 103.XXX.XXX.21:5060
CSeq: 102 INVITE
User-Agent: UltraTone 1.0
Date: Mon, 15 Dec 2014 16:50:07 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Remote-Party-ID: "11352694" <sip:anonymous at 103.XXX.XXX.21>;party=calling;privacy=off;screen=no
Content-Type: application/sdp
Content-Length: 241
v=0
o=root 1494153354 1494153354 IN IP4 103.XXX.XXX.21
s=Asterisk PBX 13.0.2
c=IN IP4 103.XXX.XXX.21
t=0 0
m=audio 15718 RTP/AVP 8 101
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=maxptime:150
a=sendrecv
---
-- Called sipprovider1/6012XXXXXXX
<--- SIP read from UDP:203.XXX.XXX.6:5060 --->
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 103.XXX.XXX.21:5060;branch=z9hG4bK6758fca8;rport=5060
From: "11352694" <sip:anonymous at 103.XXX.XXX.21>;tag=as245c636c
To: <sip:6012XXXXXXX at 203.XXX.XXX.6>;tag=gK0c844eb9
Call-ID: 592f2b224bcf2e1f42b76ce87959f918 at 103.XXX.XXX.21:5060
CSeq: 102 INVITE
Content-Length: 0
<------------->
--- (7 headers 0 lines) ---
<--- SIP read from UDP:203.XXX.XXX.6:5060 --->
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 103.XXX.XXX.21:5060;branch=z9hG4bK6758fca8;rport=5060
From: "11352694" <sip:anonymous at 103.XXX.XXX.21>;tag=as245c636c
To: <sip:6012XXXXXXX at 203.XXX.XXX.6>;tag=gK0c844eb9
Call-ID: 592f2b224bcf2e1f42b76ce87959f918 at 103.XXX.XXX.21:5060
CSeq: 102 INVITE
Contact: <sip:6012XXXXXXX at 203.XXX.XXX.6:5060>
Allow: INVITE,ACK,CANCEL,BYE,REGISTER,REFER,INFO,SUBSCRIBE,NOTIFY,PRACK,UPDATE,OPTIONS
Content-Length: 179
Content-Disposition: session; handling=required
Content-Type: application/sdp
v=0
o=Sonus_UAC 8503 28272 IN IP4 203.XXX.XXX.6
s=SIP Media Capabilities
c=IN IP4 203.121.94.5
t=0 0
m=audio 25878 RTP/AVP 8
a=rtpmap:8 PCMA/8000
a=sendrecv
a=maxptime:10
<------------->
--- (11 headers 9 lines) ---
sip_route_dump: route/path hop: <sip:6012XXXXXXX at 203.XXX.XXX.6:5060>
Found RTP audio format 8
Found audio description format PCMA for ID 8
Capabilities: us - (alaw), peer - audio=(alaw)/video=(nothing)/text=(nothing), combined - (alaw)
Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x0 (nothing), combined - 0x0 (nothing)
Peer audio RTP is at port 203.121.94.5:25878
-- SIP/sipprovider1-00000004 is ringing
-- SIP/sipprovider1-00000004 is making progress
<--- SIP read from UDP:203.XXX.XXX.6:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 103.XXX.XXX.21:5060;branch=z9hG4bK6758fca8;rport=5060
From: "11352694" <sip:anonymous at 103.XXX.XXX.21>;tag=as245c636c
To: <sip:6012XXXXXXX at 203.XXX.XXX.6>;tag=gK0c844eb9
Call-ID: 592f2b224bcf2e1f42b76ce87959f918 at 103.XXX.XXX.21:5060
CSeq: 102 INVITE
Accept: application/sdp, application/isup, application/dtmf, application/dtmf-relay, multipart/mixed
Contact: <sip:6012XXXXXXX at 203.XXX.XXX.6:5060>
Allow: INVITE,ACK,CANCEL,BYE,REGISTER,REFER,INFO,SUBSCRIBE,NOTIFY,PRACK,UPDATE,OPTIONS
Require: timer
Supported: timer
Session-Expires: 1800;refresher=uac
Content-Length: 179
Content-Disposition: session; handling=required
Content-Type: application/sdp
v=0
o=Sonus_UAC 8503 28272 IN IP4 203.XXX.XXX.6
s=SIP Media Capabilities
c=IN IP4 203.121.94.5
t=0 0
m=audio 25878 RTP/AVP 8
a=rtpmap:8 PCMA/8000
a=sendrecv
a=maxptime:10
<------------->
--- (15 headers 9 lines) ---
sip_route_dump: route/path hop: <sip:6012XXXXXXX at 203.XXX.XXX.6:5060>
Transmitting (NAT) to 203.XXX.XXX.6:5060:
ACK sip:6012XXXXXXX at 203.XXX.XXX.6:5060 SIP/2.0
Via: SIP/2.0/UDP 103.XXX.XXX.21:5060;branch=z9hG4bK4575fb97;rport
Max-Forwards: 70
From: "11352694" <sip:anonymous at 103.XXX.XXX.21>;tag=as245c636c
To: <sip:6012XXXXXXX at 203.XXX.XXX.6>;tag=gK0c844eb9
Contact: <sip:anonymous at 103.XXX.XXX.21:5060>
Call-ID: 592f2b224bcf2e1f42b76ce87959f918 at 103.XXX.XXX.21:5060
CSeq: 102 ACK
User-Agent: UltraTone 1.0
Content-Length: 0
---
-- SIP/sipprovider1-00000004 answered
-- Executing [60327XXXXXX at a2billing-callback:1] Answer("SIP/sipprovider1-00000004", "") in new stack
-- Executing [60327XXXXXX at a2billing-callback:2] Wait("SIP/sipprovider1-00000004", "1") in new stack
-- Executing [60327XXXXXX at a2billing-callback:3] AGI("SIP/sipprovider1-00000004", "a2billing.php,2,callback") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/a2billing.php
<SIP/sipprovider1-00000004>AGI Tx >> agi_request: a2billing.php
<SIP/sipprovider1-00000004>AGI Tx >> agi_channel: SIP/sipprovider1-00000004
<SIP/sipprovider1-00000004>AGI Tx >> agi_language: en
<SIP/sipprovider1-00000004>AGI Tx >> agi_type: SIP
<SIP/sipprovider1-00000004>AGI Tx >> agi_uniqueid: my01-1418662207.6
<SIP/sipprovider1-00000004>AGI Tx >> agi_version: 13.0.2
<SIP/sipprovider1-00000004>AGI Tx >> agi_callerid: anonymous
<SIP/sipprovider1-00000004>AGI Tx >> agi_calleridname: 11352694
<SIP/sipprovider1-00000004>AGI Tx >> agi_callingpres: 0
<SIP/sipprovider1-00000004>AGI Tx >> agi_callingani2: 0
<SIP/sipprovider1-00000004>AGI Tx >> agi_callington: 0
<SIP/sipprovider1-00000004>AGI Tx >> agi_callingtns: 0
<SIP/sipprovider1-00000004>AGI Tx >> agi_dnid: unknown
<SIP/sipprovider1-00000004>AGI Tx >> agi_rdnis: unknown
<SIP/sipprovider1-00000004>AGI Tx >> agi_context: a2billing-callback
<SIP/sipprovider1-00000004>AGI Tx >> agi_extension: 60327XXXXXX
<SIP/sipprovider1-00000004>AGI Tx >> agi_priority: 3
<SIP/sipprovider1-00000004>AGI Tx >> agi_enhanced: 0.0
<SIP/sipprovider1-00000004>AGI Tx >> agi_accountcode: 9578581306
<SIP/sipprovider1-00000004>AGI Tx >> agi_threadid: 140262125823744
<SIP/sipprovider1-00000004>AGI Tx >> agi_arg_1: 2
<SIP/sipprovider1-00000004>AGI Tx >> agi_arg_2: callback
<SIP/sipprovider1-00000004>AGI Tx >>
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE IDCONF
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1 (2)
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "A2Billing AGI internal configuration:" 1
a2billing.php,2,callback: A2Billing AGI internal configuration:
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "file:a2billing.php - line:85 - IDCONFIG : 2" 1
a2billing.php,2,callback: file:a2billing.php - line:85 - IDCONFIG : 2
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "file:a2billing.php - line:86 - MODE : callback" 1
a2billing.php,2,callback: file:a2billing.php - line:86 - MODE : callback
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "file:a2billing.php - line:98 - AGI Request:" 1
a2billing.php,2,callback: file:a2billing.php - line:98 - AGI Request:
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "file:Class.A2Billing.php - line:625 - get_agi_request_parameter = anonymous ; SIP/sipprovider1-00000004 ; my01-1418662207.6 ; 9578581306 ; 60327XXXXXX" 1
a2billing.php,2,callback: file:Class.A2Billing.php - line:625 - get_agi_request_parameter = anonymous ; SIP/sipprovider1-00000004 ; my01-1418662207.6 ; 9578581306 ; 60327XXXXXX
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "file:a2billing.php - line:716 - [CALLBACK]:[MODE : CALLBACK]" 1
a2billing.php,2,callback: file:a2billing.php - line:716 - [CALLBACK]:[MODE : CALLBACK]
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "file:a2billing.php - line:720 - [CALLBACK]:[ANSWER CALL]" 1
a2billing.php,2,callback: file:a2billing.php - line:720 - [CALLBACK]:[ANSWER CALL]
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << ANSWER
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=0
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE CALLED
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1 (6012XXXXXXX)
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE CALLING
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1 (60327XXXXXX)
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE MODE
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=0
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE TARIFF
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1 (1)
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE CBID
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1 (cijay-4755600534)
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE LEG
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1 (9578581306)
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "file:a2billing.php - line:771 - [CALLBACK]:[GET VARIABLE : CALLED=6012XXXXXXX | CALLING=60327XXXXXX | MODE= | TARIFF=1 | CBID=cijay-4755600534 | LEG=9578581306]" 1
a2billing.php,2,callback: file:a2billing.php - line:771 - [CALLBACK]:[GET VARIABLE : CALLED=6012XXXXXXX | CALLING=60327XXXXXX | MODE= | TARIFF=1 | CBID=cijay-4755600534 | LEG=9578581306]
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "file:Class.A2Billing.php - line:1816 - SELECT credit, tariff, activated, inuse, simultaccess, typepaid, creditlimit, language, removeinterprefix, 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, `cc_card`.`phone` FROM cc_card LEFT JOIN cc_tariffgroup ON tariff=cc_tariffgroup.id WHERE username='9578581306'" 1
a2billing.php,2,callback: file:Class.A2Billing.php - line:1816 - SELECT credit, tariff, activated, inuse, simultaccess, typepaid, creditlimit, language, removeinterprefix, 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, `cc_card`.`phone` FROM cc_card LEFT JOIN cc_tariffgroup ON tariff=cc_tariffgroup.id WHERE username='9578581306'
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "file:Class.A2Billing.php - line:1820 - print_r($this->gpn_info)=>Array" 1
a2billing.php,2,callback: file:Class.A2Billing.php - line:1820 - print_r($this->gpn_info)=>Array
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "(" 1
a2billing.php,2,callback: (
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [0] => 20487.52901" 1
a2billing.php,2,callback: [0] => 20487.52901
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [credit] => 20487.52901" 1
a2billing.php,2,callback: [credit] => 20487.52901
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [1] => 1" 1
a2billing.php,2,callback: [1] => 1
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [tariff] => 1" 1
a2billing.php,2,callback: [tariff] => 1
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [2] => t" 1
a2billing.php,2,callback: [2] => t
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [activated] => t" 1
a2billing.php,2,callback: [activated] => t
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [3] => 31" 1
a2billing.php,2,callback: [3] => 31
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [inuse] => 31" 1
a2billing.php,2,callback: [inuse] => 31
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [4] => 1" 1
a2billing.php,2,callback: [4] => 1
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [simultaccess] => 1" 1
a2billing.php,2,callback: [simultaccess] => 1
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [5] => 0" 1
a2billing.php,2,callback: [5] => 0
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [typepaid] => 0" 1
a2billing.php,2,callback: [typepaid] => 0
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [6] => " 1
a2billing.php,2,callback: [6] =>
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [creditlimit] => " 1
a2billing.php,2,callback: [creditlimit] =>
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [7] => en" 1
a2billing.php,2,callback: [7] => en
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [language] => en" 1
a2billing.php,2,callback: [language] => en
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [8] => 1" 1
a2billing.php,2,callback: [8] => 1
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [removeinterprefix] => 1" 1
a2billing.php,2,callback: [removeinterprefix] => 1
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [9] => 60327XXXXXX" 1
a2billing.php,2,callback: [9] => 60327XXXXXX
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [redial] => 60327XXXXXX" 1
a2billing.php,2,callback: [redial] => 60327XXXXXX
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [10] => 0" 1
a2billing.php,2,callback: [10] => 0
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [enableexpire] => 0" 1
a2billing.php,2,callback: [enableexpire] => 0
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [11] => 1535850509" 1
a2billing.php,2,callback: [11] => 1535850509
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [UNIX_TIMESTAMP(expirationdate)] => 1535850509" 1
a2billing.php,2,callback: [UNIX_TIMESTAMP(expirationdate)] => 1535850509
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [12] => 0" 1
a2billing.php,2,callback: [12] => 0
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [expiredays] => 0" 1
a2billing.php,2,callback: [expiredays] => 0
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [13] => 14113" 1
a2billing.php,2,callback: [13] => 14113
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [nbused] => 14113" 1
a2billing.php,2,callback: [nbused] => 14113
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [14] => 1220318618" 1
a2billing.php,2,callback: [14] => 1220318618
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [UNIX_TIMESTAMP(firstusedate)] => 1220318618" 1
a2billing.php,2,callback: [UNIX_TIMESTAMP(firstusedate)] => 1220318618
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [15] => 1220317742" 1
a2billing.php,2,callback: [15] => 1220317742
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [UNIX_TIMESTAMP(cc_card.creationdate)] => 1220317742" 1
a2billing.php,2,callback: [UNIX_TIMESTAMP(cc_card.creationdate)] => 1220317742
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [16] => MYR" 1
a2billing.php,2,callback: [16] => MYR
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [currency] => MYR" 1
a2billing.php,2,callback: [currency] => MYR
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [17] => Najafi" 1
a2billing.php,2,callback: [17] => Najafi
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [lastname] => Najafi" 1
a2billing.php,2,callback: [lastname] => Najafi
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [18] => Mohammad" 1
a2billing.php,2,callback: [18] => Mohammad
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [firstname] => Mohammad" 1
a2billing.php,2,callback: [firstname] => Mohammad
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [19] => mohd at ultratone.com.my" 1
a2billing.php,2,callback: [19] => mohd at ultratone.com.my
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [email] => mohd at ultratone.com.my" 1
a2billing.php,2,callback: [email] => mohd at ultratone.com.my
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [20] => Ads22342234" 1
a2billing.php,2,callback: [20] => Ads22342234
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [uipass] => Ads22342234" 1
a2billing.php,2,callback: [uipass] => Ads22342234
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [21] => -1" 1
a2billing.php,2,callback: [21] => -1
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [id_campaign] => -1" 1
a2billing.php,2,callback: [id_campaign] => -1
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [22] => 1" 1
a2billing.php,2,callback: [22] => 1
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [id] => 1" 1
a2billing.php,2,callback: [id] => 1
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [23] => 9001" 1
a2billing.php,2,callback: [23] => 9001
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [useralias] => 9001" 1
a2billing.php,2,callback: [useralias] => 9001
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [24] => 006012XXXXXXX" 1
a2billing.php,2,callback: [24] => 006012XXXXXXX
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [phone] => 006012XXXXXXX" 1
a2billing.php,2,callback: [phone] => 006012XXXXXXX
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE ")" 1
a2billing.php,2,callback: )
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "" 1
a2billing.php,2,callback:
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << SET VARIABLE CHANNEL(language) "en"
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "file:Class.A2Billing.php - line:1891 - [SET CHANNEL(language) en]" 1
a2billing.php,2,callback: file:Class.A2Billing.php - line:1891 - [SET CHANNEL(language) en]
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "file:Class.A2Billing.php - line:658 - [CARD STATUS UPDATE : UPDATE cc_card SET inuse=inuse+1 WHERE username='9578581306']" 1
a2billing.php,2,callback: file:Class.A2Billing.php - line:658 - [CARD STATUS UPDATE : UPDATE cc_card SET inuse=inuse+1 WHERE username='9578581306']
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "file:Class.A2Billing.php - line:1533 - [AUTO SetCallerID]" 1
a2billing.php,2,callback: file:Class.A2Billing.php - line:1533 - [AUTO SetCallerID]
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << CHANNEL STATUS SIP/sipprovider1-00000004
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=6
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "file:a2billing.php - line:800 - [CALLBACK]:[CHANNEL STATUS : 6 = Line is up][status_channel=6]:[ORIG_CREDIT : 20487.52901 - CUR_CREDIT - : 20487.52901 - CREDIT MIN_CREDIT_2CALL : 0]" 1
a2billing.php,2,callback: file:a2billing.php - line:800 - [CALLBACK]:[CHANNEL STATUS : 6 = Line is up][status_channel=6]:[ORIG_CREDIT : 20487.52901 - CUR_CREDIT - : 20487.52901 - CREDIT MIN_CREDIT_2CALL : 0]
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "file:Class.A2Billing.php - line:680 - 1 && && 11&& 0" 1
a2billing.php,2,callback: file:Class.A2Billing.php - line:680 - 1 && && 11&& 0
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "file:Class.A2Billing.php - line:705 - DESTINATION ::> 60327XXXXXX" 1
a2billing.php,2,callback: file:Class.A2Billing.php - line:705 - DESTINATION ::> 60327XXXXXX
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "file:Class.A2Billing.php - line:707 - RULES APPLY ON DESTINATION ::> 60327XXXXXX" 1
a2billing.php,2,callback: file:Class.A2Billing.php - line:707 - RULES APPLY ON DESTINATION ::> 60327XXXXXX
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "file:Class.RateEngine.php - line:68 - force the call plan : 1" 1
a2billing.php,2,callback: file:Class.RateEngine.php - line:68 - force the call plan : 1
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "file:Class.A2Billing.php - line:745 - OK - RESFINDRATE::> 1" 1
a2billing.php,2,callback: file:Class.A2Billing.php - line:745 - OK - RESFINDRATE::> 1
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE RATEINITIAL
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1 (0.08)
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE INITBLOCK
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1 (30)
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE BILLINGBLOCK
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1 (30)
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE CONNECTCHARGE
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1 (0)
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE DISCONNECTCHARGE
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1 (0)
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE STEPCHARGEA
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1 (0)
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE TIMECHARGEA
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1 (0)
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE STEPCHARGEB
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1 (0)
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE TIMECHARGEB
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1 (0)
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE STEPCHARGEC
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1 (0)
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE TIMECHARGEC
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1 (0)
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE ANSWEREDTIME
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=0
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "file:Class.A2Billing.php - line:767 - RES_ALL_CALCULTIMEOUT ::> 1" 1
a2billing.php,2,callback: file:Class.A2Billing.php - line:767 - RES_ALL_CALCULTIMEOUT ::> 1
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "file:Class.A2Billing.php - line:784 - TIMEOUT::> 10243740 : minutes=170729 - seconds=0" 1
a2billing.php,2,callback: file:Class.A2Billing.php - line:784 - TIMEOUT::> 10243740 : minutes=170729 - seconds=0
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << SAY NUMBER 170729 ""
-- <SIP/sipprovider1-00000004> Playing 'digits/1.alaw' (language 'en')
> 0x7f91340043f0 -- Probation passed - setting RTP source address to 203.121.94.5:25878
-- <SIP/sipprovider1-00000004> Playing 'digits/hundred.alaw' (language 'en')
-- <SIP/sipprovider1-00000004> Playing 'digits/70.alaw' (language 'en')
Really destroying SIP dialog '19cf73604e48dbb654b39925013d52fe at 100.XX.XX.131:5060' Method: OPTIONS
-- <SIP/sipprovider1-00000004> Playing 'digits/thousand.alaw' (language 'en')
-- <SIP/sipprovider1-00000004> Playing 'digits/7.alaw' (language 'en')
-- <SIP/sipprovider1-00000004> Playing 'digits/hundred.alaw' (language 'en')
-- <SIP/sipprovider1-00000004> Playing 'digits/20.alaw' (language 'en')
Really destroying SIP dialog '1ade668539b308796e1134e23f5bae55 at 10.1.2.132:5060' Method: OPTIONS
-- <SIP/sipprovider1-00000004> Playing 'digits/9.alaw' (language 'en')
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=0
<SIP/sipprovider1-00000004>AGI Rx << STREAM FILE prepaid-minutes "#" 0
-- Playing 'prepaid-minutes' (escape_digits=#) (sample_offset 0)
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=0 endpos=6240
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "file:Class.RateEngine.php - line:1067 - [Exctracting CallerID (6012XXXXXXX) FROM (006012XXXXXXX)]" 1
a2billing.php,2,callback: file:Class.RateEngine.php - line:1067 - [Exctracting CallerID (6012XXXXXXX) FROM (006012XXXXXXX)]
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << SET CALLERID +6012XXXXXXX
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "file:Class.RateEngine.php - line:1071 - [EXEC SetCallerID : "+6012XXXXXXX"]" 1
a2billing.php,2,callback: file:Class.RateEngine.php - line:1071 - [EXEC SetCallerID : +6012XXXXXXX]
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "file:Class.RateEngine.php - line:1074 - app_callingcard: CIDGROUPID='-1' OUTBOUND CID SELECTED IS '0'." 1
a2billing.php,2,callback: file:Class.RateEngine.php - line:1074 - app_callingcard: CIDGROUPID='-1' OUTBOUND CID SELECTED IS '0'.
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "file:Class.RateEngine.php - line:1076 - app_callingcard: Dialing 'SIP/sipprovider2/27XXXXXX,90,HgiL(2147483647:60000:30000)' with timeout of '10243740'." 1
a2billing.php,2,callback: file:Class.RateEngine.php - line:1076 - app_callingcard: Dialing 'SIP/sipprovider2/27XXXXXX,90,HgiL(2147483647:60000:30000)' with timeout of '10243740'.
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "" 1
a2billing.php,2,callback:
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << EXEC DIAL SIP/sipprovider2/27XXXXXX,90,HgiL(2147483647:60000:30000)
-- AGI Script Executing Application: (DIAL) Options: (SIP/sipprovider2/27XXXXXX,90,HgiL(2147483647:60000:30000))
> Limit Data for this call:
> timelimit = 2147483647 ms (2147483.647 s)
> play_warning = 60000 ms (60.000 s)
> play_to_caller = yes
> play_to_callee = no
> warning_freq = 30000 ms (30.000 s)
> start_sound =
> warning_sound = timeleft
> end_sound =
== Using SIP RTP TOS bits 8
== Using SIP RTP CoS mark 5
Audio is at 19576
Adding codec alaw to SDP
Adding codec ulaw to SDP
Adding non-codec 0x1 (telephone-event) to SDP
Reliably Transmitting (no NAT) to 100.XX.XX.131:5060:
INVITE sip:27XXXXXX at 100.XX.XX.131 SIP/2.0
Via: SIP/2.0/UDP 10.1.3.21:5060;branch=z9hG4bK0701ae27
Max-Forwards: 70
From: <sip:+6012XXXXXXX at 10.1.3.21>;tag=as35ec6453
To: <sip:27XXXXXX at 100.XX.XX.131>
Contact: <sip:+6012XXXXXXX at 10.1.3.21:5060>
Call-ID: 09c2567136bc62a2735f2eff2aff2677 at 10.1.3.21:5060
CSeq: 102 INVITE
User-Agent: UltraTone 1.0
Date: Mon, 15 Dec 2014 16:50:28 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Remote-Party-ID: "+6012XXXXXXX" <sip:+6012XXXXXXX at 10.1.3.21>;party=calling;privacy=off;screen=no
Content-Type: application/sdp
Content-Length: 255
v=0
o=root 447930742 447930742 IN IP4 10.1.3.21
s=Asterisk PBX 13.0.2
c=IN IP4 10.1.3.21
t=0 0
m=audio 19576 RTP/AVP 8 0 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=maxptime:150
a=sendrecv
---
-- Called SIP/sipprovider2/27XXXXXX
<--- SIP read from UDP:100.XX.XX.131:5060 --->
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 10.1.3.21:5060;branch=z9hG4bK0701ae27;received=10.1.3.21
From: <sip:+6012XXXXXXX at 10.1.3.21>;tag=as35ec6453
To: <sip:27XXXXXX at 100.XX.XX.131>
Call-ID: 09c2567136bc62a2735f2eff2aff2677 at 10.1.3.21:5060
CSeq: 102 INVITE
Server: HSX9000
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: <sip:27XXXXXX at 100.XX.XX.131:5060>
Content-Length: 0
<------------->
--- (12 headers 0 lines) ---
<--- SIP read from UDP:100.XX.XX.131:5060 --->
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 10.1.3.21:5060;branch=z9hG4bK0701ae27;received=10.1.3.21
From: <sip:+6012XXXXXXX at 10.1.3.21>;tag=as35ec6453
To: <sip:27XXXXXX at 100.XX.XX.131>;tag=as0842b502
Call-ID: 09c2567136bc62a2735f2eff2aff2677 at 10.1.3.21:5060
CSeq: 102 INVITE
Server: HSX9000
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: <sip:27XXXXXX at 100.XX.XX.131:5060>
Content-Length: 0
<------------->
--- (12 headers 0 lines) ---
sip_route_dump: route/path hop: <sip:27XXXXXX at 100.XX.XX.131:5060>
-- SIP/sipprovider2-00000005 is ringing
<--- SIP read from UDP:100.XX.XX.131:5060 --->
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 10.1.3.21:5060;branch=z9hG4bK0701ae27;received=10.1.3.21
From: <sip:+6012XXXXXXX at 10.1.3.21>;tag=as35ec6453
To: <sip:27XXXXXX at 100.XX.XX.131>;tag=as0842b502
Call-ID: 09c2567136bc62a2735f2eff2aff2677 at 10.1.3.21:5060
CSeq: 102 INVITE
Server: HSX9000
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: <sip:27XXXXXX at 100.XX.XX.131:5060>
Content-Type: application/sdp
Require: timer
Content-Length: 256
v=0
o=root 1681362662 1681362662 IN IP4 100.XX.XX.131
s=Asterisk PBX 11.11.0
c=IN IP4 100.XX.XX.131
t=0 0
m=audio 10210 RTP/AVP 0 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv
<------------->
--- (14 headers 12 lines) ---
sip_route_dump: route/path hop: <sip:27XXXXXX at 100.XX.XX.131:5060>
Found RTP audio format 0
Found RTP audio format 8
Found RTP audio format 101
Found audio description format PCMU for ID 0
Found audio description format PCMA for ID 8
Found audio description format telephone-event for ID 101
Capabilities: us - (ulaw|alaw), peer - audio=(ulaw|alaw)/video=(nothing)/text=(nothing), combined - (ulaw|alaw)
Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|)
Peer audio RTP is at port 100.XX.XX.131:10210
-- SIP/sipprovider2-00000005 is making progress passing it to SIP/sipprovider1-00000004
> 0x7f913800ca80 -- Probation passed - setting RTP source address to 100.XX.XX.131:10210
<--- SIP read from UDP:100.XX.XX.131:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.1.3.21:5060;branch=z9hG4bK0701ae27;received=10.1.3.21
From: <sip:+6012XXXXXXX at 10.1.3.21>;tag=as35ec6453
To: <sip:27XXXXXX at 100.XX.XX.131>;tag=as0842b502
Call-ID: 09c2567136bc62a2735f2eff2aff2677 at 10.1.3.21:5060
CSeq: 102 INVITE
Server: HSX9000
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: <sip:27XXXXXX at 100.XX.XX.131:5060>
Content-Type: application/sdp
Require: timer
Content-Length: 256
v=0
o=root 1681362662 1681362662 IN IP4 100.XX.XX.131
s=Asterisk PBX 11.11.0
c=IN IP4 100.XX.XX.131
t=0 0
m=audio 10210 RTP/AVP 0 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv
<------------->
--- (14 headers 12 lines) ---
sip_route_dump: route/path hop: <sip:27XXXXXX at 100.XX.XX.131:5060>
set_destination: Parsing <sip:27XXXXXX at 100.XX.XX.131:5060> for address/port to send to
set_destination: set destination to 100.XX.XX.131:5060
Transmitting (no NAT) to 100.XX.XX.131:5060:
ACK sip:27XXXXXX at 100.XX.XX.131:5060 SIP/2.0
Via: SIP/2.0/UDP 10.1.3.21:5060;branch=z9hG4bK4dab3731
Max-Forwards: 70
From: <sip:+6012XXXXXXX at 10.1.3.21>;tag=as35ec6453
To: <sip:27XXXXXX at 100.XX.XX.131>;tag=as0842b502
Contact: <sip:+6012XXXXXXX at 10.1.3.21:5060>
Call-ID: 09c2567136bc62a2735f2eff2aff2677 at 10.1.3.21:5060
CSeq: 102 ACK
User-Agent: UltraTone 1.0
Content-Length: 0
---
-- SIP/sipprovider2-00000005 answered SIP/sipprovider1-00000004
-- Channel SIP/sipprovider1-00000004 joined 'simple_bridge' basic-bridge <9f58dcdd-e25c-4e7b-87e3-6b6696cff75d>
-- Channel SIP/sipprovider2-00000005 joined 'simple_bridge' basic-bridge <9f58dcdd-e25c-4e7b-87e3-6b6696cff75d>
callback01my*CLI> core show channels verbose
Channel Context Extension Prio State Application Data CallerID Duration Accountcode PeerAccount BridgeID
SIP/sipprovider2-000000 from-unifi 1 Up AppDial (Outgoing Line) 60327XXXXXX 00:00:11 9578581306 9578581306 9f58dcdd-e25c-4e7b-8
SIP/sipprovider1-00000004 a2billing-callback 60327XXXXXX 3 Up Dial SIP/sipprovider2/27XXXXXX,90 +6012XXXXXXX 00:00:32 9578581306 9578581306 9f58dcdd-e25c-4e7b-8
2 active channels
1 active call
3 calls processed
<--- SIP read from UDP:203.XXX.XXX.6:5060 --->
BYE sip:anonymous at 103.XXX.XXX.21:5060 SIP/2.0
Via: SIP/2.0/UDP 203.XXX.XXX.6:5060;branch=z9hG4bK0cB190505fb01513dd8
From: <sip:6012XXXXXXX at 203.XXX.XXX.6>;tag=gK0c844eb9
To: "11352694" <sip:anonymous at 103.XXX.XXX.21>;tag=as245c636c
Call-ID: 592f2b224bcf2e1f42b76ce87959f918 at 103.XXX.XXX.21:5060
CSeq: 12363 BYE
Max-Forwards: 70
Content-Length: 0
<------------->
--- (8 headers 0 lines) ---
Sending to 203.XXX.XXX.6:5060 (NAT)
Scheduling destruction of SIP dialog '592f2b224bcf2e1f42b76ce87959f918 at 103.XXX.XXX.21:5060' in 32000 ms (Method: BYE)
<--- Transmitting (NAT) to 203.XXX.XXX.6:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 203.XXX.XXX.6:5060;branch=z9hG4bK0cB190505fb01513dd8;received=203.XXX.XXX.6;rport=5060
From: <sip:6012XXXXXXX at 203.XXX.XXX.6>;tag=gK0c844eb9
To: "11352694" <sip:anonymous at 103.XXX.XXX.21>;tag=as245c636c
Call-ID: 592f2b224bcf2e1f42b76ce87959f918 at 103.XXX.XXX.21:5060
CSeq: 12363 BYE
Server: UltraTone 1.0
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0
<------------>
-- Channel SIP/sipprovider1-00000004 left 'simple_bridge' basic-bridge <9f58dcdd-e25c-4e7b-87e3-6b6696cff75d>
-- Channel SIP/sipprovider2-00000005 left 'simple_bridge' basic-bridge <9f58dcdd-e25c-4e7b-87e3-6b6696cff75d>
Scheduling destruction of SIP dialog '09c2567136bc62a2735f2eff2aff2677 at 10.1.3.21:5060' in 32000 ms (Method: INVITE)
-- ast_channel_answertime(chan): 1970-01-01 07:30:00
-- ast_channel_get_up_time(872448296): 0
set_destination: Parsing <sip:27XXXXXX at 100.XX.XX.131:5060> for address/port to send to
set_destination: set destination to 100.XX.XX.131:5060
Reliably Transmitting (no NAT) to 100.XX.XX.131:5060:
BYE sip:27XXXXXX at 100.XX.XX.131:5060 SIP/2.0
Via: SIP/2.0/UDP 10.1.3.21:5060;branch=z9hG4bK3d0d0623
Max-Forwards: 70
From: <sip:+6012XXXXXXX at 10.1.3.21>;tag=as35ec6453
To: <sip:27XXXXXX at 100.XX.XX.131>;tag=as0842b502
Call-ID: 09c2567136bc62a2735f2eff2aff2677 at 10.1.3.21:5060
CSeq: 103 BYE
User-Agent: UltraTone 1.0
X-Asterisk-HangupCause: Normal Clearing
X-Asterisk-HangupCauseCode: 16
Content-Length: 0
---
-- Successfully setting ANSWEREDTIME : 0
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=-1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "file:Class.RateEngine.php - line:1079 - DIAL result : Array" 1
a2billing.php,2,callback: file:Class.RateEngine.php - line:1079 - DIAL result : Array
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "(" 1
a2billing.php,2,callback: (
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [code] => 200" 1
a2billing.php,2,callback: [code] => 200
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [result] => -1" 1
a2billing.php,2,callback: [result] => -1
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [data] => " 1
a2billing.php,2,callback: [data] =>
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE ")" 1
a2billing.php,2,callback: )
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "" 1
a2billing.php,2,callback:
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE ANSWEREDTIME
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1 (0)
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE DIALEDTIME
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1 (34)
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE CDR(billsec)
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1 (21)
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE CDR(start)
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1 (2014-12-16 00:50:19)
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE CDR(end)
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1 (2014-12-16 00:50:41)
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE CDR(channel)
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1 (SIP/sipprovider1-00000004)
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE CDR(answer)
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1 (2014-12-16 00:50:19)
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE AVAILSTATUS
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=0
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE DIALEDPEERNAME
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1 (SIP/sipprovider2-00000005)
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "file:Class.RateEngine.php - line:1094 - GET ANSWEREDTIME : Array" 1
a2billing.php,2,callback: file:Class.RateEngine.php - line:1094 - GET ANSWEREDTIME : Array
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "(" 1
a2billing.php,2,callback: (
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [code] => 200" 1
a2billing.php,2,callback: [code] => 200
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [result] => 1" 1
a2billing.php,2,callback: [result] => 1
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [data] => 0" 1
<--- SIP read from UDP:100.XX.XX.131:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 10.1.3.21:5060;branch=z9hG4bK3d0d0623;received=10.1.3.21
From: <sip:+6012XXXXXXX at 10.1.3.21>;tag=as35ec6453
To: <sip:27XXXXXX at 100.XX.XX.131>;tag=as0842b502
Call-ID: 09c2567136bc62a2735f2eff2aff2677 at 10.1.3.21:5060
CSeq: 103 BYE
Server: HSX9000
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0
<------------->
--- (10 headers 0 lines) ---
Really destroying SIP dialog '09c2567136bc62a2735f2eff2aff2677 at 10.1.3.21:5060' Method: INVITE
a2billing.php,2,callback: [data] => 0
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE ")" 1
a2billing.php,2,callback: )
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " BILL SECOND : Array" 1
a2billing.php,2,callback: BILL SECOND : Array
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "(" 1
a2billing.php,2,callback: (
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [code] => 200" 1
a2billing.php,2,callback: [code] => 200
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [result] => 1" 1
a2billing.php,2,callback: [result] => 1
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [data] => 21" 1
a2billing.php,2,callback: [data] => 21
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE ")" 1
a2billing.php,2,callback: )
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "STARTTIME: Array" 1
a2billing.php,2,callback: STARTTIME: Array
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "(" 1
a2billing.php,2,callback: (
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [code] => 200" 1
a2billing.php,2,callback: [code] => 200
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [result] => 1" 1
a2billing.php,2,callback: [result] => 1
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [data] => 2014-12-16 00:50:19" 1
a2billing.php,2,callback: [data] => 2014-12-16 00:50:19
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE ")" 1
a2billing.php,2,callback: )
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " ENDTIME: Array" 1
a2billing.php,2,callback: ENDTIME: Array
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "(" 1
a2billing.php,2,callback: (
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [code] => 200" 1
a2billing.php,2,callback: [code] => 200
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [result] => 1" 1
a2billing.php,2,callback: [result] => 1
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [data] => 2014-12-16 00:50:41" 1
a2billing.php,2,callback: [data] => 2014-12-16 00:50:41
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE ")" 1
a2billing.php,2,callback: )
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " CHANNEL : Array" 1
a2billing.php,2,callback: CHANNEL : Array
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "(" 1
a2billing.php,2,callback: (
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [code] => 200" 1
a2billing.php,2,callback: [code] => 200
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [result] => 1" 1
a2billing.php,2,callback: [result] => 1
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [data] => SIP/sipprovider1-00000004" 1
a2billing.php,2,callback: [data] => SIP/sipprovider1-00000004
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE ")" 1
a2billing.php,2,callback: )
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "" 1
a2billing.php,2,callback:
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE DIALSTATUS
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1 (ANSWER)
<SIP/sipprovider1-00000004>AGI Rx << STREAM FILE prepaid-you-have "#" 0
<SIP/sipprovider1-00000004>AGI Tx >> 511 Command Not Permitted on a dead channel
<SIP/sipprovider1-00000004>AGI Rx << SAY NUMBER 20487 ""
<SIP/sipprovider1-00000004>AGI Tx >> 511 Command Not Permitted on a dead channel
<SIP/sipprovider1-00000004>AGI Rx << STREAM FILE credit "#" 0
<SIP/sipprovider1-00000004>AGI Tx >> 511 Command Not Permitted on a dead channel
<SIP/sipprovider1-00000004>AGI Rx << STREAM FILE vm-and "#" 0
<SIP/sipprovider1-00000004>AGI Tx >> 511 Command Not Permitted on a dead channel
<SIP/sipprovider1-00000004>AGI Rx << SAY NUMBER 51 ""
<SIP/sipprovider1-00000004>AGI Tx >> 511 Command Not Permitted on a dead channel
<SIP/sipprovider1-00000004>AGI Rx << STREAM FILE prepaid-cents "#" 0
<SIP/sipprovider1-00000004>AGI Tx >> 511 Command Not Permitted on a dead channel
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "file:Class.A2Billing.php - line:658 - [CARD STATUS UPDATE : UPDATE cc_card SET inuse=inuse-1 WHERE username='9578581306']" 1
a2billing.php,2,callback: file:Class.A2Billing.php - line:658 - [CARD STATUS UPDATE : UPDATE cc_card SET inuse=inuse-1 WHERE username='9578581306']
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "file:a2billing.php - line:1017 - [CALLBACK 1ST LEG]:[INFO FOR THE 1ST LEG - callback_username=9578581306" 1
a2billing.php,2,callback: file:a2billing.php - line:1017 - [CALLBACK 1ST LEG]:[INFO FOR THE 1ST LEG - callback_username=9578581306
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "file:Class.A2Billing.php - line:1816 - SELECT credit, tariff, activated, inuse, simultaccess, typepaid, creditlimit, language, removeinterprefix, 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, `cc_card`.`phone` FROM cc_card LEFT JOIN cc_tariffgroup ON tariff=cc_tariffgroup.id WHERE username='9578581306'" 1
a2billing.php,2,callback: file:Class.A2Billing.php - line:1816 - SELECT credit, tariff, activated, inuse, simultaccess, typepaid, creditlimit, language, removeinterprefix, 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, `cc_card`.`phone` FROM cc_card LEFT JOIN cc_tariffgroup ON tariff=cc_tariffgroup.id WHERE username='9578581306'
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "file:Class.A2Billing.php - line:1820 - print_r($this->gpn_info)=>Array" 1
a2billing.php,2,callback: file:Class.A2Billing.php - line:1820 - print_r($this->gpn_info)=>Array
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "(" 1
a2billing.php,2,callback: (
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [0] => 20487.50901" 1
a2billing.php,2,callback: [0] => 20487.50901
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [credit] => 20487.50901" 1
a2billing.php,2,callback: [credit] => 20487.50901
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [1] => 1" 1
a2billing.php,2,callback: [1] => 1
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [tariff] => 1" 1
a2billing.php,2,callback: [tariff] => 1
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [2] => t" 1
a2billing.php,2,callback: [2] => t
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [activated] => t" 1
a2billing.php,2,callback: [activated] => t
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [3] => 31" 1
a2billing.php,2,callback: [3] => 31
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [inuse] => 31" 1
a2billing.php,2,callback: [inuse] => 31
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [4] => 1" 1
a2billing.php,2,callback: [4] => 1
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [simultaccess] => 1" 1
a2billing.php,2,callback: [simultaccess] => 1
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [5] => 0" 1
a2billing.php,2,callback: [5] => 0
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [typepaid] => 0" 1
a2billing.php,2,callback: [typepaid] => 0
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [6] => " 1
a2billing.php,2,callback: [6] =>
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [creditlimit] => " 1
a2billing.php,2,callback: [creditlimit] =>
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [7] => en" 1
a2billing.php,2,callback: [7] => en
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [language] => en" 1
a2billing.php,2,callback: [language] => en
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [8] => 1" 1
a2billing.php,2,callback: [8] => 1
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [removeinterprefix] => 1" 1
a2billing.php,2,callback: [removeinterprefix] => 1
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [9] => 60327XXXXXX" 1
a2billing.php,2,callback: [9] => 60327XXXXXX
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [redial] => 60327XXXXXX" 1
a2billing.php,2,callback: [redial] => 60327XXXXXX
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [10] => 0" 1
a2billing.php,2,callback: [10] => 0
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [enableexpire] => 0" 1
a2billing.php,2,callback: [enableexpire] => 0
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [11] => 1535850509" 1
a2billing.php,2,callback: [11] => 1535850509
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [UNIX_TIMESTAMP(expirationdate)] => 1535850509" 1
a2billing.php,2,callback: [UNIX_TIMESTAMP(expirationdate)] => 1535850509
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [12] => 0" 1
a2billing.php,2,callback: [12] => 0
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [expiredays] => 0" 1
a2billing.php,2,callback: [expiredays] => 0
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [13] => 14114" 1
a2billing.php,2,callback: [13] => 14114
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [nbused] => 14114" 1
a2billing.php,2,callback: [nbused] => 14114
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [14] => 1220318618" 1
a2billing.php,2,callback: [14] => 1220318618
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [UNIX_TIMESTAMP(firstusedate)] => 1220318618" 1
a2billing.php,2,callback: [UNIX_TIMESTAMP(firstusedate)] => 1220318618
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [15] => 1220317742" 1
a2billing.php,2,callback: [15] => 1220317742
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [UNIX_TIMESTAMP(cc_card.creationdate)] => 1220317742" 1
a2billing.php,2,callback: [UNIX_TIMESTAMP(cc_card.creationdate)] => 1220317742
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [16] => MYR" 1
a2billing.php,2,callback: [16] => MYR
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [currency] => MYR" 1
a2billing.php,2,callback: [currency] => MYR
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [17] => Najafi" 1
a2billing.php,2,callback: [17] => Najafi
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [lastname] => Najafi" 1
a2billing.php,2,callback: [lastname] => Najafi
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [18] => Mohammad" 1
a2billing.php,2,callback: [18] => Mohammad
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [firstname] => Mohammad" 1
a2billing.php,2,callback: [firstname] => Mohammad
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [19] => mohd at ultratone.com.my" 1
a2billing.php,2,callback: [19] => mohd at ultratone.com.my
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [email] => mohd at ultratone.com.my" 1
a2billing.php,2,callback: [email] => mohd at ultratone.com.my
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [20] => Ads22342234" 1
a2billing.php,2,callback: [20] => Ads22342234
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [uipass] => Ads22342234" 1
a2billing.php,2,callback: [uipass] => Ads22342234
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [21] => -1" 1
a2billing.php,2,callback: [21] => -1
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [id_campaign] => -1" 1
a2billing.php,2,callback: [id_campaign] => -1
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [22] => 1" 1
a2billing.php,2,callback: [22] => 1
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [id] => 1" 1
a2billing.php,2,callback: [id] => 1
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [23] => 9001" 1
a2billing.php,2,callback: [23] => 9001
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [useralias] => 9001" 1
a2billing.php,2,callback: [useralias] => 9001
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [24] => 006012XXXXXXX" 1
a2billing.php,2,callback: [24] => 006012XXXXXXX
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE " [phone] => 006012XXXXXXX" 1
a2billing.php,2,callback: [phone] => 006012XXXXXXX
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE ")" 1
a2billing.php,2,callback: )
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "" 1
a2billing.php,2,callback:
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << SET VARIABLE CHANNEL(language) "en"
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "file:Class.A2Billing.php - line:1891 - [SET CHANNEL(language) en]" 1
a2billing.php,2,callback: file:Class.A2Billing.php - line:1891 - [SET CHANNEL(language) en]
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "file:Class.A2Billing.php - line:658 - [CARD STATUS UPDATE : UPDATE cc_card SET inuse=inuse+1 WHERE username='9578581306']" 1
a2billing.php,2,callback: file:Class.A2Billing.php - line:658 - [CARD STATUS UPDATE : UPDATE cc_card SET inuse=inuse+1 WHERE username='9578581306']
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "file:a2billing.php - line:1023 - [CALLBACK 1ST LEG]:[MAKE BILLING FOR THE 1ST LEG - TARIFF:1;CALLED=6012XXXXXXX]" 1
a2billing.php,2,callback: file:a2billing.php - line:1023 - [CALLBACK 1ST LEG]:[MAKE BILLING FOR THE 1ST LEG - TARIFF:1;CALLED=6012XXXXXXX]
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "file:Class.RateEngine.php - line:68 - force the call plan : 1" 1
a2billing.php,2,callback: file:Class.RateEngine.php - line:68 - force the call plan : 1
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE RATEINITIAL
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1 (0.08)
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE INITBLOCK
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1 (30)
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE BILLINGBLOCK
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1 (30)
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE CONNECTCHARGE
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1 (0)
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE DISCONNECTCHARGE
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1 (0)
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE STEPCHARGEA
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1 (0)
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE TIMECHARGEA
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1 (0)
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE STEPCHARGEB
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1 (0)
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE TIMECHARGEB
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1 (0)
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE STEPCHARGEC
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1 (0)
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE TIMECHARGEC
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1 (0)
<SIP/sipprovider1-00000004>AGI Rx << GET VARIABLE ANSWEREDTIME
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1 (0)
<SIP/sipprovider1-00000004>AGI Rx << VERBOSE "file:a2billing.php - line:1038 - [CALLBACK]:[RateEngine -> answeredtime=20]" 1
a2billing.php,2,callback: file:a2billing.php - line:1038 - [CALLBACK]:[RateEngine -> answeredtime=20]
<SIP/sipprovider1-00000004>AGI Tx >> 200 result=1
-- <SIP/sipprovider1-00000004>AGI Script a2billing.php completed, returning 4
== Spawn extension (a2billing-callback, 60327XXXXXX, 3) exited non-zero on 'SIP/sipprovider1-00000004'
== Manager 'myasterisk' logged off from 127.0.0.1
callback01my*CLI>
Disconnected from Asterisk server
Asterisk cleanly ending (0).
Executing last minute cleanups
(Restricted to Users role)
> AGI GET VARIABLE ANSWEREDTIME gives ZERO on callback bridge using app
> ---------------------------------------------------------------------
>
> Key: ASTERISK-24620
> URL: https://issues.asterisk.org/jira/browse/ASTERISK-24620
> Project: Asterisk
> Issue Type: Bug
> Security Level: None
> Components: Applications/app_dial
> Affects Versions: 13.0.2
> Environment: CentOS 6.5 64bit
> Reporter: Sid Mason
> Assignee: Rusty Newton
> Attachments: 2014-12-15 - full_debug.txt, 2014-12-16 - full_debug.txt, debug.txt
>
>
> Below is the log of Asterisk Console during Callback request by 6012XXXXXXX
> a) when call SIP/sipprovider/6012XXXXXXX is connected
> b) Asterisk is dialing another sip channel SIP/ims_peer/60327XXXXXX
> c) call duriation on SIP/ims_peer/60327XXXXXX is 00:00:12
> d) GET VARIABLE ANSWEREDTIME report Wrong : 0
> e) GET VARIABLE DIALEDTIME report Wrong : 28
> [Edit by Rusty - Remove inline debug - logs don't go in the description field. In the future, please use attachments for logs.]
--
This message was sent by Atlassian JIRA
(v6.2#6252)
More information about the asterisk-bugs
mailing list