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

Shaun Stokes shaun.stokes at itec-support.co.uk
Mon Jul 30 08:41:06 UTC 2018


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 at 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 at 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 at 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 at 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 at 8000
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_media.c:3061 Set Codec sofia/external/+nnnnnnnnnnnn at 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 at 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 at 8000
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] switch_core_media.c:4767 sofia/external/+nnnnnnnnnnnn at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at y.y.y.y parsing [external_profile->external_profile] continue=false
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn at y.y.y.y Regex (PASS) [external_profile] destination_number(+dddddddddddd) =~ /^(.*?)$/ break=on-false
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn at y.y.y.y Action set(call_direction=inbound)
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn at y.y.y.y Action export(t38_passthru=true)
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn at y.y.y.y Action export(ignore_display_updates=true) INLINE
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn at 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 at y.y.y.y Action set(ringback=%(400,200,400,450);%(400,2000,400,450))
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn at y.y.y.y Action ring_ready()
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn at y.y.y.y Action set(send_silence_when_idle=400) INLINE
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn at 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 at y.y.y.y [send_silence_when_idle]=[400]
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn at y.y.y.y Action export(sip_cid_type=none) INLINE
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn at 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 at 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 at 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 at 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 at 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 at 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 at 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 at y.y.y.y Standard EXECUTE
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn at 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 at y.y.y.y [call_direction]=[inbound]
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn at 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 at 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 at y.y.y.y [ringback]=[%(400,200,400,450);%(400,2000,400,450)]
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn at 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 at y.y.y.y!
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:16.832670 [DEBUG] sofia.c:7084 Channel sofia/external/+nnnnnnnnnnnn at 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 at y.y.y.y!
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn at 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 at 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 at 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 at 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 at y.y.y.y to XML[8000 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at y.y.y.y parsing [customersdomain.com->user_exists] continue=true
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn at y.y.y.y Regex (PASS) [user_exists] () =~ // break=on-false
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn at 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 at 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 at y.y.y.y [user_exists]=[false]
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn at y.y.y.y Regex (PASS) [variables] () =~ // break=on-false
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn at y.y.y.y Action export(origination_callee_id_name=${destination_number})
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn at y.y.y.y Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn at y.y.y.y Action set(sip_copy_multipart=false)
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn at y.y.y.y parsing [customersdomain.com->record_variables] continue=true
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn at y.y.y.y Regex (PASS) [record_variables] () =~ // break=on-false
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn at y.y.y.y Action lua(app.lua call_recording set)
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn at y.y.y.y parsing [customersdomain.com->user_record] continue=true
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn at y.y.y.y Regex (PASS) [user_record] () =~ // break=on-false
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn at 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 at 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 at y.y.y.y [user_record]=[UNDEF]
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn at 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 at 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 at y.y.y.y [from_user_exists]=[false]
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn at y.y.y.y Regex (PASS) [redial] () =~ // break=never
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn at 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 at y.y.y.y parsing [customersdomain.com->speed_dial] continue=false
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn at y.y.y.y Regex (PASS) [default_caller_id] ${emergency_caller_id_number}() =~ /^$/ break=never
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn at 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 at 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 at y.y.y.y [emergency_caller_id_name]=[UNDEF]
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn at 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 at 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 at y.y.y.y [emergency_caller_id_number]=[UNDEF]
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn at y.y.y.y Regex (PASS) [default_caller_id] ${outbound_caller_id_number}() =~ /^$/ break=never
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn at 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 at 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 at y.y.y.y [outbound_caller_id_name]=[UNDEF]
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn at 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 at 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 at y.y.y.y [outbound_caller_id_number]=[UNDEF]
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn at y.y.y.y parsing [customersdomain.com->local_distinctive_ring] continue=true
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn at y.y.y.y Regex (PASS) [Fax] destination_number(8000) =~ /^8000$/ break=on-false
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn at 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 at y.y.y.y Action answer()
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn at y.y.y.y Action playback(silence_stream://2000)
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn at y.y.y.y Action set(fax_uuid=4d9b08f1-eac6-4c6d-bccd-ee78dd626e06)
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn at y.y.y.y Action set(fax_enable_t38=true)
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn at y.y.y.y Action set(fax_enable_t38_request=true)
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn at y.y.y.y Action set(fax_use_ecm=true)
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn at y.y.y.y Action set(fax_verbose=true)
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 Dialplan: sofia/external/+nnnnnnnnnnnn at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at y.y.y.y Standard EXECUTE
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn at 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 at 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 at y.y.y.y [RFC2822_DATE]=[Fri, 27 Jul 2018 10:30:17 +0100]
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn at 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 at y.y.y.y [sip_copy_multipart]=[false]
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn at 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 at 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 at 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 at y.y.y.y destroy/unlink session from object
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn at y.y.y.y hash(insert/customersdomain.com-last_dial//+nnnnnnnnnnnn/8000)
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn at 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 at y.y.y.y [api_hangup_hook]=[lua app/fax/resources/scripts/hangup_rx.lua]
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at y.y.y.y entering state [completed][200]
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn at 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 at 16000hz 1 channels 20ms
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 2018-07-27 10:30:17.192671 [DEBUG] sofia.c:7084 Channel sofia/external/+nnnnnnnnnnnn at 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 at 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 at y.y.y.y [fax_uuid]=[4d9b08f1-eac6-4c6d-bccd-ee78dd626e06]
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn at 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 at y.y.y.y [fax_enable_t38]=[true]
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn at 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 at y.y.y.y [fax_enable_t38_request]=[true]
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn at 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 at y.y.y.y [fax_use_ecm]=[true]
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn at 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 at y.y.y.y [fax_verbose]=[true]
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn at 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 at y.y.y.y [last_fax]=[nnnnnnnnnnnn-2018-07-27-10-30-19]
122dd615-7eec-47d4-a7d6-2bca18ca5ed6 EXECUTE sofia/external/+nnnnnnnnnnnn at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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 at 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

-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20180730/31665c97/attachment-0001.html>


More information about the FreeSWITCH-users mailing list