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@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@anonymous.invalid entering state [completed][200] 2010-12-07 23:33:51.157588 [DEBUG] sofia.c:4597 Channel sofia/external/Anonymous@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@anonymous.invalid) Callstate Change ACTIVE -> HANGUP 2010-12-07 23:35:09.772752 [NOTICE] sofia.c:528 Hangup sofia/external/Anonymous@anonymous.invalid [CS_EXECUTE] [NORMAL_CLEARING] 2010-12-07 23:35:09.772752 [DEBUG] switch_channel.c:2473 Send signal sofia/external/Anonymous@anonymous.invalid [KILL] 2010-12-07 23:35:09.772752 [DEBUG] switch_core_session.c:1057 Send signal sofia/external/Anonymous@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@anonymous.invalid Restore previous codec PCMA:8. 2010-12-07 23:35:09.772752 [DEBUG] switch_core_session.c:1933 sofia/external/Anonymous@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@anonymous.invalid) State EXECUTE going to sleep 2010-12-07 23:35:09.772752 [DEBUG] switch_core_state_machine.c:320 (sofia/external/Anonymous@anonymous.invalid) Running State Change CS_HANGUP 2010-12-07 23:35:09.772752 [DEBUG] switch_core_state_machine.c:553 (sofia/external/Anonymous@anonymous.invalid) State HANGUP 2010-12-07 23:35:09.772752 [DEBUG] mod_sofia.c:453 sofia/external/Anonymous@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@anonymous.invalid hanging up, cause: NORMAL_CLEARING 2010-12-07 23:35:09.821455 [DEBUG] switch_core_state_machine.c:46 sofia/external/Anonymous@anonymous.invalid Standard HANGUP, cause: NORMAL_CLEARING 2010-12-07 23:35:09.821455 [DEBUG] switch_core_state_machine.c:553 (sofia/external/Anonymous@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@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@anonymous.invalid entering state [completed][200] 2010-12-07 23:54:59.100811 [DEBUG] sofia.c:4597 Channel sofia/external/Anonymous@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@anonymous.invalid) Callstate Change ACTIVE -> HANGUP 2010-12-07 23:55:31.616038 [NOTICE] sofia.c:528 Hangup sofia/external/Anonymous@anonymous.invalid [CS_EXECUTE] [NORMAL_CLEARING] 2010-12-07 23:55:31.616038 [DEBUG] switch_channel.c:2473 Send signal sofia/external/Anonymous@anonymous.invalid [KILL] 2010-12-07 23:55:31.616038 [DEBUG] switch_core_session.c:1057 Send signal sofia/external/Anonymous@anonymous.invalid [BREAK] 2010-12-07 23:55:31.617356 [DEBUG] switch_core_codec.c:141 sofia/external/Anonymous@anonymous.invalid Restore previous codec PCMA:8. 2010-12-07 23:55:31.617356 [DEBUG] switch_core_session.c:1933 sofia/external/Anonymous@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@anonymous.invalid) State EXECUTE going to sleep 2010-12-07 23:55:31.617356 [DEBUG] switch_core_state_machine.c:320 (sofia/external/Anonymous@anonymous.invalid) Running State Change CS_HANGUP 2010-12-07 23:55:31.618542 [DEBUG] switch_core_state_machine.c:553 (sofia/external/Anonymous@anonymous.invalid) State HANGUP 2010-12-07 23:55:31.618542 [DEBUG] mod_sofia.c:453 sofia/external/Anonymous@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@anonymous.invalid hanging up, cause: NORMAL_CLEARING 2010-12-07 23:55:31.721993 [DEBUG] switch_core_state_machine.c:46 sofia/external/Anonymous@anonymous.invalid Standard HANGUP, cause: NORMAL_CLEARING 2010-12-07 23:55:31.721993 [DEBUG] switch_core_state_machine.c:553 (sofia/external/Anonymous@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