[asterisk-users] rxfax, spandsp and lack of ecm

[Steve Davies]

On 9/14/06, Steve Underwood <steveu at coppice.org> wrote:
> Steve Davies wrote:
[snip]
> >
> > This looks pretty good I have to say - The ECM seems as if it may be a
> > little intolerant... On a fax machine where I got 100% success in the
> > past with 0.0.2, I am now getting "result (60) Disconnected after
> > permitted retries." on about every 4th page.
> >
> > Is the ECM tolerance level tuneable in spandsp, or is this
> > hard-defined in the standard? Is it just a matter of changing:
> >  #define MAX_MESSAGE_TRIES   3
>
> Your problem probably has nothing to do with tolerance. If an exchange
> doesn't succeed after 3 tries, it is unlikely to ever succeed. You are
> probably hitting a bug. It is new code. :-)  Can you enable debug with
> "|debug" on the command line to rxfax/txfax, and send me the log?
>
Already done this, and started sending hi-res faxes to make the
problem more evident. This log fails at the end of the second page -
It always seems to allow the communication to get to the end of the
page before failing:

Sep 13 17:14:23 DEBUG[17696]: Launching 'zapEC'
Sep 13 17:14:23 DEBUG[17696]: disabled echo cancellation on channel 4
Sep 13 17:14:23 DEBUG[17696]: Launching 'RxFAX'
Sep 13 17:14:23 DEBUG[17696]: Set channel Zap/4-1 to read format slin
Sep 13 17:14:23 DEBUG[17696]: Set channel Zap/4-1 to write format slin
Sep 13 17:14:25 DEBUG[17696]: FLOW FAX Set rx type 0
Sep 13 17:14:25 DEBUG[17696]: FLOW FAX Set tx type 4
Sep 13 17:14:27 DEBUG[17696]: FLOW FAX Set rx type 4
Sep 13 17:14:27 DEBUG[17696]: FLOW FAX Set tx type 0
Sep 13 17:14:29 DEBUG[17696]: FLOW FAX Set rx type 8
Sep 13 17:14:29 DEBUG[17696]: FLOW FAX Set tx type 0
Sep 13 17:14:30 DEBUG[17696]: FLOW FAX Switching from V.29 + V.21 to V.29
Sep 13 17:14:31 DEBUG[17696]: FLOW FAX Set rx type 0
Sep 13 17:14:31 DEBUG[17696]: FLOW FAX Set tx type 4
Sep 13 17:14:32 DEBUG[17696]: FLOW FAX Set rx type 8
Sep 13 17:14:32 DEBUG[17696]: FLOW FAX Set tx type 0
Sep 13 17:14:33 DEBUG[17696]: FLOW FAX Switching from V.29 + V.21 to V.29
Sep 13 17:17:59 DEBUG[17696]: FLOW FAX Set rx type 4
Sep 13 17:17:59 DEBUG[17696]: FLOW FAX Set tx type 0
Sep 13 17:18:01 DEBUG[17696]:
==============================================================================
Sep 13 17:18:01 DEBUG[17696]: Pages transferred:  0
Sep 13 17:18:01 DEBUG[17696]: Image size:         1728 x 4529
Sep 13 17:18:01 DEBUG[17696]: Image resolution    8037 x 15400
Sep 13 17:18:01 DEBUG[17696]: Transfer Rate:      9600
Sep 13 17:18:01 DEBUG[17696]: Bad rows            0
Sep 13 17:18:01 DEBUG[17696]: Longest bad row run 0
Sep 13 17:18:01 DEBUG[17696]: Compression type    1
Sep 13 17:18:01 DEBUG[17696]: Image size (bytes)  978264
Sep 13 17:18:01 DEBUG[17696]:
==============================================================================
Sep 13 17:18:01 DEBUG[17696]: FLOW FAX Set rx type 0
Sep 13 17:18:01 DEBUG[17696]: FLOW FAX Set tx type 4
Sep 13 17:18:02 DEBUG[17696]: FLOW FAX Set rx type 8
Sep 13 17:18:02 DEBUG[17696]: FLOW FAX Set tx type 0
Sep 13 17:18:09 DEBUG[17696]: FLOW FAX Set rx type 0
Sep 13 17:18:09 DEBUG[17696]: FLOW FAX Set tx type 4
Sep 13 17:18:10 DEBUG[17696]: FLOW FAX Set rx type 4
Sep 13 17:18:10 DEBUG[17696]: FLOW FAX Set tx type 0
Sep 13 17:18:14 DEBUG[17696]: FLOW FAX Set rx type 0
Sep 13 17:18:14 DEBUG[17696]: FLOW FAX Set tx type 4
Sep 13 17:18:15 DEBUG[17696]: FLOW FAX Set rx type 4
Sep 13 17:18:15 DEBUG[17696]: FLOW FAX Set tx type 0
Sep 13 17:18:19 DEBUG[17696]: FLOW FAX Set rx type 0
Sep 13 17:18:19 DEBUG[17696]: FLOW FAX Set tx type 4
Sep 13 17:18:20 DEBUG[17696]: FLOW FAX Set rx type 4
Sep 13 17:18:20 DEBUG[17696]: FLOW FAX Set tx type 0
Sep 13 17:18:24 DEBUG[17696]: FLOW FAX Set rx type 0
Sep 13 17:18:24 DEBUG[17696]: FLOW FAX Set tx type 4
Sep 13 17:18:25 DEBUG[17696]: FLOW FAX Set rx type 4
Sep 13 17:18:25 DEBUG[17696]: FLOW FAX Set tx type 0
Sep 13 17:22:16 DEBUG[17696]: FLOW FAX Set rx type 0
Sep 13 17:22:16 DEBUG[17696]: FLOW FAX Set tx type 4
Sep 13 17:22:17 DEBUG[17696]: FLOW FAX Set rx type 0
Sep 13 17:22:17 DEBUG[17696]: FLOW FAX Set tx type 1
Sep 13 17:22:17 DEBUG[17696]:
==============================================================================
Sep 13 17:22:17 DEBUG[17696]: Fax receive not successful - result (60)
Disconnected after permitted retries.
Sep 13 17:22:17 DEBUG[17696]:
==============================================================================
Sep 13 17:22:17 DEBUG[17696]: FLOW FAX Set rx type 13
Sep 13 17:22:17 DEBUG[17696]: FLOW FAX FAX exchange complete
Sep 13 17:22:17 DEBUG[17696]: FLOW FAX Set tx type 13
Sep 13 17:22:17 DEBUG[17696]: FLOW FAX FAX exchange complete
Sep 13 17:22:30 DEBUG[17696]: Got hangup


> > I also noticed that the page title in the TIFF does not appear to be
> > set for the last page received any more (I have not looked into this
> > at-all though, so it may be my environment).
>
> Do you mean the title line which spandsp can insert on each page? If so,
> I don't know how that can come out on any page with spandsp 0.0.2 or
> 0.0.3. I fixed a bug in that last night. :-\ The next snapshot should
> have that fixed.

:) I actually meant what I think is the TIFF page number field. I
checked again last night and found no change between 0.0.2 and 0.0.3,
I had just never noticed it before! It is a bug in our fax2mail code
which mis-interprets the 'tiffinfo' output...

Steve