[Freeswitch-users] FreeSWITCH - Intermittent fax calls stuck in mod_spandsp_fax not hanging up

Hi All, We have an issue with calls intermittently not ending when receiving faxes, when this happens the call finishes with 'FLOW T.30 Call completed' but never hangs up, the calls can run for days before they're identified. It's as if the call gets stuck in mod_spandsp_fax instead of being passed back to the dialplan. Has anyone come across this problem before and\or able to advise? Example below. Thanks, Shaun The callers number has been replaced with nnnnnnnnnnnn The destination number has been replaced with dddddddddddd IP address of the sending FreeSWITCH has been replaced with y.y.y.y IP address of the receiving FreeSWITCH has been replaced with x.x.x.x 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [NOTICE] switch_channel.c:1104 New Channel sofia/external/+nnnnnnnnnnnn@y.y.y.y [122dd615-7eec-47d4-a7d6-2bca18ca5ed6] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) Running State Change CS_NEW (Cur 24 Tot 397990) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] sofia.c:9873 sofia/external/+nnnnnnnnnnnn@y.y.y.y receiving invite from y.y.y.y:5095 version: 1.6.20 git 43a9feb 2018-05-07 18:56:11Z 64bit 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] sofia.c:7084 Channel sofia/external/+nnnnnnnnnnnn@y.y.y.y entering state [received][100] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] sofia.c:7094 Remote SDP: 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 v=0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 o=FreeSWITCH 1532659742 1532659743 IN IP4 y.y.y.y 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 s=FreeSWITCH 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 c=IN IP4 y.y.y.y 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 t=0 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 m=audio 24082 RTP/AVP 9 8 101 13 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=rtpmap:9 G722/8000 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=rtpmap:8 PCMA/8000 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=rtpmap:101 telephone-event/8000 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=fmtp:101 0-16 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=ptime:20 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_media.c:3061 Set Codec sofia/external/+nnnnnnnnnnnn@y.y.y.y G722/8000 20 ms 160 samples 64000 bits 1 channels 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_codec.c:111 sofia/external/+nnnnnnnnnnnn@y.y.y.y Original read codec set to G722:9 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_media.c:4767 sofia/external/+nnnnnnnnnnnn@y.y.y.y Set 2833 dtmf send payload to 101 recv payload to 101 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] sofia.c:7507 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) State Change CS_NEW -> CS_INIT 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_state_machine.c:603 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) State NEW 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) Running State Change CS_INIT (Cur 24 Tot 397990) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_state_machine.c:627 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) State INIT 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] mod_sofia.c:90 sofia/external/+nnnnnnnnnnnn@y.y.y.y SOFIA INIT 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_state_machine.c:40 sofia/external/+nnnnnnnnnnnn@y.y.y.y Standard INIT 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_state_machine.c:48 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) State Change CS_INIT -> CS_ROUTING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_state_machine.c:627 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) State INIT going to sleep 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) Running State Change CS_ROUTING (Cur 24 Tot 397990) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_channel.c:2249 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) Callstate Change DOWN -> RINGING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_state_machine.c:643 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) State ROUTING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] mod_sofia.c:143 sofia/external/+nnnnnnnnnnnn@y.y.y.y SOFIA ROUTING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_state_machine.c:236 sofia/external/+nnnnnnnnnnnn@y.y.y.y Standard ROUTING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [INFO] mod_dialplan_xml.c:637 Processing +nnnnnnnnnnnn <+nnnnnnnnnnnn>->+dddddddddddd in context external_profile 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] mod_dialplan_xml.c:650 Getting dialplan from alternate path: /directory_path/freeswitch/config_path/external_profile.xml 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y parsing [external_profile->external_profile] continue=false 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Regex (PASS) [external_profile] destination_number(+dddddddddddd) =~ /^(.*?)$/ break=on-false 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action set(call_direction=inbound) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action export(t38_passthru=true) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action export(ignore_display_updates=true) INLINE 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y export(ignore_display_updates=true) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [ignore_display_updates]=[true] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action set(ringback=%(400,200,400,450);%(400,2000,400,450)) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action ring_ready() 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action set(send_silence_when_idle=400) INLINE 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y set(send_silence_when_idle=400) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] mod_dptools.c:1548 SET sofia/external/+nnnnnnnnnnnn@y.y.y.y [send_silence_when_idle]=[400] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action export(sip_cid_type=none) INLINE 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y export(sip_cid_type=none) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [sip_cid_type]=[none] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action lua(external_profile.lua) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_state_machine.c:286 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) State Change CS_ROUTING -> CS_EXECUTE 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_state_machine.c:643 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) State ROUTING going to sleep 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) Running State Change CS_EXECUTE (Cur 24 Tot 397990) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_state_machine.c:650 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) State EXECUTE 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] mod_sofia.c:198 sofia/external/+nnnnnnnnnnnn@y.y.y.y SOFIA EXECUTE 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_state_machine.c:328 sofia/external/+nnnnnnnnnnnn@y.y.y.y Standard EXECUTE 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y set(call_direction=inbound) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] mod_dptools.c:1548 SET sofia/external/+nnnnnnnnnnnn@y.y.y.y [call_direction]=[inbound] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y export(t38_passthru=true) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [t38_passthru]=[true] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y set(ringback=%(400,200,400,450);%(400,2000,400,450)) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] mod_dptools.c:1548 SET sofia/external/+nnnnnnnnnnnn@y.y.y.y [ringback]=[%(400,200,400,450);%(400,2000,400,450)] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y ring_ready() 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [NOTICE] mod_sofia.c:2286 Ring-Ready sofia/external/+nnnnnnnnnnnn@y.y.y.y! 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] sofia.c:7084 Channel sofia/external/+nnnnnnnnnnnn@y.y.y.y entering state [early][180] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [NOTICE] mod_dptools.c:1028 Ring Ready sofia/external/+nnnnnnnnnnnn@y.y.y.y! 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y lua(external_profile.lua) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.042706 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(hangup_after_bridge, true) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.042706 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(caller_id_number, /+nnnnnnnnnnnn) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y bridge_export(effective_caller_id_number=/+nnnnnnnnnnnn) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.042706 [DEBUG] switch_channel.c:1296 EXPORT (bridge_export_vars) [effective_caller_id_number]=[/+nnnnnnnnnnnn] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y bridge_export(effective_caller_id_name=/+nnnnnnnnnnnn) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.042706 [DEBUG] switch_channel.c:1296 EXPORT (bridge_export_vars) [effective_caller_id_name]=[/+nnnnnnnnnnnn] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.042706 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(domain_uuid, 8f80b77f-52f8-4996-8aca-7d17c958cd02) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.042706 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(domain, customersdomain.com) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.042706 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(domain_name, customersdomain.com) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.042706 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(context, customersdomain.com) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.042706 [DEBUG] switch_ivr.c:2165 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) State Change CS_EXECUTE -> CS_ROUTING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.042706 [NOTICE] switch_ivr.c:2172 Transfer sofia/external/+nnnnnnnnnnnn@y.y.y.y to XML[8000@customersdomain.com] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.042706 [DEBUG] switch_cpp.cpp:907 transfer result: 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.042706 [DEBUG] switch_cpp.cpp:1112 sofia/external/+nnnnnnnnnnnn@y.y.y.y destroy/unlink session from object 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.042706 [DEBUG] switch_core_state_machine.c:650 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) State EXECUTE going to sleep 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.042706 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) Running State Change CS_ROUTING (Cur 24 Tot 397990) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.042706 [DEBUG] switch_core_state_machine.c:643 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) State ROUTING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.042706 [DEBUG] mod_sofia.c:143 sofia/external/+nnnnnnnnnnnn@y.y.y.y SOFIA ROUTING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.042706 [DEBUG] switch_core_state_machine.c:236 sofia/external/+nnnnnnnnnnnn@y.y.y.y Standard ROUTING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.042706 [INFO] mod_dialplan_xml.c:637 Processing +nnnnnnnnnnnn <+nnnnnnnnnnnn>->8000 in context customersdomain.com 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y parsing [customersdomain.com->user_exists] continue=true 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Regex (PASS) [user_exists] () =~ // break=on-false 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y set(user_exists=false) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.052661 [DEBUG] mod_dptools.c:1548 SET sofia/external/+nnnnnnnnnnnn@y.y.y.y [user_exists]=[false] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Regex (PASS) [variables] () =~ // break=on-false 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action export(origination_callee_id_name=${destination_number}) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action set(sip_copy_multipart=false) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y parsing [customersdomain.com->record_variables] continue=true 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Regex (PASS) [record_variables] () =~ // break=on-false 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action lua(app.lua call_recording set) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y parsing [customersdomain.com->user_record] continue=true 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Regex (PASS) [user_record] () =~ // break=on-false 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action set(user_record=${user_data ${destination_number}@${domain_name} var user_record}) INLINE 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y set(user_record=) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.072659 [DEBUG] mod_dptools.c:1548 SET sofia/external/+nnnnnnnnnnnn@y.y.y.y [user_record]=[UNDEF] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y set(from_user_exists=false) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.072659 [DEBUG] mod_dptools.c:1548 SET sofia/external/+nnnnnnnnnnnn@y.y.y.y [from_user_exists]=[false] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Regex (PASS) [redial] () =~ // break=never 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number}) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y parsing [customersdomain.com->speed_dial] continue=false 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Regex (PASS) [default_caller_id] ${emergency_caller_id_number}() =~ /^$/ break=never 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action set(emergency_caller_id_name=${default_emergency_caller_id_name}) INLINE 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y set(emergency_caller_id_name=) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.072659 [DEBUG] mod_dptools.c:1548 SET sofia/external/+nnnnnnnnnnnn@y.y.y.y [emergency_caller_id_name]=[UNDEF] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action set(emergency_caller_id_number=${default_emergency_caller_id_number}) INLINE 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y set(emergency_caller_id_number=) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.072659 [DEBUG] mod_dptools.c:1548 SET sofia/external/+nnnnnnnnnnnn@y.y.y.y [emergency_caller_id_number]=[UNDEF] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Regex (PASS) [default_caller_id] ${outbound_caller_id_number}() =~ /^$/ break=never 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action set(outbound_caller_id_name=${default_outbound_caller_id_name}) INLINE 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y set(outbound_caller_id_name=) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.072659 [DEBUG] mod_dptools.c:1548 SET sofia/external/+nnnnnnnnnnnn@y.y.y.y [outbound_caller_id_name]=[UNDEF] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action set(outbound_caller_id_number=${default_outbound_caller_id_number}) INLINE 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y set(outbound_caller_id_number=) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.072659 [DEBUG] mod_dptools.c:1548 SET sofia/external/+nnnnnnnnnnnn@y.y.y.y [outbound_caller_id_number]=[UNDEF] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y parsing [customersdomain.com->local_distinctive_ring] continue=true 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Regex (PASS) [Fax] destination_number(8000) =~ /^8000$/ break=on-false 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action set(api_hangup_hook=lua app/fax/resources/scripts/hangup_rx.lua) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action answer() 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action playback(silence_stream://2000) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action set(fax_uuid=4d9b08f1-eac6-4c6d-bccd-ee78dd626e06) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action set(fax_enable_t38=true) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action set(fax_enable_t38_request=true) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action set(fax_use_ecm=true) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action set(fax_verbose=true) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action set(last_fax=${sip_from_user_stripped}-${strftime(%Y-%m-%d-%H-%M-%S)}) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action rxfax(/media/store/storage/fax/customersdomain.com/8000/inbox/${last_fax}.tif) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn@y.y.y.y Action hangup() 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.072659 [DEBUG] switch_core_state_machine.c:286 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) State Change CS_ROUTING -> CS_EXECUTE 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.072659 [DEBUG] switch_core_state_machine.c:643 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) State ROUTING going to sleep 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.072659 [DEBUG] switch_core_state_machine.c:584 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) Running State Change CS_EXECUTE (Cur 24 Tot 397990) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.072659 [DEBUG] switch_core_state_machine.c:650 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) State EXECUTE 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.072659 [DEBUG] mod_sofia.c:198 sofia/external/+nnnnnnnnnnnn@y.y.y.y SOFIA EXECUTE 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.072659 [DEBUG] switch_core_state_machine.c:328 sofia/external/+nnnnnnnnnnnn@y.y.y.y Standard EXECUTE 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y export(origination_callee_id_name=8000) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.072659 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [origination_callee_id_name]=[8000] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y set(RFC2822_DATE=Fri, 27 Jul 2018 10:30:17 +0100) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.072659 [DEBUG] mod_dptools.c:1548 SET sofia/external/+nnnnnnnnnnnn@y.y.y.y [RFC2822_DATE]=[Fri, 27 Jul 2018 10:30:17 +0100] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y set(sip_copy_multipart=false) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.082683 [DEBUG] mod_dptools.c:1548 SET sofia/external/+nnnnnnnnnnnn@y.y.y.y [sip_copy_multipart]=[false] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y lua(app.lua call_recording set) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.182696 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(RECORD_APPEND, true) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.182696 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(RECORD_MIN_SEC, 0) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y bridge_export(rec_file=/directory_path/freeswitch/recording_path/customersdomain.com/archive/2018/Jul/27/122dd615-7eec-47d4-a7d6-2bca18ca5ed6.mp3) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.182696 [DEBUG] switch_channel.c:1296 EXPORT (bridge_export_vars) [rec_file]=[/directory_path/freeswitch/recording_path/customersdomain.com/archive/2018/Jul/27/122dd615-7eec-47d4-a7d6-2bca18ca5ed6.mp3] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y bridge_export(rec_uuid=122dd615-7eec-47d4-a7d6-2bca18ca5ed6) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.182696 [DEBUG] switch_channel.c:1296 EXPORT (bridge_export_vars) [rec_uuid]=[122dd615-7eec-47d4-a7d6-2bca18ca5ed6] 2018-07-27 10:30:17.182696 [NOTICE] switch_cpp.cpp:1365 uuid_getvar 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 sofia_profile_name: external 2018-07-27 10:30:17.182696 [NOTICE] switch_cpp.cpp:1365 call_recording rec_file: /directory_path/freeswitch/recording_path/customersdomain.com/archive/2018/Jul/27/122dd615-7eec-47d4-a7d6-2bca18ca5ed6.mp3 2018-07-27 10:30:17.182696 [NOTICE] switch_cpp.cpp:1365 call_recording rec_uuid: 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.182696 [DEBUG] switch_cpp.cpp:1112 sofia/external/+nnnnnnnnnnnn@y.y.y.y destroy/unlink session from object 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y hash(insert/customersdomain.com-last_dial//+nnnnnnnnnnnn/8000) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y set(api_hangup_hook=lua app/fax/resources/scripts/hangup_rx.lua) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.182696 [DEBUG] mod_dptools.c:1548 SET sofia/external/+nnnnnnnnnnnn@y.y.y.y [api_hangup_hook]=[lua app/fax/resources/scripts/hangup_rx.lua] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y answer() 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.182696 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/external/+nnnnnnnnnnnn@y.y.y.y] x.x.x.x port 27332 -> y.y.y.y port 24082 codec: 9 ms: 20 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.182696 [DEBUG] switch_rtp.c:4137 Starting timer [soft] 160 bytes per 20ms 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.182696 [DEBUG] switch_core_media.c:7180 sofia/external/+nnnnnnnnnnnn@y.y.y.y Set 2833 dtmf send payload to 101 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.182696 [DEBUG] switch_core_media.c:7187 sofia/external/+nnnnnnnnnnnn@y.y.y.y Set 2833 dtmf receive payload to 101 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.182696 [DEBUG] switch_core_media.c:7210 sofia/external/+nnnnnnnnnnnn@y.y.y.y Set rtp dtmf delay to 40 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.182696 [DEBUG] switch_core_media.c:7216 Set comfort noise payload to 13 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.182696 [DEBUG] mod_sofia.c:850 Local SDP sofia/external/+nnnnnnnnnnnn@y.y.y.y: 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 v=0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 o=FreeSWITCH 1532656485 1532656486 IN IP4 x.x.x.x 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 s=FreeSWITCH 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 c=IN IP4 x.x.x.x 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 t=0 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 m=audio 27332 RTP/AVP 9 101 13 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=rtpmap:9 G722/8000 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=rtpmap:101 telephone-event/8000 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=fmtp:101 0-16 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=rtpmap:13 CN/8000 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=ptime:20 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=sendrecv 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.182696 [NOTICE] mod_dptools.c:1312 Channel [sofia/external/+nnnnnnnnnnnn@y.y.y.y] has been answered 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.182696 [DEBUG] switch_channel.c:3773 (sofia/external/+nnnnnnnnnnnn@y.y.y.y) Callstate Change RINGING -> ACTIVE 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.182696 [DEBUG] sofia.c:7084 Channel sofia/external/+nnnnnnnnnnnn@y.y.y.y entering state [completed][200] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y playback(silence_stream://2000) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.182696 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@16000hz 1 channels 20ms 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.192671 [DEBUG] sofia.c:7084 Channel sofia/external/+nnnnnnnnnnnn@y.y.y.y entering state [ready][200] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.242687 [DEBUG] switch_rtp.c:7308 Correct audio ip/port confirmed. 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.182671 [DEBUG] switch_ivr_play_say.c:1942 done playing file silence_stream://2000 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y set(fax_uuid=4d9b08f1-eac6-4c6d-bccd-ee78dd626e06) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.182671 [DEBUG] mod_dptools.c:1548 SET sofia/external/+nnnnnnnnnnnn@y.y.y.y [fax_uuid]=[4d9b08f1-eac6-4c6d-bccd-ee78dd626e06] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y set(fax_enable_t38=true) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.182671 [DEBUG] mod_dptools.c:1548 SET sofia/external/+nnnnnnnnnnnn@y.y.y.y [fax_enable_t38]=[true] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y set(fax_enable_t38_request=true) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.182671 [DEBUG] mod_dptools.c:1548 SET sofia/external/+nnnnnnnnnnnn@y.y.y.y [fax_enable_t38_request]=[true] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y set(fax_use_ecm=true) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.182671 [DEBUG] mod_dptools.c:1548 SET sofia/external/+nnnnnnnnnnnn@y.y.y.y [fax_use_ecm]=[true] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y set(fax_verbose=true) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.182671 [DEBUG] mod_dptools.c:1548 SET sofia/external/+nnnnnnnnnnnn@y.y.y.y [fax_verbose]=[true] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y set(last_fax=nnnnnnnnnnnn-2018-07-27-10-30-19) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.182671 [DEBUG] mod_dptools.c:1548 SET sofia/external/+nnnnnnnnnnnn@y.y.y.y [last_fax]=[nnnnnnnnnnnn-2018-07-27-10-30-19] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn@y.y.y.y rxfax(/media/store/storage/fax/customersdomain.com/8000/inbox/nnnnnnnnnnnn-2018-07-27-10-30-19.tif) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.192630 [DEBUG] mod_spandsp_fax.c:1448 Raw read codec activation Success L16 20000 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.192630 [DEBUG] switch_core_codec.c:223 sofia/external/+nnnnnnnnnnnn@y.y.y.y Push codec L16:100 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.192630 [DEBUG] mod_spandsp_fax.c:1466 Raw write codec activation Success L16 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.192630 [DEBUG] switch_ivr.c:195 Codec Activated L16@8000hz 1 channels 20ms 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.202705 [NOTICE] switch_core_io.c:1202 Activating write resampler 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.202705 [DEBUG] switch_core_io.c:1448 Engaging Write Buffer at 640 bytes to accommodate 640->640 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.242689 [NOTICE] switch_core_io.c:570 Activating read resampler 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.462696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier up (-2) in state ANSWERING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.482690 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier down (-1) in state ANSWERING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.502696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier up (-2) in state ANSWERING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.522686 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Abort (-8) in state ANSWERING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.582696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier down (-1) in state ANSWERING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.602696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier up (-2) in state ANSWERING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:19.642696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier down (-1) in state ANSWERING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:20.002708 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier up (-2) in state ANSWERING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:20.022686 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier down (-1) in state ANSWERING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:20.402696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier up (-2) in state ANSWERING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:20.422690 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Abort (-8) in state ANSWERING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:20.442690 [DEBUG] switch_core_media.c:9390 sofia/external/+nnnnnnnnnnnn@y.y.y.y image media sdp: 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 v=0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 o=FreeSWITCH 1532656485 1532656487 IN IP4 x.x.x.x 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 s=FreeSWITCH 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 c=IN IP4 x.x.x.x 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 t=0 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 m=image 27332 udptl t38 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38FaxVersion:0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38MaxBitRate:14400 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38FaxFillBitRemoval 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38FaxRateManagement:transferredTCF 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38FaxMaxBuffer:2000 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38FaxMaxDatagram:400 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38FaxUdpEC:t38UDPRedundancy 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:20.442690 [DEBUG] sofia_glue.c:1295 sofia/external/+nnnnnnnnnnnn@y.y.y.y sending invite version: 1.6.20 git 43a9feb 2018-05-07 18:56:11Z 64bit 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Local SDP: 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 v=0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 o=FreeSWITCH 1532656485 1532656487 IN IP4 x.x.x.x 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 s=FreeSWITCH 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 c=IN IP4 x.x.x.x 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 t=0 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 m=image 27332 udptl t38 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38FaxVersion:0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38MaxBitRate:14400 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38FaxFillBitRemoval 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38FaxRateManagement:transferredTCF 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38FaxMaxBuffer:2000 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38FaxMaxDatagram:400 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38FaxUdpEC:t38UDPRedundancy 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:20.442690 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier down (-1) in state ANSWERING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:20.442690 [DEBUG] sofia.c:7084 Channel sofia/external/+nnnnnnnnnnnn@y.y.y.y entering state [calling][0] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:20.642707 [DEBUG] sofia.c:7084 Channel sofia/external/+nnnnnnnnnnnn@y.y.y.y entering state [completing][200] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:20.642707 [DEBUG] sofia.c:7094 Remote SDP: 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 v=0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 o=FreeSWITCH 1532659742 1532659744 IN IP4 y.y.y.y 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 s=FreeSWITCH 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 c=IN IP4 y.y.y.y 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 t=0 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 m=image 24082 udptl t38 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38FaxVersion:0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38MaxBitRate:14400 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38FaxFillBitRemoval 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38FaxRateManagement:transferredTCF 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38FaxMaxBuffer:300 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38FaxMaxDatagram:176 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 a=T38FaxUdpEC:t38UDPRedundancy 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:20.662688 [DEBUG] sofia.c:7084 Channel sofia/external/+nnnnnnnnnnnn@y.y.y.y entering state [ready][200] 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:20.662688 [DEBUG] switch_core_media.c:4041 sofia/external/+nnnnnnnnnnnn@y.y.y.y T38 ACCEPT on response 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:20.662688 [DEBUG] switch_core_media.c:4146 sofia/external/+nnnnnnnnnnnn@y.y.y.y T38 IS POSSIBLE on response 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:20.662688 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/external/+nnnnnnnnnnnn@y.y.y.y. 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:20.682689 [DEBUG] switch_core_media.c:9751 Remote address:port [y.y.y.y:24082] has not changed. 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:20.682689 [DEBUG] switch_core_media.c:9751 Remote address:port [y.y.y.y:24082] has not changed. 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:23.862657 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase A_CED, state ANSWERING 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:23.862657 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Starting answer mode 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:23.862657 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase A_CED to B_TX 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:23.862657 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:23.862657 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:23.862657 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Start T2 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:23.862657 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from state ANSWERING to R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:23.862657 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Sending ident SpanDSP Fax Ident 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:23.862657 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: CSI without final frame tag 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:23.862657 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: ff 03 40 74 6e 65 64 49 20 78 61 46 20 50 53 44 6e 61 70 53 20 20 20 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 DIS: 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...1= Store and forward Internet fax (T.37): Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Real-time Internet fax (T.38): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= 3G mobile network: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= V.8 capabilities: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Preferred octets: 256 octets 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Ready to transmit a fax document (polling): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..1.= Can receive fax: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..10 11..= Supported data signalling rates: V.27 ter, V.29, and V.17 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .1.. ....= R8x7.7lines/mm and/or 200x200pels/25.4mm: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= 2-D coding: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..10= Recording width: 215mm +- 1%, 255mm +- 1% and 303mm +- 1% 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 10..= Recording length: Unlimited 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .111 ....= Receiver's minimum scan line time: 0ms at 3.85 l/mm; T7.7 = T3.85 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Compressed/uncompressed mode: Compressed 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .1..= Error correction mode (ECM): ECM 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .1.. ....= T.6 coding: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= "Field not valid" supported: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Multiple selective polling: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Polled sub-address: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= T.43 coding: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...0 ....= Plane interleave: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Voice coding with 32kbit/s ADPCM (Rec. G.726): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Reserved for the use of extended voice coding set: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...1= R8x15.4lines/mm: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= 300x300pels/25.4mm: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .1..= R16x15.4lines/mm and/or 400x400pels/25.4mm: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 1...= Inch-based resolution preferred: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...1 ....= Metric-based resolution preferred: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Minimum scan line time for higher resolutions: T15.4 = T7.7 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Selective polling: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Sub-addressing: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Password: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Ready to transmit a data file (polling): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...0 ....= Binary file transfer (BFT): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Document transfer mode (DTM): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Electronic data interchange (EDI): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Basic transfer mode (BTM): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Ready to transfer a character or mixed mode document (polling): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= Character mode: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Mixed mode (Annex E/T.4): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Processable mode 26 (Rec. T.505): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Digital network capability: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Duplex capability: Half only 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= JPEG coding: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...0 ....= Full colour mode: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= 12bits/pel component: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= No subsampling (1:1:1): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Custom illuminant: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Custom gamut range: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 1...= North American Letter (215.9mm x 279.4mm): Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...1 ....= North American Legal (215.9mm x 355.6mm): Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..1. ....= Single-progression sequential coding (Rec. T.85) basic: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .1.. ....= Single-progression sequential coding (Rec. T.85) optional L0: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 0... ....= Extension indicator: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: DIS with final frame tag 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:25.582694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: ff 13 80 01 ee fa c4 80 9d 80 80 80 78 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:26.062691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:26.062691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:26.062691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase B_TX to B_RX 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:26.062691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:26.062691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:26.062691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Start T4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:29.522689 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Retry number 1 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:29.522689 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase B_RX to B_TX 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:29.522689 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:29.522689 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:29.522689 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Sending ident SpanDSP Fax Ident 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:29.522689 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: CSI without final frame tag 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:29.522689 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: ff 03 40 74 6e 65 64 49 20 78 61 46 20 50 53 44 6e 61 70 53 20 20 20 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 DIS: 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...1= Store and forward Internet fax (T.37): Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Real-time Internet fax (T.38): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= 3G mobile network: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= V.8 capabilities: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Preferred octets: 256 octets 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Ready to transmit a fax document (polling): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..1.= Can receive fax: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..10 11..= Supported data signalling rates: V.27 ter, V.29, and V.17 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .1.. ....= R8x7.7lines/mm and/or 200x200pels/25.4mm: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= 2-D coding: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..10= Recording width: 215mm +- 1%, 255mm +- 1% and 303mm +- 1% 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 10..= Recording length: Unlimited 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .111 ....= Receiver's minimum scan line time: 0ms at 3.85 l/mm; T7.7 = T3.85 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Compressed/uncompressed mode: Compressed 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .1..= Error correction mode (ECM): ECM 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .1.. ....= T.6 coding: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= "Field not valid" supported: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Multiple selective polling: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Polled sub-address: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= T.43 coding: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...0 ....= Plane interleave: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Voice coding with 32kbit/s ADPCM (Rec. G.726): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Reserved for the use of extended voice coding set: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...1= R8x15.4lines/mm: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= 300x300pels/25.4mm: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .1..= R16x15.4lines/mm and/or 400x400pels/25.4mm: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 1...= Inch-based resolution preferred: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...1 ....= Metric-based resolution preferred: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Minimum scan line time for higher resolutions: T15.4 = T7.7 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Selective polling: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Sub-addressing: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Password: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Ready to transmit a data file (polling): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...0 ....= Binary file transfer (BFT): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Document transfer mode (DTM): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Electronic data interchange (EDI): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Basic transfer mode (BTM): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Ready to transfer a character or mixed mode document (polling): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= Character mode: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Mixed mode (Annex E/T.4): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Processable mode 26 (Rec. T.505): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Digital network capability: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Duplex capability: Half only 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= JPEG coding: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...0 ....= Full colour mode: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= 12bits/pel component: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= No subsampling (1:1:1): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Custom illuminant: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Custom gamut range: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 1...= North American Letter (215.9mm x 279.4mm): Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...1 ....= North American Legal (215.9mm x 355.6mm): Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..1. ....= Single-progression sequential coding (Rec. T.85) basic: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .1.. ....= Single-progression sequential coding (Rec. T.85) optional L0: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 0... ....= Extension indicator: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: DIS with final frame tag 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.222687 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: ff 13 80 01 ee fa c4 80 9d 80 80 80 78 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.702689 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.702689 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.702689 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase B_TX to B_RX 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.702689 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.702689 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:31.702689 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Start T4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:34.712699 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 A signal is present 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:34.712699 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier up (-2) in state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:34.712699 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Framing OK (-6) in state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:34.712699 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Start T4-flagged 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:37.112661 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Type CRP - CRC OK (clean) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:37.112661 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Stop T4-flagged (4800 remaining) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:37.112661 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx: CRP with final frame tag 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:37.112661 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx: ff 13 1b 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:37.112661 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx final frame in state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:37.112661 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Retry number 2 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:37.112661 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Queuing phase B_TX 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:37.112661 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Sending ident SpanDSP Fax Ident 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:37.112661 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: CSI without final frame tag 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:37.112661 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: ff 03 40 74 6e 65 64 49 20 78 61 46 20 50 53 44 6e 61 70 53 20 20 20 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:37.192653 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Receive complete in phase B_RX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:37.192653 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier down (-1) in state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:37.192653 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase B_RX to B_TX 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:37.192653 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:37.192653 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:37.192653 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 No signal is present 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 DIS: 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...1= Store and forward Internet fax (T.37): Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Real-time Internet fax (T.38): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= 3G mobile network: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= V.8 capabilities: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Preferred octets: 256 octets 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Ready to transmit a fax document (polling): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..1.= Can receive fax: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..10 11..= Supported data signalling rates: V.27 ter, V.29, and V.17 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .1.. ....= R8x7.7lines/mm and/or 200x200pels/25.4mm: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= 2-D coding: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..10= Recording width: 215mm +- 1%, 255mm +- 1% and 303mm +- 1% 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 10..= Recording length: Unlimited 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .111 ....= Receiver's minimum scan line time: 0ms at 3.85 l/mm; T7.7 = T3.85 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Compressed/uncompressed mode: Compressed 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .1..= Error correction mode (ECM): ECM 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .1.. ....= T.6 coding: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= "Field not valid" supported: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Multiple selective polling: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Polled sub-address: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= T.43 coding: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...0 ....= Plane interleave: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Voice coding with 32kbit/s ADPCM (Rec. G.726): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Reserved for the use of extended voice coding set: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...1= R8x15.4lines/mm: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= 300x300pels/25.4mm: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .1..= R16x15.4lines/mm and/or 400x400pels/25.4mm: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 1...= Inch-based resolution preferred: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...1 ....= Metric-based resolution preferred: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Minimum scan line time for higher resolutions: T15.4 = T7.7 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Selective polling: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Sub-addressing: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Password: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Ready to transmit a data file (polling): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...0 ....= Binary file transfer (BFT): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Document transfer mode (DTM): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Electronic data interchange (EDI): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Basic transfer mode (BTM): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Ready to transfer a character or mixed mode document (polling): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= Character mode: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Mixed mode (Annex E/T.4): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Processable mode 26 (Rec. T.505): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Digital network capability: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Duplex capability: Half only 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= JPEG coding: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...0 ....= Full colour mode: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= 12bits/pel component: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= No subsampling (1:1:1): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Custom illuminant: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Custom gamut range: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 1...= North American Letter (215.9mm x 279.4mm): Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...1 ....= North American Legal (215.9mm x 355.6mm): Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..1. ....= Single-progression sequential coding (Rec. T.85) basic: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .1.. ....= Single-progression sequential coding (Rec. T.85) optional L0: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 0... ....= Extension indicator: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: DIS with final frame tag 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:38.902691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: ff 13 80 01 ee fa c4 80 9d 80 80 80 78 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:39.382700 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:39.382700 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:39.382700 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase B_TX to B_RX 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:39.382700 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:39.382700 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:39.382700 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Start T4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:42.392675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 A signal is present 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:42.392675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier up (-2) in state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:42.392675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Framing OK (-6) in state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:42.392675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Start T4-flagged 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:44.792697 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Type CRP - CRC OK (clean) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:44.792697 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Stop T4-flagged (4800 remaining) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:44.792697 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx: CRP with final frame tag 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:44.792697 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx: ff 13 1b 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:44.792697 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx final frame in state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:44.792697 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Retry number 3 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:44.792697 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Queuing phase B_TX 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:44.792697 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Sending ident SpanDSP Fax Ident 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:44.792697 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: CSI without final frame tag 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:44.792697 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: ff 03 40 74 6e 65 64 49 20 78 61 46 20 50 53 44 6e 61 70 53 20 20 20 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:44.872696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Receive complete in phase B_RX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:44.872696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier down (-1) in state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:44.872696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase B_RX to B_TX 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:44.872696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:44.872696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:44.872696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 No signal is present 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 DIS: 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...1= Store and forward Internet fax (T.37): Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Real-time Internet fax (T.38): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= 3G mobile network: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= V.8 capabilities: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Preferred octets: 256 octets 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Ready to transmit a fax document (polling): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..1.= Can receive fax: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..10 11..= Supported data signalling rates: V.27 ter, V.29, and V.17 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .1.. ....= R8x7.7lines/mm and/or 200x200pels/25.4mm: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= 2-D coding: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..10= Recording width: 215mm +- 1%, 255mm +- 1% and 303mm +- 1% 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 10..= Recording length: Unlimited 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .111 ....= Receiver's minimum scan line time: 0ms at 3.85 l/mm; T7.7 = T3.85 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Compressed/uncompressed mode: Compressed 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .1..= Error correction mode (ECM): ECM 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .1.. ....= T.6 coding: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= "Field not valid" supported: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Multiple selective polling: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Polled sub-address: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= T.43 coding: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...0 ....= Plane interleave: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Voice coding with 32kbit/s ADPCM (Rec. G.726): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Reserved for the use of extended voice coding set: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...1= R8x15.4lines/mm: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= 300x300pels/25.4mm: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .1..= R16x15.4lines/mm and/or 400x400pels/25.4mm: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 1...= Inch-based resolution preferred: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...1 ....= Metric-based resolution preferred: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Minimum scan line time for higher resolutions: T15.4 = T7.7 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Selective polling: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Sub-addressing: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Password: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Ready to transmit a data file (polling): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...0 ....= Binary file transfer (BFT): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Document transfer mode (DTM): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Electronic data interchange (EDI): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Basic transfer mode (BTM): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Ready to transfer a character or mixed mode document (polling): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= Character mode: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Mixed mode (Annex E/T.4): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Processable mode 26 (Rec. T.505): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Digital network capability: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Duplex capability: Half only 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= JPEG coding: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...0 ....= Full colour mode: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= 12bits/pel component: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= No subsampling (1:1:1): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Custom illuminant: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Custom gamut range: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 1...= North American Letter (215.9mm x 279.4mm): Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...1 ....= North American Legal (215.9mm x 355.6mm): Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..1. ....= Single-progression sequential coding (Rec. T.85) basic: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .1.. ....= Single-progression sequential coding (Rec. T.85) optional L0: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 0... ....= Extension indicator: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: DIS with final frame tag 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:46.582709 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: ff 13 80 01 ee fa c4 80 9d 80 80 80 78 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:47.062684 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:47.062684 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:47.062684 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase B_TX to B_RX 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:47.062684 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:47.062684 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:47.062684 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Start T4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:50.072694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 A signal is present 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:50.072694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier up (-2) in state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:50.072694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Framing OK (-6) in state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:50.072694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Start T4-flagged 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:52.472696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Type CRP - CRC OK (clean) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:52.472696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Stop T4-flagged (4800 remaining) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:52.472696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx: CRP with final frame tag 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:52.472696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx: ff 13 1b 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:52.472696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx final frame in state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:52.472696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Retry number 4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:52.472696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Queuing phase B_TX 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:52.472696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Sending ident SpanDSP Fax Ident 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:52.472696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: CSI without final frame tag 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:52.472696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: ff 03 40 74 6e 65 64 49 20 78 61 46 20 50 53 44 6e 61 70 53 20 20 20 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:52.552696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Receive complete in phase B_RX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:52.552696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier down (-1) in state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:52.552696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase B_RX to B_TX 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:52.552696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:52.552696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:52.552696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 No signal is present 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 DIS: 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...1= Store and forward Internet fax (T.37): Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Real-time Internet fax (T.38): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= 3G mobile network: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= V.8 capabilities: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Preferred octets: 256 octets 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Ready to transmit a fax document (polling): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..1.= Can receive fax: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..10 11..= Supported data signalling rates: V.27 ter, V.29, and V.17 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .1.. ....= R8x7.7lines/mm and/or 200x200pels/25.4mm: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= 2-D coding: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..10= Recording width: 215mm +- 1%, 255mm +- 1% and 303mm +- 1% 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 10..= Recording length: Unlimited 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .111 ....= Receiver's minimum scan line time: 0ms at 3.85 l/mm; T7.7 = T3.85 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Compressed/uncompressed mode: Compressed 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .1..= Error correction mode (ECM): ECM 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .1.. ....= T.6 coding: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= "Field not valid" supported: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Multiple selective polling: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Polled sub-address: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= T.43 coding: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...0 ....= Plane interleave: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Voice coding with 32kbit/s ADPCM (Rec. G.726): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Reserved for the use of extended voice coding set: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...1= R8x15.4lines/mm: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= 300x300pels/25.4mm: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .1..= R16x15.4lines/mm and/or 400x400pels/25.4mm: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 1...= Inch-based resolution preferred: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...1 ....= Metric-based resolution preferred: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Minimum scan line time for higher resolutions: T15.4 = T7.7 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Selective polling: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Sub-addressing: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Password: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Ready to transmit a data file (polling): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...0 ....= Binary file transfer (BFT): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Document transfer mode (DTM): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Electronic data interchange (EDI): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Basic transfer mode (BTM): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Ready to transfer a character or mixed mode document (polling): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= Character mode: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Mixed mode (Annex E/T.4): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Processable mode 26 (Rec. T.505): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Digital network capability: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Duplex capability: Half only 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= JPEG coding: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...0 ....= Full colour mode: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= 12bits/pel component: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= No subsampling (1:1:1): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Custom illuminant: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Custom gamut range: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 1...= North American Letter (215.9mm x 279.4mm): Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...1 ....= North American Legal (215.9mm x 355.6mm): Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..1. ....= Single-progression sequential coding (Rec. T.85) basic: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .1.. ....= Single-progression sequential coding (Rec. T.85) optional L0: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 0... ....= Extension indicator: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: DIS with final frame tag 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.262671 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: ff 13 80 01 ee fa c4 80 9d 80 80 80 78 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.742691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.742691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.742691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase B_TX to B_RX 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.742691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.742691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:54.742691 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Start T4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:57.752681 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 A signal is present 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:57.752681 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier up (-2) in state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:57.752681 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Framing OK (-6) in state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:57.752681 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Start T4-flagged 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:00.152675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Type CRP - CRC OK (clean) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:00.152675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Stop T4-flagged (4800 remaining) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:00.152675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx: CRP with final frame tag 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:00.152675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx: ff 13 1b 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:00.152675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx final frame in state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:00.152675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Retry number 5 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:00.152675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Queuing phase B_TX 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:00.152675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Sending ident SpanDSP Fax Ident 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:00.152675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: CSI without final frame tag 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:00.152675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: ff 03 40 74 6e 65 64 49 20 78 61 46 20 50 53 44 6e 61 70 53 20 20 20 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:00.232701 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Receive complete in phase B_RX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:00.232701 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier down (-1) in state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:00.232701 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase B_RX to B_TX 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:00.232701 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:00.232701 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:00.232701 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 No signal is present 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 DIS: 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...1= Store and forward Internet fax (T.37): Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Real-time Internet fax (T.38): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= 3G mobile network: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= V.8 capabilities: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Preferred octets: 256 octets 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Ready to transmit a fax document (polling): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..1.= Can receive fax: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..10 11..= Supported data signalling rates: V.27 ter, V.29, and V.17 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .1.. ....= R8x7.7lines/mm and/or 200x200pels/25.4mm: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= 2-D coding: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..10= Recording width: 215mm +- 1%, 255mm +- 1% and 303mm +- 1% 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 10..= Recording length: Unlimited 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .111 ....= Receiver's minimum scan line time: 0ms at 3.85 l/mm; T7.7 = T3.85 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Compressed/uncompressed mode: Compressed 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .1..= Error correction mode (ECM): ECM 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .1.. ....= T.6 coding: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= "Field not valid" supported: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Multiple selective polling: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Polled sub-address: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= T.43 coding: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...0 ....= Plane interleave: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Voice coding with 32kbit/s ADPCM (Rec. G.726): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Reserved for the use of extended voice coding set: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...1= R8x15.4lines/mm: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= 300x300pels/25.4mm: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .1..= R16x15.4lines/mm and/or 400x400pels/25.4mm: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 1...= Inch-based resolution preferred: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...1 ....= Metric-based resolution preferred: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Minimum scan line time for higher resolutions: T15.4 = T7.7 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Selective polling: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Sub-addressing: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Password: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Ready to transmit a data file (polling): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...0 ....= Binary file transfer (BFT): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Document transfer mode (DTM): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= Electronic data interchange (EDI): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Basic transfer mode (BTM): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Ready to transfer a character or mixed mode document (polling): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= Character mode: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..0. ....= Mixed mode (Annex E/T.4): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= Processable mode 26 (Rec. T.505): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Digital network capability: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Duplex capability: Half only 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 0...= JPEG coding: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...0 ....= Full colour mode: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .0.. ....= 12bits/pel component: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 1... ....= Extension indicator: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ...0= No subsampling (1:1:1): Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... ..0.= Custom illuminant: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... .0..= Custom gamut range: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .... 1...= North American Letter (215.9mm x 279.4mm): Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ...1 ....= North American Legal (215.9mm x 355.6mm): Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 ..1. ....= Single-progression sequential coding (Rec. T.85) basic: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 .1.. ....= Single-progression sequential coding (Rec. T.85) optional L0: Set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 0... ....= Extension indicator: Not set 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: DIS with final frame tag 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:01.942696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: ff 13 80 01 ee fa c4 80 9d 80 80 80 78 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:02.422694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:02.422694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase B_TX, state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:02.422694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase B_TX to B_RX 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:02.422694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:02.422694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:02.422694 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Start T4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:05.432688 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 A signal is present 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:05.432688 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier up (-2) in state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:05.432688 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Framing OK (-6) in state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:05.432688 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Start T4-flagged 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:07.832695 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Type CRP - CRC OK (clean) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:07.832695 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Stop T4-flagged (4800 remaining) 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:07.832695 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx: CRP with final frame tag 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:07.832695 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx: ff 13 1b 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:07.832695 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Rx final frame in state R 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:07.832695 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Too many retries. Giving up. 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:07.832695 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Status changing to Disconnected after permitted retries 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:07.832695 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Queuing phase D_TX 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:07.832695 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from state R to C 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:07.832695 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: DCN with final frame tag 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:07.832695 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Tx: ff 13 fa 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:07.912690 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Receive complete in phase B_RX, state C 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:07.912690 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 HDLC signal status is Carrier down (-1) in state C 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:07.912690 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase B_RX to D_TX 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:07.912690 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:07.912690 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 4 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:07.912690 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 No signal is present 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:09.082675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase D_TX, state C 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:09.082675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase D_TX, state C 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:09.082675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Starting final pause before disconnecting 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:09.082675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase D_TX to E 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:09.082675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:09.082675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 1 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:09.082675 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from state C to B 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Send complete in phase E, state B 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:496 ============================================================================== 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:508 Fax processing not successful - result (48) Disconnected after permitted retries. 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:513 Remote station id: 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:514 Local station id: SpanDSP Fax Ident 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:515 Pages transferred: 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:517 Total fax pages: 0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:518 Image resolution: 0x0 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:519 Transfer Rate: 14400 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:521 ECM status off 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:522 remote country: 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:523 remote vendor: 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:524 remote model: 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:526 ============================================================================== 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from state B to CALL_FINISHED 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Changing from phase E to CALL_FINISHED 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set rx type 9 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T Set tx type 9 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.38T FAX exchange complete 122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:31:10.402696 [DEBUG] mod_spandsp_fax.c:276 FLOW T.30 Call completed