No audio on extensions with voxbeam trunk

mauritz · January 3, 2017, 9:47am

We have a freepbx13 / centos 7 installation - The installation currently is working perfectly with our main DID (incoming and outgoing calls). We want to test voxbeam services and have setup everything accordingly but am unable to get audio on the extension. When we dial to the DID (which rings straight to one of our extensions) audio works on the caller end (external) but there is no audio on the extension itself. Also, when we assign the DID incoming to our main IVR we are unable to get any keypressed registered to asterisk. I am not sure if the 2 issues are related but figured it’s worth mentioning

As we’ve already got a working installation I want to go as far to say that it’s not NAT or firewall. I have also disabled the firewall to test and getting the same issue. I am convinced this is an issue on our end

Herewith the trunk config as well as asterisk log when trying to make an incoming call:

OUTGOING
type=peer
insecure=very
nat=no
canreinvite=no
username=****
secret=****
host=sbc.voxbeam.com
context=from-trunk

INCOMING
type=peer
insecure=very
nat=no
canreinvite=no
username=****
secret=****
host=sbc.voxbeam.com
context=from-trunk

mauritz · January 3, 2017, 9:49am

ASTERISK LOG ON INCOMING CALL FROM EXTERNAL NETWORK (with sip show peers)

Reliably Transmitting (NAT) to 196.210.50.90:5062:
OPTIONS sip:[email protected]:5062 SIP/2.0
Via: SIP/2.0/UDP ***.***.***.***:5060;branch=z9hG4bK02471bd1;rport
Max-Forwards: 70
From: "Unknown" <sip:Unknown@***.***.***.***>;tag=as1be9c6a9
To: <sip:[email protected]:5062>
Contact: <sip:Unknown@***.***.***.***:5060>
Call-ID: 1e1ddbef3ea5c82d5517e74a4aac6c36@***.***.***.***:5060
CSeq: 102 OPTIONS
User-Agent: FPBX-13.0.190.8(13.13.0)
Date: Tue, 03 Jan 2017 09:15:47 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0

--
Reliably Transmitting (NAT) to 196.210.50.90:5063:
OPTIONS sip:[email protected]:5063 SIP/2.0
Via: SIP/2.0/UDP ***.***.***.***:5060;branch=z9hG4bK72d5ae55;rport
Max-Forwards: 70
From: "Unknown" <sip:Unknown@***.***.***.***>;tag=as2803bf9d
To: <sip:[email protected]:5063>
Contact: <sip:Unknown@***.***.***.***:5060>
Call-ID: 7a50a8187e5c8b065ad662e06815aa1f@***.***.***.***:5060
CSeq: 102 OPTIONS
User-Agent: FPBX-13.0.190.8(13.13.0)
Date: Tue, 03 Jan 2017 09:15:47 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0

---

<--- SIP read from UDP:196.210.50.90:5062 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP ***.***.***.***:5060;branch=z9hG4bK02471bd1;rport
From: "Unknown" <sip:Unknown@***.***.***.***>;tag=as1be9c6a9
To: <sip:[email protected]:5062>;tag=2613574887
Call-ID: 1e1ddbef3ea5c82d5517e74a4aac6c36@***.***.***.***:5060
CSeq: 102 OPTIONS
User-Agent: Yealink SIP-W52P 25.73.0.40
Content-Length: 0

<------------->
--- (8 headers 0 lines) ---
Really destroying SIP dialog '1e1ddbef3ea5c82d5517e74a4aac6c36@***.***.***.***:5060' Method: OPTIONS

<--- SIP read from UDP:196.210.50.90:5063 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP ***.***.***.***:5060;branch=z9hG4bK72d5ae55;rport
From: "Unknown" <sip:Unknown@***.***.***.***>;tag=as2803bf9d
To: <sip:[email protected]:5063>;tag=4229495177
Call-ID: 7a50a8187e5c8b065ad662e06815aa1f@***.***.***.***:5060
CSeq: 102 OPTIONS
User-Agent: Yealink SIP-W52P 25.73.0.40
Content-Length: 0

<------------->
--- (8 headers 0 lines) ---
Really destroying SIP dialog '7a50a8187e5c8b065ad662e06815aa1f@***.***.***.***:5060' Method: OPTIONS

<--- SIP read from UDP:95.211.119.240:5060 --->
INVITE sip:27*******@***.***.***.*** SIP/2.0
Call-ID: [email protected]
CSeq: 102 INVITE
From: <sip:**************@voxbone.com>;tag=as26fd4e26
To: <sip:27*******@***.***.***.***>
Via: SIP/2.0/UDP 95.211.119.240;branch=z9hG4bK8c1b.6f182877.0
Max-Forwards: 66
Content-Type: application/sdp
Contact: <sip:95.211.119.240;did=8f2.9608608>
User-Agent: Vox Callcontrol
Content-Length: 321
X-LP-DID-Attrs: didchans=2
X-GW-Attrs: orig=magic
X-Orig-To: 27*******

v=0
o=root 2117616962 2117616962 IN IP4 81.201.85.81
s=session
c=IN IP4 81.201.85.81
t=0 0
m=audio 16364 RTP/AVP 8 0 18 101
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=silenceSupp:off - - - -
a=ptime:20
a=sendrecv
<------------->
--- (14 headers 15 lines) ---
Sending to 95.211.119.240:5060 (no NAT)
Sending to 95.211.119.240:5060 (no NAT)
Using INVITE request as basis request - [email protected]
Found peer 'voxbeam_inbound' for '**************' from 95.211.119.240:5060
  == Using SIP RTP TOS bits 184
  == Using SIP RTP CoS mark 5
Found RTP audio format 8
Found RTP audio format 0
Found RTP audio format 18
Found RTP audio format 101
Found audio description format PCMA for ID 8
Found audio description format PCMU for ID 0
Found audio description format G729 for ID 18
Found audio description format telephone-event for ID 101
Capabilities: us - (ulaw|alaw|gsm|g726), peer - audio=(ulaw|alaw|g729)/video=(nothing)/text=(nothing), combined - (ulaw|alaw)
Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|)
Peer audio RTP is at port 81.201.85.81:16364
Looking for 27******* in from-trunk (domain ***.***.***.***)
sip_route_dump: route/path hop: <sip:95.211.119.240;did=8f2.9608608>

<--- Transmitting (no NAT) to 95.211.119.240:5060 --->
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 95.211.119.240;branch=z9hG4bK8c1b.6f182877.0;received=95.211.119.240
From: <sip:**************@voxbone.com>;tag=as26fd4e26
To: <sip:27*******@***.***.***.***>
Call-ID: [email protected]
CSeq: 102 INVITE
Server: FPBX-13.0.190.8(13.13.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Contact: <sip:27*******@***.***.***.***:5060>
Content-Length: 0


<------------>
    -- Executing [27*******@from-trunk:1] Set("SIP/voxbeam_inbound-0000001e", "__DIRECTION=INBOUND") in new stack
    -- Executing [27*******@from-trunk:2] Gosub("SIP/voxbeam_inbound-0000001e", "sub-record-check,s,1(in,27*******,force)") in new stack
    -- Executing [s@sub-record-check:1] GotoIf("SIP/voxbeam_inbound-0000001e", "0?initialized") in new stack
    -- Executing [s@sub-record-check:2] Set("SIP/voxbeam_inbound-0000001e", "__REC_STATUS=INITIALIZED") in new stack
    -- Executing [s@sub-record-check:3] Set("SIP/voxbeam_inbound-0000001e", "NOW=1483434948") in new stack
    -- Executing [s@sub-record-check:4] Set("SIP/voxbeam_inbound-0000001e", "__DAY=03") in new stack
    -- Executing [s@sub-record-check:5] Set("SIP/voxbeam_inbound-0000001e", "__MONTH=01") in new stack
    -- Executing [s@sub-record-check:6] Set("SIP/voxbeam_inbound-0000001e", "__YEAR=2017") in new stack
    -- Executing [s@sub-record-check:7] Set("SIP/voxbeam_inbound-0000001e", "__TIMESTR=20170103-111548") in new stack
    -- Executing [s@sub-record-check:8] Set("SIP/voxbeam_inbound-0000001e", "__FROMEXTEN=unknown") in new stack
    -- Executing [s@sub-record-check:9] Set("SIP/voxbeam_inbound-0000001e", "__MON_FMT=wav") in new stack
    -- Executing [s@sub-record-check:10] NoOp("SIP/voxbeam_inbound-0000001e", "Recordings initialized") in new stack
    -- Executing [s@sub-record-check:11] ExecIf("SIP/voxbeam_inbound-0000001e", "0?Set(ARG3=dontcare)") in new stack
    -- Executing [s@sub-record-check:12] Set("SIP/voxbeam_inbound-0000001e", "REC_POLICY_MODE_SAVE=") in new stack
    -- Executing [s@sub-record-check:13] ExecIf("SIP/voxbeam_inbound-0000001e", "0?Set(REC_STATUS=NO)") in new stack
    -- Executing [s@sub-record-check:14] GotoIf("SIP/voxbeam_inbound-0000001e", "2?checkaction") in new stack
    -- Goto (sub-record-check,s,17)
    -- Executing [s@sub-record-check:17] GotoIf("SIP/voxbeam_inbound-0000001e", "1?sub-record-check,in,1") in new stack
    -- Goto (sub-record-check,in,1)
    -- Executing [in@sub-record-check:1] NoOp("SIP/voxbeam_inbound-0000001e", "Inbound Recording Check to 27*******") in new stack
    -- Executing [in@sub-record-check:2] Set("SIP/voxbeam_inbound-0000001e", "FROMEXTEN=unknown") in new stack
    -- Executing [in@sub-record-check:3] ExecIf("SIP/voxbeam_inbound-0000001e", "11?Set(FROMEXTEN=**************)") in new stack
    -- Executing [in@sub-record-check:4] Gosub("SIP/voxbeam_inbound-0000001e", "recordcheck,1(force,in,27*******)") in new stack
    -- Executing [recordcheck@sub-record-check:1] NoOp("SIP/voxbeam_inbound-0000001e", "Starting recording check against force") in new stack
    -- Executing [recordcheck@sub-record-check:2] Goto("SIP/voxbeam_inbound-0000001e", "force") in new stack
    -- Goto (sub-record-check,recordcheck,5)
    -- Executing [recordcheck@sub-record-check:5] Set("SIP/voxbeam_inbound-0000001e", "__REC_POLICY_MODE=FORCE") in new stack
    -- Executing [recordcheck@sub-record-check:6] GotoIf("SIP/voxbeam_inbound-0000001e", "1?startrec") in new stack
    -- Goto (sub-record-check,recordcheck,16)
    -- Executing [recordcheck@sub-record-check:16] NoOp("SIP/voxbeam_inbound-0000001e", "Starting recording: in, 27*******") in new stack
    -- Executing [recordcheck@sub-record-check:17] Set("SIP/voxbeam_inbound-0000001e", "AUDIOHOOK_INHERIT(MixMonitor)=yes") in new stack
    -- Executing [recordcheck@sub-record-check:18] Set("SIP/voxbeam_inbound-0000001e", "__CALLFILENAME=in-27*******-**************-20170103-111548-1483434948.30") in new stack
    -- Executing [recordcheck@sub-record-check:19] MixMonitor("SIP/voxbeam_inbound-0000001e", "2017/01/03/in-27*******-**************-20170103-111548-1483434948.30.wav,ai(LOCAL_MIXMON_ID),") in new stack
    -- Executing [recordcheck@sub-record-check:20] Set("SIP/voxbeam_inbound-0000001e", "__MIXMON_ID=0x7f8df86e0050") in new stack
    -- Executing [recordcheck@sub-record-check:21] Set("SIP/voxbeam_inbound-0000001e", "__RECORD_ID=SIP/voxbeam_inbound-0000001e") in new stack
    -- Executing [recordcheck@sub-record-check:22] Set("SIP/voxbeam_inbound-0000001e", "__REC_STATUS=RECORDING") in new stack
    -- Executing [recordcheck@sub-record-check:23] Set("SIP/voxbeam_inbound-0000001e", "CDR(recordingfile)=in-27*******-**************-20170103-111548-1483434948.30.wav") in new stack
    -- Executing [recordcheck@sub-record-check:24] Return("SIP/voxbeam_inbound-0000001e", "") in new stack
    -- Executing [in@sub-record-check:5] Return("SIP/voxbeam_inbound-0000001e", "") in new stack
    -- Executing [27*******@from-trunk:3] Gosub("SIP/voxbeam_inbound-0000001e", "app-blacklist-check,s,1()") in new stack
    -- Executing [s@app-blacklist-check:1] GotoIf("SIP/voxbeam_inbound-0000001e", "0?blacklisted") in new stack
    -- Executing [s@app-blacklist-check:2] Set("SIP/voxbeam_inbound-0000001e", "CALLED_BLACKLIST=1") in new stack
    -- Executing [s@app-blacklist-check:3] Return("SIP/voxbeam_inbound-0000001e", "") in new stack
    -- Executing [27*******@from-trunk:4] Set("SIP/voxbeam_inbound-0000001e", "__FROM_DID=27*******") in new stack
    -- Executing [27*******@from-trunk:5] Set("SIP/voxbeam_inbound-0000001e", "CDR(did)=27*******") in new stack
    -- Executing [27*******@from-trunk:6] ExecIf("SIP/voxbeam_inbound-0000001e", "1 ?Set(CALLERID(name)=**************)") in new stack
    -- Executing [27*******@from-trunk:7] Set("SIP/voxbeam_inbound-0000001e", "__MOHCLASS=") in new stack
    -- Executing [27*******@from-trunk:8] Ringing("SIP/voxbeam_inbound-0000001e", "") in new stack

<--- Transmitting (no NAT) to 95.211.119.240:5060 --->
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 95.211.119.240;branch=z9hG4bK8c1b.6f182877.0;received=95.211.119.240
From: <sip:**************@voxbone.com>;tag=as26fd4e26
To: <sip:27*******@***.***.***.***>;tag=as7954a6ae
Call-ID: [email protected]
CSeq: 102 INVITE
Server: FPBX-13.0.190.8(13.13.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Contact: <sip:27*******@***.***.***.***:5060>
Content-Length: 0


<------------>
    -- Executing [27*******@from-trunk:9] Set("SIP/voxbeam_inbound-0000001e", "__RINGINGSENT=TRUE") in new stack
    -- Executing [27*******@from-trunk:10] Set("SIP/voxbeam_inbound-0000001e", "__REVERSAL_REJECT=TRUE") in new stack
    -- Executing [27*******@from-trunk:11] GotoIf("SIP/voxbeam_inbound-0000001e", "0?post-reverse-charge") in new stack
[2017-01-03 11:15:48] WARNING[957][C-00000014]: func_channel.c:450 func_channel_read: Unknown or unavailable item requested: 'reversecharge'
    -- Executing [27*******@from-trunk:12] GotoIf("SIP/voxbeam_inbound-0000001e", "0?macro-hangupcall") in new stack
    -- Executing [27*******@from-trunk:13] NoOp("SIP/voxbeam_inbound-0000001e", "") in new stack
    -- Executing [27*******@from-trunk:14] Set("SIP/voxbeam_inbound-0000001e", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
    -- Executing [27*******@from-trunk:15] Set("SIP/voxbeam_inbound-0000001e", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
    -- Executing [27*******@from-trunk:16] Set("SIP/voxbeam_inbound-0000001e", "CALLERID(name-pres)=allowed_not_screened") in new stack
    -- Executing [27*******@from-trunk:17] Set("SIP/voxbeam_inbound-0000001e", "CALLERID(num-pres)=allowed_not_screened") in new stack
    -- Executing [27*******@from-trunk:18] NoOp("SIP/voxbeam_inbound-0000001e", "CallerID Entry Point") in new stack
    -- Executing [27*******@from-trunk:19] Goto("SIP/voxbeam_inbound-0000001e", "from-did-direct,250,1") in new stack
    -- Goto (from-did-direct,250,1)
    -- Executing [250@from-did-direct:1] GotoIf("SIP/voxbeam_inbound-0000001e", "1?ext-local,250,1:followme-check,250,1") in new stack
    -- Goto (ext-local,250,1)
    -- Executing [250@ext-local:1] Set("SIP/voxbeam_inbound-0000001e", "__RINGTIMER=15") in new stack
    -- Executing [250@ext-local:2] Macro("SIP/voxbeam_inbound-0000001e", "exten-vm,novm,250,0,0,0") in new stack
    -- Executing [s@macro-exten-vm:1] Macro("SIP/voxbeam_inbound-0000001e", "user-callerid,") in new stack
    -- Executing [s@macro-user-callerid:1] Set("SIP/voxbeam_inbound-0000001e", "TOUCH_MONITOR=1483434948.30") in new stack
    -- Executing [s@macro-user-callerid:2] Set("SIP/voxbeam_inbound-0000001e", "AMPUSER=**************") in new stack
    -- Executing [s@macro-user-callerid:3] GotoIf("SIP/voxbeam_inbound-0000001e", "0?report") in new stack
    -- Executing [s@macro-user-callerid:4] ExecIf("SIP/voxbeam_inbound-0000001e", "1?Set(REALCALLERIDNUM=**************)") in new stack
    -- Executing [s@macro-user-callerid:5] Set("SIP/voxbeam_inbound-0000001e", "AMPUSER=") in new stack
    -- Executing [s@macro-user-callerid:6] GotoIf("SIP/voxbeam_inbound-0000001e", "0?limit") in new stack
    -- Executing [s@macro-user-callerid:7] Set("SIP/voxbeam_inbound-0000001e", "AMPUSERCIDNAME=") in new stack
    -- Executing [s@macro-user-callerid:8] GotoIf("SIP/voxbeam_inbound-0000001e", "1?report") in new stack
    -- Goto (macro-user-callerid,s,14)
    -- Executing [s@macro-user-callerid:14] GotoIf("SIP/voxbeam_inbound-0000001e", "0?continue") in new stack
    -- Executing [s@macro-user-callerid:15] Set("SIP/voxbeam_inbound-0000001e", "__TTL=64") in new stack
    -- Executing [s@macro-user-callerid:16] GotoIf("SIP/voxbeam_inbound-0000001e", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,27)
    -- Executing [s@macro-user-callerid:27] Set("SIP/voxbeam_inbound-0000001e", "CALLERID(number)=**************") in new stack
    -- Executing [s@macro-user-callerid:28] Set("SIP/voxbeam_inbound-0000001e", "CALLERID(name)=**************") in new stack
    -- Executing [s@macro-user-callerid:29] GotoIf("SIP/voxbeam_inbound-0000001e", "0?cnum") in new stack
    -- Executing [s@macro-user-callerid:30] Set("SIP/voxbeam_inbound-0000001e", "CDR(cnam)=**************") in new stack
    -- Executing [s@macro-user-callerid:31] Set("SIP/voxbeam_inbound-0000001e", "CDR(cnum)=**************") in new stack
    -- Executing [s@macro-user-callerid:32] Set("SIP/voxbeam_inbound-0000001e", "CHANNEL(language)=en") in new stack
    -- Executing [s@macro-exten-vm:2] Set("SIP/voxbeam_inbound-0000001e", "RingGroupMethod=none") in new stack
    -- Executing [s@macro-exten-vm:3] Set("SIP/voxbeam_inbound-0000001e", "__EXTTOCALL=250") in new stack
    -- Executing [s@macro-exten-vm:4] Set("SIP/voxbeam_inbound-0000001e", "__PICKUPMARK=250") in new stack
    -- Executing [s@macro-exten-vm:5] Set("SIP/voxbeam_inbound-0000001e", "RT=") in new stack
    -- Executing [s@macro-exten-vm:6] Gosub("SIP/voxbeam_inbound-0000001e", "sub-record-check,s,1(exten,250,dontcare)") in new stack
    -- Executing [s@sub-record-check:1] GotoIf("SIP/voxbeam_inbound-0000001e", "11?initialized") in new stack
    -- Goto (sub-record-check,s,10)
    -- Executing [s@sub-record-check:10] NoOp("SIP/voxbeam_inbound-0000001e", "Recordings initialized") in new stack
    -- Executing [s@sub-record-check:11] ExecIf("SIP/voxbeam_inbound-0000001e", "0?Set(ARG3=dontcare)") in new stack
    -- Executing [s@sub-record-check:12] Set("SIP/voxbeam_inbound-0000001e", "REC_POLICY_MODE_SAVE=FORCE") in new stack
    -- Executing [s@sub-record-check:13] ExecIf("SIP/voxbeam_inbound-0000001e", "0?Set(REC_STATUS=NO)") in new stack
    -- Executing [s@sub-record-check:14] GotoIf("SIP/voxbeam_inbound-0000001e", "5?checkaction") in new stack
    -- Goto (sub-record-check,s,17)
    -- Executing [s@sub-record-check:17] GotoIf("SIP/voxbeam_inbound-0000001e", "1?sub-record-check,exten,1") in new stack
    -- Goto (sub-record-check,exten,1)
    -- Executing [exten@sub-record-check:1] NoOp("SIP/voxbeam_inbound-0000001e", "Exten Recording Check between ************** and 250") in new stack
    -- Executing [exten@sub-record-check:2] Set("SIP/voxbeam_inbound-0000001e", "CALLTYPE=external") in new stack
    -- Executing [exten@sub-record-check:3] ExecIf("SIP/voxbeam_inbound-0000001e", "0?Set(CALLTYPE=)") in new stack
    -- Executing [exten@sub-record-check:4] Set("SIP/voxbeam_inbound-0000001e", "CALLEE=dontcare") in new stack
    -- Executing [exten@sub-record-check:5] ExecIf("SIP/voxbeam_inbound-0000001e", "0?Set(CALLEE=dontcare)") in new stack
    -- Executing [exten@sub-record-check:6] GotoIf("SIP/voxbeam_inbound-0000001e", "1?callee") in new stack
    -- Goto (sub-record-check,exten,11)
    -- Executing [exten@sub-record-check:11] Gosub("SIP/voxbeam_inbound-0000001e", "recordcheck,1(dontcare,external,250)") in new stack
    -- Executing [recordcheck@sub-record-check:1] NoOp("SIP/voxbeam_inbound-0000001e", "Starting recording check against dontcare") in new stack
    -- Executing [recordcheck@sub-record-check:2] Goto("SIP/voxbeam_inbound-0000001e", "dontcare") in new stack
    -- Goto (sub-record-check,recordcheck,3)
    -- Executing [recordcheck@sub-record-check:3] Return("SIP/voxbeam_inbound-0000001e", "") in new stack
    -- Executing [exten@sub-record-check:12] Return("SIP/voxbeam_inbound-0000001e", "") in new stack
    -- Executing [s@macro-exten-vm:7] GotoIf("SIP/voxbeam_inbound-0000001e", "1?macrodial") in new stack
    -- Goto (macro-exten-vm,s,13)
    -- Executing [s@macro-exten-vm:13] GosubIf("SIP/voxbeam_inbound-0000001e", "0?clrheader,1()") in new stack
  == Begin MixMonitor Recording SIP/voxbeam_inbound-0000001e
    -- Executing [s@macro-exten-vm:14] Macro("SIP/voxbeam_inbound-0000001e", "dial-one,,Ttr,250") in new stack
    -- Executing [s@macro-dial-one:1] Set("SIP/voxbeam_inbound-0000001e", "DEXTEN=250") in new stack
    -- Executing [s@macro-dial-one:2] ExecIf("SIP/voxbeam_inbound-0000001e", "0?Set(EXTTOCALL=250)") in new stack
    -- Executing [s@macro-dial-one:3] Set("SIP/voxbeam_inbound-0000001e", "DIALSTATUS_CW=") in new stack
    -- Executing [s@macro-dial-one:4] GosubIf("SIP/voxbeam_inbound-0000001e", "0?screen,1()") in new stack
    -- Executing [s@macro-dial-one:5] GosubIf("SIP/voxbeam_inbound-0000001e", "0?cf,1()") in new stack
    -- Executing [s@macro-dial-one:6] GotoIf("SIP/voxbeam_inbound-0000001e", "1?skip1") in new stack
    -- Goto (macro-dial-one,s,9)
    -- Executing [s@macro-dial-one:9] GotoIf("SIP/voxbeam_inbound-0000001e", "0?nodial") in new stack
    -- Executing [s@macro-dial-one:10] GotoIf("SIP/voxbeam_inbound-0000001e", "0?continue") in new stack
    -- Executing [s@macro-dial-one:11] Set("SIP/voxbeam_inbound-0000001e", "EXTHASCW=ENABLED") in new stack
    -- Executing [s@macro-dial-one:12] GotoIf("SIP/voxbeam_inbound-0000001e", "0?next1:cwinusebusy") in new stack
    -- Goto (macro-dial-one,s,24)
    -- Executing [s@macro-dial-one:24] GotoIf("SIP/voxbeam_inbound-0000001e", "0?next3:continue") in new stack
    -- Goto (macro-dial-one,s,26)
    -- Executing [s@macro-dial-one:26] GotoIf("SIP/voxbeam_inbound-0000001e", "0?nodial") in new stack
    -- Executing [s@macro-dial-one:27] GosubIf("SIP/voxbeam_inbound-0000001e", "1?dstring,1():dlocal,1()") in new stack
    -- Executing [dstring@macro-dial-one:1] Set("SIP/voxbeam_inbound-0000001e", "DSTRING=") in new stack
    -- Executing [dstring@macro-dial-one:2] Set("SIP/voxbeam_inbound-0000001e", "DEVICES=250") in new stack
    -- Executing [dstring@macro-dial-one:3] ExecIf("SIP/voxbeam_inbound-0000001e", "0?Return()") in new stack
    -- Executing [dstring@macro-dial-one:4] ExecIf("SIP/voxbeam_inbound-0000001e", "0?Set(DEVICES=50)") in new stack
    -- Executing [dstring@macro-dial-one:5] Set("SIP/voxbeam_inbound-0000001e", "LOOPCNT=1") in new stack
    -- Executing [dstring@macro-dial-one:6] Set("SIP/voxbeam_inbound-0000001e", "ITER=1") in new stack
    -- Executing [dstring@macro-dial-one:7] Set("SIP/voxbeam_inbound-0000001e", "THISDIAL=SIP/250") in new stack
    -- Executing [dstring@macro-dial-one:8] GosubIf("SIP/voxbeam_inbound-0000001e", "1?zap2dahdi,1()") in new stack
    -- Executing [zap2dahdi@macro-dial-one:1] ExecIf("SIP/voxbeam_inbound-0000001e", "0?Return()") in new stack
    -- Executing [zap2dahdi@macro-dial-one:2] Set("SIP/voxbeam_inbound-0000001e", "NEWDIAL=") in new stack
    -- Executing [zap2dahdi@macro-dial-one:3] Set("SIP/voxbeam_inbound-0000001e", "LOOPCNT2=1") in new stack
    -- Executing [zap2dahdi@macro-dial-one:4] Set("SIP/voxbeam_inbound-0000001e", "ITER2=1") in new stack
    -- Executing [zap2dahdi@macro-dial-one:5] Set("SIP/voxbeam_inbound-0000001e", "THISPART2=SIP/250") in new stack
    -- Executing [zap2dahdi@macro-dial-one:6] ExecIf("SIP/voxbeam_inbound-0000001e", "0?Set(THISPART2=DAHDI/250)") in new stack
    -- Executing [zap2dahdi@macro-dial-one:7] Set("SIP/voxbeam_inbound-0000001e", "NEWDIAL=SIP/250&") in new stack
    -- Executing [zap2dahdi@macro-dial-one:8] Set("SIP/voxbeam_inbound-0000001e", "ITER2=2") in new stack
    -- Executing [zap2dahdi@macro-dial-one:9] GotoIf("SIP/voxbeam_inbound-0000001e", "0?begin2") in new stack
    -- Executing [zap2dahdi@macro-dial-one:10] Set("SIP/voxbeam_inbound-0000001e", "THISDIAL=SIP/250") in new stack
    -- Executing [zap2dahdi@macro-dial-one:11] Return("SIP/voxbeam_inbound-0000001e", "") in new stack
    -- Executing [dstring@macro-dial-one:9] GotoIf("SIP/voxbeam_inbound-0000001e", "1?docheck") in new stack
    -- Goto (macro-dial-one,dstring,14)
    -- Executing [dstring@macro-dial-one:14] GotoIf("SIP/voxbeam_inbound-0000001e", "0?skipset") in new stack
    -- Executing [dstring@macro-dial-one:15] Set("SIP/voxbeam_inbound-0000001e", "DSTRING=SIP/250&") in new stack
    -- Executing [dstring@macro-dial-one:16] Set("SIP/voxbeam_inbound-0000001e", "ITER=2") in new stack
    -- Executing [dstring@macro-dial-one:17] GotoIf("SIP/voxbeam_inbound-0000001e", "0?begin") in new stack
    -- Executing [dstring@macro-dial-one:18] ExecIf("SIP/voxbeam_inbound-0000001e", "0?Return()") in new stack
    -- Executing [dstring@macro-dial-one:19] Set("SIP/voxbeam_inbound-0000001e", "DSTRING=SIP/250") in new stack
    -- Executing [dstring@macro-dial-one:20] Return("SIP/voxbeam_inbound-0000001e", "") in new stack
    -- Executing [s@macro-dial-one:28] GotoIf("SIP/voxbeam_inbound-0000001e", "0?nodial") in new stack
    -- Executing [s@macro-dial-one:29] GotoIf("SIP/voxbeam_inbound-0000001e", "0?skiptrace") in new stack
    -- Executing [s@macro-dial-one:30] GosubIf("SIP/voxbeam_inbound-0000001e", "1?ctset,1():ctclear,1()") in new stack
    -- Executing [ctset@macro-dial-one:1] Set("SIP/voxbeam_inbound-0000001e", "DB(CALLTRACE/250)=**************") in new stack
    -- Executing [ctset@macro-dial-one:2] Return("SIP/voxbeam_inbound-0000001e", "") in new stack
    -- Executing [s@macro-dial-one:31] Set("SIP/voxbeam_inbound-0000001e", "D_OPTIONS=Ttr") in new stack
    -- Executing [s@macro-dial-one:32] NoOp("SIP/voxbeam_inbound-0000001e", "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
    -- Executing [s@macro-dial-one:33] ExecIf("SIP/voxbeam_inbound-0000001e", "0?Set(ALERT_INFO=)") in new stack
    -- Executing [s@macro-dial-one:34] ExecIf("SIP/voxbeam_inbound-0000001e", "0?Set(ALERT_INFO=)") in new stack
    -- Executing [s@macro-dial-one:35] ExecIf("SIP/voxbeam_inbound-0000001e", "0?Set(ALERT_INFO=)") in new stack
    -- Executing [s@macro-dial-one:36] ExecIf("SIP/voxbeam_inbound-0000001e", "0?Set(ALERT_INFO=;volume=)") in new stack
    -- Executing [s@macro-dial-one:37] ExecIf("SIP/voxbeam_inbound-0000001e", "0?Set(ALERT_INFO=;volume=)") in new stack
    -- Executing [s@macro-dial-one:38] GosubIf("SIP/voxbeam_inbound-0000001e", "0?func-set-sipheader,s,1(Alert-Info,)") in new stack
    -- Executing [s@macro-dial-one:39] ExecIf("SIP/voxbeam_inbound-0000001e", "0?Set(CHANNEL(musicclass)=)") in new stack
    -- Executing [s@macro-dial-one:40] GosubIf("SIP/voxbeam_inbound-0000001e", "0?qwait,1()") in new stack
    -- Executing [s@macro-dial-one:41] Set("SIP/voxbeam_inbound-0000001e", "__CWIGNORE=") in new stack
    -- Executing [s@macro-dial-one:42] Set("SIP/voxbeam_inbound-0000001e", "__KEEPCID=TRUE") in new stack
    -- Executing [s@macro-dial-one:43] GotoIf("SIP/voxbeam_inbound-0000001e", "0?usegoto,1") in new stack
    -- Executing [s@macro-dial-one:44] GotoIf("SIP/voxbeam_inbound-0000001e", "1?godial") in new stack
    -- Goto (macro-dial-one,s,49)
    -- Executing [s@macro-dial-one:49] Macro("SIP/voxbeam_inbound-0000001e", "dialout-one-predial-hook,") in new stack
    -- Executing [s@macro-dialout-one-predial-hook:1] MacroExit("SIP/voxbeam_inbound-0000001e", "") in new stack
    -- Executing [s@macro-dial-one:50] ExecIf("SIP/voxbeam_inbound-0000001e", "1?Set(D_OPTIONS=trI)") in new stack
    -- Executing [s@macro-dial-one:51] Dial("SIP/voxbeam_inbound-0000001e", "SIP/250,,trIb(func-apply-sipheaders^s^1)") in new stack
  == Using SIP RTP TOS bits 184
  == Using SIP RTP CoS mark 5
    -- SIP/250-0000001f Internal Gosub(func-apply-sipheaders,s,1) start
    -- Executing [s@func-apply-sipheaders:1] NoOp("SIP/250-0000001f", "Applying SIP Headers to channel") in new stack
    -- Executing [s@func-apply-sipheaders:2] Set("SIP/250-0000001f", "SIPHEADERKEYS=") in new stack
    -- Executing [s@func-apply-sipheaders:3] While("SIP/250-0000001f", "0") in new stack
    -- Jumping to priority 7
    -- Executing [s@func-apply-sipheaders:8] Return("SIP/250-0000001f", "") in new stack
  == Spawn extension (from-internal, 250, 1) exited non-zero on 'SIP/250-0000001f'
    -- SIP/250-0000001f Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
Audio is at 11126
Adding codec ulaw to SDP
Adding codec alaw to SDP
Adding codec gsm to SDP
Adding codec g726 to SDP
Adding non-codec 0x1 (telephone-event) to SDP
Reliably Transmitting (NAT) to 196.210.50.90:5062:
INVITE sip:[email protected]:5062 SIP/2.0
Via: SIP/2.0/UDP ***.***.***.***:5060;branch=z9hG4bK6892511e;rport
Max-Forwards: 70
From: "**************" <sip:**************@***.***.***.***>;tag=as475af12a
To: <sip:[email protected]:5062>
Contact: <sip:**************@***.***.***.***:5060>
Call-ID: 001ab40300915dd34d69196b2fcdfa5a@***.***.***.***:5060
CSeq: 102 INVITE
User-Agent: FPBX-13.0.190.8(13.13.0)
Date: Tue, 03 Jan 2017 09:15:48 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
P-Asserted-Identity: "**************" <sip:**************@***.***.***.***>
Content-Type: application/sdp
Content-Length: 336

v=0
o=root 2045924796 2045924796 IN IP4 ***.***.***.***
s=Asterisk PBX 13.13.0
c=IN IP4 ***.***.***.***
t=0 0
m=audio 11126 RTP/AVP 0 8 3 111 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:111 G726-32/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=maxptime:150
a=sendrecv

mauritz · January 3, 2017, 9:50am

CONTINUED (LAST)

---
    -- Called SIP/250

<--- Transmitting (no NAT) to 95.211.119.240:5060 --->
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 95.211.119.240;branch=z9hG4bK8c1b.6f182877.0;received=95.211.119.240
From: <sip:**************@voxbone.com>;tag=as26fd4e26
To: <sip:27*******@***.***.***.***>;tag=as7954a6ae
Call-ID: [email protected]
CSeq: 102 INVITE
Server: FPBX-13.0.190.8(13.13.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Contact: <sip:27*******@***.***.***.***:5060>
Content-Length: 0


<------------>
    -- Connected line update to SIP/voxbeam_inbound-0000001e prevented.

<--- SIP read from UDP:196.210.50.90:5062 --->
SIP/2.0 100 Trying
Via: SIP/2.0/UDP ***.***.***.***:5060;branch=z9hG4bK6892511e;rport
From: "**************" <sip:**************@***.***.***.***>;tag=as475af12a
To: <sip:[email protected]:5062>
Call-ID: 001ab40300915dd34d69196b2fcdfa5a@***.***.***.***:5060
CSeq: 102 INVITE
User-Agent: Yealink SIP-W52P 25.73.0.40
Content-Length: 0

<------------->
--- (8 headers 0 lines) ---

<--- SIP read from UDP:196.210.50.90:5062 --->
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP ***.***.***.***:5060;branch=z9hG4bK6892511e;rport
From: "**************" <sip:**************@***.***.***.***>;tag=as475af12a
To: <sip:[email protected]:5062>;tag=1838789842
Call-ID: 001ab40300915dd34d69196b2fcdfa5a@***.***.***.***:5060
CSeq: 102 INVITE
Contact: <sip:[email protected]:5062>
Allow: INVITE, INFO, PRACK, ACK, BYE, CANCEL, OPTIONS, NOTIFY, REGISTER, SUBSCRIBE, REFER, PUBLISH, UPDATE, MESSAGE
User-Agent: Yealink SIP-W52P 25.73.0.40
Allow-Events: talk,hold,conference,refer,check-sync
Content-Length: 0

<------------->
--- (11 headers 0 lines) ---
sip_route_dump: route/path hop: <sip:[email protected]:5062>
    -- SIP/250-0000001f is ringing

<--- Transmitting (no NAT) to 95.211.119.240:5060 --->
SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 95.211.119.240;branch=z9hG4bK8c1b.6f182877.0;received=95.211.119.240
From: <sip:**************@voxbone.com>;tag=as26fd4e26
To: <sip:27*******@***.***.***.***>;tag=as7954a6ae
Call-ID: [email protected]
CSeq: 102 INVITE
Server: FPBX-13.0.190.8(13.13.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Contact: <sip:27*******@***.***.***.***:5060>
Content-Length: 0


<------------>

<--- SIP read from UDP:196.210.50.90:5062 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP ***.***.***.***:5060;branch=z9hG4bK6892511e;rport
From: "**************" <sip:**************@***.***.***.***>;tag=as475af12a
To: <sip:[email protected]:5062>;tag=1838789842
Call-ID: 001ab40300915dd34d69196b2fcdfa5a@***.***.***.***:5060
CSeq: 102 INVITE
Contact: <sip:[email protected]:5062>
Content-Type: application/sdp
Allow: INVITE, INFO, PRACK, ACK, BYE, CANCEL, OPTIONS, NOTIFY, REGISTER, SUBSCRIBE, REFER, PUBLISH, UPDATE, MESSAGE
User-Agent: Yealink SIP-W52P 25.73.0.40
Content-Length: 203

v=0
o=- 20001 20001 IN IP4 10.0.0.3
s=SDP data
c=IN IP4 10.0.0.3
t=0 0
m=audio 11782 RTP/AVP 0 101
a=rtpmap:0 PCMU/8000
a=sendrecv
a=ptime:20
a=fmtp:101 0-15
a=rtpmap:101 telephone-event/8000
<------------->
--- (11 headers 11 lines) ---
Found RTP audio format 0
Found RTP audio format 101
Found audio description format PCMU for ID 0
Found audio description format telephone-event for ID 101
Capabilities: us - (ulaw|alaw|gsm|g726), peer - audio=(ulaw)/video=(nothing)/text=(nothing), combined - (ulaw)
Non-codec capabilities (dtmf): us - 0x1 (telephone-event|), peer - 0x1 (telephone-event|), combined - 0x1 (telephone-event|)
Peer audio RTP is at port 10.0.0.3:11782
sip_route_dump: route/path hop: <sip:[email protected]:5062>
Transmitting (NAT) to 196.210.50.90:5062:
ACK sip:[email protected]:5062 SIP/2.0
Via: SIP/2.0/UDP ***.***.***.***:5060;branch=z9hG4bK60f44470;rport
Max-Forwards: 70
From: "**************" <sip:**************@***.***.***.***>;tag=as475af12a
To: <sip:[email protected]:5062>;tag=1838789842
Contact: <sip:**************@***.***.***.***:5060>
Call-ID: 001ab40300915dd34d69196b2fcdfa5a@***.***.***.***:5060
CSeq: 102 ACK
User-Agent: FPBX-13.0.190.8(13.13.0)
Content-Length: 0


---
    -- Connected line update to SIP/voxbeam_inbound-0000001e prevented.
    -- SIP/250-0000001f answered SIP/voxbeam_inbound-0000001e
Audio is at 14622
Adding codec ulaw to SDP
Adding codec alaw to SDP
Adding non-codec 0x1 (telephone-event) to SDP

<--- Reliably Transmitting (no NAT) to 95.211.119.240:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 95.211.119.240;branch=z9hG4bK8c1b.6f182877.0;received=95.211.119.240
From: <sip:**************@voxbone.com>;tag=as26fd4e26
To: <sip:27*******@***.***.***.***>;tag=as7954a6ae
Call-ID: [email protected]
CSeq: 102 INVITE
Server: FPBX-13.0.190.8(13.13.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Contact: <sip:27*******@***.***.***.***:5060>
Content-Type: application/sdp
Content-Length: 280

v=0
o=root 494452645 494452645 IN IP4 ***.***.***.***
s=Asterisk PBX 13.13.0
c=IN IP4 ***.***.***.***
t=0 0
m=audio 14622 RTP/AVP 0 8 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=maxptime:150
a=sendrecv

<------------>
    -- Channel SIP/250-0000001f joined 'simple_bridge' basic-bridge <e0812499-4a13-4f90-94db-67c3ef3635d2>
    -- Channel SIP/voxbeam_inbound-0000001e joined 'simple_bridge' basic-bridge <e0812499-4a13-4f90-94db-67c3ef3635d2>
       > 0x7f8df856c180 -- Probation passed - setting RTP source address to 196.210.50.90:11782
       > 0x7f8df856c180 -- Probation passed - setting RTP source address to 196.210.50.90:11782

<--- SIP read from UDP:95.211.119.240:5060 --->
ACK sip:27*******@***.***.***.***:5060 SIP/2.0
Call-ID: [email protected]
CSeq: 102 ACK
From: <sip:**************@voxbone.com>;tag=as26fd4e26
To: <sip:27*******@***.***.***.***>;tag=as7954a6ae
Via: SIP/2.0/UDP 95.211.119.240;branch=z9hG4bK8c1b.6f182877.2
Max-Forwards: 66
Contact: <sip:95.211.119.240;did=8f2.9608608>
User-Agent: Vox Callcontrol
Content-Length: 0

<------------->
--- (10 headers 0 lines) ---

<--- SIP read from UDP:196.210.50.90:38520 --->
REGISTER sip:sip.**********:5060;transport=UDP SIP/2.0
Via: SIP/2.0/UDP 196.210.50.90:38520;branch=z9hG4bK-524287-1---34a5d12dc0b17cd5;rport
Max-Forwards: 70
Contact: <sip:[email protected]:38520;transport=UDP;rinstance=6e1fa006ce5353b2>
To: <sip:251@sip.**********:5060;transport=UDP>
From: <sip:251@sip.**********:5060;transport=UDP>;tag=5c8d0d27
Call-ID: qvOBXd172J-iXJmdoixJBg..
CSeq: 259 REGISTER
Expires: 60
User-Agent: Zoiper rv2.8.15
Authorization: Digest username="251",realm="asterisk",nonce="5439bb9a",uri="sip:sip.**********:5060;transport=UDP",response="8bde247079bf6dc89ea316a83905b489",algorithm=MD5
Allow-Events: presence, kpml, talk
Content-Length: 0

<------------->
--- (13 headers 0 lines) ---
Sending to 196.210.50.90:38520 (no NAT)
Sending to 196.210.50.90:38520 (no NAT)

<--- Transmitting (NAT) to 196.210.50.90:38520 --->
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 196.210.50.90:38520;branch=z9hG4bK-524287-1---34a5d12dc0b17cd5;received=196.210.50.90;rport=38520
From: <sip:251@sip.**********:5060;transport=UDP>;tag=5c8d0d27
To: <sip:251@sip.**********:5060;transport=UDP>;tag=as4da2c045
Call-ID: qvOBXd172J-iXJmdoixJBg..
CSeq: 259 REGISTER
Server: FPBX-13.0.190.8(13.13.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="7ca3079a"
Content-Length: 0


<------------>
Scheduling destruction of SIP dialog 'qvOBXd172J-iXJmdoixJBg..' in 32000 ms (Method: REGISTER)

<--- SIP read from UDP:196.210.50.90:38520 --->
REGISTER sip:sip.**********:5060;transport=UDP SIP/2.0
Via: SIP/2.0/UDP 196.210.50.90:38520;branch=z9hG4bK-524287-1---2dbb33aed2546464;rport
Max-Forwards: 70
Contact: <sip:[email protected]:38520;transport=UDP;rinstance=211acbfc96c28721>
To: <sip:2787001@sip.**********:5060;transport=UDP>
From: <sip:2787001@sip.**********:5060;transport=UDP>;tag=7e14f657
Call-ID: 4iUWyctJxBaE2odcgYln7g..
CSeq: 259 REGISTER
Expires: 60
User-Agent: Zoiper rv2.8.15
Authorization: Digest username="2787001",realm="asterisk",nonce="2e05f3e5",uri="sip:sip.**********:5060;transport=UDP",response="9ad3a39165ee3ec96e4c0a084ad83913",algorithm=MD5
Allow-Events: presence, kpml, talk
Content-Length: 0

<------------->
--- (13 headers 0 lines) ---
Sending to 196.210.50.90:38520 (no NAT)
Sending to 196.210.50.90:38520 (no NAT)

<--- Transmitting (NAT) to 196.210.50.90:38520 --->
SIP/2.0 401 Unauthorized
Via: SIP/2.0/UDP 196.210.50.90:38520;branch=z9hG4bK-524287-1---2dbb33aed2546464;received=196.210.50.90;rport=38520
From: <sip:2787001@sip.**********:5060;transport=UDP>;tag=7e14f657
To: <sip:2787001@sip.**********:5060;transport=UDP>;tag=as69f32c36
Call-ID: 4iUWyctJxBaE2odcgYln7g..
CSeq: 259 REGISTER
Server: FPBX-13.0.190.8(13.13.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
WWW-Authenticate: Digest algorithm=MD5, realm="asterisk", nonce="7d961dbc"
Content-Length: 0


<------------>
Scheduling destruction of SIP dialog '4iUWyctJxBaE2odcgYln7g..' in 32000 ms (Method: REGISTER)

<--- SIP read from UDP:196.210.50.90:38520 --->
REGISTER sip:sip.**********:5060;transport=UDP SIP/2.0
Via: SIP/2.0/UDP 196.210.50.90:38520;branch=z9hG4bK-524287-1---b78f3ea8bd011f33;rport
Max-Forwards: 70
Contact: <sip:[email protected]:38520;transport=UDP;rinstance=6e1fa006ce5353b2>
To: <sip:251@sip.**********:5060;transport=UDP>
From: <sip:251@sip.**********:5060;transport=UDP>;tag=5c8d0d27
Call-ID: qvOBXd172J-iXJmdoixJBg..
CSeq: 260 REGISTER
Expires: 60
User-Agent: Zoiper rv2.8.15
Authorization: Digest username="251",realm="asterisk",nonce="7ca3079a",uri="sip:sip.**********:5060;transport=UDP",response="14d66e70380faeeead1839f606d9db26",algorithm=MD5
Allow-Events: presence, kpml, talk
Content-Length: 0

<------------->
--- (13 headers 0 lines) ---
Sending to 196.210.50.90:38520 (NAT)
Reliably Transmitting (NAT) to 196.210.50.90:38520:
OPTIONS sip:[email protected]:38520;transport=UDP;rinstance=6e1fa006ce5353b2 SIP/2.0
Via: SIP/2.0/UDP ***.***.***.***:5060;branch=z9hG4bK65fd9078;rport
Max-Forwards: 70
From: "Unknown" <sip:Unknown@***.***.***.***>;tag=as3e703d5d
To: <sip:[email protected]:38520;transport=UDP;rinstance=6e1fa006ce5353b2>
Contact: <sip:Unknown@***.***.***.***:5060>
Call-ID: 7850e9ba4127314978e054ad024f2bc4@***.***.***.***:5060
CSeq: 102 OPTIONS
User-Agent: FPBX-13.0.190.8(13.13.0)
Date: Tue, 03 Jan 2017 09:15:55 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0


---

<--- Transmitting (NAT) to 196.210.50.90:38520 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 196.210.50.90:38520;branch=z9hG4bK-524287-1---b78f3ea8bd011f33;received=196.210.50.90;rport=38520
From: <sip:251@sip.**********:5060;transport=UDP>;tag=5c8d0d27
To: <sip:251@sip.**********:5060;transport=UDP>;tag=as4da2c045
Call-ID: qvOBXd172J-iXJmdoixJBg..
CSeq: 260 REGISTER
Server: FPBX-13.0.190.8(13.13.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Expires: 60
Contact: <sip:[email protected]:38520;transport=UDP;rinstance=6e1fa006ce5353b2>;expires=60
Date: Tue, 03 Jan 2017 09:15:55 GMT
Content-Length: 0


<------------>
Scheduling destruction of SIP dialog 'qvOBXd172J-iXJmdoixJBg..' in 32000 ms (Method: REGISTER)

<--- SIP read from UDP:196.210.50.90:38520 --->
REGISTER sip:sip.**********:5060;transport=UDP SIP/2.0
Via: SIP/2.0/UDP 196.210.50.90:38520;branch=z9hG4bK-524287-1---e4f8e360cde637b1;rport
Max-Forwards: 70
Contact: <sip:[email protected]:38520;transport=UDP;rinstance=211acbfc96c28721>
To: <sip:2787001@sip.**********:5060;transport=UDP>
From: <sip:2787001@sip.**********:5060;transport=UDP>;tag=7e14f657
Call-ID: 4iUWyctJxBaE2odcgYln7g..
CSeq: 260 REGISTER
Expires: 60
User-Agent: Zoiper rv2.8.15
Authorization: Digest username="2787001",realm="asterisk",nonce="7d961dbc",uri="sip:sip.**********:5060;transport=UDP",response="a96b40129d5789f972f587f49725cf5b",algorithm=MD5
Allow-Events: presence, kpml, talk
Content-Length: 0

<------------->
--- (13 headers 0 lines) ---
Sending to 196.210.50.90:38520 (NAT)
Reliably Transmitting (NAT) to 196.210.50.90:38520:
OPTIONS sip:[email protected]:38520;transport=UDP;rinstance=211acbfc96c28721 SIP/2.0
Via: SIP/2.0/UDP ***.***.***.***:5060;branch=z9hG4bK64833c3b;rport
Max-Forwards: 70
From: "Unknown" <sip:Unknown@***.***.***.***>;tag=as0787a10f
To: <sip:[email protected]:38520;transport=UDP;rinstance=211acbfc96c28721>
Contact: <sip:Unknown@***.***.***.***:5060>
Call-ID: 0bd1ae311c5ac47643e4d0c22680a939@***.***.***.***:5060
CSeq: 102 OPTIONS
User-Agent: FPBX-13.0.190.8(13.13.0)
Date: Tue, 03 Jan 2017 09:15:55 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0


---

<--- Transmitting (NAT) to 196.210.50.90:38520 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 196.210.50.90:38520;branch=z9hG4bK-524287-1---e4f8e360cde637b1;received=196.210.50.90;rport=38520
From: <sip:2787001@sip.**********:5060;transport=UDP>;tag=7e14f657
To: <sip:2787001@sip.**********:5060;transport=UDP>;tag=as69f32c36
Call-ID: 4iUWyctJxBaE2odcgYln7g..
CSeq: 260 REGISTER
Server: FPBX-13.0.190.8(13.13.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Expires: 60
Contact: <sip:[email protected]:38520;transport=UDP;rinstance=211acbfc96c28721>;expires=60
Date: Tue, 03 Jan 2017 09:15:55 GMT
Content-Length: 0


<------------>
Scheduling destruction of SIP dialog '16890d46179247c1692423c30ef01939@***.***.***.***:5060' in 6400 ms (Method: NOTIFY)
Reliably Transmitting (NAT) to 196.210.50.90:38520:
NOTIFY sip:[email protected]:38520;transport=UDP;rinstance=211acbfc96c28721 SIP/2.0
Via: SIP/2.0/UDP ***.***.***.***:5060;branch=z9hG4bK05352529;rport
Max-Forwards: 70
From: "Unknown" <sip:Unknown@***.***.***.***>;tag=as26e2ac1f
To: <sip:[email protected]:38520;transport=UDP;rinstance=211acbfc96c28721>
Contact: <sip:Unknown@***.***.***.***:5060>
Call-ID: 16890d46179247c1692423c30ef01939@***.***.***.***:5060
CSeq: 102 NOTIFY
User-Agent: FPBX-13.0.190.8(13.13.0)
Event: message-summary
Content-Type: application/simple-message-summary
Content-Length: 90

Messages-Waiting: no
Message-Account: sip:*97@***.***.***.***
Voice-Message: 0/0 (0/0)

---
Scheduling destruction of SIP dialog '4iUWyctJxBaE2odcgYln7g..' in 32000 ms (Method: REGISTER)

<--- SIP read from UDP:196.210.50.90:38520 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP ***.***.***.***:5060;branch=z9hG4bK65fd9078;rport=5060
Contact: <sip:10.0.0.240:38520>
To: <sip:[email protected]:38520;transport=UDP;rinstance=6e1fa006ce5353b2>;tag=754eae57
From: "Unknown" <sip:Unknown@***.***.***.***>;tag=as3e703d5d
Call-ID: 7850e9ba4127314978e054ad024f2bc4@***.***.***.***:5060
CSeq: 102 OPTIONS
Accept: application/sdp, application/sdp
Accept-Language: en
Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE
Supported: replaces, norefersub, extended-refer, timer, outbound, path, X-cisco-serviceuri
User-Agent: Zoiper rv2.8.15
Allow-Events: presence, kpml, talk
Content-Length: 0

<------------->
--- (14 headers 0 lines) ---
Really destroying SIP dialog '7850e9ba4127314978e054ad024f2bc4@***.***.***.***:5060' Method: OPTIONS

<--- SIP read from UDP:196.210.50.90:38520 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP ***.***.***.***:5060;branch=z9hG4bK64833c3b;rport=5060
Contact: <sip:10.0.0.240:38520>
To: <sip:[email protected]:38520;transport=UDP;rinstance=211acbfc96c28721>;tag=ef150a2d
From: "Unknown" <sip:Unknown@***.***.***.***>;tag=as0787a10f
Call-ID: 0bd1ae311c5ac47643e4d0c22680a939@***.***.***.***:5060
CSeq: 102 OPTIONS
Accept: application/sdp, application/sdp
Accept-Language: en
Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE
Supported: replaces, norefersub, extended-refer, timer, outbound, path, X-cisco-serviceuri
User-Agent: Zoiper rv2.8.15
Allow-Events: presence, kpml, talk
Content-Length: 0

<------------->
--- (14 headers 0 lines) ---
Really destroying SIP dialog '0bd1ae311c5ac47643e4d0c22680a939@***.***.***.***:5060' Method: OPTIONS

<--- SIP read from UDP:196.210.50.90:38520 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP ***.***.***.***:5060;branch=z9hG4bK05352529;rport=5060
Contact: <sip:10.0.0.240:38520>
To: <sip:[email protected]:38520;transport=UDP;rinstance=211acbfc96c28721>;tag=36fd1b23
From: "Unknown" <sip:Unknown@***.***.***.***>;tag=as26e2ac1f
Call-ID: 16890d46179247c1692423c30ef01939@***.***.***.***:5060
CSeq: 102 NOTIFY
User-Agent: Zoiper rv2.8.15
Content-Length: 0

<------------->
--- (9 headers 0 lines) ---
Really destroying SIP dialog '16890d46179247c1692423c30ef01939@***.***.***.***:5060' Method: NOTIFY

<--- SIP read from UDP:95.211.119.240:5060 --->
BYE sip:27*******@***.***.***.***:5060 SIP/2.0
Call-ID: [email protected]
CSeq: 103 BYE
From: <sip:**************@voxbone.com>;tag=as26fd4e26
To: <sip:27*******@***.***.***.***>;tag=as7954a6ae
Via: SIP/2.0/UDP 95.211.119.240;branch=z9hG4bK9c1b.801ff6a3.0
Max-Forwards: 66
User-Agent: Vox Callcontrol
Content-Length: 0

<------------->
--- (9 headers 0 lines) ---
Sending to 95.211.119.240:5060 (no NAT)
Scheduling destruction of SIP dialog '[email protected]' in 32000 ms (Method: BYE)

<--- Transmitting (no NAT) to 95.211.119.240:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 95.211.119.240;branch=z9hG4bK9c1b.801ff6a3.0;received=95.211.119.240
From: <sip:**************@voxbone.com>;tag=as26fd4e26
To: <sip:27*******@***.***.***.***>;tag=as7954a6ae
Call-ID: [email protected]
CSeq: 103 BYE
Server: FPBX-13.0.190.8(13.13.0)
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0

<------------>
    -- Channel SIP/voxbeam_inbound-0000001e left 'simple_bridge' basic-bridge <e0812499-4a13-4f90-94db-67c3ef3635d2>
  == Spawn extension (macro-dial-one, s, 51) exited non-zero on 'SIP/voxbeam_inbound-0000001e' in macro 'dial-one'
  == Spawn extension (macro-exten-vm, s, 14) exited non-zero on 'SIP/voxbeam_inbound-0000001e' in macro 'exten-vm'
  == Spawn extension (ext-local, 250, 2) exited non-zero on 'SIP/voxbeam_inbound-0000001e'
    -- Executing [h@ext-local:1] Macro("SIP/voxbeam_inbound-0000001e", "hangupcall,") in new stack
    -- Executing [s@macro-hangupcall:1] GotoIf("SIP/voxbeam_inbound-0000001e", "1?theend") in new stack
    -- Goto (macro-hangupcall,s,3)
    -- Channel SIP/250-0000001f left 'simple_bridge' basic-bridge <e0812499-4a13-4f90-94db-67c3ef3635d2>
Scheduling destruction of SIP dialog '001ab40300915dd34d69196b2fcdfa5a@***.***.***.***:5060' in 6400 ms (Method: INVITE)
Reliably Transmitting (NAT) to 196.210.50.90:5062:
BYE sip:[email protected]:5062 SIP/2.0
Via: SIP/2.0/UDP ***.***.***.***:5060;branch=z9hG4bK42394ccc;rport
Max-Forwards: 70
From: "**************" <sip:**************@***.***.***.***>;tag=as475af12a
To: <sip:[email protected]:5062>;tag=1838789842
Call-ID: 001ab40300915dd34d69196b2fcdfa5a@***.***.***.***:5060
CSeq: 103 BYE
User-Agent: FPBX-13.0.190.8(13.13.0)
X-Asterisk-HangupCause: Normal Clearing
X-Asterisk-HangupCauseCode: 16
Content-Length: 0

dcitelecom · October 29, 2021, 10:12pm

Did you ever resolve the issue? I have the same problem with our system and a Voxbeam outbound trunk. We also have a FreePBX 13 system.

I narrowed the problem down to the firewall. If the FreePBX firewall is disabled, then calls work fine. If I enable it, we can not make calls with the Voxbeam trunk.