[hylafax-users] Troubleshooting failure to train modems

[David Brodbeck <DavidB@xxxxxxxxxxxxxxxxxxx>]

I'm getting a lot of incoming fax failures due to failure to train modems.
What's odd is faxes from the same machine will sometimes work, sometimes
not.  I'm wondering if there are any settings I can tweak to improve the
situation.  A log of one of the failures is attached below.  I'm wondering
if I should increase Class1TCFMaxNonZero, since that seems to be the error
that's being flagged.

Mar 02 12:43:44.72: [ 7629]: SESSION BEGIN 000000171 17349952413
Mar 02 12:43:44.72: [ 7629]: HylaFAX (tm) Version 4.2.1
Mar 02 12:43:44.72: [ 7629]: <-- [4:ATA\r]
Mar 02 12:43:56.89: [ 7629]: --> [7:CONNECT]
Mar 02 12:43:56.89: [ 7629]: ANSWER: FAX CONNECTION  DEVICE '/dev/ttyS0'
Mar 02 12:43:56.89: [ 7629]: STATE CHANGE: ANSWERING -> RECEIVING
Mar 02 12:43:56.89: [ 7629]: MODEM input buffering enabled
Mar 02 12:43:56.89: [ 7629]: RECV FAX: begin
Mar 02 12:43:56.89: [ 7629]: MODEM input buffering disabled
Mar 02 12:43:56.89: [ 7629]: <-- HDLC<32:FF C0 04 AD 00 55 12 9E 36 86 62 82
1A 04 14 2E B6 94 04 6A A6 4E CE 96 F6 76 04 2C 74 4C 74 8C>
Mar 02 12:43:56.89: [ 7629]: <-- data [32]
Mar 02 12:43:56.89: [ 7629]: <-- data [2]
Mar 02 12:43:56.91: [ 7629]: --> [7:CONNECT]
Mar 02 12:43:56.91: [ 7629]: <-- HDLC<23:FF C0 02 2A 72 A2 B2 0A 92 AA 8A A2
04 72 82 A2 32 C2 4A A2 2A 72 92>
Mar 02 12:43:56.91: [ 7629]: <-- data [23]
Mar 02 12:43:56.91: [ 7629]: <-- data [2]
Mar 02 12:43:56.92: [ 7629]: --> [7:CONNECT]
Mar 02 12:43:56.92: [ 7629]: <-- HDLC<10:FF C8 01 04 76 5F 23 01 FB C0>
Mar 02 12:43:56.92: [ 7629]: <-- data [10]
Mar 02 12:43:56.92: [ 7629]: <-- data [2]
Mar 02 12:43:59.12: [ 7629]: --> [2:OK]
Mar 02 12:43:59.12: [ 7629]: <-- [9:AT+FRH=3\r]
Mar 02 12:43:59.72: [ 7629]: --> [7:CONNECT]
Mar 02 12:44:00.97: [ 7629]: --> HDLC<9:FF C8 C1 00 60 1F 22 0D DD>
Mar 02 12:44:00.97: [ 7629]: --> [2:OK]
Mar 02 12:44:00.97: [ 7629]: REMOTE wants 9600 bit/s
Mar 02 12:44:00.97: [ 7629]: REMOTE wants A4 page width (215 mm)
Mar 02 12:44:00.97: [ 7629]: REMOTE wants unlimited page length
Mar 02 12:44:00.97: [ 7629]: REMOTE wants 3.85 line/mm
Mar 02 12:44:00.97: [ 7629]: REMOTE wants 2-D MMR
Mar 02 12:44:00.97: [ 7629]: REMOTE wants T.30 Annex A, 256-byte ECM
Mar 02 12:44:00.97: [ 7629]: <-- [9:AT+FRH=3\r]
Mar 02 12:44:00.98: [ 7629]: --> [7:CONNECT]
Mar 02 12:44:01.00: [ 7629]: --> [2:]
Mar 02 12:44:01.01: [ 7629]: --> [10:NO CARRIER]
Mar 02 12:44:01.01: [ 7629]: RECV training at v.29 9600 bit/s
Mar 02 12:44:01.01: [ 7629]: <-- [10:AT+FRM=96\r]
Mar 02 12:44:01.33: [ 7629]: --> [7:CONNECT]
Mar 02 12:44:09.67: [ 7629]: MODEM TIMEOUT: receiving TCF
Mar 02 12:44:09.67: [ 7629]: <-- data [1]
Mar 02 12:44:09.68: [ 7629]: --> [16:.¬ç¨pi'"è'ÀÄø]
Mar 02 12:44:09.68: [ 7629]: DELAY 75 ms
Mar 02 12:44:09.76: [ 7629]: <-- [9:AT+FTH=3\r]
Mar 02 12:44:09.76: [ 7629]: --> [2:OK]
Mar 02 12:44:09.76: [ 7629]: TRAINING failed
Mar 02 12:44:09.76: [ 7629]: <-- [9:AT+FRH=3\r]
Mar 02 12:44:12.15: [ 7629]: --> [7:CONNECT]
Mar 02 12:44:13.40: [ 7629]: --> HDLC<9:FF C8 C1 00 60 1F 22 0D DD>
Mar 02 12:44:13.40: [ 7629]: --> [2:OK]
Mar 02 12:44:13.40: [ 7629]: REMOTE wants 9600 bit/s
Mar 02 12:44:13.40: [ 7629]: REMOTE wants A4 page width (215 mm)
Mar 02 12:44:13.40: [ 7629]: REMOTE wants unlimited page length
Mar 02 12:44:13.40: [ 7629]: REMOTE wants 3.85 line/mm
Mar 02 12:44:13.40: [ 7629]: REMOTE wants 2-D MMR
Mar 02 12:44:13.40: [ 7629]: REMOTE wants T.30 Annex A, 256-byte ECM
Mar 02 12:44:13.40: [ 7629]: <-- [9:AT+FRH=3\r]
Mar 02 12:44:13.41: [ 7629]: --> [7:CONNECT]
Mar 02 12:44:13.44: [ 7629]: --> [2:]
Mar 02 12:44:13.44: [ 7629]: --> [10:NO CARRIER]
Mar 02 12:44:13.44: [ 7629]: RECV training at v.29 9600 bit/s
Mar 02 12:44:13.44: [ 7629]: <-- [10:AT+FRM=96\r]
Mar 02 12:44:13.76: [ 7629]: --> [7:CONNECT]
Mar 02 12:44:19.86: [ 7629]: MODEM TIMEOUT: receiving TCF
Mar 02 12:44:19.86: [ 7629]: <-- data [1]
Mar 02 12:44:19.86: [ 7629]: --> [10:NO CARRIER]
Mar 02 12:44:19.86: [ 7629]: MODEM No carrier
Mar 02 12:44:19.86: [ 7629]: RECV: TCF 7312 bytes, 74% non-zero, 1852
zero-run
Mar 02 12:44:19.86: [ 7629]: RECV: reject TCF (too many non-zero, max 10%)
Mar 02 12:44:21.86: [ 7629]: MODEM TIMEOUT: reading line from modem
Mar 02 12:44:21.86: [ 7629]: MODEM <Empty line>
Mar 02 12:44:23.86: [ 7629]: MODEM TIMEOUT: reading line from modem
Mar 02 12:44:23.86: [ 7629]: MODEM <Empty line>
Mar 02 12:44:25.86: [ 7629]: MODEM TIMEOUT: reading line from modem
Mar 02 12:44:25.86: [ 7629]: MODEM <Empty line>
Mar 02 12:44:25.86: [ 7629]: DELAY 75 ms
Mar 02 12:44:25.94: [ 7629]: <-- [9:AT+FTH=3\r]
Mar 02 12:44:26.90: [ 7629]: --> [7:CONNECT]
Mar 02 12:44:26.90: [ 7629]: <-- HDLC<3:FF C8 22>
Mar 02 12:44:26.90: [ 7629]: <-- data [3]
Mar 02 12:44:26.90: [ 7629]: <-- data [2]
Mar 02 12:44:27.29: [ 7629]: --> [2:OK]
Mar 02 12:44:27.29: [ 7629]: TRAINING failed
Mar 02 12:44:27.29: [ 7629]: <-- [9:AT+FRH=3\r]
Mar 02 12:44:34.29: [ 7629]: --> [0:]
Mar 02 12:44:34.29: [ 7629]: MODEM <Empty line>
Mar 02 12:44:34.29: [ 7629]: MODEM TIMEOUT: waiting for v.21 carrier
Mar 02 12:44:34.29: [ 7629]: <-- data [1]
Mar 02 12:44:34.30: [ 7629]: --> [2:]
Mar 02 12:44:34.30: [ 7629]: --> [2:OK]
Mar 02 12:44:34.30: [ 7629]: RECV FAX: Failure to train modems
Mar 02 12:44:34.30: [ 7629]: RECV FAX: end
Mar 02 12:44:34.30: [ 7629]: SESSION END

____________________ HylaFAX(tm) Users Mailing List _______________________
  To subscribe/unsubscribe, click http://lists.hylafax.org/cgi-bin/lsg2.cgi
 On UNIX: mail -s unsubscribe hylafax-users-request@xxxxxxxxxxx < /dev/null
  *To learn about commercial HylaFAX(tm) support, mail sales@xxxxxxxxx*