[Freeswitch-users] Why doesn't this call get answered?

Thu Apr 15 01:19:42 UTC 2021

The sip trace is attached.
It seems to show that it sends INVITE messages, but never gets a response.
However, when it sends an OPTIONS message, it does get a response.
When calling from a different extension (using a Polycom instead of a
Grandstream ATA), the INVITE gets answered and the call proceeds.

It seems that there is something "wrong" with this INVITE that makes
Flowroute ignore it. What could that be, and how do I fix it?

I may have enough data here to ask Flowroute directly, so I'm going to give
that a try as well.

-- 
Steve

On Wed, Apr 14, 2021 at 12:43 PM Brian West <brian at freeswitch.com> wrote:

> That's the FMTP for OPUS.  Chances are that invite breaks the Polycom,
> what does the SDP look like coming back from that invite? I'll be you, it's
> broken.
>
> /b
>
>
> On Wed, Apr 14, 2021 at 1:51 PM Bote Man <botelist at gmail.com> wrote:
>
>> I am absolutely no expert on SDP, but that SDP line that begins
>>
>> a=fmtp:102 useinbandfec=1…
>>
>> looks to me like it’s trying to set up a video call. I saw this behavior
>> with the newer Polycom VVX501 before I beat those eager beavers into
>> submission.
>>
>>
>> It also looks like the Grandstream is offering a lot more codecs which
>> you might prefer to trim down to only those necessary to get the job done.
>> Sometimes additional codecs or codecs listed in the “wrong” sequence can
>> cause mystery problems.
>>
>>
>>
>> You might have to resort to siptrace logging between FS and your carrier.
>>
>> sofia profile external siptrace on ç or whatever profile handles your
>> provider; or maybe internal to snoop what’s going on between FS and your
>> Grandstream.
>>
>>
>>
>> Hope this helps.
>>
>>
>>
>>
>>
>> ---
>>
>> John Boteler
>>
>> BnC Group U.S.A.
>>
>>
>>
>>
>>
>>
>>
>>
>>
>> *From:* FreeSWITCH-users <freeswitch-users-bounces at lists.freeswitch.org> *On
>> Behalf Of *Steven Schoch
>> *Sent:* Tuesday, 13 April, 2021 20:47
>> *To:* freeswitch-users <freeswitch-users at lists.freeswitch.org>
>> *Subject:* [Freeswitch-users] Why doesn't this call get answered?
>>
>>
>>
>> This office has a bunch of Polycom SoundPoint IP 320 phones, and a single
>> Grandstream HT801 (connected to a FAX machine for outgoing FAXes).
>>
>>
>>
>> I can make a call from the phones to outside numbers.
>>
>> I can make a call from the HT801 to local phones.
>>
>> But I can't call from the HT801 to outside numbers.
>>
>>
>>
>> The last important thing that happens in the failed call is this:
>>
>> 2021-04-13 17:28:15.260844 [DEBUG] sofia.c:7406 Channel
>> sofia/external/<number> entering state [calling][0]
>>
>>
>>
>> The difference between the work and not work seems to be this: When I
>> call from a phone to an outside number, it does this:
>>
>>
>>
>> Local SDP:
>> v=0
>> o=FreeSWITCH 1618335260 1618335261 IN IP4 <external-IP>
>> s=FreeSWITCH
>> c=IN IP4 <external-IP>
>> t=0 0
>> m=audio 25104 RTP/AVP 0 8 101
>> a=rtpmap:0 PCMU/8000
>> a=rtpmap:8 PCMA/8000
>> a=rtpmap:101 telephone-event/8000
>> a=fmtp:101 0-16
>> a=ptime:20
>> a=sendrecv
>>
>>
>>
>> When it tries to call from the HT801 to an outside number, it does this:
>>
>>
>>
>> Local SDP:
>>
>> v=0
>> o=FreeSWITCH 1618327543 1618327544 IN IP4 <external-IP>
>> s=FreeSWITCH
>> c=IN IP4 <external-IP>
>> t=0 0
>> m=audio 32552 RTP/AVP 0 8 102 9 101 103
>> a=rtpmap:0 PCMU/8000
>> a=rtpmap:8 PCMA/8000
>> a=rtpmap:102 opus/48000/2
>> a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000;
>> maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
>> a=rtpmap:9 G722/8000
>> a=rtpmap:101 telephone-event/8000
>> a=fmtp:101 0-16
>> a=rtpmap:103 telephone-event/48000
>> a=fmtp:103 0-16
>> a=ptime:20
>> a=sendrecv
>>
>>
>>
>> Is that why it doesn't answer? If so, how do I change it?
>>
>>
>>
>> I should mention that when I tried this at home, it worked, but when I
>> attempted to install it here at the bookstore, it didn't. The Comcast
>> router at my home is a little different; they use a Netgear router here;
>> and I may have upgraded Freeswitch between the time it worked and now.
>>
>>
>>
>> --
>>
>> Steve
>> _________________________________________________________________________
>>
>> The FreeSWITCH project is sponsored by SignalWire https://signalwire.com
>> Enhance your FreeSWITCH install with disruptive priced SMS and PSTN
>> services.
>> Build your next product on our scalable cloud platform.
>>
>> Join our online community to chat in real time
>> https://signalwire.community
>>
>> Professional FreeSWITCH Services
>> sales at freeswitch.com
>> https://freeswitch.com
>>
>> Official FreeSWITCH Sites
>> https://freeswitch.com/oss
>> https://freeswitch.org/confluence
>> https://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
>> https://freeswitch.com
>
>
>
> --
>
> Brian West | Co-founder and Developer
>
> Need Commercial support? email sales at freeswitch.com
>
> FreeSWITCH Solutions | 17345 Civic Drive #2531 Brookfield, WI 53045
> <https://maps.google.com/?q=17345+Civic+Drive+%232531+Brookfield,+WI+53045&entry=gmail&source=g>
>
> Email: brian at freeswitch.com
>
> Mobile: 918-424-9378
>
> Website: https://www.FreeSWITCH.com <https://www.freeswitch.com/>
>
> [image: https://www.facebook.com/signalwireinc?src=email]
> <https://www.facebook.com/freeswitch> [image:
> https://twitter.com/freeswitch] <https://twitter.com/freeswitch>
> _________________________________________________________________________
>
> The FreeSWITCH project is sponsored by SignalWire https://signalwire.com
> Enhance your FreeSWITCH install with disruptive priced SMS and PSTN
> services.
> Build your next product on our scalable cloud platform.
>
> Join our online community to chat in real time
> https://signalwire.community
>
> Professional FreeSWITCH Services
> sales at freeswitch.com
> https://freeswitch.com
>
> Official FreeSWITCH Sites
> https://freeswitch.com/oss
> https://freeswitch.org/confluence
> https://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
> https://freeswitch.com
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20210414/a4884d18/attachment-0001.html>
-------------- next part --------------
2021-04-14 15:59:12.541007 [NOTICE] switch_channel.c:1118 New Channel sofia/phone/331 at 192.168.3.2 [350c616b-0b55-4283-94d6-34110d174993]
2021-04-14 15:59:12.541007 [DEBUG] switch_core_state_machine.c:585 (sofia/phone/331 at 192.168.3.2) Running State Change CS_NEW (Cur 1 Tot 40)
2021-04-14 15:59:12.541007 [INFO] sofia.c:10362 sofia/phone/331 at 192.168.3.2 receiving invite from 192.168.3.106:5060 version: 1.10.6 -release-18-1ff9d0a60e 64bit call-id: 913219790-5060-5 at BJC.BGI.D.BAG
2021-04-14 15:59:12.541007 [DEBUG] sofia.c:10456 verifying acl "domains" for ip/port 192.168.3.106:0.
2021-04-14 15:59:12.541007 [DEBUG] switch_core_state_machine.c:604 (sofia/phone/331 at 192.168.3.2) State NEW
2021-04-14 15:59:12.541007 [DEBUG] sofia.c:2447 detaching session 350c616b-0b55-4283-94d6-34110d174993
2021-04-14 15:59:12.560832 [DEBUG] sofia.c:2562 Re-attaching to session 350c616b-0b55-4283-94d6-34110d174993
2021-04-14 15:59:12.560832 [INFO] sofia.c:10362 sofia/phone/331 at 192.168.3.2 receiving invite from 192.168.3.106:5060 version: 1.10.6 -release-18-1ff9d0a60e 64bit call-id: 913219790-5060-5 at BJC.BGI.D.BAG
2021-04-14 15:59:12.560832 [DEBUG] sofia.c:10456 verifying acl "domains" for ip/port 192.168.3.106:0.
2021-04-14 15:59:12.560832 [DEBUG] sofia.c:7406 Channel sofia/phone/331 at 192.168.3.2 entering state [received][100]
2021-04-14 15:59:12.560832 [DEBUG] sofia.c:7416 Remote SDP:
v=0
o=331 8000 8000 IN IP4 192.168.3.106
s=SIP Call
c=IN IP4 192.168.3.106
t=0 0
m=audio 5004 RTP/AVP 0 8 4 18 2 97 123 9 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:4 G723/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:2 G726-32/8000
a=rtpmap:97 iLBC/8000
a=fmtp:97 mode=20
a=rtpmap:123 opus/48000/2
a=fmtp:123 maxplaybackrate=16000
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16,32-36,54
a=ptime:20

2021-04-14 15:59:12.560832 [DEBUG] sofia.c:7819 (sofia/phone/331 at 192.168.3.2) State Change CS_NEW -> CS_INIT
2021-04-14 15:59:12.560832 [DEBUG] switch_core_state_machine.c:585 (sofia/phone/331 at 192.168.3.2) Running State Change CS_INIT (Cur 1 Tot 40)
2021-04-14 15:59:12.560832 [DEBUG] switch_core_state_machine.c:628 (sofia/phone/331 at 192.168.3.2) State INIT
2021-04-14 15:59:12.560832 [DEBUG] mod_sofia.c:93 sofia/phone/331 at 192.168.3.2 SOFIA INIT
2021-04-14 15:59:12.560832 [DEBUG] switch_core_state_machine.c:40 sofia/phone/331 at 192.168.3.2 Standard INIT
2021-04-14 15:59:12.560832 [DEBUG] switch_core_state_machine.c:48 (sofia/phone/331 at 192.168.3.2) State Change CS_INIT -> CS_ROUTING
2021-04-14 15:59:12.560832 [DEBUG] switch_core_state_machine.c:628 (sofia/phone/331 at 192.168.3.2) State INIT going to sleep
2021-04-14 15:59:12.560832 [DEBUG] switch_core_state_machine.c:585 (sofia/phone/331 at 192.168.3.2) Running State Change CS_ROUTING (Cur 1 Tot 40)
2021-04-14 15:59:12.560832 [DEBUG] switch_channel.c:2332 (sofia/phone/331 at 192.168.3.2) Callstate Change DOWN -> RINGING
2021-04-14 15:59:12.560832 [DEBUG] switch_core_state_machine.c:644 (sofia/phone/331 at 192.168.3.2) State ROUTING
2021-04-14 15:59:12.560832 [DEBUG] mod_sofia.c:154 sofia/phone/331 at 192.168.3.2 SOFIA ROUTING
2021-04-14 15:59:12.560832 [DEBUG] switch_core_state_machine.c:236 sofia/phone/331 at 192.168.3.2 Standard ROUTING
2021-04-14 15:59:12.560832 [INFO] mod_dialplan_xml.c:637 Processing Fax Machine <331>->1<outside_number> in context default
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->unloop] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->open_hours] continue=true
Dialplan: sofia/phone/331 at 192.168.3.2 Date/Time Match (PASS) [open_hours] break=on-true
Dialplan: sofia/phone/331 at 192.168.3.2 Action set(open=true) INLINE
EXECUTE [depth=0] sofia/phone/331 at 192.168.3.2 set(open=true)
2021-04-14 15:59:12.560832 [DEBUG] mod_dptools.c:1685 SET sofia/phone/331 at 192.168.3.2 [open]=[true]
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->holiday_hours] continue=true
Dialplan: sofia/phone/331 at 192.168.3.2 Date/TimeMatch (FAIL) [holiday_hours] break=on-true
Dialplan: sofia/phone/331 at 192.168.3.2 Date/TimeMatch (FAIL) [holiday_hours] break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->global-intercept] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [global-intercept] destination_number(1<outside_number>) =~ /^886$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->group-intercept] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [group-intercept] destination_number(1<outside_number>) =~ /^\*8$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->intercept-ext] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [intercept-ext] destination_number(1<outside_number>) =~ /^\*\*(\d+)$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->redial] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [redial] destination_number(1<outside_number>) =~ /^(redial|870)$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->global] continue=true
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [global] ${default_password}(<my_password>) =~ /^1234$/ break=never
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [global] ${rtp_has_crypto}() =~ /^(AEAD_AES_256_GCM_8|AEAD_AES_128_GCM_8|AES_CM_256_HMAC_SHA1_80|AES_CM_192_HMAC_SHA1_80|AES_CM_128_HMAC_SHA1_80|AES_CM_256_HMAC_SHA1_32|AES_CM_192_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_32|AES_CM_128_NULL_AUTH)$/ break=never
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (PASS) [global] ${endpoint_disposition}(DELAYED NEGOTIATION) =~ /^(DELAYED NEGOTIATION)/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [global] ${switch_r_sdp}(v=0
o=331 8000 8000 IN IP4 192.168.3.106
s=SIP Call
c=IN IP4 192.168.3.106
t=0 0
m=audio 5004 RTP/AVP 0 8 4 18 2 97 123 9 101
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:4 G723/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=rtpmap:2 G726-32/8000
a=rtpmap:97 iLBC/8000
a=fmtp:97 mode=20
a=rtpmap:123 opus/48000/2
a=fmtp:123 maxplaybackrate=16000
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16,32-36,54
a=ptime:20
) =~ /(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)/ break=never
Dialplan: sofia/phone/331 at 192.168.3.2 Absolute Condition [global]
Dialplan: sofia/phone/331 at 192.168.3.2 Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid}) 
Dialplan: sofia/phone/331 at 192.168.3.2 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number}) 
Dialplan: sofia/phone/331 at 192.168.3.2 Action hash(insert/${domain_name}-last_dial/global/${uuid}) 
Dialplan: sofia/phone/331 at 192.168.3.2 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) 
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->snom-demo-2] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [snom-demo-2] destination_number(1<outside_number>) =~ /^9001$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->snom-demo-1] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [snom-demo-1] destination_number(1<outside_number>) =~ /^9000$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->eavesdrop] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [eavesdrop] destination_number(1<outside_number>) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->eavesdrop] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [eavesdrop] destination_number(1<outside_number>) =~ /^779$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->paging-with-chime] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [paging-with-chime] destination_number(1<outside_number>) =~ /^321$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->call_return] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [call_return] destination_number(1<outside_number>) =~ /^\*69$|^869$|^lcr$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->main_cid] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [main_cid] destination_number(1<outside_number>) =~ /^\*61(.......*)$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->del-group] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [del-group] destination_number(1<outside_number>) =~ /^80(\d{2})$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->add-group] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [add-group] destination_number(1<outside_number>) =~ /^81(\d{2})$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->call-group-simo] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [call-group-simo] destination_number(1<outside_number>) =~ /^82(\d{2})$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->call-group-order] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [call-group-order] destination_number(1<outside_number>) =~ /^83(\d{2})$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->extension-intercom] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [extension-intercom] destination_number(1<outside_number>) =~ /^8(3[012][0-9])$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->Local_Extension] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (PASS) [Local_Extension] ${alert_info}() =~ /^$/ break=never
Dialplan: sofia/phone/331 at 192.168.3.2 Action export(alert_info=internal) 
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [Local_Extension] destination_number(1<outside_number>) =~ /^(3[012][0-9])$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->Direct_Voicemail] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [Direct_Voicemail] destination_number(1<outside_number>) =~ /^5([0-2][0-9])$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->Local_Extension_Skinny] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [Local_Extension_Skinny] destination_number(1<outside_number>) =~ /^(11[01][0-9])$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->group_dial_sales] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [group_dial_sales] destination_number(1<outside_number>) =~ /^2000$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->group_dial_support] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [group_dial_support] destination_number(1<outside_number>) =~ /^2001$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->group_dial_billing] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [group_dial_billing] destination_number(1<outside_number>) =~ /^2002$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->main_did] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [main_did] destination_number(1<outside_number>) =~ /^(main_did|0)$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->did_message] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [did_message] destination_number(1<outside_number>) =~ /^(did_message|0)$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->operator] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [operator] destination_number(1<outside_number>) =~ /^(operator|0)$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->vmain] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [vmain] destination_number(1<outside_number>) =~ /^vmain$|^\*86$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->sip_uri] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [sip_uri] destination_number(1<outside_number>) =~ /^sip:(.*)$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->nb_conferences] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [nb_conferences] destination_number(1<outside_number>) =~ /^(30\d{2})$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->wb_conferences] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [wb_conferences] destination_number(1<outside_number>) =~ /^(31\d{2})$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->uwb_conferences] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [uwb_conferences] destination_number(1<outside_number>) =~ /^(32\d{2})$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->cdquality_conferences] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [cdquality_conferences] destination_number(1<outside_number>) =~ /^(33\d{2})$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->cdquality_stereo_conferences] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [cdquality_stereo_conferences] destination_number(1<outside_number>) =~ /^(35\d{2}).*?-screen$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->conference-canvases] continue=true
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [conference-canvases] destination_number(1<outside_number>) =~ /(35\d{2})-canvas-(\d+)/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->conf mod] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [conf mod] destination_number(1<outside_number>) =~ /^6070-moderator$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->cdquality_conferences] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [cdquality_conferences] destination_number(1<outside_number>) =~ /^(35\d{2})$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->cdquality_conferences_720] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [cdquality_conferences_720] destination_number(1<outside_number>) =~ /^(36\d{2})$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->cdquality_conferences_480] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [cdquality_conferences_480] destination_number(1<outside_number>) =~ /^(37\d{2})$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->cdquality_conferences_320] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [cdquality_conferences_320] destination_number(1<outside_number>) =~ /^(38\d{2})$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->freeswitch_public_conf_via_sip] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [freeswitch_public_conf_via_sip] destination_number(1<outside_number>) =~ /^9(888|8888|1616|3232)$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->mad_boss_intercom] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [mad_boss_intercom] destination_number(1<outside_number>) =~ /^0911$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->mad_boss_intercom] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [mad_boss_intercom] destination_number(1<outside_number>) =~ /^0912$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->mad_boss] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [mad_boss] destination_number(1<outside_number>) =~ /^0913$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->ivr_demo] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [ivr_demo] destination_number(1<outside_number>) =~ /^5000$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->dynamic_conference] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [dynamic_conference] destination_number(1<outside_number>) =~ /^5001$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->rtp_multicast_page] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [rtp_multicast_page] destination_number(1<outside_number>) =~ /^pagegroup$|^7243$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->park] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [park] destination_number(1<outside_number>) =~ /^5900$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->unpark] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [unpark] destination_number(1<outside_number>) =~ /^5901$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->valet_park] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [valet_park] destination_number(1<outside_number>) =~ /^(6000)$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->valet_park] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [valet_park] destination_number(1<outside_number>) =~ /^((?!6000)60\d{2})$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->park] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (PASS) [park] source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [park] destination_number(1<outside_number>) =~ /park\+(\d+)/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->unpark] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (PASS) [unpark] source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [unpark] destination_number(1<outside_number>) =~ /^parking$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->park] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (PASS) [park] source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [park] destination_number(1<outside_number>) =~ /callpark/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->unpark] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (PASS) [unpark] source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [unpark] destination_number(1<outside_number>) =~ /pickup/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->wait] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [wait] destination_number(1<outside_number>) =~ /^wait$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->fax_receive] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [fax_receive] destination_number(1<outside_number>) =~ /^9178$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->fax_transmit] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [fax_transmit] destination_number(1<outside_number>) =~ /^9179$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->ringback_180] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [ringback_180] destination_number(1<outside_number>) =~ /^9180$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->ringback_183_uk_ring] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [ringback_183_uk_ring] destination_number(1<outside_number>) =~ /^9181$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->ringback_183_music_ring] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [ringback_183_music_ring] destination_number(1<outside_number>) =~ /^9182$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->ringback_post_answer_uk_ring] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [ringback_post_answer_uk_ring] destination_number(1<outside_number>) =~ /^9183$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->ringback_post_answer_music] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [ringback_post_answer_music] destination_number(1<outside_number>) =~ /^9184$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->ClueCon] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [ClueCon] destination_number(1<outside_number>) =~ /^9191$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->show_info] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [show_info] destination_number(1<outside_number>) =~ /^9192$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->video_record] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [video_record] destination_number(1<outside_number>) =~ /^9193$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->video_playback] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [video_playback] destination_number(1<outside_number>) =~ /^9194$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->delay_echo] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [delay_echo] destination_number(1<outside_number>) =~ /^9195$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->echo] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [echo] destination_number(1<outside_number>) =~ /^9196$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->milliwatt] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [milliwatt] destination_number(1<outside_number>) =~ /^9197$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->tone_stream] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [tone_stream] destination_number(1<outside_number>) =~ /^9198$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->zrtp_enrollement] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [zrtp_enrollement] destination_number(1<outside_number>) =~ /^9787$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->hold_music] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [hold_music] destination_number(1<outside_number>) =~ /^9664$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->laugh break] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [laugh break] destination_number(1<outside_number>) =~ /^9386$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->101] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [101] destination_number(1<outside_number>) =~ /^101$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->pizza_demo] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [pizza_demo] destination_number(1<outside_number>) =~ /^(pizza|74992)$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->Talking Clock Time] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [Talking Clock Time] destination_number(1<outside_number>) =~ /^9170$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->Talking Clock Date] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [Talking Clock Date] destination_number(1<outside_number>) =~ /^9171$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->Talking Clock Date and Time] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (FAIL) [Talking Clock Date and Time] destination_number(1<outside_number>) =~ /^9172$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 parsing [default->domestic] continue=false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (PASS) [domestic] ${toll_allow}(domestic,international,local) =~ /domestic/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 Regex (PASS) [domestic] destination_number(1<outside_number>) =~ /^9?\+?1?(\d{10})$/ break=on-false
Dialplan: sofia/phone/331 at 192.168.3.2 Action set(effective_caller_id_number=${outbound_caller_id_number}) 
Dialplan: sofia/phone/331 at 192.168.3.2 Action set(effective_caller_id_name=${outbound_caller_id_name}) 
Dialplan: sofia/phone/331 at 192.168.3.2 Action set(effective_callee_id_number=1<outside_number>) 
Dialplan: sofia/phone/331 at 192.168.3.2 Action set(effective_callee_id_name=${cidlookup(1<outside_number>)}) 
Dialplan: sofia/phone/331 at 192.168.3.2 Action set(ignore_display_updates=true) 
Dialplan: sofia/phone/331 at 192.168.3.2 Action bridge(sofia/gateway/${default_gateway}/1<outside_number>) 
2021-04-14 15:59:12.560832 [DEBUG] switch_core_state_machine.c:287 (sofia/phone/331 at 192.168.3.2) State Change CS_ROUTING -> CS_EXECUTE
2021-04-14 15:59:12.560832 [DEBUG] switch_core_state_machine.c:644 (sofia/phone/331 at 192.168.3.2) State ROUTING going to sleep
2021-04-14 15:59:12.560832 [DEBUG] switch_core_state_machine.c:585 (sofia/phone/331 at 192.168.3.2) Running State Change CS_EXECUTE (Cur 1 Tot 40)
2021-04-14 15:59:12.580787 [DEBUG] switch_core_state_machine.c:651 (sofia/phone/331 at 192.168.3.2) State EXECUTE
2021-04-14 15:59:12.580787 [DEBUG] mod_sofia.c:209 sofia/phone/331 at 192.168.3.2 SOFIA EXECUTE
2021-04-14 15:59:12.580787 [DEBUG] switch_core_state_machine.c:329 sofia/phone/331 at 192.168.3.2 Standard EXECUTE
EXECUTE [depth=0] sofia/phone/331 at 192.168.3.2 hash(insert/eastwest-spymap/331/350c616b-0b55-4283-94d6-34110d174993)
EXECUTE [depth=0] sofia/phone/331 at 192.168.3.2 hash(insert/eastwest-last_dial/331/1<outside_number>)
EXECUTE [depth=0] sofia/phone/331 at 192.168.3.2 hash(insert/eastwest-last_dial/global/350c616b-0b55-4283-94d6-34110d174993)
EXECUTE [depth=0] sofia/phone/331 at 192.168.3.2 export(RFC2822_DATE=Wed, 14 Apr 2021 15:59:12 -0700)
2021-04-14 15:59:12.580787 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [RFC2822_DATE]=[Wed, 14 Apr 2021 15:59:12 -0700]
EXECUTE [depth=0] sofia/phone/331 at 192.168.3.2 export(alert_info=internal)
2021-04-14 15:59:12.580787 [DEBUG] switch_channel.c:1310 EXPORT (export_vars) [alert_info]=[internal]
EXECUTE [depth=0] sofia/phone/331 at 192.168.3.2 set(effective_caller_id_number=1<my_CID>)
2021-04-14 15:59:12.580787 [DEBUG] mod_dptools.c:1685 SET sofia/phone/331 at 192.168.3.2 [effective_caller_id_number]=[1<my_CID>]
EXECUTE [depth=0] sofia/phone/331 at 192.168.3.2 set(effective_caller_id_name=East West Fax)
2021-04-14 15:59:12.580787 [DEBUG] mod_dptools.c:1685 SET sofia/phone/331 at 192.168.3.2 [effective_caller_id_name]=[East West Fax]
EXECUTE [depth=0] sofia/phone/331 at 192.168.3.2 set(effective_callee_id_number=1<outside_number>)
2021-04-14 15:59:12.580787 [DEBUG] mod_dptools.c:1685 SET sofia/phone/331 at 192.168.3.2 [effective_callee_id_number]=[1<outside_number>]
EXECUTE [depth=0] sofia/phone/331 at 192.168.3.2 set(effective_callee_id_name=SUNNYVALE, CA)
2021-04-14 15:59:12.580787 [DEBUG] mod_dptools.c:1685 SET sofia/phone/331 at 192.168.3.2 [effective_callee_id_name]=[SUNNYVALE, CA]
EXECUTE [depth=0] sofia/phone/331 at 192.168.3.2 set(ignore_display_updates=true)
2021-04-14 15:59:12.580787 [DEBUG] mod_dptools.c:1685 SET sofia/phone/331 at 192.168.3.2 [ignore_display_updates]=[true]
EXECUTE [depth=0] sofia/phone/331 at 192.168.3.2 bridge(sofia/gateway/flowroute/1<outside_number>)
2021-04-14 15:59:12.580787 [DEBUG] switch_channel.c:1264 sofia/phone/331 at 192.168.3.2 EXPORTING[export_vars] [RFC2822_DATE]=[Wed, 14 Apr 2021 15:59:12 -0700] to event
2021-04-14 15:59:12.580787 [DEBUG] switch_channel.c:1264 sofia/phone/331 at 192.168.3.2 EXPORTING[export_vars] [alert_info]=[internal] to event
2021-04-14 15:59:12.580787 [DEBUG] switch_ivr_originate.c:2272 Parsing global variables
2021-04-14 15:59:12.580787 [NOTICE] switch_channel.c:1118 New Channel sofia/external/1<outside_number> [e59824db-e99f-49eb-ae80-285c21f19d39]
2021-04-14 15:59:12.580787 [DEBUG] mod_sofia.c:5102 (sofia/external/1<outside_number>) State Change CS_NEW -> CS_INIT
2021-04-14 15:59:12.580787 [DEBUG] switch_core_state_machine.c:585 (sofia/external/1<outside_number>) Running State Change CS_INIT (Cur 2 Tot 41)
2021-04-14 15:59:12.580787 [DEBUG] switch_core_state_machine.c:628 (sofia/external/1<outside_number>) State INIT
2021-04-14 15:59:12.580787 [DEBUG] mod_sofia.c:93 sofia/external/1<outside_number> SOFIA INIT
2021-04-14 15:59:12.580787 [INFO] sofia_glue.c:1621 sofia/external/1<outside_number> sending invite call-id: (null)
2021-04-14 15:59:12.580787 [DEBUG] sofia_glue.c:1624 sofia/external/1<outside_number> sending invite version: 1.10.6 -release-18-1ff9d0a60e 64bit
Local SDP:
v=0
o=FreeSWITCH 1618413436 1618413437 IN IP4 <ext_IP>
s=FreeSWITCH
c=IN IP4 <ext_IP>
t=0 0
m=audio 27716 RTP/AVP 0 8 102 9 101 103
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:102 opus/48000/2
a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:103 telephone-event/48000
a=fmtp:103 0-16
a=ptime:20
a=sendrecv

2021-04-14 15:59:12.580787 [DEBUG] switch_core_state_machine.c:40 sofia/external/1<outside_number> Standard INIT
2021-04-14 15:59:12.580787 [DEBUG] switch_core_state_machine.c:48 (sofia/external/1<outside_number>) State Change CS_INIT -> CS_ROUTING
2021-04-14 15:59:12.580787 [DEBUG] switch_core_state_machine.c:628 (sofia/external/1<outside_number>) State INIT going to sleep
2021-04-14 15:59:12.580787 [DEBUG] switch_core_state_machine.c:585 (sofia/external/1<outside_number>) Running State Change CS_ROUTING (Cur 2 Tot 41)
2021-04-14 15:59:12.580787 [DEBUG] switch_core_state_machine.c:644 (sofia/external/1<outside_number>) State ROUTING
2021-04-14 15:59:12.580787 [DEBUG] mod_sofia.c:154 sofia/external/1<outside_number> SOFIA ROUTING
2021-04-14 15:59:12.580787 [DEBUG] switch_ivr_originate.c:67 (sofia/external/1<outside_number>) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2021-04-14 15:59:12.580787 [DEBUG] switch_core_state_machine.c:644 (sofia/external/1<outside_number>) State ROUTING going to sleep
2021-04-14 15:59:12.580787 [DEBUG] switch_core_state_machine.c:585 (sofia/external/1<outside_number>) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 41)
2021-04-14 15:59:12.580787 [DEBUG] switch_core_state_machine.c:663 (sofia/external/1<outside_number>) State CONSUME_MEDIA
send 1509 bytes to udp/[34.210.91.114]:5060 at 15:59:12.585438:
------------------------------------------------------------------------
INVITE sip:1<outside_number>@us-west-or.sip.flowroute.com SIP/2.0
Via: SIP/2.0/UDP <ext_IP>:5089;rport;branch=z9hG4bKcBHrQKXgHNctm
Max-Forwards: 69
From: "East West Fax" <sip:1<my_CID>@<ext_IP>>;tag=c99SXeSa6mSSQ
To: <sip:1<outside_number>@us-west-or.sip.flowroute.com>
Call-ID: dec531ef-1817-123a-9b91-10e7c6b0315b
CSeq: 34666784 INVITE
Contact: <sip:gw+flowroute@<ext_IP>:5089;transport=udp;gw=flowroute>
User-Agent: FreeSWITCH-mod_sofia/1.10.6-release-18-1ff9d0a60e~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 477
P-Access-Network-Info: IEEE-EUI-48;eui-48-addr=84-D8-1B-E9-50-5F
P-Emergency-Info: IEEE-EUI-48;eui-48-addr=C0-74-AD-30-CD-39
X-FS-Support: update_display,send_info
Alert-Info: <internal>
Remote-Party-ID: "East West Fax" <sip:1<my_CID>@<ext_IP>>;party=calling;screen=yes;privacy=off

v=0
o=FreeSWITCH 1618413436 1618413437 IN IP4 <ext_IP>
s=FreeSWITCH
c=IN IP4 <ext_IP>
t=0 0
m=audio 27716 RTP/AVP 0 8 102 9 101 103
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:102 opus/48000/2
a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:103 telephone-event/48000
a=fmtp:103 0-16
a=ptime:20
2021-04-14 15:59:12.580787 [DEBUG] switch_core_state_machine.c:663 (sofia/external/1<outside_number>) State CONSUME_MEDIA going to sleep
2021-04-14 15:59:12.580787 [DEBUG] sofia.c:7406 Channel sofia/external/1<outside_number> entering state [calling][0]
send 1509 bytes to udp/[34.210.91.114]:5060 at 15:59:13.585507:
------------------------------------------------------------------------
INVITE sip:1<outside_number>@us-west-or.sip.flowroute.com SIP/2.0
Via: SIP/2.0/UDP <ext_IP>:5089;rport;branch=z9hG4bKcBHrQKXgHNctm
Max-Forwards: 69
From: "East West Fax" <sip:1<my_CID>@<ext_IP>>;tag=c99SXeSa6mSSQ
To: <sip:1<outside_number>@us-west-or.sip.flowroute.com>
Call-ID: dec531ef-1817-123a-9b91-10e7c6b0315b
CSeq: 34666784 INVITE
Contact: <sip:gw+flowroute@<ext_IP>:5089;transport=udp;gw=flowroute>
User-Agent: FreeSWITCH-mod_sofia/1.10.6-release-18-1ff9d0a60e~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 477
P-Access-Network-Info: IEEE-EUI-48;eui-48-addr=84-D8-1B-E9-50-5F
P-Emergency-Info: IEEE-EUI-48;eui-48-addr=C0-74-AD-30-CD-39
X-FS-Support: update_display,send_info
Alert-Info: <internal>
Remote-Party-ID: "East West Fax" <sip:1<my_CID>@<ext_IP>>;party=calling;screen=yes;privacy=off

v=0
o=FreeSWITCH 1618413436 1618413437 IN IP4 <ext_IP>
s=FreeSWITCH
c=IN IP4 <ext_IP>
t=0 0
m=audio 27716 RTP/AVP 0 8 102 9 101 103
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:102 opus/48000/2
a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:103 telephone-event/48000
a=fmtp:103 0-16
a=ptime:20
send 1509 bytes to udp/[34.210.91.114]:5060 at 15:59:15.586508:
------------------------------------------------------------------------
INVITE sip:1<outside_number>@us-west-or.sip.flowroute.com SIP/2.0
Via: SIP/2.0/UDP <ext_IP>:5089;rport;branch=z9hG4bKcBHrQKXgHNctm
Max-Forwards: 69
From: "East West Fax" <sip:1<my_CID>@<ext_IP>>;tag=c99SXeSa6mSSQ
To: <sip:1<outside_number>@us-west-or.sip.flowroute.com>
Call-ID: dec531ef-1817-123a-9b91-10e7c6b0315b
CSeq: 34666784 INVITE
Contact: <sip:gw+flowroute@<ext_IP>:5089;transport=udp;gw=flowroute>
User-Agent: FreeSWITCH-mod_sofia/1.10.6-release-18-1ff9d0a60e~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 477
P-Access-Network-Info: IEEE-EUI-48;eui-48-addr=84-D8-1B-E9-50-5F
P-Emergency-Info: IEEE-EUI-48;eui-48-addr=C0-74-AD-30-CD-39
X-FS-Support: update_display,send_info
Alert-Info: <internal>
Remote-Party-ID: "East West Fax" <sip:1<my_CID>@<ext_IP>>;party=calling;screen=yes;privacy=off

v=0
o=FreeSWITCH 1618413436 1618413437 IN IP4 <ext_IP>
s=FreeSWITCH
c=IN IP4 <ext_IP>
t=0 0
m=audio 27716 RTP/AVP 0 8 102 9 101 103
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:102 opus/48000/2
a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:103 telephone-event/48000
a=fmtp:103 0-16
a=ptime:20
send 1509 bytes to udp/[34.210.91.114]:5060 at 15:59:19.587156:
------------------------------------------------------------------------
INVITE sip:1<outside_number>@us-west-or.sip.flowroute.com SIP/2.0
Via: SIP/2.0/UDP <ext_IP>:5089;rport;branch=z9hG4bKcBHrQKXgHNctm
Max-Forwards: 69
From: "East West Fax" <sip:1<my_CID>@<ext_IP>>;tag=c99SXeSa6mSSQ
To: <sip:1<outside_number>@us-west-or.sip.flowroute.com>
Call-ID: dec531ef-1817-123a-9b91-10e7c6b0315b
CSeq: 34666784 INVITE
Contact: <sip:gw+flowroute@<ext_IP>:5089;transport=udp;gw=flowroute>
User-Agent: FreeSWITCH-mod_sofia/1.10.6-release-18-1ff9d0a60e~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 477
P-Access-Network-Info: IEEE-EUI-48;eui-48-addr=84-D8-1B-E9-50-5F
P-Emergency-Info: IEEE-EUI-48;eui-48-addr=C0-74-AD-30-CD-39
X-FS-Support: update_display,send_info
Alert-Info: <internal>
Remote-Party-ID: "East West Fax" <sip:1<my_CID>@<ext_IP>>;party=calling;screen=yes;privacy=off

v=0
o=FreeSWITCH 1618413436 1618413437 IN IP4 <ext_IP>
s=FreeSWITCH
c=IN IP4 <ext_IP>
t=0 0
m=audio 27716 RTP/AVP 0 8 102 9 101 103
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:102 opus/48000/2
a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:103 telephone-event/48000
a=fmtp:103 0-16
a=ptime:20
send 1509 bytes to udp/[34.210.91.114]:5060 at 15:59:27.587021:
------------------------------------------------------------------------
INVITE sip:1<outside_number>@us-west-or.sip.flowroute.com SIP/2.0
Via: SIP/2.0/UDP <ext_IP>:5089;rport;branch=z9hG4bKcBHrQKXgHNctm
Max-Forwards: 69
From: "East West Fax" <sip:1<my_CID>@<ext_IP>>;tag=c99SXeSa6mSSQ
To: <sip:1<outside_number>@us-west-or.sip.flowroute.com>
Call-ID: dec531ef-1817-123a-9b91-10e7c6b0315b
CSeq: 34666784 INVITE
Contact: <sip:gw+flowroute@<ext_IP>:5089;transport=udp;gw=flowroute>
User-Agent: FreeSWITCH-mod_sofia/1.10.6-release-18-1ff9d0a60e~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 477
P-Access-Network-Info: IEEE-EUI-48;eui-48-addr=84-D8-1B-E9-50-5F
P-Emergency-Info: IEEE-EUI-48;eui-48-addr=C0-74-AD-30-CD-39
X-FS-Support: update_display,send_info
Alert-Info: <internal>
Remote-Party-ID: "East West Fax" <sip:1<my_CID>@<ext_IP>>;party=calling;screen=yes;privacy=off

v=0
o=FreeSWITCH 1618413436 1618413437 IN IP4 <ext_IP>
s=FreeSWITCH
c=IN IP4 <ext_IP>
t=0 0
m=audio 27716 RTP/AVP 0 8 102 9 101 103
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:102 opus/48000/2
a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:103 telephone-event/48000
a=fmtp:103 0-16
a=ptime:20
send 582 bytes to udp/[34.210.91.114]:5060 at 15:59:27.736742:
------------------------------------------------------------------------
OPTIONS sip:us-west-or.sip.flowroute.com;transport=udp SIP/2.0
Via: SIP/2.0/UDP <ext_IP>:5089;rport;branch=z9hG4bKeX39t9yQB7rZB
Max-Forwards: 70
From: <sip:us-west-or.sip.flowroute.com>;tag=eUvB14tH065ye
To: <sip:us-west-or.sip.flowroute.com>
Call-ID: e7cd185c-1817-123a-9b91-10e7c6b0315b
CSeq: 34628365 OPTIONS
User-Agent: FreeSWITCH-mod_sofia/1.10.6-release-18-1ff9d0a60e~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, refer
Content-Length: 0

recv 422 bytes from udp/[34.210.91.114]:5060 at 15:59:27.793329:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP <ext_IP>:5089;rport=5089;branch=z9hG4bKeX39t9yQB7rZB;received=<ext_IP>
From: <sip:us-west-or.sip.flowroute.com>;tag=eUvB14tH065ye
To: <sip:us-west-or.sip.flowroute.com>;tag=bf8638324618dc61059d4c604476fea1.670a0cb3
Call-ID: e7cd185c-1817-123a-9b91-10e7c6b0315b
CSeq: 34628365 OPTIONS
Accept: */*
Accept-Encoding: 
Accept-Language: en
Supported: timer
Content-Length: 0

send 1509 bytes to udp/[34.210.91.114]:5060 at 15:59:43.586631:
------------------------------------------------------------------------
INVITE sip:1<outside_number>@us-west-or.sip.flowroute.com SIP/2.0
Via: SIP/2.0/UDP <ext_IP>:5089;rport;branch=z9hG4bKcBHrQKXgHNctm
Max-Forwards: 69
From: "East West Fax" <sip:1<my_CID>@<ext_IP>>;tag=c99SXeSa6mSSQ
To: <sip:1<outside_number>@us-west-or.sip.flowroute.com>
Call-ID: dec531ef-1817-123a-9b91-10e7c6b0315b
CSeq: 34666784 INVITE
Contact: <sip:gw+flowroute@<ext_IP>:5089;transport=udp;gw=flowroute>
User-Agent: FreeSWITCH-mod_sofia/1.10.6-release-18-1ff9d0a60e~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 477
P-Access-Network-Info: IEEE-EUI-48;eui-48-addr=84-D8-1B-E9-50-5F
P-Emergency-Info: IEEE-EUI-48;eui-48-addr=C0-74-AD-30-CD-39
X-FS-Support: update_display,send_info
Alert-Info: <internal>
Remote-Party-ID: "East West Fax" <sip:1<my_CID>@<ext_IP>>;party=calling;screen=yes;privacy=off

v=0
o=FreeSWITCH 1618413436 1618413437 IN IP4 <ext_IP>
s=FreeSWITCH
c=IN IP4 <ext_IP>
t=0 0
m=audio 27716 RTP/AVP 0 8 102 9 101 103
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:102 opus/48000/2
a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:103 telephone-event/48000
a=fmtp:103 0-16
a=ptime:20
send 1509 bytes to udp/[147.75.60.161]:5060 at 15:59:44.584671:
------------------------------------------------------------------------
INVITE sip:1<outside_number>@us-west-or.sip.flowroute.com SIP/2.0
Via: SIP/2.0/UDP <ext_IP>:5089;rport;branch=z9hG4bKcBHrQKXgHNctm
Max-Forwards: 69
From: "East West Fax" <sip:1<my_CID>@<ext_IP>>;tag=c99SXeSa6mSSQ
To: <sip:1<outside_number>@us-west-or.sip.flowroute.com>
Call-ID: dec531ef-1817-123a-9b91-10e7c6b0315b
CSeq: 34666784 INVITE
Contact: <sip:gw+flowroute@<ext_IP>:5089;transport=udp;gw=flowroute>
User-Agent: FreeSWITCH-mod_sofia/1.10.6-release-18-1ff9d0a60e~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, refer
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 477
P-Access-Network-Info: IEEE-EUI-48;eui-48-addr=84-D8-1B-E9-50-5F
P-Emergency-Info: IEEE-EUI-48;eui-48-addr=C0-74-AD-30-CD-39
X-FS-Support: update_display,send_info
Alert-Info: <internal>
Remote-Party-ID: "East West Fax" <sip:1<my_CID>@<ext_IP>>;party=calling;screen=yes;privacy=off

v=0
o=FreeSWITCH 1618413436 1618413437 IN IP4 <ext_IP>
s=FreeSWITCH
c=IN IP4 <ext_IP>
t=0 0
m=audio 27716 RTP/AVP 0 8 102 9 101 103
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:102 opus/48000/2
a=fmtp:102 useinbandfec=1; maxaveragebitrate=30000; maxplaybackrate=48000; ptime=20; minptime=10; maxptime=40; stereo=1
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:103 telephone-event/48000
a=fmtp:103 0-16
a=ptime:20
freeswitch at pbx> sofia profile external siptrace off