[asterisk-bugs] [JIRA] (ASTERISK-28199) No audio when using iLBC codec
Ariel Grin (JIRA)
noreply at issues.asterisk.org
Fri Dec 7 12:19:02 CST 2018
[ https://issues.asterisk.org/jira/browse/ASTERISK-28199?page=com.atlassian.jira.plugin.system.issuetabpanels:comment-tabpanel&focusedCommentId=245670#comment-245670 ]
Ariel Grin commented on ASTERISK-28199:
---------------------------------------
Here I'm attaching a SIP debug for peer 106, which is configured to use iLBC, calling extension 101 which is on DAHDI card.
101 can hear 106, but 106 can't hear 101
Connected to Asterisk 13.23.1 currently running on freepbx (pid = 2546)
freepbx*CLI> sip set debug peer 106
SIP Debugging Enabled for IP: 192.168.10.106
<--- SIP read from UDP:192.168.10.106:5060 --->
INVITE sip:101 at 192.168.10.4 SIP/2.0
Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK1839075671;rport
From: <sip:106 at 192.168.10.4>;tag=1674326932
To: <sip:101 at 192.168.10.4>
Call-ID: 2000295813-5060-336 at BJC.BGI.BA.BAG
CSeq: 1080 INVITE
Contact: <sip:106 at 192.168.10.106:5060>
X-Grandstream-PBX: true
Max-Forwards: 70
User-Agent: Grandstream GXP2110 1.0.8.6
Privacy: none
P-Preferred-Identity: <sip:106 at 192.168.10.4>
Supported: replaces, path, timer
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
Content-Type: application/sdp
Accept: application/sdp, application/dtmf-relay
Content-Length: 405
v=0
o=106 8000 8000 IN IP4 192.168.10.106
s=SIP Call
c=IN IP4 192.168.10.106
t=0 0
m=audio 5004 RTP/AVP 97 8 4 18 9 0 2 101
a=sendrecv
a=rtpmap:97 iLBC/8000
a=fmtp:97 mode=30
a=ptime:60
a=rtpmap:8 PCMA/8000
a=rtpmap:4 G723/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
<------------->
--- (17 headers 19 lines) ---
Sending to 192.168.10.106:5060 (no NAT)
Sending to 192.168.10.106:5060 (no NAT)
Using INVITE request as basis request - 2000295813-5060-336 at BJC.BGI.BA.BAG
Found peer '106' for '106' from 192.168.10.106:5060
<--- Reliably Transmitting (no NAT) to 192.168.10.106:5060 --->
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK1839075671;received=192.168.10.106;rport=5060
From: <sip:106 at 192.168.10.4>;tag=1674326932
To: <sip:101 at 192.168.10.4>;tag=as606c6233
Call-ID: 2000295813-5060-336 at BJC.BGI.BA.BAG
CSeq: 1080 INVITE
Server: FPBX-13.0.195.19(13.23.1)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="4af1d250"
Content-Length: 0
<------------>
Scheduling destruction of SIP dialog '2000295813-5060-336 at BJC.BGI.BA.BAG' in 6400 ms (Method: INVITE)
<--- SIP read from UDP:192.168.10.106:5060 --->
ACK sip:101 at 192.168.10.4 SIP/2.0
Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK1839075671;rport
From: <sip:106 at 192.168.10.4>;tag=1674326932
To: <sip:101 at 192.168.10.4>;tag=as606c6233
Call-ID: 2000295813-5060-336 at BJC.BGI.BA.BAG
CSeq: 1080 ACK
Content-Length: 0
<------------->
--- (7 headers 0 lines) ---
<--- SIP read from UDP:192.168.10.106:5060 --->
INVITE sip:101 at 192.168.10.4 SIP/2.0
Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK1868569856;rport
From: <sip:106 at 192.168.10.4>;tag=1674326932
To: <sip:101 at 192.168.10.4>
Call-ID: 2000295813-5060-336 at BJC.BGI.BA.BAG
CSeq: 1081 INVITE
Contact: <sip:106 at 192.168.10.106:5060>
Authorization: Digest username="106", realm="asterisk", nonce="4af1d250", uri="sip:101 at 192.168.10.4", response="516bd7777e0f8d2eef24d09b1d1a74b9", algorithm=MD5
X-Grandstream-PBX: true
Max-Forwards: 70
User-Agent: Grandstream GXP2110 1.0.8.6
Privacy: none
P-Preferred-Identity: <sip:106 at 192.168.10.4>
Supported: replaces, path, timer
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
Content-Type: application/sdp
Accept: application/sdp, application/dtmf-relay
Content-Length: 405
v=0
o=106 8000 8000 IN IP4 192.168.10.106
s=SIP Call
c=IN IP4 192.168.10.106
t=0 0
m=audio 5004 RTP/AVP 97 8 4 18 9 0 2 101
a=sendrecv
a=rtpmap:97 iLBC/8000
a=fmtp:97 mode=30
a=ptime:60
a=rtpmap:8 PCMA/8000
a=rtpmap:4 G723/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
<------------->
--- (18 headers 19 lines) ---
Sending to 192.168.10.106:5060 (no NAT)
Using INVITE request as basis request - 2000295813-5060-336 at BJC.BGI.BA.BAG
Found peer '106' for '106' from 192.168.10.106:5060
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
Found RTP audio format 97
Found RTP audio format 8
Found RTP audio format 4
Found RTP audio format 18
Found RTP audio format 9
Found RTP audio format 0
Found RTP audio format 2
Found RTP audio format 101
Found audio description format iLBC for ID 97
Found audio description format PCMA for ID 8
Found audio description format G723 for ID 4
Found audio description format G729 for ID 18
Found audio description format G722 for ID 9
Found audio description format PCMU for ID 0
Found audio description format G726-32 for ID 2
Found audio description format telephone-event for ID 101
Capabilities: us - (ilbc|g729|ulaw|alaw|g722), peer - audio=(ulaw|g726|g723|alaw|g722|g729|ilbc)/video=(nothing)/text=(nothing), combined - (ilbc|g729|ulaw|alaw|g722)
Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|)
> 0xb7452bd0 -- Strict RTP learning after remote address set to: 192.168.10.106:5004
Peer audio RTP is at port 192.168.10.106:5004
Looking for 101 in from-internal (domain 192.168.10.4)
== Extension Changed 106[ext-local] new state InUse for Notify User 103
== Extension Changed 106[ext-local] new state InUse for Notify User 100
== Extension Changed 106[ext-local] new state InUse for Notify User 104
== Extension Changed 106[ext-local] new state InUse for Notify User 105
sip_route_dump: route/path hop: <sip:106 at 192.168.10.106:5060>
<--- Transmitting (no NAT) to 192.168.10.106:5060 --->
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK1868569856;received=192.168.10.106;rport=5060
From: <sip:106 at 192.168.10.4>;tag=1674326932
To: <sip:101 at 192.168.10.4>
Call-ID: 2000295813-5060-336 at BJC.BGI.BA.BAG
CSeq: 1081 INVITE
Server: FPBX-13.0.195.19(13.23.1)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: <sip:101 at 192.168.10.4:5060>
Content-Length: 0
<------------>
-- Executing [101 at from-internal:1] GotoIf("SIP/106-000000b3", "1?ext-local,101,1:followme-check,101,1") in new stack
-- Goto (ext-local,101,1)
-- Executing [101 at ext-local:1] Set("SIP/106-000000b3", "__RINGTIMER=15") in new stack
-- Executing [101 at ext-local:2] Macro("SIP/106-000000b3", "exten-vm,novm,101,0,0,0") in new stack
-- Executing [s at macro-exten-vm:1] Macro("SIP/106-000000b3", "user-callerid,") in new stack
-- Executing [s at macro-user-callerid:1] Set("SIP/106-000000b3", "TOUCH_MONITOR=1544205572.1516") in new stack
-- Executing [s at macro-user-callerid:2] Set("SIP/106-000000b3", "AMPUSER=106") in new stack
-- Executing [s at macro-user-callerid:3] GotoIf("SIP/106-000000b3", "0?report") in new stack
-- Executing [s at macro-user-callerid:4] ExecIf("SIP/106-000000b3", "1?Set(REALCALLERIDNUM=106)") in new stack
-- Executing [s at macro-user-callerid:5] Set("SIP/106-000000b3", "AMPUSER=106") in new stack
-- Executing [s at macro-user-callerid:6] GotoIf("SIP/106-000000b3", "0?limit") in new stack
-- Executing [s at macro-user-callerid:7] Set("SIP/106-000000b3", "AMPUSERCIDNAME=Marianela Lopez") in new stack
-- Executing [s at macro-user-callerid:8] ExecIf("SIP/106-000000b3", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
-- Executing [s at macro-user-callerid:9] GotoIf("SIP/106-000000b3", "0?report") in new stack
-- Executing [s at macro-user-callerid:10] Set("SIP/106-000000b3", "AMPUSERCID=106") in new stack
-- Executing [s at macro-user-callerid:11] Set("SIP/106-000000b3", "__DIAL_OPTIONS=Ttr") in new stack
-- Executing [s at macro-user-callerid:12] Set("SIP/106-000000b3", "CALLERID(all)="Marianela Lopez" <106>") in new stack
-- Executing [s at macro-user-callerid:13] GotoIf("SIP/106-000000b3", "0?limit") in new stack
-- Executing [s at macro-user-callerid:14] ExecIf("SIP/106-000000b3", "0?Set(GROUP(concurrency_limit)=106)") in new stack
-- Executing [s at macro-user-callerid:15] GosubIf("SIP/106-000000b3", "7?sub-ccss,s,1(macro-exten-vm,101)") in new stack
-- Executing [s at sub-ccss:1] ExecIf("SIP/106-000000b3", "0?Return()") in new stack
-- Executing [s at sub-ccss:2] Set("SIP/106-000000b3", "CCSS_SETUP=TRUE") in new stack
-- Executing [s at sub-ccss:3] GosubIf("SIP/106-000000b3", "0?monitor_config,1(macro-exten-vm,101):monitor_default,1(macro-exten-vm,101)") in new stack
-- Executing [monitor_default at sub-ccss:1] GotoIf("SIP/106-000000b3", "1?is_exten") in new stack
-- Goto (sub-ccss,monitor_default,4)
-- Executing [monitor_default at sub-ccss:4] Set("SIP/106-000000b3", "CALLCOMPLETION(cc_monitor_policy)=generic") in new stack
-- Executing [monitor_default at sub-ccss:5] Set("SIP/106-000000b3", "CALLCOMPLETION(cc_max_monitors)=5") in new stack
-- Executing [monitor_default at sub-ccss:6] Return("SIP/106-000000b3", "TRUE") in new stack
-- Executing [s at sub-ccss:4] GosubIf("SIP/106-000000b3", "7?agent_config,1():agent_default,1()") in new stack
-- Executing [agent_config at sub-ccss:1] Set("SIP/106-000000b3", "CALLCOMPLETION(cc_agent_policy)=generic") in new stack
-- Executing [agent_config at sub-ccss:2] Set("SIP/106-000000b3", "CALLCOMPLETION(cc_offer_timer)=30") in new stack
-- Executing [agent_config at sub-ccss:3] Set("SIP/106-000000b3", "CALLCOMPLETION(ccbs_available_timer)=") in new stack
-- Executing [agent_config at sub-ccss:4] Set("SIP/106-000000b3", "CALLCOMPLETION(ccnr_available_timer)=") in new stack
-- Executing [agent_config at sub-ccss:5] Set("SIP/106-000000b3", "CALLCOMPLETION(cc_callback_macro)=ccss-default") in new stack
[2018-12-07 14:59:32] WARNING[32490][C-0000007a]: ccss.c:1012 ast_set_cc_callback_macro: Usage of cc_callback_macro is deprecated. Please use cc_callback_sub instead.
-- Executing [agent_config at sub-ccss:6] ExecIf("SIP/106-000000b3", "1?Set(CALLCOMPLETION(cc_recall_timer)=)") in new stack
-- Executing [agent_config at sub-ccss:7] ExecIf("SIP/106-000000b3", "1?Set(CALLCOMPLETION(cc_max_agents)=)") in new stack
-- Executing [agent_config at sub-ccss:8] ExecIf("SIP/106-000000b3", "0?Set(CALLCOMPLETION(cc_agent_dialstring)=Local/106_101 at from-ccss-)") in new stack
-- Executing [agent_config at sub-ccss:9] Set("SIP/106-000000b3", "CALLCOMPLETION(cc_callback_macro)=ccss-default") in new stack
[2018-12-07 14:59:32] WARNING[32490][C-0000007a]: ccss.c:1012 ast_set_cc_callback_macro: Usage of cc_callback_macro is deprecated. Please use cc_callback_sub instead.
-- Executing [agent_config at sub-ccss:10] Return("SIP/106-000000b3", "") in new stack
-- Executing [s at sub-ccss:5] Set("SIP/106-000000b3", "DB(AMPUSER/106/ccss/last_number)=101") in new stack
-- Executing [s at sub-ccss:6] Return("SIP/106-000000b3", "") in new stack
-- Executing [s at macro-user-callerid:16] ExecIf("SIP/106-000000b3", "0?Set(CHANNEL(language)=)") in new stack
-- Executing [s at macro-user-callerid:17] NoOp("SIP/106-000000b3", "Macro Depth is 2") in new stack
-- Executing [s at macro-user-callerid:18] GotoIf("SIP/106-000000b3", "1?report2:macroerror") in new stack
-- Goto (macro-user-callerid,s,19)
-- Executing [s at macro-user-callerid:19] GotoIf("SIP/106-000000b3", "0?continue") in new stack
-- Executing [s at macro-user-callerid:20] ExecIf("SIP/106-000000b3", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
-- Executing [s at macro-user-callerid:21] Set("SIP/106-000000b3", "__TTL=64") in new stack
-- Executing [s at macro-user-callerid:22] GotoIf("SIP/106-000000b3", "1?continue") in new stack
-- Goto (macro-user-callerid,s,38)
-- Executing [s at macro-user-callerid:38] Set("SIP/106-000000b3", "CALLERID(number)=106") in new stack
-- Executing [s at macro-user-callerid:39] Set("SIP/106-000000b3", "CALLERID(name)=Marianela Lopez") in new stack
-- Executing [s at macro-user-callerid:40] GotoIf("SIP/106-000000b3", "0?cnum") in new stack
-- Executing [s at macro-user-callerid:41] Set("SIP/106-000000b3", "CDR(cnam)=Marianela Lopez") in new stack
-- Executing [s at macro-user-callerid:42] Set("SIP/106-000000b3", "CDR(cnum)=106") in new stack
-- Executing [s at macro-user-callerid:43] Set("SIP/106-000000b3", "CHANNEL(language)=es") in new stack
-- Executing [s at macro-exten-vm:2] Set("SIP/106-000000b3", "RingGroupMethod=none") in new stack
-- Executing [s at macro-exten-vm:3] Set("SIP/106-000000b3", "__EXTTOCALL=101") in new stack
-- Executing [s at macro-exten-vm:4] Set("SIP/106-000000b3", "__PICKUPMARK=101") in new stack
-- Executing [s at macro-exten-vm:5] Set("SIP/106-000000b3", "RT=") in new stack
-- Executing [s at macro-exten-vm:6] Gosub("SIP/106-000000b3", "sub-record-check,s,1(exten,101,dontcare)") in new stack
-- Executing [s at sub-record-check:1] GotoIf("SIP/106-000000b3", "0?initialized") in new stack
-- Executing [s at sub-record-check:2] Set("SIP/106-000000b3", "__REC_STATUS=INITIALIZED") in new stack
-- Executing [s at sub-record-check:3] Set("SIP/106-000000b3", "NOW=1544205572") in new stack
-- Executing [s at sub-record-check:4] Set("SIP/106-000000b3", "__DAY=07") in new stack
-- Executing [s at sub-record-check:5] Set("SIP/106-000000b3", "__MONTH=12") in new stack
-- Executing [s at sub-record-check:6] Set("SIP/106-000000b3", "__YEAR=2018") in new stack
-- Executing [s at sub-record-check:7] Set("SIP/106-000000b3", "__TIMESTR=20181207-145932") in new stack
-- Executing [s at sub-record-check:8] Set("SIP/106-000000b3", "__FROMEXTEN=106") in new stack
-- Executing [s at sub-record-check:9] Set("SIP/106-000000b3", "__MON_FMT=wav") in new stack
-- Executing [s at sub-record-check:10] NoOp("SIP/106-000000b3", "Recordings initialized") in new stack
-- Executing [s at sub-record-check:11] ExecIf("SIP/106-000000b3", "0?Set(ARG3=dontcare)") in new stack
-- Executing [s at sub-record-check:12] Set("SIP/106-000000b3", "REC_POLICY_MODE_SAVE=") in new stack
-- Executing [s at sub-record-check:13] ExecIf("SIP/106-000000b3", "0?Set(REC_STATUS=NO)") in new stack
-- Executing [s at sub-record-check:14] GotoIf("SIP/106-000000b3", "5?checkaction") in new stack
-- Goto (sub-record-check,s,17)
-- Executing [s at sub-record-check:17] GotoIf("SIP/106-000000b3", "1?sub-record-check,exten,1") in new stack
-- Goto (sub-record-check,exten,1)
-- Executing [exten at sub-record-check:1] NoOp("SIP/106-000000b3", "Exten Recording Check between 106 and 101") in new stack
-- Executing [exten at sub-record-check:2] Set("SIP/106-000000b3", "CALLTYPE=internal") in new stack
-- Executing [exten at sub-record-check:3] ExecIf("SIP/106-000000b3", "0?Set(CALLTYPE=)") in new stack
-- Executing [exten at sub-record-check:4] Set("SIP/106-000000b3", "CALLEE=dontcare") in new stack
-- Executing [exten at sub-record-check:5] ExecIf("SIP/106-000000b3", "0?Set(CALLEE=dontcare)") in new stack
-- Executing [exten at sub-record-check:6] GotoIf("SIP/106-000000b3", "0?callee") in new stack
-- Executing [exten at sub-record-check:7] GotoIf("SIP/106-000000b3", "1?caller") in new stack
-- Goto (sub-record-check,exten,13)
-- Executing [exten at sub-record-check:13] Set("SIP/106-000000b3", "RECMODE=dontcare") in new stack
-- Executing [exten at sub-record-check:14] ExecIf("SIP/106-000000b3", "0?Set(RECMODE=dontcare)") in new stack
-- Executing [exten at sub-record-check:15] ExecIf("SIP/106-000000b3", "1?Set(RECMODE=dontcare)") in new stack
-- Executing [exten at sub-record-check:16] Gosub("SIP/106-000000b3", "recordcheck,1(dontcare,internal,101)") in new stack
-- Executing [recordcheck at sub-record-check:1] NoOp("SIP/106-000000b3", "Starting recording check against dontcare") in new stack
-- Executing [recordcheck at sub-record-check:2] Goto("SIP/106-000000b3", "dontcare") in new stack
-- Goto (sub-record-check,recordcheck,3)
-- Executing [recordcheck at sub-record-check:3] Return("SIP/106-000000b3", "") in new stack
-- Executing [exten at sub-record-check:17] Return("SIP/106-000000b3", "") in new stack
-- Executing [s at macro-exten-vm:7] GotoIf("SIP/106-000000b3", "1?macrodial") in new stack
-- Goto (macro-exten-vm,s,13)
-- Executing [s at macro-exten-vm:13] GosubIf("SIP/106-000000b3", "0?clrheader,1()") in new stack
-- Executing [s at macro-exten-vm:14] Macro("SIP/106-000000b3", "dial-one,,Ttr,101") in new stack
-- Executing [s at macro-dial-one:1] Set("SIP/106-000000b3", "DEXTEN=101") in new stack
-- Executing [s at macro-dial-one:2] ExecIf("SIP/106-000000b3", "0?Set(__EXTTOCALL=101)") in new stack
-- Executing [s at macro-dial-one:3] Set("SIP/106-000000b3", "DIALSTATUS_CW=") in new stack
-- Executing [s at macro-dial-one:4] GosubIf("SIP/106-000000b3", "0?screen,1()") in new stack
-- Executing [s at macro-dial-one:5] GosubIf("SIP/106-000000b3", "0?cf,1()") in new stack
-- Executing [s at macro-dial-one:6] GotoIf("SIP/106-000000b3", "1?skip1") in new stack
-- Goto (macro-dial-one,s,9)
-- Executing [s at macro-dial-one:9] GotoIf("SIP/106-000000b3", "0?nodial") in new stack
-- Executing [s at macro-dial-one:10] GotoIf("SIP/106-000000b3", "0?continue") in new stack
-- Executing [s at macro-dial-one:11] Set("SIP/106-000000b3", "EXTHASCW=ENABLED") in new stack
-- Executing [s at macro-dial-one:12] GotoIf("SIP/106-000000b3", "0?next1:cwinusebusy") in new stack
-- Goto (macro-dial-one,s,24)
-- Executing [s at macro-dial-one:24] GotoIf("SIP/106-000000b3", "0?next3:continue") in new stack
-- Goto (macro-dial-one,s,26)
-- Executing [s at macro-dial-one:26] GotoIf("SIP/106-000000b3", "0?nodial") in new stack
-- Executing [s at macro-dial-one:27] GosubIf("SIP/106-000000b3", "1?dstring,1():dlocal,1()") in new stack
-- Executing [dstring at macro-dial-one:1] Set("SIP/106-000000b3", "DSTRING=") in new stack
-- Executing [dstring at macro-dial-one:2] Set("SIP/106-000000b3", "DEVICES=101") in new stack
-- Executing [dstring at macro-dial-one:3] ExecIf("SIP/106-000000b3", "0?Return()") in new stack
-- Executing [dstring at macro-dial-one:4] ExecIf("SIP/106-000000b3", "0?Set(DEVICES=01)") in new stack
-- Executing [dstring at macro-dial-one:5] Set("SIP/106-000000b3", "LOOPCNT=1") in new stack
-- Executing [dstring at macro-dial-one:6] Set("SIP/106-000000b3", "ITER=1") in new stack
-- Executing [dstring at macro-dial-one:7] Set("SIP/106-000000b3", "THISDIAL=DAHDI/5") in new stack
-- Executing [dstring at macro-dial-one:8] GosubIf("SIP/106-000000b3", "1?zap2dahdi,1()") in new stack
-- Executing [zap2dahdi at macro-dial-one:1] ExecIf("SIP/106-000000b3", "0?Return()") in new stack
-- Executing [zap2dahdi at macro-dial-one:2] Set("SIP/106-000000b3", "NEWDIAL=") in new stack
-- Executing [zap2dahdi at macro-dial-one:3] Set("SIP/106-000000b3", "LOOPCNT2=1") in new stack
-- Executing [zap2dahdi at macro-dial-one:4] Set("SIP/106-000000b3", "ITER2=1") in new stack
-- Executing [zap2dahdi at macro-dial-one:5] Set("SIP/106-000000b3", "THISPART2=DAHDI/5") in new stack
-- Executing [zap2dahdi at macro-dial-one:6] ExecIf("SIP/106-000000b3", "0?Set(THISPART2=DAHDIDI/5)") in new stack
-- Executing [zap2dahdi at macro-dial-one:7] Set("SIP/106-000000b3", "NEWDIAL=DAHDI/5&") in new stack
-- Executing [zap2dahdi at macro-dial-one:8] Set("SIP/106-000000b3", "ITER2=2") in new stack
-- Executing [zap2dahdi at macro-dial-one:9] GotoIf("SIP/106-000000b3", "0?begin2") in new stack
-- Executing [zap2dahdi at macro-dial-one:10] Set("SIP/106-000000b3", "THISDIAL=DAHDI/5") in new stack
-- Executing [zap2dahdi at macro-dial-one:11] Return("SIP/106-000000b3", "") in new stack
-- Executing [dstring at macro-dial-one:9] GotoIf("SIP/106-000000b3", "1?docheck") in new stack
-- Goto (macro-dial-one,dstring,14)
-- Executing [dstring at macro-dial-one:14] GotoIf("SIP/106-000000b3", "0?skipset") in new stack
-- Executing [dstring at macro-dial-one:15] Set("SIP/106-000000b3", "DSTRING=DAHDI/5&") in new stack
-- Executing [dstring at macro-dial-one:16] Set("SIP/106-000000b3", "ITER=2") in new stack
-- Executing [dstring at macro-dial-one:17] GotoIf("SIP/106-000000b3", "0?begin") in new stack
-- Executing [dstring at macro-dial-one:18] ExecIf("SIP/106-000000b3", "0?Return()") in new stack
-- Executing [dstring at macro-dial-one:19] Set("SIP/106-000000b3", "DSTRING=DAHDI/5") in new stack
-- Executing [dstring at macro-dial-one:20] Return("SIP/106-000000b3", "") in new stack
-- Executing [s at macro-dial-one:28] GotoIf("SIP/106-000000b3", "0?nodial") in new stack
-- Executing [s at macro-dial-one:29] GotoIf("SIP/106-000000b3", "0?skiptrace") in new stack
-- Executing [s at macro-dial-one:30] GosubIf("SIP/106-000000b3", "1?ctset,1():ctclear,1()") in new stack
-- Executing [ctset at macro-dial-one:1] Set("SIP/106-000000b3", "DB(CALLTRACE/101)=106") in new stack
-- Executing [ctset at macro-dial-one:2] Return("SIP/106-000000b3", "") in new stack
-- Executing [s at macro-dial-one:31] Set("SIP/106-000000b3", "D_OPTIONS=Ttr") in new stack
-- Executing [s at macro-dial-one:32] GosubIf("SIP/106-000000b3", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
-- Executing [s at macro-dial-one:33] NoOp("SIP/106-000000b3", "Blind Transfer: , Attended Transfer: , User: 106, Alert Info: ") in new stack
-- Executing [s at macro-dial-one:34] ExecIf("SIP/106-000000b3", "1?Set(ALERT_INFO=)") in new stack
-- Executing [s at macro-dial-one:35] ExecIf("SIP/106-000000b3", "0?Set(ALERT_INFO=)") in new stack
-- Executing [s at macro-dial-one:36] ExecIf("SIP/106-000000b3", "0?Set(ALERT_INFO=)") in new stack
-- Executing [s at macro-dial-one:37] ExecIf("SIP/106-000000b3", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
-- Executing [s at macro-dial-one:38] ExecIf("SIP/106-000000b3", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
-- Executing [s at macro-dial-one:39] GosubIf("SIP/106-000000b3", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
-- Executing [s at macro-dial-one:40] ExecIf("SIP/106-000000b3", "0?Set(CHANNEL(musicclass)=)") in new stack
-- Executing [s at macro-dial-one:41] GosubIf("SIP/106-000000b3", "0?qwait,1()") in new stack
-- Executing [s at macro-dial-one:42] Set("SIP/106-000000b3", "__CWIGNORE=") in new stack
-- Executing [s at macro-dial-one:43] Set("SIP/106-000000b3", "__KEEPCID=TRUE") in new stack
-- Executing [s at macro-dial-one:44] GotoIf("SIP/106-000000b3", "0?usegoto,1") in new stack
-- Executing [s at macro-dial-one:45] GotoIf("SIP/106-000000b3", "0?godial") in new stack
-- Executing [s at macro-dial-one:46] Gosub("SIP/106-000000b3", "sub-presencestate-display,s,1(101)") in new stack
-- Executing [s at sub-presencestate-display:1] Goto("SIP/106-000000b3", "state-not_set,1") in new stack
-- Goto (sub-presencestate-display,state-not_set,1)
-- Executing [state-not_set at sub-presencestate-display:1] Set("SIP/106-000000b3", "PRESENCESTATE_DISPLAY=") in new stack
-- Executing [state-not_set at sub-presencestate-display:2] Return("SIP/106-000000b3", "") in new stack
-- Executing [s at macro-dial-one:47] Set("SIP/106-000000b3", "CONNECTEDLINE(name,i)=Comedor") in new stack
-- Executing [s at macro-dial-one:48] Set("SIP/106-000000b3", "CONNECTEDLINE(num)=101") in new stack
-- Executing [s at macro-dial-one:49] Set("SIP/106-000000b3", "D_OPTIONS=TtrI") in new stack
-- Executing [s at macro-dial-one:50] Macro("SIP/106-000000b3", "dialout-one-predial-hook,") in new stack
-- Executing [s at macro-dialout-one-predial-hook:1] MacroExit("SIP/106-000000b3", "") in new stack
-- Executing [s at macro-dial-one:51] ExecIf("SIP/106-000000b3", "0?Set(D_OPTIONS=trII)") in new stack
-- Executing [s at macro-dial-one:52] NoOp("SIP/106-000000b3", "") in new stack
-- Executing [s at macro-dial-one:53] ExecIf("SIP/106-000000b3", "0?Set(D_OPTIONS=TtrIg)") in new stack
-- Executing [s at macro-dial-one:54] Dial("SIP/106-000000b3", "DAHDI/5,,TtrIb(func-apply-sipheaders^s^1)") in new stack
-- DAHDI/5-1 Internal Gosub(func-apply-sipheaders,s,1) start
== Extension Changed 101[ext-local] new state InUse for Notify User 100
== Extension Changed 101[ext-local] new state InUse for Notify User 104
set_destination: Parsing <sip:106 at 192.168.10.106:5060> for address/port to send to
set_destination: set destination to 192.168.10.106:5060
-- Executing [s at func-apply-sipheaders:1] NoOp("DAHDI/5-1", "Applying SIP Headers to channel") in new stack
Reliably Transmitting (no NAT) to 192.168.10.106:5060:
NOTIFY sip:106 at 192.168.10.106:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK15e6421b;rport
Max-Forwards: 70
From: <sip:101 at 192.168.10.4>;tag=as31fae48c
To: <sip:106 at 192.168.10.4>;tag=395955848
Contact: <sip:101 at 192.168.10.4:5060>
Call-ID: 413681140-5060-317 at BJC.BGI.BA.BAG
CSeq: 271 NOTIFY
User-Agent: FPBX-13.0.195.19(13.23.1)
Subscription-State: active
Event: dialog
Content-Type: application/dialog-info+xml
Content-Length: 204
<?xml version="1.0"?>
<dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="169" state="full" entity="sip:101 at 192.168.10.4">
<dialog id="101">
<state>confirmed</state>
</dialog>
</dialog-info>
---
== Extension Changed 101[ext-local] new state InUse for Notify User 106
-- Executing [s at func-apply-sipheaders:2] Set("DAHDI/5-1", "SIPHEADERKEYS=") in new stack
== Extension Changed 101[ext-local] new state InUse for Notify User 103
== Extension Changed 101[ext-local] new state InUse for Notify User 105
-- Executing [s at func-apply-sipheaders:3] ExecIf("DAHDI/5-1", "0?Set(Rheader=1)") in new stack
-- Executing [s at func-apply-sipheaders:4] While("DAHDI/5-1", "0") in new stack
-- Jumping to priority 7
-- Executing [s at func-apply-sipheaders:8] ExecIf("DAHDI/5-1", "0?SIPRemoveHeader(Alert-Info:)") in new stack
-- Executing [s at func-apply-sipheaders:9] Return("DAHDI/5-1", "") in new stack
== Spawn extension (from-internal, 101, 1) exited non-zero on 'DAHDI/5-1'
-- DAHDI/5-1 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
== Extension Changed 101[ext-local] new state Ringing for Notify User 100 (queued)
== Extension Changed 101[ext-local] new state Ringing for Notify User 104 (queued)
== Extension Changed 101[ext-local] new state Ringing for Notify User 106 (queued)
== Extension Changed 101[ext-local] new state Ringing for Notify User 103 (queued)
== Extension Changed 101[ext-local] new state Ringing for Notify User 105 (queued)
-- Called DAHDI/5
<--- Transmitting (no NAT) to 192.168.10.106:5060 --->
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK1868569856;received=192.168.10.106;rport=5060
From: <sip:106 at 192.168.10.4>;tag=1674326932
To: <sip:101 at 192.168.10.4>;tag=as2b2e2423
Call-ID: 2000295813-5060-336 at BJC.BGI.BA.BAG
CSeq: 1081 INVITE
Server: FPBX-13.0.195.19(13.23.1)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: <sip:101 at 192.168.10.4:5060>
Content-Length: 0
<------------>
-- DAHDI/5-1 is ringing
<--- Transmitting (no NAT) to 192.168.10.106:5060 --->
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK1868569856;received=192.168.10.106;rport=5060
From: <sip:106 at 192.168.10.4>;tag=1674326932
To: <sip:101 at 192.168.10.4>;tag=as2b2e2423
Call-ID: 2000295813-5060-336 at BJC.BGI.BA.BAG
CSeq: 1081 INVITE
Server: FPBX-13.0.195.19(13.23.1)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: <sip:101 at 192.168.10.4:5060>
Content-Length: 0
<------------>
<--- SIP read from UDP:192.168.10.106:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK15e6421b;rport=5060
From: <sip:101 at 192.168.10.4>;tag=as31fae48c
To: <sip:106 at 192.168.10.4>;tag=395955848
Call-ID: 413681140-5060-317 at BJC.BGI.BA.BAG
CSeq: 271 NOTIFY
Contact: <sip:106 at 192.168.10.106:5060>
Supported: replaces, path, timer
User-Agent: Grandstream GXP2110 1.0.8.6
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
Content-Length: 0
<------------->
--- (11 headers 0 lines) ---
set_destination: Parsing <sip:106 at 192.168.10.106:5060> for address/port to send to
set_destination: set destination to 192.168.10.106:5060
Reliably Transmitting (no NAT) to 192.168.10.106:5060:
NOTIFY sip:106 at 192.168.10.106:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK0d69c982;rport
Max-Forwards: 70
From: <sip:101 at 192.168.10.4>;tag=as31fae48c
To: <sip:106 at 192.168.10.4>;tag=395955848
Contact: <sip:101 at 192.168.10.4:5060>
Call-ID: 413681140-5060-317 at BJC.BGI.BA.BAG
CSeq: 272 NOTIFY
User-Agent: FPBX-13.0.195.19(13.23.1)
Subscription-State: active
Event: dialog
Content-Type: application/dialog-info+xml
Content-Length: 222
<?xml version="1.0"?>
<dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="170" state="full" entity="sip:101 at 192.168.10.4">
<dialog id="101" direction="recipient">
<state>early</state>
</dialog>
</dialog-info>
---
Retransmitting #1 (no NAT) to 192.168.10.106:5060:
NOTIFY sip:106 at 192.168.10.106:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK0d69c982;rport
Max-Forwards: 70
From: <sip:101 at 192.168.10.4>;tag=as31fae48c
To: <sip:106 at 192.168.10.4>;tag=395955848
Contact: <sip:101 at 192.168.10.4:5060>
Call-ID: 413681140-5060-317 at BJC.BGI.BA.BAG
CSeq: 272 NOTIFY
User-Agent: FPBX-13.0.195.19(13.23.1)
Subscription-State: active
Event: dialog
Content-Type: application/dialog-info+xml
Content-Length: 222
<?xml version="1.0"?>
<dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="170" state="full" entity="sip:101 at 192.168.10.4">
<dialog id="101" direction="recipient">
<state>early</state>
</dialog>
</dialog-info>
---
<--- SIP read from UDP:192.168.10.106:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK0d69c982;rport=5060
From: <sip:101 at 192.168.10.4>;tag=as31fae48c
To: <sip:106 at 192.168.10.4>;tag=395955848
Call-ID: 413681140-5060-317 at BJC.BGI.BA.BAG
CSeq: 272 NOTIFY
Contact: <sip:106 at 192.168.10.106:5060>
Supported: replaces, path, timer
User-Agent: Grandstream GXP2110 1.0.8.6
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
Content-Length: 0
<------------->
--- (11 headers 0 lines) ---
<--- SIP read from UDP:192.168.10.106:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK0d69c982;rport=5060
From: <sip:101 at 192.168.10.4>;tag=as31fae48c
To: <sip:106 at 192.168.10.4>;tag=395955848
Call-ID: 413681140-5060-317 at BJC.BGI.BA.BAG
CSeq: 272 NOTIFY
Contact: <sip:106 at 192.168.10.106:5060>
Supported: replaces, path, timer
User-Agent: Grandstream GXP2110 1.0.8.6
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
Content-Length: 0
<------------->
--- (11 headers 0 lines) ---
-- DAHDI/5-1 answered SIP/106-000000b3
== Extension Changed 101[ext-local] new state InUse for Notify User 100
== Extension Changed 101[ext-local] new state InUse for Notify User 104
set_destination: Parsing <sip:106 at 192.168.10.106:5060> for address/port to send to
set_destination: set destination to 192.168.10.106:5060
Reliably Transmitting (no NAT) to 192.168.10.106:5060:
NOTIFY sip:106 at 192.168.10.106:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK412bd2cb;rport
Max-Forwards: 70
From: <sip:101 at 192.168.10.4>;tag=as31fae48c
To: <sip:106 at 192.168.10.4>;tag=395955848
Contact: <sip:101 at 192.168.10.4:5060>
Call-ID: 413681140-5060-317 at BJC.BGI.BA.BAG
CSeq: 273 NOTIFY
User-Agent: FPBX-13.0.195.19(13.23.1)
Subscription-State: active
Event: dialog
Content-Type: application/dialog-info+xml
Content-Length: 204
<?xml version="1.0"?>
<dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="171" state="full" entity="sip:101 at 192.168.10.4">
<dialog id="101">
<state>confirmed</state>
</dialog>
</dialog-info>
---
== Extension Changed 101[ext-local] new state InUse for Notify User 106
== Extension Changed 101[ext-local] new state InUse for Notify User 103
== Extension Changed 101[ext-local] new state InUse for Notify User 105
Audio is at 15854
Adding codec ilbc to SDP
Adding codec g729 to SDP
Adding codec ulaw to SDP
Adding codec alaw to SDP
Adding codec g722 to SDP
Adding non-codec 0x1 (telephone-event) to SDP
<--- Reliably Transmitting (no NAT) to 192.168.10.106:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK1868569856;received=192.168.10.106;rport=5060
From: <sip:106 at 192.168.10.4>;tag=1674326932
To: <sip:101 at 192.168.10.4>;tag=as2b2e2423
Call-ID: 2000295813-5060-336 at BJC.BGI.BA.BAG
CSeq: 1081 INVITE
Server: FPBX-13.0.195.19(13.23.1)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: <sip:101 at 192.168.10.4:5060>
Content-Type: application/sdp
Require: timer
Content-Length: 371
v=0
o=root 650668316 650668316 IN IP4 192.168.10.4
s=Asterisk PBX 13.23.1
c=IN IP4 192.168.10.4
t=0 0
m=audio 15854 RTP/AVP 97 18 0 8 9 101
a=rtpmap:97 iLBC/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=maxptime:150
a=sendrecv
<------------>
-- Channel DAHDI/5-1 joined 'simple_bridge' basic-bridge <68206207-19b4-4f84-9c43-a5a39b17eddd>
-- Channel SIP/106-000000b3 joined 'simple_bridge' basic-bridge <68206207-19b4-4f84-9c43-a5a39b17eddd>
<--- SIP read from UDP:192.168.10.106:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK412bd2cb;rport=5060
From: <sip:101 at 192.168.10.4>;tag=as31fae48c
To: <sip:106 at 192.168.10.4>;tag=395955848
Call-ID: 413681140-5060-317 at BJC.BGI.BA.BAG
CSeq: 273 NOTIFY
Contact: <sip:106 at 192.168.10.106:5060>
Supported: replaces, path, timer
User-Agent: Grandstream GXP2110 1.0.8.6
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
Content-Length: 0
<------------->
--- (11 headers 0 lines) ---
<--- SIP read from UDP:192.168.10.106:5060 --->
ACK sip:101 at 192.168.10.4:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK2048660464;rport
From: <sip:106 at 192.168.10.4>;tag=1674326932
To: <sip:101 at 192.168.10.4>;tag=as2b2e2423
Call-ID: 2000295813-5060-336 at BJC.BGI.BA.BAG
CSeq: 1081 ACK
Contact: <sip:106 at 192.168.10.106:5060>
X-Grandstream-PBX: true
Max-Forwards: 70
Supported: replaces, path, timer
User-Agent: Grandstream GXP2110 1.0.8.6
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
Content-Length: 0
<------------->
--- (13 headers 0 lines) ---
> 0xb7452bd0 -- Strict RTP switching to RTP target address 192.168.10.106:5004 as source
> 0xb7452bd0 -- Strict RTP learning complete - Locking on source address 192.168.10.106:5004
Reliably Transmitting (no NAT) to 192.168.10.106:5060:
OPTIONS sip:106 at 192.168.10.106:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK69daa9e3
Max-Forwards: 70
From: "Unknown" <sip:Unknown at 192.168.10.4>;tag=as4ceb2595
To: <sip:106 at 192.168.10.106:5060>
Contact: <sip:Unknown at 192.168.10.4:5060>
Call-ID: 3c305f1e6d913a65413069d623a50a6a at 192.168.10.4:5060
CSeq: 102 OPTIONS
User-Agent: FPBX-13.0.195.19(13.23.1)
Date: Fri, 07 Dec 2018 17:59:57 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0
---
<--- SIP read from UDP:192.168.10.106:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK69daa9e3
From: "Unknown" <sip:Unknown at 192.168.10.4>;tag=as4ceb2595
To: <sip:106 at 192.168.10.106:5060>;tag=312204839
Call-ID: 3c305f1e6d913a65413069d623a50a6a at 192.168.10.4:5060
CSeq: 102 OPTIONS
Supported: replaces, path, timer
User-Agent: Grandstream GXP2110 1.0.8.6
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
Content-Length: 0
<------------->
--- (10 headers 0 lines) ---
Really destroying SIP dialog '3c305f1e6d913a65413069d623a50a6a at 192.168.10.4:5060' Method: OPTIONS
<--- SIP read from UDP:192.168.10.106:5060 --->
BYE sip:101 at 192.168.10.4:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK342592800;rport
From: <sip:106 at 192.168.10.4>;tag=1674326932
To: <sip:101 at 192.168.10.4>;tag=as2b2e2423
Call-ID: 2000295813-5060-336 at BJC.BGI.BA.BAG
CSeq: 1082 BYE
Contact: <sip:106 at 192.168.10.106:5060>
X-Grandstream-PBX: true
Max-Forwards: 70
Supported: replaces, path, timer
User-Agent: Grandstream GXP2110 1.0.8.6
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
Content-Length: 0
<------------->
--- (13 headers 0 lines) ---
Sending to 192.168.10.106:5060 (no NAT)
Scheduling destruction of SIP dialog '2000295813-5060-336 at BJC.BGI.BA.BAG' in 6400 ms (Method: BYE)
<--- Transmitting (no NAT) to 192.168.10.106:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK342592800;received=192.168.10.106;rport=5060
From: <sip:106 at 192.168.10.4>;tag=1674326932
To: <sip:101 at 192.168.10.4>;tag=as2b2e2423
Call-ID: 2000295813-5060-336 at BJC.BGI.BA.BAG
CSeq: 1082 BYE
Server: FPBX-13.0.195.19(13.23.1)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0
<------------>
-- Channel SIP/106-000000b3 left 'simple_bridge' basic-bridge <68206207-19b4-4f84-9c43-a5a39b17eddd>
== Spawn extension (macro-dial-one, s, 54) exited non-zero on 'SIP/106-000000b3' in macro 'dial-one'
== Spawn extension (macro-exten-vm, s, 14) exited non-zero on 'SIP/106-000000b3' in macro 'exten-vm'
-- Channel DAHDI/5-1 left 'simple_bridge' basic-bridge <68206207-19b4-4f84-9c43-a5a39b17eddd>
== Spawn extension (ext-local, 101, 2) exited non-zero on 'SIP/106-000000b3'
-- Executing [h at ext-local:1] Macro("SIP/106-000000b3", "hangupcall,") in new stack
-- Hanging up on 'DAHDI/5-1'
-- Hungup 'DAHDI/5-1'
-- Executing [s at macro-hangupcall:1] GotoIf("SIP/106-000000b3", "1?theend") in new stack
-- Goto (macro-hangupcall,s,3)
-- Executing [s at macro-hangupcall:3] ExecIf("SIP/106-000000b3", "0?Set(CDR(recordingfile)=)") in new stack
== Extension Changed 101[ext-local] new state Idle for Notify User 100
== Extension Changed 101[ext-local] new state Idle for Notify User 104
set_destination: Parsing <sip:106 at 192.168.10.106:5060> for address/port to send to
set_destination: set destination to 192.168.10.106:5060
Reliably Transmitting (no NAT) to 192.168.10.106:5060:
NOTIFY sip:106 at 192.168.10.106:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK4c6e7a99;rport
Max-Forwards: 70
From: <sip:101 at 192.168.10.4>;tag=as31fae48c
To: <sip:106 at 192.168.10.4>;tag=395955848
Contact: <sip:101 at 192.168.10.4:5060>
Call-ID: 413681140-5060-317 at BJC.BGI.BA.BAG
CSeq: 274 NOTIFY
User-Agent: FPBX-13.0.195.19(13.23.1)
Subscription-State: active
Event: dialog
Content-Type: application/dialog-info+xml
Content-Length: 205
<?xml version="1.0"?>
<dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="172" state="full" entity="sip:101 at 192.168.10.4">
<dialog id="101">
<state>terminated</state>
</dialog>
</dialog-info>
---
== Extension Changed 101[ext-local] new state Idle for Notify User 106
== Extension Changed 101[ext-local] new state Idle for Notify User 103
-- Executing [s at macro-hangupcall:4] Hangup("SIP/106-000000b3", "") in new stack
== Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/106-000000b3' in macro 'hangupcall'
== Spawn extension (ext-local, h, 1) exited non-zero on 'SIP/106-000000b3'
== Extension Changed 101[ext-local] new state Idle for Notify User 105
== Extension Changed 106[ext-local] new state Idle for Notify User 103
== Extension Changed 106[ext-local] new state Idle for Notify User 100
== Extension Changed 106[ext-local] new state Idle for Notify User 104
== Extension Changed 106[ext-local] new state Idle for Notify User 105
Retransmitting #1 (no NAT) to 192.168.10.106:5060:
NOTIFY sip:106 at 192.168.10.106:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK4c6e7a99;rport
Max-Forwards: 70
From: <sip:101 at 192.168.10.4>;tag=as31fae48c
To: <sip:106 at 192.168.10.4>;tag=395955848
Contact: <sip:101 at 192.168.10.4:5060>
Call-ID: 413681140-5060-317 at BJC.BGI.BA.BAG
CSeq: 274 NOTIFY
User-Agent: FPBX-13.0.195.19(13.23.1)
Subscription-State: active
Event: dialog
Content-Type: application/dialog-info+xml
Content-Length: 205
<?xml version="1.0"?>
<dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="172" state="full" entity="sip:101 at 192.168.10.4">
<dialog id="101">
<state>terminated</state>
</dialog>
</dialog-info>
---
<--- SIP read from UDP:192.168.10.106:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK4c6e7a99;rport=5060
From: <sip:101 at 192.168.10.4>;tag=as31fae48c
To: <sip:106 at 192.168.10.4>;tag=395955848
Call-ID: 413681140-5060-317 at BJC.BGI.BA.BAG
CSeq: 274 NOTIFY
Contact: <sip:106 at 192.168.10.106:5060>
Supported: replaces, path, timer
User-Agent: Grandstream GXP2110 1.0.8.6
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
Content-Length: 0
<------------->
--- (11 headers 0 lines) ---
<--- SIP read from UDP:192.168.10.106:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK4c6e7a99;rport=5060
From: <sip:101 at 192.168.10.4>;tag=as31fae48c
To: <sip:106 at 192.168.10.4>;tag=395955848
Call-ID: 413681140-5060-317 at BJC.BGI.BA.BAG
CSeq: 274 NOTIFY
Contact: <sip:106 at 192.168.10.106:5060>
Supported: replaces, path, timer
User-Agent: Grandstream GXP2110 1.0.8.6
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
Content-Length: 0
<------------->
--- (11 headers 0 lines) ---
Here I'm attaching a SIP debug for peer 106, calling 107, both are set to iLBC, neither can hear each other.
freepbx*CLI> sip set debug peer 106
SIP Debugging Enabled for IP: 192.168.10.106
<--- SIP read from UDP:192.168.10.106:5060 --->
INVITE sip:107 at 192.168.10.4 SIP/2.0
Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK1551511013;rport
From: <sip:106 at 192.168.10.4>;tag=118405106
To: <sip:107 at 192.168.10.4>
Call-ID: 1224405074-5060-340 at BJC.BGI.BA.BAG
CSeq: 1120 INVITE
Contact: <sip:106 at 192.168.10.106:5060>
X-Grandstream-PBX: true
Max-Forwards: 70
User-Agent: Grandstream GXP2110 1.0.8.6
Privacy: none
P-Preferred-Identity: <sip:106 at 192.168.10.4>
Supported: replaces, path, timer
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
Content-Type: application/sdp
Accept: application/sdp, application/dtmf-relay
Content-Length: 405
v=0
o=106 8000 8000 IN IP4 192.168.10.106
s=SIP Call
c=IN IP4 192.168.10.106
t=0 0
m=audio 5004 RTP/AVP 97 8 4 18 9 0 2 101
a=sendrecv
a=rtpmap:97 iLBC/8000
a=fmtp:97 mode=30
a=ptime:60
a=rtpmap:8 PCMA/8000
a=rtpmap:4 G723/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
<------------->
--- (17 headers 19 lines) ---
Sending to 192.168.10.106:5060 (no NAT)
Sending to 192.168.10.106:5060 (no NAT)
Using INVITE request as basis request - 1224405074-5060-340 at BJC.BGI.BA.BAG
Found peer '106' for '106' from 192.168.10.106:5060
<--- Reliably Transmitting (no NAT) to 192.168.10.106:5060 --->
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK1551511013;received=192.168.10.106;rport=5060
From: <sip:106 at 192.168.10.4>;tag=118405106
To: <sip:107 at 192.168.10.4>;tag=as77dff450
Call-ID: 1224405074-5060-340 at BJC.BGI.BA.BAG
CSeq: 1120 INVITE
Server: FPBX-13.0.195.19(13.23.1)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="2242e0f6"
Content-Length: 0
<------------>
Scheduling destruction of SIP dialog '1224405074-5060-340 at BJC.BGI.BA.BAG' in 6400 ms (Method: INVITE)
<--- SIP read from UDP:192.168.10.106:5060 --->
ACK sip:107 at 192.168.10.4 SIP/2.0
Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK1551511013;rport
From: <sip:106 at 192.168.10.4>;tag=118405106
To: <sip:107 at 192.168.10.4>;tag=as77dff450
Call-ID: 1224405074-5060-340 at BJC.BGI.BA.BAG
CSeq: 1120 ACK
Content-Length: 0
<------------->
--- (7 headers 0 lines) ---
<--- SIP read from UDP:192.168.10.106:5060 --->
INVITE sip:107 at 192.168.10.4 SIP/2.0
Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK587288699;rport
From: <sip:106 at 192.168.10.4>;tag=118405106
To: <sip:107 at 192.168.10.4>
Call-ID: 1224405074-5060-340 at BJC.BGI.BA.BAG
CSeq: 1121 INVITE
Contact: <sip:106 at 192.168.10.106:5060>
Authorization: Digest username="106", realm="asterisk", nonce="2242e0f6", uri="sip:107 at 192.168.10.4", response="572c1e8497982e16b5f85ecf869e980b", algorithm=MD5
X-Grandstream-PBX: true
Max-Forwards: 70
User-Agent: Grandstream GXP2110 1.0.8.6
Privacy: none
P-Preferred-Identity: <sip:106 at 192.168.10.4>
Supported: replaces, path, timer
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
Content-Type: application/sdp
Accept: application/sdp, application/dtmf-relay
Content-Length: 405
v=0
o=106 8000 8000 IN IP4 192.168.10.106
s=SIP Call
c=IN IP4 192.168.10.106
t=0 0
m=audio 5004 RTP/AVP 97 8 4 18 9 0 2 101
a=sendrecv
a=rtpmap:97 iLBC/8000
a=fmtp:97 mode=30
a=ptime:60
a=rtpmap:8 PCMA/8000
a=rtpmap:4 G723/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:2 G726-32/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
<------------->
--- (18 headers 19 lines) ---
Sending to 192.168.10.106:5060 (no NAT)
Using INVITE request as basis request - 1224405074-5060-340 at BJC.BGI.BA.BAG
Found peer '106' for '106' from 192.168.10.106:5060
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
Found RTP audio format 97
Found RTP audio format 8
Found RTP audio format 4
Found RTP audio format 18
Found RTP audio format 9
Found RTP audio format 0
Found RTP audio format 2
Found RTP audio format 101
Found audio description format iLBC for ID 97
Found audio description format PCMA for ID 8
Found audio description format G723 for ID 4
Found audio description format G729 for ID 18
Found audio description format G722 for ID 9
Found audio description format PCMU for ID 0
Found audio description format G726-32 for ID 2
Found audio description format telephone-event for ID 101
Capabilities: us - (ilbc|g729|ulaw|alaw|g722), peer - audio=(ulaw|g726|g723|alaw|g722|g729|ilbc)/video=(nothing)/text=(nothing), combined - (ilbc|g729|ulaw|alaw|g722)
Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|)
> 0xb7452bd0 -- Strict RTP learning after remote address set to: 192.168.10.106:5004
Peer audio RTP is at port 192.168.10.106:5004
Looking for 107 in from-internal (domain 192.168.10.4)
== Extension Changed 106[ext-local] new state InUse for Notify User 103
== Extension Changed 106[ext-local] new state InUse for Notify User 100
== Extension Changed 106[ext-local] new state InUse for Notify User 104
== Extension Changed 106[ext-local] new state InUse for Notify User 105
sip_route_dump: route/path hop: <sip:106 at 192.168.10.106:5060>
<--- Transmitting (no NAT) to 192.168.10.106:5060 --->
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK587288699;received=192.168.10.106;rport=5060
From: <sip:106 at 192.168.10.4>;tag=118405106
To: <sip:107 at 192.168.10.4>
Call-ID: 1224405074-5060-340 at BJC.BGI.BA.BAG
CSeq: 1121 INVITE
Server: FPBX-13.0.195.19(13.23.1)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: <sip:107 at 192.168.10.4:5060>
Content-Length: 0
<------------>
-- Executing [107 at from-internal:1] GotoIf("SIP/106-000000bc", "1?ext-local,107,1:followme-check,107,1") in new stack
-- Goto (ext-local,107,1)
-- Executing [107 at ext-local:1] Set("SIP/106-000000bc", "__RINGTIMER=15") in new stack
-- Executing [107 at ext-local:2] Macro("SIP/106-000000bc", "exten-vm,novm,107,0,0,0") in new stack
-- Executing [s at macro-exten-vm:1] Macro("SIP/106-000000bc", "user-callerid,") in new stack
-- Executing [s at macro-user-callerid:1] Set("SIP/106-000000bc", "TOUCH_MONITOR=1544206193.1628") in new stack
-- Executing [s at macro-user-callerid:2] Set("SIP/106-000000bc", "AMPUSER=106") in new stack
-- Executing [s at macro-user-callerid:3] GotoIf("SIP/106-000000bc", "0?report") in new stack
-- Executing [s at macro-user-callerid:4] ExecIf("SIP/106-000000bc", "1?Set(REALCALLERIDNUM=106)") in new stack
-- Executing [s at macro-user-callerid:5] Set("SIP/106-000000bc", "AMPUSER=106") in new stack
-- Executing [s at macro-user-callerid:6] GotoIf("SIP/106-000000bc", "0?limit") in new stack
-- Executing [s at macro-user-callerid:7] Set("SIP/106-000000bc", "AMPUSERCIDNAME=Marianela Lopez") in new stack
-- Executing [s at macro-user-callerid:8] ExecIf("SIP/106-000000bc", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
-- Executing [s at macro-user-callerid:9] GotoIf("SIP/106-000000bc", "0?report") in new stack
-- Executing [s at macro-user-callerid:10] Set("SIP/106-000000bc", "AMPUSERCID=106") in new stack
-- Executing [s at macro-user-callerid:11] Set("SIP/106-000000bc", "__DIAL_OPTIONS=Ttr") in new stack
-- Executing [s at macro-user-callerid:12] Set("SIP/106-000000bc", "CALLERID(all)="Marianela Lopez" <106>") in new stack
-- Executing [s at macro-user-callerid:13] GotoIf("SIP/106-000000bc", "0?limit") in new stack
-- Executing [s at macro-user-callerid:14] ExecIf("SIP/106-000000bc", "0?Set(GROUP(concurrency_limit)=106)") in new stack
-- Executing [s at macro-user-callerid:15] GosubIf("SIP/106-000000bc", "7?sub-ccss,s,1(macro-exten-vm,107)") in new stack
-- Executing [s at sub-ccss:1] ExecIf("SIP/106-000000bc", "0?Return()") in new stack
-- Executing [s at sub-ccss:2] Set("SIP/106-000000bc", "CCSS_SETUP=TRUE") in new stack
-- Executing [s at sub-ccss:3] GosubIf("SIP/106-000000bc", "0?monitor_config,1(macro-exten-vm,107):monitor_default,1(macro-exten-vm,107)") in new stack
-- Executing [monitor_default at sub-ccss:1] GotoIf("SIP/106-000000bc", "1?is_exten") in new stack
-- Goto (sub-ccss,monitor_default,4)
-- Executing [monitor_default at sub-ccss:4] Set("SIP/106-000000bc", "CALLCOMPLETION(cc_monitor_policy)=generic") in new stack
-- Executing [monitor_default at sub-ccss:5] Set("SIP/106-000000bc", "CALLCOMPLETION(cc_max_monitors)=5") in new stack
-- Executing [monitor_default at sub-ccss:6] Return("SIP/106-000000bc", "TRUE") in new stack
-- Executing [s at sub-ccss:4] GosubIf("SIP/106-000000bc", "7?agent_config,1():agent_default,1()") in new stack
-- Executing [agent_config at sub-ccss:1] Set("SIP/106-000000bc", "CALLCOMPLETION(cc_agent_policy)=generic") in new stack
-- Executing [agent_config at sub-ccss:2] Set("SIP/106-000000bc", "CALLCOMPLETION(cc_offer_timer)=30") in new stack
-- Executing [agent_config at sub-ccss:3] Set("SIP/106-000000bc", "CALLCOMPLETION(ccbs_available_timer)=") in new stack
-- Executing [agent_config at sub-ccss:4] Set("SIP/106-000000bc", "CALLCOMPLETION(ccnr_available_timer)=") in new stack
-- Executing [agent_config at sub-ccss:5] Set("SIP/106-000000bc", "CALLCOMPLETION(cc_callback_macro)=ccss-default") in new stack
[2018-12-07 15:09:54] WARNING[562][C-00000081]: ccss.c:1012 ast_set_cc_callback_macro: Usage of cc_callback_macro is deprecated. Please use cc_callback_sub instead.
-- Executing [agent_config at sub-ccss:6] ExecIf("SIP/106-000000bc", "1?Set(CALLCOMPLETION(cc_recall_timer)=)") in new stack
-- Executing [agent_config at sub-ccss:7] ExecIf("SIP/106-000000bc", "1?Set(CALLCOMPLETION(cc_max_agents)=)") in new stack
-- Executing [agent_config at sub-ccss:8] ExecIf("SIP/106-000000bc", "0?Set(CALLCOMPLETION(cc_agent_dialstring)=Local/106_107 at from-ccss-)") in new stack
-- Executing [agent_config at sub-ccss:9] Set("SIP/106-000000bc", "CALLCOMPLETION(cc_callback_macro)=ccss-default") in new stack
[2018-12-07 15:09:54] WARNING[562][C-00000081]: ccss.c:1012 ast_set_cc_callback_macro: Usage of cc_callback_macro is deprecated. Please use cc_callback_sub instead.
-- Executing [agent_config at sub-ccss:10] Return("SIP/106-000000bc", "") in new stack
-- Executing [s at sub-ccss:5] Set("SIP/106-000000bc", "DB(AMPUSER/106/ccss/last_number)=107") in new stack
-- Executing [s at sub-ccss:6] Return("SIP/106-000000bc", "") in new stack
-- Executing [s at macro-user-callerid:16] ExecIf("SIP/106-000000bc", "0?Set(CHANNEL(language)=)") in new stack
-- Executing [s at macro-user-callerid:17] NoOp("SIP/106-000000bc", "Macro Depth is 2") in new stack
-- Executing [s at macro-user-callerid:18] GotoIf("SIP/106-000000bc", "1?report2:macroerror") in new stack
-- Goto (macro-user-callerid,s,19)
-- Executing [s at macro-user-callerid:19] GotoIf("SIP/106-000000bc", "0?continue") in new stack
-- Executing [s at macro-user-callerid:20] ExecIf("SIP/106-000000bc", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
-- Executing [s at macro-user-callerid:21] Set("SIP/106-000000bc", "__TTL=64") in new stack
-- Executing [s at macro-user-callerid:22] GotoIf("SIP/106-000000bc", "1?continue") in new stack
-- Goto (macro-user-callerid,s,38)
-- Executing [s at macro-user-callerid:38] Set("SIP/106-000000bc", "CALLERID(number)=106") in new stack
-- Executing [s at macro-user-callerid:39] Set("SIP/106-000000bc", "CALLERID(name)=Marianela Lopez") in new stack
-- Executing [s at macro-user-callerid:40] GotoIf("SIP/106-000000bc", "0?cnum") in new stack
-- Executing [s at macro-user-callerid:41] Set("SIP/106-000000bc", "CDR(cnam)=Marianela Lopez") in new stack
-- Executing [s at macro-user-callerid:42] Set("SIP/106-000000bc", "CDR(cnum)=106") in new stack
-- Executing [s at macro-user-callerid:43] Set("SIP/106-000000bc", "CHANNEL(language)=es") in new stack
-- Executing [s at macro-exten-vm:2] Set("SIP/106-000000bc", "RingGroupMethod=none") in new stack
-- Executing [s at macro-exten-vm:3] Set("SIP/106-000000bc", "__EXTTOCALL=107") in new stack
-- Executing [s at macro-exten-vm:4] Set("SIP/106-000000bc", "__PICKUPMARK=107") in new stack
-- Executing [s at macro-exten-vm:5] Set("SIP/106-000000bc", "RT=") in new stack
-- Executing [s at macro-exten-vm:6] Gosub("SIP/106-000000bc", "sub-record-check,s,1(exten,107,dontcare)") in new stack
-- Executing [s at sub-record-check:1] GotoIf("SIP/106-000000bc", "0?initialized") in new stack
-- Executing [s at sub-record-check:2] Set("SIP/106-000000bc", "__REC_STATUS=INITIALIZED") in new stack
-- Executing [s at sub-record-check:3] Set("SIP/106-000000bc", "NOW=1544206194") in new stack
-- Executing [s at sub-record-check:4] Set("SIP/106-000000bc", "__DAY=07") in new stack
-- Executing [s at sub-record-check:5] Set("SIP/106-000000bc", "__MONTH=12") in new stack
-- Executing [s at sub-record-check:6] Set("SIP/106-000000bc", "__YEAR=2018") in new stack
-- Executing [s at sub-record-check:7] Set("SIP/106-000000bc", "__TIMESTR=20181207-150954") in new stack
-- Executing [s at sub-record-check:8] Set("SIP/106-000000bc", "__FROMEXTEN=106") in new stack
-- Executing [s at sub-record-check:9] Set("SIP/106-000000bc", "__MON_FMT=wav") in new stack
-- Executing [s at sub-record-check:10] NoOp("SIP/106-000000bc", "Recordings initialized") in new stack
-- Executing [s at sub-record-check:11] ExecIf("SIP/106-000000bc", "0?Set(ARG3=dontcare)") in new stack
-- Executing [s at sub-record-check:12] Set("SIP/106-000000bc", "REC_POLICY_MODE_SAVE=") in new stack
-- Executing [s at sub-record-check:13] ExecIf("SIP/106-000000bc", "0?Set(REC_STATUS=NO)") in new stack
-- Executing [s at sub-record-check:14] GotoIf("SIP/106-000000bc", "5?checkaction") in new stack
-- Goto (sub-record-check,s,17)
-- Executing [s at sub-record-check:17] GotoIf("SIP/106-000000bc", "1?sub-record-check,exten,1") in new stack
-- Goto (sub-record-check,exten,1)
-- Executing [exten at sub-record-check:1] NoOp("SIP/106-000000bc", "Exten Recording Check between 106 and 107") in new stack
-- Executing [exten at sub-record-check:2] Set("SIP/106-000000bc", "CALLTYPE=internal") in new stack
-- Executing [exten at sub-record-check:3] ExecIf("SIP/106-000000bc", "0?Set(CALLTYPE=)") in new stack
-- Executing [exten at sub-record-check:4] Set("SIP/106-000000bc", "CALLEE=dontcare") in new stack
-- Executing [exten at sub-record-check:5] ExecIf("SIP/106-000000bc", "0?Set(CALLEE=dontcare)") in new stack
-- Executing [exten at sub-record-check:6] GotoIf("SIP/106-000000bc", "0?callee") in new stack
-- Executing [exten at sub-record-check:7] GotoIf("SIP/106-000000bc", "1?caller") in new stack
-- Goto (sub-record-check,exten,13)
-- Executing [exten at sub-record-check:13] Set("SIP/106-000000bc", "RECMODE=dontcare") in new stack
-- Executing [exten at sub-record-check:14] ExecIf("SIP/106-000000bc", "0?Set(RECMODE=dontcare)") in new stack
-- Executing [exten at sub-record-check:15] ExecIf("SIP/106-000000bc", "1?Set(RECMODE=dontcare)") in new stack
-- Executing [exten at sub-record-check:16] Gosub("SIP/106-000000bc", "recordcheck,1(dontcare,internal,107)") in new stack
-- Executing [recordcheck at sub-record-check:1] NoOp("SIP/106-000000bc", "Starting recording check against dontcare") in new stack
-- Executing [recordcheck at sub-record-check:2] Goto("SIP/106-000000bc", "dontcare") in new stack
-- Goto (sub-record-check,recordcheck,3)
-- Executing [recordcheck at sub-record-check:3] Return("SIP/106-000000bc", "") in new stack
-- Executing [exten at sub-record-check:17] Return("SIP/106-000000bc", "") in new stack
-- Executing [s at macro-exten-vm:7] GotoIf("SIP/106-000000bc", "1?macrodial") in new stack
-- Goto (macro-exten-vm,s,13)
-- Executing [s at macro-exten-vm:13] GosubIf("SIP/106-000000bc", "0?clrheader,1()") in new stack
-- Executing [s at macro-exten-vm:14] Macro("SIP/106-000000bc", "dial-one,,Ttr,107") in new stack
-- Executing [s at macro-dial-one:1] Set("SIP/106-000000bc", "DEXTEN=107") in new stack
-- Executing [s at macro-dial-one:2] ExecIf("SIP/106-000000bc", "0?Set(__EXTTOCALL=107)") in new stack
-- Executing [s at macro-dial-one:3] Set("SIP/106-000000bc", "DIALSTATUS_CW=") in new stack
-- Executing [s at macro-dial-one:4] GosubIf("SIP/106-000000bc", "0?screen,1()") in new stack
-- Executing [s at macro-dial-one:5] GosubIf("SIP/106-000000bc", "0?cf,1()") in new stack
-- Executing [s at macro-dial-one:6] GotoIf("SIP/106-000000bc", "1?skip1") in new stack
-- Goto (macro-dial-one,s,9)
-- Executing [s at macro-dial-one:9] GotoIf("SIP/106-000000bc", "0?nodial") in new stack
-- Executing [s at macro-dial-one:10] GotoIf("SIP/106-000000bc", "0?continue") in new stack
-- Executing [s at macro-dial-one:11] Set("SIP/106-000000bc", "EXTHASCW=ENABLED") in new stack
-- Executing [s at macro-dial-one:12] GotoIf("SIP/106-000000bc", "0?next1:cwinusebusy") in new stack
-- Goto (macro-dial-one,s,24)
-- Executing [s at macro-dial-one:24] GotoIf("SIP/106-000000bc", "0?next3:continue") in new stack
-- Goto (macro-dial-one,s,26)
-- Executing [s at macro-dial-one:26] GotoIf("SIP/106-000000bc", "0?nodial") in new stack
-- Executing [s at macro-dial-one:27] GosubIf("SIP/106-000000bc", "1?dstring,1():dlocal,1()") in new stack
-- Executing [dstring at macro-dial-one:1] Set("SIP/106-000000bc", "DSTRING=") in new stack
-- Executing [dstring at macro-dial-one:2] Set("SIP/106-000000bc", "DEVICES=107") in new stack
-- Executing [dstring at macro-dial-one:3] ExecIf("SIP/106-000000bc", "0?Return()") in new stack
-- Executing [dstring at macro-dial-one:4] ExecIf("SIP/106-000000bc", "0?Set(DEVICES=07)") in new stack
-- Executing [dstring at macro-dial-one:5] Set("SIP/106-000000bc", "LOOPCNT=1") in new stack
-- Executing [dstring at macro-dial-one:6] Set("SIP/106-000000bc", "ITER=1") in new stack
-- Executing [dstring at macro-dial-one:7] Set("SIP/106-000000bc", "THISDIAL=SIP/107") in new stack
-- Executing [dstring at macro-dial-one:8] GosubIf("SIP/106-000000bc", "1?zap2dahdi,1()") in new stack
-- Executing [zap2dahdi at macro-dial-one:1] ExecIf("SIP/106-000000bc", "0?Return()") in new stack
-- Executing [zap2dahdi at macro-dial-one:2] Set("SIP/106-000000bc", "NEWDIAL=") in new stack
-- Executing [zap2dahdi at macro-dial-one:3] Set("SIP/106-000000bc", "LOOPCNT2=1") in new stack
-- Executing [zap2dahdi at macro-dial-one:4] Set("SIP/106-000000bc", "ITER2=1") in new stack
-- Executing [zap2dahdi at macro-dial-one:5] Set("SIP/106-000000bc", "THISPART2=SIP/107") in new stack
-- Executing [zap2dahdi at macro-dial-one:6] ExecIf("SIP/106-000000bc", "0?Set(THISPART2=DAHDI/107)") in new stack
-- Executing [zap2dahdi at macro-dial-one:7] Set("SIP/106-000000bc", "NEWDIAL=SIP/107&") in new stack
-- Executing [zap2dahdi at macro-dial-one:8] Set("SIP/106-000000bc", "ITER2=2") in new stack
-- Executing [zap2dahdi at macro-dial-one:9] GotoIf("SIP/106-000000bc", "0?begin2") in new stack
-- Executing [zap2dahdi at macro-dial-one:10] Set("SIP/106-000000bc", "THISDIAL=SIP/107") in new stack
-- Executing [zap2dahdi at macro-dial-one:11] Return("SIP/106-000000bc", "") in new stack
-- Executing [dstring at macro-dial-one:9] GotoIf("SIP/106-000000bc", "1?docheck") in new stack
-- Goto (macro-dial-one,dstring,14)
-- Executing [dstring at macro-dial-one:14] GotoIf("SIP/106-000000bc", "0?skipset") in new stack
-- Executing [dstring at macro-dial-one:15] Set("SIP/106-000000bc", "DSTRING=SIP/107&") in new stack
-- Executing [dstring at macro-dial-one:16] Set("SIP/106-000000bc", "ITER=2") in new stack
-- Executing [dstring at macro-dial-one:17] GotoIf("SIP/106-000000bc", "0?begin") in new stack
-- Executing [dstring at macro-dial-one:18] ExecIf("SIP/106-000000bc", "0?Return()") in new stack
-- Executing [dstring at macro-dial-one:19] Set("SIP/106-000000bc", "DSTRING=SIP/107") in new stack
-- Executing [dstring at macro-dial-one:20] Return("SIP/106-000000bc", "") in new stack
-- Executing [s at macro-dial-one:28] GotoIf("SIP/106-000000bc", "0?nodial") in new stack
-- Executing [s at macro-dial-one:29] GotoIf("SIP/106-000000bc", "0?skiptrace") in new stack
-- Executing [s at macro-dial-one:30] GosubIf("SIP/106-000000bc", "1?ctset,1():ctclear,1()") in new stack
-- Executing [ctset at macro-dial-one:1] Set("SIP/106-000000bc", "DB(CALLTRACE/107)=106") in new stack
-- Executing [ctset at macro-dial-one:2] Return("SIP/106-000000bc", "") in new stack
-- Executing [s at macro-dial-one:31] Set("SIP/106-000000bc", "D_OPTIONS=Ttr") in new stack
-- Executing [s at macro-dial-one:32] GosubIf("SIP/106-000000bc", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
-- Executing [s at macro-dial-one:33] NoOp("SIP/106-000000bc", "Blind Transfer: , Attended Transfer: , User: 106, Alert Info: ") in new stack
-- Executing [s at macro-dial-one:34] ExecIf("SIP/106-000000bc", "1?Set(ALERT_INFO=)") in new stack
-- Executing [s at macro-dial-one:35] ExecIf("SIP/106-000000bc", "0?Set(ALERT_INFO=)") in new stack
-- Executing [s at macro-dial-one:36] ExecIf("SIP/106-000000bc", "0?Set(ALERT_INFO=)") in new stack
-- Executing [s at macro-dial-one:37] ExecIf("SIP/106-000000bc", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
-- Executing [s at macro-dial-one:38] ExecIf("SIP/106-000000bc", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
-- Executing [s at macro-dial-one:39] GosubIf("SIP/106-000000bc", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
-- Executing [s at macro-dial-one:40] ExecIf("SIP/106-000000bc", "0?Set(CHANNEL(musicclass)=)") in new stack
-- Executing [s at macro-dial-one:41] GosubIf("SIP/106-000000bc", "0?qwait,1()") in new stack
-- Executing [s at macro-dial-one:42] Set("SIP/106-000000bc", "__CWIGNORE=") in new stack
-- Executing [s at macro-dial-one:43] Set("SIP/106-000000bc", "__KEEPCID=TRUE") in new stack
-- Executing [s at macro-dial-one:44] GotoIf("SIP/106-000000bc", "0?usegoto,1") in new stack
-- Executing [s at macro-dial-one:45] GotoIf("SIP/106-000000bc", "0?godial") in new stack
-- Executing [s at macro-dial-one:46] Gosub("SIP/106-000000bc", "sub-presencestate-display,s,1(107)") in new stack
-- Executing [s at sub-presencestate-display:1] Goto("SIP/106-000000bc", "state-not_set,1") in new stack
-- Goto (sub-presencestate-display,state-not_set,1)
-- Executing [state-not_set at sub-presencestate-display:1] Set("SIP/106-000000bc", "PRESENCESTATE_DISPLAY=") in new stack
-- Executing [state-not_set at sub-presencestate-display:2] Return("SIP/106-000000bc", "") in new stack
-- Executing [s at macro-dial-one:47] Set("SIP/106-000000bc", "CONNECTEDLINE(name,i)=Rodrigo Martin") in new stack
-- Executing [s at macro-dial-one:48] Set("SIP/106-000000bc", "CONNECTEDLINE(num)=107") in new stack
-- Executing [s at macro-dial-one:49] Set("SIP/106-000000bc", "D_OPTIONS=TtrI") in new stack
-- Executing [s at macro-dial-one:50] Macro("SIP/106-000000bc", "dialout-one-predial-hook,") in new stack
-- Executing [s at macro-dialout-one-predial-hook:1] MacroExit("SIP/106-000000bc", "") in new stack
-- Executing [s at macro-dial-one:51] ExecIf("SIP/106-000000bc", "0?Set(D_OPTIONS=trII)") in new stack
-- Executing [s at macro-dial-one:52] NoOp("SIP/106-000000bc", "") in new stack
-- Executing [s at macro-dial-one:53] ExecIf("SIP/106-000000bc", "0?Set(D_OPTIONS=TtrIg)") in new stack
-- Executing [s at macro-dial-one:54] Dial("SIP/106-000000bc", "SIP/107,,TtrIb(func-apply-sipheaders^s^1)") in new stack
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
-- SIP/107-000000bd Internal Gosub(func-apply-sipheaders,s,1) start
-- Executing [s at func-apply-sipheaders:1] NoOp("SIP/107-000000bd", "Applying SIP Headers to channel") in new stack
-- Executing [s at func-apply-sipheaders:2] Set("SIP/107-000000bd", "SIPHEADERKEYS=") in new stack
-- Executing [s at func-apply-sipheaders:3] ExecIf("SIP/107-000000bd", "0?Set(Rheader=1)") in new stack
-- Executing [s at func-apply-sipheaders:4] While("SIP/107-000000bd", "0") in new stack
-- Jumping to priority 7
-- Executing [s at func-apply-sipheaders:8] ExecIf("SIP/107-000000bd", "0?SIPRemoveHeader(Alert-Info:)") in new stack
-- Executing [s at func-apply-sipheaders:9] Return("SIP/107-000000bd", "") in new stack
== Spawn extension (from-internal, 107, 1) exited non-zero on 'SIP/107-000000bd'
-- SIP/107-000000bd Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
set_destination: Parsing <sip:106 at 192.168.10.106:5060> for address/port to send to
set_destination: set destination to 192.168.10.106:5060
Reliably Transmitting (no NAT) to 192.168.10.106:5060:
NOTIFY sip:106 at 192.168.10.106:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK59fae04a;rport
Max-Forwards: 70
From: <sip:107 at 192.168.10.4>;tag=as76b7215d
To: <sip:106 at 192.168.10.4>;tag=1818557175
Contact: <sip:107 at 192.168.10.4:5060>
Call-ID: 156301877-5060-322 at BJC.BGI.BA.BAG
CSeq: 259 NOTIFY
User-Agent: FPBX-13.0.195.19(13.23.1)
Subscription-State: active
Event: dialog
Content-Type: application/dialog-info+xml
Content-Length: 222
<?xml version="1.0"?>
<dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="157" state="full" entity="sip:107 at 192.168.10.4">
<dialog id="107" direction="recipient">
<state>early</state>
</dialog>
</dialog-info>
---
== Extension Changed 107[ext-local] new state Ringing for Notify User 106
== Extension Changed 107[ext-local] new state Ringing for Notify User 105
== Extension Changed 107[ext-local] new state Ringing for Notify User 103
== Extension Changed 107[ext-local] new state Ringing for Notify User 100
== Extension Changed 107[ext-local] new state Ringing for Notify User 104
-- Called SIP/107
<--- Transmitting (no NAT) to 192.168.10.106:5060 --->
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK587288699;received=192.168.10.106;rport=5060
From: <sip:106 at 192.168.10.4>;tag=118405106
To: <sip:107 at 192.168.10.4>;tag=as4b4d062d
Call-ID: 1224405074-5060-340 at BJC.BGI.BA.BAG
CSeq: 1121 INVITE
Server: FPBX-13.0.195.19(13.23.1)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: <sip:107 at 192.168.10.4:5060>
Content-Length: 0
<------------>
-- Connected line update to SIP/106-000000bc prevented.
<--- SIP read from UDP:192.168.10.106:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK59fae04a;rport=5060
From: <sip:107 at 192.168.10.4>;tag=as76b7215d
To: <sip:106 at 192.168.10.4>;tag=1818557175
Call-ID: 156301877-5060-322 at BJC.BGI.BA.BAG
CSeq: 259 NOTIFY
Contact: <sip:106 at 192.168.10.106:5060>
Supported: replaces, path, timer
User-Agent: Grandstream GXP2110 1.0.8.6
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
Content-Length: 0
<------------->
--- (11 headers 0 lines) ---
set_destination: Parsing <sip:106 at 192.168.10.106:5060> for address/port to send to
set_destination: set destination to 192.168.10.106:5060
-- SIP/107-000000bd is ringing
Reliably Transmitting (no NAT) to 192.168.10.106:5060:
NOTIFY sip:106 at 192.168.10.106:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK31bafbab;rport
Max-Forwards: 70
From: <sip:107 at 192.168.10.4>;tag=as76b7215d
To: <sip:106 at 192.168.10.4>;tag=1818557175
Contact: <sip:107 at 192.168.10.4:5060>
Call-ID: 156301877-5060-322 at BJC.BGI.BA.BAG
CSeq: 260 NOTIFY
User-Agent: FPBX-13.0.195.19(13.23.1)
Subscription-State: active
Event: dialog
Content-Type: application/dialog-info+xml
Content-Length: 222
<?xml version="1.0"?>
<dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="158" state="full" entity="sip:107 at 192.168.10.4">
<dialog id="107" direction="recipient">
<state>early</state>
</dialog>
</dialog-info>
---
== Extension Changed 107[ext-local] new state Ringing for Notify User 106
<--- Transmitting (no NAT) to 192.168.10.106:5060 --->
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK587288699;received=192.168.10.106;rport=5060
From: <sip:106 at 192.168.10.4>;tag=118405106
To: <sip:107 at 192.168.10.4>;tag=as4b4d062d
Call-ID: 1224405074-5060-340 at BJC.BGI.BA.BAG
CSeq: 1121 INVITE
Server: FPBX-13.0.195.19(13.23.1)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: <sip:107 at 192.168.10.4:5060>
Content-Length: 0
<------------>
== Extension Changed 107[ext-local] new state Ringing for Notify User 105
== Extension Changed 107[ext-local] new state Ringing for Notify User 103
== Extension Changed 107[ext-local] new state Ringing for Notify User 100
== Extension Changed 107[ext-local] new state Ringing for Notify User 104
<--- SIP read from UDP:192.168.10.106:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK31bafbab;rport=5060
From: <sip:107 at 192.168.10.4>;tag=as76b7215d
To: <sip:106 at 192.168.10.4>;tag=1818557175
Call-ID: 156301877-5060-322 at BJC.BGI.BA.BAG
CSeq: 260 NOTIFY
Contact: <sip:106 at 192.168.10.106:5060>
Supported: replaces, path, timer
User-Agent: Grandstream GXP2110 1.0.8.6
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
Content-Length: 0
<------------->
--- (11 headers 0 lines) ---
> 0x918bc88 -- Strict RTP learning after remote address set to: 192.168.10.107:5020
-- Connected line update to SIP/106-000000bc prevented.
-- SIP/107-000000bd answered SIP/106-000000bc
Audio is at 18696
Adding codec ilbc to SDP
Adding codec g729 to SDP
Adding codec ulaw to SDP
Adding codec alaw to SDP
Adding codec g722 to SDP
Adding non-codec 0x1 (telephone-event) to SDP
<--- Reliably Transmitting (no NAT) to 192.168.10.106:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK587288699;received=192.168.10.106;rport=5060
From: <sip:106 at 192.168.10.4>;tag=118405106
To: <sip:107 at 192.168.10.4>;tag=as4b4d062d
Call-ID: 1224405074-5060-340 at BJC.BGI.BA.BAG
CSeq: 1121 INVITE
Server: FPBX-13.0.195.19(13.23.1)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: <sip:107 at 192.168.10.4:5060>
Content-Type: application/sdp
Require: timer
Content-Length: 371
v=0
o=root 986582881 986582881 IN IP4 192.168.10.4
s=Asterisk PBX 13.23.1
c=IN IP4 192.168.10.4
t=0 0
m=audio 18696 RTP/AVP 97 18 0 8 9 101
a=rtpmap:97 iLBC/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=maxptime:150
a=sendrecv
<------------>
set_destination: Parsing <sip:106 at 192.168.10.106:5060> for address/port to send to
set_destination: set destination to 192.168.10.106:5060
Reliably Transmitting (no NAT) to 192.168.10.106:5060:
NOTIFY sip:106 at 192.168.10.106:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK240c6fa9;rport
Max-Forwards: 70
From: <sip:107 at 192.168.10.4>;tag=as76b7215d
To: <sip:106 at 192.168.10.4>;tag=1818557175
Contact: <sip:107 at 192.168.10.4:5060>
Call-ID: 156301877-5060-322 at BJC.BGI.BA.BAG
CSeq: 261 NOTIFY
User-Agent: FPBX-13.0.195.19(13.23.1)
Subscription-State: active
Event: dialog
Content-Type: application/dialog-info+xml
Content-Length: 204
<?xml version="1.0"?>
<dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="159" state="full" entity="sip:107 at 192.168.10.4">
<dialog id="107">
<state>confirmed</state>
</dialog>
</dialog-info>
---
== Extension Changed 107[ext-local] new state InUse for Notify User 106
== Extension Changed 107[ext-local] new state InUse for Notify User 105
== Extension Changed 107[ext-local] new state InUse for Notify User 103
-- Channel SIP/107-000000bd joined 'simple_bridge' basic-bridge <285a31cc-93e9-455f-9d95-6d70f85d7d2a>
== Extension Changed 107[ext-local] new state InUse for Notify User 100
-- Channel SIP/106-000000bc joined 'simple_bridge' basic-bridge <285a31cc-93e9-455f-9d95-6d70f85d7d2a>
== Extension Changed 107[ext-local] new state InUse for Notify User 104
<--- SIP read from UDP:192.168.10.106:5060 --->
ACK sip:107 at 192.168.10.4:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK436859343;rport
From: <sip:106 at 192.168.10.4>;tag=118405106
To: <sip:107 at 192.168.10.4>;tag=as4b4d062d
Call-ID: 1224405074-5060-340 at BJC.BGI.BA.BAG
CSeq: 1121 ACK
Contact: <sip:106 at 192.168.10.106:5060>
X-Grandstream-PBX: true
Max-Forwards: 70
Supported: replaces, path, timer
User-Agent: Grandstream GXP2110 1.0.8.6
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
Content-Length: 0
<------------->
--- (13 headers 0 lines) ---
> 0x918bc88 -- Strict RTP switching to RTP target address 192.168.10.107:5020 as source
Retransmitting #1 (no NAT) to 192.168.10.106:5060:
NOTIFY sip:106 at 192.168.10.106:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK240c6fa9;rport
Max-Forwards: 70
From: <sip:107 at 192.168.10.4>;tag=as76b7215d
To: <sip:106 at 192.168.10.4>;tag=1818557175
Contact: <sip:107 at 192.168.10.4:5060>
Call-ID: 156301877-5060-322 at BJC.BGI.BA.BAG
CSeq: 261 NOTIFY
User-Agent: FPBX-13.0.195.19(13.23.1)
Subscription-State: active
Event: dialog
Content-Type: application/dialog-info+xml
Content-Length: 204
<?xml version="1.0"?>
<dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="159" state="full" entity="sip:107 at 192.168.10.4">
<dialog id="107">
<state>confirmed</state>
</dialog>
</dialog-info>
---
<--- SIP read from UDP:192.168.10.106:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK240c6fa9;rport=5060
From: <sip:107 at 192.168.10.4>;tag=as76b7215d
To: <sip:106 at 192.168.10.4>;tag=1818557175
Call-ID: 156301877-5060-322 at BJC.BGI.BA.BAG
CSeq: 261 NOTIFY
Contact: <sip:106 at 192.168.10.106:5060>
Supported: replaces, path, timer
User-Agent: Grandstream GXP2110 1.0.8.6
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
Content-Length: 0
<------------->
--- (11 headers 0 lines) ---
<--- SIP read from UDP:192.168.10.106:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK240c6fa9;rport=5060
From: <sip:107 at 192.168.10.4>;tag=as76b7215d
To: <sip:106 at 192.168.10.4>;tag=1818557175
Call-ID: 156301877-5060-322 at BJC.BGI.BA.BAG
CSeq: 261 NOTIFY
Contact: <sip:106 at 192.168.10.106:5060>
Supported: replaces, path, timer
User-Agent: Grandstream GXP2110 1.0.8.6
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
Content-Length: 0
<------------->
--- (11 headers 0 lines) ---
> 0xb7452bd0 -- Strict RTP switching to RTP target address 192.168.10.106:5004 as source
> 0xb7452bd0 -- Strict RTP learning complete - Locking on source address 192.168.10.106:5004
> 0x918bc88 -- Strict RTP learning complete - Locking on source address 192.168.10.107:5020
-- Channel SIP/107-000000bd left 'simple_bridge' basic-bridge <285a31cc-93e9-455f-9d95-6d70f85d7d2a>
-- Channel SIP/106-000000bc left 'simple_bridge' basic-bridge <285a31cc-93e9-455f-9d95-6d70f85d7d2a>
== Spawn extension (macro-dial-one, s, 54) exited non-zero on 'SIP/106-000000bc' in macro 'dial-one'
== Spawn extension (macro-exten-vm, s, 14) exited non-zero on 'SIP/106-000000bc' in macro 'exten-vm'
== Spawn extension (ext-local, 107, 2) exited non-zero on 'SIP/106-000000bc'
-- Executing [h at ext-local:1] Macro("SIP/106-000000bc", "hangupcall,") in new stack
-- Executing [s at macro-hangupcall:1] GotoIf("SIP/106-000000bc", "1?theend") in new stack
-- Goto (macro-hangupcall,s,3)
-- Executing [s at macro-hangupcall:3] ExecIf("SIP/106-000000bc", "0?Set(CDR(recordingfile)=)") in new stack
-- Executing [s at macro-hangupcall:4] Hangup("SIP/106-000000bc", "") in new stack
== Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/106-000000bc' in macro 'hangupcall'
== Spawn extension (ext-local, h, 1) exited non-zero on 'SIP/106-000000bc'
Scheduling destruction of SIP dialog '1224405074-5060-340 at BJC.BGI.BA.BAG' in 6400 ms (Method: ACK)
== Extension Changed 106[ext-local] new state Idle for Notify User 103
== Extension Changed 106[ext-local] new state Idle for Notify User 100
== Extension Changed 106[ext-local] new state Idle for Notify User 104
== Extension Changed 106[ext-local] new state Idle for Notify User 105
set_destination: Parsing <sip:106 at 192.168.10.106:5060> for address/port to send to
set_destination: set destination to 192.168.10.106:5060
Reliably Transmitting (no NAT) to 192.168.10.106:5060:
NOTIFY sip:106 at 192.168.10.106:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK4064396f;rport
Max-Forwards: 70
From: <sip:107 at 192.168.10.4>;tag=as76b7215d
To: <sip:106 at 192.168.10.4>;tag=1818557175
Contact: <sip:107 at 192.168.10.4:5060>
Call-ID: 156301877-5060-322 at BJC.BGI.BA.BAG
CSeq: 262 NOTIFY
User-Agent: FPBX-13.0.195.19(13.23.1)
Subscription-State: active
Event: dialog
Content-Type: application/dialog-info+xml
Content-Length: 205
<?xml version="1.0"?>
<dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="160" state="full" entity="sip:107 at 192.168.10.4">
<dialog id="107">
<state>terminated</state>
</dialog>
</dialog-info>
---
== Extension Changed 107[ext-local] new state Idle for Notify User 106
== Extension Changed 107[ext-local] new state Idle for Notify User 105
== Extension Changed 107[ext-local] new state Idle for Notify User 103
== Extension Changed 107[ext-local] new state Idle for Notify User 100
== Extension Changed 107[ext-local] new state Idle for Notify User 104
set_destination: Parsing <sip:106 at 192.168.10.106:5060> for address/port to send to
set_destination: set destination to 192.168.10.106:5060
Reliably Transmitting (no NAT) to 192.168.10.106:5060:
BYE sip:106 at 192.168.10.106:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK4726c7de;rport
Max-Forwards: 70
From: <sip:107 at 192.168.10.4>;tag=as4b4d062d
To: <sip:106 at 192.168.10.4>;tag=118405106
Call-ID: 1224405074-5060-340 at BJC.BGI.BA.BAG
CSeq: 102 BYE
User-Agent: FPBX-13.0.195.19(13.23.1)
Proxy-Authorization: Digest username="106", realm="asterisk", algorithm=MD5, uri="sip:192.168.10.4", nonce="2242e0f6", response="0777db9fc88e45ae6380182084debed3"
X-Asterisk-HangupCause: Normal Clearing
X-Asterisk-HangupCauseCode: 16
Content-Length: 0
---
<--- SIP read from UDP:192.168.10.106:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK4064396f;rport=5060
From: <sip:107 at 192.168.10.4>;tag=as76b7215d
To: <sip:106 at 192.168.10.4>;tag=1818557175
Call-ID: 156301877-5060-322 at BJC.BGI.BA.BAG
CSeq: 262 NOTIFY
Contact: <sip:106 at 192.168.10.106:5060>
Supported: replaces, path, timer
User-Agent: Grandstream GXP2110 1.0.8.6
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
Content-Length: 0
<------------->
--- (11 headers 0 lines) ---
<--- SIP read from UDP:192.168.10.106:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK4726c7de;rport=5060
From: <sip:107 at 192.168.10.4>;tag=as4b4d062d
To: <sip:106 at 192.168.10.4>;tag=118405106
Call-ID: 1224405074-5060-340 at BJC.BGI.BA.BAG
CSeq: 102 BYE
Contact: <sip:106 at 192.168.10.106:5060>
Supported: replaces, path, timer
User-Agent: Grandstream GXP2110 1.0.8.6
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
Content-Length: 0
<------------->
--- (11 headers 0 lines) ---
SIP Response message for INCOMING dialog BYE arrived
Really destroying SIP dialog '1224405074-5060-340 at BJC.BGI.BA.BAG' Method: ACK
Really destroying SIP dialog '124920770-5060-1 at BJC.BGI.BA.BAG' Method: REGISTER
And lastly, here is a SIP debug for peer 106, calling 107, both set to g729 and both can hear each other, no audio issues whatsoever
freepbx*CLI> sip set debug peer 106
SIP Debugging Enabled for IP: 192.168.10.106
<--- SIP read from UDP:192.168.10.106:5060 --->
INVITE sip:107 at 192.168.10.4 SIP/2.0
Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK155806354;rport
From: <sip:106 at 192.168.10.4>;tag=1872648879
To: <sip:107 at 192.168.10.4>
Call-ID: 543030694-5060-342 at BJC.BGI.BA.BAG
CSeq: 1140 INVITE
Contact: <sip:106 at 192.168.10.106:5060>
X-Grandstream-PBX: true
Max-Forwards: 70
User-Agent: Grandstream GXP2110 1.0.8.6
Privacy: none
P-Preferred-Identity: <sip:106 at 192.168.10.4>
Supported: replaces, path, timer
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
Content-Type: application/sdp
Accept: application/sdp, application/dtmf-relay
Content-Length: 405
v=0
o=106 8000 8000 IN IP4 192.168.10.106
s=SIP Call
c=IN IP4 192.168.10.106
t=0 0
m=audio 5004 RTP/AVP 0 8 4 18 9 97 2 101
a=sendrecv
a=rtpmap:0 PCMU/8000
a=ptime:20
a=rtpmap:8 PCMA/8000
a=rtpmap:4 G723/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:9 G722/8000
a=rtpmap:97 iLBC/8000
a=fmtp:97 mode=30
a=rtpmap:2 G726-32/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
<------------->
--- (17 headers 19 lines) ---
Sending to 192.168.10.106:5060 (no NAT)
Sending to 192.168.10.106:5060 (no NAT)
Using INVITE request as basis request - 543030694-5060-342 at BJC.BGI.BA.BAG
Found peer '106' for '106' from 192.168.10.106:5060
<--- Reliably Transmitting (no NAT) to 192.168.10.106:5060 --->
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK155806354;received=192.168.10.106;rport=5060
From: <sip:106 at 192.168.10.4>;tag=1872648879
To: <sip:107 at 192.168.10.4>;tag=as3e5472fb
Call-ID: 543030694-5060-342 at BJC.BGI.BA.BAG
CSeq: 1140 INVITE
Server: FPBX-13.0.195.19(13.23.1)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="60045ee1"
Content-Length: 0
<------------>
Scheduling destruction of SIP dialog '543030694-5060-342 at BJC.BGI.BA.BAG' in 6400 ms (Method: INVITE)
<--- SIP read from UDP:192.168.10.106:5060 --->
ACK sip:107 at 192.168.10.4 SIP/2.0
Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK155806354;rport
From: <sip:106 at 192.168.10.4>;tag=1872648879
To: <sip:107 at 192.168.10.4>;tag=as3e5472fb
Call-ID: 543030694-5060-342 at BJC.BGI.BA.BAG
CSeq: 1140 ACK
Content-Length: 0
<------------->
--- (7 headers 0 lines) ---
<--- SIP read from UDP:192.168.10.106:5060 --->
INVITE sip:107 at 192.168.10.4 SIP/2.0
Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK1588148074;rport
From: <sip:106 at 192.168.10.4>;tag=1872648879
To: <sip:107 at 192.168.10.4>
Call-ID: 543030694-5060-342 at BJC.BGI.BA.BAG
CSeq: 1141 INVITE
Contact: <sip:106 at 192.168.10.106:5060>
Authorization: Digest username="106", realm="asterisk", nonce="60045ee1", uri="sip:107 at 192.168.10.4", response="05b9c873f97d0a31921580b707d82694", algorithm=MD5
X-Grandstream-PBX: true
Max-Forwards: 70
User-Agent: Grandstream GXP2110 1.0.8.6
Privacy: none
P-Preferred-Identity: <sip:106 at 192.168.10.4>
Supported: replaces, path, timer
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
Content-Type: application/sdp
Accept: application/sdp, application/dtmf-relay
Content-Length: 405
v=0
o=106 8000 8000 IN IP4 192.168.10.106
s=SIP Call
c=IN IP4 192.168.10.106
t=0 0
m=audio 5004 RTP/AVP 0 8 4 18 9 97 2 101
a=sendrecv
a=rtpmap:0 PCMU/8000
a=ptime:20
a=rtpmap:8 PCMA/8000
a=rtpmap:4 G723/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:9 G722/8000
a=rtpmap:97 iLBC/8000
a=fmtp:97 mode=30
a=rtpmap:2 G726-32/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-15
<------------->
--- (18 headers 19 lines) ---
Sending to 192.168.10.106:5060 (no NAT)
Using INVITE request as basis request - 543030694-5060-342 at BJC.BGI.BA.BAG
Found peer '106' for '106' from 192.168.10.106:5060
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
Found RTP audio format 0
Found RTP audio format 8
Found RTP audio format 4
Found RTP audio format 18
Found RTP audio format 9
Found RTP audio format 97
Found RTP audio format 2
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 G723 for ID 4
Found audio description format G729 for ID 18
Found audio description format G722 for ID 9
Found audio description format iLBC for ID 97
Found audio description format G726-32 for ID 2
Found audio description format telephone-event for ID 101
Capabilities: us - (g729|ulaw|alaw|g722|ilbc), peer - audio=(ulaw|g726|g723|alaw|g722|g729|ilbc)/video=(nothing)/text=(nothing), combined - (g729|ulaw|alaw|g722|ilbc)
Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|)
> 0xb601b280 -- Strict RTP learning after remote address set to: 192.168.10.106:5004
Peer audio RTP is at port 192.168.10.106:5004
Looking for 107 in from-internal (domain 192.168.10.4)
== Extension Changed 106[ext-local] new state InUse for Notify User 103
== Extension Changed 106[ext-local] new state InUse for Notify User 100
== Extension Changed 106[ext-local] new state InUse for Notify User 104
sip_route_dump: route/path hop: <sip:106 at 192.168.10.106:5060>
<--- Transmitting (no NAT) to 192.168.10.106:5060 --->
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK1588148074;received=192.168.10.106;rport=5060
From: <sip:106 at 192.168.10.4>;tag=1872648879
To: <sip:107 at 192.168.10.4>
Call-ID: 543030694-5060-342 at BJC.BGI.BA.BAG
CSeq: 1141 INVITE
Server: FPBX-13.0.195.19(13.23.1)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: <sip:107 at 192.168.10.4:5060>
Content-Length: 0
<------------>
== Extension Changed 106[ext-local] new state InUse for Notify User 105
-- Executing [107 at from-internal:1] GotoIf("SIP/106-000000c0", "1?ext-local,107,1:followme-check,107,1") in new stack
-- Goto (ext-local,107,1)
-- Executing [107 at ext-local:1] Set("SIP/106-000000c0", "__RINGTIMER=15") in new stack
-- Executing [107 at ext-local:2] Macro("SIP/106-000000c0", "exten-vm,novm,107,0,0,0") in new stack
-- Executing [s at macro-exten-vm:1] Macro("SIP/106-000000c0", "user-callerid,") in new stack
-- Executing [s at macro-user-callerid:1] Set("SIP/106-000000c0", "TOUCH_MONITOR=1544206463.1660") in new stack
-- Executing [s at macro-user-callerid:2] Set("SIP/106-000000c0", "AMPUSER=106") in new stack
-- Executing [s at macro-user-callerid:3] GotoIf("SIP/106-000000c0", "0?report") in new stack
-- Executing [s at macro-user-callerid:4] ExecIf("SIP/106-000000c0", "1?Set(REALCALLERIDNUM=106)") in new stack
-- Executing [s at macro-user-callerid:5] Set("SIP/106-000000c0", "AMPUSER=106") in new stack
-- Executing [s at macro-user-callerid:6] GotoIf("SIP/106-000000c0", "0?limit") in new stack
-- Executing [s at macro-user-callerid:7] Set("SIP/106-000000c0", "AMPUSERCIDNAME=Marianela Lopez") in new stack
-- Executing [s at macro-user-callerid:8] ExecIf("SIP/106-000000c0", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
-- Executing [s at macro-user-callerid:9] GotoIf("SIP/106-000000c0", "0?report") in new stack
-- Executing [s at macro-user-callerid:10] Set("SIP/106-000000c0", "AMPUSERCID=106") in new stack
-- Executing [s at macro-user-callerid:11] Set("SIP/106-000000c0", "__DIAL_OPTIONS=Ttr") in new stack
-- Executing [s at macro-user-callerid:12] Set("SIP/106-000000c0", "CALLERID(all)="Marianela Lopez" <106>") in new stack
-- Executing [s at macro-user-callerid:13] GotoIf("SIP/106-000000c0", "0?limit") in new stack
-- Executing [s at macro-user-callerid:14] ExecIf("SIP/106-000000c0", "0?Set(GROUP(concurrency_limit)=106)") in new stack
-- Executing [s at macro-user-callerid:15] GosubIf("SIP/106-000000c0", "7?sub-ccss,s,1(macro-exten-vm,107)") in new stack
-- Executing [s at sub-ccss:1] ExecIf("SIP/106-000000c0", "0?Return()") in new stack
-- Executing [s at sub-ccss:2] Set("SIP/106-000000c0", "CCSS_SETUP=TRUE") in new stack
-- Executing [s at sub-ccss:3] GosubIf("SIP/106-000000c0", "0?monitor_config,1(macro-exten-vm,107):monitor_default,1(macro-exten-vm,107)") in new stack
-- Executing [monitor_default at sub-ccss:1] GotoIf("SIP/106-000000c0", "1?is_exten") in new stack
-- Goto (sub-ccss,monitor_default,4)
-- Executing [monitor_default at sub-ccss:4] Set("SIP/106-000000c0", "CALLCOMPLETION(cc_monitor_policy)=generic") in new stack
-- Executing [monitor_default at sub-ccss:5] Set("SIP/106-000000c0", "CALLCOMPLETION(cc_max_monitors)=5") in new stack
-- Executing [monitor_default at sub-ccss:6] Return("SIP/106-000000c0", "TRUE") in new stack
-- Executing [s at sub-ccss:4] GosubIf("SIP/106-000000c0", "7?agent_config,1():agent_default,1()") in new stack
-- Executing [agent_config at sub-ccss:1] Set("SIP/106-000000c0", "CALLCOMPLETION(cc_agent_policy)=generic") in new stack
-- Executing [agent_config at sub-ccss:2] Set("SIP/106-000000c0", "CALLCOMPLETION(cc_offer_timer)=30") in new stack
-- Executing [agent_config at sub-ccss:3] Set("SIP/106-000000c0", "CALLCOMPLETION(ccbs_available_timer)=") in new stack
-- Executing [agent_config at sub-ccss:4] Set("SIP/106-000000c0", "CALLCOMPLETION(ccnr_available_timer)=") in new stack
-- Executing [agent_config at sub-ccss:5] Set("SIP/106-000000c0", "CALLCOMPLETION(cc_callback_macro)=ccss-default") in new stack
[2018-12-07 15:14:23] WARNING[1007][C-00000083]: ccss.c:1012 ast_set_cc_callback_macro: Usage of cc_callback_macro is deprecated. Please use cc_callback_sub instead.
-- Executing [agent_config at sub-ccss:6] ExecIf("SIP/106-000000c0", "1?Set(CALLCOMPLETION(cc_recall_timer)=)") in new stack
-- Executing [agent_config at sub-ccss:7] ExecIf("SIP/106-000000c0", "1?Set(CALLCOMPLETION(cc_max_agents)=)") in new stack
-- Executing [agent_config at sub-ccss:8] ExecIf("SIP/106-000000c0", "0?Set(CALLCOMPLETION(cc_agent_dialstring)=Local/106_107 at from-ccss-)") in new stack
-- Executing [agent_config at sub-ccss:9] Set("SIP/106-000000c0", "CALLCOMPLETION(cc_callback_macro)=ccss-default") in new stack
[2018-12-07 15:14:23] WARNING[1007][C-00000083]: ccss.c:1012 ast_set_cc_callback_macro: Usage of cc_callback_macro is deprecated. Please use cc_callback_sub instead.
-- Executing [agent_config at sub-ccss:10] Return("SIP/106-000000c0", "") in new stack
-- Executing [s at sub-ccss:5] Set("SIP/106-000000c0", "DB(AMPUSER/106/ccss/last_number)=107") in new stack
-- Executing [s at sub-ccss:6] Return("SIP/106-000000c0", "") in new stack
-- Executing [s at macro-user-callerid:16] ExecIf("SIP/106-000000c0", "0?Set(CHANNEL(language)=)") in new stack
-- Executing [s at macro-user-callerid:17] NoOp("SIP/106-000000c0", "Macro Depth is 2") in new stack
-- Executing [s at macro-user-callerid:18] GotoIf("SIP/106-000000c0", "1?report2:macroerror") in new stack
-- Goto (macro-user-callerid,s,19)
-- Executing [s at macro-user-callerid:19] GotoIf("SIP/106-000000c0", "0?continue") in new stack
-- Executing [s at macro-user-callerid:20] ExecIf("SIP/106-000000c0", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
-- Executing [s at macro-user-callerid:21] Set("SIP/106-000000c0", "__TTL=64") in new stack
-- Executing [s at macro-user-callerid:22] GotoIf("SIP/106-000000c0", "1?continue") in new stack
-- Goto (macro-user-callerid,s,38)
-- Executing [s at macro-user-callerid:38] Set("SIP/106-000000c0", "CALLERID(number)=106") in new stack
-- Executing [s at macro-user-callerid:39] Set("SIP/106-000000c0", "CALLERID(name)=Marianela Lopez") in new stack
-- Executing [s at macro-user-callerid:40] GotoIf("SIP/106-000000c0", "0?cnum") in new stack
-- Executing [s at macro-user-callerid:41] Set("SIP/106-000000c0", "CDR(cnam)=Marianela Lopez") in new stack
-- Executing [s at macro-user-callerid:42] Set("SIP/106-000000c0", "CDR(cnum)=106") in new stack
-- Executing [s at macro-user-callerid:43] Set("SIP/106-000000c0", "CHANNEL(language)=es") in new stack
-- Executing [s at macro-exten-vm:2] Set("SIP/106-000000c0", "RingGroupMethod=none") in new stack
-- Executing [s at macro-exten-vm:3] Set("SIP/106-000000c0", "__EXTTOCALL=107") in new stack
-- Executing [s at macro-exten-vm:4] Set("SIP/106-000000c0", "__PICKUPMARK=107") in new stack
-- Executing [s at macro-exten-vm:5] Set("SIP/106-000000c0", "RT=") in new stack
-- Executing [s at macro-exten-vm:6] Gosub("SIP/106-000000c0", "sub-record-check,s,1(exten,107,dontcare)") in new stack
-- Executing [s at sub-record-check:1] GotoIf("SIP/106-000000c0", "0?initialized") in new stack
-- Executing [s at sub-record-check:2] Set("SIP/106-000000c0", "__REC_STATUS=INITIALIZED") in new stack
-- Executing [s at sub-record-check:3] Set("SIP/106-000000c0", "NOW=1544206463") in new stack
-- Executing [s at sub-record-check:4] Set("SIP/106-000000c0", "__DAY=07") in new stack
-- Executing [s at sub-record-check:5] Set("SIP/106-000000c0", "__MONTH=12") in new stack
-- Executing [s at sub-record-check:6] Set("SIP/106-000000c0", "__YEAR=2018") in new stack
-- Executing [s at sub-record-check:7] Set("SIP/106-000000c0", "__TIMESTR=20181207-151423") in new stack
-- Executing [s at sub-record-check:8] Set("SIP/106-000000c0", "__FROMEXTEN=106") in new stack
-- Executing [s at sub-record-check:9] Set("SIP/106-000000c0", "__MON_FMT=wav") in new stack
-- Executing [s at sub-record-check:10] NoOp("SIP/106-000000c0", "Recordings initialized") in new stack
-- Executing [s at sub-record-check:11] ExecIf("SIP/106-000000c0", "0?Set(ARG3=dontcare)") in new stack
-- Executing [s at sub-record-check:12] Set("SIP/106-000000c0", "REC_POLICY_MODE_SAVE=") in new stack
-- Executing [s at sub-record-check:13] ExecIf("SIP/106-000000c0", "0?Set(REC_STATUS=NO)") in new stack
-- Executing [s at sub-record-check:14] GotoIf("SIP/106-000000c0", "5?checkaction") in new stack
-- Goto (sub-record-check,s,17)
-- Executing [s at sub-record-check:17] GotoIf("SIP/106-000000c0", "1?sub-record-check,exten,1") in new stack
-- Goto (sub-record-check,exten,1)
-- Executing [exten at sub-record-check:1] NoOp("SIP/106-000000c0", "Exten Recording Check between 106 and 107") in new stack
-- Executing [exten at sub-record-check:2] Set("SIP/106-000000c0", "CALLTYPE=internal") in new stack
-- Executing [exten at sub-record-check:3] ExecIf("SIP/106-000000c0", "0?Set(CALLTYPE=)") in new stack
-- Executing [exten at sub-record-check:4] Set("SIP/106-000000c0", "CALLEE=dontcare") in new stack
-- Executing [exten at sub-record-check:5] ExecIf("SIP/106-000000c0", "0?Set(CALLEE=dontcare)") in new stack
-- Executing [exten at sub-record-check:6] GotoIf("SIP/106-000000c0", "0?callee") in new stack
-- Executing [exten at sub-record-check:7] GotoIf("SIP/106-000000c0", "1?caller") in new stack
-- Goto (sub-record-check,exten,13)
-- Executing [exten at sub-record-check:13] Set("SIP/106-000000c0", "RECMODE=dontcare") in new stack
-- Executing [exten at sub-record-check:14] ExecIf("SIP/106-000000c0", "0?Set(RECMODE=dontcare)") in new stack
-- Executing [exten at sub-record-check:15] ExecIf("SIP/106-000000c0", "1?Set(RECMODE=dontcare)") in new stack
-- Executing [exten at sub-record-check:16] Gosub("SIP/106-000000c0", "recordcheck,1(dontcare,internal,107)") in new stack
-- Executing [recordcheck at sub-record-check:1] NoOp("SIP/106-000000c0", "Starting recording check against dontcare") in new stack
-- Executing [recordcheck at sub-record-check:2] Goto("SIP/106-000000c0", "dontcare") in new stack
-- Goto (sub-record-check,recordcheck,3)
-- Executing [recordcheck at sub-record-check:3] Return("SIP/106-000000c0", "") in new stack
-- Executing [exten at sub-record-check:17] Return("SIP/106-000000c0", "") in new stack
-- Executing [s at macro-exten-vm:7] GotoIf("SIP/106-000000c0", "1?macrodial") in new stack
-- Goto (macro-exten-vm,s,13)
-- Executing [s at macro-exten-vm:13] GosubIf("SIP/106-000000c0", "0?clrheader,1()") in new stack
-- Executing [s at macro-exten-vm:14] Macro("SIP/106-000000c0", "dial-one,,Ttr,107") in new stack
-- Executing [s at macro-dial-one:1] Set("SIP/106-000000c0", "DEXTEN=107") in new stack
-- Executing [s at macro-dial-one:2] ExecIf("SIP/106-000000c0", "0?Set(__EXTTOCALL=107)") in new stack
-- Executing [s at macro-dial-one:3] Set("SIP/106-000000c0", "DIALSTATUS_CW=") in new stack
-- Executing [s at macro-dial-one:4] GosubIf("SIP/106-000000c0", "0?screen,1()") in new stack
-- Executing [s at macro-dial-one:5] GosubIf("SIP/106-000000c0", "0?cf,1()") in new stack
-- Executing [s at macro-dial-one:6] GotoIf("SIP/106-000000c0", "1?skip1") in new stack
-- Goto (macro-dial-one,s,9)
-- Executing [s at macro-dial-one:9] GotoIf("SIP/106-000000c0", "0?nodial") in new stack
-- Executing [s at macro-dial-one:10] GotoIf("SIP/106-000000c0", "0?continue") in new stack
-- Executing [s at macro-dial-one:11] Set("SIP/106-000000c0", "EXTHASCW=ENABLED") in new stack
-- Executing [s at macro-dial-one:12] GotoIf("SIP/106-000000c0", "0?next1:cwinusebusy") in new stack
-- Goto (macro-dial-one,s,24)
-- Executing [s at macro-dial-one:24] GotoIf("SIP/106-000000c0", "0?next3:continue") in new stack
-- Goto (macro-dial-one,s,26)
-- Executing [s at macro-dial-one:26] GotoIf("SIP/106-000000c0", "0?nodial") in new stack
-- Executing [s at macro-dial-one:27] GosubIf("SIP/106-000000c0", "1?dstring,1():dlocal,1()") in new stack
-- Executing [dstring at macro-dial-one:1] Set("SIP/106-000000c0", "DSTRING=") in new stack
-- Executing [dstring at macro-dial-one:2] Set("SIP/106-000000c0", "DEVICES=107") in new stack
-- Executing [dstring at macro-dial-one:3] ExecIf("SIP/106-000000c0", "0?Return()") in new stack
-- Executing [dstring at macro-dial-one:4] ExecIf("SIP/106-000000c0", "0?Set(DEVICES=07)") in new stack
-- Executing [dstring at macro-dial-one:5] Set("SIP/106-000000c0", "LOOPCNT=1") in new stack
-- Executing [dstring at macro-dial-one:6] Set("SIP/106-000000c0", "ITER=1") in new stack
-- Executing [dstring at macro-dial-one:7] Set("SIP/106-000000c0", "THISDIAL=SIP/107") in new stack
-- Executing [dstring at macro-dial-one:8] GosubIf("SIP/106-000000c0", "1?zap2dahdi,1()") in new stack
-- Executing [zap2dahdi at macro-dial-one:1] ExecIf("SIP/106-000000c0", "0?Return()") in new stack
-- Executing [zap2dahdi at macro-dial-one:2] Set("SIP/106-000000c0", "NEWDIAL=") in new stack
-- Executing [zap2dahdi at macro-dial-one:3] Set("SIP/106-000000c0", "LOOPCNT2=1") in new stack
-- Executing [zap2dahdi at macro-dial-one:4] Set("SIP/106-000000c0", "ITER2=1") in new stack
-- Executing [zap2dahdi at macro-dial-one:5] Set("SIP/106-000000c0", "THISPART2=SIP/107") in new stack
-- Executing [zap2dahdi at macro-dial-one:6] ExecIf("SIP/106-000000c0", "0?Set(THISPART2=DAHDI/107)") in new stack
-- Executing [zap2dahdi at macro-dial-one:7] Set("SIP/106-000000c0", "NEWDIAL=SIP/107&") in new stack
-- Executing [zap2dahdi at macro-dial-one:8] Set("SIP/106-000000c0", "ITER2=2") in new stack
-- Executing [zap2dahdi at macro-dial-one:9] GotoIf("SIP/106-000000c0", "0?begin2") in new stack
-- Executing [zap2dahdi at macro-dial-one:10] Set("SIP/106-000000c0", "THISDIAL=SIP/107") in new stack
-- Executing [zap2dahdi at macro-dial-one:11] Return("SIP/106-000000c0", "") in new stack
-- Executing [dstring at macro-dial-one:9] GotoIf("SIP/106-000000c0", "1?docheck") in new stack
-- Goto (macro-dial-one,dstring,14)
-- Executing [dstring at macro-dial-one:14] GotoIf("SIP/106-000000c0", "0?skipset") in new stack
-- Executing [dstring at macro-dial-one:15] Set("SIP/106-000000c0", "DSTRING=SIP/107&") in new stack
-- Executing [dstring at macro-dial-one:16] Set("SIP/106-000000c0", "ITER=2") in new stack
-- Executing [dstring at macro-dial-one:17] GotoIf("SIP/106-000000c0", "0?begin") in new stack
-- Executing [dstring at macro-dial-one:18] ExecIf("SIP/106-000000c0", "0?Return()") in new stack
-- Executing [dstring at macro-dial-one:19] Set("SIP/106-000000c0", "DSTRING=SIP/107") in new stack
-- Executing [dstring at macro-dial-one:20] Return("SIP/106-000000c0", "") in new stack
-- Executing [s at macro-dial-one:28] GotoIf("SIP/106-000000c0", "0?nodial") in new stack
-- Executing [s at macro-dial-one:29] GotoIf("SIP/106-000000c0", "0?skiptrace") in new stack
-- Executing [s at macro-dial-one:30] GosubIf("SIP/106-000000c0", "1?ctset,1():ctclear,1()") in new stack
-- Executing [ctset at macro-dial-one:1] Set("SIP/106-000000c0", "DB(CALLTRACE/107)=106") in new stack
-- Executing [ctset at macro-dial-one:2] Return("SIP/106-000000c0", "") in new stack
-- Executing [s at macro-dial-one:31] Set("SIP/106-000000c0", "D_OPTIONS=Ttr") in new stack
-- Executing [s at macro-dial-one:32] GosubIf("SIP/106-000000c0", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
-- Executing [s at macro-dial-one:33] NoOp("SIP/106-000000c0", "Blind Transfer: , Attended Transfer: , User: 106, Alert Info: ") in new stack
-- Executing [s at macro-dial-one:34] ExecIf("SIP/106-000000c0", "1?Set(ALERT_INFO=)") in new stack
-- Executing [s at macro-dial-one:35] ExecIf("SIP/106-000000c0", "0?Set(ALERT_INFO=)") in new stack
-- Executing [s at macro-dial-one:36] ExecIf("SIP/106-000000c0", "0?Set(ALERT_INFO=)") in new stack
-- Executing [s at macro-dial-one:37] ExecIf("SIP/106-000000c0", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
-- Executing [s at macro-dial-one:38] ExecIf("SIP/106-000000c0", "0?Set(ALERT_INFO=Normal;volume=)") in new stack
-- Executing [s at macro-dial-one:39] GosubIf("SIP/106-000000c0", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
-- Executing [s at macro-dial-one:40] ExecIf("SIP/106-000000c0", "0?Set(CHANNEL(musicclass)=)") in new stack
-- Executing [s at macro-dial-one:41] GosubIf("SIP/106-000000c0", "0?qwait,1()") in new stack
-- Executing [s at macro-dial-one:42] Set("SIP/106-000000c0", "__CWIGNORE=") in new stack
-- Executing [s at macro-dial-one:43] Set("SIP/106-000000c0", "__KEEPCID=TRUE") in new stack
-- Executing [s at macro-dial-one:44] GotoIf("SIP/106-000000c0", "0?usegoto,1") in new stack
-- Executing [s at macro-dial-one:45] GotoIf("SIP/106-000000c0", "0?godial") in new stack
-- Executing [s at macro-dial-one:46] Gosub("SIP/106-000000c0", "sub-presencestate-display,s,1(107)") in new stack
-- Executing [s at sub-presencestate-display:1] Goto("SIP/106-000000c0", "state-not_set,1") in new stack
-- Goto (sub-presencestate-display,state-not_set,1)
-- Executing [state-not_set at sub-presencestate-display:1] Set("SIP/106-000000c0", "PRESENCESTATE_DISPLAY=") in new stack
-- Executing [state-not_set at sub-presencestate-display:2] Return("SIP/106-000000c0", "") in new stack
-- Executing [s at macro-dial-one:47] Set("SIP/106-000000c0", "CONNECTEDLINE(name,i)=Rodrigo Martin") in new stack
-- Executing [s at macro-dial-one:48] Set("SIP/106-000000c0", "CONNECTEDLINE(num)=107") in new stack
-- Executing [s at macro-dial-one:49] Set("SIP/106-000000c0", "D_OPTIONS=TtrI") in new stack
-- Executing [s at macro-dial-one:50] Macro("SIP/106-000000c0", "dialout-one-predial-hook,") in new stack
-- Executing [s at macro-dialout-one-predial-hook:1] MacroExit("SIP/106-000000c0", "") in new stack
-- Executing [s at macro-dial-one:51] ExecIf("SIP/106-000000c0", "0?Set(D_OPTIONS=trII)") in new stack
-- Executing [s at macro-dial-one:52] NoOp("SIP/106-000000c0", "") in new stack
-- Executing [s at macro-dial-one:53] ExecIf("SIP/106-000000c0", "0?Set(D_OPTIONS=TtrIg)") in new stack
-- Executing [s at macro-dial-one:54] Dial("SIP/106-000000c0", "SIP/107,,TtrIb(func-apply-sipheaders^s^1)") in new stack
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
-- SIP/107-000000c1 Internal Gosub(func-apply-sipheaders,s,1) start
-- Executing [s at func-apply-sipheaders:1] NoOp("SIP/107-000000c1", "Applying SIP Headers to channel") in new stack
-- Executing [s at func-apply-sipheaders:2] Set("SIP/107-000000c1", "SIPHEADERKEYS=") in new stack
-- Executing [s at func-apply-sipheaders:3] ExecIf("SIP/107-000000c1", "0?Set(Rheader=1)") in new stack
-- Executing [s at func-apply-sipheaders:4] While("SIP/107-000000c1", "0") in new stack
-- Jumping to priority 7
-- Executing [s at func-apply-sipheaders:8] ExecIf("SIP/107-000000c1", "0?SIPRemoveHeader(Alert-Info:)") in new stack
-- Executing [s at func-apply-sipheaders:9] Return("SIP/107-000000c1", "") in new stack
== Spawn extension (from-internal, 107, 1) exited non-zero on 'SIP/107-000000c1'
-- SIP/107-000000c1 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
set_destination: Parsing <sip:106 at 192.168.10.106:5060> for address/port to send to
set_destination: set destination to 192.168.10.106:5060
Reliably Transmitting (no NAT) to 192.168.10.106:5060:
NOTIFY sip:106 at 192.168.10.106:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK1f94d422;rport
Max-Forwards: 70
From: <sip:107 at 192.168.10.4>;tag=as76b7215d
To: <sip:106 at 192.168.10.4>;tag=1818557175
Contact: <sip:107 at 192.168.10.4:5060>
Call-ID: 156301877-5060-322 at BJC.BGI.BA.BAG
CSeq: 268 NOTIFY
User-Agent: FPBX-13.0.195.19(13.23.1)
Subscription-State: active
Event: dialog
Content-Type: application/dialog-info+xml
Content-Length: 222
<?xml version="1.0"?>
<dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="166" state="full" entity="sip:107 at 192.168.10.4">
<dialog id="107" direction="recipient">
<state>early</state>
</dialog>
</dialog-info>
---
== Extension Changed 107[ext-local] new state Ringing for Notify User 106
== Extension Changed 107[ext-local] new state Ringing for Notify User 105
== Extension Changed 107[ext-local] new state Ringing for Notify User 103
-- Called SIP/107
== Extension Changed 107[ext-local] new state Ringing for Notify User 100
<--- Transmitting (no NAT) to 192.168.10.106:5060 --->
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK1588148074;received=192.168.10.106;rport=5060
From: <sip:106 at 192.168.10.4>;tag=1872648879
To: <sip:107 at 192.168.10.4>;tag=as1c2f4725
Call-ID: 543030694-5060-342 at BJC.BGI.BA.BAG
CSeq: 1141 INVITE
Server: FPBX-13.0.195.19(13.23.1)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: <sip:107 at 192.168.10.4:5060>
Content-Length: 0
<------------>
== Extension Changed 107[ext-local] new state Ringing for Notify User 104
-- Connected line update to SIP/106-000000c0 prevented.
<--- SIP read from UDP:192.168.10.106:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK1f94d422;rport=5060
From: <sip:107 at 192.168.10.4>;tag=as76b7215d
To: <sip:106 at 192.168.10.4>;tag=1818557175
Call-ID: 156301877-5060-322 at BJC.BGI.BA.BAG
CSeq: 268 NOTIFY
Contact: <sip:106 at 192.168.10.106:5060>
Supported: replaces, path, timer
User-Agent: Grandstream GXP2110 1.0.8.6
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
Content-Length: 0
<------------->
--- (11 headers 0 lines) ---
set_destination: Parsing <sip:106 at 192.168.10.106:5060> for address/port to send to
set_destination: set destination to 192.168.10.106:5060
Reliably Transmitting (no NAT) to 192.168.10.106:5060:
NOTIFY sip:106 at 192.168.10.106:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK75404efa;rport
Max-Forwards: 70
From: <sip:107 at 192.168.10.4>;tag=as76b7215d
To: <sip:106 at 192.168.10.4>;tag=1818557175
Contact: <sip:107 at 192.168.10.4:5060>
Call-ID: 156301877-5060-322 at BJC.BGI.BA.BAG
CSeq: 269 NOTIFY
User-Agent: FPBX-13.0.195.19(13.23.1)
Subscription-State: active
Event: dialog
Content-Type: application/dialog-info+xml
Content-Length: 222
<?xml version="1.0"?>
<dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="167" state="full" entity="sip:107 at 192.168.10.4">
<dialog id="107" direction="recipient">
<state>early</state>
</dialog>
</dialog-info>
---
== Extension Changed 107[ext-local] new state Ringing for Notify User 106
== Extension Changed 107[ext-local] new state Ringing for Notify User 105
== Extension Changed 107[ext-local] new state Ringing for Notify User 103
-- SIP/107-000000c1 is ringing
<--- Transmitting (no NAT) to 192.168.10.106:5060 --->
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK1588148074;received=192.168.10.106;rport=5060
From: <sip:106 at 192.168.10.4>;tag=1872648879
To: <sip:107 at 192.168.10.4>;tag=as1c2f4725
Call-ID: 543030694-5060-342 at BJC.BGI.BA.BAG
CSeq: 1141 INVITE
Server: FPBX-13.0.195.19(13.23.1)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: <sip:107 at 192.168.10.4:5060>
Content-Length: 0
<------------>
== Extension Changed 107[ext-local] new state Ringing for Notify User 100
== Extension Changed 107[ext-local] new state Ringing for Notify User 104
Retransmitting #1 (no NAT) to 192.168.10.106:5060:
NOTIFY sip:106 at 192.168.10.106:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK75404efa;rport
Max-Forwards: 70
From: <sip:107 at 192.168.10.4>;tag=as76b7215d
To: <sip:106 at 192.168.10.4>;tag=1818557175
Contact: <sip:107 at 192.168.10.4:5060>
Call-ID: 156301877-5060-322 at BJC.BGI.BA.BAG
CSeq: 269 NOTIFY
User-Agent: FPBX-13.0.195.19(13.23.1)
Subscription-State: active
Event: dialog
Content-Type: application/dialog-info+xml
Content-Length: 222
<?xml version="1.0"?>
<dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="167" state="full" entity="sip:107 at 192.168.10.4">
<dialog id="107" direction="recipient">
<state>early</state>
</dialog>
</dialog-info>
---
<--- SIP read from UDP:192.168.10.106:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK75404efa;rport=5060
From: <sip:107 at 192.168.10.4>;tag=as76b7215d
To: <sip:106 at 192.168.10.4>;tag=1818557175
Call-ID: 156301877-5060-322 at BJC.BGI.BA.BAG
CSeq: 269 NOTIFY
Contact: <sip:106 at 192.168.10.106:5060>
Supported: replaces, path, timer
User-Agent: Grandstream GXP2110 1.0.8.6
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
Content-Length: 0
<------------->
--- (11 headers 0 lines) ---
<--- SIP read from UDP:192.168.10.106:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK75404efa;rport=5060
From: <sip:107 at 192.168.10.4>;tag=as76b7215d
To: <sip:106 at 192.168.10.4>;tag=1818557175
Call-ID: 156301877-5060-322 at BJC.BGI.BA.BAG
CSeq: 269 NOTIFY
Contact: <sip:106 at 192.168.10.106:5060>
Supported: replaces, path, timer
User-Agent: Grandstream GXP2110 1.0.8.6
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
Content-Length: 0
<------------->
--- (11 headers 0 lines) ---
> 0x99dd4f8 -- Strict RTP learning after remote address set to: 192.168.10.107:5028
set_destination: Parsing <sip:106 at 192.168.10.106:5060> for address/port to send to
set_destination: set destination to 192.168.10.106:5060
-- Connected line update to SIP/106-000000c0 prevented.
Reliably Transmitting (no NAT) to 192.168.10.106:5060:
NOTIFY sip:106 at 192.168.10.106:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK2da5702e;rport
Max-Forwards: 70
From: <sip:107 at 192.168.10.4>;tag=as76b7215d
To: <sip:106 at 192.168.10.4>;tag=1818557175
Contact: <sip:107 at 192.168.10.4:5060>
Call-ID: 156301877-5060-322 at BJC.BGI.BA.BAG
CSeq: 270 NOTIFY
User-Agent: FPBX-13.0.195.19(13.23.1)
Subscription-State: active
Event: dialog
Content-Type: application/dialog-info+xml
Content-Length: 204
<?xml version="1.0"?>
<dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="168" state="full" entity="sip:107 at 192.168.10.4">
<dialog id="107">
<state>confirmed</state>
</dialog>
</dialog-info>
---
== Extension Changed 107[ext-local] new state InUse for Notify User 106
-- SIP/107-000000c1 answered SIP/106-000000c0
== Extension Changed 107[ext-local] new state InUse for Notify User 105
Audio is at 10122
Adding codec g729 to SDP
Adding codec ulaw to SDP
Adding codec alaw to SDP
Adding codec g722 to SDP
Adding codec ilbc to SDP
Adding non-codec 0x1 (telephone-event) to SDP
<--- Reliably Transmitting (no NAT) to 192.168.10.106:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK1588148074;received=192.168.10.106;rport=5060
From: <sip:106 at 192.168.10.4>;tag=1872648879
To: <sip:107 at 192.168.10.4>;tag=as1c2f4725
Call-ID: 543030694-5060-342 at BJC.BGI.BA.BAG
CSeq: 1141 INVITE
Server: FPBX-13.0.195.19(13.23.1)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: <sip:107 at 192.168.10.4:5060>
Content-Type: application/sdp
Require: timer
Content-Length: 373
v=0
o=root 2109965229 2109965229 IN IP4 192.168.10.4
s=Asterisk PBX 13.23.1
c=IN IP4 192.168.10.4
t=0 0
m=audio 10122 RTP/AVP 18 0 8 9 97 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:9 G722/8000
a=rtpmap:97 iLBC/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=maxptime:150
a=sendrecv
<------------>
== Extension Changed 107[ext-local] new state InUse for Notify User 103
== Extension Changed 107[ext-local] new state InUse for Notify User 100
== Extension Changed 107[ext-local] new state InUse for Notify User 104
-- Channel SIP/107-000000c1 joined 'simple_bridge' basic-bridge <ddbc23d7-6169-4d4e-83d0-4a1a7a3fed9a>
-- Channel SIP/106-000000c0 joined 'simple_bridge' basic-bridge <ddbc23d7-6169-4d4e-83d0-4a1a7a3fed9a>
<--- SIP read from UDP:192.168.10.106:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK2da5702e;rport=5060
From: <sip:107 at 192.168.10.4>;tag=as76b7215d
To: <sip:106 at 192.168.10.4>;tag=1818557175
Call-ID: 156301877-5060-322 at BJC.BGI.BA.BAG
CSeq: 270 NOTIFY
Contact: <sip:106 at 192.168.10.106:5060>
Supported: replaces, path, timer
User-Agent: Grandstream GXP2110 1.0.8.6
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
Content-Length: 0
<------------->
--- (11 headers 0 lines) ---
> 0x99dd4f8 -- Strict RTP switching to RTP target address 192.168.10.107:5028 as source
<--- SIP read from UDP:192.168.10.106:5060 --->
ACK sip:107 at 192.168.10.4:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.10.106:5060;branch=z9hG4bK2141261376;rport
From: <sip:106 at 192.168.10.4>;tag=1872648879
To: <sip:107 at 192.168.10.4>;tag=as1c2f4725
Call-ID: 543030694-5060-342 at BJC.BGI.BA.BAG
CSeq: 1141 ACK
Contact: <sip:106 at 192.168.10.106:5060>
X-Grandstream-PBX: true
Max-Forwards: 70
Supported: replaces, path, timer
User-Agent: Grandstream GXP2110 1.0.8.6
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
Content-Length: 0
<------------->
--- (13 headers 0 lines) ---
> 0xb601b280 -- Strict RTP switching to RTP target address 192.168.10.106:5004 as source
> 0xb601b280 -- Strict RTP learning complete - Locking on source address 192.168.10.106:5004
> 0x99dd4f8 -- Strict RTP learning complete - Locking on source address 192.168.10.107:5028
-- Channel SIP/107-000000c1 left 'simple_bridge' basic-bridge <ddbc23d7-6169-4d4e-83d0-4a1a7a3fed9a>
-- Channel SIP/106-000000c0 left 'simple_bridge' basic-bridge <ddbc23d7-6169-4d4e-83d0-4a1a7a3fed9a>
== Spawn extension (macro-dial-one, s, 54) exited non-zero on 'SIP/106-000000c0' in macro 'dial-one'
== Spawn extension (macro-exten-vm, s, 14) exited non-zero on 'SIP/106-000000c0' in macro 'exten-vm'
== Spawn extension (ext-local, 107, 2) exited non-zero on 'SIP/106-000000c0'
-- Executing [h at ext-local:1] Macro("SIP/106-000000c0", "hangupcall,") in new stack
-- Executing [s at macro-hangupcall:1] GotoIf("SIP/106-000000c0", "1?theend") in new stack
-- Goto (macro-hangupcall,s,3)
-- Executing [s at macro-hangupcall:3] ExecIf("SIP/106-000000c0", "0?Set(CDR(recordingfile)=)") in new stack
set_destination: Parsing <sip:106 at 192.168.10.106:5060> for address/port to send to
set_destination: set destination to 192.168.10.106:5060
Reliably Transmitting (no NAT) to 192.168.10.106:5060:
NOTIFY sip:106 at 192.168.10.106:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK3e6352fa;rport
Max-Forwards: 70
From: <sip:107 at 192.168.10.4>;tag=as76b7215d
To: <sip:106 at 192.168.10.4>;tag=1818557175
Contact: <sip:107 at 192.168.10.4:5060>
Call-ID: 156301877-5060-322 at BJC.BGI.BA.BAG
CSeq: 271 NOTIFY
User-Agent: FPBX-13.0.195.19(13.23.1)
Subscription-State: active
Event: dialog
Content-Type: application/dialog-info+xml
Content-Length: 205
<?xml version="1.0"?>
<dialog-info xmlns="urn:ietf:params:xml:ns:dialog-info" version="169" state="full" entity="sip:107 at 192.168.10.4">
<dialog id="107">
<state>terminated</state>
</dialog>
</dialog-info>
---
== Extension Changed 107[ext-local] new state Idle for Notify User 106
== Extension Changed 107[ext-local] new state Idle for Notify User 105
== Extension Changed 107[ext-local] new state Idle for Notify User 103
== Extension Changed 107[ext-local] new state Idle for Notify User 100
== Extension Changed 107[ext-local] new state Idle for Notify User 104
-- Executing [s at macro-hangupcall:4] Hangup("SIP/106-000000c0", "") in new stack
== Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/106-000000c0' in macro 'hangupcall'
== Spawn extension (ext-local, h, 1) exited non-zero on 'SIP/106-000000c0'
Scheduling destruction of SIP dialog '543030694-5060-342 at BJC.BGI.BA.BAG' in 6400 ms (Method: ACK)
== Extension Changed 106[ext-local] new state Idle for Notify User 103
== Extension Changed 106[ext-local] new state Idle for Notify User 100
== Extension Changed 106[ext-local] new state Idle for Notify User 104
set_destination: Parsing <sip:106 at 192.168.10.106:5060> for address/port to send to
== Extension Changed 106[ext-local] new state Idle for Notify User 105
set_destination: set destination to 192.168.10.106:5060
Reliably Transmitting (no NAT) to 192.168.10.106:5060:
BYE sip:106 at 192.168.10.106:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK1e2485a8;rport
Max-Forwards: 70
From: <sip:107 at 192.168.10.4>;tag=as1c2f4725
To: <sip:106 at 192.168.10.4>;tag=1872648879
Call-ID: 543030694-5060-342 at BJC.BGI.BA.BAG
CSeq: 102 BYE
User-Agent: FPBX-13.0.195.19(13.23.1)
Proxy-Authorization: Digest username="106", realm="asterisk", algorithm=MD5, uri="sip:192.168.10.4", nonce="60045ee1", response="4dd982eb2ad0570a0ff14d67beaaf811"
X-Asterisk-HangupCause: Normal Clearing
X-Asterisk-HangupCauseCode: 16
Content-Length: 0
---
<--- SIP read from UDP:192.168.10.106:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK3e6352fa;rport=5060
From: <sip:107 at 192.168.10.4>;tag=as76b7215d
To: <sip:106 at 192.168.10.4>;tag=1818557175
Call-ID: 156301877-5060-322 at BJC.BGI.BA.BAG
CSeq: 271 NOTIFY
Contact: <sip:106 at 192.168.10.106:5060>
Supported: replaces, path, timer
User-Agent: Grandstream GXP2110 1.0.8.6
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
Content-Length: 0
<------------->
--- (11 headers 0 lines) ---
Retransmitting #1 (no NAT) to 192.168.10.106:5060:
BYE sip:106 at 192.168.10.106:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK1e2485a8;rport
Max-Forwards: 70
From: <sip:107 at 192.168.10.4>;tag=as1c2f4725
To: <sip:106 at 192.168.10.4>;tag=1872648879
Call-ID: 543030694-5060-342 at BJC.BGI.BA.BAG
CSeq: 102 BYE
User-Agent: FPBX-13.0.195.19(13.23.1)
Proxy-Authorization: Digest username="106", realm="asterisk", algorithm=MD5, uri="sip:192.168.10.4", nonce="60045ee1", response="4dd982eb2ad0570a0ff14d67beaaf811"
X-Asterisk-HangupCause: Normal Clearing
X-Asterisk-HangupCauseCode: 16
Content-Length: 0
---
<--- SIP read from UDP:192.168.10.106:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK1e2485a8;rport=5060
From: <sip:107 at 192.168.10.4>;tag=as1c2f4725
To: <sip:106 at 192.168.10.4>;tag=1872648879
Call-ID: 543030694-5060-342 at BJC.BGI.BA.BAG
CSeq: 102 BYE
Contact: <sip:106 at 192.168.10.106:5060>
Supported: replaces, path, timer
User-Agent: Grandstream GXP2110 1.0.8.6
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
Content-Length: 0
<------------->
--- (11 headers 0 lines) ---
SIP Response message for INCOMING dialog BYE arrived
Really destroying SIP dialog '543030694-5060-342 at BJC.BGI.BA.BAG' Method: ACK
<--- SIP read from UDP:192.168.10.106:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.10.4:5060;branch=z9hG4bK1e2485a8;rport=5060
From: <sip:107 at 192.168.10.4>;tag=as1c2f4725
To: <sip:106 at 192.168.10.4>;tag=1872648879
Call-ID: 543030694-5060-342 at BJC.BGI.BA.BAG
CSeq: 102 BYE
Contact: <sip:106 at 192.168.10.106:5060>
Supported: replaces, path, timer
User-Agent: Grandstream GXP2110 1.0.8.6
Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER, UPDATE, MESSAGE
Content-Length: 0
<------------->
--- (11 headers 0 lines) ---
Let me know if you need any further info.
> No audio when using iLBC codec
> ------------------------------
>
> Key: ASTERISK-28199
> URL: https://issues.asterisk.org/jira/browse/ASTERISK-28199
> Project: Asterisk
> Issue Type: Bug
> Security Level: None
> Components: Codecs/codec_ilbc
> Affects Versions: 13.23.1
> Environment: Centos 6 32bit
> Reporter: Ariel Grin
> Assignee: Ariel Grin
> Severity: Minor
> Labels: pjsip
>
> Hi all: I’m trying to use iLBC codec but I found that iLBC has a no audio issue. If both endpoints are using iLBC neither endpoint can hear the other, even though RTP packets are flowing correctly, as checked with rtp debug. Also if an echo test is performed on an endpoint with iLBC, neither the initial explanatory message nor the echo itself can be heard. If one endpoint is using iLBC and the other is using another codec, I tried with ulaw, g729 and g722, iLBC endpoint can’t hear the other non-iLBC endpoint, but non-iLBC endpoint can hear iLBC endpoint.
> This has been tested between internal extensions on a local LAN, no NAT involved. This issue only happens when one or both endpoints are using iLBC. There are no audio issues when using any other codec except iLBC.
--
This message was sent by Atlassian JIRA
(v6.2#6252)
More information about the asterisk-bugs
mailing list