[asterisk-users] Asterisk - Spandsp Fax not working?

Fri Sep 28 00:48:11 CDT 2007

On 9/27/07, Jonn R Taylor <jonnt at taylortelephone.com> wrote:
>
> marco britannio wrote:
> > Hi all,
> > I'm trying to setup an asterisk based fax receiving machine.
> > i'm using asterisk 1.2.18 and app_rxfax with spandsp 0.0.4pre9
> > I have no problems with a modem-fax, but with the fax machines i have
> > tried almost every fax fails, both in sending and receive.
> > the machines are sending a receiving a lot of faxes every day and
> > working well, so i think the problem is on the spandsp side.
> > i have tried almost every spandsp version from 0.0.2 to the current one,
> > both with and without ECM, but without luck.
> > has anybody succeeded in receiving faxes with asterisk app_rxfax and
> > spandsp?
> >
> > I'm noticicing a lot of different behaviours: sending w ECM gave me an
> > OK, and the second half of the page was missing, other faxes fail with
> > Sep 26 17:26:18 DEBUG[4741] app_rxfax.c:
> >
> ==============================================================================
> > Sep 26 17:26:18 DEBUG[4741] app_rxfax.c: Fax receive not successful -
> > result (11) Unexpected message received.
> > Sep 26 17:26:18 DEBUG[4741] app_rxfax.c:
> >
> ==============================================================================
> >
> >
> > can anybody help me?
> > thank you in advance,
> >
> >
> > marco
> >
> >
> > ------------------------------------------------------------------------
> >
> > _______________________________________________
> >
> > Sign up now for AstriCon 2007!  September 25-28th.
> http://www.astricon.net/
> >
> > --Bandwidth and Colocation Provided by http://www.api-digital.com--
> >
> > asterisk-users mailing list
> > To UNSUBSCRIBE or update options visit:
> >    http://lists.digium.com/mailman/listinfo/asterisk-users
>
> Marco,
>
> First off, do not use any version over 0.0.3. I am using 0.0.3 on centos
> 4.5, asterisk 1.2.24 and freepbx 2.3 and it is working very well. One
> very important thing to keep in mind is that faxing over voip will only
> work reliably with ulaw or alaw and your internet connection MUST be
> able to sustain a constant data stream with low jitter. If your
> interested I have a shell script to install asterisk 1.2.24 and
> freepbx-2.3 with rxfax and txfax on centos 4 and working on centos 5.
>
> Jonn
>
> http://jonnt.users.taylortelephone.com/asterisk/centos-asterisk-install.sh
> and hylafax / iaxmodem
> http://jonnt.users.taylortelephone.com/asterisk/
>
>
> _______________________________________________
>
> Sign up now for AstriCon 2007!  September 25-28th.
> http://www.astricon.net/
>
> --Bandwidth and Colocation Provided by http://www.api-digital.com--
>
> asterisk-users mailing list
> To UNSUBSCRIBE or update options visit:
>    http://lists.digium.com/mailman/listinfo/asterisk-users
>

hi john,
thank you for your reply.
I've installed the configuration that you suggested (asterisk 1.2.24,
spandsp 0.0.3), but with no luck: I still cannot receive faxes.
Please note that I am not using T38: I am receiving faxes with app_rxfax and
storing them into a dir on the machine.
I have attached an isdn trunk to the card and I am trying to receive the
faxes straight from the trunk.
When receiving faxes without ecm, the sending fax machine completes with an
error, and this is the log I have in asterisk:
 Sep 27 11:54:46 DEBUG[8783] app_rxfax.c: FLOW FAX Set rx type 0
Sep 27 11:54:46 DEBUG[8783] app_rxfax.c: FLOW FAX Set tx type 4
Sep 27 11:54:48 DEBUG[8783] app_rxfax.c: FLOW FAX Set rx type 4
Sep 27 11:54:48 DEBUG[8783] app_rxfax.c: FLOW FAX Set tx type 0
Sep 27 11:54:50 DEBUG[8783] app_rxfax.c: FLOW FAX Set rx type 8
Sep 27 11:54:50 DEBUG[8783] app_rxfax.c: FLOW FAX Set tx type 0
Sep 27 11:54:50 DEBUG[8783] app_rxfax.c: FLOW FAX Switching from V.29 + V.21to
V.29 (-15.04dBm0)
Sep 27 11:54:52 DEBUG[8783] app_rxfax.c: FLOW FAX Set rx type 0
Sep 27 11:54:52 DEBUG[8783] app_rxfax.c: FLOW FAX Set tx type 4
Sep 27 11:54:53 DEBUG[8783] app_rxfax.c: FLOW FAX Set rx type 8
Sep 27 11:54:53 DEBUG[8783] app_rxfax.c: FLOW FAX Set tx type 0
Sep 27 11:54:53 DEBUG[8783] app_rxfax.c: FLOW FAX Switching from V.29 + V.21to
V.29 (-17.87dBm0)
Sep 27 11:55:17 DEBUG[8783] app_rxfax.c: FLOW FAX Set rx type 4
Sep 27 11:55:17 DEBUG[8783] app_rxfax.c: FLOW FAX Set tx type 0
Sep 27 11:55:19 DEBUG[8783] app_rxfax.c:
==============================================================================
Sep 27 11:55:19 DEBUG[8783] app_rxfax.c: Pages transferred:  0
Sep 27 11:55:19 DEBUG[8783] app_rxfax.c: Image size:         1728 x 540
Sep 27 11:55:19 DEBUG[8783] app_rxfax.c: Image resolution    8037 x 3850
Sep 27 11:55:19 DEBUG[8783] app_rxfax.c: Transfer Rate:      9600
Sep 27 11:55:19 DEBUG[8783] app_rxfax.c: Bad rows            50
Sep 27 11:55:19 DEBUG[8783] app_rxfax.c: Longest bad row run 10
Sep 27 11:55:19 DEBUG[8783] app_rxfax.c: Compression type    2
Sep 27 11:55:19 DEBUG[8783] app_rxfax.c: Image size (bytes)  116841
Sep 27 11:55:19 DEBUG[8783] app_rxfax.c:
==============================================================================
Sep 27 11:55:19 DEBUG[8783] app_rxfax.c: FLOW FAX Set rx type 0
Sep 27 11:55:19 DEBUG[8783] app_rxfax.c: FLOW FAX Set tx type 4
Sep 27 11:55:20 DEBUG[8783] app_rxfax.c: FLOW FAX Set rx type 0
Sep 27 11:55:20 DEBUG[8783] app_rxfax.c: FLOW FAX Set tx type 1
Sep 27 11:55:20 DEBUG[8783] app_rxfax.c:
==============================================================================
Sep 27 11:55:20 DEBUG[8783] app_rxfax.c: Fax successfully received.
Sep 27 11:55:20 DEBUG[8783] app_rxfax.c: Remote station id: 0103747861
Sep 27 11:55:20 DEBUG[8783] app_rxfax.c: Local station id:
Sep 27 11:55:20 DEBUG[8783] app_rxfax.c: Pages transferred: 0
Sep 27 11:55:20 DEBUG[8783] app_rxfax.c: Image resolution:  8037 x 3850
Sep 27 11:55:20 DEBUG[8783] app_rxfax.c: Transfer Rate:     9600
Sep 27 11:55:20 DEBUG[8783] app_rxfax.c:
==============================================================================
Sep 27 11:55:20 DEBUG[8783] app_rxfax.c: FLOW FAX Set rx type 13
Sep 27 11:55:20 DEBUG[8783] app_rxfax.c: FLOW FAX FAX exchange complete
Sep 27 11:55:20 DEBUG[8783] app_rxfax.c: FLOW FAX Set tx type 13
Sep 27 11:55:20 DEBUG[8783] app_rxfax.c: FLOW FAX FAX exchange complete

although it states successfully received, it creates a tiff 8 bytes long.

A test enabling ECM gives me the following log:

after negotiation it starts receiving frames, then, after frame 5:
Sep 27 12:13:08 DEBUG[13611] app_rxfax.c: FLOW T.30 Storing ECM frame 5
Sep 27 12:13:08 DEBUG[13611] app_rxfax.c: FLOW T.30 HDLC framing OK in state
10
Sep 27 12:13:08 DEBUG[13611] app_rxfax.c: FLOW T.30 HDLC framing OK in state
10
Sep 27 12:13:08 DEBUG[13611] app_rxfax.c: FLOW T.30 HDLC framing OK in state
10
Sep 27 12:13:08 DEBUG[13611] app_rxfax.c: FLOW T.30 HDLC framing OK in state
10
Sep 27 12:13:08 DEBUG[13611] app_rxfax.c: FLOW T.30 HDLC framing OK in state
10
Sep 27 12:13:08 DEBUG[13611] app_rxfax.c: FLOW T.30 HDLC framing OK in state
10
Sep 27 12:13:08 DEBUG[13611] app_rxfax.c: FLOW T.30 HDLC framing OK in state
10
Sep 27 12:13:08 DEBUG[13611] app_rxfax.c: FLOW T.30 HDLC framing OK in state
10
Sep 27 12:13:08 DEBUG[13611] app_rxfax.c: FLOW T.30 HDLC framing OK in state
10
Sep 27 12:13:08 DEBUG[13611] app_rxfax.c: FLOW T.30 HDLC framing OK in state
10
Sep 27 12:13:08 DEBUG[13611] app_rxfax.c: FLOW T.30 HDLC framing OK in state
10
Sep 27 12:13:08 DEBUG[13611] app_rxfax.c: FLOW T.30 HDLC framing OK in state
10
Sep 27 12:13:08 DEBUG[13611] app_rxfax.c: FLOW T.30 HDLC framing OK in state
10
Sep 27 12:13:08 DEBUG[13611] app_rxfax.c: FLOW T.30 HDLC framing OK in state
10
Sep 27 12:13:08 DEBUG[13611] app_rxfax.c: FLOW T.30 HDLC framing OK in state
10
Sep 27 12:13:09 DEBUG[13611] app_rxfax.c: FLOW T.30 Rx:  FCD without final
frame tag
Sep 27 12:13:09 DEBUG[13611] app_rxfax.c: FLOW T.30 Rx:  ff 03 06 0a 06 e1
45 37 ec a6 db 1c 08 1f f1 67 [...]
Sep 27 12:13:09 DEBUG[13611] app_rxfax.c: FLOW T.30 Storing image frame 10,
length 256
Sep 27 12:13:09 DEBUG[13611] app_rxfax.c: FLOW T.30 Storing ECM frame 10

then it receives all the frames to frame 32, and then

Sep 27 12:13:14 DEBUG[13611] app_rxfax.c: FLOW T.30 Storing ECM frame 32
Sep 27 12:13:14 DEBUG[13611] app_rxfax.c: FLOW T.30 HDLC framing OK in state
10
Sep 27 12:13:14 DEBUG[13611] app_rxfax.c: FLOW T.30 HDLC framing OK in state
10
Sep 27 12:13:14 DEBUG[13611] app_rxfax.c: FLOW T.30 HDLC framing OK in state
10
[... more and more HDLC framing OK in state 10]
Sep 27 12:13:26 DEBUG[13611] app_rxfax.c: FLOW T.30 HDLC carrier up in state
10
Sep 27 12:13:26 DEBUG[13611] app_rxfax.c: FLOW V.29 Coarse carrier frequency
1850.18
Sep 27 12:13:26 DEBUG[13611] app_rxfax.c: FLOW V.29 Training failed
(sequence failed)
Sep 27 12:13:26 DEBUG[13611] app_rxfax.c: FLOW T.30 HDLC carrier training
failed in state 10
Sep 27 12:13:28 DEBUG[13611] app_rxfax.c: FLOW T.30 HDLC carrier down in
state 10
Sep 27 12:13:30 DEBUG[13611] app_rxfax.c: FLOW T.30 HDLC carrier up in state
10
Sep 27 12:13:31 DEBUG[13611] app_rxfax.c: FLOW V.29 Coarse carrier frequency
1658.12
Sep 27 12:13:31 DEBUG[13611] app_rxfax.c: FLOW V.29 Training failed
(sequence failed)
Sep 27 12:13:31 DEBUG[13611] app_rxfax.c: FLOW T.30 HDLC carrier training
failed in state 10
Sep 27 12:13:32 DEBUG[13611] app_rxfax.c: FLOW T.30 HDLC carrier down in
state 10
Sep 27 12:13:35 DEBUG[13611] app_rxfax.c: FLOW T.30 HDLC carrier up in state
10
Sep 27 12:13:35 DEBUG[13611] app_rxfax.c: FLOW V.29 Coarse carrier frequency
1657.37
Sep 27 12:13:35 DEBUG[13611] app_rxfax.c: FLOW V.29 Training failed
(sequence failed)
Sep 27 12:13:35 DEBUG[13611] app_rxfax.c: FLOW T.30 HDLC carrier training
failed in state 10
Sep 27 12:13:36 DEBUG[13611] app_rxfax.c: FLOW T.30 HDLC carrier down in
state 10
Sep 27 12:13:39 DEBUG[13611] app_rxfax.c: FLOW T.30 HDLC carrier up in state
10
Sep 27 12:13:39 DEBUG[13611] app_rxfax.c: FLOW V.29 Coarse carrier frequency
1656.47
Sep 27 12:13:39 DEBUG[13611] app_rxfax.c: FLOW V.29 Training failed
(sequence failed)
Sep 27 12:13:39 DEBUG[13611] app_rxfax.c: FLOW T.30 HDLC carrier training
failed in state 10
Sep 27 12:13:40 DEBUG[13611] app_rxfax.c: FLOW T.30 HDLC carrier down in
state 10
Sep 27 12:13:40 DEBUG[13611] app_rxfax.c: FLOW T.30 HDLC carrier up in state
10
Sep 27 12:13:40 DEBUG[13611] app_rxfax.c: FLOW T.30 HDLC carrier down in
state 10
Sep 27 12:13:41 DEBUG[13611] app_rxfax.c: FLOW T.30 HDLC carrier up in state
10
Sep 27 12:13:41 DEBUG[13611] app_rxfax.c: FLOW V.29 Coarse carrier frequency
1756.76
Sep 27 12:13:41 DEBUG[13611] app_rxfax.c: FLOW V.29 Training failed
(sequence failed)
Sep 27 12:13:41 DEBUG[13611] app_rxfax.c: FLOW T.30 HDLC carrier training
failed in state 10
Sep 27 12:13:41 DEBUG[13611] app_rxfax.c: FLOW T.30 HDLC carrier down in
state 10

at this point the sending fax machine hangs up and the call failes.
it seems to me that there is some kind of problem (with T.30?) in spandsp,
but I do not understand what it is.

regards,

marco
-------------- next part --------------
An HTML attachment was scrubbed...
URL: http://lists.digium.com/pipermail/asterisk-users/attachments/20070928/3175d3ea/attachment.htm 