recv 653 bytes from udp/[172.20.1.10]:5060 at 10:21:42.720276:
------------------------------------------------------------------------
REGISTER sip:172.20.1.2:5060 SIP/2.0
Via: SIP/2.0/UDP 172.20.1.10:5060;branch=z9hG4bK477b65ee7a44203c;rport
Contact: <sip:radio1@172.20.1.10:5060>;expires=5;reg-id=123
Max-Forwards: 70
Authorization: Digest username="radio1", realm="172.20.1.2", nonce="217b51f8-7916-11e7-a5b7-f77b865a2304", uri="sip:172.20.1.2:5060", response="1291bb46bca5d78989618e9ee9fdc46d", cnonce="29b30994607fbce8", qop=auth, nc=00037691
To: <sip:radio1@172.20.1.2:5060>
From: <sip:radio1@172.20.1.2:5060>;tag=39215c725235cf15
Call-ID: 29f9e1db035f537e
CSeq: 422200 REGISTER
User-Agent: Ervocom SIP UA 1.2.3 (?/?)
Supported: gruu, outbound, path
Content-Length: 0
------------------------------------------------------------------------
send 582 bytes to udp/[172.20.1.10]:5060 at 10:21:42.730592:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.20.1.10:5060;branch=z9hG4bK477b65ee7a44203c;rport=5060
From: <sip:radio1@172.20.1.2:5060>;tag=39215c725235cf15
To: <sip:radio1@172.20.1.2:5060>;tag=29tXye7mS4SyS
Call-ID: 29f9e1db035f537e
CSeq: 422200 REGISTER
Contact: <sip:radio1@172.20.1.10:5060>;expires=5
Date: Wed, 16 Aug 2017 10:21:42 GMT
User-Agent: FreeSWITCH-mod_sofia/1.6.6+git~20170203T085721Z~df20d5359b~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Content-Length: 0
------------------------------------------------------------------------
recv 1392 bytes from udp/[10.10.30.51]:5060 at 10:21:44.607542:
------------------------------------------------------------------------
INVITE sip:0792710365@172.20.1.2 SIP/2.0
Via: SIP/2.0/UDP 10.10.30.51:5060;branch=z9hG4bK-x6wtln1jlx24;rport
From: "RBS Testing" <sip:1000@172.20.1.2>;tag=qcx12rgq6j
To: <sip:0792710365@172.20.1.2>
Call-ID: 3135303238373836373632363431-00jupnh0yqw5
CSeq: 1 INVITE
Max-Forwards: 70
User-Agent: snomD375/8.9.3.15
Contact: <sip:1000@10.10.30.51:5060;line=8dg3t92k>;reg-id=1
X-Serialnumber: 000413910687
P-Key-Flags: resolution="31x13", keys="4"
Accept: application/sdp
Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO, UPDATE
Allow-Events: talk, hold, refer, call-info
Supported: timer, 100rel, replaces, from-change
Session-Expires: 3600
Min-SE: 90
Content-Type: application/sdp
Content-Length: 634
v=0
o=root 983205152 983205152 IN IP4 10.10.30.51
s=call
c=IN IP4 10.10.30.51
t=0 0
m=audio 60010 RTP/SAVP 0 8 3 99 112 18
a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:d3erFouHF3vrM6Ot3BsOjIIukGc7SwHESuakBBFU
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:99 G726-32/8000
a=rtpmap:112 AAL2-G726-32/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=ptime:20
a=sendrecv
m=audio 60010 RTP/AVP 0 8 3 99 112 18
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:99 G726-32/8000
a=rtpmap:112 AAL2-G726-32/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=ptime:20
a=sendrecv
------------------------------------------------------------------------
send 352 bytes to udp/[10.10.30.51]:5060 at 10:21:44.609116:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 10.10.30.51:5060;branch=z9hG4bK-x6wtln1jlx24;rport=5060
From: "RBS Testing" <sip:1000@172.20.1.2>;tag=qcx12rgq6j
To: <sip:0792710365@172.20.1.2>
Call-ID: 3135303238373836373632363431-00jupnh0yqw5
CSeq: 1 INVITE
User-Agent: FreeSWITCH-mod_sofia/1.6.6+git~20170203T085721Z~df20d5359b~64bit
Content-Length: 0
------------------------------------------------------------------------
2017-08-16 10:21:44.598073 [NOTICE] switch_channel.c:1101 New Channel sofia/internal/1000@172.20.1.2 [b4006ee6-826c-11e7-a743-f77b865a2304]
2017-08-16 10:21:44.598073 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1000@172.20.1.2) Running State Change CS_NEW
2017-08-16 10:21:44.598073 [DEBUG] sofia.c:9248 sofia/internal/1000@172.20.1.2 receiving invite from 10.10.30.51:5060 version: 1.6.6 git df20d53 2017-02-03 08:57:21Z 64bit
2017-08-16 10:21:44.598073 [DEBUG] sofia.c:9415 IP 10.10.30.51 Rejected by acl "domains". Falling back to Digest auth.
2017-08-16 10:21:44.598073 [DEBUG] switch_core_state_machine.c:492 (sofia/internal/1000@172.20.1.2) State NEW
send 853 bytes to udp/[10.10.30.51]:5060 at 10:21:44.612844:
------------------------------------------------------------------------
SIP/2.0 407 Proxy Authentication Required
Via: SIP/2.0/UDP 10.10.30.51:5060;branch=z9hG4bK-x6wtln1jlx24;rport=5060
From: "RBS Testing" <sip:1000@172.20.1.2>;tag=qcx12rgq6j
To: <sip:0792710365@172.20.1.2>;tag=3jmp09QrpDgHN
Call-ID: 3135303238373836373632363431-00jupnh0yqw5
CSeq: 1 INVITE
User-Agent: FreeSWITCH-mod_sofia/1.6.6+git~20170203T085721Z~df20d5359b~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="172.20.1.2", nonce="b400a78a-826c-11e7-a744-f77b865a2304", algorithm=MD5, qop="auth"
Content-Length: 0
------------------------------------------------------------------------
2017-08-16 10:21:44.598073 [DEBUG] sofia.c:2147 detaching session b4006ee6-826c-11e7-a743-f77b865a2304
recv 413 bytes from udp/[10.10.30.51]:5060 at 10:21:44.740837:
------------------------------------------------------------------------
ACK sip:0792710365@172.20.1.2 SIP/2.0
Via: SIP/2.0/UDP 10.10.30.51:5060;branch=z9hG4bK-x6wtln1jlx24;rport
From: "RBS Testing" <sip:1000@172.20.1.2>;tag=qcx12rgq6j
To: <sip:0792710365@172.20.1.2>;tag=3jmp09QrpDgHN
Call-ID: 3135303238373836373632363431-00jupnh0yqw5
CSeq: 1 ACK
Max-Forwards: 70
User-Agent: snomD375/8.9.3.15
Contact: <sip:1000@10.10.30.51:5060;line=8dg3t92k>;reg-id=1
Content-Length: 0
------------------------------------------------------------------------
recv 1630 bytes from udp/[10.10.30.51]:5060 at 10:21:46.256889:
------------------------------------------------------------------------
INVITE sip:0792710365@172.20.1.2 SIP/2.0
Via: SIP/2.0/UDP 10.10.30.51:5060;branch=z9hG4bK-0dzelj3ut8ee;rport
From: "RBS Testing" <sip:1000@172.20.1.2>;tag=qcx12rgq6j
To: <sip:0792710365@172.20.1.2>
Call-ID: 3135303238373836373632363431-00jupnh0yqw5
CSeq: 2 INVITE
Max-Forwards: 70
User-Agent: snomD375/8.9.3.15
Contact: <sip:1000@10.10.30.51:5060;line=8dg3t92k>;reg-id=1
X-Serialnumber: 000413910687
P-Key-Flags: resolution="31x13", keys="4"
Accept: application/sdp
Allow: INVITE, ACK, CANCEL, BYE, REFER, OPTIONS, NOTIFY, SUBSCRIBE, PRACK, MESSAGE, INFO, UPDATE
Allow-Events: talk, hold, refer, call-info
Supported: timer, 100rel, replaces, from-change
Session-Expires: 3600
Min-SE: 90
Proxy-Authorization: Digest username="1000",realm="172.20.1.2",nonce="b400a78a-826c-11e7-a744-f77b865a2304",uri="sip:0792710365@172.20.1.2",qop=auth,nc=00000001,cnonce="5df0c72c",response="7b7ae0c2c1b8eaffe954ca7affdcff08",algorithm=MD5
Content-Type: application/sdp
Content-Length: 634
v=0
o=root 983205152 983205152 IN IP4 10.10.30.51
s=call
c=IN IP4 10.10.30.51
t=0 0
m=audio 60010 RTP/SAVP 0 8 3 99 112 18
a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:d3erFouHF3vrM6Ot3BsOjIIukGc7SwHESuakBBFU
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:99 G726-32/8000
a=rtpmap:112 AAL2-G726-32/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=ptime:20
a=sendrecv
m=audio 60010 RTP/AVP 0 8 3 99 112 18
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:99 G726-32/8000
a=rtpmap:112 AAL2-G726-32/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=ptime:20
a=sendrecv
------------------------------------------------------------------------
send 352 bytes to udp/[10.10.30.51]:5060 at 10:21:46.258522:
------------------------------------------------------------------------
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 10.10.30.51:5060;branch=z9hG4bK-0dzelj3ut8ee;rport=5060
From: "RBS Testing" <sip:1000@172.20.1.2>;tag=qcx12rgq6j
To: <sip:0792710365@172.20.1.2>
Call-ID: 3135303238373836373632363431-00jupnh0yqw5
CSeq: 2 INVITE
User-Agent: FreeSWITCH-mod_sofia/1.6.6+git~20170203T085721Z~df20d5359b~64bit
Content-Length: 0
------------------------------------------------------------------------
2017-08-16 10:21:46.258063 [DEBUG] sofia.c:2255 Re-attaching to session b4006ee6-826c-11e7-a743-f77b865a2304
2017-08-16 10:21:46.258063 [DEBUG] sofia.c:9248 sofia/internal/1000@172.20.1.2 receiving invite from 10.10.30.51:5060 version: 1.6.6 git df20d53 2017-02-03 08:57:21Z 64bit
2017-08-16 10:21:46.258063 [DEBUG] sofia.c:9415 IP 10.10.30.51 Rejected by acl "domains". Falling back to Digest auth.
2017-08-16 10:21:46.278084 [DEBUG] sofia.c:6760 Channel sofia/internal/1000@172.20.1.2 entering state [received][100]
2017-08-16 10:21:46.278084 [DEBUG] sofia.c:6770 Remote SDP:
v=0
o=root 983205152 983205152 IN IP4 10.10.30.51
s=call
c=IN IP4 10.10.30.51
t=0 0
m=audio 60010 RTP/SAVP 0 8 3 99 112 18
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:99 G726-32/8000
a=rtpmap:112 AAL2-G726-32/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:d3erFouHF3vrM6Ot3BsOjIIukGc7SwHESuakBBFU
a=ptime:20
m=audio 60010 RTP/AVP 0 8 3 99 112 18
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:99 G726-32/8000
a=rtpmap:112 AAL2-G726-32/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=ptime:20
2017-08-16 10:21:46.278084 [DEBUG] sofia.c:7125 (sofia/internal/1000@172.20.1.2) State Change CS_NEW -> CS_INIT
2017-08-16 10:21:46.278084 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1000@172.20.1.2) Running State Change CS_INIT
2017-08-16 10:21:46.278084 [DEBUG] switch_core_state_machine.c:516 (sofia/internal/1000@172.20.1.2) State INIT
2017-08-16 10:21:46.278084 [DEBUG] mod_sofia.c:88 sofia/internal/1000@172.20.1.2 SOFIA INIT
2017-08-16 10:21:46.278084 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1000@172.20.1.2 Standard INIT
2017-08-16 10:21:46.278084 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1000@172.20.1.2) State Change CS_INIT -> CS_ROUTING
2017-08-16 10:21:46.278084 [DEBUG] switch_core_state_machine.c:516 (sofia/internal/1000@172.20.1.2) State INIT going to sleep
2017-08-16 10:21:46.278084 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1000@172.20.1.2) Running State Change CS_ROUTING
2017-08-16 10:21:46.278084 [DEBUG] switch_channel.c:2247 (sofia/internal/1000@172.20.1.2) Callstate Change DOWN -> RINGING
2017-08-16 10:21:46.278084 [DEBUG] switch_core_state_machine.c:532 (sofia/internal/1000@172.20.1.2) State ROUTING
2017-08-16 10:21:46.278084 [DEBUG] mod_sofia.c:141 sofia/internal/1000@172.20.1.2 SOFIA ROUTING
2017-08-16 10:21:46.278084 [DEBUG] switch_core_state_machine.c:166 sofia/internal/1000@172.20.1.2 Standard ROUTING
2017-08-16 10:21:46.278084 [INFO] mod_dialplan_xml.c:637 Processing RBS Testing <1000>->0792710365 in context default
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->unloop] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->tod_example] continue=true
Dialplan: sofia/internal/1000@172.20.1.2 Date/Time Match (PASS) [tod_example] break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 Action set(open=true)
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->holiday_example] continue=true
Dialplan: sofia/internal/1000@172.20.1.2 Date/TimeMatch (FAIL) [holiday_example] break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->global-intercept] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [global-intercept] destination_number(0792710365) =~ /^886$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->group-intercept] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [group-intercept] destination_number(0792710365) =~ /^\*8$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->intercept-ext] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [intercept-ext] destination_number(0792710365) =~ /^\*\*(\d+)$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->redial] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [redial] destination_number(0792710365) =~ /^(redial|870)$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->global] continue=true
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [global] ${call_debug}(false) =~ /^true$/ break=never
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [global] ${default_password}(12345) =~ /^1234$/ break=never
Dialplan: sofia/internal/1000@172.20.1.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/internal/1000@172.20.1.2 Regex (PASS) [global] ${endpoint_disposition}(DELAYED NEGOTIATION) =~ /^(DELAYED NEGOTIATION)/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (PASS) [global] ${switch_r_sdp}(v=0
o=root 983205152 983205152 IN IP4 10.10.30.51
s=call
c=IN IP4 10.10.30.51
t=0 0
m=audio 60010 RTP/SAVP 0 8 3 99 112 18
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:99 G726-32/8000
a=rtpmap:112 AAL2-G726-32/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:d3erFouHF3vrM6Ot3BsOjIIukGc7SwHESuakBBFU
a=ptime:20
m=audio 60010 RTP/AVP 0 8 3 99 112 18
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:99 G726-32/8000
a=rtpmap:112 AAL2-G726-32/8000
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
a=ptime:20
) =~ /(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)/ break=never
Dialplan: sofia/internal/1000@172.20.1.2 Action set(rtp_secure_media=true)
Dialplan: sofia/internal/1000@172.20.1.2 Absolute Condition [global]
Dialplan: sofia/internal/1000@172.20.1.2 Action hash(insert/${domain_name}-spymap/${caller_id_number}/${uuid})
Dialplan: sofia/internal/1000@172.20.1.2 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
Dialplan: sofia/internal/1000@172.20.1.2 Action hash(insert/${domain_name}-last_dial/global/${uuid})
Dialplan: sofia/internal/1000@172.20.1.2 Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->snom-demo-2] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [snom-demo-2] destination_number(0792710365) =~ /^9001$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->snom-demo-1] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [snom-demo-1] destination_number(0792710365) =~ /^9000$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->eavesdrop] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [eavesdrop] destination_number(0792710365) =~ /^88(\d{4})$|^\*0(.*)$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->eavesdrop] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [eavesdrop] destination_number(0792710365) =~ /^779$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->call_return] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [call_return] destination_number(0792710365) =~ /^\*69$|^869$|^lcr$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->del-group] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [del-group] destination_number(0792710365) =~ /^80(\d{2})$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->add-group] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [add-group] destination_number(0792710365) =~ /^81(\d{2})$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->call-group-simo] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [call-group-simo] destination_number(0792710365) =~ /^82(\d{2})$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->call-group-order] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [call-group-order] destination_number(0792710365) =~ /^83(\d{2})$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->extension-intercom] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [extension-intercom] destination_number(0792710365) =~ /^8(10[01][0-9])$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->Local_Extension] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [Local_Extension] destination_number(0792710365) =~ /^(10[01][0-9])$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->Local_Extension_Skinny] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [Local_Extension_Skinny] destination_number(0792710365) =~ /^(11[01][0-9])$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->group_dial_sales] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [group_dial_sales] destination_number(0792710365) =~ /^2000$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->group_dial_support] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [group_dial_support] destination_number(0792710365) =~ /^2001$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->group_dial_billing] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [group_dial_billing] destination_number(0792710365) =~ /^2002$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->operator] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [operator] destination_number(0792710365) =~ /^(operator|0)$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->vmain] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [vmain] destination_number(0792710365) =~ /^vmain$|^4000$|^\*98$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->sip_uri] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [sip_uri] destination_number(0792710365) =~ /^sip:(.*)$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->nb_conferences] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [nb_conferences] destination_number(0792710365) =~ /^(30\d{2})$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->wb_conferences] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [wb_conferences] destination_number(0792710365) =~ /^(31\d{2})$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->uwb_conferences] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [uwb_conferences] destination_number(0792710365) =~ /^(32\d{2})$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->cdquality_conferences] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [cdquality_conferences] destination_number(0792710365) =~ /^(33\d{2})$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->cdquality_conferences] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [cdquality_conferences] destination_number(0792710365) =~ /^(35\d{2})$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->freeswitch_public_conf_via_sip] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [freeswitch_public_conf_via_sip] destination_number(0792710365) =~ /^9(888|8888|1616|3232)$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->mad_boss_intercom] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [mad_boss_intercom] destination_number(0792710365) =~ /^0911$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->mad_boss_intercom] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [mad_boss_intercom] destination_number(0792710365) =~ /^0912$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->mad_boss] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [mad_boss] destination_number(0792710365) =~ /^0913$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->ivr_demo] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [ivr_demo] destination_number(0792710365) =~ /^5000$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->dynamic_conference] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [dynamic_conference] destination_number(0792710365) =~ /^5001$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->rtp_multicast_page] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [rtp_multicast_page] destination_number(0792710365) =~ /^pagegroup$|^7243$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->park] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [park] destination_number(0792710365) =~ /^5900$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->unpark] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [unpark] destination_number(0792710365) =~ /^5901$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->valet_park] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [valet_park] destination_number(0792710365) =~ /^(6000)$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->valet_park] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [valet_park] destination_number(0792710365) =~ /^((?!6000)60\d{2})$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->park] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (PASS) [park] source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [park] destination_number(0792710365) =~ /park\+(\d+)/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->unpark] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (PASS) [unpark] source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [unpark] destination_number(0792710365) =~ /^parking$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->park] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (PASS) [park] source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [park] destination_number(0792710365) =~ /callpark/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->unpark] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (PASS) [unpark] source(mod_sofia) =~ /mod_sofia/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [unpark] destination_number(0792710365) =~ /pickup/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->wait] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [wait] destination_number(0792710365) =~ /^wait$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->fax_receive] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [fax_receive] destination_number(0792710365) =~ /^9178$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->fax_transmit] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [fax_transmit] destination_number(0792710365) =~ /^9179$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->ringback_180] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [ringback_180] destination_number(0792710365) =~ /^9180$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->ringback_183_uk_ring] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [ringback_183_uk_ring] destination_number(0792710365) =~ /^9181$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->ringback_183_music_ring] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [ringback_183_music_ring] destination_number(0792710365) =~ /^9182$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->ringback_post_answer_uk_ring] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [ringback_post_answer_uk_ring] destination_number(0792710365) =~ /^9183$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->ringback_post_answer_music] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [ringback_post_answer_music] destination_number(0792710365) =~ /^9184$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->ClueCon] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [ClueCon] destination_number(0792710365) =~ /^9191$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->show_info] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [show_info] destination_number(0792710365) =~ /^9192$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->video_record] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [video_record] destination_number(0792710365) =~ /^9193$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->video_playback] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [video_playback] destination_number(0792710365) =~ /^9194$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->delay_echo] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [delay_echo] destination_number(0792710365) =~ /^9195$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->echo] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [echo] destination_number(0792710365) =~ /^9196$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->milliwatt] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [milliwatt] destination_number(0792710365) =~ /^9197$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->tone_stream] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [tone_stream] destination_number(0792710365) =~ /^9198$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->zrtp_enrollement] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [zrtp_enrollement] destination_number(0792710365) =~ /^9787$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->hold_music] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [hold_music] destination_number(0792710365) =~ /^9664$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->laugh break] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [laugh break] destination_number(0792710365) =~ /^9386$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->Emergency Numbers] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (PASS) [Emergency Numbers] ${toll_allow}(domestic,international,local) =~ /local/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [Emergency Numbers] destination_number(0792710365) =~ /^[0-9][0-9][0-9]$/ match=any
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [Emergency Numbers] destination_number(0792710365) =~ /^1414$/ match=any
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [Emergency Numbers] destination_number(0792710365) =~ /^1415$/ match=any
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->101] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (FAIL) [101] destination_number(0792710365) =~ /^101$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 parsing [default->outbound calls] continue=false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (PASS) [outbound calls] ${toll_allow}(domestic,international,local) =~ /local/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 Regex (PASS) [outbound calls] destination_number(0792710365) =~ /^(\d{7,18})$/ break=on-false
Dialplan: sofia/internal/1000@172.20.1.2 Action set(effective_caller_id_number=${outbound_caller_id_number})
Dialplan: sofia/internal/1000@172.20.1.2 Action set(effective_caller_id_name=${outbound_caller_id_name})
Dialplan: sofia/internal/1000@172.20.1.2 Action bridge(sofia/gateway/swisscom/0792710365)
2017-08-16 10:21:46.278084 [DEBUG] switch_core_state_machine.c:216 (sofia/internal/1000@172.20.1.2) State Change CS_ROUTING -> CS_EXECUTE
2017-08-16 10:21:46.278084 [DEBUG] switch_core_state_machine.c:532 (sofia/internal/1000@172.20.1.2) State ROUTING going to sleep
2017-08-16 10:21:46.278084 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1000@172.20.1.2) Running State Change CS_EXECUTE
2017-08-16 10:21:46.278084 [DEBUG] switch_core_state_machine.c:539 (sofia/internal/1000@172.20.1.2) State EXECUTE
2017-08-16 10:21:46.278084 [DEBUG] mod_sofia.c:196 sofia/internal/1000@172.20.1.2 SOFIA EXECUTE
2017-08-16 10:21:46.278084 [DEBUG] switch_core_state_machine.c:258 sofia/internal/1000@172.20.1.2 Standard EXECUTE
EXECUTE sofia/internal/1000@172.20.1.2 set(open=true)
2017-08-16 10:21:46.278084 [DEBUG] mod_dptools.c:1498 SET sofia/internal/1000@172.20.1.2 [open]=[true]
EXECUTE sofia/internal/1000@172.20.1.2 set(rtp_secure_media=true)
2017-08-16 10:21:46.298081 [DEBUG] mod_dptools.c:1498 SET sofia/internal/1000@172.20.1.2 [rtp_secure_media]=[true]
EXECUTE sofia/internal/1000@172.20.1.2 hash(insert/172.20.1.2-spymap/1000/b4006ee6-826c-11e7-a743-f77b865a2304)
EXECUTE sofia/internal/1000@172.20.1.2 hash(insert/172.20.1.2-last_dial/1000/0792710365)
EXECUTE sofia/internal/1000@172.20.1.2 hash(insert/172.20.1.2-last_dial/global/b4006ee6-826c-11e7-a743-f77b865a2304)
EXECUTE sofia/internal/1000@172.20.1.2 export(RFC2822_DATE=Wed, 16 Aug 2017 10:21:46 +0000)
2017-08-16 10:21:46.298081 [DEBUG] switch_channel.c:1293 EXPORT (export_vars) [RFC2822_DATE]=[Wed, 16 Aug 2017 10:21:46 +0000]
EXECUTE sofia/internal/1000@172.20.1.2 set(effective_caller_id_number=0000000000)
2017-08-16 10:21:46.298081 [DEBUG] mod_dptools.c:1498 SET sofia/internal/1000@172.20.1.2 [effective_caller_id_number]=[0000000000]
EXECUTE sofia/internal/1000@172.20.1.2 set(effective_caller_id_name=FreeSWITCH)
2017-08-16 10:21:46.298081 [DEBUG] mod_dptools.c:1498 SET sofia/internal/1000@172.20.1.2 [effective_caller_id_name]=[FreeSWITCH]
EXECUTE sofia/internal/1000@172.20.1.2 bridge(sofia/gateway/swisscom/0792710365)
2017-08-16 10:21:46.298081 [DEBUG] switch_channel.c:1247 sofia/internal/1000@172.20.1.2 EXPORTING[export_vars] [RFC2822_DATE]=[Wed, 16 Aug 2017 10:21:46 +0000] to event
2017-08-16 10:21:46.298081 [DEBUG] switch_ivr_originate.c:2128 Parsing global variables
2017-08-16 10:21:46.298081 [NOTICE] switch_channel.c:1101 New Channel sofia/external/0792710365 [b503fd62-826c-11e7-a752-f77b865a2304]
2017-08-16 10:21:46.298081 [DEBUG] mod_sofia.c:4776 (sofia/external/0792710365) State Change CS_NEW -> CS_INIT
2017-08-16 10:21:46.298081 [DEBUG] switch_core_state_machine.c:473 (sofia/external/0792710365) Running State Change CS_INIT
2017-08-16 10:21:46.298081 [DEBUG] switch_core_state_machine.c:516 (sofia/external/0792710365) State INIT
2017-08-16 10:21:46.298081 [DEBUG] mod_sofia.c:88 sofia/external/0792710365 SOFIA INIT
2017-08-16 10:21:46.298081 [DEBUG] sofia_glue.c:1257 sofia/external/0792710365 sending invite version: 1.6.6 git df20d53 2017-02-03 08:57:21Z 64bit
Local SDP:
v=0
o=FreeSWITCH 1502860554 1502860555 IN IP4 192.168.1.34
s=FreeSWITCH
c=IN IP4 192.168.1.34
t=0 0
m=audio 18352 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
2017-08-16 10:21:46.298081 [DEBUG] switch_core_state_machine.c:40 sofia/external/0792710365 Standard INIT
2017-08-16 10:21:46.298081 [DEBUG] switch_core_state_machine.c:48 (sofia/external/0792710365) State Change CS_INIT -> CS_ROUTING
2017-08-16 10:21:46.298081 [DEBUG] switch_core_state_machine.c:516 (sofia/external/0792710365) State INIT going to sleep
2017-08-16 10:21:46.298081 [DEBUG] switch_core_state_machine.c:473 (sofia/external/0792710365) Running State Change CS_ROUTING
send 1162 bytes to udp/[192.168.1.1]:5060 at 10:21:46.316172:
------------------------------------------------------------------------
INVITE sip:0792710365@192.168.1.1 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.34:5080;rport;branch=z9hG4bKvtmNZ6X89ce6e
Max-Forwards: 69
From: "FreeSWITCH" <sip:+41554405801@192.168.1.1>;tag=9KSaFK40ScZKK
To: <sip:0792710365@192.168.1.1>
Call-ID: 8c630451-fd0f-1235-79b8-000db942b3d0
CSeq: 111103389 INVITE
Contact: <sip:gw+swisscom@192.168.1.34:5080;transport=udp;gw=swisscom>
User-Agent: FreeSWITCH-mod_sofia/1.6.6+git~20170203T085721Z~df20d5359b~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: 244
X-Serialnumber: 000413910687
P-Key-Flags: resolution="31x13", keys="4"
X-FS-Support: update_display,send_info
2017-08-16 10:21:46.298081 [DEBUG] switch_core_state_machine.c:532 (sofia/external/0792710365) State ROUTING
Remote-Party-ID: "FreeSWITCH" <sip:0000000000@192.168.1.1>;party=calling;screen=yes;privacy=off
2017-08-16 10:21:46.298081 [DEBUG] mod_sofia.c:141 sofia/external/0792710365 SOFIA ROUTING
v=0
o=FreeSWITCH 1502860554 1502860555 IN IP4 192.168.1.34
s=FreeSWITCH
2017-08-16 10:21:46.298081 [DEBUG] switch_ivr_originate.c:67 (sofia/external/0792710365) State Change CS_ROUTING -> CS_CONSUME_MEDIA
c=IN IP4 192.168.1.34
t=0 0
m=audio 18352 RTP/AVP 0 8 101
a=rtpmap:0 PCMU/8000
2017-08-16 10:21:46.298081 [DEBUG] switch_core_state_machine.c:532 (sofia/external/0792710365) State ROUTING going to sleep
a=rtpmap:8 PCMA/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
2017-08-16 10:21:46.298081 [DEBUG] switch_core_state_machine.c:473 (sofia/external/0792710365) Running State Change CS_CONSUME_MEDIA
------------------------------------------------------------------------
2017-08-16 10:21:46.298081 [DEBUG] sofia.c:6760 Channel sofia/external/0792710365 entering state [calling][0]
2017-08-16 10:21:46.298081 [DEBUG] switch_core_state_machine.c:551 (sofia/external/0792710365) State CONSUME_MEDIA
2017-08-16 10:21:46.298081 [DEBUG] switch_core_state_machine.c:551 (sofia/external/0792710365) State CONSUME_MEDIA going to sleep
recv 545 bytes from udp/[192.168.1.1]:5060 at 10:21:46.318456:
------------------------------------------------------------------------
SIP/2.0 407 Proxy Authentication Required
From: "FreeSWITCH"<sip:+41554405801@192.168.1.1>;tag=9KSaFK40ScZKK
To: <sip:0792710365@192.168.1.1>;tag=1a74cb8-101a8c0-13c4-5506-27909-62e2f23d-27909
Call-ID: 8c630451-fd0f-1235-79b8-000db942b3d0
CSeq: 111103389 INVITE
Proxy-Authenticate: Digest realm="epicentro.localrealm",nonce="1a74cb8b6b34",stale=true,algorithm=MD5,qop="auth"
Via: SIP/2.0/UDP 192.168.1.34:5080;rport=5080;branch=z9hG4bKvtmNZ6X89ce6e
Supported: replaces,100rel
User-Agent: Epicentro - YAPS ADB Stack
Content-Length: 0
------------------------------------------------------------------------
send 371 bytes to udp/[192.168.1.1]:5060 at 10:21:46.318930:
------------------------------------------------------------------------
ACK sip:0792710365@192.168.1.1 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.34:5080;rport;branch=z9hG4bKvtmNZ6X89ce6e
Max-Forwards: 69
From: "FreeSWITCH" <sip:+41554405801@192.168.1.1>;tag=9KSaFK40ScZKK
To: <sip:0792710365@192.168.1.1>;tag=1a74cb8-101a8c0-13c4-5506-27909-62e2f23d-27909
Call-ID: 8c630451-fd0f-1235-79b8-000db942b3d0
CSeq: 111103389 ACK
Content-Length: 0
------------------------------------------------------------------------
send 1417 bytes to udp/[192.168.1.1]:5060 at 10:21:46.319882:
------------------------------------------------------------------------
INVITE sip:0792710365@192.168.1.1 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.34:5080;rport;branch=z9hG4bKX3De11ec7N4ra
Max-Forwards: 69
From: "FreeSWITCH" <sip:+41554405801@192.168.1.1>;tag=9KSaFK40ScZKK
To: <sip:0792710365@192.168.1.1>
Call-ID: 8c630451-fd0f-1235-79b8-000db942b3d0
CSeq: 111103390 INVITE
Contact: <sip:gw+swisscom@192.168.1.34:5080;transport=udp;gw=swisscom>
User-Agent: FreeSWITCH-mod_sofia/1.6.6+git~20170203T085721Z~df20d5359b~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, path, replaces
Allow-Events: talk, hold, conference, refer
Proxy-Authorization: Digest username="+41554405801", realm="epicentro.localrealm", nonce="1a74cb8b6b34", cnonce="jGONsv0PEjW4eQANuUKz0A", algorithm=MD5, uri="sip:0792710365@192.168.1.1", response="23d0951b79f809b591998f604559d66b", qop=auth, nc=00000001
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 244
X-Serialnumber: 000413910687
P-Key-Flags: resolution="31x13", keys="4"
X-FS-Support: update_display,send_info
Remote-Party-ID: "FreeSWITCH" <sip:0000000000@192.168.1.1>;party=calling;screen=yes;privacy=off
v=0
o=FreeSWITCH 1502860554 1502860555 IN IP4 192.168.1.34
s=FreeSWITCH
c=IN IP4 192.168.1.34
t=0 0
m=audio 18352 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
------------------------------------------------------------------------
2017-08-16 10:21:46.318087 [DEBUG] sofia.c:6760 Channel sofia/external/0792710365 entering state [calling][0]
recv 396 bytes from udp/[192.168.1.1]:5060 at 10:21:46.323141:
------------------------------------------------------------------------
SIP/2.0 100 Trying
From: "FreeSWITCH"<sip:+41554405801@192.168.1.1>;tag=9KSaFK40ScZKK
To: <sip:0792710365@192.168.1.1>
Call-ID: 8c630451-fd0f-1235-79b8-000db942b3d0
CSeq: 111103390 INVITE
Via: SIP/2.0/UDP 192.168.1.34:5080;rport=5080;branch=z9hG4bKX3De11ec7N4ra
Supported: replaces,100rel
User-Agent: Epicentro - YAPS ADB Stack
Contact: <sip:0792710365@192.168.1.1>
Content-Length: 0
------------------------------------------------------------------------
recv 653 bytes from udp/[172.20.1.10]:5060 at 10:21:47.240083:
------------------------------------------------------------------------
REGISTER sip:172.20.1.2:5060 SIP/2.0
Via: SIP/2.0/UDP 172.20.1.10:5060;branch=z9hG4bK3f04087a58cf37a5;rport
Contact: <sip:radio1@172.20.1.10:5060>;expires=5;reg-id=123
Max-Forwards: 70
Authorization: Digest username="radio1", realm="172.20.1.2", nonce="217b51f8-7916-11e7-a5b7-f77b865a2304", uri="sip:172.20.1.2:5060", response="774654fc6d67c70ffeab568251d907bd", cnonce="7808959e7b58dca3", qop=auth, nc=00037692
To: <sip:radio1@172.20.1.2:5060>
From: <sip:radio1@172.20.1.2:5060>;tag=39215c725235cf15
Call-ID: 29f9e1db035f537e
CSeq: 422201 REGISTER
User-Agent: Ervocom SIP UA 1.2.3 (?/?)
Supported: gruu, outbound, path
Content-Length: 0
------------------------------------------------------------------------
send 582 bytes to udp/[172.20.1.10]:5060 at 10:21:47.250086:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.20.1.10:5060;branch=z9hG4bK3f04087a58cf37a5;rport=5060
From: <sip:radio1@172.20.1.2:5060>;tag=39215c725235cf15
To: <sip:radio1@172.20.1.2:5060>;tag=54673ZSZgZvpc
Call-ID: 29f9e1db035f537e
CSeq: 422201 REGISTER
Contact: <sip:radio1@172.20.1.10:5060>;expires=5
Date: Wed, 16 Aug 2017 10:21:47 GMT
User-Agent: FreeSWITCH-mod_sofia/1.6.6+git~20170203T085721Z~df20d5359b~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Content-Length: 0
------------------------------------------------------------------------
recv 653 bytes from udp/[172.20.1.10]:5060 at 10:21:51.761768:
------------------------------------------------------------------------
REGISTER sip:172.20.1.2:5060 SIP/2.0
Via: SIP/2.0/UDP 172.20.1.10:5060;branch=z9hG4bK79bca150693f58b0;rport
Contact: <sip:radio1@172.20.1.10:5060>;expires=5;reg-id=123
Max-Forwards: 70
Authorization: Digest username="radio1", realm="172.20.1.2", nonce="217b51f8-7916-11e7-a5b7-f77b865a2304", uri="sip:172.20.1.2:5060", response="a2ffa1b4fb24a7b0ba0bfc743b87b401", cnonce="0046856750a02932", qop=auth, nc=00037693
To: <sip:radio1@172.20.1.2:5060>
From: <sip:radio1@172.20.1.2:5060>;tag=39215c725235cf15
Call-ID: 29f9e1db035f537e
CSeq: 422202 REGISTER
User-Agent: Ervocom SIP UA 1.2.3 (?/?)
Supported: gruu, outbound, path
Content-Length: 0
------------------------------------------------------------------------
send 582 bytes to udp/[172.20.1.10]:5060 at 10:21:51.771857:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.20.1.10:5060;branch=z9hG4bK79bca150693f58b0;rport=5060
From: <sip:radio1@172.20.1.2:5060>;tag=39215c725235cf15
To: <sip:radio1@172.20.1.2:5060>;tag=6D005ta3D8j9Q
Call-ID: 29f9e1db035f537e
CSeq: 422202 REGISTER
Contact: <sip:radio1@172.20.1.10:5060>;expires=5
Date: Wed, 16 Aug 2017 10:21:51 GMT
User-Agent: FreeSWITCH-mod_sofia/1.6.6+git~20170203T085721Z~df20d5359b~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Content-Length: 0
------------------------------------------------------------------------
recv 654 bytes from udp/[192.168.1.1]:5060 at 10:21:52.603947:
------------------------------------------------------------------------
SIP/2.0 183 Session Progress
From: "FreeSWITCH"<sip:+41554405801@192.168.1.1>;tag=9KSaFK40ScZKK
To: <sip:0792710365@192.168.1.1>;tag=1a76240-101a8c0-13c4-5506-2790f-293f0fcb-2790f
Call-ID: 8c630451-fd0f-1235-79b8-000db942b3d0
CSeq: 111103390 INVITE
Via: SIP/2.0/UDP 192.168.1.34:5080;rport=5080;branch=z9hG4bKX3De11ec7N4ra
Supported: replaces,100rel
User-Agent: Epicentro - YAPS ADB Stack
Contact: <sip:0792710365@192.168.1.1>
Content-Type: application/sdp
Content-Length: 164
v=0
o=root 3066086076 3066086076 IN IP4 192.168.1.1
s=session
c=IN IP4 192.168.1.1
t=0 0
m=audio 4988 RTP/AVP 8
a=rtpmap:8 PCMA/8000
a=ptime:20
a=sendrecv
------------------------------------------------------------------------
2017-08-16 10:21:52.598067 [DEBUG] sofia.c:6760 Channel sofia/external/0792710365 entering state [proceeding][183]
2017-08-16 10:21:52.598067 [DEBUG] sofia.c:6770 Remote SDP:
v=0
o=root 3066086076 3066086076 IN IP4 192.168.1.1
s=session
c=IN IP4 192.168.1.1
t=0 0
m=audio 4988 RTP/AVP 8
a=rtpmap:8 PCMA/8000
a=ptime:20
2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:4216 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:2906 Set Codec sofia/external/0792710365 PCMA/8000 20 ms 160 samples 64000 bits 1 channels
2017-08-16 10:21:52.598067 [DEBUG] switch_core_codec.c:111 sofia/external/0792710365 Original read codec set to PCMA:8
2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:4459 No 2833 in SDP. Liberal DTMF mode adding 101 as telephone-event.
2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:4485 sofia/external/0792710365 Set 2833 dtmf send payload to 101 recv payload to 101
2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:6033 AUDIO RTP [sofia/external/0792710365] 172.20.1.2 port 18352 -> 192.168.1.1 port 4988 codec: 8 ms: 20
2017-08-16 10:21:52.598067 [DEBUG] switch_rtp.c:3802 Starting timer [soft] 160 bytes per 20ms
2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:6332 sofia/external/0792710365 Set 2833 dtmf send payload to 101
2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:6339 sofia/external/0792710365 Set 2833 dtmf receive payload to 101
2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:6362 sofia/external/0792710365 Set rtp dtmf delay to 40
2017-08-16 10:21:52.598067 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/0792710365!
2017-08-16 10:21:52.598067 [DEBUG] switch_channel.c:3468 (sofia/external/0792710365) Callstate Change DOWN -> EARLY
2017-08-16 10:21:52.598067 [INFO] switch_ivr_originate.c:3557 Sending early media
2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:1329 looking for crypto suite [AEAD_AES_256_GCM_8] in [1 AES_CM_128_HMAC_SHA1_32 inline:d3erFouHF3vrM6Ot3BsOjIIukGc7SwHESuakBBFU]
2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:1329 looking for crypto suite [AEAD_AES_128_GCM_8] in [1 AES_CM_128_HMAC_SHA1_32 inline:d3erFouHF3vrM6Ot3BsOjIIukGc7SwHESuakBBFU]
2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:1329 looking for crypto suite [AES_CM_256_HMAC_SHA1_80] in [1 AES_CM_128_HMAC_SHA1_32 inline:d3erFouHF3vrM6Ot3BsOjIIukGc7SwHESuakBBFU]
2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:1329 looking for crypto suite [AES_CM_192_HMAC_SHA1_80] in [1 AES_CM_128_HMAC_SHA1_32 inline:d3erFouHF3vrM6Ot3BsOjIIukGc7SwHESuakBBFU]
2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:1329 looking for crypto suite [AES_CM_128_HMAC_SHA1_80] in [1 AES_CM_128_HMAC_SHA1_32 inline:d3erFouHF3vrM6Ot3BsOjIIukGc7SwHESuakBBFU]
2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:1329 looking for crypto suite [AES_CM_256_HMAC_SHA1_32] in [1 AES_CM_128_HMAC_SHA1_32 inline:d3erFouHF3vrM6Ot3BsOjIIukGc7SwHESuakBBFU]
2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:1329 looking for crypto suite [AES_CM_192_HMAC_SHA1_32] in [1 AES_CM_128_HMAC_SHA1_32 inline:d3erFouHF3vrM6Ot3BsOjIIukGc7SwHESuakBBFU]
2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:1329 looking for crypto suite [AES_CM_128_HMAC_SHA1_32] in [1 AES_CM_128_HMAC_SHA1_32 inline:d3erFouHF3vrM6Ot3BsOjIIukGc7SwHESuakBBFU]
2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:1334 Found suite AES_CM_128_HMAC_SHA1_32
2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:1400 Set Remote Key [1 AES_CM_128_HMAC_SHA1_32 inline:d3erFouHF3vrM6Ot3BsOjIIukGc7SwHESuakBBFU]
2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:1062 Set Local audio crypto Key [1 AES_CM_128_HMAC_SHA1_32 inline:IpqklgUbMZW+Ierls8+fQp5p7+GryytNsO5wehcm]
2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:10:64000:1]
2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:4197 Audio Codec Compare [PCMU:0:8000:10:64000:1] is saved as a near-match
2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:10:64000:1]
2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:10:64000:1]
2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:10:64000:1]
2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:4197 Audio Codec Compare [PCMA:8:8000:10:64000:1] is saved as a near-match
2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:10:64000:1]
2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:10:64000:1]
2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G726-32:99:8000:20:0:1]/[PCMU:0:8000:10:64000:1]
2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G726-32:99:8000:20:0:1]/[PCMA:8:8000:10:64000:1]
2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [AAL2-G726-32:112:8000:20:0:1]/[PCMU:0:8000:10:64000:1]
2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [AAL2-G726-32:112:8000:20:0:1]/[PCMA:8:8000:10:64000:1]
2017-08-16 10:21:52.598067 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:10:64000:1]
2017-08-16 10:21:52.618075 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:10:64000:1]
2017-08-16 10:21:52.618075 [DEBUG] switch_core_media.c:4267 Substituting codec PCMU@20i@8000h@1c
2017-08-16 10:21:52.618075 [DEBUG] switch_core_media.c:2906 Set Codec sofia/internal/1000@172.20.1.2 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
2017-08-16 10:21:52.618075 [DEBUG] switch_core_codec.c:111 sofia/internal/1000@172.20.1.2 Original read codec set to PCMU:0
2017-08-16 10:21:52.618075 [DEBUG] switch_core_media.c:4459 No 2833 in SDP. Liberal DTMF mode adding 101 as telephone-event.
2017-08-16 10:21:52.618075 [DEBUG] switch_core_media.c:4485 sofia/internal/1000@172.20.1.2 Set 2833 dtmf send payload to 101 recv payload to 101
2017-08-16 10:21:52.618075 [DEBUG] switch_core_media.c:6033 AUDIO RTP [sofia/internal/1000@172.20.1.2] 172.20.1.2 port 18182 -> 10.10.30.51 port 60010 codec: 0 ms: 20
2017-08-16 10:21:52.618075 [DEBUG] switch_rtp.c:3802 Starting timer [soft] 160 bytes per 20ms
2017-08-16 10:21:52.618075 [DEBUG] switch_core_media.c:6332 sofia/internal/1000@172.20.1.2 Set 2833 dtmf send payload to 101
2017-08-16 10:21:52.618075 [DEBUG] switch_core_media.c:6339 sofia/internal/1000@172.20.1.2 Set 2833 dtmf receive payload to 101
2017-08-16 10:21:52.618075 [DEBUG] switch_core_media.c:6362 sofia/internal/1000@172.20.1.2 Set rtp dtmf delay to 40
2017-08-16 10:21:52.618075 [INFO] switch_rtp.c:3614 Activating Audio Secure RTP SEND
2017-08-16 10:21:52.618075 [DEBUG] switch_core_sqldb.c:2607 Secure Type: srtp:sdes:AES_CM_128_HMAC_SHA1_32
2017-08-16 10:21:52.618075 [INFO] switch_rtp.c:3592 Activating Audio Secure RTP RECV
2017-08-16 10:21:52.618075 [NOTICE] sofia_media.c:92 Pre-Answer sofia/internal/1000@172.20.1.2!
2017-08-16 10:21:52.618075 [DEBUG] switch_core_sqldb.c:2607 Secure Type: srtp:sdes:AES_CM_128_HMAC_SHA1_32
2017-08-16 10:21:52.618075 [DEBUG] switch_channel.c:3468 (sofia/internal/1000@172.20.1.2) Callstate Change RINGING -> EARLY
2017-08-16 10:21:52.618075 [DEBUG] mod_sofia.c:2330 Ring SDP:
v=0
o=FreeSWITCH 1502860730 1502860731 IN IP4 172.20.1.2
s=FreeSWITCH
c=IN IP4 172.20.1.2
t=0 0
m=audio 18182 RTP/SAVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv
a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:IpqklgUbMZW+Ierls8+fQp5p7+GryytNsO5wehcm
2017-08-16 10:21:52.618075 [DEBUG] switch_ivr_originate.c:3608 Originate Resulted in Success: [sofia/external/0792710365]
send 1261 bytes to udp/[10.10.30.51]:5060 at 10:21:52.629113:
------------------------------------------------------------------------
SIP/2.0 183 Session Progress
Via: SIP/2.0/UDP 10.10.30.51:5060;branch=z9hG4bK-0dzelj3ut8ee;rport=5060
From: "RBS Testing" <sip:1000@172.20.1.2>;tag=qcx12rgq6j
To: <sip:0792710365@172.20.1.2>;tag=4UDF248UKp63g
Call-ID: 3135303238373836373632363431-00jupnh0yqw5
CSeq: 2 INVITE
Contact: <sip:0792710365@172.20.1.2:5060;transport=udp>
User-Agent: FreeSWITCH-mod_sofia/1.6.6+git~20170203T085721Z~df20d5359b~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
Content-Type: application/sdp
Content-Disposition: session
Content-Length: 323
Remote-Party-ID: "Outbound Call" <sip:0792710365@172.20.1.2>;party=calling;privacy=off;screen=no
v=0
o=FreeSWITCH 1502860730 1502860731 IN IP4 172.20.1.2
s=FreeSWITCH
c=IN IP4 172.20.1.2
t=0 0
m=audio 18182 RTP/SAVP 0 101
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=crypto:1 AES_CM_128_HMAC_SHA1_32 inline:IpqklgUbMZW+Ierls8+fQp5p7+GryytNsO5wehcm
m=audio 0 RTP/AVP 19
------------------------------------------------------------------------
2017-08-16 10:21:52.618075 [DEBUG] sofia.c:6760 Channel sofia/internal/1000@172.20.1.2 entering state [early][183]
2017-08-16 10:21:52.618075 [DEBUG] switch_ivr_bridge.c:1591 (sofia/external/0792710365) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
2017-08-16 10:21:52.618075 [DEBUG] switch_core_state_machine.c:473 (sofia/external/0792710365) Running State Change CS_EXCHANGE_MEDIA
2017-08-16 10:21:52.618075 [DEBUG] switch_core_state_machine.c:542 (sofia/external/0792710365) State EXCHANGE_MEDIA
2017-08-16 10:21:52.618075 [DEBUG] mod_sofia.c:613 SOFIA EXCHANGE_MEDIA
2017-08-16 10:21:53.218075 [DEBUG] switch_rtp.c:6654 Correct audio ip/port confirmed.
2017-08-16 10:21:54.798069 [DEBUG] switch_rtp.c:6654 Correct audio ip/port confirmed.
recv 420 bytes from udp/[192.168.1.1]:5060 at 10:21:54.861595:
------------------------------------------------------------------------
SIP/2.0 487 Request Terminated
From: "FreeSWITCH"<sip:+41554405801@192.168.1.1>;tag=9KSaFK40ScZKK
To: <sip:0792710365@192.168.1.1>;tag=1a76240-101a8c0-13c4-5506-2790f-293f0fcb-2790f
Call-ID: 8c630451-fd0f-1235-79b8-000db942b3d0
CSeq: 111103390 INVITE
Via: SIP/2.0/UDP 192.168.1.34:5080;rport=5080;branch=z9hG4bKX3De11ec7N4ra
Supported: replaces,100rel
User-Agent: Epicentro - YAPS ADB Stack
Content-Length: 0
------------------------------------------------------------------------
send 371 bytes to udp/[192.168.1.1]:5060 at 10:21:54.862166:
------------------------------------------------------------------------
ACK sip:0792710365@192.168.1.1 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.34:5080;rport;branch=z9hG4bKX3De11ec7N4ra
Max-Forwards: 69
From: "FreeSWITCH" <sip:+41554405801@192.168.1.1>;tag=9KSaFK40ScZKK
To: <sip:0792710365@192.168.1.1>;tag=1a76240-101a8c0-13c4-5506-2790f-293f0fcb-2790f
Call-ID: 8c630451-fd0f-1235-79b8-000db942b3d0
CSeq: 111103390 ACK
Content-Length: 0
------------------------------------------------------------------------
2017-08-16 10:21:54.858092 [DEBUG] sofia.c:6760 Channel sofia/external/0792710365 entering state [terminated][487]
2017-08-16 10:21:54.858092 [NOTICE] sofia.c:7779 Hangup sofia/external/0792710365 [CS_EXCHANGE_MEDIA] [ORIGINATOR_CANCEL]
2017-08-16 10:21:54.858092 [DEBUG] switch_ivr_bridge.c:705 sofia/external/0792710365 ending bridge by request from read function
2017-08-16 10:21:54.858092 [DEBUG] switch_ivr_bridge.c:778 BRIDGE THREAD DONE [sofia/external/0792710365]
2017-08-16 10:21:54.858092 [DEBUG] switch_core_state_machine.c:542 (sofia/external/0792710365) State EXCHANGE_MEDIA going to sleep
2017-08-16 10:21:54.858092 [DEBUG] switch_core_state_machine.c:473 (sofia/external/0792710365) Running State Change CS_HANGUP
2017-08-16 10:21:54.858092 [DEBUG] switch_core_state_machine.c:739 (sofia/external/0792710365) Callstate Change EARLY -> HANGUP
2017-08-16 10:21:54.858092 [DEBUG] switch_core_state_machine.c:741 (sofia/external/0792710365) State HANGUP
2017-08-16 10:21:54.858092 [DEBUG] mod_sofia.c:431 Channel sofia/external/0792710365 hanging up, cause: ORIGINATOR_CANCEL
2017-08-16 10:21:54.858092 [DEBUG] switch_core_state_machine.c:60 sofia/external/0792710365 Standard HANGUP, cause: ORIGINATOR_CANCEL
2017-08-16 10:21:54.858092 [DEBUG] switch_core_state_machine.c:741 (sofia/external/0792710365) State HANGUP going to sleep
2017-08-16 10:21:54.858092 [DEBUG] switch_core_state_machine.c:508 (sofia/external/0792710365) State Change CS_HANGUP -> CS_REPORTING
2017-08-16 10:21:54.858092 [DEBUG] switch_core_state_machine.c:473 (sofia/external/0792710365) Running State Change CS_REPORTING
2017-08-16 10:21:54.858092 [DEBUG] switch_core_state_machine.c:827 (sofia/external/0792710365) State REPORTING
2017-08-16 10:21:54.858092 [DEBUG] switch_core_state_machine.c:104 sofia/external/0792710365 Standard REPORTING, cause: ORIGINATOR_CANCEL
2017-08-16 10:21:54.858092 [DEBUG] switch_core_state_machine.c:827 (sofia/external/0792710365) State REPORTING going to sleep
2017-08-16 10:21:54.858092 [DEBUG] switch_core_state_machine.c:499 (sofia/external/0792710365) State Change CS_REPORTING -> CS_DESTROY
2017-08-16 10:21:54.858092 [DEBUG] mod_uci_disco.cpp:516 (catch_hangup) Caught hangup
2017-08-16 10:21:54.858092 [DEBUG] switch_core_session.c:1646 Session 1961 (sofia/external/0792710365) Locked, Waiting on external entities
2017-08-16 10:21:54.858092 [DEBUG] mod_uci_disco.cpp:526 (catch_hangup) Hang-up from RBS Testing to 0792710365 found!
2017-08-16 10:21:54.858092 [DEBUG] mod_uci_sip.cpp:451 (catch_hangup) Caught hangup
2017-08-16 10:21:54.858092 [DEBUG] mod_uci_sip.cpp:461 (catch_hangup) Hang-up from 1000 to 0792710365 found!
2017-08-16 10:21:54.878116 [DEBUG] switch_ivr_bridge.c:699 sofia/external/0792710365 ending bridge by request from write function
2017-08-16 10:21:54.878116 [DEBUG] switch_ivr_bridge.c:778 BRIDGE THREAD DONE [sofia/internal/1000@172.20.1.2]
2017-08-16 10:21:54.878116 [NOTICE] switch_core_session.c:1664 Session 1961 (sofia/external/0792710365) Ended
2017-08-16 10:21:54.878116 [NOTICE] switch_core_session.c:1668 Close Channel sofia/external/0792710365 [CS_DESTROY]
2017-08-16 10:21:54.878116 [NOTICE] switch_core_state_machine.c:315 sofia/internal/1000@172.20.1.2 has executed the last dialplan instruction, hanging up.
2017-08-16 10:21:54.878116 [NOTICE] switch_core_state_machine.c:317 Hangup sofia/internal/1000@172.20.1.2 [CS_EXECUTE] [NORMAL_CLEARING]
2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:630 (sofia/external/0792710365) Running State Change CS_DESTROY
2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:640 (sofia/external/0792710365) State DESTROY
2017-08-16 10:21:54.878116 [DEBUG] mod_sofia.c:341 sofia/external/0792710365 SOFIA DESTROY
2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:111 sofia/external/0792710365 Standard DESTROY
2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:640 (sofia/external/0792710365) State DESTROY going to sleep
2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:539 (sofia/internal/1000@172.20.1.2) State EXECUTE going to sleep
2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1000@172.20.1.2) Running State Change CS_HANGUP
2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/1000@172.20.1.2) Callstate Change EARLY -> HANGUP
2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1000@172.20.1.2) State HANGUP
2017-08-16 10:21:54.878116 [DEBUG] mod_sofia.c:425 sofia/internal/1000@172.20.1.2 Overriding SIP cause 480 with 487 from the other leg
2017-08-16 10:21:54.878116 [DEBUG] mod_sofia.c:431 Channel sofia/internal/1000@172.20.1.2 hanging up, cause: NORMAL_CLEARING
2017-08-16 10:21:54.878116 [DEBUG] mod_sofia.c:568 Responding to INVITE with: 487
2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1000@172.20.1.2 Standard HANGUP, cause: NORMAL_CLEARING
2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1000@172.20.1.2) State HANGUP going to sleep
2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:508 (sofia/internal/1000@172.20.1.2) State Change CS_HANGUP -> CS_REPORTING
send 860 bytes to udp/[10.10.30.51]:5060 at 10:21:54.891735:
------------------------------------------------------------------------
SIP/2.0 487 Request Terminated
Via: SIP/2.0/UDP 10.10.30.51:5060;branch=z9hG4bK-0dzelj3ut8ee;rport=5060
Max-Forwards: 70
From: "RBS Testing" <sip:1000@172.20.1.2>;tag=qcx12rgq6j
To: <sip:0792710365@172.20.1.2>;tag=4UDF248UKp63g
Call-ID: 3135303238373836373632363431-00jupnh0yqw5
CSeq: 2 INVITE
User-Agent: FreeSWITCH-mod_sofia/1.6.6+git~20170203T085721Z~df20d5359b~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
Reason: Q.850;cause=16;text="NORMAL_CLEARING"
Content-Length: 0
Remote-Party-ID: "Outbound Call" <sip:0792710365@172.20.1.2>;party=calling;privacy=off;screen=no
------------------------------------------------------------------------
2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1000@172.20.1.2) Running State Change CS_REPORTING
2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:827 (sofia/internal/1000@172.20.1.2) State REPORTING
2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:104 sofia/internal/1000@172.20.1.2 Standard REPORTING, cause: NORMAL_CLEARING
2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:827 (sofia/internal/1000@172.20.1.2) State REPORTING going to sleep
2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:499 (sofia/internal/1000@172.20.1.2) State Change CS_REPORTING -> CS_DESTROY
2017-08-16 10:21:54.878116 [DEBUG] mod_uci_disco.cpp:516 (catch_hangup) Caught hangup
2017-08-16 10:21:54.878116 [DEBUG] switch_core_session.c:1646 Session 1960 (sofia/internal/1000@172.20.1.2) Locked, Waiting on external entities
2017-08-16 10:21:54.878116 [DEBUG] mod_uci_disco.cpp:526 (catch_hangup) Hang-up from RBS Testing to 0792710365 found!
2017-08-16 10:21:54.878116 [DEBUG] mod_uci_sip.cpp:451 (catch_hangup) Caught hangup
2017-08-16 10:21:54.878116 [NOTICE] switch_core_session.c:1664 Session 1960 (sofia/internal/1000@172.20.1.2) Ended
2017-08-16 10:21:54.878116 [NOTICE] switch_core_session.c:1668 Close Channel sofia/internal/1000@172.20.1.2 [CS_DESTROY]
2017-08-16 10:21:54.878116 [DEBUG] mod_uci_sip.cpp:461 (catch_hangup) Hang-up from 1000 to 0792710365 found!
2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:630 (sofia/internal/1000@172.20.1.2) Running State Change CS_DESTROY
2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1000@172.20.1.2) State DESTROY
2017-08-16 10:21:54.878116 [DEBUG] mod_sofia.c:341 sofia/internal/1000@172.20.1.2 SOFIA DESTROY
2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:111 sofia/internal/1000@172.20.1.2 Standard DESTROY
2017-08-16 10:21:54.878116 [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1000@172.20.1.2) State DESTROY going to sleep
recv 413 bytes from udp/[10.10.30.51]:5060 at 10:21:54.923213:
------------------------------------------------------------------------
ACK sip:0792710365@172.20.1.2 SIP/2.0
Via: SIP/2.0/UDP 10.10.30.51:5060;branch=z9hG4bK-0dzelj3ut8ee;rport
From: "RBS Testing" <sip:1000@172.20.1.2>;tag=qcx12rgq6j
To: <sip:0792710365@172.20.1.2>;tag=4UDF248UKp63g
Call-ID: 3135303238373836373632363431-00jupnh0yqw5
CSeq: 2 ACK
Max-Forwards: 70
User-Agent: snomD375/8.9.3.15
Contact: <sip:1000@10.10.30.51:5060;line=8dg3t92k>;reg-id=1
Content-Length: 0
------------------------------------------------------------------------
recv 653 bytes from udp/[172.20.1.10]:5060 at 10:21:56.280080:
------------------------------------------------------------------------
REGISTER sip:172.20.1.2:5060 SIP/2.0
Via: SIP/2.0/UDP 172.20.1.10:5060;branch=z9hG4bK5efb5e8d08a4dfff;rport
Contact: <sip:radio1@172.20.1.10:5060>;expires=5;reg-id=123
Max-Forwards: 70
Authorization: Digest username="radio1", realm="172.20.1.2", nonce="217b51f8-7916-11e7-a5b7-f77b865a2304", uri="sip:172.20.1.2:5060", response="0d1ebe2c429cac82584a95c870b3f8df", cnonce="271e41a00d847bea", qop=auth, nc=00037694
To: <sip:radio1@172.20.1.2:5060>
From: <sip:radio1@172.20.1.2:5060>;tag=39215c725235cf15
Call-ID: 29f9e1db035f537e
CSeq: 422203 REGISTER
User-Agent: Ervocom SIP UA 1.2.3 (?/?)
Supported: gruu, outbound, path
Content-Length: 0
------------------------------------------------------------------------
send 582 bytes to udp/[172.20.1.10]:5060 at 10:21:56.290110:
------------------------------------------------------------------------
SIP/2.0 200 OK
Via: SIP/2.0/UDP 172.20.1.10:5060;branch=z9hG4bK5efb5e8d08a4dfff;rport=5060
From: <sip:radio1@172.20.1.2:5060>;tag=39215c725235cf15
To: <sip:radio1@172.20.1.2:5060>;tag=7pSS7NU6aH9UK
Call-ID: 29f9e1db035f537e
CSeq: 422203 REGISTER
Contact: <sip:radio1@172.20.1.10:5060>;expires=5
Date: Wed, 16 Aug 2017 10:21:56 GMT
User-Agent: FreeSWITCH-mod_sofia/1.6.6+git~20170203T085721Z~df20d5359b~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
Supported: timer, path, replaces
Content-Length: 0
------------------------------------------------------------------------
freeswitch@internal>
send 861 bytes to udp/[192.168.1.1]:5060 at 10:21:58.084161:
------------------------------------------------------------------------
REGISTER sip:192.168.1.1;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.1.34:5080;rport;branch=z9hG4bKyc762vZF4ytBp
Max-Forwards: 70
From: <sip:+41554405801@192.168.1.1>;tag=S2U4S0UQ40HjQ
To: <sip:+41554405801@192.168.1.1>
Call-ID: cbd6523e-7915-11e7-a55a-f77b865a2304
CSeq: 111027789 REGISTER
Contact: <sip:gw+swisscom@192.168.1.34:5080;transport=udp;gw=swisscom>
Expires: 60
User-Agent: FreeSWITCH-mod_sofia/1.6.6+git~20170203T085721Z~df20d5359b~64bit
Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
Supported: timer, path, replaces
Authorization: Digest username="+41554405801", realm="epicentro.localrealm", nonce="57d190b6b154", cnonce="oze44/O4EjW4eQANuUKz0A", algorithm=MD5, uri="sip:192.168.1.1;transport=udp", response="30092fd76126405eef9c08114a89e466", qop=auth, nc=000085cd
Content-Length: 0
------------------------------------------------------------------------
recv 516 bytes from udp/[192.168.1.1]:5060 at 10:21:58.087011:
------------------------------------------------------------------------
SIP/2.0 200 OK
From: <sip:+41554405801@192.168.1.1>;tag=S2U4S0UQ40HjQ
To: <sip:+41554405801@192.168.1.1>;tag=1a75850-101a8c0-13c4-5506-27914-17e340a7-27914
Call-ID: cbd6523e-7915-11e7-a55a-f77b865a2304
CSeq: 111027789 REGISTER
Date: Wed, 16 Aug 2017 10:18:10 GMT
User-Agent: Epicentro - YAPS ADB Stack
Supported: replaces,100rel
Contact: <sip:gw+swisscom@192.168.1.34:5080;transport=udp;gw=swisscom>;expires=60
Via: SIP/2.0/UDP 192.168.1.34:5080;rport=5080;branch=z9hG4bKyc762vZF4ytBp
Content-Length: 0
------------------------------------------------------------------------