FreeSWITCH Pastebin

Sip Trace

From TWS, 6 Years ago, written in FreeSWITCH.
- view diff
URL https://pastebin.freeswitch.org/view/a039b804
Embed
Download Paste or View Raw
  1.  
  2. recv 984 bytes from tcp/[172.23.3.29]:53546 at 15:47:26.767678:
  3.    ------------------------------------------------------------------------
  4.    INVITE sip:Hawkeye@demo.fusion.mgwnet.us;transport=tcp SIP/2.0
  5.    Via: SIP/2.0/TCP 172.23.3.29;branch=z9hG4bK74868a65906142DE
  6.    From: "222-Hulk" <sip:hulk@demo.fusion.mgwnet.us>;tag=1FC7DE3B-3ED17E44
  7.    To: <sip:Hawkeye@demo.fusion.mgwnet.us>
  8.    CSeq: 1 INVITE
  9.    Call-ID: 96da71f40b5a8db54241414750c34469
  10.    Contact: <sip:hulk@172.23.3.29;transport=tcp>
  11.   Allow: INVITE,ACK,BYE,CANCEL,OPTIONS,INFO,MESSAGE,SUBSCRIBE,NOTIFY,PRACK,UPDATE,REFER
  12.    User-Agent: PolycomVVX-VVX_310-UA/5.8.0.12848
  13.    Accept-Language: en
  14.    Supported: replaces,100rel
  15.    Allow-Events: conference,talk,hold
  16.    Max-Forwards: 70
  17.    Content-Type: application/sdp
  18.    Content-Length: 348
  19.  
  20.    v=0
  21.    o=- 1533325646 1533325646 IN IP4 172.23.3.29
  22.    s=Polycom IP Phone
  23.    c=IN IP4 172.23.3.29
  24.    t=0 0
  25.    a=sendrecv
  26.    m=audio 2230 RTP/AVP 9 102 0 8 18 127
  27.    a=rtpmap:9 G722/8000
  28.    a=rtpmap:102 G7221/16000
  29.    a=fmtp:102 bitrate=32000
  30.    a=rtpmap:0 PCMU/8000
  31.    a=rtpmap:8 PCMA/8000
  32.    a=rtpmap:18 G729/8000
  33.    a=fmtp:18 annexb=no
  34.    a=rtpmap:127 telephone-event/8000
  35.    ------------------------------------------------------------------------
  36. send 311 bytes to tcp/[172.23.3.29]:53546 at 15:47:26.767892:
  37.    ------------------------------------------------------------------------
  38.    SIP/2.0 100 Trying
  39.    Via: SIP/2.0/TCP 172.23.3.29;branch=z9hG4bK74868a65906142DE;rport=53546
  40.    From: "222-Hulk" <sip:hulk@demo.fusion.mgwnet.us>;tag=1FC7DE3B-3ED17E44
  41.    To: <sip:Hawkeye@demo.fusion.mgwnet.us>
  42.    Call-ID: 96da71f40b5a8db54241414750c34469
  43.    CSeq: 1 INVITE
  44.    User-Agent: FreeSWITCH
  45.    Content-Length: 0
  46.  
  47.    ------------------------------------------------------------------------
  48. 2018-08-03 15:47:26.764703 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/hulk@demo.fusion.mgwnet.us [0c82cb0a-9756-11e8-8181-815e756dd19a]
  49. 2018-08-03 15:47:26.764703 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/hulk@demo.fusion.mgwnet.us) Running State Change CS_NEW (Cur 1 Tot 839)
  50. 2018-08-03 15:47:26.764703 [DEBUG] sofia.c:9873 sofia/internal/hulk@demo.fusion.mgwnet.us receiving invite from 172.23.3.29:53546 version: 1.6.20  64bit
  51. 2018-08-03 15:47:26.764703 [DEBUG] sofia.c:10044 IP 172.23.3.29 Rejected by acl "domains". Falling back to Digest auth.
  52. send 823 bytes to tcp/[172.23.3.29]:53546 at 15:47:26.769048:
  53.    ------------------------------------------------------------------------
  54.    SIP/2.0 407 Proxy Authentication Required
  55.    Via: SIP/2.0/TCP 172.23.3.29;branch=z9hG4bK74868a65906142DE;rport=53546
  56.    From: "222-Hulk" <sip:hulk@demo.fusion.mgwnet.us>;tag=1FC7DE3B-3ED17E44
  57.    To: <sip:Hawkeye@demo.fusion.mgwnet.us>;tag=3c8eFepH7y78S
  58.    Call-ID: 96da71f40b5a8db54241414750c34469
  59.    CSeq: 1 INVITE
  60.    User-Agent: FreeSWITCH
  61.    Accept: application/sdp
  62.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  63.    Supported: timer, path, replaces
  64.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  65.    Proxy-Authenticate: Digest realm="demo.fusion.mgwnet.us", nonce="0c82e4b4-9756-11e8-8182-815e756dd19a", algorithm=MD5, qop="auth"
  66.    Content-Length: 0
  67.  
  68.    ------------------------------------------------------------------------
  69. 2018-08-03 15:47:26.764703 [DEBUG] sofia.c:2334 detaching session 0c82cb0a-9756-11e8-8181-815e756dd19a
  70. 2018-08-03 15:47:26.764703 [WARNING] sofia_reg.c:1792 SIP auth challenge (INVITE) on sofia profile 'internal' for [Hawkeye@demo.fusion.mgwnet.us] from ip 172.23.3.29
  71. 2018-08-03 15:47:26.764703 [DEBUG] switch_core_state_machine.c:603 (sofia/internal/hulk@demo.fusion.mgwnet.us) State NEW
  72. recv 551 bytes from tcp/[172.23.3.29]:53546 at 15:47:26.857948:
  73.    ------------------------------------------------------------------------
  74.    ACK sip:Hawkeye@demo.fusion.mgwnet.us;transport=tcp SIP/2.0
  75.    Via: SIP/2.0/TCP 172.23.3.29;branch=z9hG4bK74868a65906142DE
  76.    From: "222-Hulk" <sip:hulk@demo.fusion.mgwnet.us>;tag=1FC7DE3B-3ED17E44
  77.    To: <sip:Hawkeye@demo.fusion.mgwnet.us>;tag=3c8eFepH7y78S
  78.    CSeq: 1 ACK
  79.    Call-ID: 96da71f40b5a8db54241414750c34469
  80.    Contact: <sip:hulk@172.23.3.29;transport=tcp>
  81.   Allow: INVITE,ACK,BYE,CANCEL,OPTIONS,INFO,MESSAGE,SUBSCRIBE,NOTIFY,PRACK,UPDATE,REFER
  82.    User-Agent: PolycomVVX-VVX_310-UA/5.8.0.12848
  83.    Accept-Language: en
  84.    Max-Forwards: 70
  85.    Content-Length: 0
  86.  
  87.    ------------------------------------------------------------------------
  88. recv 1270 bytes from tcp/[172.23.3.29]:53546 at 15:47:26.903017:
  89.    ------------------------------------------------------------------------
  90.    INVITE sip:Hawkeye@demo.fusion.mgwnet.us;transport=tcp SIP/2.0
  91.    Via: SIP/2.0/TCP 172.23.3.29;branch=z9hG4bK527a3def968BF7D8
  92.    From: "222-Hulk" <sip:hulk@demo.fusion.mgwnet.us>;tag=1FC7DE3B-3ED17E44
  93.    To: <sip:Hawkeye@demo.fusion.mgwnet.us>
  94.    CSeq: 2 INVITE
  95.    Call-ID: 96da71f40b5a8db54241414750c34469
  96.    Contact: <sip:hulk@172.23.3.29;transport=tcp>
  97.   Allow: INVITE,ACK,BYE,CANCEL,OPTIONS,INFO,MESSAGE,SUBSCRIBE,NOTIFY,PRACK,UPDATE,REFER
  98.    User-Agent: PolycomVVX-VVX_310-UA/5.8.0.12848
  99.    Accept-Language: en
  100.    Supported: replaces,100rel
  101.    Allow-Events: conference,talk,hold
  102.    Proxy-Authorization: Digest username="hulk", realm="demo.fusion.mgwnet.us", nonce="0c82e4b4-9756-11e8-8182-815e756dd19a", qop=auth, cnonce="aQZvvlJHHElfxZx", nc=00000001, uri="sip:Hawkeye@demo.fusion.mgwnet.us;transport=tcp", response="66c03adf739ff74d7e49f81d53072f77", algorithm=MD5
  103.    Max-Forwards: 70
  104.    Content-Type: application/sdp
  105.    Content-Length: 348
  106.  
  107.    v=0
  108.    o=- 1533325646 1533325646 IN IP4 172.23.3.29
  109.    s=Polycom IP Phone
  110.    c=IN IP4 172.23.3.29
  111.    t=0 0
  112.    a=sendrecv
  113.    m=audio 2230 RTP/AVP 9 102 0 8 18 127
  114.    a=rtpmap:9 G722/8000
  115.    a=rtpmap:102 G7221/16000
  116.    a=fmtp:102 bitrate=32000
  117.    a=rtpmap:0 PCMU/8000
  118.    a=rtpmap:8 PCMA/8000
  119.    a=rtpmap:18 G729/8000
  120.    a=fmtp:18 annexb=no
  121.    a=rtpmap:127 telephone-event/8000
  122.    ------------------------------------------------------------------------
  123. send 311 bytes to tcp/[172.23.3.29]:53546 at 15:47:26.903238:
  124.    ------------------------------------------------------------------------
  125.    SIP/2.0 100 Trying
  126.    Via: SIP/2.0/TCP 172.23.3.29;branch=z9hG4bK527a3def968BF7D8;rport=53546
  127.    From: "222-Hulk" <sip:hulk@demo.fusion.mgwnet.us>;tag=1FC7DE3B-3ED17E44
  128.    To: <sip:Hawkeye@demo.fusion.mgwnet.us>
  129.    Call-ID: 96da71f40b5a8db54241414750c34469
  130.    CSeq: 2 INVITE
  131.    User-Agent: FreeSWITCH
  132.    Content-Length: 0
  133.  
  134.    ------------------------------------------------------------------------
  135. 2018-08-03 15:47:26.884644 [DEBUG] sofia.c:2442 Re-attaching to session 0c82cb0a-9756-11e8-8181-815e756dd19a
  136. 2018-08-03 15:47:26.904635 [DEBUG] sofia.c:9873 sofia/internal/hulk@demo.fusion.mgwnet.us receiving invite from 172.23.3.29:53546 version: 1.6.20  64bit
  137. 2018-08-03 15:47:26.904635 [DEBUG] sofia.c:10044 IP 172.23.3.29 Rejected by acl "domains". Falling back to Digest auth.
  138. 2018-08-03 15:47:26.904635 [DEBUG] sofia.c:7084 Channel sofia/internal/hulk@demo.fusion.mgwnet.us entering state [received][100]
  139. 2018-08-03 15:47:26.904635 [DEBUG] sofia.c:7094 Remote SDP:
  140. v=0
  141. o=- 1533325646 1533325646 IN IP4 172.23.3.29
  142. s=Polycom IP Phone
  143. c=IN IP4 172.23.3.29
  144. t=0 0
  145. a=sendrecv
  146. m=audio 2230 RTP/AVP 9 102 0 8 18 127
  147. a=rtpmap:9 G722/8000
  148. a=rtpmap:102 G7221/16000
  149. a=fmtp:102 bitrate=32000
  150. a=rtpmap:0 PCMU/8000
  151. a=rtpmap:8 PCMA/8000
  152. a=rtpmap:18 G729/8000
  153. a=fmtp:18 annexb=no
  154. a=rtpmap:127 telephone-event/8000
  155.  
  156. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[G7221:115:32000:20:48000:1]
  157. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[G7221:107:16000:20:32000:1]
  158. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  159. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
  160. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  161. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  162. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G7221:102:16000:20:32000:1]/[G7221:115:32000:20:48000:1]
  163. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G7221:102:16000:20:32000:1]/[G7221:107:16000:20:32000:1]
  164. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [G7221:107:16000:20:32000:1] ++++ is saved as a match
  165. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G7221:102:16000:20:32000:1]/[G722:9:8000:20:64000:1]
  166. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G7221:102:16000:20:32000:1]/[PCMU:0:8000:20:64000:1]
  167. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G7221:102:16000:20:32000:1]/[PCMA:8:8000:20:64000:1]
  168. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G7221:115:32000:20:48000:1]
  169. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G7221:107:16000:20:32000:1]
  170. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  171. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  172. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
  173. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  174. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G7221:115:32000:20:48000:1]
  175. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G7221:107:16000:20:32000:1]
  176. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  177. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  178. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  179. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
  180. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[G7221:115:32000:20:48000:1]
  181. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[G7221:107:16000:20:32000:1]
  182. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
  183. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
  184. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
  185. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 127@8000
  186. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_media.c:3061 Set Codec sofia/internal/hulk@demo.fusion.mgwnet.us G722/8000 20 ms 160 samples 64000 bits 1 channels
  187. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_codec.c:111 sofia/internal/hulk@demo.fusion.mgwnet.us Original read codec set to G722:9
  188. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 127@8000
  189. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_media.c:4767 sofia/internal/hulk@demo.fusion.mgwnet.us Set 2833 dtmf send payload to 127 recv payload to 127
  190. 2018-08-03 15:47:26.904635 [DEBUG] sofia.c:7507 (sofia/internal/hulk@demo.fusion.mgwnet.us) State Change CS_NEW -> CS_INIT
  191. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/hulk@demo.fusion.mgwnet.us) Running State Change CS_INIT (Cur 1 Tot 839)
  192. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/hulk@demo.fusion.mgwnet.us) State INIT
  193. 2018-08-03 15:47:26.904635 [DEBUG] mod_sofia.c:90 sofia/internal/hulk@demo.fusion.mgwnet.us SOFIA INIT
  194. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_state_machine.c:40 sofia/internal/hulk@demo.fusion.mgwnet.us Standard INIT
  195. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/hulk@demo.fusion.mgwnet.us) State Change CS_INIT -> CS_ROUTING
  196. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/hulk@demo.fusion.mgwnet.us) State INIT going to sleep
  197. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/hulk@demo.fusion.mgwnet.us) Running State Change CS_ROUTING (Cur 1 Tot 839)
  198. 2018-08-03 15:47:26.904635 [DEBUG] switch_channel.c:2249 (sofia/internal/hulk@demo.fusion.mgwnet.us) Callstate Change DOWN -> RINGING
  199. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/hulk@demo.fusion.mgwnet.us) State ROUTING
  200. 2018-08-03 15:47:26.904635 [DEBUG] mod_sofia.c:143 sofia/internal/hulk@demo.fusion.mgwnet.us SOFIA ROUTING
  201. 2018-08-03 15:47:26.904635 [DEBUG] switch_core_state_machine.c:236 sofia/internal/hulk@demo.fusion.mgwnet.us Standard ROUTING
  202. 2018-08-03 15:47:26.904635 [INFO] mod_dialplan_xml.c:637 Processing 222-Hulk <hulk>->Hawkeye in context demo.fusion.mgwnet.us
  203. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->Toll_Block] continue=false
  204. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [Toll_Block] ${user_exists}() =~ /false/ break=on-false
  205. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->user_exists] continue=true
  206. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (PASS) [user_exists] () =~ // break=on-false
  207. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
  208. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us set(user_exists=true)
  209. 2018-08-03 15:47:26.924634 [DEBUG] mod_dptools.c:1548 SET sofia/internal/hulk@demo.fusion.mgwnet.us [user_exists]=[true]
  210. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (PASS) [user_exists] ${user_exists}(true) =~ /^true$/ break=on-false
  211. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action set(extension_uuid=${user_data ${destination_number}@${domain_name} var extension_uuid}) INLINE
  212. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us set(extension_uuid=80678b40-6245-40f8-8f31-2fbad80fb005)
  213. 2018-08-03 15:47:26.924634 [DEBUG] mod_dptools.c:1548 SET sofia/internal/hulk@demo.fusion.mgwnet.us [extension_uuid]=[80678b40-6245-40f8-8f31-2fbad80fb005]
  214. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action set(hold_music=${user_data ${destination_number}@${domain_name} var hold_music}) INLINE
  215. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us set(hold_music=)
  216. 2018-08-03 15:47:26.924634 [DEBUG] mod_dptools.c:1548 SET sofia/internal/hulk@demo.fusion.mgwnet.us [hold_music]=[UNDEF]
  217. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action set(forward_all_enabled=${user_data ${destination_number}@${domain_name} var forward_all_enabled}) INLINE
  218. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us set(forward_all_enabled=)
  219. 2018-08-03 15:47:26.924634 [DEBUG] mod_dptools.c:1548 SET sofia/internal/hulk@demo.fusion.mgwnet.us [forward_all_enabled]=[UNDEF]
  220. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action set(forward_all_destination=${user_data ${destination_number}@${domain_name} var forward_all_destination}) INLINE
  221. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us set(forward_all_destination=)
  222. 2018-08-03 15:47:26.924634 [DEBUG] mod_dptools.c:1548 SET sofia/internal/hulk@demo.fusion.mgwnet.us [forward_all_destination]=[UNDEF]
  223. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action set(forward_busy_enabled=${user_data ${destination_number}@${domain_name} var forward_busy_enabled}) INLINE
  224. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us set(forward_busy_enabled=)
  225. 2018-08-03 15:47:26.944634 [DEBUG] mod_dptools.c:1548 SET sofia/internal/hulk@demo.fusion.mgwnet.us [forward_busy_enabled]=[UNDEF]
  226. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action set(forward_busy_destination=${user_data ${destination_number}@${domain_name} var forward_busy_destination}) INLINE
  227. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us set(forward_busy_destination=)
  228. 2018-08-03 15:47:26.944634 [DEBUG] mod_dptools.c:1548 SET sofia/internal/hulk@demo.fusion.mgwnet.us [forward_busy_destination]=[UNDEF]
  229. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action set(forward_no_answer_enabled=${user_data ${destination_number}@${domain_name} var forward_no_answer_enabled}) INLINE
  230. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us set(forward_no_answer_enabled=)
  231. 2018-08-03 15:47:26.944634 [DEBUG] mod_dptools.c:1548 SET sofia/internal/hulk@demo.fusion.mgwnet.us [forward_no_answer_enabled]=[UNDEF]
  232. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action set(forward_no_answer_destination=${user_data ${destination_number}@${domain_name} var forward_no_answer_destination}) INLINE
  233. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us set(forward_no_answer_destination=)
  234. 2018-08-03 15:47:26.944634 [DEBUG] mod_dptools.c:1548 SET sofia/internal/hulk@demo.fusion.mgwnet.us [forward_no_answer_destination]=[UNDEF]
  235. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action set(forward_user_not_registered_enabled=${user_data ${destination_number}@${domain_name} var forward_user_not_registered_enabled}) INLINE
  236. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us set(forward_user_not_registered_enabled=)
  237. 2018-08-03 15:47:26.944634 [DEBUG] mod_dptools.c:1548 SET sofia/internal/hulk@demo.fusion.mgwnet.us [forward_user_not_registered_enabled]=[UNDEF]
  238. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action set(forward_user_not_registered_destination=${user_data ${destination_number}@${domain_name} var forward_user_not_registered_destination}) INLINE
  239. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us set(forward_user_not_registered_destination=)
  240. 2018-08-03 15:47:26.964641 [DEBUG] mod_dptools.c:1548 SET sofia/internal/hulk@demo.fusion.mgwnet.us [forward_user_not_registered_destination]=[UNDEF]
  241. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action set(do_not_disturb=${user_data ${destination_number}@${domain_name} var do_not_disturb}) INLINE
  242. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us set(do_not_disturb=)
  243. 2018-08-03 15:47:26.964641 [DEBUG] mod_dptools.c:1548 SET sofia/internal/hulk@demo.fusion.mgwnet.us [do_not_disturb]=[UNDEF]
  244. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action set(call_timeout=${user_data ${destination_number}@${domain_name} var call_timeout}) INLINE
  245. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us set(call_timeout=30)
  246. 2018-08-03 15:47:26.964641 [DEBUG] mod_dptools.c:1548 SET sofia/internal/hulk@demo.fusion.mgwnet.us [call_timeout]=[30]
  247. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action set(missed_call_app=${user_data ${destination_number}@${domain_name} var missed_call_app}) INLINE
  248. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us set(missed_call_app=)
  249. 2018-08-03 15:47:26.964641 [DEBUG] mod_dptools.c:1548 SET sofia/internal/hulk@demo.fusion.mgwnet.us [missed_call_app]=[UNDEF]
  250. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action set(missed_call_data=${user_data ${destination_number}@${domain_name} var missed_call_data}) INLINE
  251. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us set(missed_call_data=)
  252. 2018-08-03 15:47:26.964641 [DEBUG] mod_dptools.c:1548 SET sofia/internal/hulk@demo.fusion.mgwnet.us [missed_call_data]=[UNDEF]
  253. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action set(toll_allow=${user_data ${destination_number}@${domain_name} var toll_allow}) INLINE
  254. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us set(toll_allow=)
  255. 2018-08-03 15:47:26.984637 [DEBUG] mod_dptools.c:1548 SET sofia/internal/hulk@demo.fusion.mgwnet.us [toll_allow]=[UNDEF]
  256. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action set(call_screen_enabled=${user_data ${destination_number}@${domain_name} var call_screen_enabled}) INLINE
  257. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us set(call_screen_enabled=false)
  258. 2018-08-03 15:47:26.984637 [DEBUG] mod_dptools.c:1548 SET sofia/internal/hulk@demo.fusion.mgwnet.us [call_screen_enabled]=[false]
  259. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->caller-details] continue=true
  260. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (PASS) [caller-details] ${caller_destination}() =~ /^$/ break=never
  261. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action set(caller_destination=${destination_number}) INLINE
  262. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us set(caller_destination=Hawkeye)
  263. 2018-08-03 15:47:26.984637 [DEBUG] mod_dptools.c:1548 SET sofia/internal/hulk@demo.fusion.mgwnet.us [caller_destination]=[Hawkeye]
  264. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action set(caller_id_name=${caller_id_name}) INLINE
  265. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us set(caller_id_name=hulk)
  266. 2018-08-03 15:47:26.984637 [DEBUG] mod_dptools.c:1548 SET sofia/internal/hulk@demo.fusion.mgwnet.us [caller_id_name]=[hulk]
  267. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action set(caller_id_number=${caller_id_number}) INLINE
  268. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us set(caller_id_number=222)
  269. 2018-08-03 15:47:26.984637 [DEBUG] mod_dptools.c:1548 SET sofia/internal/hulk@demo.fusion.mgwnet.us [caller_id_number]=[222]
  270. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->call-direction] continue=true
  271. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [call-direction] ${call_direction}() =~ /^(inbound|outbound|local)$/ break=never
  272. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us ANTI-Action export(call_direction=local)
  273. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->variables] continue=true
  274. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (PASS) [variables] () =~ // break=on-false
  275. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action export(origination_callee_id_name=${destination_number})
  276. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
  277. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->user_record] continue=true
  278. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (PASS) [user_record] () =~ // break=on-false
  279. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action set(user_record=${user_data ${destination_number}@${domain_name} var user_record}) INLINE
  280. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us set(user_record=)
  281. 2018-08-03 15:47:26.984637 [DEBUG] mod_dptools.c:1548 SET sofia/internal/hulk@demo.fusion.mgwnet.us [user_record]=[UNDEF]
  282. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE
  283. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us set(from_user_exists=true)
  284. 2018-08-03 15:47:26.984637 [DEBUG] mod_dptools.c:1548 SET sofia/internal/hulk@demo.fusion.mgwnet.us [from_user_exists]=[true]
  285. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never
  286. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never
  287. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never
  288. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [user_record] ${call_direction}() =~ /^inbound$/ break=never
  289. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never
  290. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never
  291. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [user_record] ${call_direction}() =~ /^outbound$/ break=never
  292. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never
  293. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never
  294. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [user_record] ${call_direction}() =~ /^local$/ break=never
  295. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never
  296. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
  297. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action set(from_user_record=${user_data ${sip_from_user}@${sip_from_host} var user_record}) INLINE
  298. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us set(from_user_record=)
  299. 2018-08-03 15:47:27.004638 [DEBUG] mod_dptools.c:1548 SET sofia/internal/hulk@demo.fusion.mgwnet.us [from_user_record]=[UNDEF]
  300. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
  301. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never
  302. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
  303. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [user_record] ${call_direction}() =~ /^inbound$/ break=never
  304. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never
  305. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
  306. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [user_record] ${call_direction}() =~ /^outbound$/ break=never
  307. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never
  308. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (PASS) [user_record] ${from_user_exists}(true) =~ /^true$/ break=never
  309. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [user_record] ${call_direction}() =~ /^local$/ break=never
  310. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never
  311. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false
  312. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->redial] continue=true
  313. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [redial] destination_number(Hawkeye) =~ /^(redial|\*870)$/ break=on-true
  314. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (PASS) [redial] () =~ // break=never
  315. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
  316. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->speed_dial] continue=false
  317. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [speed_dial] destination_number(Hawkeye) =~ /^\*0(.*)$/ break=on-false
  318. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->default_caller_id] continue=true
  319. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (PASS) [default_caller_id] ${emergency_caller_id_number}() =~ /^$/ break=never
  320. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action set(emergency_caller_id_name=${default_emergency_caller_id_name}) INLINE
  321. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us set(emergency_caller_id_name=)
  322. 2018-08-03 15:47:27.004638 [DEBUG] mod_dptools.c:1548 SET sofia/internal/hulk@demo.fusion.mgwnet.us [emergency_caller_id_name]=[UNDEF]
  323. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action set(emergency_caller_id_number=${default_emergency_caller_id_number}) INLINE
  324. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us set(emergency_caller_id_number=)
  325. 2018-08-03 15:47:27.004638 [DEBUG] mod_dptools.c:1548 SET sofia/internal/hulk@demo.fusion.mgwnet.us [emergency_caller_id_number]=[UNDEF]
  326. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (PASS) [default_caller_id] ${outbound_caller_id_number}() =~ /^$/ break=never
  327. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action set(outbound_caller_id_name=${default_outbound_caller_id_name}) INLINE
  328. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us set(outbound_caller_id_name=)
  329. 2018-08-03 15:47:27.004638 [DEBUG] mod_dptools.c:1548 SET sofia/internal/hulk@demo.fusion.mgwnet.us [outbound_caller_id_name]=[UNDEF]
  330. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action set(outbound_caller_id_number=${default_outbound_caller_id_number}) INLINE
  331. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us set(outbound_caller_id_number=)
  332. 2018-08-03 15:47:27.004638 [DEBUG] mod_dptools.c:1548 SET sofia/internal/hulk@demo.fusion.mgwnet.us [outbound_caller_id_number]=[UNDEF]
  333. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->Vitelity] continue=false
  334. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [Vitelity] ${user_exists}(true) =~ /false/ break=on-false
  335. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->Main] continue=false
  336. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [Main] destination_number(Hawkeye) =~ /^212$/ break=on-false
  337. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->ring group] continue=
  338. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [ring group] destination_number(Hawkeye) =~ /^202$/ break=on-false
  339. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->ring group] continue=
  340. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [ring group] destination_number(Hawkeye) =~ /^201$/ break=on-false
  341. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->agent_status] continue=false
  342. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [agent_status] destination_number(Hawkeye) =~ /^\*22$/ break=on-false
  343. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->agent_status_id] continue=false
  344. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [agent_status_id] destination_number(Hawkeye) =~ /^\*23$/ break=on-false
  345. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->group-intercept] continue=false
  346. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [group-intercept] destination_number(Hawkeye) =~ /^\*8$/ break=on-false
  347. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->page-extension] continue=false
  348. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [page-extension] destination_number(Hawkeye) =~ /^\*8(\d{2,7})$/ break=on-false
  349. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->eavesdrop] continue=false
  350. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [eavesdrop] destination_number(Hawkeye) =~ /^\*33(\d{2,7})$/ break=on-false
  351. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->call_privacy] continue=false
  352. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [call_privacy] destination_number(Hawkeye) =~ /^\*67(\d+)$/ break=on-false
  353. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->call_return] continue=false
  354. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [call_return] destination_number(Hawkeye) =~ /^\*69$/ break=on-false
  355. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->extension_queue] continue=false
  356. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [extension_queue] destination_number(Hawkeye) =~ /^\*800(.*)$/ break=on-false
  357. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->intercept-ext-polycom] continue=false
  358. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [intercept-ext-polycom] destination_number(Hawkeye) =~ /^\*97(\d+)$/ break=on-false
  359. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->intercept-ext] continue=false
  360. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [intercept-ext] destination_number(Hawkeye) =~ /^\*\*(\d+)$/ break=on-true
  361. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [intercept-ext] destination_number(Hawkeye) =~ /^\*\*$/ break=on-false
  362. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->dx] continue=false
  363. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [dx] destination_number(Hawkeye) =~ /^dx$/ break=on-false
  364. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->send_to_voicemail] continue=false
  365. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [send_to_voicemail] destination_number(Hawkeye) =~ /^\*99(\d{2,10})$/ break=on-false
  366. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->att_xfer] continue=false
  367. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [att_xfer] destination_number(Hawkeye) =~ /^att_xfer$/ break=on-false
  368. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->extension-to-voicemail] continue=false
  369. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (PASS) [extension-to-voicemail] ${user_exists}(true) =~ /^true$/ break=on-false
  370. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [extension-to-voicemail] username(hulk) =~ /^222$/ break=on-false
  371. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->vmain] continue=false
  372. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [vmain] destination_number(Hawkeye) =~ /^vmain$|^\*4000$|^\*98$/ break=never
  373. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [vmain] destination_number(Hawkeye) =~ /^(vmain$|^\*4000$|^\*98)(\d{2,12})$/ break=on-false
  374. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->xfer_vm] continue=false
  375. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [xfer_vm] destination_number(Hawkeye) =~ /^xfer_vm$/ break=on-false
  376. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->is_transfer] continue=false
  377. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [is_transfer] destination_number(Hawkeye) =~ /^is_transfer$/ break=on-false
  378. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->vmain_user] continue=false
  379. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [vmain_user] destination_number(Hawkeye) =~ /^\*97$/ break=on-false
  380. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->delay_echo] continue=false
  381. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [delay_echo] destination_number(Hawkeye) =~ /^\*9195$/ break=on-false
  382. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->cf] continue=false
  383. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [cf] destination_number(Hawkeye) =~ /^cf$/ break=on-false
  384. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->echo] continue=false
  385. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [echo] destination_number(Hawkeye) =~ /^\*9196$/ break=on-false
  386. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->milliwatt] continue=false
  387. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [milliwatt] destination_number(Hawkeye) =~ /^\*9197$/ break=on-false
  388. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->is_zrtp_secure] continue=true
  389. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [is_zrtp_secure] ${zrtp_secure_media_confirmed}() =~ /^true$/ break=on-false
  390. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us ANTI-Action eval(not_secure)
  391. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->is_secure] continue=true
  392. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [is_secure] ${sip_via_protocol}(tcp) =~ /tls/ break=on-false
  393. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->tone_stream] continue=false
  394. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [tone_stream] destination_number(Hawkeye) =~ /^\*9198$/ break=on-false
  395. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->hold_music] continue=false
  396. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [hold_music] destination_number(Hawkeye) =~ /^\*9664$/ break=on-false
  397. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->recordings] continue=false
  398. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [recordings] destination_number(Hawkeye) =~ /^\*(732)$/ break=on-false
  399. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->directory] continue=false
  400. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [directory] destination_number(Hawkeye) =~ /^\*411$/ break=on-false
  401. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->wake-up] continue=false
  402. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [wake-up] destination_number(Hawkeye) =~ /^\*(925)$/ break=on-false
  403. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->valet_park] continue=false
  404. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [valet_park] destination_number(Hawkeye) =~ /^(park\+)?(\*59[0-9][0-9])$/ break=never
  405. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [valet_park] ${sip_h_Referred-By}() =~ /sip:(.*)@.*/ break=never
  406. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [valet_park] destination_number(Hawkeye) =~ /^(park\+)?(\*59[0-9][0-9])$/ break=never
  407. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [valet_park] destination_number(Hawkeye) =~ /^(park\+)?(\*59[0-9][0-9])$/ break=on-false
  408. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->operator] continue=false
  409. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [operator] destination_number(Hawkeye) =~ /^0$|^operator$/ break=on-false
  410. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->operator-forward] continue=false
  411. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [operator-forward] destination_number(Hawkeye) =~ /^\*000$/ break=on-false
  412. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->do-not-disturb] continue=false
  413. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [do-not-disturb] destination_number(Hawkeye) =~ /^\*77$/ break=on-true
  414. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [do-not-disturb] destination_number(Hawkeye) =~ /^\*78$|\*363$/ break=on-true
  415. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [do-not-disturb] destination_number(Hawkeye) =~ /^\*79$/ break=on-true
  416. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [do-not-disturb] destination_number(Hawkeye) =~ /^dnd\+222$/ break=on-true
  417. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->call-forward] continue=false
  418. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [call-forward] destination_number(Hawkeye) =~ /^\*72$/ break=on-true
  419. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [call-forward] destination_number(Hawkeye) =~ /^\*73$/ break=on-true
  420. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [call-forward] destination_number(Hawkeye) =~ /^\*74$/ break=on-true
  421. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [call-forward] destination_number(Hawkeye) =~ /^forward\+(\Q222\E)(?:\/(\d+))?$/ break=on-true
  422. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->call forward all] continue=false
  423. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (PASS) [call forward all] ${user_exists}(true) =~ /true/ break=on-false
  424. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [call forward all] ${forward_all_enabled}() =~ /true/ break=on-false
  425. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->follow-me] continue=false
  426. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [follow-me] destination_number(Hawkeye) =~ /^\*21$/ break=on-false
  427. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->talking clock date and time] continue=true
  428. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [talking clock date and time] destination_number(Hawkeye) =~ /^\*9172$/ break=on-false
  429. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->clear_sip_auto_answer] continue=true
  430. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [clear_sip_auto_answer] ${click_to_call}() =~ /true/ break=on-false
  431. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->talking clock time] continue=true
  432. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [talking clock time] destination_number(Hawkeye) =~ /^\*9170$/ break=on-false
  433. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->talking clock date] continue=true
  434. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [talking clock date] destination_number(Hawkeye) =~ /^\*9171$/ break=on-false
  435. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->call_screen] continue=true
  436. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [call_screen] ${call_screen_enabled}(false) =~ /^true$/ break=on-false
  437. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->local_extension] continue=true
  438. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (PASS) [local_extension] ${user_exists}(true) =~ /true/ break=on-false
  439. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action export(dialed_extension=${destination_number}) INLINE
  440. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us export(dialed_extension=Hawkeye)
  441. 2018-08-03 15:47:27.004638 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [dialed_extension]=[Hawkeye]
  442. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action limit(hash ${domain_name} ${destination_number} ${limit_max} ${limit_destination})
  443. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (PASS) [local_extension] () =~ // break=on-false
  444. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action set(hangup_after_bridge=true)
  445. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action set(continue_on_fail=true)
  446. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number})
  447. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid})
  448. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action set(called_party_call_group=${user_data(${dialed_extension}@${domain_name} var call_group)})
  449. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action hash(insert/${domain_name}-last_dial/${called_party_call_group}/${uuid})
  450. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action set(api_hangup_hook=lua app.lua hangup)
  451. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action export(domain_name=${domain_name})
  452. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action bridge(user/${destination_number}@${domain_name})
  453. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action lua(app.lua failure_handler)
  454. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->Default_Route] continue=false
  455. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (FAIL) [Default_Route] ${user_exists}(true) =~ /false/ break=on-false
  456. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us parsing [demo.fusion.mgwnet.us->voicemail] continue=false
  457. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Regex (PASS) [voicemail] ${user_exists}(true) =~ /true/ break=on-false
  458. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action set(voicemail_action=save)
  459. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action set(voicemail_id=${destination_number})
  460. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action set(voicemail_profile=default)
  461. Dialplan: sofia/internal/hulk@demo.fusion.mgwnet.us Action lua(app.lua voicemail)
  462. 2018-08-03 15:47:27.004638 [DEBUG] switch_core_state_machine.c:286 (sofia/internal/hulk@demo.fusion.mgwnet.us) State Change CS_ROUTING -> CS_EXECUTE
  463. 2018-08-03 15:47:27.004638 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/hulk@demo.fusion.mgwnet.us) State ROUTING going to sleep
  464. 2018-08-03 15:47:27.004638 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/hulk@demo.fusion.mgwnet.us) Running State Change CS_EXECUTE (Cur 1 Tot 839)
  465. 2018-08-03 15:47:27.004638 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/hulk@demo.fusion.mgwnet.us) State EXECUTE
  466. 2018-08-03 15:47:27.004638 [DEBUG] mod_sofia.c:198 sofia/internal/hulk@demo.fusion.mgwnet.us SOFIA EXECUTE
  467. 2018-08-03 15:47:27.004638 [DEBUG] switch_core_state_machine.c:328 sofia/internal/hulk@demo.fusion.mgwnet.us Standard EXECUTE
  468. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us export(call_direction=local)
  469. 2018-08-03 15:47:27.004638 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [call_direction]=[local]
  470. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us export(origination_callee_id_name=Hawkeye)
  471. 2018-08-03 15:47:27.004638 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [origination_callee_id_name]=[Hawkeye]
  472. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us set(RFC2822_DATE=Fri, 03 Aug 2018 15:47:27 -0400)
  473. 2018-08-03 15:47:27.004638 [DEBUG] mod_dptools.c:1548 SET sofia/internal/hulk@demo.fusion.mgwnet.us [RFC2822_DATE]=[Fri, 03 Aug 2018 15:47:27 -0400]
  474. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us hash(insert/demo.fusion.mgwnet.us-last_dial/222/Hawkeye)
  475. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us eval(not_secure)
  476. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us limit(hash demo.fusion.mgwnet.us Hawkeye 5 error/user_busy)
  477. 2018-08-03 15:47:27.004638 [DEBUG] switch_limit.c:126 incr called: demo.fusion.mgwnet.us_Hawkeye max:5, interval:0
  478. 2018-08-03 15:47:27.004638 [DEBUG] mod_hash.c:196 Usage for demo.fusion.mgwnet.us_Hawkeye is now 1/5
  479. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us set(hangup_after_bridge=true)
  480. 2018-08-03 15:47:27.004638 [DEBUG] mod_dptools.c:1548 SET sofia/internal/hulk@demo.fusion.mgwnet.us [hangup_after_bridge]=[true]
  481. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us set(continue_on_fail=true)
  482. 2018-08-03 15:47:27.004638 [DEBUG] mod_dptools.c:1548 SET sofia/internal/hulk@demo.fusion.mgwnet.us [continue_on_fail]=[true]
  483. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us hash(insert/demo.fusion.mgwnet.us-call_return/Hawkeye/222)
  484. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us hash(insert/demo.fusion.mgwnet.us-last_dial_ext/Hawkeye/0c82cb0a-9756-11e8-8181-815e756dd19a)
  485. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us set(called_party_call_group=)
  486. 2018-08-03 15:47:27.004638 [DEBUG] mod_dptools.c:1548 SET sofia/internal/hulk@demo.fusion.mgwnet.us [called_party_call_group]=[UNDEF]
  487. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us hash(insert/demo.fusion.mgwnet.us-last_dial//0c82cb0a-9756-11e8-8181-815e756dd19a)
  488. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us set(api_hangup_hook=lua app.lua hangup)
  489. 2018-08-03 15:47:27.004638 [DEBUG] mod_dptools.c:1548 SET sofia/internal/hulk@demo.fusion.mgwnet.us [api_hangup_hook]=[lua app.lua hangup]
  490. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us export(domain_name=demo.fusion.mgwnet.us)
  491. 2018-08-03 15:47:27.004638 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [domain_name]=[demo.fusion.mgwnet.us]
  492. EXECUTE sofia/internal/hulk@demo.fusion.mgwnet.us bridge(user/Hawkeye@demo.fusion.mgwnet.us)
  493. 2018-08-03 15:47:27.004638 [DEBUG] switch_channel.c:1250 sofia/internal/hulk@demo.fusion.mgwnet.us EXPORTING[export_vars] [domain_name]=[demo.fusion.mgwnet.us] to event
  494. 2018-08-03 15:47:27.004638 [DEBUG] switch_channel.c:1250 sofia/internal/hulk@demo.fusion.mgwnet.us EXPORTING[export_vars] [dialed_extension]=[Hawkeye] to event
  495. 2018-08-03 15:47:27.004638 [DEBUG] switch_channel.c:1250 sofia/internal/hulk@demo.fusion.mgwnet.us EXPORTING[export_vars] [call_direction]=[local] to event
  496. 2018-08-03 15:47:27.004638 [DEBUG] switch_channel.c:1250 sofia/internal/hulk@demo.fusion.mgwnet.us EXPORTING[export_vars] [origination_callee_id_name]=[Hawkeye] to event
  497. 2018-08-03 15:47:27.004638 [DEBUG] switch_channel.c:1250 sofia/internal/hulk@demo.fusion.mgwnet.us EXPORTING[export_vars] [domain_name]=[demo.fusion.mgwnet.us] to event
  498. 2018-08-03 15:47:27.004638 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  499. 2018-08-03 15:47:27.004638 [DEBUG] switch_channel.c:1250 sofia/internal/hulk@demo.fusion.mgwnet.us EXPORTING[export_vars] [domain_name]=[demo.fusion.mgwnet.us] to event
  500. 2018-08-03 15:47:27.004638 [DEBUG] switch_channel.c:1250 sofia/internal/hulk@demo.fusion.mgwnet.us EXPORTING[export_vars] [dialed_extension]=[Hawkeye] to event
  501. 2018-08-03 15:47:27.004638 [DEBUG] switch_channel.c:1250 sofia/internal/hulk@demo.fusion.mgwnet.us EXPORTING[export_vars] [call_direction]=[local] to event
  502. 2018-08-03 15:47:27.004638 [DEBUG] switch_channel.c:1250 sofia/internal/hulk@demo.fusion.mgwnet.us EXPORTING[export_vars] [origination_callee_id_name]=[Hawkeye] to event
  503. 2018-08-03 15:47:27.004638 [DEBUG] switch_channel.c:1250 sofia/internal/hulk@demo.fusion.mgwnet.us EXPORTING[export_vars] [domain_name]=[demo.fusion.mgwnet.us] to event
  504. 2018-08-03 15:47:27.004638 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
  505. 2018-08-03 15:47:27.004638 [NOTICE] switch_channel.c:1104 New Channel sofia/internal/Hawkeye@172.23.3.30 [0ca9c4bc-9756-11e8-81b3-815e756dd19a]
  506. 2018-08-03 15:47:27.004638 [DEBUG] mod_sofia.c:4819 (sofia/internal/Hawkeye@172.23.3.30) State Change CS_NEW -> CS_INIT
  507. 2018-08-03 15:47:27.004638 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/Hawkeye@172.23.3.30) Running State Change CS_INIT (Cur 2 Tot 840)
  508. 2018-08-03 15:47:27.004638 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/Hawkeye@172.23.3.30) State INIT
  509. 2018-08-03 15:47:27.004638 [DEBUG] mod_sofia.c:90 sofia/internal/Hawkeye@172.23.3.30 SOFIA INIT
  510. 2018-08-03 15:47:27.004638 [DEBUG] sofia_glue.c:1264 sip:Hawkeye@172.23.3.30:5060 Setting proxy route to sofia/internal/Hawkeye@172.23.3.30
  511. 2018-08-03 15:47:27.004638 [DEBUG] sofia_glue.c:1295 sofia/internal/Hawkeye@172.23.3.30 sending invite version: 1.6.20  64bit
  512. Local SDP:
  513. v=0
  514. o=FreeSWITCH 1533299257 1533299258 IN IP4 74.123.77.190
  515. s=FreeSWITCH
  516. c=IN IP4 74.123.77.190
  517. t=0 0
  518. m=audio 26390 RTP/AVP 9 102 103 0 8 101 13 104 105 106 107
  519. a=rtpmap:9 G722/8000
  520. a=rtpmap:102 G7221/32000
  521. a=fmtp:102 bitrate=48000
  522. a=rtpmap:103 G7221/16000
  523. a=fmtp:103 bitrate=32000
  524. a=rtpmap:0 PCMU/8000
  525. a=rtpmap:8 PCMA/8000
  526. a=rtpmap:101 telephone-event/8000
  527. a=fmtp:101 0-16
  528. a=rtpmap:104 telephone-event/32000
  529. a=fmtp:104 0-16
  530. a=rtpmap:106 telephone-event/16000
  531. a=fmtp:106 0-16
  532. a=rtpmap:13 CN/8000
  533. a=rtpmap:105 CN/32000
  534. a=rtpmap:107 CN/16000
  535. a=ptime:20
  536. a=sendrecv
  537.  
  538. send 1515 bytes to udp/[172.23.3.30]:5060 at 15:47:27.024314:
  539.    ------------------------------------------------------------------------
  540.    INVITE sip:Hawkeye@172.23.3.30 SIP/2.0
  541.    Via: SIP/2.0/UDP 74.123.77.190;rport;branch=z9hG4bK49rKc5pt78cZr
  542.    Route: <sip:Hawkeye@172.23.3.30:5060>
  543.    Max-Forwards: 69
  544.    From: "222-Hulk" <sip:hulk@demo.fusion.mgwnet.us>;tag=5yt0j4Qr1gmeH
  545.    To: <sip:Hawkeye@172.23.3.30>
  546.    Call-ID: e4082487-11f8-1237-998e-00155dd37f11
  547.    CSeq: 126326759 INVITE
  548.    Contact: <sip:mod_sofia@74.123.77.190:5060>
  549.    User-Agent: FreeSWITCH
  550.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  551.    Supported: timer, path, replaces
  552.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  553.    Content-Type: application/sdp
  554.    Content-Disposition: session
  555.    Content-Length: 574
  556.    X-FS-Support: update_display,send_info
  557.    Remote-Party-ID: "222-Hulk" <sip:hulk@demo.fusion.mgwnet.us>;party=calling;screen=yes;privacy=off
  558.  
  559.    v=0
  560.    o=FreeSWITCH 1533299257 1533299258 IN IP4 74.123.77.190
  561.    s=FreeSWITCH
  562.    c=IN IP4 74.123.77.190
  563.    t=0 0
  564.    m=audio 26390 RTP/AVP 9 102 103 0 8 101 13 104 105 106 107
  565.    a=rtpmap:9 G722/8000
  566.    a=rtpmap:102 G7221/32000
  567.    a=fmtp:102 bitrate=48000
  568.    a=rtpmap:103 G7221/16000
  569.    a=fmtp:103 bitrate=32000
  570.    a=rtpmap:0 PCMU/8000
  571.    a=rtpmap:8 PCMA/8000
  572.    a=rtpmap:101 telephone-event/8000
  573.    a=fmtp:101 0-16
  574.    a=rtpmap:13 CN/8000
  575.    a=rtpmap:104 telephone-event/32000
  576.    a=fmtp:104 0-16
  577.    a=rtpmap:105 CN/32000
  578.    a=rtpmap:106 telephone-event/16000
  579.    a=fmtp:106 0-16
  580.    a=rtpmap:107 CN/16000
  581.    a=ptime:20
  582.    ------------------------------------------------------------------------
  583. 2018-08-03 15:47:27.004638 [DEBUG] switch_core_state_machine.c:40 sofia/internal/Hawkeye@172.23.3.30 Standard INIT
  584. 2018-08-03 15:47:27.004638 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/Hawkeye@172.23.3.30) State Change CS_INIT -> CS_ROUTING
  585. 2018-08-03 15:47:27.004638 [DEBUG] switch_core_state_machine.c:627 (sofia/internal/Hawkeye@172.23.3.30) State INIT going to sleep
  586. 2018-08-03 15:47:27.024636 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/Hawkeye@172.23.3.30) Running State Change CS_ROUTING (Cur 2 Tot 840)
  587. 2018-08-03 15:47:27.024636 [DEBUG] sofia.c:7084 Channel sofia/internal/Hawkeye@172.23.3.30 entering state [calling][0]
  588. 2018-08-03 15:47:27.024636 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/Hawkeye@172.23.3.30) State ROUTING
  589. 2018-08-03 15:47:27.024636 [DEBUG] mod_sofia.c:143 sofia/internal/Hawkeye@172.23.3.30 SOFIA ROUTING
  590. 2018-08-03 15:47:27.024636 [DEBUG] switch_ivr_originate.c:67 (sofia/internal/Hawkeye@172.23.3.30) State Change CS_ROUTING -> CS_CONSUME_MEDIA
  591. 2018-08-03 15:47:27.024636 [DEBUG] switch_core_state_machine.c:643 (sofia/internal/Hawkeye@172.23.3.30) State ROUTING going to sleep
  592. 2018-08-03 15:47:27.024636 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/Hawkeye@172.23.3.30) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 840)
  593. 2018-08-03 15:47:27.024636 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/Hawkeye@172.23.3.30) State CONSUME_MEDIA
  594. 2018-08-03 15:47:27.024636 [DEBUG] switch_core_state_machine.c:662 (sofia/internal/Hawkeye@172.23.3.30) State CONSUME_MEDIA going to sleep
  595. recv 416 bytes from udp/[172.23.3.30]:5060 at 15:47:27.045605:
  596.    ------------------------------------------------------------------------
  597.    SIP/2.0 100 Trying
  598.    Via: SIP/2.0/UDP 74.123.77.190;rport;branch=z9hG4bK49rKc5pt78cZr
  599.    From: "222-Hulk" <sip:hulk@demo.fusion.mgwnet.us>;tag=5yt0j4Qr1gmeH
  600.    To: "223-Hawkeye" <sip:Hawkeye@172.23.3.30>;tag=D0F484E8-A18E93
  601.    CSeq: 126326759 INVITE
  602.    Call-ID: e4082487-11f8-1237-998e-00155dd37f11
  603.    Contact: <sip:Hawkeye@172.23.3.30>
  604.    User-Agent: PolycomVVX-VVX_310-UA/5.8.0.12848
  605.    Accept-Language: en
  606.    Content-Length: 0
  607.  
  608.    ------------------------------------------------------------------------
  609. recv 453 bytes from udp/[172.23.3.30]:5060 at 15:47:27.120882:
  610.    ------------------------------------------------------------------------
  611.    SIP/2.0 180 Ringing
  612.    Via: SIP/2.0/UDP 74.123.77.190;rport;branch=z9hG4bK49rKc5pt78cZr
  613.    From: "222-Hulk" <sip:hulk@demo.fusion.mgwnet.us>;tag=5yt0j4Qr1gmeH
  614.    To: "223-Hawkeye" <sip:Hawkeye@172.23.3.30>;tag=D0F484E8-A18E93
  615.    CSeq: 126326759 INVITE
  616.    Call-ID: e4082487-11f8-1237-998e-00155dd37f11
  617.    Contact: <sip:Hawkeye@172.23.3.30>
  618.    User-Agent: PolycomVVX-VVX_310-UA/5.8.0.12848
  619.    Allow-Events: conference,talk,hold
  620.    Accept-Language: en
  621.    Content-Length: 0
  622.  
  623.    ------------------------------------------------------------------------
  624. 2018-08-03 15:47:27.104636 [DEBUG] sofia.c:7084 Channel sofia/internal/Hawkeye@172.23.3.30 entering state [proceeding][180]
  625. 2018-08-03 15:47:27.104636 [NOTICE] sofia.c:7192 Ring-Ready sofia/internal/Hawkeye@172.23.3.30!
  626. 2018-08-03 15:47:27.104636 [DEBUG] switch_channel.c:3346 (sofia/internal/Hawkeye@172.23.3.30) Callstate Change DOWN -> RINGING
  627. 2018-08-03 15:47:27.124646 [INFO] switch_ivr_originate.c:1215 Sending early media
  628. 2018-08-03 15:47:27.124646 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/internal/hulk@demo.fusion.mgwnet.us] 74.123.77.190 port 19806 -> 172.23.3.29 port 2230 codec: 9 ms: 20
  629. 2018-08-03 15:47:27.124646 [DEBUG] switch_rtp.c:4137 Starting timer [soft] 160 bytes per 20ms
  630. 2018-08-03 15:47:27.124646 [DEBUG] switch_core_media.c:7180 sofia/internal/hulk@demo.fusion.mgwnet.us Set 2833 dtmf send payload to 127
  631. 2018-08-03 15:47:27.124646 [DEBUG] switch_core_media.c:7187 sofia/internal/hulk@demo.fusion.mgwnet.us Set 2833 dtmf receive payload to 127
  632. 2018-08-03 15:47:27.124646 [DEBUG] switch_core_media.c:7210 sofia/internal/hulk@demo.fusion.mgwnet.us Set rtp dtmf delay to 40
  633. 2018-08-03 15:47:27.124646 [DEBUG] mod_sofia.c:2364 Ring SDP:
  634. v=0
  635. o=FreeSWITCH 1533305841 1533305842 IN IP4 74.123.77.190
  636. s=FreeSWITCH
  637. c=IN IP4 74.123.77.190
  638. t=0 0
  639. m=audio 19806 RTP/AVP 9 127
  640. a=rtpmap:9 G722/8000
  641. a=rtpmap:127 telephone-event/8000
  642. a=fmtp:127 0-16
  643. a=ptime:20
  644. a=sendrecv
  645.  
  646. 2018-08-03 15:47:27.124646 [NOTICE] mod_sofia.c:2367 Pre-Answer sofia/internal/hulk@demo.fusion.mgwnet.us!
  647. 2018-08-03 15:47:27.124646 [DEBUG] switch_channel.c:3474 (sofia/internal/hulk@demo.fusion.mgwnet.us) Callstate Change RINGING -> EARLY
  648. send 1095 bytes to tcp/[172.23.3.29]:53546 at 15:47:27.128969:
  649.    ------------------------------------------------------------------------
  650.    SIP/2.0 183 Session Progress
  651.    Via: SIP/2.0/TCP 172.23.3.29;branch=z9hG4bK527a3def968BF7D8;rport=53546
  652.    From: "222-Hulk" <sip:hulk@demo.fusion.mgwnet.us>;tag=1FC7DE3B-3ED17E44
  653.    To: <sip:Hawkeye@demo.fusion.mgwnet.us>;tag=4N17g96m47XUN
  654.    Call-ID: 96da71f40b5a8db54241414750c34469
  655.    CSeq: 2 INVITE
  656.    Contact: <sip:Hawkeye@74.123.77.190:5060;transport=tcp>
  657.    User-Agent: FreeSWITCH
  658.    Accept: application/sdp
  659.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  660.    Supported: timer, path, replaces
  661.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  662.    Content-Type: application/sdp
  663.    Content-Disposition: session
  664.    Content-Length: 222
  665.    Remote-Party-ID: "Hawkeye" <Hawkeye>;party=calling;privacy=off;screen=no
  666.  
  667.    v=0
  668.    o=FreeSWITCH 1533305841 1533305842 IN IP4 74.123.77.190
  669.    s=FreeSWITCH
  670.    c=IN IP4 74.123.77.190
  671.    t=0 0
  672.    m=audio 19806 RTP/AVP 9 127
  673.    a=rtpmap:9 G722/8000
  674.    a=rtpmap:127 telephone-event/8000
  675.    a=fmtp:127 0-16
  676.    a=ptime:20
  677.    ------------------------------------------------------------------------
  678. 2018-08-03 15:47:27.124646 [DEBUG] sofia.c:7084 Channel sofia/internal/hulk@demo.fusion.mgwnet.us entering state [early][183]
  679. 2018-08-03 15:47:27.124646 [DEBUG] switch_ivr_originate.c:1273 Raw Codec Activation Success L16@16000hz 1 channel 20ms
  680. 2018-08-03 15:47:27.124646 [DEBUG] switch_core_codec.c:223 sofia/internal/hulk@demo.fusion.mgwnet.us Push codec L16:100
  681. 2018-08-03 15:47:27.124646 [DEBUG] switch_ivr_originate.c:1342 Play Ringback Tone [%(2000,4000,440,480)]
  682. 2018-08-03 15:47:27.284644 [DEBUG] switch_rtp.c:7308 Correct audio ip/port confirmed.
  683. 2018-08-03 15:47:28.324672 [NOTICE] sofia_reg.c:448 Registering 0b6a6aab-f5eb-4091-af69-3437de4db44a
  684. recv 781 bytes from udp/[172.23.3.30]:5060 at 15:47:28.731818:
  685.    ------------------------------------------------------------------------
  686.    SIP/2.0 200 OK
  687.    Via: SIP/2.0/UDP 74.123.77.190;rport;branch=z9hG4bK49rKc5pt78cZr
  688.    From: "222-Hulk" <sip:hulk@demo.fusion.mgwnet.us>;tag=5yt0j4Qr1gmeH
  689.    To: "223-Hawkeye" <sip:Hawkeye@172.23.3.30>;tag=D0F484E8-A18E93
  690.    CSeq: 126326759 INVITE
  691.    Call-ID: e4082487-11f8-1237-998e-00155dd37f11
  692.    Contact: <sip:Hawkeye@172.23.3.30>
  693.   Allow: INVITE,ACK,BYE,CANCEL,OPTIONS,INFO,MESSAGE,SUBSCRIBE,NOTIFY,PRACK,UPDATE,REFER
  694.    Supported: replaces,100rel
  695.    User-Agent: PolycomVVX-VVX_310-UA/5.8.0.12848
  696.    Allow-Events: conference,talk,hold
  697.    Accept-Language: en
  698.    Content-Type: application/sdp
  699.    Content-Length: 185
  700.  
  701.    v=0
  702.    o=- 1533325648 1533325648 IN IP4 172.23.3.30
  703.    s=Polycom IP Phone
  704.    c=IN IP4 172.23.3.30
  705.    t=0 0
  706.    m=audio 2240 RTP/AVP 9 101
  707.    a=rtpmap:9 G722/8000
  708.    a=rtpmap:101 telephone-event/8000
  709.    ------------------------------------------------------------------------
  710. 2018-08-03 15:47:28.724642 [DEBUG] sofia.c:7084 Channel sofia/internal/Hawkeye@172.23.3.30 entering state [completing][200]
  711. 2018-08-03 15:47:28.724642 [DEBUG] sofia.c:7094 Remote SDP:
  712. v=0
  713. o=- 1533325648 1533325648 IN IP4 172.23.3.30
  714. s=Polycom IP Phone
  715. c=IN IP4 172.23.3.30
  716. t=0 0
  717. m=audio 2240 RTP/AVP 9 101
  718. a=rtpmap:9 G722/8000
  719. a=rtpmap:101 telephone-event/8000
  720.  
  721. send 375 bytes to udp/[172.23.3.30]:5060 at 15:47:28.733289:
  722.    ------------------------------------------------------------------------
  723.    ACK sip:Hawkeye@172.23.3.30 SIP/2.0
  724.    Via: SIP/2.0/UDP 74.123.77.190;rport;branch=z9hG4bK5jjce07X4H3Hm
  725.    Max-Forwards: 70
  726.    From: "222-Hulk" <sip:hulk@demo.fusion.mgwnet.us>;tag=5yt0j4Qr1gmeH
  727.    To: <sip:Hawkeye@172.23.3.30>;tag=D0F484E8-A18E93
  728.    Call-ID: e4082487-11f8-1237-998e-00155dd37f11
  729.    CSeq: 126326759 ACK
  730.    Contact: <sip:mod_sofia@74.123.77.190:5060>
  731.    Content-Length: 0
  732.  
  733.    ------------------------------------------------------------------------
  734. 2018-08-03 15:47:28.724642 [DEBUG] sofia.c:7084 Channel sofia/internal/Hawkeye@172.23.3.30 entering state [ready][200]
  735. 2018-08-03 15:47:28.724642 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  736. 2018-08-03 15:47:28.724642 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
  737. 2018-08-03 15:47:28.724642 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[G7221:115:32000:20:48000:1]
  738. 2018-08-03 15:47:28.724642 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[G7221:107:16000:20:32000:1]
  739. 2018-08-03 15:47:28.724642 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  740. 2018-08-03 15:47:28.724642 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  741. 2018-08-03 15:47:28.724642 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
  742. 2018-08-03 15:47:28.724642 [DEBUG] switch_core_media.c:3061 Set Codec sofia/internal/Hawkeye@172.23.3.30 G722/8000 20 ms 160 samples 64000 bits 1 channels
  743. 2018-08-03 15:47:28.724642 [DEBUG] switch_core_codec.c:111 sofia/internal/Hawkeye@172.23.3.30 Original read codec set to G722:9
  744. 2018-08-03 15:47:28.724642 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
  745. 2018-08-03 15:47:28.724642 [DEBUG] switch_core_media.c:4767 sofia/internal/Hawkeye@172.23.3.30 Set 2833 dtmf send payload to 101 recv payload to 101
  746. 2018-08-03 15:47:28.724642 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/internal/Hawkeye@172.23.3.30] 74.123.77.190 port 26390 -> 172.23.3.30 port 2240 codec: 9 ms: 20
  747. 2018-08-03 15:47:28.724642 [DEBUG] switch_rtp.c:4137 Starting timer [soft] 160 bytes per 20ms
  748. 2018-08-03 15:47:28.724642 [DEBUG] switch_core_media.c:7180 sofia/internal/Hawkeye@172.23.3.30 Set 2833 dtmf send payload to 101
  749. 2018-08-03 15:47:28.724642 [DEBUG] switch_core_media.c:7187 sofia/internal/Hawkeye@172.23.3.30 Set 2833 dtmf receive payload to 101
  750. 2018-08-03 15:47:28.724642 [DEBUG] switch_core_media.c:7210 sofia/internal/Hawkeye@172.23.3.30 Set rtp dtmf delay to 40
  751. 2018-08-03 15:47:28.724642 [NOTICE] sofia.c:8218 Channel [sofia/internal/Hawkeye@172.23.3.30] has been answered
  752. 2018-08-03 15:47:28.724642 [DEBUG] switch_channel.c:3773 (sofia/internal/Hawkeye@172.23.3.30) Callstate Change RINGING -> ACTIVE
  753. 2018-08-03 15:47:28.745659 [DEBUG] switch_core_codec.c:248 sofia/internal/hulk@demo.fusion.mgwnet.us Restore previous codec G722:9.
  754. 2018-08-03 15:47:28.745659 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/internal/hulk@demo.fusion.mgwnet.us.
  755. 2018-08-03 15:47:28.745659 [DEBUG] mod_sofia.c:850 Local SDP sofia/internal/hulk@demo.fusion.mgwnet.us:
  756. v=0
  757. o=FreeSWITCH 1533305841 1533305843 IN IP4 74.123.77.190
  758. s=FreeSWITCH
  759. c=IN IP4 74.123.77.190
  760. t=0 0
  761. m=audio 19806 RTP/AVP 9 127
  762. a=rtpmap:9 G722/8000
  763. a=rtpmap:127 telephone-event/8000
  764. a=fmtp:127 0-16
  765. a=ptime:20
  766. a=sendrecv
  767.  
  768. send 1056 bytes to tcp/[172.23.3.29]:53546 at 15:47:28.747621:
  769.    ------------------------------------------------------------------------
  770.    SIP/2.0 200 OK
  771.    Via: SIP/2.0/TCP 172.23.3.29;branch=z9hG4bK527a3def968BF7D8;rport=53546
  772.    From: "222-Hulk" <sip:hulk@demo.fusion.mgwnet.us>;tag=1FC7DE3B-3ED17E44
  773.    To: <sip:Hawkeye@demo.fusion.mgwnet.us>;tag=4N17g96m47XUN
  774.    Call-ID: 96da71f40b5a8db54241414750c34469
  775.    CSeq: 2 INVITE
  776.    Contact: <sip:Hawkeye@74.123.77.190:5060;transport=tcp>
  777.    User-Agent: FreeSWITCH
  778.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  779.    Supported: timer, path, replaces
  780.    Allow-Events: talk, hold, conference, presence, as-feature-event, dialog, line-seize, call-info, sla, include-session-description, presence.winfo, message-summary, refer
  781.    Content-Type: application/sdp
  782.    Content-Disposition: session
  783.    Content-Length: 222
  784.    Remote-Party-ID: "Hawkeye" <Hawkeye>;party=calling;privacy=off;screen=no
  785.  
  786.    v=0
  787.    o=FreeSWITCH 1533305841 1533305842 IN IP4 74.123.77.190
  788.    s=FreeSWITCH
  789.    c=IN IP4 74.123.77.190
  790.    t=0 0
  791.    m=audio 19806 RTP/AVP 9 127
  792.    a=rtpmap:9 G722/8000
  793.    a=rtpmap:127 telephone-event/8000
  794.    a=fmtp:127 0-16
  795.    a=ptime:20
  796.    ------------------------------------------------------------------------
  797. 2018-08-03 15:47:28.745659 [DEBUG] sofia.c:7084 Channel sofia/internal/hulk@demo.fusion.mgwnet.us entering state [completed][200]
  798. 2018-08-03 15:47:28.745659 [NOTICE] switch_ivr_originate.c:3647 Channel [sofia/internal/hulk@demo.fusion.mgwnet.us] has been answered
  799. 2018-08-03 15:47:28.745659 [DEBUG] switch_channel.c:3773 (sofia/internal/hulk@demo.fusion.mgwnet.us) Callstate Change EARLY -> ACTIVE
  800. 2018-08-03 15:47:28.745659 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/internal/Hawkeye@172.23.3.30]
  801. 2018-08-03 15:47:28.745659 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/internal/Hawkeye@172.23.3.30]
  802. 2018-08-03 15:47:28.745659 [DEBUG] switch_ivr_bridge.c:1614 (sofia/internal/Hawkeye@172.23.3.30) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
  803. 2018-08-03 15:47:28.745659 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/Hawkeye@172.23.3.30) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 840)
  804. 2018-08-03 15:47:28.745659 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/Hawkeye@172.23.3.30) State EXCHANGE_MEDIA
  805. 2018-08-03 15:47:28.745659 [DEBUG] mod_sofia.c:631 SOFIA EXCHANGE_MEDIA
  806. recv 548 bytes from tcp/[172.23.3.29]:53546 at 15:47:28.757698:
  807.    ------------------------------------------------------------------------
  808.    ACK sip:Hawkeye@74.123.77.190:5060;transport=tcp SIP/2.0
  809.    Via: SIP/2.0/TCP 172.23.3.29;branch=z9hG4bK852fc3234E13FAEC
  810.    From: "222-Hulk" <sip:hulk@demo.fusion.mgwnet.us>;tag=1FC7DE3B-3ED17E44
  811.    To: <sip:Hawkeye@demo.fusion.mgwnet.us>;tag=4N17g96m47XUN
  812.    CSeq: 2 ACK
  813.    Call-ID: 96da71f40b5a8db54241414750c34469
  814.    Contact: <sip:hulk@172.23.3.29;transport=tcp>
  815.   Allow: INVITE,ACK,BYE,CANCEL,OPTIONS,INFO,MESSAGE,SUBSCRIBE,NOTIFY,PRACK,UPDATE,REFER
  816.    User-Agent: PolycomVVX-VVX_310-UA/5.8.0.12848
  817.    Accept-Language: en
  818.    Max-Forwards: 70
  819.    Content-Length: 0
  820.  
  821.    ------------------------------------------------------------------------
  822. 2018-08-03 15:47:28.764636 [DEBUG] sofia.c:7084 Channel sofia/internal/hulk@demo.fusion.mgwnet.us entering state [ready][200]
  823. send 1226 bytes to udp/[172.23.3.30]:5060 at 15:47:28.774029:
  824.    ------------------------------------------------------------------------
  825.    UPDATE sip:Hawkeye@172.23.3.30 SIP/2.0
  826.    Via: SIP/2.0/UDP 74.123.77.190;rport;branch=z9hG4bK6UB5FUr11tS4F
  827.    Max-Forwards: 70
  828.    From: "222-Hulk" <sip:hulk@demo.fusion.mgwnet.us>;tag=5yt0j4Qr1gmeH
  829.    To: <sip:Hawkeye@172.23.3.30>;tag=D0F484E8-A18E93
  830.    Call-ID: e4082487-11f8-1237-998e-00155dd37f11
  831.    CSeq: 126326760 UPDATE
  832.    Contact: <sip:mod_sofia@74.123.77.190:5060>
  833.    User-Agent: FreeSWITCH
  834.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  835.    Supported: timer, path, replaces
  836.    Content-Type: application/sdp
  837.    Content-Disposition: session
  838.    Content-Length: 574
  839.    P-Asserted-Identity: "222-Hulk" <hulk>
  840.  
  841.    v=0
  842.    o=FreeSWITCH 1533299257 1533299258 IN IP4 74.123.77.190
  843.    s=FreeSWITCH
  844.    c=IN IP4 74.123.77.190
  845.    t=0 0
  846.    m=audio 26390 RTP/AVP 9 101 102 103 0 8 13 104 105 106 107
  847.    a=rtpmap:9 G722/8000
  848.    a=rtpmap:101 telephone-event/8000
  849.    a=fmtp:101 0-16
  850.    a=rtpmap:102 G7221/32000
  851.    a=fmtp:102 bitrate=48000
  852.    a=rtpmap:103 G7221/16000
  853.    a=fmtp:103 bitrate=32000
  854.    a=rtpmap:0 PCMU/8000
  855.    a=rtpmap:8 PCMA/8000
  856.    a=rtpmap:13 CN/8000
  857.    a=rtpmap:104 telephone-event/32000
  858.    a=fmtp:104 0-16
  859.    a=rtpmap:105 CN/32000
  860.    a=rtpmap:106 telephone-event/16000
  861.    a=fmtp:106 0-16
  862.    a=rtpmap:107 CN/16000
  863.    a=ptime:20
  864.    ------------------------------------------------------------------------
  865. 2018-08-03 15:47:28.764636 [DEBUG] sofia.c:7084 Channel sofia/internal/Hawkeye@172.23.3.30 entering state [calling][0]
  866. send 907 bytes to tcp/[172.23.3.29]:5060 at 15:47:28.786983:
  867.    ------------------------------------------------------------------------
  868.    UPDATE sip:hulk@172.23.3.29;transport=tcp SIP/2.0
  869.    Via: SIP/2.0/TCP 74.123.77.190;rport;branch=z9hG4bK744XHp94y3FQB
  870.    Max-Forwards: 70
  871.    From: <sip:Hawkeye@demo.fusion.mgwnet.us>;tag=4N17g96m47XUN
  872.    To: "222-Hulk" <sip:hulk@demo.fusion.mgwnet.us>;tag=1FC7DE3B-3ED17E44
  873.    Call-ID: 96da71f40b5a8db54241414750c34469
  874.    CSeq: 126326760 UPDATE
  875.    Contact: <sip:Hawkeye@74.123.77.190:5060;transport=tcp>
  876.    User-Agent: FreeSWITCH
  877.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  878.    Supported: timer, path, replaces
  879.    Content-Type: application/sdp
  880.    Content-Disposition: session
  881.    Content-Length: 222
  882.    P-Asserted-Identity: "Hawkeye" <Hawkeye>
  883.  
  884.    v=0
  885.    o=FreeSWITCH 1533305841 1533305842 IN IP4 74.123.77.190
  886.    s=FreeSWITCH
  887.    c=IN IP4 74.123.77.190
  888.    t=0 0
  889.    m=audio 19806 RTP/AVP 9 127
  890.    a=rtpmap:9 G722/8000
  891.    a=rtpmap:127 telephone-event/8000
  892.    a=fmtp:127 0-16
  893.    a=ptime:20
  894.    ------------------------------------------------------------------------
  895. 2018-08-03 15:47:28.784637 [DEBUG] sofia.c:7084 Channel sofia/internal/hulk@demo.fusion.mgwnet.us entering state [calling][0]
  896. 2018-08-03 15:47:28.804635 [DEBUG] switch_rtp.c:7308 Correct audio ip/port confirmed.
  897. recv 630 bytes from udp/[172.23.3.30]:5060 at 15:47:28.830294:
  898.    ------------------------------------------------------------------------
  899.    SIP/2.0 200 OK
  900.    Via: SIP/2.0/UDP 74.123.77.190;rport;branch=z9hG4bK6UB5FUr11tS4F
  901.    From: "222-Hulk" <sip:hulk@demo.fusion.mgwnet.us>;tag=5yt0j4Qr1gmeH
  902.    To: "223-Hawkeye" <sip:Hawkeye@172.23.3.30>;tag=D0F484E8-A18E93
  903.    CSeq: 126326760 UPDATE
  904.    Call-ID: e4082487-11f8-1237-998e-00155dd37f11
  905.    Contact: <sip:Hawkeye@172.23.3.30>
  906.    User-Agent: PolycomVVX-VVX_310-UA/5.8.0.12848
  907.    Accept-Language: en
  908.    Content-Type: application/sdp
  909.    Content-Length: 185
  910.  
  911.    v=0
  912.    o=- 1533325648 1533325648 IN IP4 172.23.3.30
  913.    s=Polycom IP Phone
  914.    c=IN IP4 172.23.3.30
  915.    t=0 0
  916.    m=audio 2240 RTP/AVP 9 101
  917.    a=rtpmap:9 G722/8000
  918.    a=rtpmap:101 telephone-event/8000
  919.    ------------------------------------------------------------------------
  920. 2018-08-03 15:47:28.824639 [DEBUG] sofia.c:7084 Channel sofia/internal/Hawkeye@172.23.3.30 entering state [ready][200]
  921. 2018-08-03 15:47:28.824639 [DEBUG] sofia.c:7091 Duplicate SDP
  922. v=0
  923. o=- 1533325648 1533325648 IN IP4 172.23.3.30
  924. s=Polycom IP Phone
  925. c=IN IP4 172.23.3.30
  926. t=0 0
  927. m=audio 2240 RTP/AVP 9 101
  928. a=rtpmap:9 G722/8000
  929. a=rtpmap:101 telephone-event/8000
  930.  
  931. 2018-08-03 15:47:28.824639 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
  932. 2018-08-03 15:47:28.824639 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
  933. 2018-08-03 15:47:28.824639 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[G7221:115:32000:20:48000:1]
  934. 2018-08-03 15:47:28.824639 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[G7221:107:16000:20:32000:1]
  935. 2018-08-03 15:47:28.824639 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
  936. 2018-08-03 15:47:28.824639 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
  937. 2018-08-03 15:47:28.824639 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
  938. 2018-08-03 15:47:28.824639 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
  939. 2018-08-03 15:47:28.824639 [DEBUG] switch_core_media.c:4767 sofia/internal/Hawkeye@172.23.3.30 Set 2833 dtmf send payload to 101 recv payload to 101
  940. 2018-08-03 15:47:28.824639 [DEBUG] sofia.c:8061 Processing updated SDP
  941. 2018-08-03 15:47:28.824639 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/internal/Hawkeye@172.23.3.30.
  942. 2018-08-03 15:47:28.844639 [DEBUG] switch_rtp.c:7308 Correct audio ip/port confirmed.
  943. 2018-08-03 15:47:29.324719 [WARNING] sofia_reg.c:505 0b6a6aab-f5eb-4091-af69-3437de4db44a Failed Registration [904], setting retry to 30 seconds.
  944. recv 827 bytes from udp/[172.23.3.32]:5060 at 15:47:32.500259:
  945.    ------------------------------------------------------------------------
  946.    REGISTER sip:demo.fusion.mgwnet.us SIP/2.0
  947.    Via: SIP/2.0/UDP 172.23.3.32;branch=z9hG4bKf96af48a46834647
  948.    From: "224-Captain" <sip:Captain-America@demo.fusion.mgwnet.us>;tag=5F28E143-759E8BD8
  949.    To: <sip:Captain-America@demo.fusion.mgwnet.us>
  950.    CSeq: 228 REGISTER
  951.    Call-ID: 792eb9aee6fc9745c7b3ccbe21c348ad
  952.   Contact: <sip:Captain-America@172.23.3.32>;methods="INVITE,ACK,BYE,CANCEL,OPTIONS,INFO,MESSAGE,SUBSCRIBE,NOTIFY,PRACK,UPDATE,REFER"
  953.    User-Agent: PolycomVVX-VVX_310-UA/5.8.0.12848
  954.    Accept-Language: en
  955.    Authorization: Digest username="Captain-America", realm="demo.fusion.mgwnet.us", nonce="7de4af68-9736-11e8-bf23-815e756dd19a", qop=auth, cnonce="lvgMsdPsBooi3uL", nc=00000227, uri="sip:demo.fusion.mgwnet.us", response="7f38d4c2a2852a8b139f288734a52e96", algorithm=MD5
  956.    Max-Forwards: 70
  957.    Expires: 120
  958.    Content-Length: 0
  959.  
  960.    ------------------------------------------------------------------------
  961. send 587 bytes to udp/[172.23.3.32]:5060 at 15:47:32.511153:
  962.    ------------------------------------------------------------------------
  963.    SIP/2.0 200 OK
  964.    Via: SIP/2.0/UDP 172.23.3.32;branch=z9hG4bKf96af48a46834647;rport=5060
  965.    From: "224-Captain" <sip:Captain-America@demo.fusion.mgwnet.us>;tag=5F28E143-759E8BD8
  966.    To: <sip:Captain-America@demo.fusion.mgwnet.us>;tag=67KSmZ8UySa1c
  967.    Call-ID: 792eb9aee6fc9745c7b3ccbe21c348ad
  968.    CSeq: 228 REGISTER
  969.    Contact: <sip:Captain-America@172.23.3.32>;expires=120
  970.    Date: Fri, 03 Aug 2018 19:47:32 GMT
  971.    User-Agent: FreeSWITCH
  972.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  973.    Supported: timer, path, replaces
  974.    Content-Length: 0
  975.  
  976.    ------------------------------------------------------------------------
  977. 2018-08-03 15:47:45.884642 [DEBUG] sofia_reg.c:2435 Changing expire time to 58 by request of proxy sip:74.123.72.61
  978. recv 821 bytes from tcp/[172.23.3.29]:53546 at 15:47:49.451771:
  979.    ------------------------------------------------------------------------
  980.    REGISTER sip:demo.fusion.mgwnet.us;transport=tcp SIP/2.0
  981.    Via: SIP/2.0/TCP 172.23.3.29;branch=z9hG4bK700788cd32871D06
  982.    From: "222-Hulk" <sip:hulk@demo.fusion.mgwnet.us>;tag=608AD073-DD16EBBC
  983.    To: <sip:hulk@demo.fusion.mgwnet.us>
  984.    CSeq: 20 REGISTER
  985.    Call-ID: bf106f60dfd4af3c330015cbf6c34469
  986.   Contact: <sip:hulk@172.23.3.29;transport=tcp>;methods="INVITE,ACK,BYE,CANCEL,OPTIONS,INFO,MESSAGE,SUBSCRIBE,NOTIFY,PRACK,UPDATE,REFER"
  987.    User-Agent: PolycomVVX-VVX_310-UA/5.8.0.12848
  988.    Accept-Language: en
  989.    Authorization: Digest username="hulk", realm="demo.fusion.mgwnet.us", nonce="964dea20-9753-11e8-8096-815e756dd19a", qop=auth, cnonce="95iJ9rwiuszA4LP", nc=00000019, uri="sip:demo.fusion.mgwnet.us;transport=tcp", response="ed0411695c507b03b593cb6c4b98500c", algorithm=MD5
  990.    Max-Forwards: 70
  991.    Expires: 120
  992.    Content-Length: 0
  993.  
  994.    ------------------------------------------------------------------------
  995. send 565 bytes to tcp/[172.23.3.29]:53546 at 15:47:49.462651:
  996.    ------------------------------------------------------------------------
  997.    SIP/2.0 200 OK
  998.    Via: SIP/2.0/TCP 172.23.3.29;branch=z9hG4bK700788cd32871D06;rport=53546
  999.    From: "222-Hulk" <sip:hulk@demo.fusion.mgwnet.us>;tag=608AD073-DD16EBBC
  1000.    To: <sip:hulk@demo.fusion.mgwnet.us>;tag=7gDjptSZU20Kr
  1001.    Call-ID: bf106f60dfd4af3c330015cbf6c34469
  1002.    CSeq: 20 REGISTER
  1003.    Contact: <sip:hulk@172.23.3.29;transport=tcp>;expires=120
  1004.    Date: Fri, 03 Aug 2018 19:47:49 GMT
  1005.    User-Agent: FreeSWITCH
  1006.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  1007.    Supported: timer, path, replaces
  1008.    Content-Length: 0
  1009.  
  1010.    ------------------------------------------------------------------------
  1011. recv 840 bytes from tcp/[23.25.122.113]:46891 at 15:47:49.914226:
  1012.    ------------------------------------------------------------------------
  1013.    REGISTER sip:demo.fusion.mgwnet.us;transport=tcp SIP/2.0
  1014.    Via: SIP/2.0/TCP 192.168.0.112;branch=z9hG4bK12030edf1BF68800
  1015.    From: "220-Ironman" <sip:Ironman@demo.fusion.mgwnet.us>;tag=F8FE254F-456BFAB0
  1016.    To: <sip:Ironman@demo.fusion.mgwnet.us>
  1017.    CSeq: 24 REGISTER
  1018.    Call-ID: 7ad66d09cb1a531138d5a1d63dc34396
  1019.   Contact: <sip:Ironman@192.168.0.112;transport=tcp>;methods="INVITE,ACK,BYE,CANCEL,OPTIONS,INFO,MESSAGE,SUBSCRIBE,NOTIFY,PRACK,UPDATE,REFER"
  1020.    User-Agent: PolycomVVX-VVX_310-UA/5.8.0.12848
  1021.    Accept-Language: en
  1022.    Authorization: Digest username="Ironman", realm="demo.fusion.mgwnet.us", nonce="07847bf6-9753-11e8-8092-815e756dd19a", qop=auth, cnonce="eKjbloLaOkmYix+", nc=00000023, uri="sip:demo.fusion.mgwnet.us;transport=tcp", response="ecbd8c2c4dc6aee6f5becc41c2913b29", algorithm=MD5
  1023.    Max-Forwards: 70
  1024.    Expires: 120
  1025.    Content-Length: 0
  1026.  
  1027.    ------------------------------------------------------------------------
  1028. send 604 bytes to tcp/[23.25.122.113]:46891 at 15:47:49.922809:
  1029.    ------------------------------------------------------------------------
  1030.    SIP/2.0 200 OK
  1031.    Via: SIP/2.0/TCP 192.168.0.112;branch=z9hG4bK12030edf1BF68800;received=23.25.122.113;rport=46891
  1032.    From: "220-Ironman" <sip:Ironman@demo.fusion.mgwnet.us>;tag=F8FE254F-456BFAB0
  1033.    To: <sip:Ironman@demo.fusion.mgwnet.us>;tag=8S6arNa3rBQ6K
  1034.    Call-ID: 7ad66d09cb1a531138d5a1d63dc34396
  1035.    CSeq: 24 REGISTER
  1036.    Contact: <sip:Ironman@192.168.0.112;transport=tcp>;expires=120
  1037.    Date: Fri, 03 Aug 2018 19:47:49 GMT
  1038.    User-Agent: FreeSWITCH
  1039.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  1040.    Supported: timer, path, replaces
  1041.    Content-Length: 0
  1042.  
  1043.    ------------------------------------------------------------------------
  1044. 2018-08-03 15:47:54.884714 [DEBUG] sofia_reg.c:2435 Changing expire time to 57 by request of proxy sip:74.123.72.61
  1045. 2018-08-03 15:48:00.424639 [NOTICE] sofia_reg.c:448 Registering 0b6a6aab-f5eb-4091-af69-3437de4db44a
  1046. send 574 bytes to tcp/[172.23.3.29]:5060 at 15:48:00.786967:
  1047.    ------------------------------------------------------------------------
  1048.    BYE sip:hulk@172.23.3.29;transport=tcp SIP/2.0
  1049.    Via: SIP/2.0/TCP 74.123.77.190;rport;branch=z9hG4bK8DypKHt8Uc69p
  1050.    Max-Forwards: 70
  1051.    From: <sip:Hawkeye@demo.fusion.mgwnet.us>;tag=4N17g96m47XUN
  1052.    To: "222-Hulk" <sip:hulk@demo.fusion.mgwnet.us>;tag=1FC7DE3B-3ED17E44
  1053.    Call-ID: 96da71f40b5a8db54241414750c34469
  1054.    CSeq: 126326761 BYE
  1055.    Contact: <sip:Hawkeye@74.123.77.190:5060;transport=tcp>
  1056.    User-Agent: FreeSWITCH
  1057.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  1058.    Supported: timer, path, replaces
  1059.    Content-Length: 0
  1060.  
  1061.    ------------------------------------------------------------------------
  1062. 2018-08-03 15:48:00.784686 [DEBUG] sofia.c:7084 Channel sofia/internal/hulk@demo.fusion.mgwnet.us entering state [terminating][487]
  1063. 2018-08-03 15:48:00.784686 [NOTICE] sofia.c:8273 Hangup sofia/internal/hulk@demo.fusion.mgwnet.us [CS_EXECUTE] [ORIGINATOR_CANCEL]
  1064. 2018-08-03 15:48:00.784686 [DEBUG] mod_hash.c:297 Usage for demo.fusion.mgwnet.us_Hawkeye is now 0
  1065. 2018-08-03 15:48:00.784686 [DEBUG] switch_ivr_bridge.c:712 sofia/internal/hulk@demo.fusion.mgwnet.us ending bridge by request from read function
  1066. 2018-08-03 15:48:00.784686 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/internal/hulk@demo.fusion.mgwnet.us]
  1067. 2018-08-03 15:48:00.784686 [DEBUG] switch_ivr_bridge.c:706 sofia/internal/hulk@demo.fusion.mgwnet.us ending bridge by request from write function
  1068. 2018-08-03 15:48:00.784686 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/internal/Hawkeye@172.23.3.30]
  1069. 2018-08-03 15:48:00.784686 [NOTICE] switch_ivr_bridge.c:904 Hangup sofia/internal/Hawkeye@172.23.3.30 [CS_EXCHANGE_MEDIA] [NORMAL_CLEARING]
  1070. 2018-08-03 15:48:00.784686 [DEBUG] switch_core_state_machine.c:653 (sofia/internal/Hawkeye@172.23.3.30) State EXCHANGE_MEDIA going to sleep
  1071. 2018-08-03 15:48:00.784686 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/Hawkeye@172.23.3.30) Running State Change CS_HANGUP (Cur 2 Tot 840)
  1072. 2018-08-03 15:48:00.784686 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/Hawkeye@172.23.3.30) Callstate Change ACTIVE -> HANGUP
  1073. 2018-08-03 15:48:00.784686 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/Hawkeye@172.23.3.30) State HANGUP
  1074. 2018-08-03 15:48:00.784686 [DEBUG] mod_sofia.c:432 sofia/internal/Hawkeye@172.23.3.30 Overriding SIP cause 480 with 487 from the other leg
  1075. 2018-08-03 15:48:00.784686 [DEBUG] mod_sofia.c:438 Channel sofia/internal/Hawkeye@172.23.3.30 hanging up, cause: NORMAL_CLEARING
  1076. 2018-08-03 15:48:00.784686 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/internal/Hawkeye@172.23.3.30
  1077. send 545 bytes to udp/[172.23.3.30]:5060 at 15:48:00.795302:
  1078.    ------------------------------------------------------------------------
  1079.    BYE sip:Hawkeye@172.23.3.30 SIP/2.0
  1080.    Via: SIP/2.0/UDP 74.123.77.190;rport;branch=z9hG4bK9pQFNcBcSNvvj
  1081.    Max-Forwards: 70
  1082.    From: "222-Hulk" <sip:hulk@demo.fusion.mgwnet.us>;tag=5yt0j4Qr1gmeH
  1083.    To: <sip:Hawkeye@172.23.3.30>;tag=D0F484E8-A18E93
  1084.    Call-ID: e4082487-11f8-1237-998e-00155dd37f11
  1085.    CSeq: 126326761 BYE
  1086.    User-Agent: FreeSWITCH
  1087.   Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY, PUBLISH, SUBSCRIBE
  1088.    Supported: timer, path, replaces
  1089.    Reason: Q.850;cause=16;text="NORMAL_CLEARING"
  1090.    Content-Length: 0
  1091.  
  1092.    ------------------------------------------------------------------------
  1093. 2018-08-03 15:48:00.784686 [DEBUG] switch_core_state_machine.c:60 sofia/internal/Hawkeye@172.23.3.30 Standard HANGUP, cause: NORMAL_CLEARING
  1094. 2018-08-03 15:48:00.784686 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/Hawkeye@172.23.3.30) State HANGUP going to sleep
  1095. 2018-08-03 15:48:00.784686 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/Hawkeye@172.23.3.30) State Change CS_HANGUP -> CS_REPORTING
  1096. 2018-08-03 15:48:00.784686 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/Hawkeye@172.23.3.30) Running State Change CS_REPORTING (Cur 2 Tot 840)
  1097. 2018-08-03 15:48:00.784686 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/Hawkeye@172.23.3.30) State REPORTING
  1098. 2018-08-03 15:48:00.784686 [DEBUG] switch_core_state_machine.c:174 sofia/internal/Hawkeye@172.23.3.30 Standard REPORTING, cause: NORMAL_CLEARING
  1099. 2018-08-03 15:48:00.784686 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/Hawkeye@172.23.3.30) State REPORTING going to sleep
  1100. 2018-08-03 15:48:00.784686 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/Hawkeye@172.23.3.30) State Change CS_REPORTING -> CS_DESTROY
  1101. 2018-08-03 15:48:00.784686 [DEBUG] switch_core_session.c:1665 Session 840 (sofia/internal/Hawkeye@172.23.3.30) Locked, Waiting on external entities
  1102. 2018-08-03 15:48:00.784686 [DEBUG] switch_ivr_bridge.c:1715 sofia/internal/hulk@demo.fusion.mgwnet.us skip receive message [UNBRIDGE] (channel is hungup already)
  1103. 2018-08-03 15:48:00.784686 [DEBUG] switch_core_session.c:2815 sofia/internal/hulk@demo.fusion.mgwnet.us skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
  1104. 2018-08-03 15:48:00.784686 [DEBUG] switch_core_state_machine.c:650 (sofia/internal/hulk@demo.fusion.mgwnet.us) State EXECUTE going to sleep
  1105. 2018-08-03 15:48:00.784686 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/hulk@demo.fusion.mgwnet.us) Running State Change CS_HANGUP (Cur 2 Tot 840)
  1106. 2018-08-03 15:48:00.784686 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/hulk@demo.fusion.mgwnet.us) Callstate Change ACTIVE -> HANGUP
  1107. 2018-08-03 15:48:00.784686 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/hulk@demo.fusion.mgwnet.us) State HANGUP
  1108. 2018-08-03 15:48:00.784686 [DEBUG] mod_sofia.c:438 Channel sofia/internal/hulk@demo.fusion.mgwnet.us hanging up, cause: ORIGINATOR_CANCEL
  1109. 2018-08-03 15:48:00.784686 [DEBUG] switch_core_state_machine.c:60 sofia/internal/hulk@demo.fusion.mgwnet.us Standard HANGUP, cause: ORIGINATOR_CANCEL
  1110. 2018-08-03 15:48:00.784686 [DEBUG] switch_core_state_machine.c:852 (sofia/internal/hulk@demo.fusion.mgwnet.us) State HANGUP going to sleep
  1111. 2018-08-03 15:48:00.784686 [DEBUG] switch_core_state_machine.c:783 Hangup Command with no Session lua(app.lua hangup):
  1112.  
  1113. 2018-08-03 15:48:00.784686 [DEBUG] switch_core_state_machine.c:619 (sofia/internal/hulk@demo.fusion.mgwnet.us) State Change CS_HANGUP -> CS_REPORTING
  1114. 2018-08-03 15:48:00.784686 [DEBUG] switch_core_state_machine.c:584 (sofia/internal/hulk@demo.fusion.mgwnet.us) Running State Change CS_REPORTING (Cur 2 Tot 840)
  1115. 2018-08-03 15:48:00.784686 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/hulk@demo.fusion.mgwnet.us) State REPORTING
  1116. 2018-08-03 15:48:00.784686 [NOTICE] switch_core_session.c:1683 Session 840 (sofia/internal/Hawkeye@172.23.3.30) Ended
  1117. 2018-08-03 15:48:00.784686 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/Hawkeye@172.23.3.30 [CS_DESTROY]
  1118. 2018-08-03 15:48:00.784686 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/Hawkeye@172.23.3.30) Running State Change CS_DESTROY (Cur 1 Tot 840)
  1119. 2018-08-03 15:48:00.784686 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/Hawkeye@172.23.3.30) State DESTROY
  1120. 2018-08-03 15:48:00.784686 [DEBUG] mod_sofia.c:343 sofia/internal/Hawkeye@172.23.3.30 SOFIA DESTROY
  1121. 2018-08-03 15:48:00.784686 [DEBUG] switch_core_state_machine.c:181 sofia/internal/Hawkeye@172.23.3.30 Standard DESTROY
  1122. 2018-08-03 15:48:00.784686 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/Hawkeye@172.23.3.30) State DESTROY going to sleep
  1123. recv 409 bytes from udp/[172.23.3.30]:5060 at 15:48:00.812713:
  1124.    ------------------------------------------------------------------------
  1125.    SIP/2.0 200 OK
  1126.    Via: SIP/2.0/UDP 74.123.77.190;rport;branch=z9hG4bK9pQFNcBcSNvvj
  1127.    From: "222-Hulk" <sip:hulk@demo.fusion.mgwnet.us>;tag=5yt0j4Qr1gmeH
  1128.    To: "223-Hawkeye" <sip:Hawkeye@172.23.3.30>;tag=D0F484E8-A18E93
  1129.    CSeq: 126326761 BYE
  1130.    Call-ID: e4082487-11f8-1237-998e-00155dd37f11
  1131.    Contact: <sip:Hawkeye@172.23.3.30>
  1132.    User-Agent: PolycomVVX-VVX_310-UA/5.8.0.12848
  1133.    Accept-Language: en
  1134.    Content-Length: 0
  1135.  
  1136.    ------------------------------------------------------------------------
  1137. 2018-08-03 15:48:00.844634 [DEBUG] switch_core_state_machine.c:174 sofia/internal/hulk@demo.fusion.mgwnet.us Standard REPORTING, cause: ORIGINATOR_CANCEL
  1138. 2018-08-03 15:48:00.844634 [DEBUG] switch_core_state_machine.c:938 (sofia/internal/hulk@demo.fusion.mgwnet.us) State REPORTING going to sleep
  1139. 2018-08-03 15:48:00.844634 [DEBUG] switch_core_state_machine.c:610 (sofia/internal/hulk@demo.fusion.mgwnet.us) State Change CS_REPORTING -> CS_DESTROY
  1140. 2018-08-03 15:48:00.844634 [DEBUG] switch_core_session.c:1665 Session 839 (sofia/internal/hulk@demo.fusion.mgwnet.us) Locked, Waiting on external entities
  1141. 2018-08-03 15:48:00.844634 [NOTICE] switch_core_session.c:1683 Session 839 (sofia/internal/hulk@demo.fusion.mgwnet.us) Ended
  1142. 2018-08-03 15:48:00.844634 [NOTICE] switch_core_session.c:1687 Close Channel sofia/internal/hulk@demo.fusion.mgwnet.us [CS_DESTROY]
  1143. 2018-08-03 15:48:00.844634 [DEBUG] switch_core_state_machine.c:741 (sofia/internal/hulk@demo.fusion.mgwnet.us) Running State Change CS_DESTROY (Cur 0 Tot 840)
  1144. 2018-08-03 15:48:00.844634 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/hulk@demo.fusion.mgwnet.us) State DESTROY
  1145. 2018-08-03 15:48:00.844634 [DEBUG] mod_sofia.c:343 sofia/internal/hulk@demo.fusion.mgwnet.us SOFIA DESTROY
  1146. 2018-08-03 15:48:00.844634 [DEBUG] switch_core_state_machine.c:181 sofia/internal/hulk@demo.fusion.mgwnet.us Standard DESTROY
  1147. 2018-08-03 15:48:00.844634 [DEBUG] switch_core_state_machine.c:751 (sofia/internal/hulk@demo.fusion.mgwnet.us) State DESTROY going to sleep
  1148. 2018-08-03 15:48:01.424668 [WARNING] sofia_reg.c:505 0b6a6aab-f5eb-4091-af69-3437de4db44a Failed Registration [904], setting retry to 30 seconds.
  1149. freeswitch@fusion.mgwnet.us> sofia profile internal siptrace off
  1150. Disabled sip debugging on internal
  1151. 2018-08-03 15:48:09.884640 [DEBUG] sofia_reg.c:2435 Changing expire time to 58 by request of proxy sip:74.123.72.61
  1152. freeswitch@fusion.mgwnet.us>
  1153.  

Reply to "Sip Trace"