From Ramon Telnyx, 7 Years ago, written in FreeSWITCH.
- view diff
Embed
  1. recv 1232 bytes from udp/[10.15.13.13]:5060 at 22:02:43.312909:
  2.    ------------------------------------------------------------------------
  3.    INVITE sip:3126225728@10.15.13.7:5070;transport=UDP SIP/2.0
  4.    Record-Route: <sip:10.15.13.13;r2=on;lr;ftag=d88d4646>
  5.    Record-Route: <sip:192.76.120.45;r2=on;lr;ftag=d88d4646>
  6.    Via: SIP/2.0/UDP 10.15.13.13;branch=z9hG4bK0de.8ccb2f858976fc7bf495773e29244c3a.0
  7.    Via: SIP/2.0/UDP 192.76.120.192:21060;rport=62080;received=50.247.144.29;branch=z9hG4bK-d8754z-4b8cd507b20b7e9f-1---d8754z-
  8.    Max-Forwards: 69
  9.    Contact: <sip:testramon3@192.76.120.192:21060;alias=50.247.144.29~62080~1;transport=UDP>
  10.    To: <sip:3126225728@siplab5.telnyx.com;transport=UDP>
  11.    From: <sip:testramon3@siplab5.telnyx.com;transport=UDP>;tag=d88d4646
  12.    Call-ID: ZjdkMTE3ZTM0ODkwNzRmMjdlYzU4M2UxNDMwNzhjODU.
  13.    CSeq: 1 INVITE
  14.   Allow: INVITE, ACK, CANCEL, BYE, NOTIFY, REFER, MESSAGE, OPTIONS, INFO, SUBSCRIBE
  15.    Content-Type: application/sdp
  16.    Supported: replaces, norefersub, extended-refer, timer, X-cisco-serviceuri
  17.    User-Agent: Z 3.3.25608 r25552
  18.    Allow-Events: presence, kpml
  19.    Content-Length: 244
  20.    X-AUTH-IP: 50.247.144.29
  21.    
  22.    v=0
  23.    o=Z 0 0 IN IP4 192.76.120.192
  24.    s=Z
  25.    c=IN IP4 192.76.120.192
  26.    t=0 0
  27.    m=audio 25685 RTP/AVP 3 110 8 0 98 101
  28.    a=rtpmap:110 speex/8000
  29.    a=rtpmap:98 iLBC/8000
  30.    a=fmtp:98 mode=20
  31.    a=rtpmap:101 telephone-event/8000
  32.    a=fmtp:101 0-15
  33.    a=sendrecv
  34.    ------------------------------------------------------------------------
  35. send 559 bytes to udp/[10.15.13.13]:5060 at 22:02:43.313200:
  36.    ------------------------------------------------------------------------
  37.    SIP/2.0 100 Trying
  38.    Via: SIP/2.0/UDP 10.15.13.13;branch=z9hG4bK0de.8ccb2f858976fc7bf495773e29244c3a.0
  39.    Via: SIP/2.0/UDP 192.76.120.192:21060;rport=62080;received=50.247.144.29;branch=z9hG4bK-d8754z-4b8cd507b20b7e9f-1---d8754z-
  40.    Record-Route: <sip:10.15.13.13;r2=on;lr;ftag=d88d4646>
  41.    Record-Route: <sip:192.76.120.45;r2=on;lr;ftag=d88d4646>
  42.    From: <sip:testramon3@siplab5.telnyx.com;transport=UDP>;tag=d88d4646
  43.    To: <sip:3126225728@siplab5.telnyx.com;transport=UDP>
  44.    Call-ID: ZjdkMTE3ZTM0ODkwNzRmMjdlYzU4M2UxNDMwNzhjODU.
  45.    CSeq: 1 INVITE
  46.    Content-Length: 0
  47.    
  48.    ------------------------------------------------------------------------
  49. 2017-09-25 22:02:43.312818 [NOTICE] switch_channel.c:1104 New Channel sofia/inbound/testramon3@siplab5.telnyx.com [873e7e53-d676-4628-b19f-d1f02c4dd6d7]
  50. 2017-09-25 22:02:43.312818 [DEBUG] switch_core_state_machine.c:584 (sofia/inbound/testramon3@siplab5.telnyx.com) Running State Change CS_NEW (Cur 1 Tot 6)
  51. 2017-09-25 22:02:43.312818 [DEBUG] sofia.c:9837 sofia/inbound/testramon3@siplab5.telnyx.com receiving invite from 10.15.13.13:5060 version: 1.6.18 git 3da0ad0 2017-06-28 17:17:04Z 64bit
  52. 2017-09-25 22:02:43.312818 [DEBUG] sofia.c:7048 Channel sofia/inbound/testramon3@siplab5.telnyx.com entering state [received][100]
  53. 2017-09-25 22:02:43.312818 [DEBUG] sofia.c:7058 Remote SDP:
  54. v=0
  55. o=Z 0 0 IN IP4 192.76.120.192
  56. s=Z
  57. c=IN IP4 192.76.120.192
  58. t=0 0
  59. m=audio 25685 RTP/AVP 3 110 8 0 98 101
  60. a=rtpmap:110 speex/8000
  61. a=rtpmap:98 iLBC/8000
  62. a=fmtp:98 mode=20
  63. a=rtpmap:101 telephone-event/8000
  64. a=fmtp:101 0-15
  65.  
  66. 2017-09-25 22:02:43.312818 [DEBUG] sofia.c:7450 (sofia/inbound/testramon3@siplab5.telnyx.com) State Change CS_NEW -> CS_INIT
  67. 2017-09-25 22:02:43.312818 [DEBUG] switch_core_state_machine.c:603 (sofia/inbound/testramon3@siplab5.telnyx.com) State NEW
  68. 2017-09-25 22:02:43.312818 [DEBUG] switch_core_state_machine.c:584 (sofia/inbound/testramon3@siplab5.telnyx.com) Running State Change CS_INIT (Cur 1 Tot 6)
  69. 2017-09-25 22:02:43.312818 [DEBUG] switch_core_state_machine.c:627 (sofia/inbound/testramon3@siplab5.telnyx.com) State INIT
  70. 2017-09-25 22:02:43.312818 [DEBUG] mod_sofia.c:90 sofia/inbound/testramon3@siplab5.telnyx.com SOFIA INIT
  71. 2017-09-25 22:02:43.312818 [DEBUG] switch_core_state_machine.c:40 sofia/inbound/testramon3@siplab5.telnyx.com Standard INIT
  72. 2017-09-25 22:02:43.312818 [DEBUG] switch_core_state_machine.c:48 (sofia/inbound/testramon3@siplab5.telnyx.com) State Change CS_INIT -> CS_ROUTING
  73. 2017-09-25 22:02:43.312818 [DEBUG] switch_core_state_machine.c:627 (sofia/inbound/testramon3@siplab5.telnyx.com) State INIT going to sleep
  74. 2017-09-25 22:02:43.312818 [DEBUG] switch_core_state_machine.c:584 (sofia/inbound/testramon3@siplab5.telnyx.com) Running State Change CS_ROUTING (Cur 1 Tot 6)
  75. 2017-09-25 22:02:43.312818 [DEBUG] switch_channel.c:2249 (sofia/inbound/testramon3@siplab5.telnyx.com) Callstate Change DOWN -> RINGING
  76. 2017-09-25 22:02:43.312818 [DEBUG] switch_core_state_machine.c:643 (sofia/inbound/testramon3@siplab5.telnyx.com) State ROUTING
  77. 2017-09-25 22:02:43.312818 [DEBUG] mod_sofia.c:143 sofia/inbound/testramon3@siplab5.telnyx.com SOFIA ROUTING
  78. 2017-09-25 22:02:43.312818 [DEBUG] switch_core_state_machine.c:236 sofia/inbound/testramon3@siplab5.telnyx.com Standard ROUTING
  79. 2017-09-25 22:02:43.312818 [INFO] mod_dialplan_xml.c:637 Processing testramon3 <testramon3>->3126225728 in context public
  80. Dialplan: sofia/inbound/testramon3@siplab5.telnyx.com parsing [public->UNKNOWN] continue=false
  81. Dialplan: sofia/inbound/testramon3@siplab5.telnyx.com Absolute Condition [UNKNOWN]
  82. Dialplan: sofia/inbound/testramon3@siplab5.telnyx.com Action set(telnyx_uuid=7a9b5111-a23a-11e7-8b5e-02420a0f0d25)
  83. Dialplan: sofia/inbound/testramon3@siplab5.telnyx.com Action export(telnyx_session_uuid=7a9ce357-a23a-11e7-8747-02420a0f0d25)
  84. Dialplan: sofia/inbound/testramon3@siplab5.telnyx.com Action export(e164_cld=+13126225728)
  85. Dialplan: sofia/inbound/testramon3@siplab5.telnyx.com Action export(e164_cli=+17739850363)
  86. Dialplan: sofia/inbound/testramon3@siplab5.telnyx.com Action export(counterparty_a=telnyx)
  87. Dialplan: sofia/inbound/testramon3@siplab5.telnyx.com Action export(counterparty_b=sippy)
  88. Dialplan: sofia/inbound/testramon3@siplab5.telnyx.com Action export(on_net=false)
  89. Dialplan: sofia/inbound/testramon3@siplab5.telnyx.com Action set(connection_id=268876107514644154)
  90. Dialplan: sofia/inbound/testramon3@siplab5.telnyx.com Action set(user_id=174)
  91. Dialplan: sofia/inbound/testramon3@siplab5.telnyx.com Action set(user_type=public-api)
  92. Dialplan: sofia/inbound/testramon3@siplab5.telnyx.com Action set(tags=)
  93. Dialplan: sofia/inbound/testramon3@siplab5.telnyx.com Action set(routing_group=US-DCA)
  94. Dialplan: sofia/inbound/testramon3@siplab5.telnyx.com Action set(outbound_profile_id=268876471479568060)
  95. Dialplan: sofia/inbound/testramon3@siplab5.telnyx.com Action set(usage_payment_method=flat-rate)
  96. Dialplan: sofia/inbound/testramon3@siplab5.telnyx.com Action export(user_data={&quot;termination&quot;: {&quot;user_id&quot;: &quot;174&quot;, &quot;connection_id&quot;: &quot;268876107514644154&quot;, &quot;outbound_profile_id&quot;: &quot;268876471479568060&quot;, &quot;user_type&quot;: &quot;public-api&quot;, &quot;tn&quot;: &quot;+13126225728&quot;, &quot;call_type&quot;: &quot;conversational&quot;}})
  97. Dialplan: sofia/inbound/testramon3@siplab5.telnyx.com Action export(planned=true)
  98. Dialplan: sofia/inbound/testramon3@siplab5.telnyx.com Absolute Condition [UNKNOWN]
  99. Dialplan: sofia/inbound/testramon3@siplab5.telnyx.com Action unset(sip_h_X-FS-Support)
  100. Dialplan: sofia/inbound/testramon3@siplab5.telnyx.com Action set(call_timeout=600)
  101. Dialplan: sofia/inbound/testramon3@siplab5.telnyx.com Action set(continue_on_fail=GATEWAY_DOWN,41)
  102. Dialplan: sofia/inbound/testramon3@siplab5.telnyx.com Action set(hangup_after_bridge=true)
  103. Dialplan: sofia/inbound/testramon3@siplab5.telnyx.com Action set(rtp_secure_media_inbound=true)
  104. Dialplan: sofia/inbound/testramon3@siplab5.telnyx.com Action set(refuse_t38=true)
  105. Dialplan: sofia/inbound/testramon3@siplab5.telnyx.com Action export(sip_cid_type=pid)
  106. Dialplan: sofia/inbound/testramon3@siplab5.telnyx.com Action bridge({local_var_clobber=true,sip_execute_on_image='t38_gateway self nocng',fax_enable_t38=true,sip_invite_domain=sip.telnyx.com,origination_caller_id_number=+17739850363}[telnyx_uuid=7ad0abfa-a23a-11e7-8596-02420a0f0d25]sofia/gateway/184.172.33.74/13126225728)
  107. Dialplan: sofia/inbound/testramon3@siplab5.telnyx.com Action bridge({local_var_clobber=true,sip_execute_on_image='t38_gateway self nocng',fax_enable_t38=true,sip_invite_domain=sip.telnyx.com,origination_caller_id_number=+17739850363}[telnyx_uuid=7ad0ae21-a23a-11e7-8b51-02420a0f0d25]sofia/gateway/50.97.255.73/13126225728)
  108. 2017-09-25 22:02:43.312818 [DEBUG] switch_core_state_machine.c:286 (sofia/inbound/testramon3@siplab5.telnyx.com) State Change CS_ROUTING -> CS_EXECUTE
  109. 2017-09-25 22:02:43.312818 [DEBUG] switch_core_state_machine.c:643 (sofia/inbound/testramon3@siplab5.telnyx.com) State ROUTING going to sleep
  110. 2017-09-25 22:02:43.312818 [DEBUG] switch_core_state_machine.c:584 (sofia/inbound/testramon3@siplab5.telnyx.com) Running State Change CS_EXECUTE (Cur 1 Tot 6)
  111. 2017-09-25 22:02:43.312818 [DEBUG] switch_core_state_machine.c:650 (sofia/inbound/testramon3@siplab5.telnyx.com) State EXECUTE
  112. 2017-09-25 22:02:43.312818 [DEBUG] mod_sofia.c:198 sofia/inbound/testramon3@siplab5.telnyx.com SOFIA EXECUTE
  113. 2017-09-25 22:02:43.312818 [DEBUG] switch_core_state_machine.c:328 sofia/inbound/testramon3@siplab5.telnyx.com Standard EXECUTE
  114. EXECUTE sofia/inbound/testramon3@siplab5.telnyx.com set(telnyx_uuid=7a9b5111-a23a-11e7-8b5e-02420a0f0d25)
  115. 2017-09-25 22:02:43.312818 [DEBUG] mod_dptools.c:1530 SET sofia/inbound/testramon3@siplab5.telnyx.com [telnyx_uuid]=[7a9b5111-a23a-11e7-8b5e-02420a0f0d25]
  116. EXECUTE sofia/inbound/testramon3@siplab5.telnyx.com export(telnyx_session_uuid=7a9ce357-a23a-11e7-8747-02420a0f0d25)
  117. 2017-09-25 22:02:43.312818 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [telnyx_session_uuid]=[7a9ce357-a23a-11e7-8747-02420a0f0d25]
  118. EXECUTE sofia/inbound/testramon3@siplab5.telnyx.com export(e164_cld=+13126225728)
  119. 2017-09-25 22:02:43.312818 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [e164_cld]=[+13126225728]
  120. EXECUTE sofia/inbound/testramon3@siplab5.telnyx.com export(e164_cli=+17739850363)
  121. 2017-09-25 22:02:43.312818 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [e164_cli]=[+17739850363]
  122. EXECUTE sofia/inbound/testramon3@siplab5.telnyx.com export(counterparty_a=telnyx)
  123. 2017-09-25 22:02:43.312818 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [counterparty_a]=[telnyx]
  124. EXECUTE sofia/inbound/testramon3@siplab5.telnyx.com export(counterparty_b=sippy)
  125. 2017-09-25 22:02:43.312818 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [counterparty_b]=[sippy]
  126. EXECUTE sofia/inbound/testramon3@siplab5.telnyx.com export(on_net=false)
  127. 2017-09-25 22:02:43.312818 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [on_net]=[false]
  128. EXECUTE sofia/inbound/testramon3@siplab5.telnyx.com set(connection_id=268876107514644154)
  129. 2017-09-25 22:02:43.312818 [DEBUG] mod_dptools.c:1530 SET sofia/inbound/testramon3@siplab5.telnyx.com [connection_id]=[268876107514644154]
  130. EXECUTE sofia/inbound/testramon3@siplab5.telnyx.com set(user_id=174)
  131. 2017-09-25 22:02:43.312818 [DEBUG] mod_dptools.c:1530 SET sofia/inbound/testramon3@siplab5.telnyx.com [user_id]=[174]
  132. EXECUTE sofia/inbound/testramon3@siplab5.telnyx.com set(user_type=public-api)
  133. 2017-09-25 22:02:43.312818 [DEBUG] mod_dptools.c:1530 SET sofia/inbound/testramon3@siplab5.telnyx.com [user_type]=[public-api]
  134. EXECUTE sofia/inbound/testramon3@siplab5.telnyx.com set(tags=)
  135. 2017-09-25 22:02:43.312818 [DEBUG] mod_dptools.c:1530 SET sofia/inbound/testramon3@siplab5.telnyx.com [tags]=[UNDEF]
  136. EXECUTE sofia/inbound/testramon3@siplab5.telnyx.com set(routing_group=US-DCA)
  137. 2017-09-25 22:02:43.312818 [DEBUG] mod_dptools.c:1530 SET sofia/inbound/testramon3@siplab5.telnyx.com [routing_group]=[US-DCA]
  138. EXECUTE sofia/inbound/testramon3@siplab5.telnyx.com set(outbound_profile_id=268876471479568060)
  139. 2017-09-25 22:02:43.312818 [DEBUG] mod_dptools.c:1530 SET sofia/inbound/testramon3@siplab5.telnyx.com [outbound_profile_id]=[268876471479568060]
  140. EXECUTE sofia/inbound/testramon3@siplab5.telnyx.com set(usage_payment_method=flat-rate)
  141. 2017-09-25 22:02:43.312818 [DEBUG] mod_dptools.c:1530 SET sofia/inbound/testramon3@siplab5.telnyx.com [usage_payment_method]=[flat-rate]
  142. EXECUTE sofia/inbound/testramon3@siplab5.telnyx.com export(user_data={&quot;termination&quot;: {&quot;user_id&quot;: &quot;174&quot;, &quot;connection_id&quot;: &quot;268876107514644154&quot;, &quot;outbound_profile_id&quot;: &quot;268876471479568060&quot;, &quot;user_type&quot;: &quot;public-api&quot;, &quot;tn&quot;: &quot;+13126225728&quot;, &quot;call_type&quot;: &quot;conversational&quot;}})
  143. 2017-09-25 22:02:43.312818 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [user_data]=[{&quot;termination&quot;: {&quot;user_id&quot;: &quot;174&quot;, &quot;connection_id&quot;: &quot;268876107514644154&quot;, &quot;outbound_profile_id&quot;: &quot;268876471479568060&quot;, &quot;user_type&quot;: &quot;public-api&quot;, &quot;tn&quot;: &quot;+13126225728&quot;, &quot;call_type&quot;: &quot;conversational&quot;}}]
  144. EXECUTE sofia/inbound/testramon3@siplab5.telnyx.com export(planned=true)
  145. 2017-09-25 22:02:43.312818 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [planned]=[true]
  146. EXECUTE sofia/inbound/testramon3@siplab5.telnyx.com unset(sip_h_X-FS-Support)
  147. 2017-09-25 22:02:43.312818 [DEBUG] mod_dptools.c:1675 UNSET [sip_h_X-FS-Support]
  148. EXECUTE sofia/inbound/testramon3@siplab5.telnyx.com set(call_timeout=600)
  149. 2017-09-25 22:02:43.312818 [DEBUG] mod_dptools.c:1530 SET sofia/inbound/testramon3@siplab5.telnyx.com [call_timeout]=[600]
  150. EXECUTE sofia/inbound/testramon3@siplab5.telnyx.com set(continue_on_fail=GATEWAY_DOWN,41)
  151. 2017-09-25 22:02:43.312818 [DEBUG] mod_dptools.c:1530 SET sofia/inbound/testramon3@siplab5.telnyx.com [continue_on_fail]=[GATEWAY_DOWN,41]
  152. EXECUTE sofia/inbound/testramon3@siplab5.telnyx.com set(hangup_after_bridge=true)
  153. 2017-09-25 22:02:43.312818 [DEBUG] mod_dptools.c:1530 SET sofia/inbound/testramon3@siplab5.telnyx.com [hangup_after_bridge]=[true]
  154. EXECUTE sofia/inbound/testramon3@siplab5.telnyx.com set(rtp_secure_media_inbound=true)
  155. 2017-09-25 22:02:43.312818 [DEBUG] mod_dptools.c:1530 SET sofia/inbound/testramon3@siplab5.telnyx.com [rtp_secure_media_inbound]=[true]
  156. EXECUTE sofia/inbound/testramon3@siplab5.telnyx.com set(refuse_t38=true)
  157. 2017-09-25 22:02:43.312818 [DEBUG] mod_dptools.c:1530 SET sofia/inbound/testramon3@siplab5.telnyx.com [refuse_t38]=[true]
  158. EXECUTE sofia/inbound/testramon3@siplab5.telnyx.com export(sip_cid_type=pid)
  159. 2017-09-25 22:02:43.312818 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [sip_cid_type]=[pid]
  160. EXECUTE sofia/inbound/testramon3@siplab5.telnyx.com bridge({local_var_clobber=true,sip_execute_on_image='t38_gateway self nocng',fax_enable_t38=true,sip_invite_domain=sip.telnyx.com,origination_caller_id_number=+17739850363}[telnyx_uuid=7ad0abfa-a23a-11e7-8596-02420a0f0d25]sofia/gateway/184.172.33.74/13126225728)
  161. 2017-09-25 22:02:43.312818 [DEBUG] switch_channel.c:1250 sofia/inbound/testramon3@siplab5.telnyx.com EXPORTING[export_vars] [telnyx_session_uuid]=[7a9ce357-a23a-11e7-8747-02420a0f0d25] to event
  162. 2017-09-25 22:02:43.312818 [DEBUG] switch_channel.c:1250 sofia/inbound/testramon3@siplab5.telnyx.com EXPORTING[export_vars] [e164_cld]=[+13126225728] to event
  163. 2017-09-25 22:02:43.312818 [DEBUG] switch_channel.c:1250 sofia/inbound/testramon3@siplab5.telnyx.com EXPORTING[export_vars] [e164_cli]=[+17739850363] to event
  164. 2017-09-25 22:02:43.312818 [DEBUG] switch_channel.c:1250 sofia/inbound/testramon3@siplab5.telnyx.com EXPORTING[export_vars] [counterparty_a]=[telnyx] to event
  165. 2017-09-25 22:02:43.312818 [DEBUG] switch_channel.c:1250 sofia/inbound/testramon3@siplab5.telnyx.com EXPORTING[export_vars] [counterparty_b]=[sippy] to event
  166. 2017-09-25 22:02:43.312818 [DEBUG] switch_channel.c:1250 sofia/inbound/testramon3@siplab5.telnyx.com EXPORTING[export_vars] [on_net]=[false] to event
  167. 2017-09-25 22:02:43.312818 [DEBUG] switch_channel.c:1250 sofia/inbound/testramon3@siplab5.telnyx.com EXPORTING[export_vars] [user_data]=[{&quot;termination&quot;: {&quot;user_id&quot;: &quot;174&quot;, &quot;connection_id&quot;: &quot;268876107514644154&quot;, &quot;outbound_profile_id&quot;: &quot;268876471479568060&quot;, &quot;user_type&quot;: &quot;public-api&quot;, &quot;tn&quot;: &quot;+13126225728&quot;, &quot;call_type&quot;: &quot;conversational&quot;}}] to event
  168. 2017-09-25 22:02:43.312818 [DEBUG] switch_channel.c:1250 sofia/inbound/testramon3@siplab5.telnyx.com EXPORTING[export_vars] [planned]=[true] to event
  169. 2017-09-25 22:02:43.312818 [DEBUG] switch_channel.c:1250 sofia/inbound/testramon3@siplab5.telnyx.com EXPORTING[export_vars] [sip_cid_type]=[pid] to event
  170. 2017-09-25 22:02:43.312818 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  171. 2017-09-25 22:02:43.312818 [DEBUG] switch_ivr_originate.c:2669 Parsing session specific variables
  172. 2017-09-25 22:02:43.312818 [NOTICE] switch_channel.c:1104 New Channel sofia/pstn/13126225728 [27887a5e-b7dc-4358-9f8f-480475051756]
  173. 2017-09-25 22:02:43.312818 [DEBUG] mod_sofia.c:4819 (sofia/pstn/13126225728) State Change CS_NEW -> CS_INIT
  174. 2017-09-25 22:02:43.312818 [DEBUG] switch_core_state_machine.c:584 (sofia/pstn/13126225728) Running State Change CS_INIT (Cur 2 Tot 7)
  175. 2017-09-25 22:02:43.312818 [DEBUG] switch_core_state_machine.c:627 (sofia/pstn/13126225728) State INIT
  176. 2017-09-25 22:02:43.312818 [DEBUG] mod_sofia.c:90 sofia/pstn/13126225728 SOFIA INIT
  177. 2017-09-25 22:02:43.312818 [DEBUG] sofia_glue.c:1295 sofia/pstn/13126225728 sending invite version: 1.6.18 git 3da0ad0 2017-06-28 17:17:04Z 64bit
  178. Local SDP:
  179. v=0
  180. o=Telnyx 1506359647 1506359648 IN IP4 64.16.248.234
  181. s=Telnyx
  182. c=IN IP4 64.16.248.234
  183. t=0 0
  184. m=audio 17316 RTP/AVP 8 0 18 9 101
  185. a=rtpmap:8 PCMA/8000
  186. a=rtpmap:0 PCMU/8000
  187. a=rtpmap:18 G729/8000
  188. a=rtpmap:9 G722/8000
  189. a=rtpmap:101 telephone-event/8000
  190. a=fmtp:101 0-16
  191. a=fmtp:18 annexb=no
  192. a=ptime:20
  193. a=sendrecv
  194.  
  195. 2017-09-25 22:02:43.312818 [DEBUG] switch_core_state_machine.c:40 sofia/pstn/13126225728 Standard INIT
  196. 2017-09-25 22:02:43.312818 [DEBUG] switch_core_state_machine.c:48 (sofia/pstn/13126225728) State Change CS_INIT -> CS_ROUTING
  197. 2017-09-25 22:02:43.312818 [DEBUG] switch_core_state_machine.c:627 (sofia/pstn/13126225728) State INIT going to sleep
  198. send 1027 bytes to udp/[184.172.33.74]:5060 at 22:02:43.326503:
  199.    ------------------------------------------------------------------------
  200.    INVITE sip:13126225728@184.172.33.74 SIP/2.0
  201.    Via: SIP/2.0/UDP 64.16.248.234:5072;rport;branch=z9hG4bK4aeXDUpgFatFB
  202.    Max-Forwards: 68
  203.    From: "testramon3" <sip:+17739850363@sip.telnyx.com>;tag=4mpUe8gev3Qvg
  204.    To: <sip:13126225728@184.172.33.74>
  205.    Call-ID: 18d792ca-1ce0-1236-4092-02420a0f0d07
  206.    CSeq: 112852417 INVITE
  207.    Contact: <sip:gw+184.172.33.74@64.16.248.234:5072;transport=udp;gw=184.172.33.74>
  208.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, NOTIFY
  209.    Supported: timer, path, replaces
  210.    Allow-Events: talk, hold, conference, refer
  211.    Privacy: none
  212.    Content-Type: application/sdp
  213.    Content-Disposition: session
  214.    Content-Length: 309
  215.    P-Asserted-Identity: "testramon3" <sip:+17739850363@sip.telnyx.com>
  216.    
  217.    v=0
  218.    o=Telnyx 1506359647 1506359648 IN IP4 64.16.248.234
  219.    s=Telnyx
  220.    c=IN IP4 64.16.248.234
  221.    t=0 0
  222.    m=audio 17316 RTP/AVP 8 0 18 9 101
  223.    a=rtpmap:8 PCMA/8000
  224.    a=rtpmap:0 PCMU/8000
  225. 2017-09-25 22:02:43.312818 [DEBUG] switch_core_state_machine.c:584 (sofia/pstn/13126225728) Running State Change CS_ROUTING (Cur 2 Tot 7)
  226.    a=rtpmap:18 G729/8000
  227.    a=fmtp:18 annexb=no
  228.    a=rtpmap:9 G722/8000
  229.    a=rtpmap:101 telephone-event/8000
  230.    a=fmtp:101 0-16
  231.    a=ptime:20
  232.    ------------------------------------------------------------------------
  233. 2017-09-25 22:02:43.312818 [DEBUG] sofia.c:7048 Channel sofia/pstn/13126225728 entering state [calling][0]
  234. 2017-09-25 22:02:43.312818 [DEBUG] switch_core_state_machine.c:643 (sofia/pstn/13126225728) State ROUTING
  235. 2017-09-25 22:02:43.312818 [DEBUG] mod_sofia.c:143 sofia/pstn/13126225728 SOFIA ROUTING
  236. 2017-09-25 22:02:43.312818 [DEBUG] switch_ivr_originate.c:67 (sofia/pstn/13126225728) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  237. 2017-09-25 22:02:43.312818 [DEBUG] switch_core_state_machine.c:643 (sofia/pstn/13126225728) State ROUTING going to sleep
  238. 2017-09-25 22:02:43.312818 [DEBUG] switch_core_state_machine.c:584 (sofia/pstn/13126225728) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 7)
  239. 2017-09-25 22:02:43.312818 [DEBUG] switch_core_state_machine.c:662 (sofia/pstn/13126225728) State CONSUME_MEDIA
  240. 2017-09-25 22:02:43.312818 [DEBUG] switch_core_state_machine.c:662 (sofia/pstn/13126225728) State CONSUME_MEDIA going to sleep
  241. recv 365 bytes from udp/[184.172.33.74]:5060 at 22:02:43.364261:
  242.    ------------------------------------------------------------------------
  243.    SIP/2.0 100 trying -- your call is important to us
  244.    Via: SIP/2.0/UDP 64.16.248.234:5072;rport=5072;branch=z9hG4bK4aeXDUpgFatFB
  245.    From: "testramon3" <sip:+17739850363@sip.telnyx.com>;tag=4mpUe8gev3Qvg
  246.    To: <sip:13126225728@184.172.33.74>
  247.    Call-ID: 18d792ca-1ce0-1236-4092-02420a0f0d07
  248.    CSeq: 112852417 INVITE
  249.    Server: Telnyx v4.5-PRODUCTION.177
  250.    Content-Length: 0
  251.    
  252.    ------------------------------------------------------------------------
  253. recv 702 bytes from udp/[184.172.33.74]:5060 at 22:02:44.774567:
  254.    ------------------------------------------------------------------------
  255.    SIP/2.0 183 Session Progress
  256.    Via: SIP/2.0/UDP 64.16.248.234:5072;branch=z9hG4bK4aeXDUpgFatFB;rport=5072
  257.    Record-Route: <sip:184.172.33.74;ftag=4mpUe8gev3Qvg;lr>
  258.    From: testramon3 <sip:+17739850363@sip.telnyx.com>;tag=4mpUe8gev3Qvg
  259.    To: <sip:13126225728@184.172.33.74>;tag=c613cdda97dddf0164a86dcbc1be18e4
  260.    Call-ID: 18d792ca-1ce0-1236-4092-02420a0f0d07
  261.    CSeq: 112852417 INVITE
  262.    Server: Telnyx v4.5-PRODUCTION.177
  263.    Content-Length: 234
  264.    Content-Type: application/sdp
  265.    
  266.    v=0
  267.    o=Sonus_UAC 616243 154874 IN IP4 67.231.1.112
  268.    s=SIP Media Capabilities
  269.    c=IN IP4 67.231.1.79
  270.    t=0 0
  271.    m=audio 28162 RTP/AVP 0 101
  272.    a=rtpmap:0 PCMU/8000
  273.    a=rtpmap:101 telephone-event/8000
  274.    a=fmtp:101 0-15
  275.    a=sendrecv
  276.    a=ptime:20
  277.    ------------------------------------------------------------------------
  278. 2017-09-25 22:02:44.772782 [DEBUG] sofia.c:7048 Channel sofia/pstn/13126225728 entering state [proceeding][183]
  279. 2017-09-25 22:02:44.772782 [DEBUG] sofia.c:7058 Remote SDP:
  280. v=0
  281. o=Sonus_UAC 616243 154874 IN IP4 67.231.1.112
  282. s=SIP Media Capabilities
  283. c=IN IP4 67.231.1.79
  284. t=0 0
  285. m=audio 28162 RTP/AVP 0 101
  286. a=rtpmap:0 PCMU/8000
  287. a=rtpmap:101 telephone-event/8000
  288. a=fmtp:101 0-15
  289. a=ptime:20
  290.  
  291. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  292. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  293. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:4500 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  294. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  295. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  296. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:4361 Set telephone-event payload to 101@8000
  297. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:3057 Set Codec sofia/pstn/13126225728 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  298. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_codec.c:111 sofia/pstn/13126225728 Original read codec set to PCMU:0
  299. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:4704 Set telephone-event payload to 101@8000
  300. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:4763 sofia/pstn/13126225728 Set 2833 dtmf send payload to 101 recv payload to 101
  301. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:6874 AUDIO RTP [sofia/pstn/13126225728] 64.16.248.234 port 17316 -> 67.231.1.79 port 28162 codec: 0 ms: 20
  302. 2017-09-25 22:02:44.772782 [DEBUG] switch_rtp.c:4112 Starting timer [soft] 160 bytes per 20ms
  303. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:7175 sofia/pstn/13126225728 Set 2833 dtmf send payload to 101
  304. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:7182 sofia/pstn/13126225728 Set 2833 dtmf receive payload to 101
  305. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:7205 sofia/pstn/13126225728 Set rtp dtmf delay to 40
  306. 2017-09-25 22:02:44.772782 [NOTICE] sofia_media.c:92 Pre-Answer sofia/pstn/13126225728!
  307. 2017-09-25 22:02:44.772782 [DEBUG] switch_channel.c:3474 (sofia/pstn/13126225728) Callstate Change DOWN -> EARLY
  308. 2017-09-25 22:02:44.772782 [INFO] switch_ivr_originate.c:3654 Sending early media
  309. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [GSM:3:8000:20:13200:1]/[G722:9:8000:20:64000:1]
  310. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMU:0:8000:20:64000:1]
  311. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [GSM:3:8000:20:13200:1]/[PCMA:8:8000:20:64000:1]
  312. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [GSM:3:8000:20:13200:1]/[G729:18:8000:20:8000:1]
  313. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [GSM:3:8000:20:13200:1]/[opus:116:48000:20:0:1]
  314. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [speex:110:8000:20:0:1]/[G722:9:8000:20:64000:1]
  315. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMU:0:8000:20:64000:1]
  316. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [speex:110:8000:20:0:1]/[PCMA:8:8000:20:64000:1]
  317. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [speex:110:8000:20:0:1]/[G729:18:8000:20:8000:1]
  318. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [speex:110:8000:20:0:1]/[opus:116:48000:20:0:1]
  319. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  320. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  321. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  322. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:4500 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  323. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  324. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[opus:116:48000:20:0:1]
  325. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  326. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  327. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:4500 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  328. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  329. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
  330. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1]
  331. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [iLBC:98:8000:30:0:1]/[G722:9:8000:20:64000:1]
  332. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMU:0:8000:20:64000:1]
  333. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [iLBC:98:8000:30:0:1]/[PCMA:8:8000:20:64000:1]
  334. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [iLBC:98:8000:30:0:1]/[G729:18:8000:20:8000:1]
  335. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [iLBC:98:8000:30:0:1]/[opus:116:48000:20:0:1]
  336. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:4361 Set telephone-event payload to 101@8000
  337. 2017-09-25 22:02:44.772782 [WARNING] switch_core_media.c:4518 Crypto not negotiated but required.
  338. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_media.c:4763 sofia/inbound/testramon3@siplab5.telnyx.com Set 2833 dtmf send payload to 101 recv payload to 101
  339. 2017-09-25 22:02:44.772782 [ERR] mod_sofia.c:2343 CODEC NEGOTIATION ERROR.  SDP:
  340. v=0
  341. o=Z 0 0 IN IP4 192.76.120.192
  342. s=Z
  343. c=IN IP4 192.76.120.192
  344. t=0 0
  345. m=audio 25685 RTP/AVP 3 110 8 0 98 101
  346. a=rtpmap:110 speex/8000
  347. a=rtpmap:98 iLBC/8000
  348. a=fmtp:98 mode=20
  349. a=rtpmap:101 telephone-event/8000
  350. a=fmtp:101 0-15
  351.  
  352. 2017-09-25 22:02:44.772782 [NOTICE] switch_channel.c:3515 Hangup sofia/inbound/testramon3@siplab5.telnyx.com [CS_EXECUTE] [INCOMPATIBLE_DESTINATION]
  353. 2017-09-25 22:02:44.772782 [DEBUG] switch_ivr_originate.c:3661 sofia/inbound/testramon3@siplab5.telnyx.com Media Establishment Failed.
  354. 2017-09-25 22:02:44.772782 [NOTICE] switch_ivr_originate.c:3663 Hangup sofia/pstn/13126225728 [CS_CONSUME_MEDIA] [INCOMPATIBLE_DESTINATION]
  355. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_state_machine.c:584 (sofia/pstn/13126225728) Running State Change CS_HANGUP (Cur 2 Tot 7)
  356. 2017-09-25 22:02:44.772782 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 88 [INCOMPATIBLE_DESTINATION]
  357. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_state_machine.c:850 (sofia/pstn/13126225728) Callstate Change EARLY -> HANGUP
  358. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_state_machine.c:852 (sofia/pstn/13126225728) State HANGUP
  359. 2017-09-25 22:02:44.772782 [DEBUG] mod_sofia.c:438 Channel sofia/pstn/13126225728 hanging up, cause: INCOMPATIBLE_DESTINATION
  360. 2017-09-25 22:02:44.772782 [DEBUG] mod_sofia.c:502 Sending CANCEL to sofia/pstn/13126225728
  361. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_state_machine.c:60 sofia/pstn/13126225728 Standard HANGUP, cause: INCOMPATIBLE_DESTINATION
  362. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_state_machine.c:852 (sofia/pstn/13126225728) State HANGUP going to sleep
  363. 2017-09-25 22:02:44.772782 [INFO] mod_dptools.c:3418 Originate Failed.  Cause: INCOMPATIBLE_DESTINATION
  364. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_state_machine.c:619 (sofia/pstn/13126225728) State Change CS_HANGUP -> CS_REPORTING
  365. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_state_machine.c:584 (sofia/pstn/13126225728) Running State Change CS_REPORTING (Cur 2 Tot 7)
  366. send 392 bytes to udp/[184.172.33.74]:5060 at 22:02:44.791017:
  367.    ------------------------------------------------------------------------
  368.    CANCEL sip:13126225728@184.172.33.74 SIP/2.0
  369.    Via: SIP/2.0/UDP 64.16.248.234:5072;rport;branch=z9hG4bK4aeXDUpgFatFB
  370.    Max-Forwards: 68
  371.    From: "testramon3" <sip:+17739850363@sip.telnyx.com>;tag=4mpUe8gev3Qvg
  372.    To: <sip:13126225728@184.172.33.74>
  373.    Call-ID: 18d792ca-1ce0-1236-4092-02420a0f0d07
  374.    CSeq: 112852417 CANCEL
  375.    Reason: Q.850;cause=88;text="INCOMPATIBLE_DESTINATION"
  376.    Content-Length: 0
  377.    
  378.    ------------------------------------------------------------------------
  379. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_session.c:2815 sofia/inbound/testramon3@siplab5.telnyx.com skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  380. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_state_machine.c:938 (sofia/pstn/13126225728) State REPORTING
  381. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_state_machine.c:650 (sofia/inbound/testramon3@siplab5.telnyx.com) State EXECUTE going to sleep
  382. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_state_machine.c:584 (sofia/inbound/testramon3@siplab5.telnyx.com) Running State Change CS_HANGUP (Cur 2 Tot 7)
  383. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_state_machine.c:850 (sofia/inbound/testramon3@siplab5.telnyx.com) Callstate Change RINGING -> HANGUP
  384. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_state_machine.c:852 (sofia/inbound/testramon3@siplab5.telnyx.com) State HANGUP
  385. 2017-09-25 22:02:44.772782 [DEBUG] mod_sofia.c:438 Channel sofia/inbound/testramon3@siplab5.telnyx.com hanging up, cause: INCOMPATIBLE_DESTINATION
  386. 2017-09-25 22:02:44.772782 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 488
  387. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_state_machine.c:60 sofia/inbound/testramon3@siplab5.telnyx.com Standard HANGUP, cause: INCOMPATIBLE_DESTINATION
  388. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_state_machine.c:852 (sofia/inbound/testramon3@siplab5.telnyx.com) State HANGUP going to sleep
  389. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_state_machine.c:619 (sofia/inbound/testramon3@siplab5.telnyx.com) State Change CS_HANGUP -> CS_REPORTING
  390. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_state_machine.c:584 (sofia/inbound/testramon3@siplab5.telnyx.com) Running State Change CS_REPORTING (Cur 2 Tot 7)
  391. 2017-09-25 22:02:44.772782 [INFO] mod_json_cdr.c:271 Process [27887a5e-b7dc-4358-9f8f-480475051756.cdr.json]
  392. 2017-09-25 22:02:44.772782 [INFO] mod_json_cdr.c:275 Log to disk [/var/log/freeswitch/json_cdr/27887a5e-b7dc-4358-9f8f-480475051756.cdr.json]
  393. send 720 bytes to udp/[10.15.13.13]:5060 at 22:02:44.791446:
  394.    ------------------------------------------------------------------------
  395.    SIP/2.0 488 Not Acceptable Here
  396.    Via: SIP/2.0/UDP 10.15.13.13;branch=z9hG4bK0de.8ccb2f858976fc7bf495773e29244c3a.0
  397.    Via: SIP/2.0/UDP 192.76.120.192:21060;rport=62080;received=50.247.144.29;branch=z9hG4bK-d8754z-4b8cd507b20b7e9f-1---d8754z-
  398.    Max-Forwards: 69
  399.    From: <sip:testramon3@siplab5.telnyx.com;transport=UDP>;tag=d88d4646
  400.    To: <sip:3126225728@siplab5.telnyx.com;transport=UDP>;tag=46586Ue29U5KN
  401.    Call-ID: ZjdkMTE3ZTM0ODkwNzRmMjdlYzU4M2UxNDMwNzhjODU.
  402. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_state_machine.c:938 (sofia/inbound/testramon3@siplab5.telnyx.com) State REPORTING
  403.    CSeq: 1 INVITE
  404.    Accept: application/sdp
  405.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, NOTIFY
  406.    Supported: path, replaces
  407.    Allow-Events: talk, hold, conference, refer
  408.    Reason: Q.850;cause=88;text="INCOMPATIBLE_DESTINATION"
  409.    Content-Length: 0
  410.    
  411.    ------------------------------------------------------------------------
  412. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_state_machine.c:174 sofia/pstn/13126225728 Standard REPORTING, cause: INCOMPATIBLE_DESTINATION
  413. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_state_machine.c:938 (sofia/pstn/13126225728) State REPORTING going to sleep
  414. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_state_machine.c:610 (sofia/pstn/13126225728) State Change CS_REPORTING -> CS_DESTROY
  415. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_session.c:1665 Session 7 (sofia/pstn/13126225728) Locked, Waiting on external entities
  416. 2017-09-25 22:02:44.772782 [NOTICE] switch_core_session.c:1683 Session 7 (sofia/pstn/13126225728) Ended
  417. 2017-09-25 22:02:44.772782 [NOTICE] switch_core_session.c:1687 Close Channel sofia/pstn/13126225728 [CS_DESTROY]
  418. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_state_machine.c:741 (sofia/pstn/13126225728) Running State Change CS_DESTROY (Cur 1 Tot 7)
  419. 2017-09-25 22:02:44.772782 [INFO] mod_json_cdr.c:271 Process [a_873e7e53-d676-4628-b19f-d1f02c4dd6d7.cdr.json]
  420. 2017-09-25 22:02:44.772782 [INFO] mod_json_cdr.c:275 Log to disk [/var/log/freeswitch/json_cdr/a_873e7e53-d676-4628-b19f-d1f02c4dd6d7.cdr.json]
  421. recv 391 bytes from udp/[10.15.13.13]:5060 at 22:02:44.791978:
  422.    ------------------------------------------------------------------------
  423.    ACK sip:3126225728@10.15.13.7:5070;transport=UDP SIP/2.0
  424.    Via: SIP/2.0/UDP 10.15.13.13;branch=z9hG4bK0de.8ccb2f858976fc7bf495773e29244c3a.0
  425.    Max-Forwards: 69
  426.    To: <sip:3126225728@siplab5.telnyx.com;transport=UDP>;tag=46586Ue29U5KN
  427.    From: <sip:testramon3@siplab5.telnyx.com;transport=UDP>;tag=d88d4646
  428.    Call-ID: ZjdkMTE3ZTM0ODkwNzRmMjdlYzU4M2UxNDMwNzhjODU.
  429.    CSeq: 1 ACK
  430.    Content-Length: 0
  431.    
  432.    ------------------------------------------------------------------------
  433. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_state_machine.c:174 sofia/inbound/testramon3@siplab5.telnyx.com Standard REPORTING, cause: INCOMPATIBLE_DESTINATION
  434. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_state_machine.c:938 (sofia/inbound/testramon3@siplab5.telnyx.com) State REPORTING going to sleep
  435. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_state_machine.c:751 (sofia/pstn/13126225728) State DESTROY
  436. 2017-09-25 22:02:44.772782 [DEBUG] mod_sofia.c:343 sofia/pstn/13126225728 SOFIA DESTROY
  437. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_state_machine.c:181 sofia/pstn/13126225728 Standard DESTROY
  438. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_state_machine.c:751 (sofia/pstn/13126225728) State DESTROY going to sleep
  439. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_state_machine.c:610 (sofia/inbound/testramon3@siplab5.telnyx.com) State Change CS_REPORTING -> CS_DESTROY
  440. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_session.c:1665 Session 6 (sofia/inbound/testramon3@siplab5.telnyx.com) Locked, Waiting on external entities
  441. 2017-09-25 22:02:44.772782 [NOTICE] switch_core_session.c:1683 Session 6 (sofia/inbound/testramon3@siplab5.telnyx.com) Ended
  442. 2017-09-25 22:02:44.772782 [NOTICE] switch_core_session.c:1687 Close Channel sofia/inbound/testramon3@siplab5.telnyx.com [CS_DESTROY]
  443. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_state_machine.c:741 (sofia/inbound/testramon3@siplab5.telnyx.com) Running State Change CS_DESTROY (Cur 0 Tot 7)
  444. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_state_machine.c:751 (sofia/inbound/testramon3@siplab5.telnyx.com) State DESTROY
  445. 2017-09-25 22:02:44.772782 [DEBUG] mod_sofia.c:343 sofia/inbound/testramon3@siplab5.telnyx.com SOFIA DESTROY
  446. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_state_machine.c:181 sofia/inbound/testramon3@siplab5.telnyx.com Standard DESTROY
  447. 2017-09-25 22:02:44.772782 [DEBUG] switch_core_state_machine.c:751 (sofia/inbound/testramon3@siplab5.telnyx.com) State DESTROY going to sleep
  448.