From Voluminous Panda, 8 Years ago, written in FreeSWITCH.
This paste is a reply to Untitled from Brent
- view diff
Embed
  1. +OK log level  [7]
  2. freeswitch@internal> sofia global siptrace on
  3. +OK Global siptrace on
  4. recv 1188 bytes from udp/[64.7.96.3]:5060 at 03:05:04.499268:
  5.    ------------------------------------------------------------------------
  6.    INVITE sip:6303437370@64.7.99.27:5080 SIP/2.0
  7.    Record-Route: <sip:64.7.96.3;lr=on;ftag=eea75a70-f420-4416-8968-d6e5dfadb3f4-30174388;mp=yes;vsf=AAAAAAEACAB4AQYeDh55ABoYNy45Ni4z>
  8.    Via: SIP/2.0/UDP 64.7.96.3;branch=z9hG4bKfe59.feb100c6.0
  9.    Via: SIP/2.0/UDP 64.7.96.6:5060;branch=z9hG4bK194a11786434f
  10.    From: "Brent Sachnoff" <sip:7086872090@64.7.96.3>;tag=eea75a70-f420-4416-8968-d6e5dfadb3f4-30174388
  11.    To: <sip:16303437370@64.7.96.3>
  12.    Date: Wed, 11 May 2016 08:05:04 GMT
  13.    Call-ID: ffe3d80-7321e7b0-bfc3-6600740@64.7.96.6
  14.    Supported: timer,replaces
  15.    Min-SE:  1800
  16.    User-Agent: Cisco-CCM5.1
  17.   Allow: INVITE, OPTIONS, INFO, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY
  18.    CSeq: 101 INVITE
  19.    Contact: <sip:6006@64.7.96.6:5060>
  20.    Expires: 180
  21.    Allow-Events: presence, kpml
  22.    Call-Info: <sip:64.7.96.6:5060>;method="NOTIFY;Event=telephone-event;Duration=500"
  23.    Session-Expires:  1800
  24.    Max-Forwards: 69
  25.    Content-Type: application/sdp
  26.    Content-Length: 206
  27.    P-hint: mediaproxy applied
  28.    
  29.    v=0
  30.    o=CiscoSystemsCCM-SIP 2000 1 IN IP4 64.7.96.6
  31.    s=SIP Call
  32.    c=IN IP4 64.7.96.3
  33.    t=0 0
  34.    m=audio 44250 RTP/AVP 0 101
  35.    a=rtpmap:0 PCMU/8000
  36.    a=ptime:20
  37.    a=rtpmap:101 telephone-event/8000
  38.    a=fmtp:101 0-15
  39.    ------------------------------------------------------------------------
  40. send 518 bytes to udp/[64.7.96.3]:5060 at 03:05:04.499627:
  41.    ------------------------------------------------------------------------
  42.    SIP/2.0 100 Trying
  43.    Via: SIP/2.0/UDP 64.7.96.3;branch=z9hG4bKfe59.feb100c6.0
  44.    Via: SIP/2.0/UDP 64.7.96.6:5060;branch=z9hG4bK194a11786434f
  45.    Record-Route: <sip:64.7.96.3;lr=on;ftag=eea75a70-f420-4416-8968-d6e5dfadb3f4-30174388;mp=yes;vsf=AAAAAAEACAB4AQYeDh55ABoYNy45Ni4z>
  46.    From: "Brent Sachnoff" <sip:7086872090@64.7.96.3>;tag=eea75a70-f420-4416-8968-d6e5dfadb3f4-30174388
  47.    To: <sip:16303437370@64.7.96.3>
  48.    Call-ID: ffe3d80-7321e7b0-bfc3-6600740@64.7.96.6
  49.    CSeq: 101 INVITE
  50.    User-Agent: FreeSWITCH
  51.    Content-Length: 0
  52.    
  53.    ------------------------------------------------------------------------
  54. 2016-05-11 03:05:04.480940 [NOTICE] switch_channel.c:1101 New Channel sofia/external/7086872090@64.7.96.3 [11828066-174f-11e6-b94d-8dcc9e1d3d65]
  55. 2016-05-11 03:05:04.480940 [DEBUG] switch_core_state_machine.c:473 (sofia/external/7086872090@64.7.96.3) Running State Change CS_NEW
  56. 2016-05-11 03:05:04.480940 [DEBUG] sofia.c:9248 sofia/external/7086872090@64.7.96.3 receiving invite from 64.7.96.3:5060 version: 1.6.6  64bit
  57. 2016-05-11 03:05:04.480940 [DEBUG] sofia.c:6760 Channel sofia/external/7086872090@64.7.96.3 entering state [received][100]
  58. 2016-05-11 03:05:04.480940 [DEBUG] sofia.c:6770 Remote SDP:
  59. v=0
  60. o=CiscoSystemsCCM-SIP 2000 1 IN IP4 64.7.96.6
  61. s=SIP Call
  62. c=IN IP4 64.7.96.3
  63. t=0 0
  64. m=audio 44250 RTP/AVP 0 101
  65. a=rtpmap:0 PCMU/8000
  66. a=rtpmap:101 telephone-event/8000
  67. a=fmtp:101 0-15
  68. a=ptime:20
  69.  
  70. 2016-05-11 03:05:04.480940 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G7221:115:32000:20:48000:1]
  71. 2016-05-11 03:05:04.480940 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G7221:107:16000:20:32000:1]
  72. 2016-05-11 03:05:04.480940 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  73. 2016-05-11 03:05:04.480940 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  74. 2016-05-11 03:05:04.480940 [DEBUG] switch_core_media.c:4216 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  75. 2016-05-11 03:05:04.480940 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  76. 2016-05-11 03:05:04.480940 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
  77. 2016-05-11 03:05:04.480940 [DEBUG] switch_core_media.c:4077 Set telephone-event payload to 101@8000
  78. 2016-05-11 03:05:04.480940 [DEBUG] switch_core_media.c:2906 Set Codec sofia/external/7086872090@64.7.96.3 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  79. 2016-05-11 03:05:04.480940 [DEBUG] switch_core_codec.c:111 sofia/external/7086872090@64.7.96.3 Original read codec set to PCMU:0
  80. 2016-05-11 03:05:04.500922 [DEBUG] switch_core_media.c:4429 Set telephone-event payload to 101@8000
  81. 2016-05-11 03:05:04.500922 [DEBUG] switch_core_media.c:4485 sofia/external/7086872090@64.7.96.3 Set 2833 dtmf send payload to 101 recv payload to 101
  82. 2016-05-11 03:05:04.500922 [DEBUG] sofia.c:7146 (sofia/external/7086872090@64.7.96.3) State Change CS_NEW -> CS_INIT
  83. 2016-05-11 03:05:04.500922 [DEBUG] switch_core_state_machine.c:492 (sofia/external/7086872090@64.7.96.3) State NEW
  84. 2016-05-11 03:05:04.500922 [DEBUG] switch_core_state_machine.c:473 (sofia/external/7086872090@64.7.96.3) Running State Change CS_INIT
  85. 2016-05-11 03:05:04.500922 [DEBUG] switch_core_state_machine.c:516 (sofia/external/7086872090@64.7.96.3) State INIT
  86. 2016-05-11 03:05:04.500922 [DEBUG] mod_sofia.c:88 sofia/external/7086872090@64.7.96.3 SOFIA INIT
  87. 2016-05-11 03:05:04.500922 [DEBUG] switch_core_state_machine.c:40 sofia/external/7086872090@64.7.96.3 Standard INIT
  88. 2016-05-11 03:05:04.500922 [DEBUG] switch_core_state_machine.c:48 (sofia/external/7086872090@64.7.96.3) State Change CS_INIT -> CS_ROUTING
  89. 2016-05-11 03:05:04.500922 [DEBUG] switch_core_state_machine.c:516 (sofia/external/7086872090@64.7.96.3) State INIT going to sleep
  90. 2016-05-11 03:05:04.500922 [DEBUG] switch_core_state_machine.c:473 (sofia/external/7086872090@64.7.96.3) Running State Change CS_ROUTING
  91. 2016-05-11 03:05:04.500922 [DEBUG] switch_channel.c:2247 (sofia/external/7086872090@64.7.96.3) Callstate Change DOWN -> RINGING
  92. 2016-05-11 03:05:04.500922 [DEBUG] switch_core_state_machine.c:532 (sofia/external/7086872090@64.7.96.3) State ROUTING
  93. 2016-05-11 03:05:04.500922 [DEBUG] mod_sofia.c:141 sofia/external/7086872090@64.7.96.3 SOFIA ROUTING
  94. 2016-05-11 03:05:04.500922 [DEBUG] switch_core_state_machine.c:166 sofia/external/7086872090@64.7.96.3 Standard ROUTING
  95. 2016-05-11 03:05:04.500922 [INFO] mod_dialplan_xml.c:637 Processing Brent Sachnoff <7086872090>->6303437370 in context public
  96. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [public->6303437370] continue=false
  97. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (PASS) [6303437370] destination_number(6303437370) =~ /6303437370/ break=on-false
  98. Dialplan: sofia/external/7086872090@64.7.96.3 Action set(call_direction=inbound)
  99. Dialplan: sofia/external/7086872090@64.7.96.3 Action set(domain_uuid=fc116b59-2402-4d62-8111-268fcc26951c)
  100. Dialplan: sofia/external/7086872090@64.7.96.3 Action set(domain_name=besindustries.urbancom.net)
  101. Dialplan: sofia/external/7086872090@64.7.96.3 Action set(domain=besindustries.urbancom.net)
  102. Dialplan: sofia/external/7086872090@64.7.96.3 Action set(effective_caller_id_name=Brent Sachnoff#${caller_id_name})
  103. Dialplan: sofia/external/7086872090@64.7.96.3 Action transfer(1001 XML besindustries.urbancom.net)
  104. 2016-05-11 03:05:04.500922 [DEBUG] switch_core_state_machine.c:216 (sofia/external/7086872090@64.7.96.3) State Change CS_ROUTING -> CS_EXECUTE
  105. 2016-05-11 03:05:04.500922 [DEBUG] switch_core_state_machine.c:532 (sofia/external/7086872090@64.7.96.3) State ROUTING going to sleep
  106. 2016-05-11 03:05:04.500922 [DEBUG] switch_core_state_machine.c:473 (sofia/external/7086872090@64.7.96.3) Running State Change CS_EXECUTE
  107. 2016-05-11 03:05:04.500922 [DEBUG] switch_core_state_machine.c:539 (sofia/external/7086872090@64.7.96.3) State EXECUTE
  108. 2016-05-11 03:05:04.500922 [DEBUG] mod_sofia.c:196 sofia/external/7086872090@64.7.96.3 SOFIA EXECUTE
  109. 2016-05-11 03:05:04.500922 [DEBUG] switch_core_state_machine.c:258 sofia/external/7086872090@64.7.96.3 Standard EXECUTE
  110. EXECUTE sofia/external/7086872090@64.7.96.3 set(call_direction=inbound)
  111. 2016-05-11 03:05:04.500922 [DEBUG] mod_dptools.c:1498 SET sofia/external/7086872090@64.7.96.3 [call_direction]=[inbound]
  112. EXECUTE sofia/external/7086872090@64.7.96.3 set(domain_uuid=fc116b59-2402-4d62-8111-268fcc26951c)
  113. 2016-05-11 03:05:04.500922 [DEBUG] mod_dptools.c:1498 SET sofia/external/7086872090@64.7.96.3 [domain_uuid]=[fc116b59-2402-4d62-8111-268fcc26951c]
  114. EXECUTE sofia/external/7086872090@64.7.96.3 set(domain_name=besindustries.urbancom.net)
  115. 2016-05-11 03:05:04.500922 [DEBUG] mod_dptools.c:1498 SET sofia/external/7086872090@64.7.96.3 [domain_name]=[besindustries.urbancom.net]
  116. EXECUTE sofia/external/7086872090@64.7.96.3 set(domain=besindustries.urbancom.net)
  117. 2016-05-11 03:05:04.500922 [DEBUG] mod_dptools.c:1498 SET sofia/external/7086872090@64.7.96.3 [domain]=[besindustries.urbancom.net]
  118. EXECUTE sofia/external/7086872090@64.7.96.3 set(effective_caller_id_name=Brent Sachnoff#Brent Sachnoff)
  119. span style="color: goldenrod; font-weight: bold;"> 2016-05-11 03:05:04.500922 [DEBUG] mod_dptools.c:1498 SET sofia/external/7086872090@64.7.96.3 [effective_caller_id_name]=[Brent Sachnoff#Brent Sachnoff]
  120. EXECUTE sofia/external/7086872090@64.7.96.3 transfer(1001 XML besindustries.urbancom.net)
  121. 2016-05-11 03:05:04.500922 [DEBUG] switch_ivr.c:2085 (sofia/external/7086872090@64.7.96.3) State Change CS_EXECUTE -> CS_ROUTING
  122. 2016-05-11 03:05:04.500922 [NOTICE] switch_ivr.c:2092 Transfer sofia/external/7086872090@64.7.96.3 to XML[1001@besindustries.urbancom.net]
  123. 2016-05-11 03:05:04.500922 [DEBUG] switch_core_state_machine.c:539 (sofia/external/7086872090@64.7.96.3) State EXECUTE going to sleep
  124. 2016-05-11 03:05:04.500922 [DEBUG] switch_core_state_machine.c:473 (sofia/external/7086872090@64.7.96.3) Running State Change CS_ROUTING
  125. 2016-05-11 03:05:04.500922 [DEBUG] switch_core_state_machine.c:532 (sofia/external/7086872090@64.7.96.3) State ROUTING
  126. 2016-05-11 03:05:04.500922 [DEBUG] mod_sofia.c:141 sofia/external/7086872090@64.7.96.3 SOFIA ROUTING
  127. 2016-05-11 03:05:04.500922 [DEBUG] switch_core_state_machine.c:166 sofia/external/7086872090@64.7.96.3 Standard ROUTING
  128. 2016-05-11 03:05:04.500922 [INFO] mod_dialplan_xml.c:637 Processing Brent Sachnoff <7086872090>->1001 in context besindustries.urbancom.net
  129. 2016-05-11 03:05:04.520899 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7fb250041150 Connected.
  130. 2016-05-11 03:05:04.580899 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7fb250041150 released.
  131. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->user_exists] continue=true
  132. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (PASS) [user_exists] () =~ // break=on-false
  133. Dialplan: sofia/external/7086872090@64.7.96.3 Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
  134. EXECUTE sofia/external/7086872090@64.7.96.3 set(user_exists=true)
  135. 2016-05-11 03:05:04.580899 [DEBUG] mod_dptools.c:1498 SET sofia/external/7086872090@64.7.96.3 [user_exists]=[true]
  136. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (PASS) [user_exists] ${user_exists}(true) =~ /^true$/ break=on-false
  137. Dialplan: sofia/external/7086872090@64.7.96.3 Action set(extension_uuid=${user_data ${destination_number}@${domain_name} var extension_uuid})
  138. Dialplan: sofia/external/7086872090@64.7.96.3 Action set(hold_music=${user_data ${destination_number}@${domain_name} var hold_music})
  139. Dialplan: sofia/external/7086872090@64.7.96.3 Action set(forward_all_enabled=${user_data ${destination_number}@${domain_name} var forward_all_enabled})
  140. Dialplan: sofia/external/7086872090@64.7.96.3 Action set(forward_all_destination=${user_data ${destination_number}@${domain_name} var forward_all_destination})
  141. Dialplan: sofia/external/7086872090@64.7.96.3 Action set(forward_busy_enabled=${user_data ${destination_number}@${domain_name} var forward_busy_enabled})
  142. Dialplan: sofia/external/7086872090@64.7.96.3 Action set(forward_busy_destination=${user_data ${destination_number}@${domain_name} var forward_busy_destination})
  143. Dialplan: sofia/external/7086872090@64.7.96.3 Action set(forward_no_answer_enabled=${user_data ${destination_number}@${domain_name} var forward_no_answer_enabled})
  144. Dialplan: sofia/external/7086872090@64.7.96.3 Action set(forward_no_answer_destination=${user_data ${destination_number}@${domain_name} var forward_no_answer_destination})
  145. Dialplan: sofia/external/7086872090@64.7.96.3 Action set(forward_user_not_registered_enabled=${user_data ${destination_number}@${domain_name} var forward_user_not_registered_enabled})
  146. Dialplan: sofia/external/7086872090@64.7.96.3 Action set(forward_user_not_registered_destination=${user_data ${destination_number}@${domain_name} var forward_user_not_registered_destination})
  147. Dialplan: sofia/external/7086872090@64.7.96.3 Action set(do_not_disturb=${user_data ${destination_number}@${domain_name} var do_not_disturb})
  148. Dialplan: sofia/external/7086872090@64.7.96.3 Action set(call_timeout=${user_data ${destination_number}@${domain_name} var call_timeout})
  149. Dialplan: sofia/external/7086872090@64.7.96.3 Action set(missed_call_app=${user_data ${destination_number}@${domain_name} var missed_call_app})
  150. Dialplan: sofia/external/7086872090@64.7.96.3 Action set(missed_call_data=${user_data ${destination_number}@${domain_name} var missed_call_data})
  151. Dialplan: sofia/external/7086872090@64.7.96.3 Action set(call_screen_enabled=${user_data ${destination_number}@${domain_name} var call_screen_enabled}) INLINE
  152. EXECUTE sofia/external/7086872090@64.7.96.3 set(call_screen_enabled=)
  153. 2016-05-11 03:05:04.600915 [DEBUG] mod_dptools.c:1498 SET sofia/external/7086872090@64.7.96.3 [call_screen_enabled]=[UNDEF]
  154. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->call-direction] continue=true
  155. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (PASS) [call-direction] ${call_direction}(inbound) =~ /^(inbound|outbound|local)$/ break=never
  156. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [call-direction] ${user_exists}(true) =~ /^false$/ break=on-false
  157. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->variables] continue=true
  158. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (PASS) [variables] () =~ // break=on-false
  159. Dialplan: sofia/external/7086872090@64.7.96.3 Action export(origination_callee_id_name=${destination_number})
  160. Dialplan: sofia/external/7086872090@64.7.96.3 Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
  161. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->user_record] continue=true
  162. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (PASS) [user_record] () =~ // break=on-false
  163. Dialplan: sofia/external/7086872090@64.7.96.3 Action set(user_record=${user_data ${destination_number}@${domain_name} var user_record}) INLINE
  164. EXECUTE sofia/external/7086872090@64.7.96.3 set(user_record=)
  165. 2016-05-11 03:05:04.600915 [DEBUG] mod_dptools.c:1498 SET sofia/external/7086872090@64.7.96.3 [user_record]=[UNDEF]
  166. Dialplan: sofia/external/7086872090@64.7.96.3 Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE
  167. 2016-05-11 03:05:04.600915 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7fb250041150 Connected.
  168. 2016-05-11 03:05:04.600915 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7fb250041150 released.
  169. EXECUTE sofia/external/7086872090@64.7.96.3 set(from_user_exists=false)
  170. 2016-05-11 03:05:04.600915 [DEBUG] mod_dptools.c:1498 SET sofia/external/7086872090@64.7.96.3 [from_user_exists]=[false]
  171. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never
  172. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never
  173. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never
  174. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never
  175. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never
  176. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never
  177. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never
  178. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never
  179. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never
  180. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never
  181. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never
  182. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
  183. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
  184. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never
  185. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
  186. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never
  187. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never
  188. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
  189. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never
  190. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never
  191. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
  192. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never
  193. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never
  194. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false
  195. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->redial] continue=true
  196. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [redial] destination_number(1001) =~ /^(redial|\*870)$/ break=on-true
  197. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (PASS) [redial] () =~ // break=never
  198. Dialplan: sofia/external/7086872090@64.7.96.3 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
  199. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->sip.urbancom.net.11d] continue=false
  200. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [sip.urbancom.net.11d] destination_number(1001) =~ /^\+?(\d{11})$/ break=on-false
  201. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->agent_status] continue=false
  202. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [agent_status] destination_number(1001) =~ /^\*22$/ break=on-false
  203. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->agent_status_id] continue=false
  204. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [agent_status_id] destination_number(1001) =~ /^\*23$/ break=on-false
  205. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->group-intercept] continue=false
  206. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [group-intercept] destination_number(1001) =~ /^\*8$/ break=on-false
  207. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->page-extension] continue=false
  208. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [page-extension] destination_number(1001) =~ /^\*8(\d{2,7})$/ break=on-false
  209. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->eavesdrop] continue=false
  210. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [eavesdrop] destination_number(1001) =~ /^\*33(\d{2,7})$/ break=on-false
  211. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->call_privacy] continue=false
  212. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [call_privacy] destination_number(1001) =~ /^\*67(\d+)$/ break=on-false
  213. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->call_return] continue=false
  214. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [call_return] destination_number(1001) =~ /^\*69$/ break=on-false
  215. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->extension_queue] continue=
  216. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [extension_queue] destination_number(1001) =~ /^\*800(.*)$/ break=on-false
  217. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->intercept-ext] continue=false
  218. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [intercept-ext] destination_number(1001) =~ /^\*\*(\d+)$/ break=on-false
  219. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->intercept-ext-polycom] continue=false
  220. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [intercept-ext-polycom] destination_number(1001) =~ /^\*97(\d+)$/ break=on-false
  221. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->dx] continue=false
  222. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [dx] destination_number(1001) =~ /^dx$/ break=on-false
  223. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->att_xfer] continue=false
  224. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [att_xfer] destination_number(1001) =~ /^att_xfer$/ break=on-false
  225. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->extension-to-voicemail] continue=false
  226. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (PASS) [extension-to-voicemail] username(7086872090) =~ /^7086872090$/ break=on-false
  227. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [extension-to-voicemail] destination_number(1001) =~ /^7086872090$/ break=on-false
  228. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->send_to_voicemail] continue=false
  229. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [send_to_voicemail] destination_number(1001) =~ /^\*99(\d{2,7})$/ break=on-false
  230. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->vmain] continue=false
  231. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [vmain] destination_number(1001) =~ /^vmain$|^\*4000$|^\*98$/ break=on-false
  232. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->xfer_vm] continue=false
  233. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [xfer_vm] destination_number(1001) =~ /^xfer_vm$/ break=on-false
  234. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->is_transfer] continue=false
  235. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [is_transfer] destination_number(1001) =~ /^is_transfer$/ break=on-false
  236. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->vmain_user] continue=false
  237. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [vmain_user] destination_number(1001) =~ /^\*97$/ break=on-false
  238. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->cf] continue=false
  239. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [cf] destination_number(1001) =~ /^cf$/ break=on-false
  240. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->delay_echo] continue=false
  241. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [delay_echo] destination_number(1001) =~ /^\*9195$/ break=on-false
  242. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->echo] continue=false
  243. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [echo] destination_number(1001) =~ /^\*9196$/ break=on-false
  244. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->is_zrtp_secure] continue=true
  245. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [is_zrtp_secure] ${zrtp_secure_media_confirmed}() =~ /^true$/ break=on-false
  246. Dialplan: sofia/external/7086872090@64.7.96.3 ANTI-Action eval(not_secure)
  247. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->milliwatt] continue=false
  248. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [milliwatt] destination_number(1001) =~ /^\*9197$/ break=on-false
  249. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->is_secure] continue=true
  250. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [is_secure] ${sip_via_protocol}(udp) =~ /tls/ break=on-false
  251. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->tone_stream] continue=false
  252. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [tone_stream] destination_number(1001) =~ /^\*9198$/ break=on-false
  253. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->hold_music] continue=false
  254. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [hold_music] destination_number(1001) =~ /^\*9664$/ break=on-false
  255. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->recordings] continue=false
  256. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [recordings] destination_number(1001) =~ /^\*(732)$/ break=on-false
  257. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->directory] continue=false
  258. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [directory] destination_number(1001) =~ /^\*411$/ break=on-false
  259. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->wake-up] continue=false
  260. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [wake-up] destination_number(1001) =~ /^\*(925)$/ break=on-false
  261. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->valet_park] continue=false
  262. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [valet_park] destination_number(1001) =~ /^(park\+)?(\*59[0-9][0-9])$/ break=never
  263. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [valet_park] ${sip_h_Referred-By}() =~ /sip:(.*)@.*/ break=never
  264. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [valet_park] destination_number(1001) =~ /^(park\+)?(\*59[0-9][0-9])$/ break=never
  265. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [valet_park] destination_number(1001) =~ /^(park\+)?(\*59[0-9][0-9])$/ break=on-false
  266. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->operator] continue=false
  267. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [operator] destination_number(1001) =~ /^0$|^operator$/ break=on-false
  268. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->operator-forward] continue=false
  269. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [operator-forward] destination_number(1001) =~ /^\*000$/ break=on-false
  270. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->do-not-disturb] continue=false
  271. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [do-not-disturb] destination_number(1001) =~ /^\*78$|\*363$/ break=on-true
  272. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [do-not-disturb] destination_number(1001) =~ /^\*79$/ break=on-false
  273. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->call-forward] continue=false
  274. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [call-forward] destination_number(1001) =~ /^\*72$/ break=on-true
  275. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [call-forward] destination_number(1001) =~ /^\*73$/ break=on-true
  276. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [call-forward] destination_number(1001) =~ /^\*74$/ break=on-true
  277. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->follow-me] continue=false
  278. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [follow-me] destination_number(1001) =~ /^\*21$/ break=on-false
  279. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->clear_sip_auto_answer] continue=true
  280. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [clear_sip_auto_answer] ${click_to_call}() =~ /true/ break=on-false
  281. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->call_screen] continue=true
  282. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [call_screen] ${call_screen_enabled}() =~ /^true$/ break=on-false
  283. Dialplan: sofia/external/7086872090@64.7.96.3 parsing [besindustries.urbancom.net->local_extension] continue=false
  284. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (PASS) [local_extension] ${user_exists}(true) =~ /true/ break=on-false
  285. Dialplan: sofia/external/7086872090@64.7.96.3 Action export(dialed_extension=${destination_number}) INLINE
  286. EXECUTE sofia/external/7086872090@64.7.96.3 export(dialed_extension=1001)
  287. 2016-05-11 03:05:04.600915 [DEBUG] switch_channel.c:1293 EXPORT (export_vars) [dialed_extension]=[1001]
  288. Dialplan: sofia/external/7086872090@64.7.96.3 Action limit(hash ${domain_name} ${destination_number} ${limit_max} ${limit_destination})
  289. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (FAIL) [local_extension] ${sip_authorized}() =~ /true/ break=never
  290. Dialplan: sofia/external/7086872090@64.7.96.3 ANTI-Action set(bind_target=both) INLINE
  291. EXECUTE sofia/external/7086872090@64.7.96.3 set(bind_target=both)
  292. 2016-05-11 03:05:04.600915 [DEBUG] mod_dptools.c:1498 SET sofia/external/7086872090@64.7.96.3 [bind_target]=[both]
  293. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (PASS) [local_extension] () =~ // break=on-false
  294. Dialplan: sofia/external/7086872090@64.7.96.3 Action bind_digit_action(local,*1,exec:execute_extension,dx XML ${context},${bind_target})
  295. Dialplan: sofia/external/7086872090@64.7.96.3 Action bind_digit_action(local,*2,exec:record_session,${recordings_dir}/${domain_name}/archive/${strftime(%Y)}/${strftime(%b)}/${strftime(%d)}/${uuid}.${record_ext},${bind_target})
  296. Dialplan: sofia/external/7086872090@64.7.96.3 Action bind_digit_action(local,*3,exec:execute_extension,cf XML ${context},${bind_target})
  297. Dialplan: sofia/external/7086872090@64.7.96.3 Action bind_digit_action(local,*4,exec:execute_extension,att_xfer XML ${context},${bind_target})
  298. Dialplan: sofia/external/7086872090@64.7.96.3 Action digit_action_set_realm(local)
  299. Dialplan: sofia/external/7086872090@64.7.96.3 Regex (PASS) [local_extension] () =~ // break=on-false
  300. Dialplan: sofia/external/7086872090@64.7.96.3 Action set(hangup_after_bridge=true)
  301. Dialplan: sofia/external/7086872090@64.7.96.3 Action set(continue_on_fail=true)
  302. Dialplan: sofia/external/7086872090@64.7.96.3 Action hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number})
  303. Dialplan: sofia/external/7086872090@64.7.96.3 Action hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid})
  304. Dialplan: sofia/external/7086872090@64.7.96.3 Action set(called_party_call_group=${user_data(${dialed_extension}@${domain_name} var call_group)})
  305. Dialplan: sofia/external/7086872090@64.7.96.3 Action hash(insert/${domain_name}-last_dial/${called_party_call_group}/${uuid})
  306. Dialplan: sofia/external/7086872090@64.7.96.3 Action set(api_hangup_hook=lua app.lua hangup)
  307. Dialplan: sofia/external/7086872090@64.7.96.3 Action bridge(user/${destination_number}@${domain_name})
  308. Dialplan: sofia/external/7086872090@64.7.96.3 Action lua(app.lua failure_handler)
  309. Dialplan: sofia/external/7086872090@64.7.96.3 Action sleep(1000)
  310. Dialplan: sofia/external/7086872090@64.7.96.3 Action set(voicemail_action=save)
  311. Dialplan: sofia/external/7086872090@64.7.96.3 Action set(voicemail_id=${dialed_extension})
  312. Dialplan: sofia/external/7086872090@64.7.96.3 Action set(voicemail_profile=default)
  313. Dialplan: sofia/external/7086872090@64.7.96.3 Action lua(app.lua voicemail)
  314. 2016-05-11 03:05:04.600915 [DEBUG] switch_core_state_machine.c:216 (sofia/external/7086872090@64.7.96.3) State Change CS_ROUTING -> CS_EXECUTE
  315. 2016-05-11 03:05:04.600915 [DEBUG] switch_core_state_machine.c:532 (sofia/external/7086872090@64.7.96.3) State ROUTING going to sleep
  316. 2016-05-11 03:05:04.600915 [DEBUG] switch_core_state_machine.c:473 (sofia/external/7086872090@64.7.96.3) Running State Change CS_EXECUTE
  317. 2016-05-11 03:05:04.600915 [DEBUG] switch_core_state_machine.c:539 (sofia/external/7086872090@64.7.96.3) State EXECUTE
  318. 2016-05-11 03:05:04.600915 [DEBUG] mod_sofia.c:196 sofia/external/7086872090@64.7.96.3 SOFIA EXECUTE
  319. 2016-05-11 03:05:04.600915 [DEBUG] switch_core_state_machine.c:258 sofia/external/7086872090@64.7.96.3 Standard EXECUTE
  320. EXECUTE sofia/external/7086872090@64.7.96.3 set(extension_uuid=9c50b3e8-714f-4dcf-8c35-13f42500338c)
  321. 2016-05-11 03:05:04.622479 [DEBUG] mod_dptools.c:1498 SET sofia/external/7086872090@64.7.96.3 [extension_uuid]=[9c50b3e8-714f-4dcf-8c35-13f42500338c]
  322. EXECUTE sofia/external/7086872090@64.7.96.3 set(hold_music=)
  323. 2016-05-11 03:05:04.622479 [DEBUG] mod_dptools.c:1498 SET sofia/external/7086872090@64.7.96.3 [hold_music]=[UNDEF]
  324. EXECUTE sofia/external/7086872090@64.7.96.3 set(forward_all_enabled=)
  325. 2016-05-11 03:05:04.622479 [DEBUG] mod_dptools.c:1498 SET sofia/external/7086872090@64.7.96.3 [forward_all_enabled]=[UNDEF]
  326. EXECUTE sofia/external/7086872090@64.7.96.3 set(forward_all_destination=)
  327. 2016-05-11 03:05:04.622479 [DEBUG] mod_dptools.c:1498 SET sofia/external/7086872090@64.7.96.3 [forward_all_destination]=[UNDEF]
  328. EXECUTE sofia/external/7086872090@64.7.96.3 set(forward_busy_enabled=)
  329. 2016-05-11 03:05:04.622479 [DEBUG] mod_dptools.c:1498 SET sofia/external/7086872090@64.7.96.3 [forward_busy_enabled]=[UNDEF]
  330. EXECUTE sofia/external/7086872090@64.7.96.3 set(forward_busy_destination=)
  331. 2016-05-11 03:05:04.622479 [DEBUG] mod_dptools.c:1498 SET sofia/external/7086872090@64.7.96.3 [forward_busy_destination]=[UNDEF]
  332. EXECUTE sofia/external/7086872090@64.7.96.3 set(forward_no_answer_enabled=)
  333. 2016-05-11 03:05:04.660920 [DEBUG] mod_dptools.c:1498 SET sofia/external/7086872090@64.7.96.3 [forward_no_answer_enabled]=[UNDEF]
  334. EXECUTE sofia/external/7086872090@64.7.96.3 set(forward_no_answer_destination=)
  335. 2016-05-11 03:05:04.660920 [DEBUG] mod_dptools.c:1498 SET sofia/external/7086872090@64.7.96.3 [forward_no_answer_destination]=[UNDEF]
  336. EXECUTE sofia/external/7086872090@64.7.96.3 set(forward_user_not_registered_enabled=)
  337. 2016-05-11 03:05:04.660920 [DEBUG] mod_dptools.c:1498 SET sofia/external/7086872090@64.7.96.3 [forward_user_not_registered_enabled]=[UNDEF]
  338. EXECUTE sofia/external/7086872090@64.7.96.3 set(forward_user_not_registered_destination=)
  339. 2016-05-11 03:05:04.680920 [DEBUG] mod_dptools.c:1498 SET sofia/external/7086872090@64.7.96.3 [forward_user_not_registered_destination]=[UNDEF]
  340. EXECUTE sofia/external/7086872090@64.7.96.3 set(do_not_disturb=)
  341. 2016-05-11 03:05:04.680920 [DEBUG] mod_dptools.c:1498 SET sofia/external/7086872090@64.7.96.3 [do_not_disturb]=[UNDEF]
  342. EXECUTE sofia/external/7086872090@64.7.96.3 set(call_timeout=30)
  343. 2016-05-11 03:05:04.680920 [DEBUG] mod_dptools.c:1498 SET sofia/external/7086872090@64.7.96.3 [call_timeout]=[30]
  344. EXECUTE sofia/external/7086872090@64.7.96.3 set(missed_call_app=)
  345. 2016-05-11 03:05:04.680920 [DEBUG] mod_dptools.c:1498 SET sofia/external/7086872090@64.7.96.3 [missed_call_app]=[UNDEF]
  346. EXECUTE sofia/external/7086872090@64.7.96.3 set(missed_call_data=)
  347. 2016-05-11 03:05:04.700921 [DEBUG] mod_dptools.c:1498 SET sofia/external/7086872090@64.7.96.3 [missed_call_data]=[UNDEF]
  348. EXECUTE sofia/external/7086872090@64.7.96.3 export(origination_callee_id_name=1001)
  349. 2016-05-11 03:05:04.700921 [DEBUG] switch_channel.c:1293 EXPORT (export_vars) [origination_callee_id_name]=[1001]
  350. EXECUTE sofia/external/7086872090@64.7.96.3 set(RFC2822_DATE=Wed, 11 May 2016 03:05:04 -0500)
  351. 2016-05-11 03:05:04.700921 [DEBUG] mod_dptools.c:1498 SET sofia/external/7086872090@64.7.96.3 [RFC2822_DATE]=[Wed, 11 May 2016 03:05:04 -0500]
  352. EXECUTE sofia/external/7086872090@64.7.96.3 hash(insert/besindustries.urbancom.net-last_dial/7086872090/1001)
  353. EXECUTE sofia/external/7086872090@64.7.96.3 eval(not_secure)
  354. EXECUTE sofia/external/7086872090@64.7.96.3 limit(hash besindustries.urbancom.net 1001  )
  355. 2016-05-11 03:05:04.700921 [DEBUG] switch_limit.c:126 incr called: besindustries.urbancom.net_1001 max:-1, interval:0
  356. 2016-05-11 03:05:04.700921 [DEBUG] mod_hash.c:194 Usage for besindustries.urbancom.net_1001 is now 1
  357. EXECUTE sofia/external/7086872090@64.7.96.3 bind_digit_action(local,*1,exec:execute_extension,dx XML besindustries.urbancom.net,both)
  358. 2016-05-11 03:05:04.700921 [INFO] switch_ivr_async.c:214 Digit parser DPTOOLS: Setting realm to 'local'
  359. 2016-05-11 03:05:04.700921 [DEBUG] switch_ivr_async.c:323 Digit parser DPTOOLS: binding *1/local/0 callback: 0x7fb2d5279400 data: 0x7fb250031b48
  360. 2016-05-11 03:05:04.700921 [INFO] switch_ivr_async.c:214 Digit parser DPTOOLS: Setting realm to 'local'
  361. 2016-05-11 03:05:04.700921 [DEBUG] switch_ivr_async.c:323 Digit parser DPTOOLS: binding *1/local/0 callback: 0x7fb2d5279400 data: 0x7fb250031bc8
  362. EXECUTE sofia/external/7086872090@64.7.96.3 bind_digit_action(local,*2,exec:record_session,/var/lib/freeswitch/recordings/besindustries.urbancom.net/archive/2016/May/11/11828066-174f-11e6-b94d-8dcc9e1d3d65.wav,both)
  363. 2016-05-11 03:05:04.700921 [DEBUG] switch_ivr_async.c:323 Digit parser DPTOOLS: binding *2/local/0 callback: 0x7fb2d5279400 data: 0x7fb250031e38
  364. 2016-05-11 03:05:04.700921 [DEBUG] switch_ivr_async.c:323 Digit parser DPTOOLS: binding *2/local/0 callback: 0x7fb2d5279400 data: 0x7fb250031f08
  365. EXECUTE sofia/external/7086872090@64.7.96.3 bind_digit_action(local,*3,exec:execute_extension,cf XML besindustries.urbancom.net,both)
  366. 2016-05-11 03:05:04.700921 [DEBUG] switch_ivr_async.c:323 Digit parser DPTOOLS: binding *3/local/0 callback: 0x7fb2d5279400 data: 0x7fb2500320c8
  367. 2016-05-11 03:05:04.700921 [DEBUG] switch_ivr_async.c:323 Digit parser DPTOOLS: binding *3/local/0 callback: 0x7fb2d5279400 data: 0x7fb250032148
  368. EXECUTE sofia/external/7086872090@64.7.96.3 bind_digit_action(local,*4,exec:execute_extension,att_xfer XML besindustries.urbancom.net,both)
  369. 2016-05-11 03:05:04.700921 [DEBUG] switch_ivr_async.c:323 Digit parser DPTOOLS: binding *4/local/0 callback: 0x7fb2d5279400 data: 0x7fb2500322c8
  370. 2016-05-11 03:05:04.700921 [DEBUG] switch_ivr_async.c:323 Digit parser DPTOOLS: binding *4/local/0 callback: 0x7fb2d5279400 data: 0x7fb250032348
  371. EXECUTE sofia/external/7086872090@64.7.96.3 digit_action_set_realm(local)
  372. 2016-05-11 03:05:04.700921 [INFO] switch_ivr_async.c:214 Digit parser DPTOOLS: Setting realm to 'local'
  373. EXECUTE sofia/external/7086872090@64.7.96.3 set(hangup_after_bridge=true)
  374. 2016-05-11 03:05:04.700921 [DEBUG] mod_dptools.c:1498 SET sofia/external/7086872090@64.7.96.3 [hangup_after_bridge]=[true]
  375. EXECUTE sofia/external/7086872090@64.7.96.3 set(continue_on_fail=true)
  376. 2016-05-11 03:05:04.700921 [DEBUG] mod_dptools.c:1498 SET sofia/external/7086872090@64.7.96.3 [continue_on_fail]=[true]
  377. EXECUTE sofia/external/7086872090@64.7.96.3 hash(insert/besindustries.urbancom.net-call_return/1001/7086872090)
  378. EXECUTE sofia/external/7086872090@64.7.96.3 hash(insert/besindustries.urbancom.net-last_dial_ext/1001/11828066-174f-11e6-b94d-8dcc9e1d3d65)
  379. EXECUTE sofia/external/7086872090@64.7.96.3 set(called_party_call_group=)
  380. 2016-05-11 03:05:04.700921 [DEBUG] mod_dptools.c:1498 SET sofia/external/7086872090@64.7.96.3 [called_party_call_group]=[UNDEF]
  381. EXECUTE sofia/external/7086872090@64.7.96.3 hash(insert/besindustries.urbancom.net-last_dial//11828066-174f-11e6-b94d-8dcc9e1d3d65)
  382. EXECUTE sofia/external/7086872090@64.7.96.3 set(api_hangup_hook=lua app.lua hangup)
  383. 2016-05-11 03:05:04.700921 [DEBUG] mod_dptools.c:1498 SET sofia/external/7086872090@64.7.96.3 [api_hangup_hook]=[lua app.lua hangup]
  384. EXECUTE sofia/external/7086872090@64.7.96.3 bridge(user/1001@besindustries.urbancom.net)
  385. 2016-05-11 03:05:04.700921 [DEBUG] switch_channel.c:1247 sofia/external/7086872090@64.7.96.3 EXPORTING[export_vars] [dialed_extension]=[1001] to event
  386. 2016-05-11 03:05:04.700921 [DEBUG] switch_channel.c:1247 sofia/external/7086872090@64.7.96.3 EXPORTING[export_vars] [origination_callee_id_name]=[1001] to event
  387. 2016-05-11 03:05:04.700921 [DEBUG] switch_ivr_originate.c:2128 Parsing global variables
  388. 2016-05-11 03:05:04.720903 [DEBUG] switch_channel.c:1247 sofia/external/7086872090@64.7.96.3 EXPORTING[export_vars] [dialed_extension]=[1001] to event
  389. 2016-05-11 03:05:04.720903 [DEBUG] switch_channel.c:1247 sofia/external/7086872090@64.7.96.3 EXPORTING[export_vars] [origination_callee_id_name]=[1001] to event
  390. 2016-05-11 03:05:04.720903 [DEBUG] switch_ivr_originate.c:2128 Parsing global variables
  391. 2016-05-11 03:05:04.720903 [NOTICE] switch_channel.c:1101 New Channel sofia/internal/1001@73.72.98.76:5060 [11a47b6c-174f-11e6-b982-8dcc9e1d3d65]
  392. 2016-05-11 03:05:04.720903 [DEBUG] mod_sofia.c:4776 (sofia/internal/1001@73.72.98.76:5060) State Change CS_NEW -> CS_INIT
  393. 2016-05-11 03:05:04.720903 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1001@73.72.98.76:5060) Running State Change CS_INIT
  394. 2016-05-11 03:05:04.720903 [DEBUG] switch_core_state_machine.c:516 (sofia/internal/1001@73.72.98.76:5060) State INIT
  395. 2016-05-11 03:05:04.720903 [DEBUG] mod_sofia.c:88 sofia/internal/1001@73.72.98.76:5060 SOFIA INIT
  396. 2016-05-11 03:05:04.720903 [DEBUG] sofia_glue.c:1257 sofia/internal/1001@73.72.98.76:5060 sending invite version: 1.6.6  64bit
  397. Local SDP:
  398. v=0
  399. o=FreeSWITCH 1462924644 1462924645 IN IP4 64.7.99.27
  400. s=FreeSWITCH
  401. c=IN IP4 64.7.99.27
  402. t=0 0
  403. m=audio 29260 RTP/AVP 0 101 13
  404. a=rtpmap:0 PCMU/8000
  405. a=rtpmap:101 telephone-event/8000
  406. a=fmtp:101 0-16
  407. a=rtpmap:13 CN/8000
  408. a=ptime:20
  409. a=sendrecv
  410.  
  411. 2016-05-11 03:05:04.720903 [DEBUG] switch_core_state_machine.c:40 sofia/internal/1001@73.72.98.76:5060 Standard INIT
  412. 2016-05-11 03:05:04.720903 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/1001@73.72.98.76:5060) State Change CS_INIT -> CS_ROUTING
  413. 2016-05-11 03:05:04.720903 [DEBUG] switch_core_state_machine.c:516 (sofia/internal/1001@73.72.98.76:5060) State INIT going to sleep
  414. 2016-05-11 03:05:04.720903 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1001@73.72.98.76:5060) Running State Change CS_ROUTING
  415. 2016-05-11 03:05:04.720903 [DEBUG] switch_core_state_machine.c:532 (sofia/internal/1001@73.72.98.76:5060) State ROUTING
  416. 2016-05-11 03:05:04.720903 [DEBUG] mod_sofia.c:141 sofia/internal/1001@73.72.98.76:5060 SOFIA ROUTING
  417. 2016-05-11 03:05:04.720903 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/1001@73.72.98.76:5060) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  418. 2016-05-11 03:05:04.720903 [DEBUG] switch_core_state_machine.c:532 (sofia/internal/1001@73.72.98.76:5060) State ROUTING going to sleep
  419. send 1231 bytes to udp/[73.72.98.76]:5060 at 03:05:04.724623:
  420.    ------------------------------------------------------------------------
  421.    INVITE sip:1001@73.72.98.76:5060 SIP/2.0
  422.    Via: SIP/2.0/UDP 64.7.99.27;rport;branch=z9hG4bKaUmBNmUZB1F0Q
  423.    Max-Forwards: 67
  424.    From: "Brent Sachnoff#Brent Sachnoff" <sip:7086872090@besindustries.urbancom.net>;tag=4g332eaZj4FyN
  425.    To: <sip:1001@73.72.98.76:5060>
  426.    Call-ID: e9030588-91f1-1234-b3a4-005056b9942b
  427.    CSeq: 91140888 INVITE
  428.    Contact: <sip:mod_sofia@64.7.99.27:5060>
  429.    User-Agent: FreeSWITCH
  430.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  431.    Supported: timer, path, replaces
  432.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  433.    Content-Type: application/sdp
  434.    Content-Disposition: session
  435.    Content-Length: 240
  436.    P-hint: mediaproxy applied
  437.    X-FS-Support: update_display,send_info
  438. 2016-05-11 03:05:04.720903 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1001@73.72.98.76:5060) Running State Change CS_CONSUME_MEDIA
  439.    Remote-Party-ID: "Brent Sachnoff#Brent Sachnoff" <sip:7086872090@besindustries.urbancom.net>;party=calling;screen=yes;privacy=off
  440.    
  441.    v=0
  442.    o=FreeSWITCH 1462924644 1462924645 IN IP4 64.7.99.27
  443.    s=FreeSWITCH
  444.    c=IN IP4 64.7.99.27
  445.    t=0 0
  446.    m=audio 29260 RTP/AVP 0 101 13
  447.    a=rtpmap:0 PCMU/8000
  448.    a=rtpmap:101 telephone-event/8000
  449.    a=fmtp:101 0-16
  450.    a=rtpmap:13 CN/8000
  451.    a=ptime:20
  452.    ------------------------------------------------------------------------
  453. 2016-05-11 03:05:04.720903 [DEBUG] switch_core_state_machine.c:551 (sofia/internal/1001@73.72.98.76:5060) State CONSUME_MEDIA
  454. 2016-05-11 03:05:04.720903 [DEBUG] switch_core_state_machine.c:551 (sofia/internal/1001@73.72.98.76:5060) State CONSUME_MEDIA going to sleep
  455. 2016-05-11 03:05:04.720903 [DEBUG] sofia.c:6760 Channel sofia/internal/1001@73.72.98.76:5060 entering state [calling][0]
  456. recv 394 bytes from udp/[73.72.98.76]:5060 at 03:05:04.748741:
  457.    ------------------------------------------------------------------------
  458.    SIP/2.0 100 Trying
  459.    Call-ID: e9030588-91f1-1234-b3a4-005056b9942b
  460.    CSeq: 91140888 INVITE
  461.    Content-Length: 0
  462.    From: "Brent Sachnoff#Brent Sachnoff" <sip:7086872090@besindustries.urbancom.net>;tag=4g332eaZj4FyN
  463.    To: <sip:1001@73.72.98.76:5060>;tag=SP1935f3a4edaa5781a
  464.    Via: SIP/2.0/UDP 10.20.12.27;branch=z9hG4bKaUmBNmUZB1F0Q;received=10.20.12.27;rport=5060
  465.    Server: OBIHAI/OBi1062-5.0.0.3223
  466.    
  467.    ------------------------------------------------------------------------
  468. recv 575 bytes from udp/[73.72.98.76]:5060 at 03:05:05.027017:
  469.    ------------------------------------------------------------------------
  470.    SIP/2.0 180 Ringing
  471.    Call-ID: e9030588-91f1-1234-b3a4-005056b9942b
  472.    CSeq: 91140888 INVITE
  473.    Content-Length: 0
  474.    From: "Brent Sachnoff#Brent Sachnoff" <sip:7086872090@besindustries.urbancom.net>;tag=4g332eaZj4FyN
  475.    To: <sip:1001@73.72.98.76:5060>;tag=SP1935f3a4edaa5781a
  476.    Via: SIP/2.0/UDP 10.20.12.27;branch=z9hG4bKaUmBNmUZB1F0Q;received=10.20.12.27;rport=5060
  477.    Server: OBIHAI/OBi1062-5.0.0.3223
  478.    Contact: <sip:1001@73.72.98.76:5060>
  479.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  480.    Allow-Events: hold,talk,dialog
  481.    
  482.    ------------------------------------------------------------------------
  483. 2016-05-11 03:05:05.020998 [DEBUG] sofia.c:6760 Channel sofia/internal/1001@73.72.98.76:5060 entering state [proceeding][180]
  484. 2016-05-11 03:05:05.020998 [NOTICE] sofia.c:6862 Ring-Ready sofia/internal/1001@73.72.98.76:5060!
  485. 2016-05-11 03:05:05.020998 [DEBUG] switch_channel.c:3340 (sofia/internal/1001@73.72.98.76:5060) Callstate Change DOWN -> RINGING
  486. 2016-05-11 03:05:05.040976 [INFO] switch_ivr_originate.c:1216 Sending early media
  487. 2016-05-11 03:05:05.040976 [DEBUG] switch_core_media.c:6033 AUDIO RTP [sofia/external/7086872090@64.7.96.3] 10.20.12.27 port 27174 -> 64.7.96.3 port 44250 codec: 0 ms: 20
  488. 2016-05-11 03:05:05.040976 [DEBUG] switch_rtp.c:3802 Starting timer [soft] 160 bytes per 20ms
  489. 2016-05-11 03:05:05.040976 [DEBUG] switch_core_media.c:6332 sofia/external/7086872090@64.7.96.3 Set 2833 dtmf send payload to 101
  490. 2016-05-11 03:05:05.040976 [DEBUG] switch_core_media.c:6339 sofia/external/7086872090@64.7.96.3 Set 2833 dtmf receive payload to 101
  491. 2016-05-11 03:05:05.040976 [DEBUG] switch_core_media.c:6362 sofia/external/7086872090@64.7.96.3 Set rtp dtmf delay to 40
  492. 2016-05-11 03:05:05.040976 [DEBUG] mod_sofia.c:2330 Ring SDP:
  493. v=0
  494. o=FreeSWITCH 1462926731 1462926732 IN IP4 64.7.99.27
  495. s=FreeSWITCH
  496. c=IN IP4 64.7.99.27
  497. t=0 0
  498. m=audio 27174 RTP/AVP 0 101
  499. a=rtpmap:0 PCMU/8000
  500. a=rtpmap:101 telephone-event/8000
  501. a=fmtp:101 0-16
  502. a=ptime:20
  503. a=sendrecv
  504.  
  505. 2016-05-11 03:05:05.040976 [NOTICE] mod_sofia.c:2333 Pre-Answer sofia/external/7086872090@64.7.96.3!
  506. 2016-05-11 03:05:05.040976 [DEBUG] switch_channel.c:3468 (sofia/external/7086872090@64.7.96.3) Callstate Change RINGING -> EARLY
  507. 2016-05-11 03:05:05.040976 [DEBUG] switch_ivr_originate.c:1274 Raw Codec Activation Success L16@8000hz 1 channel 20ms
  508. 2016-05-11 03:05:05.040976 [DEBUG] switch_core_codec.c:221 sofia/external/7086872090@64.7.96.3 Push codec L16:100
  509. 2016-05-11 03:05:05.040976 [DEBUG] switch_ivr_originate.c:1343 Play Ringback Tone [%(2000,4000,440,480)]
  510. send 1171 bytes to udp/[64.7.96.3]:5060 at 03:05:05.050024:
  511.    ------------------------------------------------------------------------
  512.    SIP/2.0 183 Session Progress
  513.    Via: SIP/2.0/UDP 64.7.96.3;branch=z9hG4bKfe59.feb100c6.0
  514.    Via: SIP/2.0/UDP 64.7.96.6:5060;branch=z9hG4bK194a11786434f
  515.    Record-Route: <sip:64.7.96.3;lr=on;ftag=eea75a70-f420-4416-8968-d6e5dfadb3f4-30174388;mp=yes;vsf=AAAAAAEACAB4AQYeDh55ABoYNy45Ni4z>
  516.    From: "Brent Sachnoff" <sip:7086872090@64.7.96.3>;tag=eea75a70-f420-4416-8968-d6e5dfadb3f4-30174388
  517.    To: <sip:16303437370@64.7.96.3>;tag=pj7BjD4ZFQ1DQ
  518.    Call-ID: ffe3d80-7321e7b0-bfc3-6600740@64.7.96.6
  519.    CSeq: 101 INVITE
  520.    Contact: <sip:16303437370@64.7.99.27:5080;transport=udp>
  521.    User-Agent: FreeSWITCH
  522.    Accept: application/sdp
  523.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  524.    Supported: timer, path, replaces
  525.    Allow-Events: talk, hold, conference, refer
  526.    Content-Type: application/sdp
  527.    Content-Disposition: session
  528.    Content-Length: 216
  529.    Remote-Party-ID: "6303437370" <sip:6303437370@64.7.96.3>;party=calling;privacy=off;screen=no
  530.    
  531.    v=0
  532.    o=FreeSWITCH 1462926731 1462926732 IN IP4 64.7.99.27
  533.    s=FreeSWITCH
  534.    c=IN IP4 64.7.99.27
  535.    t=0 0
  536.    m=audio 27174 RTP/AVP 0 101
  537.    a=rtpmap:0 PCMU/8000
  538.    a=rtpmap:101 telephone-event/8000
  539.    a=fmtp:101 0-16
  540.    a=ptime:20
  541.    ------------------------------------------------------------------------
  542. 2016-05-11 03:05:05.060917 [DEBUG] sofia.c:6760 Channel sofia/external/7086872090@64.7.96.3 entering state [early][183]
  543. 2016-05-11 03:05:05.100897 [DEBUG] switch_rtp.c:6654 Correct audio ip/port confirmed.
  544. recv 955 bytes from udp/[73.72.98.76]:5060 at 03:05:06.945970:
  545.    ------------------------------------------------------------------------
  546.    SIP/2.0 200 OK
  547.    Call-ID: e9030588-91f1-1234-b3a4-005056b9942b
  548.    CSeq: 91140888 INVITE
  549.    Content-Length: 229
  550.    From: "Brent Sachnoff#Brent Sachnoff" <sip:7086872090@besindustries.urbancom.net>;tag=4g332eaZj4FyN
  551.    To: <sip:1001@73.72.98.76:5060>;tag=SP1935f3a4edaa5781a
  552.    Via: SIP/2.0/UDP 10.20.12.27;branch=z9hG4bKaUmBNmUZB1F0Q;received=10.20.12.27;rport=5060
  553.    Server: OBIHAI/OBi1062-5.0.0.3223
  554.    Contact: <sip:1001@73.72.98.76:5060>
  555.    Supported: replaces, eventlist, record-aware
  556.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  557.    Accept: application/vnd.etsi.aoc+xml,application/dtmf-relay,application/sdp
  558.    Allow-Events: hold,talk,dialog
  559.    Content-Type: application/sdp
  560.    
  561.    v=0
  562.    o=- 12418691 1 IN IP4 73.72.98.76
  563.    s=-
  564.    c=IN IP4 73.72.98.76
  565.    t=0 0
  566.    m=audio 16606 RTP/AVP 0 101
  567.    a=rtpmap:0 PCMU/8000
  568.    a=rtpmap:101 telephone-event/8000
  569.    a=fmtp:101 0-15
  570.    a=sendrecv
  571.    a=ptime:20
  572.    a=xg726bitorder:big-endian
  573.    ------------------------------------------------------------------------
  574. 2016-05-11 03:05:06.940917 [DEBUG] sofia.c:6760 Channel sofia/internal/1001@73.72.98.76:5060 entering state [completing][200]
  575. 2016-05-11 03:05:06.940917 [DEBUG] sofia.c:6770 Remote SDP:
  576. v=0
  577. o=- 12418691 1 IN IP4 73.72.98.76
  578. s=-
  579. c=IN IP4 73.72.98.76
  580. t=0 0
  581. m=audio 16606 RTP/AVP 0 101
  582. a=rtpmap:0 PCMU/8000
  583. a=rtpmap:101 telephone-event/8000
  584. a=fmtp:101 0-15
  585. a=ptime:20
  586. a=xg726bitorder:big-endian
  587.  
  588. send 408 bytes to udp/[73.72.98.76]:5060 at 03:05:06.948349:
  589.    ------------------------------------------------------------------------
  590.    ACK sip:1001@73.72.98.76:5060 SIP/2.0
  591.    Via: SIP/2.0/UDP 64.7.99.27;rport;branch=z9hG4bKB4D4pFc3895jK
  592.    Max-Forwards: 70
  593.    From: "Brent Sachnoff#Brent Sachnoff" <sip:7086872090@besindustries.urbancom.net>;tag=4g332eaZj4FyN
  594.    To: <sip:1001@73.72.98.76:5060>;tag=SP1935f3a4edaa5781a
  595.    Call-ID: e9030588-91f1-1234-b3a4-005056b9942b
  596.    CSeq: 91140888 ACK
  597.    Contact: <sip:mod_sofia@64.7.99.27:5060>
  598.    Content-Length: 0
  599.    
  600.    ------------------------------------------------------------------------
  601. 2016-05-11 03:05:06.940917 [DEBUG] sofia.c:6760 Channel sofia/internal/1001@73.72.98.76:5060 entering state [ready][200]
  602. 2016-05-11 03:05:06.940917 [DEBUG] switch_core_media.c:4161 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  603. 2016-05-11 03:05:06.940917 [DEBUG] switch_core_media.c:4216 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  604. 2016-05-11 03:05:06.940917 [DEBUG] switch_core_media.c:4077 Set telephone-event payload to 101@8000
  605. 2016-05-11 03:05:06.940917 [DEBUG] switch_core_media.c:2906 Set Codec sofia/internal/1001@73.72.98.76:5060 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
  606. 2016-05-11 03:05:06.940917 [DEBUG] switch_core_codec.c:111 sofia/internal/1001@73.72.98.76:5060 Original read codec set to PCMU:0
  607. 2016-05-11 03:05:06.940917 [DEBUG] switch_core_media.c:4429 Set telephone-event payload to 101@8000
  608. 2016-05-11 03:05:06.940917 [DEBUG] switch_core_media.c:4485 sofia/internal/1001@73.72.98.76:5060 Set 2833 dtmf send payload to 101 recv payload to 101
  609. 2016-05-11 03:05:06.940917 [DEBUG] switch_core_media.c:6033 AUDIO RTP [sofia/internal/1001@73.72.98.76:5060] 10.20.12.27 port 29260 -> 73.72.98.76 port 16606 codec: 0 ms: 20
  610. 2016-05-11 03:05:06.940917 [DEBUG] switch_rtp.c:3802 Starting timer [soft] 160 bytes per 20ms
  611. 2016-05-11 03:05:06.940917 [DEBUG] switch_core_media.c:6332 sofia/internal/1001@73.72.98.76:5060 Set 2833 dtmf send payload to 101
  612. 2016-05-11 03:05:06.940917 [DEBUG] switch_core_media.c:6339 sofia/internal/1001@73.72.98.76:5060 Set 2833 dtmf receive payload to 101
  613. 2016-05-11 03:05:06.940917 [DEBUG] switch_core_media.c:6362 sofia/internal/1001@73.72.98.76:5060 Set rtp dtmf delay to 40
  614. 2016-05-11 03:05:06.940917 [NOTICE] sofia.c:7724 Channel [sofia/internal/1001@73.72.98.76:5060] has been answered
  615. 2016-05-11 03:05:06.940917 [DEBUG] switch_channel.c:3767 (sofia/internal/1001@73.72.98.76:5060) Callstate Change RINGING -> ACTIVE
  616. 2016-05-11 03:05:06.960923 [DEBUG] switch_core_codec.c:246 sofia/external/7086872090@64.7.96.3 Restore previous codec PCMU:0.
  617. 2016-05-11 03:05:06.960923 [DEBUG] mod_sofia.c:799 Local SDP sofia/external/7086872090@64.7.96.3:
  618. v=0
  619. o=FreeSWITCH 1462926731 1462926733 IN IP4 64.7.99.27
  620. s=FreeSWITCH
  621. c=IN IP4 64.7.99.27
  622. t=0 0
  623. m=audio 27174 RTP/AVP 0 101
  624. a=rtpmap:0 PCMU/8000
  625. a=rtpmap:101 telephone-event/8000
  626. a=fmtp:101 0-16
  627. a=ptime:20
  628. a=sendrecv
  629.  
  630. send 1173 bytes to udp/[64.7.96.3]:5060 at 03:05:06.967610:
  631.    ------------------------------------------------------------------------
  632.    SIP/2.0 200 OK
  633.    Via: SIP/2.0/UDP 64.7.96.3;branch=z9hG4bKfe59.feb100c6.0
  634.    Via: SIP/2.0/UDP 64.7.96.6:5060;branch=z9hG4bK194a11786434f
  635. 2016-05-11 03:05:06.960923 [NOTICE] switch_ivr_originate.c:3550 Channel [sofia/external/7086872090@64.7.96.3] has been answered
  636.    Record-Route: <sip:64.7.96.3;lr=on;ftag=eea75a70-f420-4416-8968-d6e5dfadb3f4-30174388;mp=yes;vsf=AAAAAAEACAB4AQYeDh55ABoYNy45Ni4z>
  637.    From: "Brent Sachnoff" <sip:7086872090@64.7.96.3>;tag=eea75a70-f420-4416-8968-d6e5dfadb3f4-30174388
  638.    To: <sip:16303437370@64.7.96.3>;tag=pj7BjD4ZFQ1DQ
  639.    Call-ID: ffe3d80-7321e7b0-bfc3-6600740@64.7.96.6
  640.    CSeq: 101 INVITE
  641.    Contact: <sip:16303437370@64.7.99.27:5080;transport=udp>
  642.    User-Agent: FreeSWITCH
  643.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  644.    Require: timer
  645.    Supported: timer, path, replaces
  646.    Allow-Events: talk, hold, conference, refer
  647.    Session-Expires: 1800;refresher=uac
  648.    Content-Type: application/sdp
  649.    Content-Disposition: session
  650.    Content-Length: 216
  651.    Remote-Party-ID: "1001" <sip:1001@64.7.96.3>;party=calling;privacy=off;screen=no
  652.    
  653.    v=0
  654.    o=FreeSWITCH 1462926731 1462926732 IN IP4 64.7.99.27
  655.    s=FreeSWITCH
  656.    c=IN IP4 64.7.99.27
  657.    t=0 0
  658.    m=audio 27174 RTP/AVP 0 101
  659.    a=rtpmap:0 PCMU/8000
  660.    a=rtpmap:101 telephone-event/8000
  661.    a=fmtp:101 0-16
  662.    a=ptime:20
  663.    ------------------------------------------------------------------------
  664. 2016-05-11 03:05:06.960923 [DEBUG] switch_channel.c:3767 (sofia/external/7086872090@64.7.96.3) Callstate Change EARLY -> ACTIVE
  665. 2016-05-11 03:05:06.960923 [DEBUG] sofia.c:6760 Channel sofia/external/7086872090@64.7.96.3 entering state [completed][200]
  666. 2016-05-11 03:05:06.960923 [DEBUG] switch_ivr_originate.c:3608 Originate Resulted in Success: [sofia/internal/1001@73.72.98.76:5060]
  667. 2016-05-11 03:05:06.960923 [DEBUG] switch_ivr_originate.c:3608 Originate Resulted in Success: [sofia/internal/1001@73.72.98.76:5060]
  668. 2016-05-11 03:05:06.960923 [DEBUG] switch_ivr_bridge.c:1591 (sofia/internal/1001@73.72.98.76:5060) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  669. 2016-05-11 03:05:06.960923 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1001@73.72.98.76:5060) Running State Change CS_EXCHANGE_MEDIA
  670. 2016-05-11 03:05:06.960923 [DEBUG] switch_core_state_machine.c:542 (sofia/internal/1001@73.72.98.76:5060) State EXCHANGE_MEDIA
  671. 2016-05-11 03:05:06.960923 [DEBUG] mod_sofia.c:613 SOFIA EXCHANGE_MEDIA
  672. recv 610 bytes from udp/[64.7.96.3]:5060 at 03:05:06.985481:
  673.    ------------------------------------------------------------------------
  674.    ACK sip:16303437370@64.7.99.27:5080;transport=udp SIP/2.0
  675.    Record-Route: <sip:64.7.96.3;lr=on;ftag=eea75a70-f420-4416-8968-d6e5dfadb3f4-30174388>
  676.    Via: SIP/2.0/UDP 64.7.96.3;branch=z9hG4bKfe59.feb100c6.2
  677.    Via: SIP/2.0/UDP 64.7.96.6:5060;branch=z9hG4bK194a25d5cacb9
  678.    From: "Brent Sachnoff" <sip:7086872090@64.7.96.3>;tag=eea75a70-f420-4416-8968-d6e5dfadb3f4-30174388
  679.    To: <sip:16303437370@64.7.96.3>;tag=pj7BjD4ZFQ1DQ
  680.    Date: Wed, 11 May 2016 08:05:04 GMT
  681.    Call-ID: ffe3d80-7321e7b0-bfc3-6600740@64.7.96.6
  682.    Max-Forwards: 69
  683.    CSeq: 101 ACK
  684.    Allow-Events: presence, kpml
  685.    Content-Length: 0
  686.    P-hint: rr-enforced
  687.    
  688.    ------------------------------------------------------------------------
  689. 2016-05-11 03:05:06.980903 [DEBUG] sofia.c:6760 Channel sofia/external/7086872090@64.7.96.3 entering state [ready][200]
  690. 2016-05-11 03:05:07.020906 [DEBUG] switch_rtp.c:6654 Correct audio ip/port confirmed.
  691. 2016-05-11 03:05:07.440904 [DEBUG] switch_rtp.c:6654 Correct audio ip/port confirmed.
  692. recv 479 bytes from udp/[73.72.98.76]:5060 at 03:05:08.180976:
  693.    ------------------------------------------------------------------------
  694.    BYE sip:mod_sofia@10.20.12.27:5060 SIP/2.0
  695.    Call-ID: e9030588-91f1-1234-b3a4-005056b9942b
  696.    Content-Length: 0
  697.    CSeq: 8001 BYE
  698.    From: <sip:1001@172.20.21.151>;tag=SP1935f3a4edaa5781a
  699.    Max-Forwards: 70
  700.    To: "Brent Sachnoff#Brent Sachnoff" <sip:7086872090@besindustries.urbancom.net>;tag=4g332eaZj4FyN
  701.    Via: SIP/2.0/UDP 73.72.98.76:5060;branch=z9hG4bK-f3fda812;rport
  702.    User-Agent: OBIHAI/OBi1062-5.0.0.3223
  703.    X-RTP-Stat: PS=35,OS=6020,PR=56,OR=9632,PL=0,JI=0,DU=0,EN=G711U,DE=G711U
  704.    
  705.    ------------------------------------------------------------------------
  706. 2016-05-11 03:05:08.180958 [NOTICE] sofia.c:952 Hangup sofia/internal/1001@73.72.98.76:5060 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
  707. 2016-05-11 03:05:08.180958 [DEBUG] switch_ivr_bridge.c:699 sofia/internal/1001@73.72.98.76:5060 ending bridge by request from write function
  708. send 493 bytes to udp/[73.72.98.76]:5060 at 03:05:08.187954:
  709.    ------------------------------------------------------------------------
  710.    SIP/2.0 200 OK
  711.    Via: SIP/2.0/UDP 73.72.98.76:5060;branch=z9hG4bK-f3fda812;rport=5060
  712.    From: <sip:1001@172.20.21.151>;tag=SP1935f3a4edaa5781a
  713.    To: "Brent Sachnoff#Brent Sachnoff" <sip:7086872090@besindustries.urbancom.net>;tag=4g332eaZj4FyN
  714.    Call-ID: e9030588-91f1-1234-b3a4-005056b9942b
  715.    CSeq: 8001 BYE
  716.    User-Agent: FreeSWITCH
  717.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  718.    Supported: timer, path, replaces
  719.    Content-Length: 0
  720.    
  721.    ------------------------------------------------------------------------
  722. 2016-05-11 03:05:08.180958 [DEBUG] switch_ivr_bridge.c:778 BRIDGE THREAD DONE [sofia/external/7086872090@64.7.96.3]
  723. 2016-05-11 03:05:08.180958 [DEBUG] switch_ivr_bridge.c:1689 sofia/internal/1001@73.72.98.76:5060 skip receive message [UNBRIDGE] (channel is hungup already)
  724. 2016-05-11 03:05:08.180958 [NOTICE] switch_ivr_bridge.c:1741 Hangup sofia/external/7086872090@64.7.96.3 [CS_EXECUTE] [NORMAL_CLEARING]
  725. 2016-05-11 03:05:08.180958 [DEBUG] mod_hash.c:297 Usage for besindustries.urbancom.net_1001 is now 0
  726. 2016-05-11 03:05:08.180958 [DEBUG] switch_core_session.c:2796 sofia/external/7086872090@64.7.96.3 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  727. 2016-05-11 03:05:08.180958 [DEBUG] switch_core_state_machine.c:539 (sofia/external/7086872090@64.7.96.3) State EXECUTE going to sleep
  728. 2016-05-11 03:05:08.180958 [DEBUG] switch_core_state_machine.c:473 (sofia/external/7086872090@64.7.96.3) Running State Change CS_HANGUP
  729. 2016-05-11 03:05:08.180958 [DEBUG] switch_core_state_machine.c:739 (sofia/external/7086872090@64.7.96.3) Callstate Change ACTIVE -> HANGUP
  730. 2016-05-11 03:05:08.180958 [DEBUG] switch_core_state_machine.c:741 (sofia/external/7086872090@64.7.96.3) State HANGUP
  731. 2016-05-11 03:05:08.180958 [DEBUG] mod_sofia.c:425 sofia/external/7086872090@64.7.96.3 Overriding SIP cause 480 with 200 from the other leg
  732. 2016-05-11 03:05:08.180958 [DEBUG] mod_sofia.c:431 Channel sofia/external/7086872090@64.7.96.3 hanging up, cause: NORMAL_CLEARING
  733. 2016-05-11 03:05:08.180958 [DEBUG] mod_sofia.c:484 Sending BYE to sofia/external/7086872090@64.7.96.3
  734. 2016-05-11 03:05:08.180958 [DEBUG] switch_core_state_machine.c:60 sofia/external/7086872090@64.7.96.3 Standard HANGUP, cause: NORMAL_CLEARING
  735. 2016-05-11 03:05:08.180958 [DEBUG] switch_core_state_machine.c:741 (sofia/external/7086872090@64.7.96.3) State HANGUP going to sleep
  736. 2016-05-11 03:05:08.180958 [DEBUG] switch_ivr_bridge.c:705 sofia/internal/1001@73.72.98.76:5060 ending bridge by request from read function
  737. 2016-05-11 03:05:08.180958 [DEBUG] switch_ivr_bridge.c:778 BRIDGE THREAD DONE [sofia/internal/1001@73.72.98.76:5060]
  738. 2016-05-11 03:05:08.180958 [DEBUG] switch_core_state_machine.c:542 (sofia/internal/1001@73.72.98.76:5060) State EXCHANGE_MEDIA going to sleep
  739. 2016-05-11 03:05:08.180958 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1001@73.72.98.76:5060) Running State Change CS_HANGUP
  740. 2016-05-11 03:05:08.180958 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/1001@73.72.98.76:5060) Callstate Change ACTIVE -> HANGUP
  741. 2016-05-11 03:05:08.180958 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1001@73.72.98.76:5060) State HANGUP
  742. 2016-05-11 03:05:08.180958 [DEBUG] mod_sofia.c:431 Channel sofia/internal/1001@73.72.98.76:5060 hanging up, cause: NORMAL_CLEARING
  743. 2016-05-11 03:05:08.180958 [DEBUG] switch_core_state_machine.c:60 sofia/internal/1001@73.72.98.76:5060 Standard HANGUP, cause: NORMAL_CLEARING
  744. 2016-05-11 03:05:08.180958 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/1001@73.72.98.76:5060) State HANGUP going to sleep
  745. 2016-05-11 03:05:08.180958 [DEBUG] switch_core_state_machine.c:508 (sofia/internal/1001@73.72.98.76:5060) State Change CS_HANGUP -> CS_REPORTING
  746. 2016-05-11 03:05:08.180958 [DEBUG] switch_core_state_machine.c:473 (sofia/internal/1001@73.72.98.76:5060) Running State Change CS_REPORTING
  747. 2016-05-11 03:05:08.180958 [DEBUG] switch_core_state_machine.c:827 (sofia/internal/1001@73.72.98.76:5060) State REPORTING
  748. 2016-05-11 03:05:08.180958 [DEBUG] switch_core_state_machine.c:104 sofia/internal/1001@73.72.98.76:5060 Standard REPORTING, cause: NORMAL_CLEARING
  749. 2016-05-11 03:05:08.180958 [DEBUG] switch_core_state_machine.c:827 (sofia/internal/1001@73.72.98.76:5060) State REPORTING going to sleep
  750. send 760 bytes to udp/[64.7.96.3]:5060 at 03:05:08.190856:
  751.    ------------------------------------------------------------------------
  752.    BYE sip:6006@64.7.96.6:5060 SIP/2.0
  753.    Via: SIP/2.0/UDP 64.7.99.27:5080;rport;branch=z9hG4bKp4X0yeSQQ1KDH
  754.    Route: <sip:64.7.96.3;lr=on;ftag=eea75a70-f420-4416-8968-d6e5dfadb3f4-30174388;mp=yes;vsf=AAAAAAEACAB4AQYeDh55ABoYNy45Ni4z>
  755.    Max-Forwards: 70
  756.    From: <sip:16303437370@64.7.96.3>;tag=pj7BjD4ZFQ1DQ
  757.    To: "Brent Sachnoff" <sip:7086872090@64.7.96.3>;tag=eea75a70-f420-4416-8968-d6e5dfadb3f4-30174388
  758.    Call-ID: ffe3d80-7321e7b0-bfc3-6600740@64.7.96.6
  759.    CSeq: 91140890 BYE
  760.    User-Agent: FreeSWITCH
  761.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
  762.    Supported: timer, path, replaces
  763.    Reason: Q.850;cause=16;text="NORMAL_CLEARING"
  764.    Content-Length: 0
  765.    X-RTP-Stat: PS=35,OS=6020,PR=56,OR=9632,PL=0,JI=0,DU=0,EN=G711U,DE=G711U
  766.    
  767.    ------------------------------------------------------------------------
  768. 2016-05-11 03:05:08.180958 [DEBUG] switch_core_state_machine.c:499 (sofia/internal/1001@73.72.98.76:5060) State Change CS_REPORTING -> CS_DESTROY
  769. 2016-05-11 03:05:08.180958 [DEBUG] switch_core_session.c:1646 Session 26 (sofia/internal/1001@73.72.98.76:5060) Locked, Waiting on external entities
  770. 2016-05-11 03:05:08.180958 [NOTICE] switch_core_session.c:1664 Session 26 (sofia/internal/1001@73.72.98.76:5060) Ended
  771. 2016-05-11 03:05:08.180958 [NOTICE] switch_core_session.c:1668 Close Channel sofia/internal/1001@73.72.98.76:5060 [CS_DESTROY]
  772. 2016-05-11 03:05:08.180958 [DEBUG] switch_core_state_machine.c:630 (sofia/internal/1001@73.72.98.76:5060) Running State Change CS_DESTROY
  773. 2016-05-11 03:05:08.180958 [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1001@73.72.98.76:5060) State DESTROY
  774. 2016-05-11 03:05:08.180958 [DEBUG] mod_sofia.c:341 sofia/internal/1001@73.72.98.76:5060 SOFIA DESTROY
  775. 2016-05-11 03:05:08.180958 [DEBUG] switch_core_state_machine.c:111 sofia/internal/1001@73.72.98.76:5060 Standard DESTROY
  776. 2016-05-11 03:05:08.180958 [DEBUG] switch_core_state_machine.c:640 (sofia/internal/1001@73.72.98.76:5060) State DESTROY going to sleep
  777. 2016-05-11 03:05:08.180958 [DEBUG] switch_core_state_machine.c:672 Hangup Command with no Session lua(app.lua hangup):
  778.  
  779. 2016-05-11 03:05:08.180958 [DEBUG] switch_core_state_machine.c:508 (sofia/external/7086872090@64.7.96.3) State Change CS_HANGUP -> CS_REPORTING
  780. 2016-05-11 03:05:08.180958 [DEBUG] switch_core_state_machine.c:473 (sofia/external/7086872090@64.7.96.3) Running State Change CS_REPORTING
  781. 2016-05-11 03:05:08.180958 [DEBUG] switch_core_state_machine.c:827 (sofia/external/7086872090@64.7.96.3) State REPORTING
  782. recv 369 bytes from udp/[64.7.96.3]:5060 at 03:05:08.204640:
  783.    ------------------------------------------------------------------------
  784.    SIP/2.0 200 OK
  785.    Via: SIP/2.0/UDP 64.7.99.27:5080;rport=5080;branch=z9hG4bKp4X0yeSQQ1KDH
  786.    From: <sip:16303437370@64.7.96.3>;tag=pj7BjD4ZFQ1DQ
  787.    To: "Brent Sachnoff" <sip:7086872090@64.7.96.3>;tag=eea75a70-f420-4416-8968-d6e5dfadb3f4-30174388
  788.    Date: Wed, 11 May 2016 08:05:08 GMT
  789.    Call-ID: ffe3d80-7321e7b0-bfc3-6600740@64.7.96.6
  790.    CSeq: 91140890 BYE
  791.    Content-Length: 0
  792.    
  793.    ------------------------------------------------------------------------
  794. 2016-05-11 03:05:08.260888 [DEBUG] switch_core_state_machine.c:104 sofia/external/7086872090@64.7.96.3 Standard REPORTING, cause: NORMAL_CLEARING
  795. 2016-05-11 03:05:08.260888 [DEBUG] switch_core_state_machine.c:827 (sofia/external/7086872090@64.7.96.3) State REPORTING going to sleep
  796. 2016-05-11 03:05:08.260888 [DEBUG] switch_core_state_machine.c:499 (sofia/external/7086872090@64.7.96.3) State Change CS_REPORTING -> CS_DESTROY
  797. 2016-05-11 03:05:08.260888 [DEBUG] switch_core_session.c:1646 Session 25 (sofia/external/7086872090@64.7.96.3) Locked, Waiting on external entities
  798. 2016-05-11 03:05:08.260888 [NOTICE] switch_core_session.c:1664 Session 25 (sofia/external/7086872090@64.7.96.3) Ended
  799. 2016-05-11 03:05:08.260888 [NOTICE] switch_core_session.c:1668 Close Channel sofia/external/7086872090@64.7.96.3 [CS_DESTROY]
  800. 2016-05-11 03:05:08.260888 [DEBUG] switch_core_state_machine.c:630 (sofia/external/7086872090@64.7.96.3) Running State Change CS_DESTROY
  801. 2016-05-11 03:05:08.260888 [DEBUG] switch_core_state_machine.c:640 (sofia/external/7086872090@64.7.96.3) State DESTROY
  802. 2016-05-11 03:05:08.260888 [DEBUG] mod_sofia.c:341 sofia/external/7086872090@64.7.96.3 SOFIA DESTROY
  803. 2016-05-11 03:05:08.260888 [DEBUG] switch_core_state_machine.c:111 sofia/external/7086872090@64.7.96.3 Standard DESTROY
  804. 2016-05-11 03:05:08.260888 [DEBUG] switch_core_state_machine.c:640 (sofia/external/7086872090@64.7.96.3) State DESTROY going to sleep
  805. recv 850 bytes from udp/[73.72.98.76]:5060 at 03:05:17.622365:
  806.    ------------------------------------------------------------------------
  807.    REGISTER sip:besindustries.urbancom.net:5060 SIP/2.0
  808.    Call-ID: 606bcf41@172.20.21.151
  809.    Content-Length: 0
  810.    CSeq: 16653 REGISTER
  811.    From: <sip:1001@besindustries.urbancom.net>;tag=SP1f75f1c86bedf8eae
  812.    Max-Forwards: 70
  813.    To: <sip:1001@besindustries.urbancom.net>
  814.    Via: SIP/2.0/UDP 73.72.98.76:5060;branch=z9hG4bK-eff76ad7;rport
  815.    Authorization: Digest algorithm=MD5,nc=00000053,qop=auth,cnonce="5f3f9cfc",nonce="5d11441e-1749-11e6-b890-8dcc9e1d3d65",realm="besindustries.urbancom.net",response="dfcca36625722d7f5428a36c8c77d4fd",uri="sip:besindustries.urbancom.net:5060",username="1001"
  816.    User-Agent: OBIHAI/OBi1062-5.0.0.3223
  817.    Contact: <sip:1001@73.72.98.76:5060>;expires=60;+sip.instance="<urn:uuid:00000000-0000-0000-0000-9cadef700ee0>"
  818.   Allow: ACK,BYE,CANCEL,INFO,INVITE,NOTIFY,OPTIONS,PRACK,REFER,UPDATE
  819.    Supported: replaces, eventlist, record-aware
  820.    
  821.    ------------------------------------------------------------------------
  822. send 546 bytes to udp/[73.72.98.76]:5060 at 03:05:17.636531:
  823.    ------------------------------------------------------------------------
  824.    SIP/2.0 200 OK
  825.    Via: SIP/2.0/UDP 73.72.98.76:5060;branch=z9hG4bK-eff76ad7;rport=5060
  826.    From: <sip:1001@besindustries.urbancom.net>;tag=SP1f75f1c86bedf8eae
  827.    To: <sip:1001@besindustries.urbancom.net>;tag=5Svv49t2FD6gH
  828.    Call-ID: 606bcf41@172.20.21.151
  829.    CSeq: 16653 REGISTER
  830.    Contact: <sip:1001@73.72.98.76:5060>;expires=60
  831.    Date: Wed, 11 May 2016 08:05:17 GMT
  832.    User-Agent: FreeSWITCH
  833.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  834.    Supported: timer, path, replaces
  835.    Content-Length: 0
  836.    
  837.    ------------------------------------------------------------------------
  838. recv 850 bytes from udp/[73.72.98.76]:5060 at 03:05:47.650952:
  839.    ------------------------------------------------------------------------
  840.    REGISTER sip:besindustries.urbancom.net:5060 SIP/2.0
  841.    Call-ID: 606bcf41@172.20.21.151
  842.    Content-Length: 0
  843.    CSeq: 16654 REGISTER
  844.    From: <sip:1001@besindustries.urbancom.net>;tag=SP1f75f1c86bedf8eae
  845.    Max-Forwards: 70
  846.    To: <sip:1001@besindustries.urbancom.net>
  847.    Via: SIP/2.0/UDP 73.72.98.76:5060;branch=z9hG4bK-67d716eb;rport
  848.    Authorization: Digest algorithm=MD5,nc=00000054,qop=auth,cnonce="5f3f9cfc",nonce="5d11441e-1749-11e6-b890-8dcc9e1d3d65",realm="besindustries.urbancom.net",response="1cab14d805c3cd8691c80851cccc7622",uri="sip:besindustries.urbancom.net:5060",username="1001"
  849.    User-Agent: OBIHAI/OBi1062-5.0.0.3223
  850.    Contact: <sip:1001@73.72.98.76:5060>;expires=60;+sip.instance="<urn:uuid:00000000-0000-0000-0000-9cadef700ee0>"
  851.   Allow: ACK,BYE,CANCEL,INFO,INVITE,NOTIFY,OPTIONS,PRACK,REFER,UPDATE
  852.    Supported: replaces, eventlist, record-aware
  853.    
  854.    ------------------------------------------------------------------------
  855. send 546 bytes to udp/[73.72.98.76]:5060 at 03:05:47.666108:
  856.    ------------------------------------------------------------------------
  857.    SIP/2.0 200 OK
  858.    Via: SIP/2.0/UDP 73.72.98.76:5060;branch=z9hG4bK-67d716eb;rport=5060
  859.    From: <sip:1001@besindustries.urbancom.net>;tag=SP1f75f1c86bedf8eae
  860.    To: <sip:1001@besindustries.urbancom.net>;tag=62NN64B6cpv3c
  861.    Call-ID: 606bcf41@172.20.21.151
  862.    CSeq: 16654 REGISTER
  863.    Contact: <sip:1001@73.72.98.76:5060>;expires=60
  864.    Date: Wed, 11 May 2016 08:05:47 GMT
  865.    User-Agent: FreeSWITCH
  866.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  867.    Supported: timer, path, replaces
  868.    Content-Length: 0
  869.    
  870.    ------------------------------------------------------------------------
  871. freeswitch@internal>