1. recv 1221 bytes from udp/[172.16.254.191]:58573 at 14:40:16.144516:
2.    ------------------------------------------------------------------------
3.    INVITE sip:84810@pci.vivint.com:5080 SIP/2.0
4.    Via: SIP/2.0/UDP apex.local:5060;branch=z9hG4bK37E57E41372
5.    From: <sip:8017189923@apex.local>;tag=96CADFAC-220E
6.    To: <sip:84810@pci.vivint.com>
7.    Date: Thu, 10 Nov 2016 21:40:16 GMT
8.    Call-ID: 1A4DB109-A6C511E6-931DAAA7-2043EB45@172.16.254.191
9.    Supported: timer,resource-priority,replaces,histinfo,sdp-anat
10.    Require: 100rel
11.    Min-SE:  1800
12.    Cisco-Guid: 0441279221-2797933030-2467801767-0541322053
13.    User-Agent: Cisco-SIPGateway/IOS-15.3.3.M5
14.    Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, UPDATE, REFER, SUBSCRIBE, NOTIFY, INFO, REGISTER
15.    CSeq: 101 INVITE
16.    Timestamp: 1478814016
17.    Contact: <sip:8017189923@172.16.254.191:5060>
18.    History-Info: <sip:84810@10.2.160.150:5080>;index=1
19.    Expires: 180
20.    Allow-Events: telephone-event
21.    Max-Forwards: 53
22.    P-Asserted-Identity: <sip:8017189923@172.16.254.191>
23.    Session-Expires:  1800
24.    Content-Type: application/sdp
25.    Content-Disposition: session;handling=required
26.    Content-Length: 253
27.  
28.    v=0
29.    o=CiscoSystemsSIP-GW-UserAgent 8801 4020 IN IP4 172.16.254.191
30.    s=SIP Call
31.    c=IN IP4 172.16.254.191
32.    t=0 0
33.    m=audio 20606 RTP/AVP 0 120
34.    c=IN IP4 172.16.254.191
35.    a=rtpmap:0 PCMU/8000
36.    a=rtpmap:120 telephone-event/8000
37.    a=fmtp:120 0-15
38.    a=ptime:20
39.    ------------------------------------------------------------------------
40. send 368 bytes to udp/[172.16.254.191]:5060 at 14:40:16.145073:
41.    ------------------------------------------------------------------------
42.    SIP/2.0 100 Trying
43.    Via: SIP/2.0/UDP apex.local:5060;branch=z9hG4bK37E57E41372;received=172.16.254.191
44.    From: <sip:8017189923@apex.local>;tag=96CADFAC-220E
45.    To: <sip:84810@pci.vivint.com>
46.    Call-ID: 1A4DB109-A6C511E6-931DAAA7-2043EB45@172.16.254.191
47.    CSeq: 101 INVITE
48.    Timestamp: 1478814016 0.000159
49.    User-Agent: FreeSWITCH-mod_sofia/1.6.12~64bit
50.    Content-Length: 0
51.  
52.    ------------------------------------------------------------------------
53. 2016-11-10 14:40:16.143933 [NOTICE] switch_channel.c:1104 New Channel sofia/external/8017189923@apex.local [44b78e98-a78e-11e6-b7e1-85d38162b0e3]
54. 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:584 (sofia/external/8017189923@apex.local) Running State Change CS_NEW
55. 2016-11-10 14:40:16.143933 [DEBUG] sofia.c:9725 sofia/external/8017189923@apex.local receiving invite from 172.16.254.191:58573 version: 1.6.12  64bit
56. 2016-11-10 14:40:16.143933 [DEBUG] sofia.c:7014 Channel sofia/external/8017189923@apex.local entering state [received][100]
57. 2016-11-10 14:40:16.143933 [DEBUG] sofia.c:7024 Remote SDP:
58. v=0
59. o=CiscoSystemsSIP-GW-UserAgent 8801 4020 IN IP4 172.16.254.191
60. s=SIP Call
61. c=IN IP4 172.16.254.191
62. t=0 0
63. m=audio 20606 RTP/AVP 0 120
64. c=IN IP4 172.16.254.191
65. a=rtpmap:0 PCMU/8000
66. a=rtpmap:120 telephone-event/8000
67. a=fmtp:120 0-15
68. a=ptime:20
69.  
70. 2016-11-10 14:40:16.143933 [DEBUG] sofia.c:7378 (sofia/external/8017189923@apex.local) State Change CS_NEW -> CS_INIT
71. 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:603 (sofia/external/8017189923@apex.local) State NEW
72. 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:584 (sofia/external/8017189923@apex.local) Running State Change CS_INIT
73. 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:627 (sofia/external/8017189923@apex.local) State INIT
74. 2016-11-10 14:40:16.143933 [DEBUG] mod_sofia.c:90 sofia/external/8017189923@apex.local SOFIA INIT
75. 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:40 sofia/external/8017189923@apex.local Standard INIT
76. 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:48 (sofia/external/8017189923@apex.local) State Change CS_INIT -> CS_ROUTING
77. 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:627 (sofia/external/8017189923@apex.local) State INIT going to sleep
78. 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:584 (sofia/external/8017189923@apex.local) Running State Change CS_ROUTING
79. 2016-11-10 14:40:16.143933 [DEBUG] switch_channel.c:2249 (sofia/external/8017189923@apex.local) Callstate Change DOWN -> RINGING
80. 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:643 (sofia/external/8017189923@apex.local) State ROUTING
81. 2016-11-10 14:40:16.143933 [DEBUG] mod_sofia.c:143 sofia/external/8017189923@apex.local SOFIA ROUTING
82. 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:236 sofia/external/8017189923@apex.local Standard ROUTING
83. 2016-11-10 14:40:16.143933 [INFO] mod_dialplan_xml.c:637 Processing 8017189923 <8017189923>->84810 in context vivint
84. Dialplan: sofia/external/8017189923@apex.local parsing [vivint->unloop] continue=false
85. Dialplan: sofia/external/8017189923@apex.local Regex (PASS) [unloop] ${unroll_loops}(true) =~ /^true$/ break=on-false
86. Dialplan: sofia/external/8017189923@apex.local Regex (FAIL) [unloop] ${sip_looped_call}() =~ /^true$/ break=on-false
87. Dialplan: sofia/external/8017189923@apex.local parsing [vivint->outside_call] continue=true
88. Dialplan: sofia/external/8017189923@apex.local Absolute Condition [outside_call]
89. Dialplan: sofia/external/8017189923@apex.local Action set(outside_call=true)
90. Dialplan: sofia/external/8017189923@apex.local Action export(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
91. Dialplan: sofia/external/8017189923@apex.local parsing [vivint->call_debug] continue=true
92. Dialplan: sofia/external/8017189923@apex.local Regex (FAIL) [call_debug] ${call_debug}(false) =~ /^true$/ break=never
93. Dialplan: sofia/external/8017189923@apex.local parsing [vivint->test] continue=false
94. Dialplan: sofia/external/8017189923@apex.local Regex (PASS) [test] chan_name(sofia/external/8017189923@apex.local) =~ /^sofia\/external/ break=on-false
95. Dialplan: sofia/external/8017189923@apex.local Regex (FAIL) [test] destination_number(84810) =~ /^5551212$/ break=on-false
96. Dialplan: sofia/external/8017189923@apex.local parsing [vivint->default] continue=false
97. Dialplan: sofia/external/8017189923@apex.local Regex (PASS) [default] chan_name(sofia/external/8017189923@apex.local) =~ /^sofia\/external/ break=on-false
98. Dialplan: sofia/external/8017189923@apex.local Regex (PASS) [default] destination_number(84810) =~ /^.....$/ break=on-false
99. Dialplan: sofia/external/8017189923@apex.local Action set(domain_name=freeswitch1-stg.pci.vivint.com)
100. Dialplan: sofia/external/8017189923@apex.local Action bridge(sofia/external/${destination_number}@apex.local;fs_path=sip:172.16.254.122|sofia/external/${destination_number}@apex.local;fs_path=sip:172.16.254.125)
101. 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:286 (sofia/external/8017189923@apex.local) State Change CS_ROUTING -> CS_EXECUTE
102. 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:643 (sofia/external/8017189923@apex.local) State ROUTING going to sleep
103. 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:584 (sofia/external/8017189923@apex.local) Running State Change CS_EXECUTE
104. 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:650 (sofia/external/8017189923@apex.local) State EXECUTE
105. 2016-11-10 14:40:16.143933 [DEBUG] mod_sofia.c:198 sofia/external/8017189923@apex.local SOFIA EXECUTE
106. 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:328 sofia/external/8017189923@apex.local Standard EXECUTE
107. EXECUTE sofia/external/8017189923@apex.local set(outside_call=true)
108. 2016-11-10 14:40:16.143933 [DEBUG] mod_dptools.c:1519 SET sofia/external/8017189923@apex.local [outside_call]=[true]
109. EXECUTE sofia/external/8017189923@apex.local export(RFC2822_DATE=Thu, 10 Nov 2016 14:40:16 -0700)
110. 2016-11-10 14:40:16.143933 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Thu, 10 Nov 2016 14:40:16 -0700]
111. EXECUTE sofia/external/8017189923@apex.local set(domain_name=freeswitch1-stg.pci.vivint.com)
112. 2016-11-10 14:40:16.143933 [DEBUG] mod_dptools.c:1519 SET sofia/external/8017189923@apex.local [domain_name]=[freeswitch1-stg.pci.vivint.com]
113. EXECUTE sofia/external/8017189923@apex.local bridge(sofia/external/84810@apex.local;fs_path=sip:172.16.254.122|sofia/external/84810@apex.local;fs_path=sip:172.16.254.125)
114. 2016-11-10 14:40:16.143933 [DEBUG] switch_channel.c:1250 sofia/external/8017189923@apex.local EXPORTING[export_vars] [RFC2822_DATE]=[Thu, 10 Nov 2016 14:40:16 -0700] to event
115. 2016-11-10 14:40:16.143933 [DEBUG] switch_ivr_originate.c:2138 Parsing global variables
116. 2016-11-10 14:40:16.143933 [NOTICE] switch_channel.c:1104 New Channel sofia/external/84810@apex.local [44b81c28-a78e-11e6-b7e9-85d38162b0e3]
117. 2016-11-10 14:40:16.143933 [DEBUG] mod_sofia.c:4757 (sofia/external/84810@apex.local) State Change CS_NEW -> CS_INIT
118. 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:584 (sofia/external/84810@apex.local) Running State Change CS_INIT
119. 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:627 (sofia/external/84810@apex.local) State INIT
120. 2016-11-10 14:40:16.143933 [DEBUG] mod_sofia.c:90 sofia/external/84810@apex.local SOFIA INIT
121. 2016-11-10 14:40:16.143933 [DEBUG] sofia_glue.c:1253 sip:172.16.254.122 Setting proxy route to sofia/external/84810@apex.local
122. 2016-11-10 14:40:16.143933 [DEBUG] sofia_glue.c:1282 sofia/external/84810@apex.local sending invite version: 1.6.12  64bit
123. Local SDP:
124. v=0
125. o=CiscoSystemsSIP-GW-UserAgent 8801 4020 IN IP4 172.16.254.191
126. s=SIP Call
127. c=IN IP4 172.16.254.191
128. t=0 0
129. m=audio 20606 RTP/AVP 0 120
130. c=IN IP4 172.16.254.191
131. a=rtpmap:0 PCMU/8000
132. a=rtpmap:120 telephone-event/8000
133. a=fmtp:120 0-15
134. a=ptime:20
135.  
136. 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:40 sofia/external/84810@apex.local Standard INIT
137. 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:48 (sofia/external/84810@apex.local) State Change CS_INIT -> CS_ROUTING
138. 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:627 (sofia/external/84810@apex.local) State INIT going to sleep
139. 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:584 (sofia/external/84810@apex.local) Running State Change CS_ROUTING
140. send 1066 bytes to udp/[172.16.254.122]:5060 at 14:40:16.150561:
141.    ------------------------------------------------------------------------
142.    INVITE sip:84810@apex.local SIP/2.0
143.    Via: SIP/2.0/UDP 10.2.160.150:5080;rport;branch=z9hG4bKDXK2aatgeHp7p
144.    Route: <sip:172.16.254.122>
145.    Max-Forwards: 52
146.    From: "8017189923" <sip:8017189923@10.2.160.150>;tag=X7c78r50489Qp
147.    To: <sip:84810@apex.local>
148.    Call-ID: 1c1692e7-2231-1235-5bbe-005056b26115
149.    CSeq: 99070944 INVITE
150.    Contact: <sip:mod_sofia@10.2.160.150:5080>
151.    User-Agent: FreeSWITCH-mod_sofia/1.6.12~64bit
152.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, PRACK, NOTIFY
153.    Supported: precondition, 100rel, timer, path, replaces
154. 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:643 (sofia/external/84810@apex.local) State ROUTING
155. 2016-11-10 14:40:16.143933 [DEBUG] mod_sofia.c:143 sofia/external/84810@apex.local SOFIA ROUTING
156.    Allow-Events: talk, hold, conference, refer
157.    Privacy: none
158. 2016-11-10 14:40:16.143933 [DEBUG] switch_ivr_originate.c:67 (sofia/external/84810@apex.local) State Change CS_ROUTING -> CS_CONSUME_MEDIA
159.    Content-Type: application/sdp
160.    Content-Disposition: session
161.    Content-Length: 253
162. 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:643 (sofia/external/84810@apex.local) State ROUTING going to sleep
163.    X-FS-Support: update_display,send_info
164.    P-Asserted-Identity: "8017189923" <sip:8017189923@10.2.160.150>
165.  
166.    v=0
167. 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:584 (sofia/external/84810@apex.local) Running State Change CS_CONSUME_MEDIA
168.    o=CiscoSystemsSIP-GW-UserAgent 8801 4020 IN IP4 172.16.254.191
169.    s=SIP Call
170.    c=IN IP4 172.16.254.191
171.    t=0 0
172.    m=audio 20606 RTP/AVP 0 120
173.    c=IN IP4 172.16.254.191
174.    a=rtpmap:0 PCMU/8000
175.    a=rtpmap:120 telephone-event/8000
176.    a=fmtp:120 0-15
177.    a=ptime:20
178.    ------------------------------------------------------------------------
179. 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:662 (sofia/external/84810@apex.local) State CONSUME_MEDIA
180. 2016-11-10 14:40:16.143933 [DEBUG] switch_core_state_machine.c:662 (sofia/external/84810@apex.local) State CONSUME_MEDIA going to sleep
181. 2016-11-10 14:40:16.143933 [DEBUG] sofia.c:7014 Channel sofia/external/84810@apex.local entering state [calling][0]
182. recv 282 bytes from udp/[172.16.254.122]:5060 at 14:40:16.154565:
183.    ------------------------------------------------------------------------
184.    SIP/2.0 100 Trying
185.    Call-ID: 1c1692e7-2231-1235-5bbe-005056b26115
186.    CSeq: 99070944 INVITE
187.    From: "8017189923" <sip:8017189923@10.2.160.150>;tag=X7c78r50489Qp
188.    To: <sip:84810@apex.local>
189.    Via: SIP/2.0/UDP 10.2.160.150:5080;rport=5080;branch=z9hG4bKDXK2aatgeHp7p
190.    Content-Length: 0
191.  
192.    ------------------------------------------------------------------------
193. recv 1652 bytes from udp/[172.16.254.122]:5060 at 14:40:16.197958:
194.    ------------------------------------------------------------------------
195.    SIP/2.0 183 Session Progress
196.    Remote-Party-ID: <sip:84810@172.16.254.190>;party=called;screen=no;privacy=off
197.    P-Asserted-Identity: <sip:84810@172.16.254.190>
198.    Supported: 100rel, histinfo, join, replaces, sdp-anat, timer
199.    P-Location: SM;origlocname="Provo";origsiglocname="Provo";origmedialocname="Provo";termlocname="Provo";termsiglocname="Provo";termmedialocname="Provo";smaccounting="true"
200.    P-AV-Message-Id: 1_2
201.    Server: Avaya CM/R016x.03.0.124.0 AVAYA-SM-6.3.17.0.631705
202.    Av-Global-Session-ID: 44b44bc0-a78e-11e6-a95f-0050568452e0
203.    RSeq: 1
204.    Require: 100rel
205.    Record-Route: <sip:172.16.254.100;transport=tcp;lr>
206.    Record-Route: <sip:rw-39656cad@172.16.254.122;lr;transport=TCP>
207.    Record-Route: <sip:172.16.254.123:15060;transport=tcp;lr;ibmsid=local.1471281760668_17021202_17076146;ibmdrr>
208.    Record-Route: <sip:172.16.254.123:15060;transport=udp;lr;ibmsid=local.1471281760668_17021202_17076146;ibmdrr>
209.    Record-Route: <sip:rw-39656cad@172.16.254.122;lr>
210.    Contact: <sip:84810@172.16.254.100;transport=tcp;gsid=44b44bc0-a78e-11e6-a95f-0050568452e0>
211.    Allow: INVITE, ACK, OPTIONS, BYE, CANCEL, NOTIFY, REFER, INFO, PRACK, UPDATE
212.    To: <sip:84810@apex.local>;tag=0c81bbe41b6e615223571eb39c00
213.    From: "8017189923" <sip:8017189923@10.2.160.150>;tag=X7c78r50489Qp
214.    Call-ID: 1c1692e7-2231-1235-5bbe-005056b26115
215.    CSeq: 99070944 INVITE
216.    Via: SIP/2.0/UDP 10.2.160.150:5080;rport=5080;branch=z9hG4bKDXK2aatgeHp7p
217.    Content-Type: application/sdp
218.    Content-Length: 201
219.  
220.    v=0
221.    o=- 1478814016 2 IN IP4 172.16.254.100
222.    s=-
223.    c=IN IP4 172.16.254.106
224.    b=AS:64
225.    t=0 0
226.    m=audio 47932 RTP/AVP 0 120
227.    a=sendrecv
228.    a=rtpmap:0 PCMU/8000
229.    a=rtpmap:120 telephone-event/8000
230.    a=ptime:20
231.    ------------------------------------------------------------------------
232. send 1028 bytes to udp/[172.16.254.122]:5060 at 14:40:16.198417:
233.    ------------------------------------------------------------------------
234.    PRACK sip:84810@172.16.254.100;transport=tcp;gsid=44b44bc0-a78e-11e6-a95f-0050568452e0 SIP/2.0
235.    Via: SIP/2.0/UDP 10.2.160.150:5080;rport;branch=z9hG4bKe6cUc5amBtctj
236.    Route: <sip:rw-39656cad@172.16.254.122;lr>
237.    Route: <sip:172.16.254.123:15060;transport=udp;lr;ibmsid=local.1471281760668_17021202_17076146;ibmdrr>
238.    Route: <sip:172.16.254.123:15060;transport=tcp;lr;ibmsid=local.1471281760668_17021202_17076146;ibmdrr>
239.    Route: <sip:rw-39656cad@172.16.254.122;lr;transport=TCP>
240.    Route: <sip:172.16.254.100;transport=tcp;lr>
241.    Max-Forwards: 70
242.    From: "8017189923" <sip:8017189923@10.2.160.150>;tag=X7c78r50489Qp
243.    To: <sip:84810@apex.local>;tag=0c81bbe41b6e615223571eb39c00
244.    Call-ID: 1c1692e7-2231-1235-5bbe-005056b26115
245.    CSeq: 99070945 PRACK
246.    Contact: <sip:mod_sofia@10.2.160.150:5080>
247.    RAck: 1 99070944 INVITE
248.    User-Agent: FreeSWITCH-mod_sofia/1.6.12~64bit
249.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, PRACK, NOTIFY
250.    Supported: precondition, 100rel, timer, path, replaces
251.    Content-Length: 0
252.  
253.    ------------------------------------------------------------------------
254. 2016-11-10 14:40:16.183913 [DEBUG] sofia.c:7014 Channel sofia/external/84810@apex.local entering state [proceeding][183]
255. 2016-11-10 14:40:16.183913 [DEBUG] sofia.c:7024 Remote SDP:
256. v=0
257. o=- 1478814016 2 IN IP4 172.16.254.100
258. s=-
259. c=IN IP4 172.16.254.106
260. b=AS:64
261. t=0 0
262. m=audio 47932 RTP/AVP 0 120
263. a=rtpmap:0 PCMU/8000
264. a=rtpmap:120 telephone-event/8000
265. a=ptime:20
266.  
267. 2016-11-10 14:40:16.183913 [NOTICE] sofia.c:7170 Pre-Answer sofia/external/84810@apex.local!
268. 2016-11-10 14:40:16.183913 [DEBUG] switch_channel.c:3471 (sofia/external/84810@apex.local) Callstate Change DOWN -> EARLY
269. recv 430 bytes from udp/[172.16.254.122]:5060 at 14:40:16.202892:
270.    ------------------------------------------------------------------------
271.    SIP/2.0 200 OK
272.    From: "8017189923" <sip:8017189923@10.2.160.150>;tag=X7c78r50489Qp
273.    To: <sip:84810@apex.local>;tag=0c81bbe41b6e615223571eb39c00
274.    Call-ID: 1c1692e7-2231-1235-5bbe-005056b26115
275.    CSeq: 99070945 PRACK
276.    Via: SIP/2.0/UDP 10.2.160.150:5080;rport=5080;branch=z9hG4bKe6cUc5amBtctj
277.    Av-Global-Session-ID: 44b44bc0-a78e-11e6-a95f-0050568452e0
278.    Server: Avaya CM/R016x.03.0.124.0 AVAYA-SM-6.3.17.0.631705
279.    Content-Length: 0
280.  
281.    ------------------------------------------------------------------------
282. 2016-11-10 14:40:16.203937 [DEBUG] switch_ivr_originate.c:3686 Originate Resulted in Success: [sofia/external/84810@apex.local]
283. 2016-11-10 14:40:16.203937 [INFO] switch_ivr.c:784 Sending early media
284. 2016-11-10 14:40:16.203937 [NOTICE] mod_sofia.c:2314 Pre-Answer sofia/external/8017189923@apex.local!
285. 2016-11-10 14:40:16.203937 [DEBUG] switch_channel.c:3471 (sofia/external/8017189923@apex.local) Callstate Change RINGING -> EARLY
286. send 1178 bytes to udp/[172.16.254.191]:5060 at 14:40:16.212794:
287.    ------------------------------------------------------------------------
288.    SIP/2.0 183 Session Progress
289.    Via: SIP/2.0/UDP apex.local:5060;branch=z9hG4bK37E57E41372;received=172.16.254.191
290.    From: <sip:8017189923@apex.local>;tag=96CADFAC-220E
291.    To: <sip:84810@pci.vivint.com>;tag=vyKe7XmX7ZK5a
292.    Call-ID: 1A4DB109-A6C511E6-931DAAA7-2043EB45@172.16.254.191
293.    CSeq: 101 INVITE
294.    Contact: <sip:84810@10.2.160.150:5080;transport=udp>
295.    RSeq: 446981481
296.    User-Agent: FreeSWITCH-mod_sofia/1.6.12~64bit
297.    Accept: application/sdp
298.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, PRACK, NOTIFY
299.    Require: 100rel
300.    Supported: precondition, 100rel, timer, path, replaces
301.    Allow-Events: talk, hold, conference, refer
302.    Content-Type: application/sdp
303.    Content-Disposition: session
304.    Content-Length: 189
305.    P-Location: SM;origlocname="Provo";origsiglocname="Provo";origmedialocname="Provo";termlocname="Provo";termsiglocname="Provo";termmedialocname="Provo";smaccounting="true"
306.    P-AV-Message-Id: 1_2
307.    P-Asserted-Identity: "84810" <sip:84810@pci.vivint.com>
308.  
309.    v=0
310.    o=- 1478814016 2 IN IP4 172.16.254.100
311.    s=-
312.    c=IN IP4 172.16.254.106
313.    b=AS:64
314.    t=0 0
315.    m=audio 47932 RTP/AVP 0 120
316.    a=rtpmap:0 PCMU/8000
317.    a=rtpmap:120 telephone-event/8000
318.    a=ptime:20
319.    ------------------------------------------------------------------------
320. 2016-11-10 14:40:16.203937 [DEBUG] switch_ivr_bridge.c:1324 (sofia/external/8017189923@apex.local) State Change CS_EXECUTE -> CS_HIBERNATE
321. 2016-11-10 14:40:16.203937 [DEBUG] switch_ivr_bridge.c:1325 (sofia/external/84810@apex.local) State Change CS_CONSUME_MEDIA -> CS_HIBERNATE
322. 2016-11-10 14:40:16.203937 [DEBUG] switch_core_state_machine.c:584 (sofia/external/84810@apex.local) Running State Change CS_HIBERNATE
323. 2016-11-10 14:40:16.203937 [DEBUG] sofia.c:7014 Channel sofia/external/8017189923@apex.local entering state [early][183]
324. 2016-11-10 14:40:16.203937 [DEBUG] switch_core_state_machine.c:650 (sofia/external/8017189923@apex.local) State EXECUTE going to sleep
325. 2016-11-10 14:40:16.203937 [DEBUG] switch_core_state_machine.c:584 (sofia/external/8017189923@apex.local) Running State Change CS_HIBERNATE
326. 2016-11-10 14:40:16.203937 [DEBUG] switch_core_state_machine.c:665 (sofia/external/84810@apex.local) State HIBERNATE
327. 2016-11-10 14:40:16.203937 [DEBUG] mod_sofia.c:180 sofia/external/84810@apex.local SOFIA HIBERNATE
328. 2016-11-10 14:40:16.203937 [DEBUG] switch_core_state_machine.c:420 sofia/external/84810@apex.local Standard HIBERNATE
329. 2016-11-10 14:40:16.203937 [DEBUG] switch_core_state_machine.c:665 (sofia/external/84810@apex.local) State HIBERNATE going to sleep
330. 2016-11-10 14:40:16.203937 [DEBUG] switch_core_state_machine.c:665 (sofia/external/8017189923@apex.local) State HIBERNATE
331. 2016-11-10 14:40:16.203937 [DEBUG] mod_sofia.c:180 sofia/external/8017189923@apex.local SOFIA HIBERNATE
332. 2016-11-10 14:40:16.203937 [DEBUG] switch_core_state_machine.c:420 sofia/external/8017189923@apex.local Standard HIBERNATE
333. 2016-11-10 14:40:16.203937 [DEBUG] switch_core_state_machine.c:665 (sofia/external/8017189923@apex.local) State HIBERNATE going to sleep
334. recv 434 bytes from udp/[172.16.254.191]:58573 at 14:40:16.214528:
335.    ------------------------------------------------------------------------
336.    PRACK sip:84810@pci.vivint.com:5080;transport=udp SIP/2.0
337.    Via: SIP/2.0/UDP apex.local:5060;branch=z9hG4bK37E57E5EB7
338.    From: <sip:8017189923@apex.local>;tag=96CADFAC-220E
339.    To: <sip:84810@pci.vivint.com>;tag=vyKe7XmX7ZK5a
340.    Date: Thu, 10 Nov 2016 21:40:16 GMT
341.    Call-ID: 1A4DB109-A6C511E6-931DAAA7-2043EB45@172.16.254.191
342.    CSeq: 102 PRACK
343.    RAck: 446981481 101 INVITE
344.    Allow-Events: telephone-event
345.    Max-Forwards: 70
346.    Content-Length: 0
347.  
348.    ------------------------------------------------------------------------
349. send 580 bytes to udp/[172.16.254.191]:5060 at 14:40:16.214690:
350.    ------------------------------------------------------------------------
351.    SIP/2.0 200 OK
352.    Via: SIP/2.0/UDP apex.local:5060;branch=z9hG4bK37E57E5EB7;received=172.16.254.191
353.    From: <sip:8017189923@apex.local>;tag=96CADFAC-220E
354.    To: <sip:84810@pci.vivint.com>;tag=vyKe7XmX7ZK5a
355.    Call-ID: 1A4DB109-A6C511E6-931DAAA7-2043EB45@172.16.254.191
356.    CSeq: 102 PRACK
357.    Contact: <sip:84810@10.2.160.150:5080;transport=udp>
358.    User-Agent: FreeSWITCH-mod_sofia/1.6.12~64bit
359.    Accept: application/sdp
360.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, PRACK, NOTIFY
361.    Supported: precondition, 100rel, timer, path, replaces
362.    Content-Length: 0
363.  
364.    ------------------------------------------------------------------------
365. recv 1637 bytes from udp/[172.16.254.122]:5060 at 14:40:17.170480:
366.    ------------------------------------------------------------------------
367.    SIP/2.0 180 Ringing
368.    Remote-Party-ID: <sip:84810@172.16.254.190>;party=called;screen=no;privacy=off
369.    P-Asserted-Identity: <sip:84810@172.16.254.190>
370.    Supported: 100rel, histinfo, join, replaces, sdp-anat, timer
371.    P-Location: SM;origlocname="Provo";origsiglocname="Provo";origmedialocname="Provo";termlocname="Provo";termsiglocname="Provo";termmedialocname="Provo";smaccounting="true"
372.    P-AV-Message-Id: 1_2
373.    Server: Avaya CM/R016x.03.0.124.0 AVAYA-SM-6.3.17.0.631705
374.    Av-Global-Session-ID: 44b44bc0-a78e-11e6-a95f-0050568452e0
375.    RSeq: 2
376.    Require: 100rel
377.    Record-Route: <sip:172.16.254.100;transport=tcp;lr>
378.    Record-Route: <sip:rw-39656cad@172.16.254.122;lr;transport=TCP>
379.    Record-Route: <sip:172.16.254.123:15060;transport=tcp;lr;ibmsid=local.1471281760668_17021202_17076146;ibmdrr>
380.    Record-Route: <sip:172.16.254.123:15060;transport=udp;lr;ibmsid=local.1471281760668_17021202_17076146;ibmdrr>
381.    Record-Route: <sip:rw-39656cad@172.16.254.122;lr>
382.    Contact: <sip:172.16.254.100;transport=tcp;gsid=44b44bc0-a78e-11e6-a95f-0050568452e0>
383.    Allow: INVITE, ACK, OPTIONS, BYE, CANCEL, NOTIFY, REFER, INFO, PRACK, UPDATE
384.    To: <sip:84810@apex.local>;tag=0c81bbe41b6e615223571eb39c00
385.    From: "8017189923" <sip:8017189923@10.2.160.150>;tag=X7c78r50489Qp
386.    Call-ID: 1c1692e7-2231-1235-5bbe-005056b26115
387.    CSeq: 99070944 INVITE
388.    Via: SIP/2.0/UDP 10.2.160.150:5080;rport=5080;branch=z9hG4bKDXK2aatgeHp7p
389.    Content-Type: application/sdp
390.    Content-Length: 201
391.  
392.    v=0
393.    o=- 1478814016 2 IN IP4 172.16.254.100
394.    s=-
395.    c=IN IP4 172.16.254.106
396.    b=AS:64
397.    t=0 0
398.    m=audio 47932 RTP/AVP 0 120
399.    a=sendrecv
400.    a=rtpmap:0 PCMU/8000
401.    a=rtpmap:120 telephone-event/8000
402.    a=ptime:20
403.    ------------------------------------------------------------------------
404. send 1028 bytes to udp/[172.16.254.122]:5060 at 14:40:17.171796:
405.    ------------------------------------------------------------------------
406.    PRACK sip:84810@172.16.254.100;transport=tcp;gsid=44b44bc0-a78e-11e6-a95f-0050568452e0 SIP/2.0
407.    Via: SIP/2.0/UDP 10.2.160.150:5080;rport;branch=z9hG4bKFF6Ke0UQ822ce
408.    Route: <sip:rw-39656cad@172.16.254.122;lr>
409.    Route: <sip:172.16.254.123:15060;transport=udp;lr;ibmsid=local.1471281760668_17021202_17076146;ibmdrr>
410.    Route: <sip:172.16.254.123:15060;transport=tcp;lr;ibmsid=local.1471281760668_17021202_17076146;ibmdrr>
411.    Route: <sip:rw-39656cad@172.16.254.122;lr;transport=TCP>
412.    Route: <sip:172.16.254.100;transport=tcp;lr>
413.    Max-Forwards: 70
414.    From: "8017189923" <sip:8017189923@10.2.160.150>;tag=X7c78r50489Qp
415.    To: <sip:84810@apex.local>;tag=0c81bbe41b6e615223571eb39c00
416.    Call-ID: 1c1692e7-2231-1235-5bbe-005056b26115
417.    CSeq: 99070946 PRACK
418.    Contact: <sip:mod_sofia@10.2.160.150:5080>
419.    RAck: 2 99070944 INVITE
420.    User-Agent: FreeSWITCH-mod_sofia/1.6.12~64bit
421.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, PRACK, NOTIFY
422.    Supported: precondition, 100rel, timer, path, replaces
423.    Content-Length: 0
424.  
425.    ------------------------------------------------------------------------
426. 2016-11-10 14:40:17.163941 [DEBUG] sofia.c:7014 Channel sofia/external/84810@apex.local entering state [proceeding][180]
427. 2016-11-10 14:40:17.163941 [DEBUG] sofia.c:7021 Duplicate SDP
428. v=0
429. o=- 1478814016 2 IN IP4 172.16.254.100
430. s=-
431. c=IN IP4 172.16.254.106
432. b=AS:64
433. t=0 0
434. m=audio 47932 RTP/AVP 0 120
435. a=rtpmap:0 PCMU/8000
436. a=rtpmap:120 telephone-event/8000
437. a=ptime:20
438.  
439. recv 430 bytes from udp/[172.16.254.122]:5060 at 14:40:17.175453:
440.    ------------------------------------------------------------------------
441.    SIP/2.0 200 OK
442.    From: "8017189923" <sip:8017189923@10.2.160.150>;tag=X7c78r50489Qp
443.    To: <sip:84810@apex.local>;tag=0c81bbe41b6e615223571eb39c00
444.    Call-ID: 1c1692e7-2231-1235-5bbe-005056b26115
445.    CSeq: 99070946 PRACK
446.    Via: SIP/2.0/UDP 10.2.160.150:5080;rport=5080;branch=z9hG4bKFF6Ke0UQ822ce
447.    Av-Global-Session-ID: 44b44bc0-a78e-11e6-a95f-0050568452e0
448.    Server: Avaya CM/R016x.03.0.124.0 AVAYA-SM-6.3.17.0.631705
449.    Content-Length: 0
450.  
451.    ------------------------------------------------------------------------
452. recv 1680 bytes from udp/[172.16.254.122]:5060 at 14:40:18.752126:
453.    ------------------------------------------------------------------------
454.    SIP/2.0 200 OK
455.    Remote-Party-ID: <sip:84810@172.16.254.190>;party=called;screen=no;privacy=off
456.    P-Asserted-Identity: <sip:84810@172.16.254.190>
457.    Supported: 100rel, histinfo, join, replaces, sdp-anat, timer
458.    P-Location: SM;origlocname="Provo";origsiglocname="Provo";origmedialocname="Provo";termlocname="Provo";termsiglocname="Provo";termmedialocname="Provo";smaccounting="true"
459.    P-AV-Message-Id: 1_2
460.    Server: Avaya CM/R016x.03.0.124.0 AVAYA-SM-6.3.17.0.631705
461.    Av-Global-Session-ID: 44b44bc0-a78e-11e6-a95f-0050568452e0
462.    Session-Expires: 1200;refresher=uas
463.    Require: timer
464.    Record-Route: <sip:172.16.254.100;transport=tcp;lr>
465.    Record-Route: <sip:rw-39656cad@172.16.254.122;lr;transport=TCP>
466.    Record-Route: <sip:172.16.254.123:15060;transport=tcp;lr;ibmsid=local.1471281760668_17021202_17076146;ibmdrr>
467.    Record-Route: <sip:172.16.254.123:15060;transport=udp;lr;ibmsid=local.1471281760668_17021202_17076146;ibmdrr>
468.    Record-Route: <sip:rw-39656cad@172.16.254.122;lr>
469.    Accept-Language: en
470.    Contact: <sip:172.16.254.100;transport=tcp;gsid=44b44bc0-a78e-11e6-a95f-0050568452e0>
471.    Allow: INVITE, ACK, OPTIONS, BYE, CANCEL, NOTIFY, REFER, INFO, PRACK, UPDATE
472.    To: <sip:84810@apex.local>;tag=0c81bbe41b6e615223571eb39c00
473.    From: "8017189923" <sip:8017189923@10.2.160.150>;tag=X7c78r50489Qp
474.    Call-ID: 1c1692e7-2231-1235-5bbe-005056b26115
475.    CSeq: 99070944 INVITE
476.    Via: SIP/2.0/UDP 10.2.160.150:5080;rport=5080;branch=z9hG4bKDXK2aatgeHp7p
477.    Content-Type: application/sdp
478.    Content-Length: 201
479.  
480.    v=0
481.    o=- 1478814016 2 IN IP4 172.16.254.100
482.    s=-
483.    c=IN IP4 172.16.254.106
484.    b=AS:64
485.    t=0 0
486.    m=audio 47932 RTP/AVP 0 120
487.    a=sendrecv
488.    a=rtpmap:0 PCMU/8000
489.    a=rtpmap:120 telephone-event/8000
490.    a=ptime:20
491.    ------------------------------------------------------------------------
492. 2016-11-10 14:40:18.743937 [DEBUG] sofia.c:7014 Channel sofia/external/84810@apex.local entering state [completing][200]
493. 2016-11-10 14:40:18.743937 [DEBUG] sofia.c:7021 Duplicate SDP
494. v=0
495. o=- 1478814016 2 IN IP4 172.16.254.100
496. s=-
497. c=IN IP4 172.16.254.106
498. b=AS:64
499. t=0 0
500. m=audio 47932 RTP/AVP 0 120
501. a=rtpmap:0 PCMU/8000
502. a=rtpmap:120 telephone-event/8000
503. a=ptime:20
504.  
505. send 793 bytes to udp/[172.16.254.122]:5060 at 14:40:18.753798:
506.    ------------------------------------------------------------------------
507.    ACK sip:172.16.254.100;transport=tcp;gsid=44b44bc0-a78e-11e6-a95f-0050568452e0 SIP/2.0
508.    Via: SIP/2.0/UDP 10.2.160.150:5080;rport;branch=z9hG4bKgrZcgUcU5BSZS
509.    Route: <sip:rw-39656cad@172.16.254.122;lr>
510.    Route: <sip:172.16.254.123:15060;transport=udp;lr;ibmsid=local.1471281760668_17021202_17076146;ibmdrr>
511.    Route: <sip:172.16.254.123:15060;transport=tcp;lr;ibmsid=local.1471281760668_17021202_17076146;ibmdrr>
512.    Route: <sip:rw-39656cad@172.16.254.122;lr;transport=TCP>
513.    Route: <sip:172.16.254.100;transport=tcp;lr>
514.    Max-Forwards: 70
515.    From: "8017189923" <sip:8017189923@10.2.160.150>;tag=X7c78r50489Qp
516.    To: <sip:84810@apex.local>;tag=0c81bbe41b6e615223571eb39c00
517.    Call-ID: 1c1692e7-2231-1235-5bbe-005056b26115
518.    CSeq: 99070944 ACK
519.    Contact: <sip:mod_sofia@10.2.160.150:5080>
520.    Content-Length: 0
521.  
522.    ------------------------------------------------------------------------
523. 2016-11-10 14:40:18.743937 [DEBUG] sofia.c:7014 Channel sofia/external/84810@apex.local entering state [ready][200]
524. 2016-11-10 14:40:18.743937 [NOTICE] sofia.c:8013 Channel [sofia/external/84810@apex.local] has been answered
525. 2016-11-10 14:40:18.743937 [DEBUG] switch_channel.c:3770 (sofia/external/84810@apex.local) Callstate Change EARLY -> ACTIVE
526. 2016-11-10 14:40:18.743937 [NOTICE] switch_ivr.c:779 Channel [sofia/external/8017189923@apex.local] has been answered
527. 2016-11-10 14:40:18.743937 [DEBUG] switch_channel.c:3770 (sofia/external/8017189923@apex.local) Callstate Change EARLY -> ACTIVE
528. send 914 bytes to udp/[172.16.254.191]:5060 at 14:40:18.755735:
529.    ------------------------------------------------------------------------
530.    SIP/2.0 200 OK
531.    Via: SIP/2.0/UDP apex.local:5060;branch=z9hG4bK37E57E41372;received=172.16.254.191
532.    From: <sip:8017189923@apex.local>;tag=96CADFAC-220E
533.    To: <sip:84810@pci.vivint.com>;tag=vyKe7XmX7ZK5a
534.    Call-ID: 1A4DB109-A6C511E6-931DAAA7-2043EB45@172.16.254.191
535.    CSeq: 101 INVITE
536.    Contact: <sip:84810@10.2.160.150:5080;transport=udp>
537.    User-Agent: FreeSWITCH-mod_sofia/1.6.12~64bit
538.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, PRACK, NOTIFY
539.    Require: timer
540.    Supported: precondition, 100rel, timer, path, replaces
541.    Allow-Events: talk, hold, conference, refer
542.    Session-Expires: 1800;refresher=uac
543.    Content-Length: 0
544.    P-Location: SM;origlocname="Provo";origsiglocname="Provo";origmedialocname="Provo";termlocname="Provo";termsiglocname="Provo";termmedialocname="Provo";smaccounting="true"
545.    P-AV-Message-Id: 1_2
546.    P-Asserted-Identity: "Outbound Call" <sip:84810@pci.vivint.com>
547.  
548.    ------------------------------------------------------------------------
549. 2016-11-10 14:40:18.743937 [DEBUG] sofia.c:7014 Channel sofia/external/8017189923@apex.local entering state [completed][200]
550. recv 403 bytes from udp/[172.16.254.191]:58573 at 14:40:18.757750:
551.    ------------------------------------------------------------------------
552.    ACK sip:84810@pci.vivint.com:5080;transport=udp SIP/2.0
553.    Via: SIP/2.0/UDP apex.local:5060;branch=z9hG4bK37E57F32550
554.    From: <sip:8017189923@apex.local>;tag=96CADFAC-220E
555.    To: <sip:84810@pci.vivint.com>;tag=vyKe7XmX7ZK5a
556.    Date: Thu, 10 Nov 2016 21:40:16 GMT
557.    Call-ID: 1A4DB109-A6C511E6-931DAAA7-2043EB45@172.16.254.191
558.    Max-Forwards: 70
559.    CSeq: 101 ACK
560.    Allow-Events: telephone-event
561.    Content-Length: 0
562.  
563.    ------------------------------------------------------------------------
564. 2016-11-10 14:40:18.743937 [DEBUG] sofia.c:7014 Channel sofia/external/8017189923@apex.local entering state [ready][200]
565. freeswitch@freeswitch1-stg>
566. freeswitch@freeswitch1-stg>
567. freeswitch@freeswitch1-stg>
568. freeswitch@freeswitch1-stg>
569. freeswitch@freeswitch1-stg>
570. freeswitch@freeswitch1-stg>
571. freeswitch@freeswitch1-stg>
572. recv 1386 bytes from udp/[172.16.254.122]:5060 at 14:40:32.552090:
573.    ------------------------------------------------------------------------
574.    UPDATE sip:mod_sofia@10.2.160.150:5080 SIP/2.0
575.    Remote-Party-ID: "Brain Beebe" <sip:+8012297771@172.16.254.190>;party=calling;screen=no;privacy=off
576.    P-Asserted-Identity: "Brain Beebe" <sip:+8012297771@172.16.254.190>
577.    Supported: 100rel, histinfo, join, replaces, sdp-anat, timer
578.    User-Agent: Avaya CM/R016x.03.0.124.0 AVAYA-SM-6.3.17.0.631705
579.    Via: SIP/2.0/UDP 172.16.254.122;rport;branch=z9hG4bK497169581328340-AP;ft=172.16.254.122~13c4
580.    Via: SIP/2.0/UDP 172.16.254.123:15060;rport=15060;ibmsid=local.1471281760668_17021202_17076146;branch=z9hG4bK497169581328340
581.    Via: SIP/2.0/TCP 172.16.254.122;branch=z9hG4bK030a5c741b6e61c823571eb39c00-AP;ft=31;received=172.16.254.122;rport=41142
582.    Via: SIP/2.0/TCP 172.16.254.100;branch=z9hG4bK030a5c741b6e61c823571eb39c00
583.    Allow: INVITE, ACK, OPTIONS, BYE, CANCEL, NOTIFY, REFER, INFO, PRACK, UPDATE
584.    Contact: "Brain Beebe" <sip:+8012297771@172.16.254.100;transport=tcp;gsid=44b44bc0-a78e-11e6-a95f-0050568452e0>
585.    Accept-Language: en
586.    Min-SE: 1200
587.    Session-Expires: 1200;refresher=uac
588.    P-Charging-Vector: icid-value="44b44bc0-a78e-11e6-a95f-0050568452e0"
589.    Av-Global-Session-ID: 44b44bc0-a78e-11e6-a95f-0050568452e0
590.    From: <sip:84810@apex.local>;tag=0c81bbe41b6e615223571eb39c00
591.    To: "8017189923" <sip:8017189923@10.2.160.150>;tag=X7c78r50489Qp
592.    Call-ID: 1c1692e7-2231-1235-5bbe-005056b26115
593.    Max-Forwards: 67
594.    CSeq: 1 UPDATE
595.    Content-Length: 0
596.  
597.    ------------------------------------------------------------------------
598. send 974 bytes to udp/[172.16.254.122]:5060 at 14:40:32.552463:
599.    ------------------------------------------------------------------------
600.    SIP/2.0 200 OK
601.    Via: SIP/2.0/UDP 172.16.254.122;rport=5060;branch=z9hG4bK497169581328340-AP;ft=172.16.254.122~13c4
602.    Via: SIP/2.0/UDP 172.16.254.123:15060;rport=15060;ibmsid=local.1471281760668_17021202_17076146;branch=z9hG4bK497169581328340
603.    Via: SIP/2.0/TCP 172.16.254.122;branch=z9hG4bK030a5c741b6e61c823571eb39c00-AP;ft=31;received=172.16.254.122;rport=41142
604.    Via: SIP/2.0/TCP 172.16.254.100;branch=z9hG4bK030a5c741b6e61c823571eb39c00
605.    From: <sip:84810@apex.local>;tag=0c81bbe41b6e615223571eb39c00
606.    To: "8017189923" <sip:8017189923@10.2.160.150>;tag=X7c78r50489Qp
607.    Call-ID: 1c1692e7-2231-1235-5bbe-005056b26115
608.    CSeq: 1 UPDATE
609.    Contact: <sip:mod_sofia@10.2.160.150:5080>
610.    User-Agent: FreeSWITCH-mod_sofia/1.6.12~64bit
611.    Accept: application/sdp
612.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, PRACK, NOTIFY
613.    Require: timer
614.    Supported: precondition, 100rel, timer, path, replaces
615.    Session-Expires: 1200;refresher=uac
616.    Content-Length: 0
617.  
618.    ------------------------------------------------------------------------
619. 2016-11-10 14:40:32.543924 [INFO] sofia.c:1278 sofia/external/84810@apex.local Update Callee ID to "Brain Beebe" <+8012297771>
620. recv 1558 bytes from udp/[172.16.254.122]:5060 at 14:40:32.556424:
621.    ------------------------------------------------------------------------
622.    INVITE sip:mod_sofia@10.2.160.150:5080 SIP/2.0
623.    P-Location: SM;origlocname="Provo";origsiglocname="Provo";origmedialocname="Provo";termlocname="Provo";termsiglocname="Provo";termmedialocname="Provo";smaccounting="true"
624.    Remote-Party-ID: "Brain Beebe" <sip:+8012297771@172.16.254.190>;party=calling;screen=no;privacy=off
625.    P-Asserted-Identity: "Brain Beebe" <sip:+8012297771@172.16.254.190>
626.    Supported: 100rel, histinfo, join, replaces, sdp-anat, timer
627.    User-Agent: Avaya CM/R016x.03.0.124.0 AVAYA-SM-6.3.17.0.631705
628.    Via: SIP/2.0/UDP 172.16.254.122;rport;branch=z9hG4bK805411851421476-AP;ft=172.16.254.122~13c4
629.    Via: SIP/2.0/UDP 172.16.254.123:15060;rport=15060;ibmsid=local.1471281760668_17021202_17076146;branch=z9hG4bK805411851421476
630.    Via: SIP/2.0/TCP 172.16.254.122;branch=z9hG4bK030a5c741b6e61c923571eb39c00-AP;ft=31;received=172.16.254.122;rport=41142
631.    Via: SIP/2.0/TCP 172.16.254.100;branch=z9hG4bK030a5c741b6e61c923571eb39c00
632.    Allow: INVITE, ACK, OPTIONS, BYE, CANCEL, NOTIFY, REFER, INFO, PRACK, UPDATE
633.    Contact: "Brain Beebe" <sip:+8012297771@172.16.254.100;transport=tcp;gsid=44b44bc0-a78e-11e6-a95f-0050568452e0>
634.    Accept-Language: en
635.    Min-SE: 1200
636.    Session-Expires: 1200;refresher=uac
637.    P-Charging-Vector: icid-value="44b44bc0-a78e-11e6-a95f-0050568452e0"
638.    Av-Global-Session-ID: 44b44bc0-a78e-11e6-a95f-0050568452e0
639.    From: <sip:84810@apex.local>;tag=0c81bbe41b6e615223571eb39c00
640.    To: "8017189923" <sip:8017189923@10.2.160.150>;tag=X7c78r50489Qp
641.    Call-ID: 1c1692e7-2231-1235-5bbe-005056b26115
642.    Max-Forwards: 67
643.    CSeq: 2 INVITE
644.    Content-Length: 0
645.  
646.    ------------------------------------------------------------------------
647. send 703 bytes to udp/[172.16.254.122]:5060 at 14:40:32.556731:
648.    ------------------------------------------------------------------------
649.    SIP/2.0 100 Trying
650.    Via: SIP/2.0/UDP 172.16.254.122;rport=5060;branch=z9hG4bK805411851421476-AP;ft=172.16.254.122~13c4
651.    Via: SIP/2.0/UDP 172.16.254.123:15060;rport=15060;ibmsid=local.1471281760668_17021202_17076146;branch=z9hG4bK805411851421476
652.    Via: SIP/2.0/TCP 172.16.254.122;branch=z9hG4bK030a5c741b6e61c923571eb39c00-AP;ft=31;received=172.16.254.122;rport=41142
653.    Via: SIP/2.0/TCP 172.16.254.100;branch=z9hG4bK030a5c741b6e61c923571eb39c00
654.    From: <sip:84810@apex.local>;tag=0c81bbe41b6e615223571eb39c00
655.    To: "8017189923" <sip:8017189923@10.2.160.150>;tag=X7c78r50489Qp
656.    Call-ID: 1c1692e7-2231-1235-5bbe-005056b26115
657.    CSeq: 2 INVITE
658.    User-Agent: FreeSWITCH-mod_sofia/1.6.12~64bit
659.    Content-Length: 0
660.  
661.    ------------------------------------------------------------------------
662. 2016-11-10 14:40:32.543924 [DEBUG] sofia.c:7014 Channel sofia/external/84810@apex.local entering state [received][100]
663. send 1116 bytes to udp/[172.16.254.122]:5060 at 14:40:32.557454:
664.    ------------------------------------------------------------------------
665.    SIP/2.0 200 OK
666.    Via: SIP/2.0/UDP 172.16.254.122;rport=5060;branch=z9hG4bK805411851421476-AP;ft=172.16.254.122~13c4
667.    Via: SIP/2.0/UDP 172.16.254.123:15060;rport=15060;ibmsid=local.1471281760668_17021202_17076146;branch=z9hG4bK805411851421476
668.    Via: SIP/2.0/TCP 172.16.254.122;branch=z9hG4bK030a5c741b6e61c923571eb39c00-AP;ft=31;received=172.16.254.122;rport=41142
669.    Via: SIP/2.0/TCP 172.16.254.100;branch=z9hG4bK030a5c741b6e61c923571eb39c00
670.    From: <sip:84810@apex.local>;tag=0c81bbe41b6e615223571eb39c00
671.    To: "8017189923" <sip:8017189923@10.2.160.150>;tag=X7c78r50489Qp
672.    Call-ID: 1c1692e7-2231-1235-5bbe-005056b26115
673.    CSeq: 2 INVITE
674.    Contact: <sip:mod_sofia@10.2.160.150:5080>
675.    User-Agent: FreeSWITCH-mod_sofia/1.6.12~64bit
676.    Accept: application/sdp
677.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, PRACK, NOTIFY
678.    Require: timer
679.    Supported: precondition, 100rel, timer, path, replaces
680.    Session-Expires: 1200;refresher=uac
681.    Content-Type: application/sdp
682.    Content-Length: 109
683.  
684.    v=0
685.    o=FreeSWITCH 1478793426 1478793427 IN IP4 172.16.254.191
686.    s=FreeSWITCH
687.    c=IN IP4 172.16.254.191
688.    t=0 0
689.    ------------------------------------------------------------------------
690. 2016-11-10 14:40:32.543924 [DEBUG] sofia.c:7014 Channel sofia/external/84810@apex.local entering state [completed][200]
691. recv 987 bytes from udp/[172.16.254.122]:5060 at 14:40:32.625265:
692.    ------------------------------------------------------------------------
693.    ACK sip:mod_sofia@10.2.160.150:5080 SIP/2.0
694.    P-Location: SM;origlocname="Provo";origsiglocname="Provo";origmedialocname="Provo";termlocname="Provo";termsiglocname="Provo";termmedialocname="Provo";smaccounting="true"
695.    User-Agent: Avaya CM/R016x.03.0.124.0 AVAYA-SM-6.3.17.0.631705
696.    Via: SIP/2.0/UDP 172.16.254.122;rport;branch=z9hG4bK345450167102362-AP;ft=172.16.254.122~13c4
697.    Via: SIP/2.0/UDP 172.16.254.123:15060;rport=15060;ibmsid=local.1471281760668_17021202_17076146;branch=z9hG4bK345450167102362
698.    Via: SIP/2.0/TCP 172.16.254.122;branch=z9hG4bK030a5c741b6e61cb23571eb39c00-AP;ft=31;received=172.16.254.122;rport=41142
699.    Via: SIP/2.0/TCP 172.16.254.100;branch=z9hG4bK030a5c741b6e61cb23571eb39c00
700.    Av-Global-Session-ID: 44b44bc0-a78e-11e6-a95f-0050568452e0
701.    From: <sip:84810@apex.local>;tag=0c81bbe41b6e615223571eb39c00
702.    To: "8017189923" <sip:8017189923@10.2.160.150>;tag=X7c78r50489Qp
703.    Call-ID: 1c1692e7-2231-1235-5bbe-005056b26115
704.    Max-Forwards: 67
705.    CSeq: 2 ACK
706.    Content-Length: 0
707.  
708.    ------------------------------------------------------------------------
709. recv 815 bytes from udp/[172.16.254.122]:5060 at 14:40:32.625507:
710.    ------------------------------------------------------------------------
711.    BYE sip:mod_sofia@10.2.160.150:5080 SIP/2.0
712.    User-Agent: Avaya CM/R016x.03.0.124.0 AVAYA-SM-6.3.17.0.631705
713.    Via: SIP/2.0/UDP 172.16.254.122;rport;branch=z9hG4bK576225203948050-AP;ft=172.16.254.122~13c4
714.    Via: SIP/2.0/UDP 172.16.254.123:15060;rport=15060;ibmsid=local.1471281760668_17021202_17076146;branch=z9hG4bK576225203948050
715.    Via: SIP/2.0/TCP 172.16.254.122;branch=z9hG4bK030a5c741b6e61cc23571eb39c00-AP;ft=31;received=172.16.254.122;rport=41142
716.    Via: SIP/2.0/TCP 172.16.254.100;branch=z9hG4bK030a5c741b6e61cc23571eb39c00
717.    Av-Global-Session-ID: 44b44bc0-a78e-11e6-a95f-0050568452e0
718.    From: <sip:84810@apex.local>;tag=0c81bbe41b6e615223571eb39c00
719.    To: "8017189923" <sip:8017189923@10.2.160.150>;tag=X7c78r50489Qp
720.    Call-ID: 1c1692e7-2231-1235-5bbe-005056b26115
721.    Max-Forwards: 67
722.    CSeq: 3 BYE
723.    Content-Length: 0
724.  
725.    ------------------------------------------------------------------------
726. 2016-11-10 14:40:32.623919 [DEBUG] sofia.c:7014 Channel sofia/external/84810@apex.local entering state [ready][200]
727. 2016-11-10 14:40:32.623919 [NOTICE] sofia.c:1011 Hangup sofia/external/84810@apex.local [CS_HIBERNATE] [NORMAL_CLEARING]
728. 2016-11-10 14:40:32.623919 [DEBUG] switch_ivr_bridge.c:1071 sofia/external/84810@apex.local skip receive message [UNBRIDGE] (channel is hungup already)
729. 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:584 (sofia/external/84810@apex.local) Running State Change CS_HANGUP
730. send 849 bytes to udp/[172.16.254.122]:5060 at 14:40:32.627166:
731.    ------------------------------------------------------------------------
732.    SIP/2.0 200 OK
733. 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:850 (sofia/external/84810@apex.local) Callstate Change ACTIVE -> HANGUP
734.    Via: SIP/2.0/UDP 172.16.254.122;rport=5060;branch=z9hG4bK576225203948050-AP;ft=172.16.254.122~13c4
735.    Via: SIP/2.0/UDP 172.16.254.123:15060;rport=15060;ibmsid=local.1471281760668_17021202_17076146;branch=z9hG4bK576225203948050
736.    Via: SIP/2.0/TCP 172.16.254.122;branch=z9hG4bK030a5c741b6e61cc23571eb39c00-AP;ft=31;received=172.16.254.122;rport=41142
737.    Via: SIP/2.0/TCP 172.16.254.100;branch=z9hG4bK030a5c741b6e61cc23571eb39c00
738.    From: <sip:84810@apex.local>;tag=0c81bbe41b6e615223571eb39c00
739.    To: "8017189923" <sip:8017189923@10.2.160.150>;tag=X7c78r50489Qp
740.    Call-ID: 1c1692e7-2231-1235-5bbe-005056b26115
741.    CSeq: 3 BYE
742.    User-Agent: FreeSWITCH-mod_sofia/1.6.12~64bit
743.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, PRACK, NOTIFY
744.    Supported: precondition, 100rel, timer, path, replaces
745.    Content-Length: 0
746.  
747.    ------------------------------------------------------------------------
748. 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:852 (sofia/external/84810@apex.local) State HANGUP
749. 2016-11-10 14:40:32.623919 [DEBUG] mod_sofia.c:438 Channel sofia/external/84810@apex.local hanging up, cause: NORMAL_CLEARING
750. 2016-11-10 14:40:32.623919 [DEBUG] switch_ivr_bridge.c:1207 (sofia/external/8017189923@apex.local) State Change CS_HIBERNATE -> CS_EXECUTE
751. 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:584 (sofia/external/8017189923@apex.local) Running State Change CS_EXECUTE
752. 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:60 sofia/external/84810@apex.local Standard HANGUP, cause: NORMAL_CLEARING
753. 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:852 (sofia/external/84810@apex.local) State HANGUP going to sleep
754. 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:619 (sofia/external/84810@apex.local) State Change CS_HANGUP -> CS_REPORTING
755. 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:584 (sofia/external/84810@apex.local) Running State Change CS_REPORTING
756. 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:938 (sofia/external/84810@apex.local) State REPORTING
757. 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:174 sofia/external/84810@apex.local Standard REPORTING, cause: NORMAL_CLEARING
758. 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:938 (sofia/external/84810@apex.local) State REPORTING going to sleep
759. 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:650 (sofia/external/8017189923@apex.local) State EXECUTE
760. 2016-11-10 14:40:32.623919 [DEBUG] mod_sofia.c:198 sofia/external/8017189923@apex.local SOFIA EXECUTE
761. 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:328 sofia/external/8017189923@apex.local Standard EXECUTE
762. 2016-11-10 14:40:32.623919 [NOTICE] switch_core_state_machine.c:385 sofia/external/8017189923@apex.local has executed the last dialplan instruction, hanging up.
763. 2016-11-10 14:40:32.623919 [NOTICE] switch_core_state_machine.c:387 Hangup sofia/external/8017189923@apex.local [CS_EXECUTE] [NORMAL_CLEARING]
764. 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:610 (sofia/external/84810@apex.local) State Change CS_REPORTING -> CS_DESTROY
765. 2016-11-10 14:40:32.623919 [DEBUG] switch_core_session.c:1647 Session 16 (sofia/external/84810@apex.local) Locked, Waiting on external entities
766. 2016-11-10 14:40:32.623919 [NOTICE] switch_core_session.c:1665 Session 16 (sofia/external/84810@apex.local) Ended
767. 2016-11-10 14:40:32.623919 [NOTICE] switch_core_session.c:1669 Close Channel sofia/external/84810@apex.local [CS_DESTROY]
768. 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:650 (sofia/external/8017189923@apex.local) State EXECUTE going to sleep
769. 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:584 (sofia/external/8017189923@apex.local) Running State Change CS_HANGUP
770. 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:741 (sofia/external/84810@apex.local) Running State Change CS_DESTROY
771. 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:850 (sofia/external/8017189923@apex.local) Callstate Change ACTIVE -> HANGUP
772. 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:751 (sofia/external/84810@apex.local) State DESTROY
773. 2016-11-10 14:40:32.623919 [DEBUG] mod_sofia.c:343 sofia/external/84810@apex.local SOFIA DESTROY
774. 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:181 sofia/external/84810@apex.local Standard DESTROY
775. 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:751 (sofia/external/84810@apex.local) State DESTROY going to sleep
776. 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:852 (sofia/external/8017189923@apex.local) State HANGUP
777. 2016-11-10 14:40:32.623919 [DEBUG] mod_sofia.c:432 sofia/external/8017189923@apex.local Overriding SIP cause 480 with 200 from the other leg
778. 2016-11-10 14:40:32.623919 [DEBUG] mod_sofia.c:438 Channel sofia/external/8017189923@apex.local hanging up, cause: NORMAL_CLEARING
779. 2016-11-10 14:40:32.623919 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/external/8017189923@apex.local
780. 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:60 sofia/external/8017189923@apex.local Standard HANGUP, cause: NORMAL_CLEARING
781. 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:852 (sofia/external/8017189923@apex.local) State HANGUP going to sleep
782. 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:619 (sofia/external/8017189923@apex.local) State Change CS_HANGUP -> CS_REPORTING
783. 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:584 (sofia/external/8017189923@apex.local) Running State Change CS_REPORTING
784. 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:938 (sofia/external/8017189923@apex.local) State REPORTING
785. send 588 bytes to udp/[172.16.254.191]:5060 at 14:40:32.628759:
786.    ------------------------------------------------------------------------
787.    BYE sip:8017189923@172.16.254.191:5060 SIP/2.0
788.    Via: SIP/2.0/UDP 10.2.160.150:5080;rport;branch=z9hG4bKH1r5HpXy2mFjN
789.    Max-Forwards: 70
790.    From: <sip:84810@pci.vivint.com>;tag=vyKe7XmX7ZK5a
791.    To: <sip:8017189923@apex.local>;tag=96CADFAC-220E
792.    Call-ID: 1A4DB109-A6C511E6-931DAAA7-2043EB45@172.16.254.191
793.    CSeq: 99070952 BYE
794.    User-Agent: FreeSWITCH-mod_sofia/1.6.12~64bit
795.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, PRACK, NOTIFY
796.    Supported: precondition, 100rel, timer, path, replaces
797.    Reason: Q.850;cause=16;text="NORMAL_CLEARING"
798.    Content-Length: 0
799.  
800.    ------------------------------------------------------------------------
801. 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:174 sofia/external/8017189923@apex.local Standard REPORTING, cause: NORMAL_CLEARING
802. 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:938 (sofia/external/8017189923@apex.local) State REPORTING going to sleep
803. 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:610 (sofia/external/8017189923@apex.local) State Change CS_REPORTING -> CS_DESTROY
804. 2016-11-10 14:40:32.623919 [DEBUG] switch_core_session.c:1647 Session 15 (sofia/external/8017189923@apex.local) Locked, Waiting on external entities
805. 2016-11-10 14:40:32.623919 [NOTICE] switch_core_session.c:1665 Session 15 (sofia/external/8017189923@apex.local) Ended
806. 2016-11-10 14:40:32.623919 [NOTICE] switch_core_session.c:1669 Close Channel sofia/external/8017189923@apex.local [CS_DESTROY]
807. 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:741 (sofia/external/8017189923@apex.local) Running State Change CS_DESTROY
808. 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:751 (sofia/external/8017189923@apex.local) State DESTROY
809. 2016-11-10 14:40:32.623919 [DEBUG] mod_sofia.c:343 sofia/external/8017189923@apex.local SOFIA DESTROY
810. 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:181 sofia/external/8017189923@apex.local Standard DESTROY
811. 2016-11-10 14:40:32.623919 [DEBUG] switch_core_state_machine.c:751 (sofia/external/8017189923@apex.local) State DESTROY going to sleep
812. recv 464 bytes from udp/[172.16.254.191]:58573 at 14:40:32.630921:
813.    ------------------------------------------------------------------------
814.    SIP/2.0 200 OK
815.    Via: SIP/2.0/UDP 10.2.160.150:5080;rport;branch=z9hG4bKH1r5HpXy2mFjN
816.    From: <sip:84810@pci.vivint.com>;tag=vyKe7XmX7ZK5a
817.    To: <sip:8017189923@172.16.254.191>;tag=96CADFAC-220E
818.    Date: Thu, 10 Nov 2016 21:40:32 GMT
819.    Call-ID: 1A4DB109-A6C511E6-931DAAA7-2043EB45@172.16.254.191
820.    Server: Cisco-SIPGateway/IOS-15.3.3.M5
821.    CSeq: 99070952 BYE
822.    Reason: Q.850;cause=16
823.    P-RTP-Stat: PS=819,OS=131040,PR=821,OR=131360,PL=0,JI=0,LA=0,DU=13
824.    Content-Length: 0
825.  
826.    ------------------------------------------------------------------------
827.