[Freeswitch-users] mod_fax receives fax to file but logs error msg

Tue Feb 16 17:01:45 PST 2010

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 at 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

-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20100216/5d51c234/attachment-0002.html 