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