1. recv 1220 bytes from udp/[172.16.254.192]:56031 at 14:33:32.791748:
2.    ------------------------------------------------------------------------
3.    INVITE sip:84810@pci.vivint.com:5080 SIP/2.0
4.    Via: SIP/2.0/UDP apex.local:5060;branch=z9hG4bK77F6A017FE
5.    From: <sip:8017189923@apex.local>;tag=E583DFFA-18E6
6.    To: <sip:84810@pci.vivint.com>
7.    Date: Thu, 10 Nov 2016 21:33:32 GMT
8.    Call-ID: 29E354F5-A6C411E6-8665C066-636412D4@172.16.254.192
9.    Supported: timer,resource-priority,replaces,histinfo,sdp-anat
10.    Require: 100rel
11.    Min-SE:  1800
12.    Cisco-Guid: 0702744289-2797867494-2254422118-1667502804
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: 1478813612
17.    Contact: <sip:8017189923@172.16.254.192: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.192>
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 8870 4742 IN IP4 172.16.254.192
30.    s=SIP Call
31.    c=IN IP4 172.16.254.192
32.    t=0 0
33.    m=audio 18032 RTP/AVP 0 120
34.    c=IN IP4 172.16.254.192
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 367 bytes to udp/[172.16.254.192]:5060 at 14:33:32.792134:
41.    ------------------------------------------------------------------------
42.    SIP/2.0 100 Trying
43.    Via: SIP/2.0/UDP apex.local:5060;branch=z9hG4bK77F6A017FE;received=172.16.254.192
44.    From: <sip:8017189923@apex.local>;tag=E583DFFA-18E6
45.    To: <sip:84810@pci.vivint.com>
46.    Call-ID: 29E354F5-A6C411E6-8665C066-636412D4@172.16.254.192
47.    CSeq: 101 INVITE
48.    Timestamp: 1478813612 0.000151
49.    User-Agent: FreeSWITCH-mod_sofia/1.6.12~64bit
50.    Content-Length: 0
51.  
52.    ------------------------------------------------------------------------
53. 2016-11-10 14:33:32.784001 [NOTICE] switch_channel.c:1104 New Channel sofia/external/8017189923@apex.local [544cc54a-a78d-11e6-b7c7-85d38162b0e3]
54. 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:584 (sofia/external/8017189923@apex.local) Running State Change CS_NEW
55. 2016-11-10 14:33:32.784001 [DEBUG] sofia.c:9725 sofia/external/8017189923@apex.local receiving invite from 172.16.254.192:56031 version: 1.6.12  64bit
56. 2016-11-10 14:33:32.784001 [DEBUG] sofia.c:7014 Channel sofia/external/8017189923@apex.local entering state [received][100]
57. 2016-11-10 14:33:32.784001 [DEBUG] sofia.c:7024 Remote SDP:
58. v=0
59. o=CiscoSystemsSIP-GW-UserAgent 8870 4742 IN IP4 172.16.254.192
60. s=SIP Call
61. c=IN IP4 172.16.254.192
62. t=0 0
63. m=audio 18032 RTP/AVP 0 120
64. c=IN IP4 172.16.254.192
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:33:32.784001 [DEBUG] sofia.c:7378 (sofia/external/8017189923@apex.local) State Change CS_NEW -> CS_INIT
71. 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:603 (sofia/external/8017189923@apex.local) State NEW
72. 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:584 (sofia/external/8017189923@apex.local) Running State Change CS_INIT
73. 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:627 (sofia/external/8017189923@apex.local) State INIT
74. 2016-11-10 14:33:32.784001 [DEBUG] mod_sofia.c:90 sofia/external/8017189923@apex.local SOFIA INIT
75. 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:40 sofia/external/8017189923@apex.local Standard INIT
76. 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:48 (sofia/external/8017189923@apex.local) State Change CS_INIT -> CS_ROUTING
77. 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:627 (sofia/external/8017189923@apex.local) State INIT going to sleep
78. 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:584 (sofia/external/8017189923@apex.local) Running State Change CS_ROUTING
79. 2016-11-10 14:33:32.784001 [DEBUG] switch_channel.c:2249 (sofia/external/8017189923@apex.local) Callstate Change DOWN -> RINGING
80. 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:643 (sofia/external/8017189923@apex.local) State ROUTING
81. 2016-11-10 14:33:32.784001 [DEBUG] mod_sofia.c:143 sofia/external/8017189923@apex.local SOFIA ROUTING
82. 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:236 sofia/external/8017189923@apex.local Standard ROUTING
83. 2016-11-10 14:33:32.784001 [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:33:32.784001 [DEBUG] switch_core_state_machine.c:286 (sofia/external/8017189923@apex.local) State Change CS_ROUTING -> CS_EXECUTE
102. 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:643 (sofia/external/8017189923@apex.local) State ROUTING going to sleep
103. 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:584 (sofia/external/8017189923@apex.local) Running State Change CS_EXECUTE
104. 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:650 (sofia/external/8017189923@apex.local) State EXECUTE
105. 2016-11-10 14:33:32.784001 [DEBUG] mod_sofia.c:198 sofia/external/8017189923@apex.local SOFIA EXECUTE
106. 2016-11-10 14:33:32.784001 [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:33:32.784001 [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:33:32 -0700)
110. 2016-11-10 14:33:32.784001 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [RFC2822_DATE]=[Thu, 10 Nov 2016 14:33:32 -0700]
111. EXECUTE sofia/external/8017189923@apex.local set(domain_name=freeswitch1-stg.pci.vivint.com)
112. 2016-11-10 14:33:32.784001 [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:33:32.784001 [DEBUG] switch_channel.c:1250 sofia/external/8017189923@apex.local EXPORTING[export_vars] [RFC2822_DATE]=[Thu, 10 Nov 2016 14:33:32 -0700] to event
115. 2016-11-10 14:33:32.784001 [DEBUG] switch_ivr_originate.c:2138 Parsing global variables
116. 2016-11-10 14:33:32.784001 [NOTICE] switch_channel.c:1104 New Channel sofia/external/84810@apex.local [544d6a54-a78d-11e6-b7cf-85d38162b0e3]
117. 2016-11-10 14:33:32.784001 [DEBUG] mod_sofia.c:4757 (sofia/external/84810@apex.local) State Change CS_NEW -> CS_INIT
118. 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:584 (sofia/external/84810@apex.local) Running State Change CS_INIT
119. 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:627 (sofia/external/84810@apex.local) State INIT
120. 2016-11-10 14:33:32.784001 [DEBUG] mod_sofia.c:90 sofia/external/84810@apex.local SOFIA INIT
121. 2016-11-10 14:33:32.784001 [DEBUG] sofia_glue.c:1253 sip:172.16.254.122 Setting proxy route to sofia/external/84810@apex.local
122. 2016-11-10 14:33:32.784001 [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 8870 4742 IN IP4 172.16.254.192
126. s=SIP Call
127. c=IN IP4 172.16.254.192
128. t=0 0
129. m=audio 18032 RTP/AVP 0 120
130. c=IN IP4 172.16.254.192
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:33:32.784001 [DEBUG] switch_core_state_machine.c:40 sofia/external/84810@apex.local Standard INIT
137. 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:48 (sofia/external/84810@apex.local) State Change CS_INIT -> CS_ROUTING
138. 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:627 (sofia/external/84810@apex.local) State INIT going to sleep
139. 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:584 (sofia/external/84810@apex.local) Running State Change CS_ROUTING
140. 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:643 (sofia/external/84810@apex.local) State ROUTING
141. 2016-11-10 14:33:32.784001 [DEBUG] mod_sofia.c:143 sofia/external/84810@apex.local SOFIA ROUTING
142. 2016-11-10 14:33:32.784001 [DEBUG] switch_ivr_originate.c:67 (sofia/external/84810@apex.local) State Change CS_ROUTING -> CS_CONSUME_MEDIA
143. 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:643 (sofia/external/84810@apex.local) State ROUTING going to sleep
144. 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:584 (sofia/external/84810@apex.local) Running State Change CS_CONSUME_MEDIA
145. 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:662 (sofia/external/84810@apex.local) State CONSUME_MEDIA
146. 2016-11-10 14:33:32.784001 [DEBUG] switch_core_state_machine.c:662 (sofia/external/84810@apex.local) State CONSUME_MEDIA going to sleep
147. send 1066 bytes to udp/[172.16.254.122]:5060 at 14:33:32.798155:
148.    ------------------------------------------------------------------------
149.    INVITE sip:84810@apex.local SIP/2.0
150.    Via: SIP/2.0/UDP 10.2.160.150:5080;rport;branch=z9hG4bK32paSUHDBrQcK
151.    Route: <sip:172.16.254.122>
152.    Max-Forwards: 52
153.    From: "8017189923" <sip:8017189923@10.2.160.150>;tag=S3731c2jg5gDr
154.    To: <sip:84810@apex.local>
155.    Call-ID: 2babe1e8-2230-1235-5bbe-005056b26115
156.    CSeq: 99070742 INVITE
157.    Contact: <sip:mod_sofia@10.2.160.150:5080>
158.    User-Agent: FreeSWITCH-mod_sofia/1.6.12~64bit
159.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, PRACK, NOTIFY
160.    Supported: precondition, 100rel, timer, path, replaces
161.    Allow-Events: talk, hold, conference, refer
162.    Privacy: none
163.    Content-Type: application/sdp
164.    Content-Disposition: session
165.    Content-Length: 253
166.    X-FS-Support: update_display,send_info
167.    P-Asserted-Identity: "8017189923" <sip:8017189923@10.2.160.150>
168.  
169.    v=0
170.    o=CiscoSystemsSIP-GW-UserAgent 8870 4742 IN IP4 172.16.254.192
171.    s=SIP Call
172.    c=IN IP4 172.16.254.192
173.    t=0 0
174.    m=audio 18032 RTP/AVP 0 120
175.    c=IN IP4 172.16.254.192
176.    a=rtpmap:0 PCMU/8000
177.    a=rtpmap:120 telephone-event/8000
178.    a=fmtp:120 0-15
179.    a=ptime:20
180.    ------------------------------------------------------------------------
181. 2016-11-10 14:33:32.784001 [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:33:32.800410:
183.    ------------------------------------------------------------------------
184.    SIP/2.0 100 Trying
185.    Call-ID: 2babe1e8-2230-1235-5bbe-005056b26115
186.    CSeq: 99070742 INVITE
187.    From: "8017189923" <sip:8017189923@10.2.160.150>;tag=S3731c2jg5gDr
188.    To: <sip:84810@apex.local>
189.    Via: SIP/2.0/UDP 10.2.160.150:5080;rport=5080;branch=z9hG4bK32paSUHDBrQcK
190.    Content-Length: 0
191.  
192.    ------------------------------------------------------------------------
193. recv 1651 bytes from udp/[172.16.254.122]:5060 at 14:33:32.845615:
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: 544871c0-a78d-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_17020266_17075199;ibmdrr>
208.    Record-Route: <sip:172.16.254.123:15060;transport=udp;lr;ibmsid=local.1471281760668_17020266_17075199;ibmdrr>
209.    Record-Route: <sip:rw-39656cad@172.16.254.122;lr>
210.    Contact: <sip:84810@172.16.254.100;transport=tcp;gsid=544871c0-a78d-11e6-a95f-0050568452e0>
211.    Allow: INVITE, ACK, OPTIONS, BYE, CANCEL, NOTIFY, REFER, INFO, PRACK, UPDATE
212.    To: <sip:84810@apex.local>;tag=0464ecd40b6e6181a571eb39c00
213.    From: "8017189923" <sip:8017189923@10.2.160.150>;tag=S3731c2jg5gDr
214.    Call-ID: 2babe1e8-2230-1235-5bbe-005056b26115
215.    CSeq: 99070742 INVITE
216.    Via: SIP/2.0/UDP 10.2.160.150:5080;rport=5080;branch=z9hG4bK32paSUHDBrQcK
217.    Content-Type: application/sdp
218.    Content-Length: 201
219.  
220.    v=0
221.    o=- 1478813612 2 IN IP4 172.16.254.100
222.    s=-
223.    c=IN IP4 172.16.254.107
224.    b=AS:64
225.    t=0 0
226.    m=audio 18960 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 1027 bytes to udp/[172.16.254.122]:5060 at 14:33:32.846279:
233.    ------------------------------------------------------------------------
234.    PRACK sip:84810@172.16.254.100;transport=tcp;gsid=544871c0-a78d-11e6-a95f-0050568452e0 SIP/2.0
235.    Via: SIP/2.0/UDP 10.2.160.150:5080;rport;branch=z9hG4bK4Bg3tp2g80DZe
236.    Route: <sip:rw-39656cad@172.16.254.122;lr>
237.    Route: <sip:172.16.254.123:15060;transport=udp;lr;ibmsid=local.1471281760668_17020266_17075199;ibmdrr>
238.    Route: <sip:172.16.254.123:15060;transport=tcp;lr;ibmsid=local.1471281760668_17020266_17075199;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=S3731c2jg5gDr
243.    To: <sip:84810@apex.local>;tag=0464ecd40b6e6181a571eb39c00
244.    Call-ID: 2babe1e8-2230-1235-5bbe-005056b26115
245.    CSeq: 99070743 PRACK
246.    Contact: <sip:mod_sofia@10.2.160.150:5080>
247.    RAck: 1 99070742 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:33:32.843921 [DEBUG] sofia.c:7014 Channel sofia/external/84810@apex.local entering state [proceeding][183]
255. 2016-11-10 14:33:32.843921 [DEBUG] sofia.c:7024 Remote SDP:
256. v=0
257. o=- 1478813612 2 IN IP4 172.16.254.100
258. s=-
259. c=IN IP4 172.16.254.107
260. b=AS:64
261. t=0 0
262. m=audio 18960 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:33:32.843921 [NOTICE] sofia.c:7170 Pre-Answer sofia/external/84810@apex.local!
268. 2016-11-10 14:33:32.843921 [DEBUG] switch_channel.c:3471 (sofia/external/84810@apex.local) Callstate Change DOWN -> EARLY
269. recv 429 bytes from udp/[172.16.254.122]:5060 at 14:33:32.849992:
270.    ------------------------------------------------------------------------
271.    SIP/2.0 200 OK
272.    From: "8017189923" <sip:8017189923@10.2.160.150>;tag=S3731c2jg5gDr
273.    To: <sip:84810@apex.local>;tag=0464ecd40b6e6181a571eb39c00
274.    Call-ID: 2babe1e8-2230-1235-5bbe-005056b26115
275.    CSeq: 99070743 PRACK
276.    Via: SIP/2.0/UDP 10.2.160.150:5080;rport=5080;branch=z9hG4bK4Bg3tp2g80DZe
277.    Av-Global-Session-ID: 544871c0-a78d-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:33:32.843921 [DEBUG] switch_ivr_originate.c:3686 Originate Resulted in Success: [sofia/external/84810@apex.local]
283. 2016-11-10 14:33:32.843921 [INFO] switch_ivr.c:784 Sending early media
284. 2016-11-10 14:33:32.843921 [NOTICE] mod_sofia.c:2314 Pre-Answer sofia/external/8017189923@apex.local!
285. 2016-11-10 14:33:32.843921 [DEBUG] switch_channel.c:3471 (sofia/external/8017189923@apex.local) Callstate Change RINGING -> EARLY
286. send 1178 bytes to udp/[172.16.254.192]:5060 at 14:33:32.860896:
287.    ------------------------------------------------------------------------
288.    SIP/2.0 183 Session Progress
289.    Via: SIP/2.0/UDP apex.local:5060;branch=z9hG4bK77F6A017FE;received=172.16.254.192
290.    From: <sip:8017189923@apex.local>;tag=E583DFFA-18E6
291.    To: <sip:84810@pci.vivint.com>;tag=rteB0HHFKvttc
292.    Call-ID: 29E354F5-A6C411E6-8665C066-636412D4@172.16.254.192
293.    CSeq: 101 INVITE
294.    Contact: <sip:84810@10.2.160.150:5080;transport=udp>
295.    RSeq: 1938420048
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=- 1478813612 2 IN IP4 172.16.254.100
311.    s=-
312.    c=IN IP4 172.16.254.107
313.    b=AS:64
314.    t=0 0
315.    m=audio 18960 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:33:32.843921 [DEBUG] switch_ivr_bridge.c:1324 (sofia/external/8017189923@apex.local) State Change CS_EXECUTE -> CS_HIBERNATE
321. 2016-11-10 14:33:32.843921 [DEBUG] switch_ivr_bridge.c:1325 (sofia/external/84810@apex.local) State Change CS_CONSUME_MEDIA -> CS_HIBERNATE
322. 2016-11-10 14:33:32.843921 [DEBUG] sofia.c:7014 Channel sofia/external/8017189923@apex.local entering state [early][183]
323. 2016-11-10 14:33:32.843921 [DEBUG] switch_core_state_machine.c:650 (sofia/external/8017189923@apex.local) State EXECUTE going to sleep
324. 2016-11-10 14:33:32.843921 [DEBUG] switch_core_state_machine.c:584 (sofia/external/8017189923@apex.local) Running State Change CS_HIBERNATE
325. 2016-11-10 14:33:32.843921 [DEBUG] switch_core_state_machine.c:665 (sofia/external/8017189923@apex.local) State HIBERNATE
326. 2016-11-10 14:33:32.843921 [DEBUG] mod_sofia.c:180 sofia/external/8017189923@apex.local SOFIA HIBERNATE
327. 2016-11-10 14:33:32.843921 [DEBUG] switch_core_state_machine.c:420 sofia/external/8017189923@apex.local Standard HIBERNATE
328. 2016-11-10 14:33:32.843921 [DEBUG] switch_core_state_machine.c:665 (sofia/external/8017189923@apex.local) State HIBERNATE going to sleep
329. 2016-11-10 14:33:32.843921 [DEBUG] switch_core_state_machine.c:584 (sofia/external/84810@apex.local) Running State Change CS_HIBERNATE
330. 2016-11-10 14:33:32.843921 [DEBUG] switch_core_state_machine.c:665 (sofia/external/84810@apex.local) State HIBERNATE
331. 2016-11-10 14:33:32.843921 [DEBUG] mod_sofia.c:180 sofia/external/84810@apex.local SOFIA HIBERNATE
332. 2016-11-10 14:33:32.843921 [DEBUG] switch_core_state_machine.c:420 sofia/external/84810@apex.local Standard HIBERNATE
333. 2016-11-10 14:33:32.843921 [DEBUG] switch_core_state_machine.c:665 (sofia/external/84810@apex.local) State HIBERNATE going to sleep
334. recv 435 bytes from udp/[172.16.254.192]:56031 at 14:33:32.862894:
335.    ------------------------------------------------------------------------
336.    PRACK sip:84810@pci.vivint.com:5080;transport=udp SIP/2.0
337.    Via: SIP/2.0/UDP apex.local:5060;branch=z9hG4bK77F6A11346
338.    From: <sip:8017189923@apex.local>;tag=E583DFFA-18E6
339.    To: <sip:84810@pci.vivint.com>;tag=rteB0HHFKvttc
340.    Date: Thu, 10 Nov 2016 21:33:32 GMT
341.    Call-ID: 29E354F5-A6C411E6-8665C066-636412D4@172.16.254.192
342.    CSeq: 102 PRACK
343.    RAck: 1938420048 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.192]:5060 at 14:33:32.863058:
350.    ------------------------------------------------------------------------
351.    SIP/2.0 200 OK
352.    Via: SIP/2.0/UDP apex.local:5060;branch=z9hG4bK77F6A11346;received=172.16.254.192
353.    From: <sip:8017189923@apex.local>;tag=E583DFFA-18E6
354.    To: <sip:84810@pci.vivint.com>;tag=rteB0HHFKvttc
355.    Call-ID: 29E354F5-A6C411E6-8665C066-636412D4@172.16.254.192
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 1636 bytes from udp/[172.16.254.122]:5060 at 14:33:33.811270:
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: 544871c0-a78d-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_17020266_17075199;ibmdrr>
380.    Record-Route: <sip:172.16.254.123:15060;transport=udp;lr;ibmsid=local.1471281760668_17020266_17075199;ibmdrr>
381.    Record-Route: <sip:rw-39656cad@172.16.254.122;lr>
382.    Contact: <sip:172.16.254.100;transport=tcp;gsid=544871c0-a78d-11e6-a95f-0050568452e0>
383.    Allow: INVITE, ACK, OPTIONS, BYE, CANCEL, NOTIFY, REFER, INFO, PRACK, UPDATE
384.    To: <sip:84810@apex.local>;tag=0464ecd40b6e6181a571eb39c00
385.    From: "8017189923" <sip:8017189923@10.2.160.150>;tag=S3731c2jg5gDr
386.    Call-ID: 2babe1e8-2230-1235-5bbe-005056b26115
387.    CSeq: 99070742 INVITE
388.    Via: SIP/2.0/UDP 10.2.160.150:5080;rport=5080;branch=z9hG4bK32paSUHDBrQcK
389.    Content-Type: application/sdp
390.    Content-Length: 201
391.  
392.    v=0
393.    o=- 1478813612 2 IN IP4 172.16.254.100
394.    s=-
395.    c=IN IP4 172.16.254.107
396.    b=AS:64
397.    t=0 0
398.    m=audio 18960 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 1027 bytes to udp/[172.16.254.122]:5060 at 14:33:33.811581:
405.    ------------------------------------------------------------------------
406.    PRACK sip:84810@172.16.254.100;transport=tcp;gsid=544871c0-a78d-11e6-a95f-0050568452e0 SIP/2.0
407.    Via: SIP/2.0/UDP 10.2.160.150:5080;rport;branch=z9hG4bK5m9UvHKm593Ha
408.    Route: <sip:rw-39656cad@172.16.254.122;lr>
409.    Route: <sip:172.16.254.123:15060;transport=udp;lr;ibmsid=local.1471281760668_17020266_17075199;ibmdrr>
410.    Route: <sip:172.16.254.123:15060;transport=tcp;lr;ibmsid=local.1471281760668_17020266_17075199;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=S3731c2jg5gDr
415.    To: <sip:84810@apex.local>;tag=0464ecd40b6e6181a571eb39c00
416.    Call-ID: 2babe1e8-2230-1235-5bbe-005056b26115
417.    CSeq: 99070744 PRACK
418.    Contact: <sip:mod_sofia@10.2.160.150:5080>
419.    RAck: 2 99070742 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:33:33.803924 [DEBUG] sofia.c:7014 Channel sofia/external/84810@apex.local entering state [proceeding][180]
427. 2016-11-10 14:33:33.803924 [DEBUG] sofia.c:7021 Duplicate SDP
428. v=0
429. o=- 1478813612 2 IN IP4 172.16.254.100
430. s=-
431. c=IN IP4 172.16.254.107
432. b=AS:64
433. t=0 0
434. m=audio 18960 RTP/AVP 0 120
435. a=rtpmap:0 PCMU/8000
436. a=rtpmap:120 telephone-event/8000
437. a=ptime:20
438.  
439. recv 429 bytes from udp/[172.16.254.122]:5060 at 14:33:33.815742:
440.    ------------------------------------------------------------------------
441.    SIP/2.0 200 OK
442.    From: "8017189923" <sip:8017189923@10.2.160.150>;tag=S3731c2jg5gDr
443.    To: <sip:84810@apex.local>;tag=0464ecd40b6e6181a571eb39c00
444.    Call-ID: 2babe1e8-2230-1235-5bbe-005056b26115
445.    CSeq: 99070744 PRACK
446.    Via: SIP/2.0/UDP 10.2.160.150:5080;rport=5080;branch=z9hG4bK5m9UvHKm593Ha
447.    Av-Global-Session-ID: 544871c0-a78d-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 1679 bytes from udp/[172.16.254.122]:5060 at 14:33:35.446840:
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: 544871c0-a78d-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_17020266_17075199;ibmdrr>
467.    Record-Route: <sip:172.16.254.123:15060;transport=udp;lr;ibmsid=local.1471281760668_17020266_17075199;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=544871c0-a78d-11e6-a95f-0050568452e0>
471.    Allow: INVITE, ACK, OPTIONS, BYE, CANCEL, NOTIFY, REFER, INFO, PRACK, UPDATE
472.    To: <sip:84810@apex.local>;tag=0464ecd40b6e6181a571eb39c00
473.    From: "8017189923" <sip:8017189923@10.2.160.150>;tag=S3731c2jg5gDr
474.    Call-ID: 2babe1e8-2230-1235-5bbe-005056b26115
475.    CSeq: 99070742 INVITE
476.    Via: SIP/2.0/UDP 10.2.160.150:5080;rport=5080;branch=z9hG4bK32paSUHDBrQcK
477.    Content-Type: application/sdp
478.    Content-Length: 201
479.  
480.    v=0
481.    o=- 1478813612 2 IN IP4 172.16.254.100
482.    s=-
483.    c=IN IP4 172.16.254.107
484.    b=AS:64
485.    t=0 0
486.    m=audio 18960 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:33:35.443934 [DEBUG] sofia.c:7014 Channel sofia/external/84810@apex.local entering state [completing][200]
493. 2016-11-10 14:33:35.443934 [DEBUG] sofia.c:7021 Duplicate SDP
494. v=0
495. o=- 1478813612 2 IN IP4 172.16.254.100
496. s=-
497. c=IN IP4 172.16.254.107
498. b=AS:64
499. t=0 0
500. m=audio 18960 RTP/AVP 0 120
501. a=rtpmap:0 PCMU/8000
502. a=rtpmap:120 telephone-event/8000
503. a=ptime:20
504.  
505. send 792 bytes to udp/[172.16.254.122]:5060 at 14:33:35.448558:
506.    ------------------------------------------------------------------------
507.    ACK sip:172.16.254.100;transport=tcp;gsid=544871c0-a78d-11e6-a95f-0050568452e0 SIP/2.0
508.    Via: SIP/2.0/UDP 10.2.160.150:5080;rport;branch=z9hG4bK6X2myc4Q2jt4N
509.    Route: <sip:rw-39656cad@172.16.254.122;lr>
510.    Route: <sip:172.16.254.123:15060;transport=udp;lr;ibmsid=local.1471281760668_17020266_17075199;ibmdrr>
511.    Route: <sip:172.16.254.123:15060;transport=tcp;lr;ibmsid=local.1471281760668_17020266_17075199;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=S3731c2jg5gDr
516.    To: <sip:84810@apex.local>;tag=0464ecd40b6e6181a571eb39c00
517.    Call-ID: 2babe1e8-2230-1235-5bbe-005056b26115
518.    CSeq: 99070742 ACK
519.    Contact: <sip:mod_sofia@10.2.160.150:5080>
520.    Content-Length: 0
521.  
522.    ------------------------------------------------------------------------
523. 2016-11-10 14:33:35.443934 [DEBUG] sofia.c:7014 Channel sofia/external/84810@apex.local entering state [ready][200]
524. 2016-11-10 14:33:35.443934 [NOTICE] sofia.c:8013 Channel [sofia/external/84810@apex.local] has been answered
525. 2016-11-10 14:33:35.443934 [DEBUG] switch_channel.c:3770 (sofia/external/84810@apex.local) Callstate Change EARLY -> ACTIVE
526. send 913 bytes to udp/[172.16.254.192]:5060 at 14:33:35.450959:
527.    ------------------------------------------------------------------------
528.    SIP/2.0 200 OK
529.    Via: SIP/2.0/UDP apex.local:5060;branch=z9hG4bK77F6A017FE;received=172.16.254.192
530.    From: <sip:8017189923@apex.local>;tag=E583DFFA-18E6
531.    To: <sip:84810@pci.vivint.com>;tag=rteB0HHFKvttc
532.    Call-ID: 29E354F5-A6C411E6-8665C066-636412D4@172.16.254.192
533.    CSeq: 101 INVITE
534.    Contact: <sip:84810@10.2.160.150:5080;transport=udp>
535.    User-Agent: FreeSWITCH-mod_sofia/1.6.12~64bit
536.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, PRACK, NOTIFY
537.    Require: timer
538.    Supported: precondition, 100rel, timer, path, replaces
539.    Allow-Events: talk, hold, conference, refer
540.    Session-Expires: 1800;refresher=uac
541.    Content-Length: 0
542.    P-Location: SM;origlocname="Provo";origsiglocname="Provo";origmedialocname="Provo";termlocname="Provo";termsiglocname="Provo";termmedialocname="Provo";smaccounting="true"
543.    P-AV-Message-Id: 1_2
544.    P-Asserted-Identity: "Outbound Call" <sip:84810@pci.vivint.com>
545.  
546.    ------------------------------------------------------------------------
547. 2016-11-10 14:33:35.443934 [NOTICE] switch_ivr.c:779 Channel [sofia/external/8017189923@apex.local] has been answered
548. 2016-11-10 14:33:35.443934 [DEBUG] switch_channel.c:3770 (sofia/external/8017189923@apex.local) Callstate Change EARLY -> ACTIVE
549. 2016-11-10 14:33:35.443934 [DEBUG] sofia.c:7014 Channel sofia/external/8017189923@apex.local entering state [completed][200]
550. recv 402 bytes from udp/[172.16.254.192]:56031 at 14:33:35.453268:
551.    ------------------------------------------------------------------------
552.    ACK sip:84810@pci.vivint.com:5080;transport=udp SIP/2.0
553.    Via: SIP/2.0/UDP apex.local:5060;branch=z9hG4bK77F6A51280
554.    From: <sip:8017189923@apex.local>;tag=E583DFFA-18E6
555.    To: <sip:84810@pci.vivint.com>;tag=rteB0HHFKvttc
556.    Date: Thu, 10 Nov 2016 21:33:32 GMT
557.    Call-ID: 29E354F5-A6C411E6-8665C066-636412D4@172.16.254.192
558.    Max-Forwards: 70
559.    CSeq: 101 ACK
560.    Allow-Events: telephone-event
561.    Content-Length: 0
562.  
563.    ------------------------------------------------------------------------
564. 2016-11-10 14:33:35.443934 [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. freeswitch@freeswitch1-stg>
573. freeswitch@freeswitch1-stg>
574. freeswitch@freeswitch1-stg>
575. recv 1383 bytes from udp/[172.16.254.122]:5060 at 14:33:54.206630:
576.    ------------------------------------------------------------------------
577.    UPDATE sip:mod_sofia@10.2.160.150:5080 SIP/2.0
578.    Remote-Party-ID: "Brain Beebe" <sip:+8012297771@172.16.254.190>;party=calling;screen=no;privacy=off
579.    P-Asserted-Identity: "Brain Beebe" <sip:+8012297771@172.16.254.190>
580.    Supported: 100rel, histinfo, join, replaces, sdp-anat, timer
581.    User-Agent: Avaya CM/R016x.03.0.124.0 AVAYA-SM-6.3.17.0.631705
582.    Via: SIP/2.0/UDP 172.16.254.122;rport;branch=z9hG4bK496700933092488-AP;ft=172.16.254.122~13c4
583.    Via: SIP/2.0/UDP 172.16.254.123:15060;rport=15060;ibmsid=local.1471281760668_17020266_17075199;branch=z9hG4bK496700933092488
584.    Via: SIP/2.0/TCP 172.16.254.122;branch=z9hG4bK083ad940b6e61681a571eb39c00-AP;ft=31;received=172.16.254.122;rport=41142
585.    Via: SIP/2.0/TCP 172.16.254.100;branch=z9hG4bK083ad940b6e61681a571eb39c00
586.    Allow: INVITE, ACK, OPTIONS, BYE, CANCEL, NOTIFY, REFER, INFO, PRACK, UPDATE
587.    Contact: "Brain Beebe" <sip:+8012297771@172.16.254.100;transport=tcp;gsid=544871c0-a78d-11e6-a95f-0050568452e0>
588.    Accept-Language: en
589.    Min-SE: 1200
590.    Session-Expires: 1200;refresher=uac
591.    P-Charging-Vector: icid-value="544871c0-a78d-11e6-a95f-0050568452e0"
592.    Av-Global-Session-ID: 544871c0-a78d-11e6-a95f-0050568452e0
593.    From: <sip:84810@apex.local>;tag=0464ecd40b6e6181a571eb39c00
594.    To: "8017189923" <sip:8017189923@10.2.160.150>;tag=S3731c2jg5gDr
595.    Call-ID: 2babe1e8-2230-1235-5bbe-005056b26115
596.    Max-Forwards: 67
597.    CSeq: 1 UPDATE
598.    Content-Length: 0
599.  
600.    ------------------------------------------------------------------------
601. send 971 bytes to udp/[172.16.254.122]:5060 at 14:33:54.206938:
602.    ------------------------------------------------------------------------
603.    SIP/2.0 200 OK
604.    Via: SIP/2.0/UDP 172.16.254.122;rport=5060;branch=z9hG4bK496700933092488-AP;ft=172.16.254.122~13c4
605.    Via: SIP/2.0/UDP 172.16.254.123:15060;rport=15060;ibmsid=local.1471281760668_17020266_17075199;branch=z9hG4bK496700933092488
606.    Via: SIP/2.0/TCP 172.16.254.122;branch=z9hG4bK083ad940b6e61681a571eb39c00-AP;ft=31;received=172.16.254.122;rport=41142
607.    Via: SIP/2.0/TCP 172.16.254.100;branch=z9hG4bK083ad940b6e61681a571eb39c00
608.    From: <sip:84810@apex.local>;tag=0464ecd40b6e6181a571eb39c00
609.    To: "8017189923" <sip:8017189923@10.2.160.150>;tag=S3731c2jg5gDr
610.    Call-ID: 2babe1e8-2230-1235-5bbe-005056b26115
611.    CSeq: 1 UPDATE
612.    Contact: <sip:mod_sofia@10.2.160.150:5080>
613.    User-Agent: FreeSWITCH-mod_sofia/1.6.12~64bit
614.    Accept: application/sdp
615.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, PRACK, NOTIFY
616.    Require: timer
617.    Supported: precondition, 100rel, timer, path, replaces
618.    Session-Expires: 1200;refresher=uac
619.    Content-Length: 0
620.  
621.    ------------------------------------------------------------------------
622. 2016-11-10 14:33:54.184290 [INFO] sofia.c:1278 sofia/external/84810@apex.local Update Callee ID to "Brain Beebe" <+8012297771>
623. recv 1555 bytes from udp/[172.16.254.122]:5060 at 14:33:54.210701:
624.    ------------------------------------------------------------------------
625.    INVITE sip:mod_sofia@10.2.160.150:5080 SIP/2.0
626.    P-Location: SM;origlocname="Provo";origsiglocname="Provo";origmedialocname="Provo";termlocname="Provo";termsiglocname="Provo";termmedialocname="Provo";smaccounting="true"
627.    Remote-Party-ID: "Brain Beebe" <sip:+8012297771@172.16.254.190>;party=calling;screen=no;privacy=off
628.    P-Asserted-Identity: "Brain Beebe" <sip:+8012297771@172.16.254.190>
629.    Supported: 100rel, histinfo, join, replaces, sdp-anat, timer
630.    User-Agent: Avaya CM/R016x.03.0.124.0 AVAYA-SM-6.3.17.0.631705
631.    Via: SIP/2.0/UDP 172.16.254.122;rport;branch=z9hG4bK315187799640258-AP;ft=172.16.254.122~13c4
632.    Via: SIP/2.0/UDP 172.16.254.123:15060;rport=15060;ibmsid=local.1471281760668_17020266_17075199;branch=z9hG4bK315187799640258
633.    Via: SIP/2.0/TCP 172.16.254.122;branch=z9hG4bK083ad940b6e61691a571eb39c00-AP;ft=31;received=172.16.254.122;rport=41142
634.    Via: SIP/2.0/TCP 172.16.254.100;branch=z9hG4bK083ad940b6e61691a571eb39c00
635.    Allow: INVITE, ACK, OPTIONS, BYE, CANCEL, NOTIFY, REFER, INFO, PRACK, UPDATE
636.    Contact: "Brain Beebe" <sip:+8012297771@172.16.254.100;transport=tcp;gsid=544871c0-a78d-11e6-a95f-0050568452e0>
637.    Accept-Language: en
638.    Min-SE: 1200
639.    Session-Expires: 1200;refresher=uac
640.    P-Charging-Vector: icid-value="544871c0-a78d-11e6-a95f-0050568452e0"
641.    Av-Global-Session-ID: 544871c0-a78d-11e6-a95f-0050568452e0
642.    From: <sip:84810@apex.local>;tag=0464ecd40b6e6181a571eb39c00
643.    To: "8017189923" <sip:8017189923@10.2.160.150>;tag=S3731c2jg5gDr
644.    Call-ID: 2babe1e8-2230-1235-5bbe-005056b26115
645.    Max-Forwards: 67
646.    CSeq: 2 INVITE
647.    Content-Length: 0
648.  
649.    ------------------------------------------------------------------------
650. send 700 bytes to udp/[172.16.254.122]:5060 at 14:33:54.210933:
651.    ------------------------------------------------------------------------
652.    SIP/2.0 100 Trying
653.    Via: SIP/2.0/UDP 172.16.254.122;rport=5060;branch=z9hG4bK315187799640258-AP;ft=172.16.254.122~13c4
654.    Via: SIP/2.0/UDP 172.16.254.123:15060;rport=15060;ibmsid=local.1471281760668_17020266_17075199;branch=z9hG4bK315187799640258
655.    Via: SIP/2.0/TCP 172.16.254.122;branch=z9hG4bK083ad940b6e61691a571eb39c00-AP;ft=31;received=172.16.254.122;rport=41142
656.    Via: SIP/2.0/TCP 172.16.254.100;branch=z9hG4bK083ad940b6e61691a571eb39c00
657.    From: <sip:84810@apex.local>;tag=0464ecd40b6e6181a571eb39c00
658.    To: "8017189923" <sip:8017189923@10.2.160.150>;tag=S3731c2jg5gDr
659.    Call-ID: 2babe1e8-2230-1235-5bbe-005056b26115
660.    CSeq: 2 INVITE
661.    User-Agent: FreeSWITCH-mod_sofia/1.6.12~64bit
662.    Content-Length: 0
663.  
664.    ------------------------------------------------------------------------
665. 2016-11-10 14:33:54.184290 [DEBUG] sofia.c:7014 Channel sofia/external/84810@apex.local entering state [received][100]
666. send 1113 bytes to udp/[172.16.254.122]:5060 at 14:33:54.211871:
667.    ------------------------------------------------------------------------
668.    SIP/2.0 200 OK
669.    Via: SIP/2.0/UDP 172.16.254.122;rport=5060;branch=z9hG4bK315187799640258-AP;ft=172.16.254.122~13c4
670.    Via: SIP/2.0/UDP 172.16.254.123:15060;rport=15060;ibmsid=local.1471281760668_17020266_17075199;branch=z9hG4bK315187799640258
671.    Via: SIP/2.0/TCP 172.16.254.122;branch=z9hG4bK083ad940b6e61691a571eb39c00-AP;ft=31;received=172.16.254.122;rport=41142
672.    Via: SIP/2.0/TCP 172.16.254.100;branch=z9hG4bK083ad940b6e61691a571eb39c00
673.    From: <sip:84810@apex.local>;tag=0464ecd40b6e6181a571eb39c00
674.    To: "8017189923" <sip:8017189923@10.2.160.150>;tag=S3731c2jg5gDr
675.    Call-ID: 2babe1e8-2230-1235-5bbe-005056b26115
676.    CSeq: 2 INVITE
677.    Contact: <sip:mod_sofia@10.2.160.150:5080>
678.    User-Agent: FreeSWITCH-mod_sofia/1.6.12~64bit
679.    Accept: application/sdp
680.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, PRACK, NOTIFY
681.    Require: timer
682.    Supported: precondition, 100rel, timer, path, replaces
683.    Session-Expires: 1200;refresher=uac
684.    Content-Type: application/sdp
685.    Content-Length: 109
686.  
687.    v=0
688.    o=FreeSWITCH 1478795602 1478795603 IN IP4 172.16.254.192
689.    s=FreeSWITCH
690.    c=IN IP4 172.16.254.192
691.    t=0 0
692.    ------------------------------------------------------------------------
693. 2016-11-10 14:33:54.184290 [DEBUG] sofia.c:7014 Channel sofia/external/84810@apex.local entering state [completed][200]
694. recv 984 bytes from udp/[172.16.254.122]:5060 at 14:33:54.252463:
695.    ------------------------------------------------------------------------
696.    ACK sip:mod_sofia@10.2.160.150:5080 SIP/2.0
697.    P-Location: SM;origlocname="Provo";origsiglocname="Provo";origmedialocname="Provo";termlocname="Provo";termsiglocname="Provo";termmedialocname="Provo";smaccounting="true"
698.    User-Agent: Avaya CM/R016x.03.0.124.0 AVAYA-SM-6.3.17.0.631705
699.    Via: SIP/2.0/UDP 172.16.254.122;rport;branch=z9hG4bK504520250318247-AP;ft=172.16.254.122~13c4
700.    Via: SIP/2.0/UDP 172.16.254.123:15060;rport=15060;ibmsid=local.1471281760668_17020266_17075199;branch=z9hG4bK504520250318247
701.    Via: SIP/2.0/TCP 172.16.254.122;branch=z9hG4bK083ad940b6e616a1a571eb39c00-AP;ft=31;received=172.16.254.122;rport=41142
702.    Via: SIP/2.0/TCP 172.16.254.100;branch=z9hG4bK083ad940b6e616a1a571eb39c00
703.    Av-Global-Session-ID: 544871c0-a78d-11e6-a95f-0050568452e0
704.    From: <sip:84810@apex.local>;tag=0464ecd40b6e6181a571eb39c00
705.    To: "8017189923" <sip:8017189923@10.2.160.150>;tag=S3731c2jg5gDr
706.    Call-ID: 2babe1e8-2230-1235-5bbe-005056b26115
707.    Max-Forwards: 67
708.    CSeq: 2 ACK
709.    Content-Length: 0
710.  
711.    ------------------------------------------------------------------------
712. 2016-11-10 14:33:54.224650 [DEBUG] sofia.c:7014 Channel sofia/external/84810@apex.local entering state [ready][200]
713. recv 810 bytes from udp/[172.16.254.122]:5060 at 14:33:54.291771:
714.    ------------------------------------------------------------------------
715.    BYE sip:mod_sofia@10.2.160.150:5080 SIP/2.0
716.    User-Agent: Avaya CM/R016x.03.0.124.0 AVAYA-SM-6.3.17.0.631705
717.    Via: SIP/2.0/UDP 172.16.254.122;rport;branch=z9hG4bK71713389489978-AP;ft=172.16.254.122~13c4
718.    Via: SIP/2.0/UDP 172.16.254.123:15060;rport=15060;ibmsid=local.1471281760668_17020266_17075199;branch=z9hG4bK71713389489978
719.    Via: SIP/2.0/TCP 172.16.254.122;branch=z9hG4bK083ad940b6e616b1a571eb39c00-AP;ft=31;received=172.16.254.122;rport=41142
720.    Via: SIP/2.0/TCP 172.16.254.100;branch=z9hG4bK083ad940b6e616b1a571eb39c00
721.    Av-Global-Session-ID: 544871c0-a78d-11e6-a95f-0050568452e0
722.    From: <sip:84810@apex.local>;tag=0464ecd40b6e6181a571eb39c00
723.    To: "8017189923" <sip:8017189923@10.2.160.150>;tag=S3731c2jg5gDr
724.    Call-ID: 2babe1e8-2230-1235-5bbe-005056b26115
725.    Max-Forwards: 67
726.    CSeq: 3 BYE
727.    Content-Length: 0
728.  
729.    ------------------------------------------------------------------------
730. 2016-11-10 14:33:54.283925 [NOTICE] sofia.c:1011 Hangup sofia/external/84810@apex.local [CS_HIBERNATE] [NORMAL_CLEARING]
731. 2016-11-10 14:33:54.283925 [DEBUG] switch_ivr_bridge.c:1071 sofia/external/84810@apex.local skip receive message [UNBRIDGE] (channel is hungup already)
732. 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:584 (sofia/external/84810@apex.local) Running State Change CS_HANGUP
733. 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:850 (sofia/external/84810@apex.local) Callstate Change ACTIVE -> HANGUP
734. send 844 bytes to udp/[172.16.254.122]:5060 at 14:33:54.293356:
735.    ------------------------------------------------------------------------
736.    SIP/2.0 200 OK
737.    Via: SIP/2.0/UDP 172.16.254.122;rport=5060;branch=z9hG4bK71713389489978-AP;ft=172.16.254.122~13c4
738. 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:852 (sofia/external/84810@apex.local) State HANGUP
739.    Via: SIP/2.0/UDP 172.16.254.123:15060;rport=15060;ibmsid=local.1471281760668_17020266_17075199;branch=z9hG4bK71713389489978
740.    Via: SIP/2.0/TCP 172.16.254.122;branch=z9hG4bK083ad940b6e616b1a571eb39c00-AP;ft=31;received=172.16.254.122;rport=41142
741.    Via: SIP/2.0/TCP 172.16.254.100;branch=z9hG4bK083ad940b6e616b1a571eb39c00
742. 2016-11-10 14:33:54.283925 [DEBUG] mod_sofia.c:438 Channel sofia/external/84810@apex.local hanging up, cause: NORMAL_CLEARING
743.    From: <sip:84810@apex.local>;tag=0464ecd40b6e6181a571eb39c00
744.    To: "8017189923" <sip:8017189923@10.2.160.150>;tag=S3731c2jg5gDr
745.    Call-ID: 2babe1e8-2230-1235-5bbe-005056b26115
746.    CSeq: 3 BYE
747.    User-Agent: FreeSWITCH-mod_sofia/1.6.12~64bit
748.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, PRACK, NOTIFY
749.    Supported: precondition, 100rel, timer, path, replaces
750.    Content-Length: 0
751.  
752.    ------------------------------------------------------------------------
753. 2016-11-10 14:33:54.283925 [DEBUG] switch_ivr_bridge.c:1207 (sofia/external/8017189923@apex.local) State Change CS_HIBERNATE -> CS_EXECUTE
754. 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:584 (sofia/external/8017189923@apex.local) Running State Change CS_EXECUTE
755. 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:60 sofia/external/84810@apex.local Standard HANGUP, cause: NORMAL_CLEARING
756. 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:852 (sofia/external/84810@apex.local) State HANGUP going to sleep
757. 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:619 (sofia/external/84810@apex.local) State Change CS_HANGUP -> CS_REPORTING
758. 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:584 (sofia/external/84810@apex.local) Running State Change CS_REPORTING
759. 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:938 (sofia/external/84810@apex.local) State REPORTING
760. 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:174 sofia/external/84810@apex.local Standard REPORTING, cause: NORMAL_CLEARING
761. 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:938 (sofia/external/84810@apex.local) State REPORTING going to sleep
762. 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:650 (sofia/external/8017189923@apex.local) State EXECUTE
763. 2016-11-10 14:33:54.283925 [DEBUG] mod_sofia.c:198 sofia/external/8017189923@apex.local SOFIA EXECUTE
764. 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:328 sofia/external/8017189923@apex.local Standard EXECUTE
765. 2016-11-10 14:33:54.283925 [NOTICE] switch_core_state_machine.c:385 sofia/external/8017189923@apex.local has executed the last dialplan instruction, hanging up.
766. 2016-11-10 14:33:54.283925 [NOTICE] switch_core_state_machine.c:387 Hangup sofia/external/8017189923@apex.local [CS_EXECUTE] [NORMAL_CLEARING]
767. 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:610 (sofia/external/84810@apex.local) State Change CS_REPORTING -> CS_DESTROY
768. 2016-11-10 14:33:54.283925 [DEBUG] switch_core_session.c:1647 Session 12 (sofia/external/84810@apex.local) Locked, Waiting on external entities
769. 2016-11-10 14:33:54.283925 [NOTICE] switch_core_session.c:1665 Session 12 (sofia/external/84810@apex.local) Ended
770. 2016-11-10 14:33:54.283925 [NOTICE] switch_core_session.c:1669 Close Channel sofia/external/84810@apex.local [CS_DESTROY]
771. 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:650 (sofia/external/8017189923@apex.local) State EXECUTE going to sleep
772. 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:584 (sofia/external/8017189923@apex.local) Running State Change CS_HANGUP
773. 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:741 (sofia/external/84810@apex.local) Running State Change CS_DESTROY
774. 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:850 (sofia/external/8017189923@apex.local) Callstate Change ACTIVE -> HANGUP
775. 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:751 (sofia/external/84810@apex.local) State DESTROY
776. 2016-11-10 14:33:54.283925 [DEBUG] mod_sofia.c:343 sofia/external/84810@apex.local SOFIA DESTROY
777. 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:181 sofia/external/84810@apex.local Standard DESTROY
778. 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:751 (sofia/external/84810@apex.local) State DESTROY going to sleep
779. 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:852 (sofia/external/8017189923@apex.local) State HANGUP
780. 2016-11-10 14:33:54.283925 [DEBUG] mod_sofia.c:432 sofia/external/8017189923@apex.local Overriding SIP cause 480 with 200 from the other leg
781. 2016-11-10 14:33:54.283925 [DEBUG] mod_sofia.c:438 Channel sofia/external/8017189923@apex.local hanging up, cause: NORMAL_CLEARING
782. 2016-11-10 14:33:54.283925 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/external/8017189923@apex.local
783. 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:60 sofia/external/8017189923@apex.local Standard HANGUP, cause: NORMAL_CLEARING
784. 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:852 (sofia/external/8017189923@apex.local) State HANGUP going to sleep
785. 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:619 (sofia/external/8017189923@apex.local) State Change CS_HANGUP -> CS_REPORTING
786. 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:584 (sofia/external/8017189923@apex.local) Running State Change CS_REPORTING
787. 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:938 (sofia/external/8017189923@apex.local) State REPORTING
788. 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:174 sofia/external/8017189923@apex.local Standard REPORTING, cause: NORMAL_CLEARING
789. 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:938 (sofia/external/8017189923@apex.local) State REPORTING going to sleep
790. send 588 bytes to udp/[172.16.254.192]:5060 at 14:33:54.294891:
791.    ------------------------------------------------------------------------
792.    BYE sip:8017189923@172.16.254.192:5060 SIP/2.0
793.    Via: SIP/2.0/UDP 10.2.160.150:5080;rport;branch=z9hG4bK76UD07mUZUgQH
794.    Max-Forwards: 70
795.    From: <sip:84810@pci.vivint.com>;tag=rteB0HHFKvttc
796.    To: <sip:8017189923@apex.local>;tag=E583DFFA-18E6
797.    Call-ID: 29E354F5-A6C411E6-8665C066-636412D4@172.16.254.192
798.    CSeq: 99070753 BYE
799.    User-Agent: FreeSWITCH-mod_sofia/1.6.12~64bit
800.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, PRACK, NOTIFY
801.    Supported: precondition, 100rel, timer, path, replaces
802.    Reason: Q.850;cause=16;text="NORMAL_CLEARING"
803.    Content-Length: 0
804.  
805.    ------------------------------------------------------------------------
806. 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:610 (sofia/external/8017189923@apex.local) State Change CS_REPORTING -> CS_DESTROY
807. 2016-11-10 14:33:54.283925 [DEBUG] switch_core_session.c:1647 Session 11 (sofia/external/8017189923@apex.local) Locked, Waiting on external entities
808. 2016-11-10 14:33:54.283925 [NOTICE] switch_core_session.c:1665 Session 11 (sofia/external/8017189923@apex.local) Ended
809. 2016-11-10 14:33:54.283925 [NOTICE] switch_core_session.c:1669 Close Channel sofia/external/8017189923@apex.local [CS_DESTROY]
810. 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:741 (sofia/external/8017189923@apex.local) Running State Change CS_DESTROY
811. 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:751 (sofia/external/8017189923@apex.local) State DESTROY
812. 2016-11-10 14:33:54.283925 [DEBUG] mod_sofia.c:343 sofia/external/8017189923@apex.local SOFIA DESTROY
813. 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:181 sofia/external/8017189923@apex.local Standard DESTROY
814. 2016-11-10 14:33:54.283925 [DEBUG] switch_core_state_machine.c:751 (sofia/external/8017189923@apex.local) State DESTROY going to sleep
815. recv 466 bytes from udp/[172.16.254.192]:56031 at 14:33:54.296902:
816.    ------------------------------------------------------------------------
817.    SIP/2.0 200 OK
818.    Via: SIP/2.0/UDP 10.2.160.150:5080;rport;branch=z9hG4bK76UD07mUZUgQH
819.    From: <sip:84810@pci.vivint.com>;tag=rteB0HHFKvttc
820.    To: <sip:8017189923@172.16.254.192>;tag=E583DFFA-18E6
821.    Date: Thu, 10 Nov 2016 21:33:54 GMT
822.    Call-ID: 29E354F5-A6C411E6-8665C066-636412D4@172.16.254.192
823.    Server: Cisco-SIPGateway/IOS-15.3.3.M5
824.    CSeq: 99070753 BYE
825.    Reason: Q.850;cause=16
826.    P-RTP-Stat: PS=1069,OS=171040,PR=1069,OR=171040,PL=0,JI=0,LA=0,DU=18
827.    Content-Length: 0
828.  
829.    ------------------------------------------------------------------------
830.