1. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.310072 [NOTICE] switch_channel.c:1104 New Channel sofia/external/1234 [1cd39dcf-bccc-4a25-9f9d-9b75edb4c938]
2. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.310072 [DEBUG] mod_sofia.c:4819 (sofia/external/1234) State Change CS_NEW -> CS_INIT
3. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.331074 [DEBUG] switch_core_state_machine.c:584 (sofia/external/1234) Running State Change CS_INIT (Cur 1 Tot 2)
4. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.331074 [DEBUG] switch_core_state_machine.c:627 (sofia/external/1234) State INIT
5. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.331074 [DEBUG] mod_sofia.c:90 sofia/external/1234 SOFIA INIT
6. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.331074 [DEBUG] sofia_glue.c:1295 sofia/external/1234 sending invite version: 1.6.18 git 628f1bd 2017-07-06 18:33:44Z 64bit
7. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 Local SDP:
8. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 v=0
9.  
10. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 o=FreeSWITCH 1510112840 1510112841 IN IP4 10.10.30.24
11.  
12. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 s=FreeSWITCH
13.  
14. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 c=IN IP4 10.10.30.24
15.  
16. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 t=0 0
17.  
18. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 m=audio 56716 RTP/AVP 0 101 13
19.  
20. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 a=rtpmap:0 PCMU/8000
21.  
22. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 a=rtpmap:101 telephone-event/8000
23.  
24. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 a=fmtp:101 0-16
25.  
26. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 a=rtpmap:13 CN/8000
27.  
28. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 a=ptime:20
29.  
30. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 a=sendrecv
31.  
32. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938
33. send 1055 bytes to udp/[10.10.101.96]:5060 at 19:32:36.335074:
34.    ------------------------------------------------------------------------
35.    INVITE sip:1234@10.10.101.96 SIP/2.0
36.    Via: SIP/2.0/UDP 10.10.30.24:5080;rport;branch=z9hG4bK8pemXF8Fc9y7H
37.    Max-Forwards: 70
38.    From: "" <sip:1000@10.10.101.96>;tag=6v0DjeHDX2pcD
39.    To: <sip:1234@10.10.101.96>
40.    Call-ID: 6a6ec2d6-3f5e-1236-2795-8d8a610f888f
41.    CSeq: 114748714 INVITE
42.    Contact: <sip:gw+10.10.101.96@10.10.30.24:5080;transport=udp;gw=10.10.101.96>
43.    User-Agent: FreeSWITCH-mod_sofia/1.6.18+git~20170706T183344Z~628f1bd44c~64bit
44.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
45.    Supported: timer, path, replaces
46.    Allow-Events: talk, hold, conference, refer
47.    Content-Type: application/sdp
48.    Content-Disposition: session
49.    Content-Length: 242
50.    X-FS-Support: update_display,send_info
51.    Remote-Party-ID: <sip:0000000000@10.10.101.96>;party=calling;screen=yes;privacy=off
52.    
53.    v=0
54.    o=FreeSWITCH 1510112840 1510112841 IN IP4 10.10.30.24
55.    s=FreeSWITCH
56.    c=IN IP4 10.10.30.24
57.    t=0 0
58.    m=audio 56716 RTP/AVP 0 101 13
59.    a=rtpmap:0 PCMU/8000
60.    a=rtpmap:101 telephone-event/8000
61.    a=fmtp:101 0-16
62.    a=rtpmap:13 CN/8000
63.    a=ptime:20
64.    ------------------------------------------------------------------------
65. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.331074 [DEBUG] switch_core_state_machine.c:40 sofia/external/1234 Standard INIT
66. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.331074 [DEBUG] switch_core_state_machine.c:48 (sofia/external/1234) State Change CS_INIT -> CS_ROUTING
67. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.331074 [DEBUG] switch_core_state_machine.c:627 (sofia/external/1234) State INIT going to sleep
68. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.331074 [DEBUG] switch_core_state_machine.c:584 (sofia/external/1234) Running State Change CS_ROUTING (Cur 1 Tot 2)
69. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.331074 [DEBUG] sofia.c:7048 Channel sofia/external/1234 entering state [calling][0]
70. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.331074 [DEBUG] switch_core_state_machine.c:643 (sofia/external/1234) State ROUTING
71. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.331074 [DEBUG] mod_sofia.c:143 sofia/external/1234 SOFIA ROUTING
72. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.331074 [DEBUG] switch_ivr_originate.c:67 (sofia/external/1234) State Change CS_ROUTING -> CS_CONSUME_MEDIA
73. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.331074 [DEBUG] switch_core_state_machine.c:643 (sofia/external/1234) State ROUTING going to sleep
74. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.331074 [DEBUG] switch_core_state_machine.c:584 (sofia/external/1234) Running State Change CS_CONSUME_MEDIA (Cur 1 Tot 2)
75. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.331074 [DEBUG] switch_core_state_machine.c:662 (sofia/external/1234) State CONSUME_MEDIA
76. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.331074 [DEBUG] switch_core_state_machine.c:662 (sofia/external/1234) State CONSUME_MEDIA going to sleep
77. recv 403 bytes from udp/[10.10.101.96]:5060 at 19:32:36.348075:
78.    ------------------------------------------------------------------------
79.    SIP/2.0 180 Ringing
80.    From: ""<sip:1000@10.10.101.96>;tag=6v0DjeHDX2pcD
81.    To: <sip:1234@10.10.101.96>;tag=8e901d0-0-13c4-55013-1d0029-25e5412a-1d0029
82.    Call-ID: 6a6ec2d6-3f5e-1236-2795-8d8a610f888f
83.    CSeq: 114748714 INVITE
84.    Via: SIP/2.0/UDP 10.10.30.24:5080;rport=5080;branch=z9hG4bK8pemXF8Fc9y7H
85.    Supported: 100rel
86.    User-Agent: Brktsip/6.3.2B8 (Dialogic)
87.    Contact: <sip:10.10.101.96>
88.    Content-Length: 0
89.    
90.    ------------------------------------------------------------------------
91. recv 579 bytes from udp/[10.10.101.96]:5060 at 19:32:36.349075:
92.    ------------------------------------------------------------------------
93.    SIP/2.0 200 OK
94.    From: ""<sip:1000@10.10.101.96>;tag=6v0DjeHDX2pcD
95.    To: <sip:1234@10.10.101.96>;tag=8e901d0-0-13c4-55013-1d0029-25e5412a-1d0029
96.    Call-ID: 6a6ec2d6-3f5e-1236-2795-8d8a610f888f
97.    CSeq: 114748714 INVITE
98.    Via: SIP/2.0/UDP 10.10.30.24:5080;rport=5080;branch=z9hG4bK8pemXF8Fc9y7H
99.    Supported: 100rel
100.    User-Agent: Brktsip/6.3.2B8 (Dialogic)
101.    Contact: <sip:10.10.101.96>
102.    Content-Type: application/sdp
103.    Content-Length: 148
104.    
105.    v=0
106.    o=- 2210889541 0820743000 IN IP4 10.10.101.96
107.    s=no_session_name
108.    t=0 0
109.    m=audio 56383 RTP/AVP 0
110.    c=IN IP4 10.10.101.96
111.    a=rtpmap:0 PCMU/8000
112.    ------------------------------------------------------------------------
113. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.370076 [DEBUG] sofia.c:7048 Channel sofia/external/1234 entering state [proceeding][180]
114. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.370076 [NOTICE] sofia.c:7156 Ring-Ready sofia/external/1234!
115. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.370076 [DEBUG] switch_channel.c:3346 (sofia/external/1234) Callstate Change DOWN -> RINGING
116. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.410078 [DEBUG] sofia.c:7048 Channel sofia/external/1234 entering state [completing][200]
117. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.410078 [DEBUG] sofia.c:7058 Remote SDP:
118. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 v=0
119.  
120. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 o=- 2210889541 820743000 IN IP4 10.10.101.96
121.  
122. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 s=no_session_name
123.  
124. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 t=0 0
125.  
126. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 m=audio 56383 RTP/AVP 0
127.  
128. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 c=IN IP4 10.10.101.96
129.  
130. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 a=rtpmap:0 PCMU/8000
131.  
132. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938
133. send 414 bytes to udp/[10.10.101.96]:5060 at 19:32:36.419079:
134.    ------------------------------------------------------------------------
135.    ACK sip:10.10.101.96 SIP/2.0
136.    Via: SIP/2.0/UDP 10.10.30.24:5080;rport;branch=z9hG4bK9Z7cZaSK9HNtD
137.    Max-Forwards: 70
138.    From: "" <sip:1000@10.10.101.96>;tag=6v0DjeHDX2pcD
139.    To: <sip:1234@10.10.101.96>;tag=8e901d0-0-13c4-55013-1d0029-25e5412a-1d0029
140.    Call-ID: 6a6ec2d6-3f5e-1236-2795-8d8a610f888f
141.    CSeq: 114748714 ACK
142.    Contact: <sip:gw+10.10.101.96@10.10.30.24:5080;transport=udp;gw=10.10.101.96>
143.    Content-Length: 0
144.    
145.    ------------------------------------------------------------------------
146. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.410078 [DEBUG] sofia.c:7048 Channel sofia/external/1234 entering state [ready][200]
147. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.410078 [DEBUG] switch_core_media.c:4445 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
148. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.410078 [DEBUG] switch_core_media.c:4500 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
149. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.410078 [DEBUG] switch_core_media.c:3057 Set Codec sofia/external/1234 PCMU/8000 20 ms 160 samples 64000 bits 1 channels
150. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.410078 [DEBUG] switch_core_codec.c:111 sofia/external/1234 Original read codec set to PCMU:0
151. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.410078 [DEBUG] switch_core_media.c:4770 No 2833 in SDP.  Disable 2833 dtmf and switch to INFO
152. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.410078 [DEBUG] switch_core_media.c:6874 AUDIO RTP [sofia/external/1234] 10.10.30.24 port 56716 -> 10.10.101.96 port 56383 codec: 0 ms: 20
153. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.410078 [DEBUG] switch_rtp.c:4108 Starting timer [soft] 160 bytes per 20ms
154. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.430079 [DEBUG] switch_core_media.c:7205 sofia/external/1234 Set rtp dtmf delay to 40
155. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.430079 [NOTICE] sofia.c:8182 Channel [sofia/external/1234] has been answered
156. 2017-11-08 13:32:36.430079 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/external/1234]
157. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.450080 [INFO] switch_channel.c:3129 sofia/external/1234 Flipping CID from "" <0000000000> to "Outbound Call" <1234>
158. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.450080 [DEBUG] mod_commands.c:4845 (sofia/external/1234) State Change CS_CONSUME_MEDIA -> CS_EXECUTE
159. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.450080 [DEBUG] switch_channel.c:3773 (sofia/external/1234) Callstate Change RINGING -> ACTIVE
160. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.470082 [DEBUG] switch_core_state_machine.c:584 (sofia/external/1234) Running State Change CS_EXECUTE (Cur 1 Tot 2)
161. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.470082 [DEBUG] switch_core_state_machine.c:650 (sofia/external/1234) State EXECUTE
162. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.470082 [DEBUG] mod_sofia.c:198 sofia/external/1234 SOFIA EXECUTE
163. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.470082 [DEBUG] switch_core_state_machine.c:328 sofia/external/1234 Standard EXECUTE
164. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 EXECUTE sofia/external/1234 txfax(c:/streem/documents/test1.tif)
165. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.470082 [DEBUG] mod_spandsp_fax.c:1503 Raw read codec activation Success L16 20000
166. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.470082 [DEBUG] switch_core_codec.c:223 sofia/external/1234 Push codec L16:100
167. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.470082 [DEBUG] mod_spandsp_fax.c:1521 Raw write codec activation Success L16
168. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.530085 [DEBUG] switch_rtp.c:7254 Correct audio ip/port confirmed.
169. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:36.750098 [DEBUG] mod_spandsp_fax.c:277 FLOW T.30 HDLC signal status is Carrier up (-2) in state T
170. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:38.510198 [DEBUG] mod_spandsp_fax.c:277 FLOW T.30 HDLC signal status is Carrier down (-1) in state T
171. recv 796 bytes from udp/[10.10.101.96]:5060 at 19:32:39.464253:
172.    ------------------------------------------------------------------------
173.    INVITE sip:gw+10.10.101.96@10.10.30.24:5080;transport=udp;gw=10.10.101.96 SIP/2.0
174.    From: <sip:1234@10.10.101.96>;tag=8e901d0-0-13c4-55013-1d0029-25e5412a-1d0029
175.    To: ""<sip:1000@10.10.101.96>;tag=6v0DjeHDX2pcD
176.    Call-ID: 6a6ec2d6-3f5e-1236-2795-8d8a610f888f
177.    CSeq: 1 INVITE
178.    Via: SIP/2.0/UDP 10.10.101.96:5060;branch=z9hG4bK-1d002c-7148ad8c-34bef768
179.    Supported: 100rel
180.    Max-Forwards: 70
181.    User-Agent: Brktsip/6.3.2B8 (Dialogic)
182.    Contact: <sip:10.10.101.96>
183.    Content-Type: application/sdp
184.    Content-Length: 286
185.    
186.    v=0
187.    o=- 2210889541 0934851000 IN IP4 10.10.101.96
188.    s=no_session_name
189.    t=0 0
190.    m=image 56383 udptl t38
191.    c=IN IP4 10.10.101.96
192.    a=T38FaxVersion:0
193.    a=T38MaxBitRate:14400
194.    a=T38FaxRateManagement:transferredTCF
195.    a=T38FaxMaxBuffer:200
196.    a=T38FaxMaxDatagram:72
197.    a=T38FaxUdpEC:t38UDPRedundancy
198.    ------------------------------------------------------------------------
199. send 387 bytes to udp/[10.10.101.96]:5060 at 19:32:39.466253:
200.    ------------------------------------------------------------------------
201.    SIP/2.0 100 Trying
202.    Via: SIP/2.0/UDP 10.10.101.96:5060;branch=z9hG4bK-1d002c-7148ad8c-34bef768
203.    From: <sip:1234@10.10.101.96>;tag=8e901d0-0-13c4-55013-1d0029-25e5412a-1d0029
204.    To: ""<sip:1000@10.10.101.96>;tag=6v0DjeHDX2pcD
205.    Call-ID: 6a6ec2d6-3f5e-1236-2795-8d8a610f888f
206.    CSeq: 1 INVITE
207.    User-Agent: FreeSWITCH-mod_sofia/1.6.18+git~20170706T183344Z~628f1bd44c~64bit
208.    Content-Length: 0
209.    
210.    ------------------------------------------------------------------------
211. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:39.470253 [DEBUG] sofia.c:7048 Channel sofia/external/1234 entering state [received][100]
212. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:39.470253 [DEBUG] sofia.c:7058 Remote SDP:
213. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 v=0
214.  
215. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 o=- 2210889541 934851000 IN IP4 10.10.101.96
216.  
217. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 s=no_session_name
218.  
219. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 t=0 0
220.  
221. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 m=image 56383 udptl t38
222.  
223. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 c=IN IP4 10.10.101.96
224.  
225. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 a=T38FaxVersion:0
226.  
227. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 a=T38MaxBitRate:14400
228.  
229. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 a=T38FaxRateManagement:transferredTCF
230.  
231. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 a=T38FaxMaxBuffer:200
232.  
233. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 a=T38FaxMaxDatagram:72
234.  
235. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 a=T38FaxUdpEC:t38UDPRedundancy
236.  
237. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938
238. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:39.490254 [DEBUG] switch_core_media.c:4037 sofia/external/1234 T38 ACCEPT on request
239. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:39.490254 [DEBUG] switch_core_media.c:4142 sofia/external/1234 T38 IS POSSIBLE on request
240. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:39.490254 [DEBUG] mod_spandsp_fax.c:1072 T38 SDP Origin = -
241. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:39.490254 [DEBUG] mod_spandsp_fax.c:1073 T38FaxVersion = 0
242. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:39.490254 [DEBUG] mod_spandsp_fax.c:1074 T38MaxBitRate = 14400
243. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:39.490254 [DEBUG] mod_spandsp_fax.c:1075 T38FaxFillBitRemoval = 0
244. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:39.490254 [DEBUG] mod_spandsp_fax.c:1076 T38FaxTranscodingMMR = 0
245. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:39.490254 [DEBUG] mod_spandsp_fax.c:1077 T38FaxTranscodingJBIG = 0
246. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:39.490254 [DEBUG] mod_spandsp_fax.c:1078 T38FaxRateManagement = transferredTCF
247. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:39.490254 [DEBUG] mod_spandsp_fax.c:1079 T38FaxMaxBuffer = 200
248. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:39.490254 [DEBUG] mod_spandsp_fax.c:1080 T38FaxMaxDatagram = 72
249. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:39.490254 [DEBUG] mod_spandsp_fax.c:1081 T38FaxUdpEC = t38UDPRedundancy
250. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:39.490254 [DEBUG] mod_spandsp_fax.c:1082 T38VendorInfo =
251. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:39.490254 [DEBUG] mod_spandsp_fax.c:1083 ip = 10.10.101.96
252. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:39.490254 [DEBUG] mod_spandsp_fax.c:1085 port = 56383
253. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:32:39.490254 [DEBUG] switch_core_media.c:9746 Remote address:port [10.10.101.96:56383] has not changed.
254. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.489686 [DEBUG] mod_spandsp_fax.c:277 FLOW T.30 T0 expired in state T
255. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.489686 [DEBUG] mod_spandsp_fax.c:277 FLOW T.30 Status changing to Timed out waiting for initial communication
256. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.489686 [DEBUG] mod_spandsp_fax.c:524 ==============================================================================
257. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.489686 [DEBUG] mod_spandsp_fax.c:536 Fax processing not successful - result (2) Timed out waiting for initial communication.
258. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.489686 [DEBUG] mod_spandsp_fax.c:541 Remote station id:
259. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.489686 [DEBUG] mod_spandsp_fax.c:542 Local station id:  xStreem
260. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.489686 [DEBUG] mod_spandsp_fax.c:543 Pages transferred: 0
261. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.489686 [DEBUG] mod_spandsp_fax.c:545 Total fax pages:   0
262. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.489686 [DEBUG] mod_spandsp_fax.c:546 Image resolution:  0x0
263. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.489686 [DEBUG] mod_spandsp_fax.c:547 Transfer Rate:     14400
264. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.489686 [DEBUG] mod_spandsp_fax.c:549 ECM status         off
265. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.489686 [DEBUG] mod_spandsp_fax.c:550 remote country:
266. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.489686 [DEBUG] mod_spandsp_fax.c:551 remote vendor:
267. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.489686 [DEBUG] mod_spandsp_fax.c:552 remote model:
268. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.489686 [DEBUG] mod_spandsp_fax.c:554 ==============================================================================
269. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.509687 [DEBUG] mod_spandsp_fax.c:277 FLOW T.30 Changing from state T to CALL_FINISHED
270. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.509687 [DEBUG] mod_spandsp_fax.c:277 FLOW T.30 Changing from phase A_CNG to CALL_FINISHED
271. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.509687 [DEBUG] mod_spandsp_fax.c:277 FLOW T.38T Set rx type 9
272. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.509687 [DEBUG] mod_spandsp_fax.c:277 FLOW T.38T Set tx type 9
273. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.509687 [DEBUG] mod_spandsp_fax.c:277 FLOW T.38T FAX exchange complete
274. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.509687 [DEBUG] mod_spandsp_fax.c:277 FLOW T.30 Call completed
275. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.509687 [DEBUG] switch_core_codec.c:248 sofia/external/1234 Restore previous codec PCMU:0.
276. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.509687 [NOTICE] switch_core_state_machine.c:385 sofia/external/1234 has executed the last dialplan instruction, hanging up.
277. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.509687 [NOTICE] switch_core_state_machine.c:387 Hangup sofia/external/1234 [CS_EXECUTE] [NORMAL_CLEARING]
278. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.529688 [DEBUG] switch_core_state_machine.c:650 (sofia/external/1234) State EXECUTE going to sleep
279. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.529688 [DEBUG] switch_core_state_machine.c:584 (sofia/external/1234) Running State Change CS_HANGUP (Cur 1 Tot 2)
280. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.529688 [DEBUG] switch_core_state_machine.c:850 (sofia/external/1234) Callstate Change ACTIVE -> HANGUP
281. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.529688 [DEBUG] switch_core_state_machine.c:852 (sofia/external/1234) State HANGUP
282. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.529688 [DEBUG] mod_sofia.c:438 Channel sofia/external/1234 hanging up, cause: NORMAL_CLEARING
283. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.529688 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/external/1234
284. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.529688 [DEBUG] switch_core_state_machine.c:60 sofia/external/1234 Standard HANGUP, cause: NORMAL_CLEARING
285. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.529688 [DEBUG] switch_core_state_machine.c:852 (sofia/external/1234) State HANGUP going to sleep
286. send 585 bytes to udp/[10.10.101.96]:5060 at 19:33:39.534689:
287.    ------------------------------------------------------------------------
288. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.529688 [DEBUG] switch_core_state_machine.c:619 (sofia/external/1234) State Change CS_HANGUP -> CS_REPORTING
289.    BYE sip:10.10.101.96 SIP/2.0
290.    Via: SIP/2.0/UDP 10.10.30.24:5080;rport;branch=z9hG4bKa905059p6tBDS1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.529688 [DEBUG] switch_core_state_machine.c:584 (sofia/external/1234) Running State Change CS_REPORTING (Cur 1 Tot 2)
291.  
292.    Max-Forwards: 70
293.    From: "" <sip:1000@10.10.101.96>;tag=6v0DjeHDX2pcD
294.    To: <sip:1234@10.10.101.96>;tag=8e901d0-0-13c4-55013-1d0029-25e5412a-1d0029
295.    Call-ID: 6a6ec2d6-3f5e-1236-2795-8d8a610f888f
296.    CSeq: 114748715 BYE
297.    User-Agent: FreeSWITCH-mod_sofia/1.6.18+git~20170706T183344Z~628f1bd44c~64bit
298.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
299.    Supported: timer, path, replaces
300.    Reason: Q.850;cause=16;text="NORMAL_CLEARING"
301.    Content-Length: 0
302.    
303.    ------------------------------------------------------------------------
304. send 539 bytes to udp/[10.10.101.96]:5060 at 19:33:39.535689:
305.    ------------------------------------------------------------------------
306.    SIP/2.0 486 Busy Here
307.    Via: SIP/2.0/UDP 10.10.101.96:5060;branch=z9hG4bK-1d002c-7148ad8c-34bef768
308.    From: <sip:1234@10.10.101.96>;tag=8e901d0-0-13c4-55013-1d0029-25e5412a-1d0029
309.    To: ""<sip:1000@10.10.101.96>;tag=6v0DjeHDX2pcD
310.    Call-ID: 6a6ec2d6-3f5e-1236-2795-8d8a610f888f
311.    CSeq: 1 INVITE
312.    User-Agent: FreeSWITCH-mod_sofia/1.6.18+git~20170706T183344Z~628f1bd44c~64bit
313.    Accept: application/sdp
314.    Allow: INVITE, ACK, BYE, CANCEL, OPTIONS, MESSAGE, INFO, UPDATE, REGISTER, REFER, NOTIFY
315.    Supported: timer, path, replaces
316.    Content-Length: 0
317. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.529688 [DEBUG] switch_core_state_machine.c:938 (sofia/external/1234) State REPORTING
318.    
319.    ------------------------------------------------------------------------
320. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.529688 [DEBUG] switch_core_state_machine.c:174 sofia/external/1234 Standard REPORTING, cause: NORMAL_CLEARING
321. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.529688 [DEBUG] switch_core_state_machine.c:938 (sofia/external/1234) State REPORTING going to sleep
322. recv 366 bytes from udp/[10.10.101.96]:5060 at 19:33:39.536689:
323.    ------------------------------------------------------------------------
324.    SIP/2.0 200 OK
325.    From: ""<sip:1000@10.10.101.96>;tag=6v0DjeHDX2pcD
326.    To: <sip:1234@10.10.101.96>;tag=8e901d0-0-13c4-55013-1d0029-25e5412a-1d0029
327.    Call-ID: 6a6ec2d6-3f5e-1236-2795-8d8a610f888f
328.    CSeq: 114748715 BYE
329.    Via: SIP/2.0/UDP 10.10.30.24:5080;rport=5080;branch=z9hG4bKa905059p6tBDS
330.    Supported: 100rel
331.    User-Agent: Brktsip/6.3.2B8 (Dialogic)
332.    Content-Length: 0
333.    
334.    ------------------------------------------------------------------------
335. recv 383 bytes from udp/[10.10.101.96]:5060 at 19:33:39.538689:
336.    ------------------------------------------------------------------------
337.    ACK sip:gw+10.10.101.96@10.10.30.24:5080;transport=udp;gw=10.10.101.96 SIP/2.0
338.    From: <sip:1234@10.10.101.96>;tag=8e901d0-0-13c4-55013-1d0029-25e5412a-1d0029
339.    To: ""<sip:1000@10.10.101.96>;tag=6v0DjeHDX2pcD
340.    Call-ID: 6a6ec2d6-3f5e-1236-2795-8d8a610f888f
341.    CSeq: 1 ACK
342.    Via: SIP/2.0/UDP 10.10.101.96:5060;branch=z9hG4bK-1d002c-7148ad8c-34bef768
343.    Max-Forwards: 70
344.    Content-Length: 0
345.    
346.    ------------------------------------------------------------------------
347. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.529688 [DEBUG] switch_core_state_machine.c:610 (sofia/external/1234) State Change CS_REPORTING -> CS_DESTROY
348. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.529688 [DEBUG] switch_core_session.c:1664 Session 2 (sofia/external/1234) Locked, Waiting on external entities
349. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.529688 [NOTICE] switch_core_session.c:1682 Session 2 (sofia/external/1234) Ended
350. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.529688 [NOTICE] switch_core_session.c:1686 Close Channel sofia/external/1234 [CS_DESTROY]
351. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.529688 [DEBUG] switch_core_state_machine.c:741 (sofia/external/1234) Running State Change CS_DESTROY (Cur 0 Tot 2)
352. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.529688 [DEBUG] switch_core_state_machine.c:751 (sofia/external/1234) State DESTROY
353. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.529688 [DEBUG] mod_sofia.c:343 sofia/external/1234 SOFIA DESTROY
354. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.529688 [DEBUG] switch_core_state_machine.c:181 sofia/external/1234 Standard DESTROY
355. 1cd39dcf-bccc-4a25-9f9d-9b75edb4c938 2017-11-08 13:33:39.529688 [DEBUG] switch_core_state_machine.c:751 (sofia/external/1234) State DESTROY going to sleep