[hylafax-users] Failed Faxes - failure to train remote modem at 2400 bps

["Bryan Glass - Party Stuff & U-Rent-It" <bglass@xxxxxxxxxxxxx>]

We have just installed hylafax on Red Hat Enterprise Linux and are using a US Robotics external modem model 5686E.  We are having trouble sending faxes only to a few destination fax numbers.  In each of these instances, the failure was "Failure to train remote modem at 2400 bps or minimum speed".  We are using the standard config file as created by faxaddmodem.  Has anyone had the same problem?  Any suggestions?  The trace log is included below:

 

    ---- Transcript of session follows ----

Dec 11 21:44:04.30: [ 5082]: SESSION BEGIN 000000501 16474355804
Dec 11 21:44:04.30: [ 5082]: HylaFAX (tm) Version 4.4.0
Dec 11 21:44:04.30: [ 5082]: SEND FAX: JOB 420 DEST 1-647-435-5804 COMMID 000000501 DEVICE '/dev/ttyc16' FROM 'bryan <support@xxxxxxxxxxxxx>' USER bryan
Dec 11 21:44:04.30: [ 5082]: STATE CHANGE: RUNNING -> SENDING
Dec 11 21:44:04.30: [ 5082]: <-- [12:AT+FCLASS=1\r]
Dec 11 21:44:04.56: [ 5082]: --> [2:OK]
Dec 11 21:44:04.56: [ 5082]: MODEM set XON/XOFF/FLUSH: input ignored, output disabled
Dec 11 21:44:04.56: [ 5082]: DIAL 16474355804
Dec 11 21:44:04.56: [ 5082]: <-- [16:ATDT16474355804\r]
Dec 11 21:44:26.53: [ 5082]: --> [7:CONNECT]
Dec 11 21:44:27.51: [ 5082]: --> HDLC<16:FF C0 04 00 00 6A AA AA 00 11 09 01 FA 00 A8 8A>
Dec 11 21:44:27.51: [ 5082]: --> [2:OK]
Dec 11 21:44:27.51: [ 5082]: REMOTE NSF "00 00 56 55 55 00 88 90 80 5F 00"
Dec 11 21:44:27.51: [ 5082]: NSF remote fax equipment: Brother Fax-560/770
Dec 11 21:44:27.51: [ 5082]: <-- [9:AT+FRH=3\r]
Dec 11 21:44:27.64: [ 5082]: --> [7:CONNECT]
Dec 11 21:44:28.29: [ 5082]: --> HDLC<25:FF C0 02 2C 0C 1C AC AC CC 2C EC 2C 6C 04 04 04 04 04 04 04 04 04 04 97 29>
Dec 11 21:44:28.29: [ 5082]: --> [2:OK]
Dec 11 21:44:28.29: [ 5082]: REMOTE CSI "6474355804"
Dec 11 21:44:28.29: [ 5082]: <-- [9:AT+FRH=3\r]
Dec 11 21:44:28.42: [ 5082]: --> [7:CONNECT]
Dec 11 21:44:28.54: [ 5082]: --> HDLC<11:FF C8 01 00 72 1D 21 01 88 2F 39>
Dec 11 21:44:28.54: [ 5082]: --> [2:OK]
Dec 11 21:44:28.54: [ 5082]: REMOTE best rate 9600 bit/s
Dec 11 21:44:28.54: [ 5082]: REMOTE max A4 page width (215 mm)
Dec 11 21:44:28.54: [ 5082]: REMOTE max unlimited page length
Dec 11 21:44:28.54: [ 5082]: REMOTE best vres 15.4 line/mm
Dec 11 21:44:28.54: [ 5082]: REMOTE format support: MH
Dec 11 21:44:28.54: [ 5082]: REMOTE supports T.30 Annex A, 256-byte ECM
Dec 11 21:44:28.54: [ 5082]: REMOTE best 20 ms, 10 ms/scanline
Dec 11 21:44:28.54: [ 5082]: USE 9600 bit/s
Dec 11 21:44:28.54: [ 5082]: USE error correction mode
Dec 11 21:44:28.54: [ 5082]: SEND file "docq/doc614.ps;31"
Dec 11 21:44:28.54: [ 5082]: USE A4 page width (215 mm)
Dec 11 21:44:28.54: [ 5082]: USE unlimited page length
Dec 11 21:44:28.54: [ 5082]: USE 7.7 line/mm
Dec 11 21:44:28.54: [ 5082]: USE 1-D MH
Dec 11 21:44:28.54: [ 5082]: USE 0 ms/scanline
Dec 11 21:44:28.54: [ 5082]: SEND training at v.29 9600 bit/s
Dec 11 21:44:28.54: [ 5082]: DELAY 70 ms
Dec 11 21:44:28.61: [ 5082]: <-- [9:AT+FTH=3\r]
Dec 11 21:44:28.95: [ 5082]: --> [7:CONNECT]
Dec 11 21:44:28.95: [ 5082]: <-- HDLC<23:FF C0 C2 2E 92 04 2E 76 A6 4A 04 AA FA 66 66 AE 2E CA 9E 2E 4E 86 0A>
Dec 11 21:44:29.09: [ 5082]: --> [7:CONNECT]
Dec 11 21:44:29.09: [ 5082]: <-- HDLC<7:FF C8 C1 00 62 1F 20>
Dec 11 21:44:30.98: [ 5082]: --> [2:OK]
Dec 11 21:44:30.98: [ 5082]: <-- [9:AT+FTS=7\r]
Dec 11 21:44:31.18: [ 5082]: --> [2:OK]
Dec 11 21:44:31.18: [ 5082]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled
Dec 11 21:44:31.18: [ 5082]: <-- [10:AT+FTM=96\r]
Dec 11 21:44:31.63: [ 5082]: --> [7:CONNECT]
Dec 11 21:44:33.27: [ 5082]: --> [2:OK]
Dec 11 21:44:33.27: [ 5082]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled
Dec 11 21:44:33.27: [ 5082]: <-- [9:AT+FRS=1\r]
Dec 11 21:44:33.42: [ 5082]: --> [2:OK]
Dec 11 21:44:33.42: [ 5082]: <-- [9:AT+FRH=3\r]
Dec 11 21:44:33.72: [ 5082]: --> [7:CONNECT]
Dec 11 21:44:34.65: [ 5082]: --> HDLC<5:FF C8 22 67 DD>
Dec 11 21:44:34.65: [ 5082]: --> [2:OK]
Dec 11 21:44:34.65: [ 5082]: SEND training at v.29 7200 bit/s
Dec 11 21:44:34.65: [ 5082]: DELAY 70 ms
Dec 11 21:44:34.72: [ 5082]: <-- [9:AT+FTH=3\r]
Dec 11 21:44:35.02: [ 5082]: --> [7:CONNECT]
Dec 11 21:44:35.02: [ 5082]: <-- HDLC<23:FF C0 C2 2E 92 04 2E 76 A6 4A 04 AA FA 66 66 AE 2E CA 9E 2E 4E 86 0A>
Dec 11 21:44:35.16: [ 5082]: --> [7:CONNECT]
Dec 11 21:44:35.16: [ 5082]: <-- HDLC<7:FF C8 C1 00 72 1F 20>
Dec 11 21:44:37.04: [ 5082]: --> [2:OK]
Dec 11 21:44:37.04: [ 5082]: <-- [9:AT+FTS=7\r]
Dec 11 21:44:37.25: [ 5082]: --> [2:OK]
Dec 11 21:44:37.25: [ 5082]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled
Dec 11 21:44:37.25: [ 5082]: <-- [10:AT+FTM=72\r]
Dec 11 21:44:37.70: [ 5082]: --> [7:CONNECT]
Dec 11 21:44:39.36: [ 5082]: --> [2:OK]
Dec 11 21:44:39.36: [ 5082]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled
Dec 11 21:44:39.36: [ 5082]: <-- [9:AT+FRS=1\r]
Dec 11 21:44:39.54: [ 5082]: --> [2:OK]
Dec 11 21:44:39.54: [ 5082]: <-- [9:AT+FRH=3\r]
Dec 11 21:44:40.12: [ 5082]: --> [7:CONNECT]
Dec 11 21:44:40.75: [ 5082]: --> HDLC<5:FF C8 22 67 DD>
Dec 11 21:44:40.75: [ 5082]: --> [2:OK]
Dec 11 21:44:40.75: [ 5082]: SEND training at v.27ter 4800 bit/s
Dec 11 21:44:40.75: [ 5082]: DELAY 70 ms
Dec 11 21:44:40.82: [ 5082]: <-- [9:AT+FTH=3\r]
Dec 11 21:44:41.14: [ 5082]: --> [7:CONNECT]
Dec 11 21:44:41.14: [ 5082]: <-- HDLC<23:FF C0 C2 2E 92 04 2E 76 A6 4A 04 AA FA 66 66 AE 2E CA 9E 2E 4E 86 0A>
Dec 11 21:44:41.29: [ 5082]: --> [7:CONNECT]
Dec 11 21:44:41.29: [ 5082]: <-- HDLC<7:FF C8 C1 00 52 1F 20>
Dec 11 21:44:43.15: [ 5082]: --> [2:OK]
Dec 11 21:44:43.15: [ 5082]: <-- [9:AT+FTS=7\r]
Dec 11 21:44:43.34: [ 5082]: --> [2:OK]
Dec 11 21:44:43.34: [ 5082]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled
Dec 11 21:44:43.34: [ 5082]: <-- [10:AT+FTM=48\r]
Dec 11 21:44:44.48: [ 5082]: --> [7:CONNECT]
Dec 11 21:44:46.16: [ 5082]: --> [2:OK]
Dec 11 21:44:46.16: [ 5082]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled
Dec 11 21:44:46.16: [ 5082]: <-- [9:AT+FRS=1\r]
Dec 11 21:44:46.31: [ 5082]: --> [2:OK]
Dec 11 21:44:46.31: [ 5082]: <-- [9:AT+FRH=3\r]
Dec 11 21:44:46.88: [ 5082]: --> [7:CONNECT]
Dec 11 21:44:47.51: [ 5082]: --> HDLC<5:FF C8 22 67 DD>
Dec 11 21:44:47.51: [ 5082]: --> [2:OK]
Dec 11 21:44:47.51: [ 5082]: SEND training at v.27ter fallback mode 2400 bit/s
Dec 11 21:44:47.51: [ 5082]: DELAY 70 ms
Dec 11 21:44:47.58: [ 5082]: <-- [9:AT+FTH=3\r]
Dec 11 21:44:47.92: [ 5082]: --> [7:CONNECT]
Dec 11 21:44:47.92: [ 5082]: <-- HDLC<23:FF C0 C2 2E 92 04 2E 76 A6 4A 04 AA FA 66 66 AE 2E CA 9E 2E 4E 86 0A>
Dec 11 21:44:48.07: [ 5082]: --> [7:CONNECT]
Dec 11 21:44:48.07: [ 5082]: <-- HDLC<7:FF C8 C1 00 42 1F 20>
Dec 11 21:44:49.91: [ 5082]: --> [2:OK]
Dec 11 21:44:49.91: [ 5082]: <-- [9:AT+FTS=7\r]
Dec 11 21:44:50.10: [ 5082]: --> [2:OK]
Dec 11 21:44:50.10: [ 5082]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled
Dec 11 21:44:50.10: [ 5082]: <-- [10:AT+FTM=24\r]
Dec 11 21:44:51.47: [ 5082]: --> [7:CONNECT]
Dec 11 21:44:53.17: [ 5082]: --> [2:OK]
Dec 11 21:44:53.17: [ 5082]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled
Dec 11 21:44:53.17: [ 5082]: <-- [9:AT+FRS=1\r]
Dec 11 21:44:53.33: [ 5082]: --> [2:OK]
Dec 11 21:44:53.33: [ 5082]: <-- [9:AT+FRH=3\r]
Dec 11 21:44:53.72: [ 5082]: --> [7:CONNECT]
Dec 11 21:44:54.54: [ 5082]: --> HDLC<5:FF C8 22 67 DD>
Dec 11 21:44:54.54: [ 5082]: --> [2:OK]
Dec 11 21:44:54.54: [ 5082]: TRAINING failed
Dec 11 21:44:54.54: [ 5082]: SEND: Giving up after 3 attempts to send same page "docq/doc614.ps;31", dirnum 0
Dec 11 21:44:54.54: [ 5082]: DELAY 70 ms
Dec 11 21:44:54.61: [ 5082]: <-- [9:AT+FTH=3\r]
Dec 11 21:44:54.93: [ 5082]: --> [7:CONNECT]
Dec 11 21:44:54.93: [ 5082]: <-- HDLC<3:FF C8 DF>
Dec 11 21:44:56.10: [ 5082]: --> [2:OK]
Dec 11 21:44:56.10: [ 5082]: MODEM input buffering enabled
Dec 11 21:44:57.10: [ 5082]: <-- [5:ATH0\r]
Dec 11 21:44:57.45: [ 5082]: --> [2:OK]
Dec 11 21:44:57.45: [ 5082]: MODEM set DTR OFF
Dec 11 21:44:57.45: [ 5082]: STATE CHANGE: SENDING -> MODEMWAIT (timeout 5)
Dec 11 21:44:57.45: [ 5082]: SESSION END