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.