I have been playing around with mod_fax and can successfully
receive a fax to file. However, while doing so mod_fax is logging an
error message. Does anybody know what this error means?
Dialplan: OpenZAP/2:1/1011 parsing
[default->REH_test_fax_receive] continue=false
Dialplan: OpenZAP/2:1/1011 Regex (PASS)
[REH_test_fax_receive] destination_number(1011) =~ /^1011$/ break=on-false
Dialplan: OpenZAP/2:1/1011 Action disable_ec()
Dialplan: OpenZAP/2:1/1011 Action answer()
Dialplan: OpenZAP/2:1/1011 Action
playback(silence_stream://2000)
Dialplan: OpenZAP/2:1/1011 Action
rxfax(/tmp/rxfax.tif)
Dialplan: OpenZAP/2:1/1011 Action hangup()
2010-02-16 16:27:08.542746 [DEBUG] switch_core_state_machine.c:122
(OpenZAP/2:1/1011) State Change CS_ROUTING -> CS_EXECUTE
2010-02-16 16:27:08.542746 [DEBUG]
switch_core_session.c:1019 Send signal OpenZAP/2:1/1011 [BREAK]
2010-02-16 16:27:08.542746 [DEBUG]
switch_core_state_machine.c:341 (OpenZAP/2:1/1011) State ROUTING going to sleep
2010-02-16 16:27:08.542746 [DEBUG]
switch_core_state_machine.c:314 (OpenZAP/2:1/1011) Running State Change
CS_EXECUTE
2010-02-16 16:27:08.542746 [DEBUG]
switch_core_state_machine.c:348 (OpenZAP/2:1/1011) State EXECUTE
2010-02-16 16:27:08.542746 [DEBUG] mod_openzap.c:434
OpenZAP/2:1/1011 CHANNEL EXECUTE
2010-02-16 16:27:08.542746 [DEBUG]
switch_core_state_machine.c:159 OpenZAP/2:1/1011 Standard EXECUTE
2010-02-16 16:27:08.542746 [DEBUG]
switch_core_session.c:1521 Application disable_ec Requires media! pre_answering
channel OpenZAP/2:1/1011
2010-02-16 16:27:08.542746 [DEBUG]
switch_core_session.c:1523 OpenZAP/2:1/1011 receive message [PROGRESS]
2010-02-16 16:27:08.542746 [DEBUG] mod_openzap.c:960
Changing state on 2:1 from IDLE to UP
2010-02-16 16:27:08.542746 [NOTICE]
mod_openzap.c:961 Channel [OpenZAP/2:1/1011] has been answered
2010-02-16 16:27:08.542746 [DEBUG]
switch_channel.c:182 OpenZAP/2:1/1011 receive message [AUDIO_SYNC]
2010-02-16 16:27:08.542746 [DEBUG] switch_core_session.c:634
Send signal OpenZAP/2:1/1011 [BREAK]
EXECUTE OpenZAP/2:1/1011 disable_ec()
2010-02-16 16:27:08.542746 [INFO] mod_openzap.c:2951
Echo Canceller Disabled
EXECUTE OpenZAP/2:1/1011 answer()
EXECUTE OpenZAP/2:1/1011
playback(silence_stream://2000)
2010-02-16 16:27:08.542746 [DEBUG]
switch_ivr_play_say.c:1162 Codec Activated L16@8000hz 1 channels 20ms
2010-02-16 16:27:08.542746 [DEBUG]
switch_core_io.c:652 OpenZAP/2:1/1011 receive message [TRANSCODING_NECESSARY]
2010-02-16 16:27:08.562747 [DEBUG]
ozmod_analog.c:450 Executing state handler on 2:1 for UP
2010-02-16 16:27:08.562747 [DEBUG]
mod_openzap.c:1463 got FXS sig [UP]
2010-02-16 16:27:10.522277 [DEBUG]
switch_ivr_play_say.c:1454 done playing file
EXECUTE OpenZAP/2:1/1011 rxfax(/tmp/rxfax.tif)
2010-02-16 16:27:10.522277 [DEBUG] mod_fax.c:591 Raw
read codec activation Success L16 20000
2010-02-16 16:27:10.522277 [DEBUG]
switch_core_codec.c:112 OpenZAP/2:1/1011 Push codec L16:10
2010-02-16 16:27:10.522277 [DEBUG] mod_fax.c:607 Raw
write codec activation Success L16
2010-02-16 16:27:10.522277 [DEBUG]
switch_channel.c:182 OpenZAP/2:1/1011 receive message [AUDIO_SYNC]
2010-02-16 16:27:10.542275 [DEBUG]
switch_core_io.c:234 OpenZAP/2:1/1011 receive message [TRANSCODING_NECESSARY]
2010-02-16 16:28:14.982013 [DEBUG]
ozmod_analog.c:788 EVENT [ONHOOK][2:1] STATE [UP]
2010-02-16 16:28:14.982013 [DEBUG]
ozmod_analog.c:824 Changing state on 2:1 from UP to DOWN
2010-02-16 16:28:14.992014 [DEBUG]
ozmod_analog.c:450 Executing state handler on 2:1 for DOWN
2010-02-16 16:28:14.992014 [DEBUG]
mod_openzap.c:1463 got FXS sig [STOP]
2010-02-16 16:28:14.992014 [NOTICE]
mod_openzap.c:1554 Hangup OpenZAP/2:1/1011 [CS_EXECUTE] [NORMAL_CLEARING]
2010-02-16 16:28:14.992014 [DEBUG] switch_channel.c:1976
Send signal OpenZAP/2:1/1011 [KILL]
2010-02-16
16:28:15.002011 [ERR] mod_fax.c:666 Cannot write frame [datalen: 320, samples:
160]
2010-02-16 16:28:15.002011 [DEBUG] mod_fax.c:167
==============================================================================
2010-02-16 16:28:15.002011 [DEBUG] mod_fax.c:174 Fax
successfully received.
2010-02-16 16:28:15.002011 [DEBUG] mod_fax.c:185
Remote station id: 206 742 3831
2010-02-16 16:28:15.002011 [DEBUG] mod_fax.c:186
Local station id: SpanDSP Fax Ident
2010-02-16 16:28:15.002011 [DEBUG] mod_fax.c:187
Pages transferred: 1
2010-02-16 16:28:15.002011 [DEBUG] mod_fax.c:189
Total fax pages: 1
2010-02-16 16:28:15.002011 [DEBUG] mod_fax.c:190
Image resolution: 8031x3850
2010-02-16 16:28:15.002011 [DEBUG] mod_fax.c:191
Transfer Rate: 9600
2010-02-16 16:28:15.002011 [DEBUG] mod_fax.c:193 ECM
status off
2010-02-16 16:28:15.002011 [DEBUG] mod_fax.c:194
remote country:
2010-02-16 16:28:15.002011 [DEBUG] mod_fax.c:195
remote vendor:
2010-02-16 16:28:15.002011 [DEBUG] mod_fax.c:196
remote model:
2010-02-16 16:28:15.002011 [DEBUG] mod_fax.c:198
==============================================================================
I traced the error message as coming from
switch_core_session_write_frame in src/switch_core_io.c which returns
SWITCH_STATUS_FALSE.
Thanks for any information,
Robert