[Asterisk-Users] spandsp 0.0.2pre25

[Jesse Guardiani]

Hello,

Is anyone successfully using spandsp 0.0.2pre25 with either asterisk 1.0.x or
1.2.4? I've built a Gentoo ebuild for this version of spandsp and app_rtxfax,
and it builds, but I'm not having any luck getting it working. 99% of my test
faxes fail. Reverting to 0.0.2pre20 yields a much higher success rate.

I've bumped the console debugging level in logger.conf to include debug and
verbose, as well as the defaults.

I'm running libtiff-3.7.1, because Mr. Underwood's site recommends it, even
though it's a vulnerable version of libtiff.

I'm faxing from a working, production asterisk 1.0.9 + spandsp 0.0.2pre20 system
to a testing asterisk 1.0.10 + spandsp 0.0.2pre25 system, and 1 of 3 things
usually happens:

1.) The fax goes through (very rare in testing)
2.) The fax loops indefinitely like this:

Feb 19 11:46:04 DEBUG[5089]: chan_zap.c:4059 zt_read: DTMF digit: f on Zap/1-1
Feb 19 11:46:04 DEBUG[5089]: chan_zap.c:4101 zt_read: Fax already handled
Feb 19 11:46:07 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier up
Feb 19 11:46:07 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier down
Feb 19 11:46:07 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier up
Feb 19 11:46:07 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier down
Feb 19 11:46:07 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier up
Feb 19 11:46:07 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier down
Feb 19 11:46:07 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier up
Feb 19 11:46:07 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier down
Feb 19 11:46:07 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier up
Feb 19 11:46:07 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier down
Feb 19 11:46:07 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier up
Feb 19 11:46:07 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier down
Feb 19 11:46:07 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier up
Feb 19 11:46:07 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier down
Feb 19 11:46:07 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier up
Feb 19 11:46:07 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier down
Feb 19 11:46:07 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier up
Feb 19 11:46:07 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier down
Feb 19 11:46:07 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier up
Feb 19 11:46:07 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier down
Feb 19 11:46:07 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier up
Feb 19 11:46:07 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier down
Feb 19 11:46:07 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier up
Feb 19 11:46:07 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier down
Feb 19 11:46:07 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier up
Feb 19 11:46:07 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier down
Feb 19 11:46:07 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier up
Feb 19 11:46:07 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier down
Feb 19 11:46:07 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier up
Feb 19 11:46:07 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier down
Feb 19 11:46:07 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier up
Feb 19 11:46:07 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier down
Feb 19 11:46:07 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier up
Feb 19 11:46:07 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier down
Feb 19 11:46:07 DEBUG[5089]: chan_zap.c:4059 zt_read: DTMF digit: f on Zap/1-1
Feb 19 11:46:07 DEBUG[5089]: chan_zap.c:4101 zt_read: Fax already handled
Feb 19 11:46:10 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier up
Feb 19 11:46:10 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier down
Feb 19 11:46:10 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier up
Feb 19 11:46:10 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier down
Feb 19 11:46:10 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier up
Feb 19 11:46:10 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier down
Feb 19 11:46:10 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier up
Feb 19 11:46:10 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier down
Feb 19 11:46:10 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier up
Feb 19 11:46:10 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier down
Feb 19 11:46:10 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier up
Feb 19 11:46:10 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier down
Feb 19 11:46:10 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier up
Feb 19 11:46:10 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier down
Feb 19 11:46:10 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier up
Feb 19 11:46:10 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier down
Feb 19 11:46:10 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier up
Feb 19 11:46:10 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier down
Feb 19 11:46:10 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier up
Feb 19 11:46:10 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier down
Feb 19 11:46:10 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier up
Feb 19 11:46:10 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier down
Feb 19 11:46:10 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier up
Feb 19 11:46:10 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier down
Feb 19 11:46:11 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier up
Feb 19 11:46:11 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier down
Feb 19 11:46:11 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier up
Feb 19 11:46:11 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier down
Feb 19 11:46:11 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier up
Feb 19 11:46:11 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier down
Feb 19 11:46:11 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier up
Feb 19 11:46:11 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier down
Feb 19 11:46:11 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier up
Feb 19 11:46:11 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier down
Feb 19 11:46:11 DEBUG[5089]: chan_zap.c:4059 zt_read: DTMF digit: f on Zap/1-1
Feb 19 11:46:11 DEBUG[5089]: chan_zap.c:4101 zt_read: Fax already handled

3.) The fax loops indefinitely like this:

Feb 19 11:48:00 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW DIS with final
frame tag
Feb 19 11:48:00 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW In state 9
Feb 19 11:48:00 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW DIS nothing to
send [0]
Feb 19 11:48:00 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW Start receiving
document
Feb 19 11:48:00 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW Changed from
phase 3 to 4
Feb 19 11:48:00 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW >>> DIS:Feb 19
11:48:00 DEBUG[5089]: app_rxfax.c:70 span_message:  80Feb 19 11:48:00
DEBUG[5089]: app_rxfax.c:70 span_message:  00Feb 19 11:48:00 DEBUG[5089]:
app_rxfax.c:70 span_message:  ceFeb 19 11:48:00 DEBUG[5089]: app_rxfax.c:70
span_message:  f4Feb 19 11:48:00 DEBUG[5089]: app_rxfax.c:70 span_message: 
80Feb 19 11:48:00 DEBUG[5089]: app_rxfax.c:70 span_message:  80Feb 19 11:48:00
DEBUG[5089]: app_rxfax.c:70 span_message:  81Feb 19 11:48:00 DEBUG[5089]:
app_rxfax.c:70 span_message:  80Feb 19 11:48:00 DEBUG[5089]: app_rxfax.c:70
span_message:  80Feb 19 11:48:00 DEBUG[5089]: app_rxfax.c:70 span_message: 
80Feb 19 11:48:00 DEBUG[5089]: app_rxfax.c:70 span_message:  18Feb 19 11:48:00
DEBUG[5089]: app_rxfax.c:70 span_message:
Feb 19 11:48:02 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC underflow in
state 9
Feb 19 11:48:02 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW Changed from
phase 4 to 3
Feb 19 11:48:02 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier up
Feb 19 11:48:02 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC framing OK
Feb 19 11:48:04 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW <<< DIS:Feb 19
11:48:04 DEBUG[5089]: app_rxfax.c:70 span_message:  80Feb 19 11:48:04
DEBUG[5089]: app_rxfax.c:70 span_message:  00Feb 19 11:48:04 DEBUG[5089]:
app_rxfax.c:70 span_message:  ceFeb 19 11:48:04 DEBUG[5089]: app_rxfax.c:70
span_message:  f4Feb 19 11:48:04 DEBUG[5089]: app_rxfax.c:70 span_message: 
80Feb 19 11:48:04 DEBUG[5089]: app_rxfax.c:70 span_message:  80Feb 19 11:48:04
DEBUG[5089]: app_rxfax.c:70 span_message:  81Feb 19 11:48:04 DEBUG[5089]:
app_rxfax.c:70 span_message:  80Feb 19 11:48:04 DEBUG[5089]: app_rxfax.c:70
span_message:  80Feb 19 11:48:04 DEBUG[5089]: app_rxfax.c:70 span_message: 
80Feb 19 11:48:04 DEBUG[5089]: app_rxfax.c:70 span_message:  18Feb 19 11:48:04
DEBUG[5089]: app_rxfax.c:70 span_message:
Feb 19 11:48:04 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW DIS with final
frame tag
Feb 19 11:48:04 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW In state 9
Feb 19 11:48:04 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW DIS nothing to
send [0]
Feb 19 11:48:04 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW Start receiving
document
Feb 19 11:48:04 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW Changed from
phase 3 to 4
Feb 19 11:48:04 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW >>> DIS:Feb 19
11:48:04 DEBUG[5089]: app_rxfax.c:70 span_message:  80Feb 19 11:48:04
DEBUG[5089]: app_rxfax.c:70 span_message:  00Feb 19 11:48:04 DEBUG[5089]:
app_rxfax.c:70 span_message:  ceFeb 19 11:48:04 DEBUG[5089]: app_rxfax.c:70
span_message:  f4Feb 19 11:48:04 DEBUG[5089]: app_rxfax.c:70 span_message: 
80Feb 19 11:48:04 DEBUG[5089]: app_rxfax.c:70 span_message:  80Feb 19 11:48:04
DEBUG[5089]: app_rxfax.c:70 span_message:  81Feb 19 11:48:04 DEBUG[5089]:
app_rxfax.c:70 span_message:  80Feb 19 11:48:04 DEBUG[5089]: app_rxfax.c:70
span_message:  80Feb 19 11:48:04 DEBUG[5089]: app_rxfax.c:70 span_message: 
80Feb 19 11:48:04 DEBUG[5089]: app_rxfax.c:70 span_message:  18Feb 19 11:48:04
DEBUG[5089]: app_rxfax.c:70 span_message:
Feb 19 11:48:05 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC underflow in
state 9
Feb 19 11:48:05 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW Changed from
phase 4 to 3
Feb 19 11:48:05 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC carrier up
Feb 19 11:48:05 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW HDLC framing OK
Feb 19 11:48:07 DEBUG[5089]: app_rxfax.c:70 span_message: FLOW <<< DIS:Feb 19
11:48:07 DEBUG[5089]: app_rxfax.c:70 span_message:  80Feb 19 11:48:07
DEBUG[5089]: app_rxfax.c:70 span_message:  00Feb 19 11:48:07 DEBUG[5089]:
app_rxfax.c:70 span_message:  ceFeb 19 11:48:07 DEBUG[5089]: app_rxfax.c:70
span_message:  f4Feb 19 11:48:07 DEBUG[5089]: app_rxfax.c:70 span_message: 
80Feb 19 11:48:07 DEBUG[5089]: app_rxfax.c:70 span_message:  80Feb 19 11:48:07
DEBUG[5089]: app_rxfax.c:70 span_message:  81Feb 19 11:48:07 DEBUG[5089]:
app_rxfax.c:70 span_message:  80Feb 19 11:48:07 DEBUG[5089]: app_rxfax.c:70
span_message:  80Feb 19 11:48:07 DEBUG[5089]: app_rxfax.c:70 span_message: 
80Feb 19 11:48:07 DEBUG[5089]: app_rxfax.c:70 span_message:  18Feb 19 11:48:07 

Of these, the 3rd one seems most common.
So, is anyone using 0.0.2pre25 in production successfully, or should I just
stick with pre20?

Thanks!