The version of freeswitch is 1.2 stable

FreeSWITCH Version 1.2.11+git~20130704T172759Z~667a8d8868 (git 667a8d8 2013-07-04 17:27:59Z)



On Thu, Jul 4, 2013 at 10:33 PM, arun sethi <asethi168@gmail.com> wrote:
I am trying to send fax to a local fax machine (Canon MX 512) on my LAN using freeswitch. The machine is connected via linksys pap2 (ATA) on LAN to freeswitch.

the command I am using on freeswitch console is:
/usr/local/freeswitch/bin/fs_cli -x  "originate {fax_verbose=true,ignore_early_media=true,absolute_codec_string='PCMU'}user/1006 &txfax(/tmp/txfax.tiff)"

The machine in question is Canon Mx 512 in fax only mode. Note that I am able to send out fax from this machine and receive it in freeswitch using rxfax. The problem is only in sending fax to this machine using txfax. 

The complete error log is at paste http://pastebin.freeswitch.org/21151
Here are some relevant snippets from error log

2013-07-04 21:23:09.215751 [DEBUG] mod_sofia.c:243 sofia/internal/sip:1006@10.2.2.159:8081 SOFIA EXECUTE
2013-07-04 21:23:09.215751 [DEBUG] switch_core_state_machine.c:209 sofia/internal/sip:1006@10.2.2.159:8081 Standard EXECUTE
EXECUTE sofia/internal/sip:1006@10.2.2.159:8081 txfax(/tmp/txfax.tiff)
2013-07-04 21:23:09.215751 [DEBUG] mod_spandsp_fax.c:1387 Raw read codec activation Success L16 30000
2013-07-04 21:23:09.215751 [DEBUG] switch_core_codec.c:219 sofia/internal/sip:1006@10.2.2.159:8081 Push codec L16:70
2013-07-04 21:23:09.215751 [DEBUG] mod_spandsp_fax.c:1403 Raw write codec activation Success L16
2013-07-04 21:23:09.265748 [DEBUG] switch_rtp.c:3706 Correct ip/port confirmed.
2013-07-04 21:23:09.265748 [DEBUG] switch_core_io.c:759 Engaging Read Buffer at 480 bytes vs 160
2013-07-04 21:23:09.475748 [WARNING] mod_sofia.c:1279 Asynchronous PTIME not supported, changing our end from 30 to 20
2013-07-04 21:23:09.475748 [DEBUG] sofia_glue.c:3037 Changing Codec from PCMU@30ms@8000hz to PCMU@20ms@8000hz
2013-07-04 21:23:09.535748 [DEBUG] switch_rtp.c:1873 RE-Starting timer [soft] 160 bytes per 20ms
2013-07-04 21:23:09.535748 [DEBUG] sofia_glue.c:3124 Set Codec sofia/internal/sip:1006@10.2.2.159:8081 PCMU/8000 20 ms 160 samples 64000 bits
2013-07-04 21:23:09.555751 [DEBUG] switch_core_io.c:1365 Engaging Write Buffer at 320 bytes to accommodate 480->320
2013-07-04 21:23:12.095748 [DEBUG] mod_spandsp_fax.c:291 FLOW T.30 HDLC signal status is Carrier up (-2) in state T
2013-07-04 21:23:15.095748 [DEBUG] mod_spandsp_fax.c:291 FLOW T.30 HDLC signal status is Carrier down (-1) in state T
2013-07-04 21:23:15.155748 [DEBUG] mod_spandsp_fax.c:291 FLOW T.30 HDLC signal status is Carrier up (-2) in state T
2013-07-04 21:23:15.195750 [DEBUG] mod_spandsp_fax.c:291 FLOW T.30 HDLC signal status is Abort (-8) in state T
2013-07-04 21:23:15.235749 [DEBUG] mod_spandsp_fax.c:291 FLOW T.30 HDLC signal status is Abort (-8) in state T
2013-07-04 21:23:15.275748 [DEBUG] mod_spandsp_fax.c:291 FLOW T.30 HDLC signal status is Abort (-8) in state T
2013-07-04 21:23:15.315750 [DEBUG] mod_spandsp_fax.c:291 FLOW T.30 HDLC signal status is Abort (-8) in state T
2013-07-04 21:23:15.355748 [DEBUG] mod_spandsp_fax.c:291 FLOW T.30 HDLC signal status is Abort (-8) in state T
2013-07-04 21:23:15.395748 [DEBUG] mod_spandsp_fax.c:291 FLOW T.30 HDLC signal status is Abort (-8) in state T
2013-07-04 21:23:15.635748 [DEBUG] mod_spandsp_fax.c:291 FLOW T.30 HDLC signal status is Abort (-8) in state T
2013-07-04 21:23:15.715751 [DEBUG] mod_spandsp_fax.c:291 FLOW T.30 HDLC signal status is Abort (-8) in state T
2013-07-04 21:23:15.795749 [DEBUG] mod_spandsp_fax.c:291 FLOW T.30 HDLC signal status is Abort (-8) in state T
2013-07-04 21:23:15.915751 [DEBUG] mod_spandsp_fax.c:291 FLOW T.30 HDLC signal s

**** repeats many times ****

2013-07-04 21:23:47.035750 [DEBUG] mod_spandsp_fax.c:291 FLOW T.30 HDLC signal status is Abort (-8) in state T
2013-07-04 21:23:48.115755 [DEBUG] mod_spandsp_fax.c:291 FLOW T.30 HDLC signal status is Abort (-8) in state T
2013-07-04 21:23:48.135749 [DEBUG] mod_spandsp_fax.c:291 FLOW T.30 HDLC signal status is Carrier down (-1) in state T
2013-07-04 21:23:49.535749 [DEBUG] mod_spandsp_fax.c:291 FLOW T.30 T0 expired in state T
2013-07-04 21:23:49.535749 [DEBUG] mod_spandsp_fax.c:291 FLOW T.30 Status changing to 'Timed out waiting for initial communication'
2013-07-04 21:23:49.535749 [DEBUG] mod_spandsp_fax.c:291 FLOW T.30 Disconnecting
2013-07-04 21:23:49.535749 [DEBUG] mod_spandsp_fax.c:291 FLOW T.30 Changing from phase A_CNG to E
2013-07-04 21:23:49.535749 [DEBUG] mod_spandsp_fax.c:291 FLOW FAX Set rx type 0
2013-07-04 21:23:49.535749 [DEBUG] mod_spandsp_fax.c:291 FLOW FAX Set tx type 1
2013-07-04 21:23:49.535749 [DEBUG] mod_spandsp_fax.c:291 FLOW T.30 Changing from state T to B
2013-07-04 21:23:50.195748 [DEBUG] mod_spandsp_fax.c:291 FLOW T.30 Send complete in phase E, state B
2013-07-04 21:23:50.195748 [DEBUG] mod_spandsp_fax.c:511 ==============================================================================
2013-07-04 21:23:50.195748 [DEBUG] mod_spandsp_fax.c:524 Fax processing not successful - result (2) Timed out waiting for initial communication.
2013-07-04 21:23:50.195748 [DEBUG] mod_spandsp_fax.c:529 Remote station id: 
2013-07-04 21:23:50.195748 [DEBUG] mod_spandsp_fax.c:530 Local station id:  SpanDSP Fax Ident
2013-07-04 21:23:50.195748 [DEBUG] mod_spandsp_fax.c:531 Pages transferred: 0
2013-07-04 21:23:50.195748 [DEBUG] mod_spandsp_fax.c:533 Total fax pages:   0
2013-07-04 21:23:50.195748 [DEBUG] mod_spandsp_fax.c:534 Image resolution:  0x0
2013-07-04 21:23:50.195748 [DEBUG] mod_spandsp_fax.c:535 Transfer Rate:     14400
2013-07-04 21:23:50.195748 [DEBUG] mod_spandsp_fax.c:537 ECM status         off
2013-07-04 21:23:50.195748 [DEBUG] mod_spandsp_fax.c:538 remote country:   
2013-07-04 21:23:50.195748 [DEBUG] mod_spandsp_fax.c:539 remote vendor:    
2013-07-04 21:23:50.195748 [DEBUG] mod_spandsp_fax.c:540 remote model:     
2013-07-04 21:23:50.195748 [DEBUG] mod_spandsp_fax.c:542 ==============================================================================
2013-07-04 21:23:50.195748 [DEBUG] mod_spandsp_fax.c:291 FLOW T.30 Changing from state B to CALL_FINISHED
2013-07-04 21:23:50.195748 [DEBUG] mod_spandsp_fax.c:291 FLOW T.30 Changing from phase E to CALL_FINISHED
2013-07-04 21:23:50.195748 [DEBUG] mod_spandsp_fax.c:291 FLOW FAX Set rx type 9
2013-07-04 21:23:50.195748 [DEBUG] mod_spandsp_fax.c:291 FLOW FAX FAX exchange complete
2013-07-04 21:23:50.195748 [DEBUG] mod_spandsp_fax.c:291 FLOW FAX Set tx type 9
2013-07-04 21:23:50.195748 [DEBUG] mod_spandsp_fax.c:291 FLOW FAX FAX exchange complete
2013-07-04 21:23:50.215748 [DEBUG] switch_core_codec.c:244 sofia/internal/sip:1006@10.2.2.159:8081 Restore previous codec PCMU:0.
2013-07-04 21:23:50.215748 [NOTICE] switch_core_state_machine.c:262 sofia/internal/sip:1006@10.2.2.159:8081 has executed the last dialplan instruction, hanging up.