[Freeswitch-users] fax files not saved

pbdlists at pinboard.com pbdlists at pinboard.com
Wed Dec 8 18:30:25 MSK 2010 

Hello!

I managed to solve most of my other problems sofar, but with this one I
have no clue at all. Has anybody an idea what is happening here?

 1 an inbound call to a number which is registered as fax (mod_spandsp)
 2 fax is detected and received according to the log (log entries see below)
 3 but _sometimes_ the tiff file is never written to disk (post processing
   script can't find it and it is really not there)

The destination filesystem has enough free space and free inodes,
permissions for writing are ok, the system is not under any kind of
heavy load, no other calls going on at the same time...

I don't have much data to test this (using www.freepopfax.com for
testing and they have a daily limit on faxes I can send), but it seems
to happen roughly for 30-50% of the incoming fax messages. From the same
fax provider it works fine one time, then not a couple of minutes later,
another couple of minutes later it may work again, all while nothing at
all is being changed on the freeswitch server. If it was something like
a dropped line or anything I could understand it, but the logs say
everything is ok and still _sometimes_ the fax is not written to disk...

Cheers,

Kurt

=======================================================================
entries in fax.conf.xml
=======================================================================
<configuration name="fax.conf" description="FAX application configuration">
    <settings>
        <param name="use-ecm"           value="true"/>
        <param name="verbose"           value="false"/>
        <param name="disable-v17"       value="false"/>
        <param name="ident"             value="SpanDSP Fax Ident"/>
        <param name="header"            value="SpanDSP Fax Header"/>

        <param name="spool-dir"         value="/tmp"/>
        <param name="file-prefix"       value="faxrx"/>
    </settings>
</configuration>


=======================================================================
the part from default.xml
=======================================================================
    <extension name="fax_receive">
        <condition field="destination_number" expression="^(1111)$">
            <action application="set" data="api_hangup_hook=system ${base_dir}/scripts/emailfax.sh $1 /tmp/FAX-${uuid}.tiff '\\\${fax_success}' '\\\${fax_result_code}' '\\\${fax_result_text}' '\\\${fax_local_station_id}' '\\\${fax_remote_station_id}' '\\\${fax_document_transferred_pages}' '\\\${fax_document_total_pages}' '\\\${fax__image_resolution}' '\\\${fax_image_size}' '\\\${fax_bad_rows}' '\\\${fax_transfer_rate}' '\\\${fax_v17_disabled}' '\\\${fax_ecm_requested}' '\\\${fax_filename}' "/>
            <action application="answer" />
            <action application="playback" data="silence_stream://2000"/>
<!--
            <action application="set" data="fax_enable_t38_request=true"/>
            <action application="set" data="fax_enable_t38=true"/>
-->
            <action application="set" data="fax_ident=+41xxxxxxxxx"/>
            <action application="set" data="fax_disable_v17=1"/>
            <action application="set" data="fax_verbose=0"/>
            <action application="rxfax" data="/tmp/FAX-${uuid}.tiff"/>
            <action application="hangup"/>
        </condition>
    </extension>


=======================================================================
log entry from an unsuccessful incoming fax:
=======================================================================

2010-12-07 23:33:51.157588 [DEBUG] sofia_glue.c:182 sofia/external/Anonymous at anonymous.invalid image media sdp:
v=0
o=FreeSWITCH 1291733462 1291733465 IN IP4 xxx.xxx.xxx.xxx
s=FreeSWITCH
c=IN IP4 xxx.xxx.xxx.xxx
t=0 0
m=image 31358 udptl t38
a=T38FaxVersion:0
a=T38MaxBitRate:9600
a=T38FaxFillBitRemoval
a=T38FaxRateManagement:transferredTCF
a=T38FaxMaxBuffer:2000
a=T38FaxMaxDatagram:400
a=T38FaxUdpEC:t38UDPRedundancy

2010-12-07 23:33:51.157588 [DEBUG] sofia.c:4597 Channel sofia/external/Anonymous at anonymous.invalid entering state [completed][200]
2010-12-07 23:33:51.157588 [DEBUG] sofia.c:4597 Channel sofia/external/Anonymous at anonymous.invalid entering state [ready][200]
2010-12-07 23:33:58.814979 [DEBUG] switch_rtp.c:2544 Correct ip/port confirmed.
2010-12-07 23:35:09.772752 [DEBUG] switch_channel.c:2457 (sofia/external/Anonymous at anonymous.invalid) Callstate Change ACTIVE -> HANGUP
2010-12-07 23:35:09.772752 [NOTICE] sofia.c:528 Hangup sofia/external/Anonymous at anonymous.invalid [CS_EXECUTE] [NORMAL_CLEARING]
2010-12-07 23:35:09.772752 [DEBUG] switch_channel.c:2473 Send signal sofia/external/Anonymous at anonymous.invalid [KILL]
2010-12-07 23:35:09.772752 [DEBUG] switch_core_session.c:1057 Send signal sofia/external/Anonymous at anonymous.invalid [BREAK]
2010-12-07 23:35:09.772752 [DEBUG] mod_spandsp_fax.c:323 ==============================================================================
2010-12-07 23:35:09.772752 [DEBUG] mod_spandsp_fax.c:329 Fax successfully received.
2010-12-07 23:35:09.772752 [DEBUG] mod_spandsp_fax.c:340 Remote station id: 
2010-12-07 23:35:09.772752 [DEBUG] mod_spandsp_fax.c:341 Local station id:  +41xxxxxxxxx
2010-12-07 23:35:09.772752 [DEBUG] mod_spandsp_fax.c:342 Pages transferred: 2
2010-12-07 23:35:09.772752 [DEBUG] mod_spandsp_fax.c:344 Total fax pages:   2
2010-12-07 23:35:09.772752 [DEBUG] mod_spandsp_fax.c:345 Image resolution:  8031x7700
2010-12-07 23:35:09.772752 [DEBUG] mod_spandsp_fax.c:346 Transfer Rate:     9600
2010-12-07 23:35:09.772752 [DEBUG] mod_spandsp_fax.c:348 ECM status         on
2010-12-07 23:35:09.772752 [DEBUG] mod_spandsp_fax.c:349 remote country:   
2010-12-07 23:35:09.772752 [DEBUG] mod_spandsp_fax.c:350 remote vendor:    
2010-12-07 23:35:09.772752 [DEBUG] mod_spandsp_fax.c:351 remote model:     
2010-12-07 23:35:09.772752 [DEBUG] mod_spandsp_fax.c:353 ==============================================================================
2010-12-07 23:35:09.772752 [DEBUG] switch_core_codec.c:141 sofia/external/Anonymous at anonymous.invalid Restore previous codec PCMA:8.
2010-12-07 23:35:09.772752 [DEBUG] switch_core_session.c:1933 sofia/external/Anonymous at anonymous.invalid skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2010-12-07 23:35:09.772752 [DEBUG] switch_core_state_machine.c:366 (sofia/external/Anonymous at anonymous.invalid) State EXECUTE going to sleep
2010-12-07 23:35:09.772752 [DEBUG] switch_core_state_machine.c:320 (sofia/external/Anonymous at anonymous.invalid) Running State Change CS_HANGUP
2010-12-07 23:35:09.772752 [DEBUG] switch_core_state_machine.c:553 (sofia/external/Anonymous at anonymous.invalid) State HANGUP
2010-12-07 23:35:09.772752 [DEBUG] mod_sofia.c:453 sofia/external/Anonymous at anonymous.invalid Overriding SIP cause 480 with 200 from the other leg
2010-12-07 23:35:09.772752 [DEBUG] mod_sofia.c:459 Channel sofia/external/Anonymous at anonymous.invalid hanging up, cause: NORMAL_CLEARING
2010-12-07 23:35:09.821455 [DEBUG] switch_core_state_machine.c:46 sofia/external/Anonymous at anonymous.invalid Standard HANGUP, cause: NORMAL_CLEARING
2010-12-07 23:35:09.821455 [DEBUG] switch_core_state_machine.c:553 (sofia/external/Anonymous at anonymous.invalid) State HANGUP going to sleep
2010-12-07 23:35:09.822803 [NOTICE] mod_commands.c:4187 Executing command: /usr/local/freeswitch/scripts/emailfax.sh 1111 /tmp/FAX-6bf84d88-025a-11e0-b236-7391745d448b.tiff '1' '0' 'OK' '+41xxxxxxxxx' '' '2' '2
' '' '0' '0' '9600' '1' '1' '/tmp/FAX-6bf84d88-025a-11e0-b236-7391745d448b.tiff'
2010-12-07 23:35:10.076623 [DEBUG] switch_core_state_machine.c:488 Hangup Command with no Session system(/usr/local/freeswitch/scripts/emailfax.sh 1111 /tmp/FAX-6bf84d88-025a-11e0-b236-7391745d448b.tiff '1' '0'
 'OK' '+41xxxxxxxxx' '' '2' '2' '' '0' '0' '9600' '1' '1' '/tmp/FAX-6bf84d88-025a-11e0-b236-7391745d448b.tiff' ):
+OK


=======================================================================
log entry from a successful incoming fax:
=======================================================================

2010-12-07 23:54:59.082301 [DEBUG] sofia_glue.c:182 sofia/external/Anonymous at anonymous.invalid image media sdp:
v=0
o=FreeSWITCH 1291735801 1291735804 IN IP4 xxx.xxx.xxx.xxx
s=FreeSWITCH
c=IN IP4 xxx.xxx.xxx.xxx
t=0 0
m=image 30280 udptl t38
a=T38FaxVersion:0
a=T38MaxBitRate:9600
a=T38FaxFillBitRemoval
a=T38FaxRateManagement:transferredTCF
a=T38FaxMaxBuffer:2000
a=T38FaxMaxDatagram:400
a=T38FaxUdpEC:t38UDPRedundancy

2010-12-07 23:54:59.082301 [DEBUG] sofia.c:4597 Channel sofia/external/Anonymous at anonymous.invalid entering state [completed][200]
2010-12-07 23:54:59.100811 [DEBUG] sofia.c:4597 Channel sofia/external/Anonymous at anonymous.invalid entering state [ready][200]
2010-12-07 23:55:06.783427 [DEBUG] switch_rtp.c:2544 Correct ip/port confirmed.
2010-12-07 23:55:31.563208 [DEBUG] mod_spandsp_fax.c:323 ==============================================================================
2010-12-07 23:55:31.563208 [DEBUG] mod_spandsp_fax.c:329 Fax successfully received.
2010-12-07 23:55:31.563208 [DEBUG] mod_spandsp_fax.c:340 Remote station id: 
2010-12-07 23:55:31.563208 [DEBUG] mod_spandsp_fax.c:341 Local station id:  +41xxxxxxxxx
2010-12-07 23:55:31.563208 [DEBUG] mod_spandsp_fax.c:342 Pages transferred: 1
2010-12-07 23:55:31.563208 [DEBUG] mod_spandsp_fax.c:344 Total fax pages:   1
2010-12-07 23:55:31.563208 [DEBUG] mod_spandsp_fax.c:345 Image resolution:  8031x7700
2010-12-07 23:55:31.563208 [DEBUG] mod_spandsp_fax.c:346 Transfer Rate:     9600
2010-12-07 23:55:31.563208 [DEBUG] mod_spandsp_fax.c:348 ECM status         on
2010-12-07 23:55:31.563208 [DEBUG] mod_spandsp_fax.c:349 remote country:   
2010-12-07 23:55:31.563208 [DEBUG] mod_spandsp_fax.c:350 remote vendor:    
2010-12-07 23:55:31.563208 [DEBUG] mod_spandsp_fax.c:351 remote model:     
2010-12-07 23:55:31.563208 [DEBUG] mod_spandsp_fax.c:353 ==============================================================================
2010-12-07 23:55:31.616038 [DEBUG] switch_channel.c:2457 (sofia/external/Anonymous at anonymous.invalid) Callstate Change ACTIVE -> HANGUP
2010-12-07 23:55:31.616038 [NOTICE] sofia.c:528 Hangup sofia/external/Anonymous at anonymous.invalid [CS_EXECUTE] [NORMAL_CLEARING]
2010-12-07 23:55:31.616038 [DEBUG] switch_channel.c:2473 Send signal sofia/external/Anonymous at anonymous.invalid [KILL]
2010-12-07 23:55:31.616038 [DEBUG] switch_core_session.c:1057 Send signal sofia/external/Anonymous at anonymous.invalid [BREAK]
2010-12-07 23:55:31.617356 [DEBUG] switch_core_codec.c:141 sofia/external/Anonymous at anonymous.invalid Restore previous codec PCMA:8.
2010-12-07 23:55:31.617356 [DEBUG] switch_core_session.c:1933 sofia/external/Anonymous at anonymous.invalid skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
2010-12-07 23:55:31.617356 [DEBUG] switch_core_state_machine.c:366 (sofia/external/Anonymous at anonymous.invalid) State EXECUTE going to sleep
2010-12-07 23:55:31.617356 [DEBUG] switch_core_state_machine.c:320 (sofia/external/Anonymous at anonymous.invalid) Running State Change CS_HANGUP
2010-12-07 23:55:31.618542 [DEBUG] switch_core_state_machine.c:553 (sofia/external/Anonymous at anonymous.invalid) State HANGUP
2010-12-07 23:55:31.618542 [DEBUG] mod_sofia.c:453 sofia/external/Anonymous at anonymous.invalid Overriding SIP cause 480 with 200 from the other leg
2010-12-07 23:55:31.618542 [DEBUG] mod_sofia.c:459 Channel sofia/external/Anonymous at anonymous.invalid hanging up, cause: NORMAL_CLEARING
2010-12-07 23:55:31.721993 [DEBUG] switch_core_state_machine.c:46 sofia/external/Anonymous at anonymous.invalid Standard HANGUP, cause: NORMAL_CLEARING
2010-12-07 23:55:31.721993 [DEBUG] switch_core_state_machine.c:553 (sofia/external/Anonymous at anonymous.invalid) State HANGUP going to sleep
2010-12-07 23:55:31.721993 [NOTICE] mod_commands.c:4187 Executing command: /usr/local/freeswitch/scripts/emailfax.sh 1111 /tmp/FAX-5bd5649c-025d-11e0-b242-7391745d448b.tiff '1' '0' 'OK' '+41xxxxxxxxx' '' '1' '1' '' '0' '0' '9600' '1' '1' '/tmp/FAX-5bd5649c-025d-11e0-b242-7391745d448b.tiff'
2010-12-07 23:55:31.904090 [DEBUG] switch_core_state_machine.c:488 Hangup Command with no Session system(/usr/local/freeswitch/scripts/emailfax.sh 1111 /tmp/FAX-5bd5649c-025d-11e0-b242-7391745d448b.tiff '1' '0' 'OK' '+41xxxxxxxxx' '' '1' '1' '' '0' '0' '9600' '1' '1' '/tmp/FAX-5bd5649c-025d-11e0-b242-7391745d448b.tiff' ):
+OK

More information about the FreeSWITCH-users mailing list