<div><div>Hello</div><div><br></div><div>I am trying to send a traditional fax through the voip gateway to Freeswitch, but it fails a lot</div><div><font class="Apple-style-span" face="arial, sans-serif" size="6"><span class="Apple-style-span" style="font-size: 24px;"><span class="Apple-style-span" style="font-size: small;"><br>
</span></span></font></div><div><font class="Apple-style-span" face="arial, sans-serif">the error message is : </font>Fax processing not successful - result (48) Disconnected after permitted retries.</div><div><font class="Apple-style-span" face="arial, sans-serif" size="6"><span class="Apple-style-span" style="font-size: 24px;"><span class="Apple-style-span" style="font-size: small;"><br>
</span></span></font></div><div><font class="Apple-style-span" face="arial, sans-serif" size="6"><span class="Apple-style-span" style="font-size: 24px;"><span class="Apple-style-span" style="font-size: small;">the route looks like this: </span></span></font></div>
<div><br></div><div>HP officejet 5610 all-in-one ---------RJ11--------- Wellgate 2644 ---------RJ45--------- FreeSwitch Server </div><div><br></div><div><div>system: Windows XP</div><div>FreeSWITCH: Version 1.0.head (git-)</div>
</div><div><br></div><div>i donno what's the problem it is .</div><div>i have tried many solutions. like disable-v17 set to true, it increase the successful rate but still not good.</div><div>thank you~</div><div><br>
</div><div><br></div><div><br></div><div><br></div><div>here is the log:</div><div><br></div><div>2010-09-08 10:52:36.710250 [NOTICE] switch_channel.c:779 New Channel sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> [5585d019-0b99-43c2-9202-6901f83fb348]</div>
<div>2010-09-08 10:52:36.725875 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a>) Running State Change CS_NEW</div><div>2010-09-08 10:52:36.725875 [DEBUG] switch_core_state_machine.c:320 (sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a>) State NEW</div>
<div>2010-09-08 10:52:36.819625 [DEBUG] sofia.c:4318 Channel sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> entering state [received][100]</div><div>2010-09-08 10:52:36.819625 [DEBUG] sofia.c:4329 Remote SDP:</div>
<div>v=0</div><div>o=2dsp_4fxs_4fxo_108 16394 0 IN IP4 192.168.1.24</div><div>s=SIP CALL</div><div>i=Audio Session</div><div>c=IN IP4 192.168.1.24</div><div>t=0 0</div><div>m=audio 16394 RTP/AVP 18 4 8 0 2 96</div><div>a=rtpmap:18 G729/8000/1</div>
<div>a=rtpmap:4 G723/8000/1</div><div>a=rtpmap:8 PCMA/8000/1</div><div>a=rtpmap:0 PCMU/8000/1</div><div>a=rtpmap:2 G726-32/8000/1</div><div>a=rtpmap:96 telephone-event/8000</div><div>a=fmtp:96 0-15</div><div><br></div><div>
2010-09-08 10:52:36.819625 [DEBUG] sofia_glue.c:3845 Audio Codec Compare [G729:18:8000:20]/[G7221:115:32000:20]</div><div>2010-09-08 10:52:36.819625 [DEBUG] sofia_glue.c:3845 Audio Codec Compare [G729:18:8000:20]/[G7221:107:16000:20]</div>
<div>2010-09-08 10:52:36.819625 [DEBUG] sofia_glue.c:3845 Audio Codec Compare [G729:18:8000:20]/[G722:9:8000:20]</div><div>2010-09-08 10:52:36.819625 [DEBUG] sofia_glue.c:3845 Audio Codec Compare [G729:18:8000:20]/[PCMU:0:8000:20]</div>
<div>2010-09-08 10:52:36.819625 [DEBUG] sofia_glue.c:3845 Audio Codec Compare [G729:18:8000:20]/[PCMA:8:8000:20]</div><div>2010-09-08 10:52:36.819625 [DEBUG] sofia_glue.c:3845 Audio Codec Compare [G729:18:8000:20]/[GSM:3:8000:20]</div>
<div>2010-09-08 10:52:36.819625 [DEBUG] sofia_glue.c:3845 Audio Codec Compare [G723:4:8000:20]/[G7221:115:32000:20]</div><div>2010-09-08 10:52:36.819625 [DEBUG] sofia_glue.c:3845 Audio Codec Compare [G723:4:8000:20]/[G7221:107:16000:20]</div>
<div>2010-09-08 10:52:36.819625 [DEBUG] sofia_glue.c:3845 Audio Codec Compare [G723:4:8000:20]/[G722:9:8000:20]</div><div>2010-09-08 10:52:36.835250 [DEBUG] sofia_glue.c:3845 Audio Codec Compare [G723:4:8000:20]/[PCMU:0:8000:20]</div>
<div>2010-09-08 10:52:36.835250 [DEBUG] sofia_glue.c:3845 Audio Codec Compare [G723:4:8000:20]/[PCMA:8:8000:20]</div><div>2010-09-08 10:52:36.835250 [DEBUG] sofia_glue.c:3845 Audio Codec Compare [G723:4:8000:20]/[GSM:3:8000:20]</div>
<div>2010-09-08 10:52:36.835250 [DEBUG] sofia_glue.c:3845 Audio Codec Compare [PCMA:8:8000:20]/[G7221:115:32000:20]</div><div>2010-09-08 10:52:36.835250 [DEBUG] sofia_glue.c:3845 Audio Codec Compare [PCMA:8:8000:20]/[G7221:107:16000:20]</div>
<div>2010-09-08 10:52:36.835250 [DEBUG] sofia_glue.c:3845 Audio Codec Compare [PCMA:8:8000:20]/[G722:9:8000:20]</div><div>2010-09-08 10:52:36.835250 [DEBUG] sofia_glue.c:3845 Audio Codec Compare [PCMA:8:8000:20]/[PCMU:0:8000:20]</div>
<div>2010-09-08 10:52:36.835250 [DEBUG] sofia_glue.c:3845 Audio Codec Compare [PCMA:8:8000:20]/[PCMA:8:8000:20]</div><div>2010-09-08 10:52:36.835250 [DEBUG] sofia_glue.c:2442 Set Codec sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> PCMA/8000 20 ms 160 samples</div>
<div>2010-09-08 10:52:36.835250 [DEBUG] sofia_glue.c:3941 Set 2833 dtmf send/recv payload to 96</div><div>2010-09-08 10:52:36.835250 [DEBUG] sofia.c:4476 (sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a>) State Change CS_NEW -> CS_INIT</div>
<div>2010-09-08 10:52:36.835250 [DEBUG] switch_core_session.c:1039 Send signal sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> [BREAK]</div><div>2010-09-08 10:52:36.835250 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a>) Running State Change CS_INIT</div>
<div>2010-09-08 10:52:36.835250 [DEBUG] switch_core_state_machine.c:338 (sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a>) State INIT</div><div>2010-09-08 10:52:36.835250 [DEBUG] mod_sofia.c:83 sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> SOFIA INIT</div>
<div>2010-09-08 10:52:36.835250 [DEBUG] mod_sofia.c:119 (sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a>) State Change CS_INIT -> CS_ROUTING</div><div>2010-09-08 10:52:36.835250 [DEBUG] switch_core_session.c:1039 Send signal sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> [BREAK]</div>
<div>2010-09-08 10:52:36.835250 [DEBUG] switch_core_state_machine.c:338 (sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a>) State INIT going to sleep</div><div>2010-09-08 10:52:36.835250 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a>) Running State Change CS_ROUTING</div>
<div>2010-09-08 10:52:36.835250 [DEBUG] switch_channel.c:1512 (sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a>) Callstate Change DOWN -> RINGING</div><div>2010-09-08 10:52:36.835250 [DEBUG] switch_core_state_machine.c:341 (sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a>) State ROUTING</div>
<div>2010-09-08 10:52:36.835250 [DEBUG] mod_sofia.c:142 sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> SOFIA ROUTING</div><div>2010-09-08 10:52:36.835250 [DEBUG] switch_core_state_machine.c:77 sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> Standard ROUTING</div>
<div>2010-09-08 10:52:36.835250 [INFO] mod_dialplan_xml.c:331 Processing test123_inner_fax->11111111 in context default</div><div>Dialplan: sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> parsing [default->Local_Extension] continue=false</div>
<div>Dialplan: sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> Regex (FAIL) [Local_Extension] destination_number(11111111) =~ /^([0-9][0-9][0-9][0-9])$/ break=on-false</div>
<div>Dialplan: sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> parsing [default->czc] continue=false</div><div>Dialplan: sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> Regex (FAIL) [czc] destination_number(11111111) =~ /^99999$/ break=on-false</div>
<div>Dialplan: sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> parsing [default->voip_inner_fax] continue=false</div><div>Dialplan: sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> Regex (FAIL) [voip_inner_fax] caller_id_number(test123_inner_fax) =~ /^voip_inner_fax$/ break=on-false</div>
<div>Dialplan: sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> parsing [default->2296_fax_in] continue=false</div><div>Dialplan: sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> Regex (PASS) [2296_fax_in] caller_id_number(test123_inner_fax) =~ /^test123_inner_fax$/ break=on-false</div>
<div>Dialplan: sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> Action answer() </div><div>Dialplan: sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> Action lua(FaxOut_PSTN.lua ${destination_number} test123_inner_fax) </div>
<div>2010-09-08 10:52:36.835250 [DEBUG] switch_core_state_machine.c:119 (sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a>) State Change CS_ROUTING -> CS_EXECUTE</div>
<div>2010-09-08 10:52:36.835250 [DEBUG] switch_core_session.c:1039 Send signal sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> [BREAK]</div><div>2010-09-08 10:52:36.835250 [DEBUG] switch_core_state_machine.c:341 (sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a>) State ROUTING going to sleep</div>
<div>2010-09-08 10:52:36.835250 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a>) Running State Change CS_EXECUTE</div><div>
2010-09-08 10:52:36.850875 [DEBUG] switch_core_state_machine.c:348 (sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a>) State EXECUTE</div><div>2010-09-08 10:52:36.850875 [DEBUG] mod_sofia.c:235 sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> SOFIA EXECUTE</div>
<div>2010-09-08 10:52:36.850875 [DEBUG] switch_core_state_machine.c:157 sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> Standard EXECUTE</div><div>EXECUTE sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> answer()</div>
<div>2010-09-08 10:52:36.944625 [DEBUG] sofia_glue.c:2682 AUDIO RTP [sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a>] 192.168.1.23 port 30974 -> 192.168.1.24 port 16394 codec: 8 ms: 20</div>
<div>2010-09-08 10:52:36.944625 [DEBUG] switch_rtp.c:1413 Starting timer [soft] 160 bytes per 20ms</div><div>2010-09-08 10:52:36.944625 [DEBUG] sofia_glue.c:2892 Set 2833 dtmf send payload to 96</div><div>2010-09-08 10:52:36.944625 [DEBUG] sofia_glue.c:2897 Set 2833 dtmf receive payload to 96</div>
<div>2010-09-08 10:52:36.944625 [DEBUG] mod_sofia.c:677 Local SDP sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a>:</div><div>v=0</div><div>o=FreeSWITCH 1283883382 1283883383 IN IP4 192.168.1.23</div>
<div>s=FreeSWITCH</div><div>c=IN IP4 192.168.1.23</div><div>t=0 0</div><div>m=audio 30974 RTP/AVP 8 96</div><div>a=rtpmap:8 PCMA/8000</div><div>a=rtpmap:96 telephone-event/8000</div><div>a=fmtp:96 0-16</div><div>a=silenceSupp:off - - - -</div>
<div>a=ptime:20</div><div>a=sendrecv</div><div><br></div><div>2010-09-08 10:52:36.944625 [DEBUG] switch_core_session.c:658 Send signal sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> [BREAK]</div>
<div>2010-09-08 10:52:36.944625 [DEBUG] switch_channel.c:2542 (sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a>) Callstate Change RINGING -> ACTIVE</div><div>2010-09-08 10:52:36.944625 [NOTICE] mod_dptools.c:746 Channel [sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a>] has been answered</div>
<div>EXECUTE sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> lua(FaxOut_PSTN.lua 11111111 test123_inner_fax)</div><div>2010-09-08 10:52:37.116500 [NOTICE] mod_commands.c:4080 Executing command: extra\log\Log_2.exe 5585d019-0b99-43c2-9202-6901f83fb348 test123_inner_fax fax to 11111111</div>
<div>2010-09-08 10:52:37.491500 [DEBUG] sofia.c:4318 Channel sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> entering state [completed][200]</div><div>2010-09-08 10:52:37.491500 [DEBUG] sofia.c:4318 Channel sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> entering state [ready][200]</div>
<div>2010-09-08 10:52:37.585250 [ALERT] switch_cpp.cpp:1177 FaxOUT_PSTN: fax from test123_inner_fax to 11111111</div><div>2010-09-08 10:52:37.585250 [ALERT] switch_cpp.cpp:1177 Fax_Receive: Fax Recieving</div><div>EXECUTE sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> playback(silence_stream://2000)</div>
<div>2010-09-08 10:52:37.616500 [DEBUG] switch_ivr_play_say.c:1161 Codec Activated L16@8000hz 1 channels 20ms</div><div>2010-09-08 10:52:37.679000 [DEBUG] switch_rtp.c:2519 Correct ip/port confirmed.</div><div>2010-09-08 10:52:39.632125 [DEBUG] switch_ivr_play_say.c:1468 done playing file</div>
<div>EXECUTE sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> system(mkdir tmp\fax\5585d019-0b99-43c2-9202-6901f83fb348)</div><div>2010-09-08 10:52:39.632125 [NOTICE] mod_dptools.c:1332 Executing command: mkdir tmp\fax\5585d019-0b99-43c2-9202-6901f83fb348</div>
<div>EXECUTE sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> set(fax_v17_disabled=true)</div><div>2010-09-08 10:52:39.679000 [DEBUG] mod_dptools.c:854 sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> SET [fax_v17_disabled]=[true]</div>
<div>EXECUTE sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> rxfax(tmp/fax/5585d019-0b99-43c2-9202-6901f83fb348/FAX-5585d019-0b99-43c2-9202-6901f83fb348.tif)</div>
<div>2010-09-08 10:52:39.679000 [DEBUG] mod_spandsp_fax.c:1077 Raw read codec activation Success L16 20000</div><div>2010-09-08 10:52:39.679000 [DEBUG] switch_core_codec.c:116 sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> Push codec L16:10</div>
<div>2010-09-08 10:52:39.679000 [DEBUG] mod_spandsp_fax.c:1093 Raw write codec activation Success L16</div><div>2010-09-08 10:52:40.710250 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 HDLC signal status is Carrier up (-2) in state 1</div>
<div>2010-09-08 10:52:42.741500 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Send complete in phase T30_PHASE_A_CED, state 1</div><div>2010-09-08 10:52:42.741500 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Starting answer mode</div>
<div>2010-09-08 10:52:42.741500 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Changing from phase T30_PHASE_A_CED to T30_PHASE_B_TX</div><div>2010-09-08 10:52:42.741500 [DEBUG] mod_spandsp_fax.c:270 FLOW FAX Set rx type 0</div>
<div>2010-09-08 10:52:42.741500 [DEBUG] mod_spandsp_fax.c:270 FLOW FAX Set tx type 4</div><div>2010-09-08 10:52:42.741500 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Start T2</div><div>2010-09-08 10:52:42.741500 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Changing from state 1 to 17</div>
<div>2010-09-08 10:52:42.741500 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Sending ident 'TEST123 VOIP'</div><div>2010-09-08 10:52:42.741500 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Tx: CSI without final frame tag</div>
<div>2010-09-08 10:52:42.741500 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Tx: ff 03 40 50 49 4f 56 20 4f 55 55 4e 20 20 20 20 20 20 20 20 20 20 20</div><div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Send complete in phase T30_PHASE_B_TX, state 17</div>
<div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 DIS:</div><div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ...0= Store and forward Internet fax (T.37): Not set</div><div>
2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... .0..= Real-time Internet fax (T.38): Not set</div><div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... 0...= 3G mobile network: Not set</div>
<div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 ..0. ....= V.8 capabilities: Not set</div><div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .0.. ....= Preferred octets: 256 octets</div>
<div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ...0= Ready to transmit a fax document (polling): Not set</div><div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ..1.= Can receive fax: Set</div>
<div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 ..00 11..= Supported data signalling rates: V.27 ter and V.29</div><div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .1.. ....= R8x7.7lines/mm and/or 200x200pels/25.4mm: Set</div>
<div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 1... ....= 2-D coding: Set</div><div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ..10= Recording width: 215mm +- 1%, 255mm +- 1% and 303mm +- 1%</div>
<div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... 10..= Recording length: Unlimited</div><div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .111 ....= Receiver's minimum scan line time: 0ms at 3.85 l/mm; T7.7 = T3.85</div>
<div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 1... ....= Extension indicator: Set</div><div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ..0.= Compressed/uncompressed mode: Compressed</div>
<div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... .1..= Error correction mode (ECM): ECM</div><div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .1.. ....= T.6 coding: Set</div>
<div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 1... ....= Extension indicator: Set</div><div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ...0= "Field not valid" supported: Not set</div>
<div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ..0.= Multiple selective polling: Not set</div><div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... .0..= Polled sub-address: Not set</div>
<div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... 0...= T.43 coding: Not set</div><div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 ...0 ....= Plane interleave: Not set</div>
<div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 ..0. ....= Voice coding with 32kbit/s ADPCM (Rec. G.726): Not set</div><div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .0.. ....= Reserved for the use of extended voice coding set: Not set</div>
<div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 1... ....= Extension indicator: Set</div><div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ...1= R8x15.4lines/mm: Set</div>
<div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ..0.= 300x300pels/25.4mm: Not set</div><div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... .1..= R16x15.4lines/mm and/or 400x400pels/25.4mm: Set</div>
<div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... 0...= Inch-based resolution preferred: Not set</div><div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 ...1 ....= Metric-based resolution preferred: Set</div>
<div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 ..0. ....= Minimum scan line time for higher resolutions: T15.4 = T7.7</div><div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .0.. ....= Selective polling: Not set</div>
<div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 1... ....= Extension indicator: Set</div><div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ...0= Sub-addressing: Not set</div>
<div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ..0.= Password: Not set</div><div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... .0..= Ready to transmit a data file (polling): Not set</div>
<div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 ...0 ....= Binary file transfer (BFT): Not set</div><div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 ..0. ....= Document transfer mode (DTM): Not set</div>
<div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .0.. ....= Electronic data interchange (EDI): Not set</div><div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 1... ....= Extension indicator: Set</div>
<div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ...0= Basic transfer mode (BTM): Not set</div><div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... .0..= Ready to transfer a character or mixed mode document (polling): Not set</div>
<div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... 0...= Character mode: Not set</div><div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 ..0. ....= Mixed mode (Annex E/T.4): Not set</div>
<div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 1... ....= Extension indicator: Set</div><div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ...0= Processable mode 26 (Rec. T.505): Not set</div>
<div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ..0.= Digital network capability: Not set</div><div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... .0..= Duplex capability: Half only</div>
<div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... 0...= JPEG coding: Not set</div><div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 ...0 ....= Full colour mode: Not set</div>
<div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .0.. ....= 12bits/pel component: Not set</div><div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 1... ....= Extension indicator: Set</div>
<div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ...0= No subsampling (1:1:1): Not set</div><div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ..0.= Custom illuminant: Not set</div>
<div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... .0..= Custom gamut range: Not set</div><div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... 1...= North American Letter (215.9mm x 279.4mm): Set</div>
<div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 ...1 ....= North American Legal (215.9mm x 355.6mm): Set</div><div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 ..0. ....= Single-progression sequential coding (Rec. T.85) basic: Not set</div>
<div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .0.. ....= Single-progression sequential coding (Rec. T.85) optional L0: Not set</div><div>2010-09-08 10:52:44.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 0... ....= Extension indicator: Not set</div>
<div>2010-09-08 10:52:44.335250 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Tx: DIS with final frame tag</div><div>2010-09-08 10:52:44.335250 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Tx: ff 13 80 00 ce fa c4 80 95 80 80 80 18</div>
<div>2010-09-08 10:52:44.788375 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Send complete in phase T30_PHASE_B_TX, state 17</div><div>2010-09-08 10:52:44.866500 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Send complete in phase T30_PHASE_B_TX, state 17</div>
<div>2010-09-08 10:52:44.866500 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Changing from phase T30_PHASE_B_TX to T30_PHASE_B_RX</div><div>2010-09-08 10:52:44.866500 [DEBUG] mod_spandsp_fax.c:270 FLOW FAX Set rx type 4</div><div>
2010-09-08 10:52:44.866500 [DEBUG] mod_spandsp_fax.c:270 FLOW FAX Set tx type 0</div><div>2010-09-08 10:52:44.866500 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Start T4</div><div>2010-09-08 10:52:44.882125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 HDLC signal status is Carrier up (-2) in state 17</div>
<div>2010-09-08 10:52:44.944625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 HDLC signal status is Carrier down (-1) in state 17</div><div>2010-09-08 10:52:48.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 T4 expired in phase T30_PHASE_B_RX, state 17</div>
<div>2010-09-08 10:52:48.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Retry number 1</div><div>2010-09-08 10:52:48.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Changing from phase T30_PHASE_B_RX to T30_PHASE_B_TX</div>
<div>2010-09-08 10:52:48.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW FAX Set rx type 0</div><div>2010-09-08 10:52:48.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW FAX Set tx type 4</div><div>2010-09-08 10:52:48.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Sending ident 'TEST123 VOIP'</div>
<div>2010-09-08 10:52:48.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Tx: CSI without final frame tag</div><div>2010-09-08 10:52:48.319625 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Tx: ff 03 40 50 49 4f 56 20 4f 55 55 4e 20 20 20 20 20 20 20 20 20 20 20</div>
<div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Send complete in phase T30_PHASE_B_TX, state 17</div><div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 DIS:</div><div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ...0= Store and forward Internet fax (T.37): Not set</div>
<div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... .0..= Real-time Internet fax (T.38): Not set</div><div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... 0...= 3G mobile network: Not set</div>
<div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 ..0. ....= V.8 capabilities: Not set</div><div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .0.. ....= Preferred octets: 256 octets</div>
<div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ...0= Ready to transmit a fax document (polling): Not set</div><div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ..1.= Can receive fax: Set</div>
<div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 ..00 11..= Supported data signalling rates: V.27 ter and V.29</div><div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .1.. ....= R8x7.7lines/mm and/or 200x200pels/25.4mm: Set</div>
<div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 1... ....= 2-D coding: Set</div><div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ..10= Recording width: 215mm +- 1%, 255mm +- 1% and 303mm +- 1%</div>
<div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... 10..= Recording length: Unlimited</div><div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .111 ....= Receiver's minimum scan line time: 0ms at 3.85 l/mm; T7.7 = T3.85</div>
<div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 1... ....= Extension indicator: Set</div><div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ..0.= Compressed/uncompressed mode: Compressed</div>
<div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... .1..= Error correction mode (ECM): ECM</div><div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .1.. ....= T.6 coding: Set</div>
<div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 1... ....= Extension indicator: Set</div><div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ...0= "Field not valid" supported: Not set</div>
<div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ..0.= Multiple selective polling: Not set</div><div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... .0..= Polled sub-address: Not set</div>
<div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... 0...= T.43 coding: Not set</div><div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 ...0 ....= Plane interleave: Not set</div>
<div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 ..0. ....= Voice coding with 32kbit/s ADPCM (Rec. G.726): Not set</div><div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .0.. ....= Reserved for the use of extended voice coding set: Not set</div>
<div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 1... ....= Extension indicator: Set</div><div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ...1= R8x15.4lines/mm: Set</div>
<div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ..0.= 300x300pels/25.4mm: Not set</div><div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... .1..= R16x15.4lines/mm and/or 400x400pels/25.4mm: Set</div>
<div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... 0...= Inch-based resolution preferred: Not set</div><div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 ...1 ....= Metric-based resolution preferred: Set</div>
<div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 ..0. ....= Minimum scan line time for higher resolutions: T15.4 = T7.7</div><div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .0.. ....= Selective polling: Not set</div>
<div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 1... ....= Extension indicator: Set</div><div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ...0= Sub-addressing: Not set</div>
<div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ..0.= Password: Not set</div><div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... .0..= Ready to transmit a data file (polling): Not set</div>
<div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 ...0 ....= Binary file transfer (BFT): Not set</div><div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 ..0. ....= Document transfer mode (DTM): Not set</div>
<div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .0.. ....= Electronic data interchange (EDI): Not set</div><div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 1... ....= Extension indicator: Set</div>
<div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ...0= Basic transfer mode (BTM): Not set</div><div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... .0..= Ready to transfer a character or mixed mode document (polling): Not set</div>
<div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... 0...= Character mode: Not set</div><div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 ..0. ....= Mixed mode (Annex E/T.4): Not set</div>
<div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 1... ....= Extension indicator: Set</div><div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ...0= Processable mode 26 (Rec. T.505): Not set</div>
<div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ..0.= Digital network capability: Not set</div><div>2010-09-08 10:52:49.897750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... .0..= Duplex capability: Half only</div>
<div>2010-09-08 10:52:49.913375 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... 0...= JPEG coding: Not set</div><div>2010-09-08 10:52:49.913375 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 ...0 ....= Full colour mode: Not set</div>
<div>2010-09-08 10:52:49.913375 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .0.. ....= 12bits/pel component: Not set</div><div>2010-09-08 10:52:49.913375 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 1... ....= Extension indicator: Set</div>
<div>2010-09-08 10:52:49.913375 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ...0= No subsampling (1:1:1): Not set</div><div>2010-09-08 10:52:49.913375 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ..0.= Custom illuminant: Not set</div>
<div>2010-09-08 10:52:49.913375 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... .0..= Custom gamut range: Not set</div><div>2010-09-08 10:52:49.913375 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... 1...= North American Letter (215.9mm x 279.4mm): Set</div>
<div>2010-09-08 10:52:49.913375 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 ...1 ....= North American Legal (215.9mm x 355.6mm): Set</div><div>2010-09-08 10:52:49.913375 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 ..0. ....= Single-progression sequential coding (Rec. T.85) basic: Not set</div>
<div>2010-09-08 10:52:49.913375 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .0.. ....= Single-progression sequential coding (Rec. T.85) optional L0: Not set</div><div>2010-09-08 10:52:49.913375 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 0... ....= Extension indicator: Not set</div>
<div>2010-09-08 10:52:49.913375 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Tx: DIS with final frame tag</div><div>2010-09-08 10:52:49.913375 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Tx: ff 13 80 00 ce fa c4 80 95 80 80 80 18</div>
<div>2010-09-08 10:52:50.382125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Send complete in phase T30_PHASE_B_TX, state 17</div><div>2010-09-08 10:52:50.460250 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Send complete in phase T30_PHASE_B_TX, state 17</div>
<div>2010-09-08 10:52:50.460250 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Changing from phase T30_PHASE_B_TX to T30_PHASE_B_RX</div><div>2010-09-08 10:52:50.460250 [DEBUG] mod_spandsp_fax.c:270 FLOW FAX Set rx type 4</div><div>
2010-09-08 10:52:50.460250 [DEBUG] mod_spandsp_fax.c:270 FLOW FAX Set tx type 0</div><div>2010-09-08 10:52:50.460250 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Start T4</div><div>2010-09-08 10:52:50.491500 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 HDLC signal status is Carrier up (-2) in state 17</div>
<div>2010-09-08 10:52:50.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 HDLC signal status is Carrier down (-1) in state 17</div><div>2010-09-08 10:52:50.538375 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 HDLC signal status is Carrier up (-2) in state 17</div>
<div>2010-09-08 10:52:50.538375 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 HDLC signal status is Carrier down (-1) in state 17</div><div>2010-09-08 10:52:53.929000 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 T4 expired in phase T30_PHASE_B_RX, state 17</div>
<div>2010-09-08 10:52:53.929000 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Retry number 2</div><div>2010-09-08 10:52:53.929000 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Changing from phase T30_PHASE_B_RX to T30_PHASE_B_TX</div>
<div>2010-09-08 10:52:53.929000 [DEBUG] mod_spandsp_fax.c:270 FLOW FAX Set rx type 0</div><div>2010-09-08 10:52:53.929000 [DEBUG] mod_spandsp_fax.c:270 FLOW FAX Set tx type 4</div><div>2010-09-08 10:52:53.929000 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Sending ident 'TEST123 VOIP'</div>
<div>2010-09-08 10:52:53.929000 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Tx: CSI without final frame tag</div><div>2010-09-08 10:52:53.929000 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Tx: ff 03 40 50 49 4f 56 20 4f 55 55 4e 20 20 20 20 20 20 20 20 20 20 20</div>
<div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Send complete in phase T30_PHASE_B_TX, state 17</div><div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 DIS:</div><div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ...0= Store and forward Internet fax (T.37): Not set</div>
<div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... .0..= Real-time Internet fax (T.38): Not set</div><div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... 0...= 3G mobile network: Not set</div>
<div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 ..0. ....= V.8 capabilities: Not set</div><div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .0.. ....= Preferred octets: 256 octets</div>
<div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ...0= Ready to transmit a fax document (polling): Not set</div><div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ..1.= Can receive fax: Set</div>
<div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 ..00 11..= Supported data signalling rates: V.27 ter and V.29</div><div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .1.. ....= R8x7.7lines/mm and/or 200x200pels/25.4mm: Set</div>
<div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 1... ....= 2-D coding: Set</div><div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ..10= Recording width: 215mm +- 1%, 255mm +- 1% and 303mm +- 1%</div>
<div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... 10..= Recording length: Unlimited</div><div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .111 ....= Receiver's minimum scan line time: 0ms at 3.85 l/mm; T7.7 = T3.85</div>
<div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 1... ....= Extension indicator: Set</div><div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ..0.= Compressed/uncompressed mode: Compressed</div>
<div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... .1..= Error correction mode (ECM): ECM</div><div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .1.. ....= T.6 coding: Set</div>
<div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 1... ....= Extension indicator: Set</div><div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ...0= "Field not valid" supported: Not set</div>
<div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ..0.= Multiple selective polling: Not set</div><div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... .0..= Polled sub-address: Not set</div>
<div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... 0...= T.43 coding: Not set</div><div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 ...0 ....= Plane interleave: Not set</div>
<div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 ..0. ....= Voice coding with 32kbit/s ADPCM (Rec. G.726): Not set</div><div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .0.. ....= Reserved for the use of extended voice coding set: Not set</div>
<div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 1... ....= Extension indicator: Set</div><div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ...1= R8x15.4lines/mm: Set</div>
<div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ..0.= 300x300pels/25.4mm: Not set</div><div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... .1..= R16x15.4lines/mm and/or 400x400pels/25.4mm: Set</div>
<div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... 0...= Inch-based resolution preferred: Not set</div><div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 ...1 ....= Metric-based resolution preferred: Set</div>
<div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 ..0. ....= Minimum scan line time for higher resolutions: T15.4 = T7.7</div><div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .0.. ....= Selective polling: Not set</div>
<div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 1... ....= Extension indicator: Set</div><div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ...0= Sub-addressing: Not set</div>
<div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ..0.= Password: Not set</div><div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... .0..= Ready to transmit a data file (polling): Not set</div>
<div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 ...0 ....= Binary file transfer (BFT): Not set</div><div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 ..0. ....= Document transfer mode (DTM): Not set</div>
<div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .0.. ....= Electronic data interchange (EDI): Not set</div><div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 1... ....= Extension indicator: Set</div>
<div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ...0= Basic transfer mode (BTM): Not set</div><div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... .0..= Ready to transfer a character or mixed mode document (polling): Not set</div>
<div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... 0...= Character mode: Not set</div><div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 ..0. ....= Mixed mode (Annex E/T.4): Not set</div>
<div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 1... ....= Extension indicator: Set</div><div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ...0= Processable mode 26 (Rec. T.505): Not set</div>
<div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ..0.= Digital network capability: Not set</div><div>2010-09-08 10:52:55.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... .0..= Duplex capability: Half only</div>
<div>2010-09-08 10:52:55.522750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... 0...= JPEG coding: Not set</div><div>2010-09-08 10:52:55.522750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 ...0 ....= Full colour mode: Not set</div>
<div>2010-09-08 10:52:55.522750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .0.. ....= 12bits/pel component: Not set</div><div>2010-09-08 10:52:55.522750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 1... ....= Extension indicator: Set</div>
<div>2010-09-08 10:52:55.522750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ...0= No subsampling (1:1:1): Not set</div><div>2010-09-08 10:52:55.522750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... ..0.= Custom illuminant: Not set</div>
<div>2010-09-08 10:52:55.522750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... .0..= Custom gamut range: Not set</div><div>2010-09-08 10:52:55.522750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .... 1...= North American Letter (215.9mm x 279.4mm): Set</div>
<div>2010-09-08 10:52:55.522750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 ...1 ....= North American Legal (215.9mm x 355.6mm): Set</div><div>2010-09-08 10:52:55.522750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 ..0. ....= Single-progression sequential coding (Rec. T.85) basic: Not set</div>
<div>2010-09-08 10:52:55.522750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 .0.. ....= Single-progression sequential coding (Rec. T.85) optional L0: Not set</div><div>2010-09-08 10:52:55.522750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 0... ....= Extension indicator: Not set</div>
<div>2010-09-08 10:52:55.522750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Tx: DIS with final frame tag</div><div>2010-09-08 10:52:55.522750 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Tx: ff 13 80 00 ce fa c4 80 95 80 80 80 18</div>
<div>2010-09-08 10:52:55.960250 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Send complete in phase T30_PHASE_B_TX, state 17</div><div>2010-09-08 10:52:56.038375 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Send complete in phase T30_PHASE_B_TX, state 17</div>
<div>2010-09-08 10:52:56.038375 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Changing from phase T30_PHASE_B_TX to T30_PHASE_B_RX</div><div>2010-09-08 10:52:56.038375 [DEBUG] mod_spandsp_fax.c:270 FLOW FAX Set rx type 4</div><div>
2010-09-08 10:52:56.038375 [DEBUG] mod_spandsp_fax.c:270 FLOW FAX Set tx type 0</div><div>2010-09-08 10:52:56.038375 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Start T4</div><div>2010-09-08 10:52:56.085250 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 HDLC signal status is Carrier up (-2) in state 17</div>
<div>2010-09-08 10:52:56.085250 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 HDLC signal status is Carrier down (-1) in state 17</div><div>2010-09-08 10:52:56.132125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 HDLC signal status is Carrier up (-2) in state 17</div>
<div>2010-09-08 10:52:56.132125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 HDLC signal status is Carrier down (-1) in state 17</div><div>2010-09-08 10:52:59.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 T4 expired in phase T30_PHASE_B_RX, state 17</div>
<div>2010-09-08 10:52:59.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Too many retries. Giving up.</div><div>2010-09-08 10:52:59.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Changing from phase T30_PHASE_B_RX to T30_PHASE_D_TX</div>
<div>2010-09-08 10:52:59.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW FAX Set rx type 0</div><div>2010-09-08 10:52:59.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW FAX Set tx type 4</div><div>2010-09-08 10:52:59.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Changing from state 17 to 3</div>
<div>2010-09-08 10:52:59.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Tx: DCN with final frame tag</div><div>2010-09-08 10:52:59.507125 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Tx: ff 13 fa</div><div>2010-09-08 10:53:00.585250 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Send complete in phase T30_PHASE_D_TX, state 3</div>
<div>2010-09-08 10:53:00.663375 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Send complete in phase T30_PHASE_D_TX, state 3</div><div>2010-09-08 10:53:00.663375 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Disconnecting</div><div>2010-09-08 10:53:00.663375 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Changing from phase T30_PHASE_D_TX to T30_PHASE_E</div>
<div>2010-09-08 10:53:00.663375 [DEBUG] mod_spandsp_fax.c:270 FLOW FAX Set rx type 0</div><div>2010-09-08 10:53:00.663375 [DEBUG] mod_spandsp_fax.c:270 FLOW FAX Set tx type 1</div><div>2010-09-08 10:53:00.663375 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Changing from state 3 to 2</div>
<div>2010-09-08 10:53:01.663375 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Send complete in phase T30_PHASE_E, state 2</div><div>2010-09-08 10:53:01.663375 [DEBUG] mod_spandsp_fax.c:308 ==============================================================================</div>
<div>2010-09-08 10:53:01.663375 [DEBUG] mod_spandsp_fax.c:334 Fax processing not successful - result (48) Disconnected after permitted retries.</div><div>2010-09-08 10:53:01.663375 [DEBUG] mod_spandsp_fax.c:341 Remote station id: </div>
<div>2010-09-08 10:53:01.663375 [DEBUG] mod_spandsp_fax.c:342 Local station id: TEST123 VOIP</div><div>2010-09-08 10:53:01.663375 [DEBUG] mod_spandsp_fax.c:343 Pages transferred: 0</div><div>2010-09-08 10:53:01.663375 [DEBUG] mod_spandsp_fax.c:345 Total fax pages: 0</div>
<div>2010-09-08 10:53:01.663375 [DEBUG] mod_spandsp_fax.c:346 Image resolution: 0x0</div><div>2010-09-08 10:53:01.663375 [DEBUG] mod_spandsp_fax.c:347 Transfer Rate: 14400</div><div>2010-09-08 10:53:01.663375 [DEBUG] mod_spandsp_fax.c:349 ECM status off</div>
<div>2010-09-08 10:53:01.663375 [DEBUG] mod_spandsp_fax.c:350 remote country: </div><div>2010-09-08 10:53:01.663375 [DEBUG] mod_spandsp_fax.c:351 remote vendor: </div><div>2010-09-08 10:53:01.663375 [DEBUG] mod_spandsp_fax.c:352 remote model: </div>
<div>2010-09-08 10:53:01.663375 [DEBUG] mod_spandsp_fax.c:354 ==============================================================================</div><div>2010-09-08 10:53:01.663375 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Changing from state 2 to 32</div>
<div>2010-09-08 10:53:01.663375 [DEBUG] mod_spandsp_fax.c:270 FLOW T.30 Changing from phase T30_PHASE_E to T30_PHASE_CALL_FINISHED</div><div>2010-09-08 10:53:01.663375 [DEBUG] mod_spandsp_fax.c:270 FLOW FAX Set rx type 9</div>
<div>2010-09-08 10:53:01.663375 [DEBUG] mod_spandsp_fax.c:270 FLOW FAX FAX exchange complete</div><div>2010-09-08 10:53:01.663375 [DEBUG] mod_spandsp_fax.c:270 FLOW FAX Set tx type 9</div><div>2010-09-08 10:53:01.663375 [DEBUG] mod_spandsp_fax.c:270 FLOW FAX FAX exchange complete</div>
<div>2010-09-08 10:53:01.679000 [DEBUG] switch_core_codec.c:140 sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> Restore previous codec PCMA:8.</div><div>2010-09-08 10:53:01.679000 [NOTICE] mod_commands.c:4080 Executing command: extra\log\Log_2.exe 5585d019-0b99-43c2-9202-6901f83fb348 freeswitch receive from test123_inner_fax Disconnected after permitted retries</div>
<div>2010-09-08 10:53:01.772750 [DEBUG] switch_channel.c:2309 (sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a>) Callstate Change ACTIVE -> HANGUP</div><div>2010-09-08 10:53:01.772750 [NOTICE] sofia.c:481 Hangup sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> [CS_EXECUTE] [NORMAL_CLEARING]</div>
<div>2010-09-08 10:53:01.772750 [DEBUG] switch_channel.c:2325 Send signal sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> [KILL]</div><div>2010-09-08 10:53:01.772750 [DEBUG] switch_core_session.c:1039 Send signal sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> [BREAK]</div>
<div>2010-09-08 10:53:01.866500 [DEBUG] switch_cpp.cpp:602 CoreSession::hangup</div><div>2010-09-08 10:53:01.882125 [DEBUG] switch_cpp.cpp:968 sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> destroy/unlink session from object</div>
<div>2010-09-08 10:53:01.882125 [DEBUG] switch_core_session.c:1905 sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)</div>
<div>2010-09-08 10:53:01.882125 [DEBUG] switch_core_state_machine.c:348 (sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a>) State EXECUTE going to sleep</div><div>
2010-09-08 10:53:01.882125 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a>) Running State Change CS_HANGUP</div><div>2010-09-08 10:53:01.882125 [DEBUG] switch_core_state_machine.c:535 (sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a>) State HANGUP</div>
<div>2010-09-08 10:53:01.882125 [DEBUG] mod_sofia.c:447 sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> Overriding SIP cause 480 with 200 from the other leg</div>
<div>2010-09-08 10:53:01.882125 [DEBUG] mod_sofia.c:453 Channel sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> hanging up, cause: NORMAL_CLEARING</div><div>2010-09-08 10:53:01.991500 [DEBUG] switch_core_state_machine.c:46 sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> Standard HANGUP, cause: NORMAL_CLEARING</div>
<div>2010-09-08 10:53:01.991500 [DEBUG] switch_core_state_machine.c:535 (sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a>) State HANGUP going to sleep</div><div>2010-09-08 10:53:01.991500 [DEBUG] switch_core_state_machine.c:333 (sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a>) State Change CS_HANGUP -> CS_REPORTING</div>
<div>2010-09-08 10:53:01.991500 [DEBUG] switch_core_session.c:1039 Send signal sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> [BREAK]</div><div>2010-09-08 10:53:01.991500 [DEBUG] switch_core_state_machine.c:314 (sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a>) Running State Change CS_REPORTING</div>
<div>2010-09-08 10:53:01.991500 [DEBUG] switch_core_state_machine.c:595 (sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a>) State REPORTING</div><div>2010-09-08 10:53:02.007125 [DEBUG] switch_core_state_machine.c:53 sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> Standard REPORTING, cause: NORMAL_CLEARING</div>
<div>2010-09-08 10:53:02.007125 [DEBUG] switch_core_state_machine.c:595 (sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a>) State REPORTING going to sleep</div><div>
2010-09-08 10:53:02.007125 [DEBUG] switch_core_state_machine.c:327 (sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a>) State Change CS_REPORTING -> CS_DESTROY</div>
<div>2010-09-08 10:53:02.007125 [DEBUG] switch_core_session.c:1039 Send signal sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> [BREAK]</div><div>2010-09-08 10:53:02.007125 [DEBUG] switch_core_session.c:1202 Session 1 (sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a>) Locked, Waiting on external entities</div>
<div>2010-09-08 10:53:02.007125 [NOTICE] switch_core_session.c:1220 Session 1 (sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a>) Ended</div><div>2010-09-08 10:53:02.007125 [NOTICE] switch_core_session.c:1222 Close Channel sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> [CS_DESTROY]</div>
<div>2010-09-08 10:53:02.007125 [DEBUG] switch_core_state_machine.c:427 (sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a>) Callstate Change HANGUP -> DOWN</div>
<div>2010-09-08 10:53:02.007125 [DEBUG] switch_core_state_machine.c:430 (sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a>) Running State Change CS_DESTROY</div><div>
2010-09-08 10:53:02.022750 [DEBUG] switch_core_state_machine.c:440 (sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a>) State DESTROY</div><div>2010-09-08 10:53:02.022750 [DEBUG] mod_sofia.c:358 sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> SOFIA DESTROY</div>
<div>2010-09-08 10:53:02.022750 [DEBUG] switch_core_state_machine.c:60 sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a> Standard DESTROY</div><div>2010-09-08 10:53:02.022750 [DEBUG] switch_core_state_machine.c:440 (sofia/internal/<a href="http://test123_inner_fax@192.168.1.23:5566">test123_inner_fax@192.168.1.23:5566</a>) State DESTROY going to sleep</div>
</div>