[Freeswitch-users] 480 Temporarily Unavailable[MANDATORY_IE_MISSING]

Thu Mar 31 18:19:13 MSD 2016

Hi Michael

Thank you for your assistance.

We are trying to make call between two freeswitch users.

One user is registered in softphone and other is registered in Asterisk by
register string but asterisk user cannot authenticate properly and
freeswitch directly send 480 Temporary Unavailable.

In sip profile we set challenge-realm= auto_to also tried with auto_from
but still not getting success.

I am attaching some more logs if anyone can guide in right direction.

--
Thanks,
Rutu Patel

On Mon, Mar 28, 2016 at 10:23 PM, Michael Collins <msc at freeswitch.org>
wrote:

> Try a gateway. Look in conf/sip_profiles/external/example.xml for a
> heavily-commented sample. For more detailed discussion see this nice doc:
> https://freeswitch.org/confluence/display/FREESWITCH/Gateways+Configuration
>
> -MSC
>
> On Fri, Mar 25, 2016 at 11:34 PM, Rutu Patel <rutu.patel at inextrix.com>
> wrote:
>
>> Hi,
>>
>> Thank you for reply.
>>
>> We have registered extension of Freeswitch in Asterisk as below
>>
>> register=>test-ext:secret at 192.168.1.64/test-ext
>>
>> We want to send call from Freeswitch to Asterisk.
>>
>> What authentication parameters we need to set in Freeswitch ?
>>
>> --
>> Thanks,
>> Rutu Patel
>> On Wed, Mar 23, 2016 at 8:46 PM, Anthony Minessale <
>> anthony.minessale at gmail.com> wrote:
>>
>>> You need to supply auth credentials to Asterisk because its set to
>>> authenticate calls.
>>> The best thing to do would be to make a gateway pointing to Asterisk
>>> with all the credentials and call it using that.
>>>
>>>
>>>
>>>
>>> On Wed, Mar 23, 2016 at 10:20 AM, Jurijs Ivolga <jurijs.ivolga at gmail.com
>>> > wrote:
>>>
>>>> Hi Rutu,
>>>>
>>>> In logs I can see that user-agent Asterisk is sending INVITE to
>>>> Freeswitch and Freeswitch replies with "480 Temporarily Unavailable". This
>>>> a bit differs from what you explained in your mail...
>>>>
>>>> First think what pop up in my mind is that you need to configure
>>>> Asterisk as a trunk on Freeswitch, not sure if this helps, but this is my
>>>> first guess...
>>>>
>>>> https://freeswitch.org/confluence/display/FREESWITCH/Asterisk
>>>>
>>>> With kind regards,
>>>>
>>>> Jurijs
>>>>
>>>> On Wed, Mar 23, 2016 at 3:46 PM, Rutu Patel <rutu.patel at inextrix.com>
>>>> wrote:
>>>>
>>>>> Hi,
>>>>>
>>>>> We are sending call to asterisk PBX but we are getting 480 Temporarily
>>>>> Unavailable immediately. from freeswitch server.
>>>>>
>>>>> Call flow is as below:
>>>>>
>>>>> User => opensips => FS => PBX,
>>>>>
>>>>> but call not sending out from FS and drop by FS.
>>>>>
>>>>> Here I am attaching both sip logs and freeswitch logs.
>>>>>
>>>>> Can anyone please help to sort out the issue.
>>>>> --
>>>>> Thanks,
>>>>> Rutu Patel
>>>>> <http://www.inextrix.com>
>>>>>
>>>>>
>>>>> _________________________________________________________________________
>>>>> Professional FreeSWITCH Consulting Services:
>>>>> consulting at freeswitch.org
>>>>> http://www.freeswitchsolutions.com
>>>>>
>>>>> Official FreeSWITCH Sites
>>>>> http://www.freeswitch.org
>>>>> http://confluence.freeswitch.org
>>>>> http://www.cluecon.com
>>>>>
>>>>> FreeSWITCH-users mailing list
>>>>> FreeSWITCH-users at lists.freeswitch.org
>>>>> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
>>>>> UNSUBSCRIBE:
>>>>> http://lists.freeswitch.org/mailman/options/freeswitch-users
>>>>> http://www.freeswitch.org
>>>>>
>>>>
>>>>
>>>>
>>>> _________________________________________________________________________
>>>> Professional FreeSWITCH Consulting Services:
>>>> consulting at freeswitch.org
>>>> http://www.freeswitchsolutions.com
>>>>
>>>> Official FreeSWITCH Sites
>>>> http://www.freeswitch.org
>>>> http://confluence.freeswitch.org
>>>> http://www.cluecon.com
>>>>
>>>> FreeSWITCH-users mailing list
>>>> FreeSWITCH-users at lists.freeswitch.org
>>>> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
>>>> UNSUBSCRIBE:
>>>> http://lists.freeswitch.org/mailman/options/freeswitch-users
>>>> http://www.freeswitch.org
>>>>
>>>
>>>
>>>
>>> --
>>> Anthony Minessale II       ♬ @anthmfs  ♬ @FreeSWITCH  ♬
>>>
>>> ☞ http://freeswitch.org/  ☞ http://cluecon.com/  ☞
>>> http://twitter.com/FreeSWITCH
>>> ☞ irc.freenode.net #freeswitch ☞ *http://freeswitch.org/g+
>>> <http://freeswitch.org/g+>*
>>>
>>> ClueCon Weekly Development Call
>>> ☎ sip:888 at conference.freeswitch.org  ☎ +19193869900
>>>
>>> https://www.youtube.com/watch?v=9XXgW34t40s
>>> https://www.youtube.com/watch?v=NLaDpGQuZDA
>>>
>>> _________________________________________________________________________
>>> Professional FreeSWITCH Consulting Services:
>>> consulting at freeswitch.org
>>> http://www.freeswitchsolutions.com
>>>
>>> Official FreeSWITCH Sites
>>> http://www.freeswitch.org
>>> http://confluence.freeswitch.org
>>> http://www.cluecon.com
>>>
>>> FreeSWITCH-users mailing list
>>> FreeSWITCH-users at lists.freeswitch.org
>>> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
>>> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
>>> http://www.freeswitch.org
>>>
>>
>>
>> _________________________________________________________________________
>> Professional FreeSWITCH Consulting Services:
>> consulting at freeswitch.org
>> http://www.freeswitchsolutions.com
>>
>> Official FreeSWITCH Sites
>> http://www.freeswitch.org
>> http://confluence.freeswitch.org
>> http://www.cluecon.com
>>
>> FreeSWITCH-users mailing list
>> FreeSWITCH-users at lists.freeswitch.org
>> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
>> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
>> http://www.freeswitch.org
>>
>
>
> _________________________________________________________________________
> Professional FreeSWITCH Consulting Services:
> consulting at freeswitch.org
> http://www.freeswitchsolutions.com
>
> Official FreeSWITCH Sites
> http://www.freeswitch.org
> http://confluence.freeswitch.org
> http://www.cluecon.com
>
> FreeSWITCH-users mailing list
> FreeSWITCH-users at lists.freeswitch.org
> http://lists.freeswitch.org/mailman/listinfo/freeswitch-users
> UNSUBSCRIBE:http://lists.freeswitch.org/mailman/options/freeswitch-users
> http://www.freeswitch.org
>
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20160331/7526f0c5/attachment-0001.html 
-------------- next part --------------
U x.x.x.45:5060 -> x.x.x.46:5060
INVITE sip:123654874 at x.x.x.46:5060;transport=UDP SIP/2.0.
Via: SIP/2.0/UDP x.x.x.45:5060;branch=z9hG4bK7e79.8122dbe3.0.
Max-Forwards: 30.
Contact: <sip:xxxxxxxx20 at x.x.x.45;did=0b7.4eb7f677>.
To: <sip:123654874 at x.x.x.45;transport=UDP>.
From: <sip:xxxxxxxx20 at x.x.x.45;transport=UDP>;tag=fe41ad73.
Call-ID: DLGCH_AjQCGR4dNDsCCg0ECR4WOhYKDlwcHRViFic3BgoeFj4CGj8FHB0GPgEdNEA-.
CSeq: 1 INVITE.
Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE.
Content-Type: application/sdp.
Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri.
User-Agent: Z 3.3.25608 r25552.
Allow-Events: presence, kpml.
Content-Length: 241.
X-AUTH-IP: x.x.x.47.
.
v=0.
o=Z 0 0 IN IP4 x.x.x.47.
s=Z.
c=IN IP4 x.x.x.47.
t=0 0.
m=audio 8000 RTP/AVP 3 110 8 0 98 101.
a=rtpmap:110 speex/8000.
a=rtpmap:98 iLBC/8000.
a=fmtp:98 mode=20.
a=rtpmap:101 telephone-event/8000.
a=fmtp:101 0-15.
a=sendrecv.

U x.x.x.46:5060 -> x.x.x.45:5060
SIP/2.0 100 Trying.
Via: SIP/2.0/UDP x.x.x.45:5060;branch=z9hG4bK7e79.8122dbe3.0.
From: <sip:xxxxxxxx20 at x.x.x.45;transport=UDP>;tag=fe41ad73.
To: <sip:123654874 at x.x.x.45;transport=UDP>.
Call-ID: DLGCH_AjQCGR4dNDsCCg0ECR4WOhYKDlwcHRViFic3BgoeFj4CGj8FHB0GPgEdNEA-.
CSeq: 1 INVITE.
User-Agent: FreeSWITCH-mod_sofia/1.6.6+git~20160111T201612Z~d2d0b3283a~64bit.
Content-Length: 0.
.

U x.x.x.46:5060 -> x.x.x.45:5060
SIP/2.0 407 Proxy Authentication Required.
Via: SIP/2.0/UDP x.x.x.45:5060;branch=z9hG4bK7e79.8122dbe3.0.
From: <sip:xxxxxxxx20 at x.x.x.45;transport=UDP>;tag=fe41ad73.
To: <sip:123654874 at x.x.x.45;transport=UDP>;tag=NcN62NvFe1yQa.
Call-ID: DLGCH_AjQCGR4dNDsCCg0ECR4WOhYKDlwcHRViFic3BgoeFj4CGj8FHB0GPgEdNEA-.
CSeq: 1 INVITE.
User-Agent: FreeSWITCH-mod_sofia/1.6.6+git~20160111T201612Z~d2d0b3283a~64bit.
Accept: application/sdp.
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE.
Supported: timer, path, replaces.
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer.
Proxy-Authenticate: Digest realm="x.x.x.45", nonce="89522af1-ae9e-49db-8bca-7edcf8a07e2a", algorithm=MD5, qop="auth".
Content-Length: 0.
.

U x.x.x.45:5060 -> x.x.x.46:5060
ACK sip:123654874 at x.x.x.46:5060;transport=UDP SIP/2.0.
Via: SIP/2.0/UDP x.x.x.45:5060;branch=z9hG4bK7e79.8122dbe3.0.
From: <sip:xxxxxxxx20 at x.x.x.45;transport=UDP>;tag=fe41ad73.
Call-ID: DLGCH_AjQCGR4dNDsCCg0ECR4WOhYKDlwcHRViFic3BgoeFj4CGj8FHB0GPgEdNEA-.
To: <sip:123654874 at x.x.x.45;transport=UDP>;tag=NcN62NvFe1yQa.
CSeq: 1 ACK.
Max-Forwards: 70.
User-Agent: Cvt SIP Proxy.
Content-Length: 0.
.

U x.x.x.45:5060 -> x.x.x.46:5060
INVITE sip:123654874 at x.x.x.46:5060;transport=UDP SIP/2.0.
Via: SIP/2.0/UDP x.x.x.45:5060;branch=z9hG4bK4e79.8d4872f2.0.
Max-Forwards: 30.
Contact: <sip:xxxxxxxx20 at x.x.x.45;did=0b7.5eb7f677>.
To: <sip:123654874 at x.x.x.45;transport=UDP>.
From: <sip:xxxxxxxx20 at x.x.x.45;transport=UDP>;tag=fe41ad73.
Call-ID: DLGCH_AjQCGR4dNDsCCg0ECR4WOhYKDlwcHRViFic3BgoeFj4CGj8FHB0GPgEdNEA-.
CSeq: 2 INVITE.
Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE.
Content-Type: application/sdp.
Proxy-Authorization: Digest username="xxxxxxxx20",realm="x.x.x.45",nonce="89522af1-ae9e-49db-8bca-7edcf8a07e2a",uri="sip:123654874 at x.x.x.45;transport=UDP",response="b1366a968d661e9e52ef598b12f0e406",cnonce="ce0b2af8b0033103c9c45aada7202e94",nc=00000001,qop=auth,algorithm=MD5.
Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri.
User-Agent: Z 3.3.25608 r25552.
Allow-Events: presence, kpml.
Content-Length: 241.
X-AUTH-IP: x.x.x.47.
.
v=0.
o=Z 0 0 IN IP4 x.x.x.47.
s=Z.
c=IN IP4 x.x.x.47.
t=0 0.
m=audio 8000 RTP/AVP 3 110 8 0 98 101.
a=rtpmap:110 speex/8000.
a=rtpmap:98 iLBC/8000.
a=fmtp:98 mode=20.
a=rtpmap:101 telephone-event/8000.
a=fmtp:101 0-15.
a=sendrecv.

U x.x.x.46:5060 -> x.x.x.45:5060
SIP/2.0 100 Trying.
Via: SIP/2.0/UDP x.x.x.45:5060;branch=z9hG4bK4e79.8d4872f2.0.
From: <sip:xxxxxxxx20 at x.x.x.45;transport=UDP>;tag=fe41ad73.
To: <sip:123654874 at x.x.x.45;transport=UDP>.
Call-ID: DLGCH_AjQCGR4dNDsCCg0ECR4WOhYKDlwcHRViFic3BgoeFj4CGj8FHB0GPgEdNEA-.
CSeq: 2 INVITE.
User-Agent: FreeSWITCH-mod_sofia/1.6.6+git~20160111T201612Z~d2d0b3283a~64bit.
Content-Length: 0.
.

U x.x.x.46:5060 -> x.x.x.45:5060
INVITE sip:123654874 at x.x.x.42:5060 SIP/2.0.
Via: SIP/2.0/UDP x.x.x.46;rport;branch=z9hG4bKcUZNpKm5FBD4e.
Route: <sip:x.x.x.45>;lr;received=sip:x.x.x.42:5060.
Max-Forwards: 29.
From: "Extension 1001" <sip:1001 at x.x.x.46>;tag=Qy7Q6Byp8jBXH.
To: <sip:123654874 at x.x.x.42:5060>.
Call-ID: d8158cb3-71eb-1234-a581-7e3d9898227f.
CSeq: 89380367 INVITE.
Contact: <sip:mod_sofia at x.x.x.46:5060>.
User-Agent: FreeSWITCH-mod_sofia/1.6.6+git~20160111T201612Z~d2d0b3283a~64bit.
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE.
Supported: timer, path, replaces.
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer.
Content-Type: application/sdp.
Content-Disposition: session.
Content-Length: 248.
X-AUTH-IP: x.x.x.47.
X-FS-Support: update_display,send_info.
Remote-Party-ID: "Extension 1001" <sip:1001 at x.x.x.46>;party=calling;screen=yes;privacy=off.
.
v=0.
o=FreeSWITCH 1459403220 1459403221 IN IP4 x.x.x.46.
s=FreeSWITCH.
c=IN IP4 x.x.x.46.
t=0 0.
m=audio 29642 RTP/AVP 8 101 13.
a=rtpmap:8 PCMA/8000.
a=rtpmap:101 telephone-event/8000.
a=fmtp:101 0-16.
a=rtpmap:13 CN/8000.
a=ptime:20.

U x.x.x.45:5060 -> x.x.x.46:5060
SIP/2.0 100 Trying.
Via: SIP/2.0/UDP x.x.x.46;received=x.x.x.46;rport=5060;branch=z9hG4bKcUZNpKm5FBD4e.
From: "Extension 1001" <sip:1001 at x.x.x.46>;tag=Qy7Q6Byp8jBXH.
To: <sip:123654874 at x.x.x.42:5060>.
Call-ID: d8158cb3-71eb-1234-a581-7e3d9898227f.
CSeq: 89380367 INVITE.
server: Cvt SBC.
Content-Length: 0.
.

U x.x.x.45:5060 -> x.x.x.46:5060
SIP/2.0 401 Unauthorized.
Via: SIP/2.0/UDP x.x.x.46;received=x.x.x.46;rport=5060;branch=z9hG4bKcUZNpKm5FBD4e.
From: "Extension 1001" <sip:1001 at x.x.x.46>;tag=Qy7Q6Byp8jBXH.
To: <sip:123654874 at x.x.x.42:5060>;tag=as3a58be86.
Call-ID: d8158cb3-71eb-1234-a581-7e3d9898227f.
CSeq: 89380367 INVITE.
Server: CvtVPBX.
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE.
Supported: replaces, timer.
WWW-Authenticate: Digest algorithm=MD5, realm="pbx.registerendpoint.com", nonce="38cef028".
Content-Length: 0.
.

U x.x.x.46:5060 -> x.x.x.45:5060
ACK sip:123654874 at x.x.x.42:5060 SIP/2.0.
Via: SIP/2.0/UDP x.x.x.46;rport;branch=z9hG4bKcUZNpKm5FBD4e.
Route: <sip:x.x.x.45>;lr;received=sip:x.x.x.42:5060.
Max-Forwards: 29.
From: "Extension 1001" <sip:1001 at x.x.x.46>;tag=Qy7Q6Byp8jBXH.
To: <sip:123654874 at x.x.x.42:5060>;tag=as3a58be86.
Call-ID: d8158cb3-71eb-1234-a581-7e3d9898227f.
CSeq: 89380367 ACK.
Content-Length: 0.
.

U x.x.x.46:5060 -> x.x.x.45:5060
SIP/2.0 480 Temporarily Unavailable.
Via: SIP/2.0/UDP x.x.x.45:5060;branch=z9hG4bK4e79.8d4872f2.0.
Max-Forwards: 30.
From: <sip:xxxxxxxx20 at x.x.x.45;transport=UDP>;tag=fe41ad73.
To: <sip:123654874 at x.x.x.45;transport=UDP>;tag=pNeZ4gDKBaNap.
Call-ID: DLGCH_AjQCGR4dNDsCCg0ECR4WOhYKDlwcHRViFic3BgoeFj4CGj8FHB0GPgEdNEA-.
CSeq: 2 INVITE.
User-Agent: FreeSWITCH-mod_sofia/1.6.6+git~20160111T201612Z~d2d0b3283a~64bit.
Accept: application/sdp.
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE.
Supported: timer, path, replaces.
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer.
Reason: Q.850;cause=96;text="MANDATORY_IE_MISSING".
Content-Length: 0.
Remote-Party-ID: "123654874" <sip:123654874 at x.x.x.45>;party=calling;privacy=off;screen=no.
.

U x.x.x.45:5060 -> x.x.x.46:5060
ACK sip:123654874 at x.x.x.46:5060;transport=UDP SIP/2.0.
Via: SIP/2.0/UDP x.x.x.45:5060;branch=z9hG4bK4e79.8d4872f2.0.
From: <sip:xxxxxxxx20 at x.x.x.45;transport=UDP>;tag=fe41ad73.
Call-ID: DLGCH_AjQCGR4dNDsCCg0ECR4WOhYKDlwcHRViFic3BgoeFj4CGj8FHB0GPgEdNEA-.
To: <sip:123654874 at x.x.x.45;transport=UDP>;tag=pNeZ4gDKBaNap.
CSeq: 2 ACK.
Max-Forwards: 70.
User-Agent: Cvt SIP Proxy.
Content-Length: 0.
-------------- next part --------------
    2016-03-23 13:48:43.435074 [INFO] mod_dialplan_xml.c:637 Processing +xxxxxxxxx65 <xxxxxxxxx65>->+xxxxxxxxx01 in context default
    Dialplan: sofia/default/+xxxxxxxxx65 at x.x.x.86 parsing [default->+xxxxxxxxx01] continue=false
    Dialplan: sofia/default/+xxxxxxxxx65 at x.x.x.86 Regex (PASS) [+xxxxxxxxx01] destination_number(+xxxxxxxxx01) =~ // break=on-false
    Dialplan: sofia/default/+xxxxxxxxx65 at x.x.x.86 Action set(callstart=2016-03-23 11:48:43)
    Dialplan: sofia/default/+xxxxxxxxx65 at x.x.x.86 Action set(call_processed=internal)
    Dialplan: sofia/default/+xxxxxxxxx65 at x.x.x.86 Action set(originated_destination_number=xxxxxxxx01)
    Dialplan: sofia/default/+xxxxxxxxx65 at x.x.x.86 Action export(t38_passthru=true)
    Dialplan: sofia/default/+xxxxxxxxx65 at x.x.x.86 Action set(fax_enable_t38=true)
    Dialplan: sofia/default/+xxxxxxxxx65 at x.x.x.86 Action set(fax_enable_t38_request=true)
    Dialplan: sofia/default/+xxxxxxxxx65 at x.x.x.86 Action set(call_direction=inbound) 
    Dialplan: sofia/default/+xxxxxxxxx65 at x.x.x.86 Action sched_hangup(+172800 allotted_timeout)
    Dialplan: sofia/default/+xxxxxxxxx65 at x.x.x.86 Action set(effective_destination_number=xxxxxxxx01)
    Dialplan: sofia/default/+xxxxxxxxx65 at x.x.x.86 Action export(t38_passthru=true)
    Dialplan: sofia/default/+xxxxxxxxx65 at x.x.x.86 Action set(fax_enable_t38=true)
    Dialplan: sofia/default/+xxxxxxxxx65 at x.x.x.86 Action set(fax_enable_t38_request=true)
    Dialplan: sofia/default/+xxxxxxxxx65 at x.x.x.86 Action set(did_calltype=LOCAL)
    Dialplan: sofia/default/+xxxxxxxxx65 at x.x.x.86 Action bridge({sip_invite_to_uri=<sip:xxxxxxxx01 at x.x.x.42:5060>,sip_invite_req_uri=sip:xxxxxxxx01 at x.x.x.42:5060}sofia/default/sip:test-user at x.x.x.42:5060;fs_path=sip%3Ax.x.x.35%3Blr%3Breceived%3Dsip%3Ax.x.x.42%3A5060)
    2016-03-23 13:48:43.615031 [DEBUG] switch_core_state_machine.c:216 (sofia/default/+xxxxxxxxx65 at x.x.x.86) State Change CS_ROUTING -> CS_EXECUTE
    2016-03-23 13:48:43.615031 [DEBUG] switch_core_state_machine.c:532 (sofia/default/+xxxxxxxxx65 at x.x.x.86) State ROUTING going to sleep
    2016-03-23 13:48:43.615031 [DEBUG] switch_core_state_machine.c:473 (sofia/default/+xxxxxxxxx65 at x.x.x.86) Running State Change CS_EXECUTE
    2016-03-23 13:48:43.615031 [DEBUG] switch_core_state_machine.c:539 (sofia/default/+xxxxxxxxx65 at x.x.x.86) State EXECUTE
    2016-03-23 13:48:43.615031 [DEBUG] mod_sofia.c:196 sofia/default/+xxxxxxxxx65 at x.x.x.86 SOFIA EXECUTE
    2016-03-23 13:48:43.615031 [DEBUG] switch_core_state_machine.c:258 sofia/default/+xxxxxxxxx65 at x.x.x.86 Standard EXECUTE
    EXECUTE sofia/default/+xxxxxxxxx65 at x.x.x.86 set(callstart=2016-03-23 11:48:43)
    2016-03-23 13:48:43.615031 [DEBUG] mod_dptools.c:1498 SET sofia/default/+xxxxxxxxx65 at x.x.x.86 [callstart]=[2016-03-23 11:48:43]
    EXECUTE sofia/default/+xxxxxxxxx65 at x.x.x.86 set(call_processed=internal)
    2016-03-23 13:48:43.615031 [DEBUG] mod_dptools.c:1498 SET sofia/default/+xxxxxxxxx65 at x.x.x.86 [call_processed]=[internal]
    EXECUTE sofia/default/+xxxxxxxxx65 at x.x.x.86 set(originated_destination_number=xxxxxxxx01)
    2016-03-23 13:48:43.615031 [DEBUG] mod_dptools.c:1498 SET sofia/default/+xxxxxxxxx65 at x.x.x.86 [originated_destination_number]=[xxxxxxxx01]
    EXECUTE sofia/default/+xxxxxxxxx65 at x.x.x.86 export(t38_passthru=true)
    2016-03-23 13:48:43.615031 [DEBUG] switch_channel.c:1293 EXPORT (export_vars) [t38_passthru]=[true]
    EXECUTE sofia/default/+xxxxxxxxx65 at x.x.x.86 set(fax_enable_t38=true)
    2016-03-23 13:48:43.635031 [DEBUG] mod_dptools.c:1498 SET sofia/default/+xxxxxxxxx65 at x.x.x.86 [fax_enable_t38]=[true]
    EXECUTE sofia/default/+xxxxxxxxx65 at x.x.x.86 set(fax_enable_t38_request=true)
    2016-03-23 13:48:43.635031 [DEBUG] mod_dptools.c:1498 SET sofia/default/+xxxxxxxxx65 at x.x.x.86 [fax_enable_t38_request]=[true]
    EXECUTE sofia/default/+xxxxxxxxx65 at x.x.x.86 set(call_direction=inbound)
    2016-03-23 13:48:43.635031 [DEBUG] mod_dptools.c:1498 SET sofia/default/+xxxxxxxxx65 at x.x.x.86 [call_direction]=[inbound]
    EXECUTE sofia/default/+xxxxxxxxx65 at x.x.x.86 sched_hangup(+172800 allotted_timeout)
    2016-03-23 13:48:43.635031 [DEBUG] switch_scheduler.c:249 Added task 21 switch_ivr_schedule_hangup (67849c93-6b10-4377-834e-78657fcc2023) to run at 1458906523
    EXECUTE sofia/default/+xxxxxxxxx65 at x.x.x.86 set(effective_destination_number=xxxxxxxx01)
    2016-03-23 13:48:43.635031 [DEBUG] mod_dptools.c:1498 SET sofia/default/+xxxxxxxxx65 at x.x.x.86 [effective_destination_number]=[xxxxxxxx01]
    EXECUTE sofia/default/+xxxxxxxxx65 at x.x.x.86 export(t38_passthru=true)
    2016-03-23 13:48:43.635031 [DEBUG] switch_channel.c:1293 EXPORT (export_vars) [t38_passthru]=[true]
    EXECUTE sofia/default/+xxxxxxxxx65 at x.x.x.86 set(fax_enable_t38=true)
    2016-03-23 13:48:43.635031 [DEBUG] mod_dptools.c:1498 SET sofia/default/+xxxxxxxxx65 at x.x.x.86 [fax_enable_t38]=[true]
    EXECUTE sofia/default/+xxxxxxxxx65 at x.x.x.86 set(fax_enable_t38_request=true)
    2016-03-23 13:48:43.635031 [DEBUG] mod_dptools.c:1498 SET sofia/default/+xxxxxxxxx65 at x.x.x.86 [fax_enable_t38_request]=[true]
    EXECUTE sofia/default/+xxxxxxxxx65 at x.x.x.86 bridge({sip_invite_to_uri=<sip:xxxxxxxx01 at x.x.x.42:5060>,sip_invite_req_uri=sip:xxxxxxxx01 at x.x.x.42:5060}sofia/default/sip:test-user at x.x.x.42:5060;fs_path=sip%3Ax.x.x.35%3Blr%3Breceived%3Dsip%3Ax.x.x.42%3A5060)
    2016-03-23 13:48:43.635031 [DEBUG] switch_channel.c:1247 sofia/default/+xxxxxxxxx65 at x.x.x.86 EXPORTING[export_vars] [t38_passthru]=[true] to event
    2016-03-23 13:48:43.635031 [DEBUG] switch_channel.c:1247 sofia/default/+xxxxxxxxx65 at x.x.x.86 EXPORTING[export_vars] [t38_passthru]=[true] to event
    2016-03-23 13:48:43.635031 [DEBUG] switch_ivr_originate.c:2128 Parsing global variables
    2016-03-23 13:48:43.635031 [NOTICE] switch_channel.c:1101 New Channel sofia/default/test-user at x.x.x.42:5060 [36b94456-46e2-4ef2-9fb8-9ebe4d9d67b1]
    2016-03-23 13:48:43.635031 [DEBUG] mod_sofia.c:4776 (sofia/default/test-user at x.x.x.42:5060) State Change CS_NEW -> CS_INIT
    2016-03-23 13:48:43.635031 [DEBUG] switch_core_state_machine.c:473 (sofia/default/test-user at x.x.x.42:5060) Running State Change CS_INIT
    2016-03-23 13:48:43.635031 [DEBUG] switch_core_state_machine.c:516 (sofia/default/test-user at x.x.x.42:5060) State INIT
    2016-03-23 13:48:43.635031 [DEBUG] mod_sofia.c:88 sofia/default/test-user at x.x.x.42:5060 SOFIA INIT
    2016-03-23 13:48:43.635031 [DEBUG] sofia_glue.c:1228 sip:x.x.x.35;lr;received=sip:x.x.x.42:5060 Setting proxy route to sofia/default/test-user at x.x.x.42:5060
    2016-03-23 13:48:43.635031 [DEBUG] sofia_glue.c:1257 sofia/default/test-user at x.x.x.42:5060 sending invite version: 1.6.6 git d2d0b32 2016-01-11 20:16:12Z 64bit
    Local SDP:
    v=0
    o=SBC 1458713961 1458713962 IN IP4 x.x.x.37
    s=SBC
    c=IN IP4 x.x.x.37
    t=0 0
    m=audio 19762 RTP/AVP 18 8 101 13
    a=rtpmap:18 G729/8000
    a=fmtp:18 annexb=no
    a=rtpmap:8 PCMA/8000
    a=rtpmap:101 telephone-event/8000
    a=fmtp:101 0-16
    a=rtpmap:13 CN/8000
    a=ptime:60
    a=sendrecv

    2016-03-23 13:48:43.635031 [DEBUG] switch_core_state_machine.c:40 sofia/default/test-user at x.x.x.42:5060 Standard INIT
    2016-03-23 13:48:43.635031 [DEBUG] switch_core_state_machine.c:48 (sofia/default/test-user at x.x.x.42:5060) State Change CS_INIT -> CS_ROUTING
    2016-03-23 13:48:43.635031 [DEBUG] switch_core_state_machine.c:516 (sofia/default/test-user at x.x.x.42:5060) State INIT going to sleep
    2016-03-23 13:48:43.635031 [DEBUG] switch_core_state_machine.c:473 (sofia/default/test-user at x.x.x.42:5060) Running State Change CS_ROUTING
    2016-03-23 13:48:43.635031 [DEBUG] sofia.c:6760 Channel sofia/default/test-user at x.x.x.42:5060 entering state [calling][0]
    2016-03-23 13:48:43.635031 [DEBUG] switch_core_state_machine.c:532 (sofia/default/test-user at x.x.x.42:5060) State ROUTING
    2016-03-23 13:48:43.635031 [DEBUG] mod_sofia.c:141 sofia/default/test-user at x.x.x.42:5060 SOFIA ROUTING
    2016-03-23 13:48:43.635031 [DEBUG] switch_ivr_originate.c:67 (sofia/default/test-user at x.x.x.42:5060) State Change CS_ROUTING -> CS_CONSUME_MEDIA
    2016-03-23 13:48:43.635031 [DEBUG] switch_core_state_machine.c:532 (sofia/default/test-user at x.x.x.42:5060) State ROUTING going to sleep
    2016-03-23 13:48:43.635031 [DEBUG] switch_core_state_machine.c:473 (sofia/default/test-user at x.x.x.42:5060) Running State Change CS_CONSUME_MEDIA
    2016-03-23 13:48:43.635031 [DEBUG] switch_core_state_machine.c:551 (sofia/default/test-user at x.x.x.42:5060) State CONSUME_MEDIA
    2016-03-23 13:48:43.635031 [DEBUG] switch_core_state_machine.c:551 (sofia/default/test-user at x.x.x.42:5060) State CONSUME_MEDIA going to sleep
    2016-03-23 13:48:43.775034 [ERR] sofia_reg.c:2583 Cannot locate any authentication credentials to complete an authentication request for realm '"vpbx01.xxx.com"'
    2016-03-23 13:48:43.775034 [NOTICE] sofia_reg.c:2606 Hangup sofia/default/test-user at x.x.x.42:5060 [CS_CONSUME_MEDIA] [MANDATORY_IE_MISSING]
    2016-03-23 13:48:43.775034 [DEBUG] switch_core_state_machine.c:473 (sofia/default/test-user at x.x.x.42:5060) Running State Change CS_HANGUP
    2016-03-23 13:48:43.775034 [DEBUG] switch_core_state_machine.c:739 (sofia/default/test-user at x.x.x.42:5060) Callstate Change DOWN -> HANGUP
    2016-03-23 13:48:43.775034 [DEBUG] switch_core_state_machine.c:741 (sofia/default/test-user at x.x.x.42:5060) State HANGUP
    2016-03-23 13:48:43.775034 [DEBUG] mod_sofia.c:431 Channel sofia/default/test-user at x.x.x.42:5060 hanging up, cause: MANDATORY_IE_MISSING
    2016-03-23 13:48:43.775034 [DEBUG] mod_sofia.c:495 Sending CANCEL to sofia/default/test-user at x.x.x.42:5060
    2016-03-23 13:48:43.775034 [DEBUG] switch_core_state_machine.c:60 sofia/default/test-user at x.x.x.42:5060 Standard HANGUP, cause: MANDATORY_IE_MISSING
    2016-03-23 13:48:43.775034 [DEBUG] switch_core_state_machine.c:741 (sofia/default/test-user at x.x.x.42:5060) State HANGUP going to sleep
    2016-03-23 13:48:43.775034 [DEBUG] switch_core_state_machine.c:508 (sofia/default/test-user at x.x.x.42:5060) State Change CS_HANGUP -> CS_REPORTING
    2016-03-23 13:48:43.775034 [DEBUG] switch_core_state_machine.c:473 (sofia/default/test-user at x.x.x.42:5060) Running State Change CS_REPORTING
    2016-03-23 13:48:43.775034 [DEBUG] switch_core_state_machine.c:827 (sofia/default/test-user at x.x.x.42:5060) State REPORTING
    2016-03-23 13:48:43.775034 [DEBUG] switch_core_state_machine.c:104 sofia/default/test-user at x.x.x.42:5060 Standard REPORTING, cause: MANDATORY_IE_MISSING
    2016-03-23 13:48:43.775034 [DEBUG] switch_core_state_machine.c:827 (sofia/default/test-user at x.x.x.42:5060) State REPORTING going to sleep
    2016-03-23 13:48:43.775034 [DEBUG] switch_core_state_machine.c:499 (sofia/default/test-user at x.x.x.42:5060) State Change CS_REPORTING -> CS_DESTROY
    2016-03-23 13:48:43.775034 [DEBUG] switch_core_session.c:1646 Session 25 (sofia/default/test-user at x.x.x.42:5060) Locked, Waiting on external entities
    2016-03-23 13:48:43.775034 [DEBUG] switch_ivr_originate.c:3751 Originate Resulted in Error Cause: 96 [MANDATORY_IE_MISSING]
    2016-03-23 13:48:43.775034 [NOTICE] switch_core_session.c:1664 Session 25 (sofia/default/test-user at x.x.x.42:5060) Ended
    2016-03-23 13:48:43.775034 [NOTICE] switch_core_session.c:1668 Close Channel sofia/default/test-user at x.x.x.42:5060 [CS_DESTROY]
    2016-03-23 13:48:43.775034 [DEBUG] switch_core_state_machine.c:630 (sofia/default/test-user at x.x.x.42:5060) Running State Change CS_DESTROY
    2016-03-23 13:48:43.775034 [INFO] mod_dptools.c:3379 Originate Failed.  Cause: MANDATORY_IE_MISSING
    2016-03-23 13:48:43.775034 [NOTICE] switch_channel.c:4804 Hangup sofia/default/+xxxxxxxxx65 at x.x.x.86 [CS_EXECUTE] [MANDATORY_IE_MISSING]
    2016-03-23 13:48:43.775034 [DEBUG] switch_core_state_machine.c:640 (sofia/default/test-user at x.x.x.42:5060) State DESTROY
    2016-03-23 13:48:43.775034 [DEBUG] mod_sofia.c:341 sofia/default/test-user at x.x.x.42:5060 SOFIA DESTROY
    2016-03-23 13:48:43.775034 [DEBUG] switch_core_state_machine.c:111 sofia/default/test-user at x.x.x.42:5060 Standard DESTROY
    2016-03-23 13:48:43.775034 [DEBUG] switch_core_state_machine.c:640 (sofia/default/test-user at x.x.x.42:5060) State DESTROY going to sleep
    2016-03-23 13:48:43.775034 [DEBUG] switch_core_session.c:2796 sofia/default/+xxxxxxxxx65 at x.x.x.86 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
    2016-03-23 13:48:43.775034 [DEBUG] switch_core_state_machine.c:539 (sofia/default/+xxxxxxxxx65 at x.x.x.86) State EXECUTE going to sleep
    2016-03-23 13:48:43.775034 [DEBUG] switch_core_state_machine.c:473 (sofia/default/+xxxxxxxxx65 at x.x.x.86) Running State Change CS_HANGUP
    2016-03-23 13:48:43.775034 [DEBUG] switch_core_state_machine.c:739 (sofia/default/+xxxxxxxxx65 at x.x.x.86) Callstate Change RINGING -> HANGUP
    2016-03-23 13:48:43.775034 [DEBUG] switch_core_state_machine.c:741 (sofia/default/+xxxxxxxxx65 at x.x.x.86) State HANGUP
    2016-03-23 13:48:43.775034 [DEBUG] mod_sofia.c:431 Channel sofia/default/+xxxxxxxxx65 at x.x.x.86 hanging up, cause: MANDATORY_IE_MISSING
    2016-03-23 13:48:43.775034 [DEBUG] mod_sofia.c:568 Responding to INVITE with: 480
    2016-03-23 13:48:43.775034 [DEBUG] switch_core_state_machine.c:60 sofia/default/+xxxxxxxxx65 at x.x.x.86 Standard HANGUP, cause: MANDATORY_IE_MISSING
    2016-03-23 13:48:43.775034 [DEBUG] switch_core_state_machine.c:741 (sofia/default/+xxxxxxxxx65 at x.x.x.86) State HANGUP going to sleep
    2016-03-23 13:48:43.775034 [DEBUG] switch_core_state_machine.c:508 (sofia/default/+xxxxxxxxx65 at x.x.x.86) State Change CS_HANGUP -> CS_REPORTING
    2016-03-23 13:48:43.775034 [DEBUG] switch_core_state_machine.c:473 (sofia/default/+xxxxxxxxx65 at x.x.x.86) Running State Change CS_REPORTING
    2016-03-23 13:48:43.775034 [DEBUG] switch_core_state_machine.c:827 (sofia/default/+xxxxxxxxx65 at x.x.x.86) State REPORTING
    2016-03-23 13:48:44.035036 [DEBUG] switch_core_state_machine.c:104 sofia/default/+xxxxxxxxx65 at x.x.x.86 Standard REPORTING, cause: MANDATORY_IE_MISSING
    2016-03-23 13:48:44.035036 [DEBUG] switch_core_state_machine.c:827 (sofia/default/+xxxxxxxxx65 at x.x.x.86) State REPORTING going to sleep
    2016-03-23 13:48:44.035036 [DEBUG] switch_core_state_machine.c:499 (sofia/default/+xxxxxxxxx65 at x.x.x.86) State Change CS_REPORTING -> CS_DESTROY
    2016-03-23 13:48:44.035036 [DEBUG] switch_core_session.c:1646 Session 24 (sofia/default/+xxxxxxxxx65 at x.x.x.86) Locked, Waiting on external entities
    2016-03-23 13:48:44.035036 [NOTICE] switch_core_session.c:1664 Session 24 (sofia/default/+xxxxxxxxx65 at x.x.x.86) Ended
    2016-03-23 13:48:44.035036 [NOTICE] switch_core_session.c:1668 Close Channel sofia/default/+xxxxxxxxx65 at x.x.x.86 [CS_DESTROY]
    2016-03-23 13:48:44.035036 [DEBUG] switch_core_state_machine.c:630 (sofia/default/+xxxxxxxxx65 at x.x.x.86) Running State Change CS_DESTROY
    2016-03-23 13:48:44.035036 [DEBUG] switch_core_state_machine.c:640 (sofia/default/+xxxxxxxxx65 at x.x.x.86) State DESTROY
    2016-03-23 13:48:44.035036 [DEBUG] mod_sofia.c:341 sofia/default/+xxxxxxxxx65 at x.x.x.86 SOFIA DESTROY
    2016-03-23 13:48:44.035036 [DEBUG] switch_core_state_machine.c:111 sofia/default/+xxxxxxxxx65 at x.x.x.86 Standard DESTROY
    2016-03-23 13:48:44.035036 [DEBUG] switch_core_state_machine.c:640 (sofia/default/+xxxxxxxxx65 at x.x.x.86) State DESTROY going to sleep
    2016-03-23 13:48:44.135038 [DEBUG] switch_scheduler.c:144 Deleting task 21 switch_ivr_schedule_hangup (67849c93-6b10-4377-834e-78657fcc2023)

-------------- next part --------------
U x.x.x.47:33199 -> x.x.x.45:5060
INVITE sip:123654874 at x.x.x.45;transport=UDP SIP/2.0.
Via: SIP/2.0/UDP x.x.x.47:33199;branch=z9hG4bK-d8754z-02710fbea4cffc51-1---d8754z-.
Max-Forwards: 70.
Contact: <sip:xxxxxxxx20 at x.x.x.47:33199;transport=UDP>.
To: <sip:123654874 at x.x.x.45;transport=UDP>.
From: <sip:xxxxxxxx20 at x.x.x.45;transport=UDP>;tag=bad0d368.
Call-ID: Mjg2NzFmNzg3N2Y5N2U2MWZhZjdhMTMxMmE1NTVhNTg..
CSeq: 1 INVITE.
Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE.
Content-Type: application/sdp.
Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri.
User-Agent: Z 3.3.25608 r25552.
Allow-Events: presence, kpml.
Content-Length: 241.
.
v=0.
o=Z 0 0 IN IP4 x.x.x.47.
s=Z.
c=IN IP4 x.x.x.47.
t=0 0.
m=audio 8000 RTP/AVP 3 110 8 0 98 101.
a=rtpmap:110 speex/8000.
a=rtpmap:98 iLBC/8000.
a=fmtp:98 mode=20.
a=rtpmap:101 telephone-event/8000.
a=fmtp:101 0-15.
a=sendrecv.

U x.x.x.45:5060 -> x.x.x.47:33199
SIP/2.0 100 Trying.
Via: SIP/2.0/UDP x.x.x.47:33199;branch=z9hG4bK-d8754z-02710fbea4cffc51-1---d8754z-.
To: <sip:123654874 at x.x.x.45;transport=UDP>.
From: <sip:xxxxxxxx20 at x.x.x.45;transport=UDP>;tag=bad0d368.
Call-ID: Mjg2NzFmNzg3N2Y5N2U2MWZhZjdhMTMxMmE1NTVhNTg..
CSeq: 1 INVITE.
server: Cvt SBC.
Content-Length: 0.
.

U x.x.x.45:5060 -> x.x.x.46:5060
INVITE sip:123654874 at x.x.x.46:5060;transport=UDP SIP/2.0.
Via: SIP/2.0/UDP x.x.x.45:5060;branch=z9hG4bK19ee.edc06b92.0.
Max-Forwards: 30.
Contact: <sip:xxxxxxxx20 at x.x.x.45;did=a5e.9c607973>.
To: <sip:123654874 at x.x.x.45;transport=UDP>.
From: <sip:xxxxxxxx20 at x.x.x.45;transport=UDP>;tag=bad0d368.
Call-ID: DLGCH_AhoCXB0zFj4BCgJdHXsJZgFCMFweHgo7FRoBBh4dHSsCHSBfHR0GOwEkAkA-.
CSeq: 1 INVITE.
Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE.
Content-Type: application/sdp.
Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri.
User-Agent: Z 3.3.25608 r25552.
Allow-Events: presence, kpml.
Content-Length: 241.
X-AUTH-IP: x.x.x.47.
.
v=0.
o=Z 0 0 IN IP4 x.x.x.47.
s=Z.
c=IN IP4 x.x.x.47.
t=0 0.
m=audio 8000 RTP/AVP 3 110 8 0 98 101.
a=rtpmap:110 speex/8000.
a=rtpmap:98 iLBC/8000.
a=fmtp:98 mode=20.
a=rtpmap:101 telephone-event/8000.
a=fmtp:101 0-15.
a=sendrecv.

U x.x.x.46:5060 -> x.x.x.45:5060
SIP/2.0 100 Trying.
Via: SIP/2.0/UDP x.x.x.45:5060;branch=z9hG4bK19ee.edc06b92.0.
From: <sip:xxxxxxxx20 at x.x.x.45;transport=UDP>;tag=bad0d368.
To: <sip:123654874 at x.x.x.45;transport=UDP>.
Call-ID: DLGCH_AhoCXB0zFj4BCgJdHXsJZgFCMFweHgo7FRoBBh4dHSsCHSBfHR0GOwEkAkA-.
CSeq: 1 INVITE.
User-Agent: FreeSWITCH-mod_sofia/1.6.6+git~20160111T201612Z~d2d0b3283a~64bit.
Content-Length: 0.
.

U x.x.x.46:5060 -> x.x.x.45:5060
SIP/2.0 407 Proxy Authentication Required.
Via: SIP/2.0/UDP x.x.x.45:5060;branch=z9hG4bK19ee.edc06b92.0.
From: <sip:xxxxxxxx20 at x.x.x.45;transport=UDP>;tag=bad0d368.
To: <sip:123654874 at x.x.x.45;transport=UDP>;tag=tra9jUNgvg5yH.
Call-ID: DLGCH_AhoCXB0zFj4BCgJdHXsJZgFCMFweHgo7FRoBBh4dHSsCHSBfHR0GOwEkAkA-.
CSeq: 1 INVITE.
User-Agent: FreeSWITCH-mod_sofia/1.6.6+git~20160111T201612Z~d2d0b3283a~64bit.
Accept: application/sdp.
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE.
Supported: timer, path, replaces.
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer.
Proxy-Authenticate: Digest realm="x.x.x.45", nonce="43ea5b0d-e985-4c71-b104-5644c2f517cf", algorithm=MD5, qop="auth".
Content-Length: 0.
.

U x.x.x.45:5060 -> x.x.x.46:5060
ACK sip:123654874 at x.x.x.46:5060;transport=UDP SIP/2.0.
Via: SIP/2.0/UDP x.x.x.45:5060;branch=z9hG4bK19ee.edc06b92.0.
From: <sip:xxxxxxxx20 at x.x.x.45;transport=UDP>;tag=bad0d368.
Call-ID: DLGCH_AhoCXB0zFj4BCgJdHXsJZgFCMFweHgo7FRoBBh4dHSsCHSBfHR0GOwEkAkA-.
To: <sip:123654874 at x.x.x.45;transport=UDP>;tag=tra9jUNgvg5yH.
CSeq: 1 ACK.
Max-Forwards: 70.
User-Agent: Cvt SIP Proxy.
Content-Length: 0.
.

U x.x.x.45:5060 -> x.x.x.47:33199
SIP/2.0 407 Proxy Authentication Required.
Via: SIP/2.0/UDP x.x.x.47:33199;branch=z9hG4bK-d8754z-02710fbea4cffc51-1---d8754z-.
From: <sip:xxxxxxxx20 at x.x.x.45;transport=UDP>;tag=bad0d368.
To: <sip:123654874 at x.x.x.45;transport=UDP>;tag=tra9jUNgvg5yH.
Call-ID: Mjg2NzFmNzg3N2Y5N2U2MWZhZjdhMTMxMmE1NTVhNTg..
CSeq: 1 INVITE.
User-Agent: FreeSWITCH-mod_sofia/1.6.6+git~20160111T201612Z~d2d0b3283a~64bit.
Accept: application/sdp.
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE.
Supported: timer, path, replaces.
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer.
Proxy-Authenticate: Digest realm="x.x.x.45", nonce="43ea5b0d-e985-4c71-b104-5644c2f517cf", algorithm=MD5, qop="auth".
Content-Length: 0.
.

U x.x.x.47:33199 -> x.x.x.45:5060
ACK sip:123654874 at x.x.x.45;transport=UDP SIP/2.0.
Via: SIP/2.0/UDP x.x.x.47:33199;branch=z9hG4bK-d8754z-02710fbea4cffc51-1---d8754z-.
Max-Forwards: 70.
To: <sip:123654874 at x.x.x.45;transport=UDP>;tag=tra9jUNgvg5yH.
From: <sip:xxxxxxxx20 at x.x.x.45;transport=UDP>;tag=bad0d368.
Call-ID: Mjg2NzFmNzg3N2Y5N2U2MWZhZjdhMTMxMmE1NTVhNTg..
CSeq: 1 ACK.
Content-Length: 0.
.

U x.x.x.47:33199 -> x.x.x.45:5060
INVITE sip:123654874 at x.x.x.45;transport=UDP SIP/2.0.
Via: SIP/2.0/UDP x.x.x.47:33199;branch=z9hG4bK-d8754z-7965674fe5e4ee4e-1---d8754z-.
Max-Forwards: 70.
Contact: <sip:xxxxxxxx20 at x.x.x.47:33199;transport=UDP>.
To: <sip:123654874 at x.x.x.45;transport=UDP>.
From: <sip:xxxxxxxx20 at x.x.x.45;transport=UDP>;tag=bad0d368.
Call-ID: Mjg2NzFmNzg3N2Y5N2U2MWZhZjdhMTMxMmE1NTVhNTg..
CSeq: 2 INVITE.
Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE.
Content-Type: application/sdp.
Proxy-Authorization: Digest username="xxxxxxxx20",realm="x.x.x.45",nonce="43ea5b0d-e985-4c71-b104-5644c2f517cf",uri="sip:123654874 at x.x.x.45;transport=UDP",response="5096b79472dc0c8f623cd6f48f7ef59f",cnonce="a01e305ac7f31953c8a0e83cbd282546",nc=00000001,qop=auth,algorithm=MD5.
Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri.
User-Agent: Z 3.3.25608 r25552.
Allow-Events: presence, kpml.
Content-Length: 241.
.
v=0.
o=Z 0 0 IN IP4 x.x.x.47.
s=Z.
c=IN IP4 x.x.x.47.
t=0 0.
m=audio 8000 RTP/AVP 3 110 8 0 98 101.
a=rtpmap:110 speex/8000.
a=rtpmap:98 iLBC/8000.
a=fmtp:98 mode=20.
a=rtpmap:101 telephone-event/8000.
a=fmtp:101 0-15.
a=sendrecv.

U x.x.x.45:5060 -> x.x.x.47:33199
SIP/2.0 100 Trying.
Via: SIP/2.0/UDP x.x.x.47:33199;branch=z9hG4bK-d8754z-7965674fe5e4ee4e-1---d8754z-.
To: <sip:123654874 at x.x.x.45;transport=UDP>.
From: <sip:xxxxxxxx20 at x.x.x.45;transport=UDP>;tag=bad0d368.
Call-ID: Mjg2NzFmNzg3N2Y5N2U2MWZhZjdhMTMxMmE1NTVhNTg..
CSeq: 2 INVITE.
server: Cvt SBC.
Content-Length: 0.
.

U x.x.x.45:5060 -> x.x.x.46:5060
INVITE sip:123654874 at x.x.x.46:5060;transport=UDP SIP/2.0.
Via: SIP/2.0/UDP x.x.x.45:5060;branch=z9hG4bKe8ee.96f31373.0.
Max-Forwards: 30.
Contact: <sip:xxxxxxxx20 at x.x.x.45;did=a5e.ac607973>.
To: <sip:123654874 at x.x.x.45;transport=UDP>.
From: <sip:xxxxxxxx20 at x.x.x.45;transport=UDP>;tag=bad0d368.
Call-ID: DLGCH_AhoCXB0zFj4BCgJdHXsJZgFCMFweHgo7FRoBBh4dHSsCHSBfHR0GOwEkAkA-.
CSeq: 2 INVITE.
Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE.
Content-Type: application/sdp.
Proxy-Authorization: Digest username="xxxxxxxx20",realm="x.x.x.45",nonce="43ea5b0d-e985-4c71-b104-5644c2f517cf",uri="sip:123654874 at x.x.x.45;transport=UDP",response="5096b79472dc0c8f623cd6f48f7ef59f",cnonce="a01e305ac7f31953c8a0e83cbd282546",nc=00000001,qop=auth,algorithm=MD5.
Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri.
User-Agent: Z 3.3.25608 r25552.
Allow-Events: presence, kpml.
Content-Length: 241.
X-AUTH-IP: x.x.x.47.
.
v=0.
o=Z 0 0 IN IP4 x.x.x.47.
s=Z.
c=IN IP4 x.x.x.47.
t=0 0.
m=audio 8000 RTP/AVP 3 110 8 0 98 101.
a=rtpmap:110 speex/8000.
a=rtpmap:98 iLBC/8000.
a=fmtp:98 mode=20.
a=rtpmap:101 telephone-event/8000.
a=fmtp:101 0-15.
a=sendrecv.

U x.x.x.46:5060 -> x.x.x.45:5060
SIP/2.0 100 Trying.
Via: SIP/2.0/UDP x.x.x.45:5060;branch=z9hG4bKe8ee.96f31373.0.
From: <sip:xxxxxxxx20 at x.x.x.45;transport=UDP>;tag=bad0d368.
To: <sip:123654874 at x.x.x.45;transport=UDP>.
Call-ID: DLGCH_AhoCXB0zFj4BCgJdHXsJZgFCMFweHgo7FRoBBh4dHSsCHSBfHR0GOwEkAkA-.
CSeq: 2 INVITE.
User-Agent: FreeSWITCH-mod_sofia/1.6.6+git~20160111T201612Z~d2d0b3283a~64bit.
Content-Length: 0.
.

U x.x.x.46:5060 -> x.x.x.45:5060
INVITE sip:123654874 at x.x.x.42:5060 SIP/2.0.
Via: SIP/2.0/UDP x.x.x.46;rport;branch=z9hG4bKBj6vmr31j2pHK.
Route: <sip:x.x.x.45>;lr;received=sip:x.x.x.42:5060.
Max-Forwards: 29.
From: "Extension 1001" <sip:1001 at x.x.x.46>;tag=vaXtpHQQp2H4r.
To: <sip:123654874 at x.x.x.42:5060>.
Call-ID: 8c4e80cc-71eb-1234-a581-7e3d9898227f.
CSeq: 89380303 INVITE.
Contact: <sip:mod_sofia at x.x.x.46:5060>.
User-Agent: FreeSWITCH-mod_sofia/1.6.6+git~20160111T201612Z~d2d0b3283a~64bit.
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE.
Supported: timer, path, replaces.
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer.
Content-Type: application/sdp.
Content-Disposition: session.
Content-Length: 248.
X-AUTH-IP: x.x.x.47.
X-FS-Support: update_display,send_info.
Remote-Party-ID: "Extension 1001" <sip:1001 at x.x.x.46>;party=calling;screen=yes;privacy=off.
.
v=0.
o=FreeSWITCH 1459407238 1459407239 IN IP4 x.x.x.46.
s=FreeSWITCH.
c=IN IP4 x.x.x.46.
t=0 0.
m=audio 25496 RTP/AVP 8 101 13.
a=rtpmap:8 PCMA/8000.
a=rtpmap:101 telephone-event/8000.
a=fmtp:101 0-16.
a=rtpmap:13 CN/8000.
a=ptime:20.

U x.x.x.45:5060 -> x.x.x.46:5060
SIP/2.0 100 Trying.
Via: SIP/2.0/UDP x.x.x.46;received=x.x.x.46;rport=5060;branch=z9hG4bKBj6vmr31j2pHK.
From: "Extension 1001" <sip:1001 at x.x.x.46>;tag=vaXtpHQQp2H4r.
To: <sip:123654874 at x.x.x.42:5060>.
Call-ID: 8c4e80cc-71eb-1234-a581-7e3d9898227f.
CSeq: 89380303 INVITE.
server: Cvt SBC.
Content-Length: 0.
.

U x.x.x.45:5060 -> x.x.x.42:5060
INVITE sip:123654874 at x.x.x.42:5060 SIP/2.0.
Via: SIP/2.0/UDP x.x.x.45:5060;branch=z9hG4bKd078.e8120e82.0.
Max-Forwards: 28.
From: "1001" <sip:1001 at x.x.x.45>;tag=vaXtpHQQp2H4r.
To: <sip:123654874 at x.x.x.42:5060>.
Call-ID: DLGCH_dxNRC2t5MzBiR1QLMWRhYXxESA9mcWF+eBVWCmpxaWt9QlII.
CSeq: 89380303 INVITE.
Contact: <sip:mod_sofia at x.x.x.45;did=48.c32077b2>.
User-Agent: FreeSWITCH-mod_sofia/1.6.6+git~20160111T201612Z~d2d0b3283a~64bit.
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE.
Supported: timer, path, replaces.
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer.
Content-Type: application/sdp.
Content-Disposition: session.
Content-Length: 248.
X-FS-Support: update_display,send_info.
Remote-Party-ID: "1001" <sip:1001 at x.x.x.45>;party=calling;screen=yes;privacy=off.
.
v=0.
o=FreeSWITCH 1459407238 1459407239 IN IP4 x.x.x.46.
s=FreeSWITCH.
c=IN IP4 x.x.x.46.
t=0 0.
m=audio 25496 RTP/AVP 8 101 13.
a=rtpmap:8 PCMA/8000.
a=rtpmap:101 telephone-event/8000.
a=fmtp:101 0-16.
a=rtpmap:13 CN/8000.
a=ptime:20.

U x.x.x.42:5060 -> x.x.x.45:5060
SIP/2.0 401 Unauthorized.
Via: SIP/2.0/UDP x.x.x.45:5060;branch=z9hG4bKd078.e8120e82.0;received=x.x.x.45.
From: "1001" <sip:1001 at x.x.x.45>;tag=vaXtpHQQp2H4r.
To: <sip:123654874 at x.x.x.42:5060>;tag=as4e547f2a.
Call-ID: DLGCH_dxNRC2t5MzBiR1QLMWRhYXxESA9mcWF+eBVWCmpxaWt9QlII.
CSeq: 89380303 INVITE.
Server: CvtVPBX.
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE.
Supported: replaces, timer.
WWW-Authenticate: Digest algorithm=MD5, realm="pbx.registerendpoint.com", nonce="2f4958e6".
Content-Length: 0.
.

U x.x.x.45:5060 -> x.x.x.42:5060
ACK sip:123654874 at x.x.x.42:5060 SIP/2.0.
Via: SIP/2.0/UDP x.x.x.45:5060;branch=z9hG4bKd078.e8120e82.0.
From: "1001" <sip:1001 at x.x.x.45>;tag=vaXtpHQQp2H4r.
Call-ID: DLGCH_dxNRC2t5MzBiR1QLMWRhYXxESA9mcWF+eBVWCmpxaWt9QlII.
To: <sip:123654874 at x.x.x.42:5060>;tag=as4e547f2a.
CSeq: 89380303 ACK.
Max-Forwards: 70.
User-Agent: Cvt SIP Proxy.
Content-Length: 0.
.

U x.x.x.45:5060 -> x.x.x.46:5060
SIP/2.0 401 Unauthorized.
Via: SIP/2.0/UDP x.x.x.46;received=x.x.x.46;rport=5060;branch=z9hG4bKBj6vmr31j2pHK.
From: "Extension 1001" <sip:1001 at x.x.x.46>;tag=vaXtpHQQp2H4r.
To: <sip:123654874 at x.x.x.42:5060>;tag=as4e547f2a.
Call-ID: 8c4e80cc-71eb-1234-a581-7e3d9898227f.
CSeq: 89380303 INVITE.
Server: CvtVPBX.
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE.
Supported: replaces, timer.
WWW-Authenticate: Digest algorithm=MD5, realm="pbx.registerendpoint.com", nonce="2f4958e6".
Content-Length: 0.
.

U x.x.x.46:5060 -> x.x.x.45:5060
ACK sip:123654874 at x.x.x.42:5060 SIP/2.0.
Via: SIP/2.0/UDP x.x.x.46;rport;branch=z9hG4bKBj6vmr31j2pHK.
Route: <sip:x.x.x.45>;lr;received=sip:x.x.x.42:5060.
Max-Forwards: 29.
From: "Extension 1001" <sip:1001 at x.x.x.46>;tag=vaXtpHQQp2H4r.
To: <sip:123654874 at x.x.x.42:5060>;tag=as4e547f2a.
Call-ID: 8c4e80cc-71eb-1234-a581-7e3d9898227f.
CSeq: 89380303 ACK.
Content-Length: 0.
.

U x.x.x.46:5060 -> x.x.x.45:5060
SIP/2.0 480 Temporarily Unavailable.
Via: SIP/2.0/UDP x.x.x.45:5060;branch=z9hG4bKe8ee.96f31373.0.
Max-Forwards: 30.
From: <sip:xxxxxxxx20 at x.x.x.45;transport=UDP>;tag=bad0d368.
To: <sip:123654874 at x.x.x.45;transport=UDP>;tag=U131mp6KSSUHD.
Call-ID: DLGCH_AhoCXB0zFj4BCgJdHXsJZgFCMFweHgo7FRoBBh4dHSsCHSBfHR0GOwEkAkA-.
CSeq: 2 INVITE.
User-Agent: FreeSWITCH-mod_sofia/1.6.6+git~20160111T201612Z~d2d0b3283a~64bit.
Accept: application/sdp.
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE.
Supported: timer, path, replaces.
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer.
Reason: Q.850;cause=96;text="MANDATORY_IE_MISSING".
Content-Length: 0.
Remote-Party-ID: "123654874" <sip:123654874 at x.x.x.45>;party=calling;privacy=off;screen=no.
.

U x.x.x.45:5060 -> x.x.x.46:5060
ACK sip:123654874 at x.x.x.46:5060;transport=UDP SIP/2.0.
Via: SIP/2.0/UDP x.x.x.45:5060;branch=z9hG4bKe8ee.96f31373.0.
From: <sip:xxxxxxxx20 at x.x.x.45;transport=UDP>;tag=bad0d368.
Call-ID: DLGCH_AhoCXB0zFj4BCgJdHXsJZgFCMFweHgo7FRoBBh4dHSsCHSBfHR0GOwEkAkA-.
To: <sip:123654874 at x.x.x.45;transport=UDP>;tag=U131mp6KSSUHD.
CSeq: 2 ACK.
Max-Forwards: 70.
User-Agent: Cvt SIP Proxy.
Content-Length: 0.
.

U x.x.x.45:5060 -> x.x.x.47:33199
SIP/2.0 480 Temporarily Unavailable.
Via: SIP/2.0/UDP x.x.x.47:33199;branch=z9hG4bK-d8754z-7965674fe5e4ee4e-1---d8754z-.
Max-Forwards: 30.
From: <sip:xxxxxxxx20 at x.x.x.45;transport=UDP>;tag=bad0d368.
To: <sip:123654874 at x.x.x.45;transport=UDP>;tag=U131mp6KSSUHD.
Call-ID: Mjg2NzFmNzg3N2Y5N2U2MWZhZjdhMTMxMmE1NTVhNTg..
CSeq: 2 INVITE.
User-Agent: FreeSWITCH-mod_sofia/1.6.6+git~20160111T201612Z~d2d0b3283a~64bit.
Accept: application/sdp.
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE.
Supported: timer, path, replaces.
Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer.
Reason: Q.850;cause=96;text="MANDATORY_IE_MISSING".
Content-Length: 0.
Remote-Party-ID: "123654874" <sip:123654874 at x.x.x.45>;party=calling;privacy=off;screen=no.
.

U x.x.x.47:33199 -> x.x.x.45:5060
ACK sip:123654874 at x.x.x.45;transport=UDP SIP/2.0.
Via: SIP/2.0/UDP x.x.x.47:33199;branch=z9hG4bK-d8754z-7965674fe5e4ee4e-1---d8754z-.
Max-Forwards: 70.
To: <sip:123654874 at x.x.x.45;transport=UDP>;tag=U131mp6KSSUHD.
From: <sip:xxxxxxxx20 at x.x.x.45;transport=UDP>;tag=bad0d368.
Call-ID: Mjg2NzFmNzg3N2Y5N2U2MWZhZjdhMTMxMmE1NTVhNTg..
CSeq: 2 ACK.
Content-Length: 0.
.