Failure To Train Modem Speed
Brian <[email protected]> Fri, 10 Feb 2017 14:41:10 -0500
| Newsgroups | gmane.comp.telephony.fax.hylafax.user |
|---|---|
| Message-ID | <[email protected]> |
Good Day, I was hoping someone could help me out with this issue. We have a Hylafax-IAXmodem-Asterisk system with a Digium Inc Wildcard TE121. It works very well, however we have one partner that we are unable to fax to. That site is using CX93010 Conexant USB dongle on a Windows server. They report no problems with any other partners. The notification email says "Problem sending the fax.: Failure to train remote modem at 2400 bps or minimum speed; Giving up after 3 attempts to send same page" This is what we see in the log: Feb 10 13:51:55.89: [29624]: SESSION BEGIN 000306517 1234567890 Feb 10 13:51:55.89: [29624]: HylaFAX (tm) Version 6.0.3 Feb 10 13:51:55.89: [29624]: SEND FAX: JOB 2089439 DEST 1234567 COMMID 000306517 DEVICE '/dev/ttyIAX05' FROM 'XYZ' USER fax Feb 10 13:51:55.89: [29624]: <-- [14:AT+FCLASS=1.0\r] Feb 10 13:51:55.91: [29624]: --> [2:OK] Feb 10 13:51:55.91: [29624]: DIAL 6243423 Feb 10 13:51:55.91: [29624]: <-- [12:ATDT1234567\r] Feb 10 13:52:14.85: [29624]: --> [7:CONNECT] Feb 10 13:52:16.27: [29624]: --> [2:OK] Feb 10 13:52:16.27: [29624]: REMOTE CSI "Fax" Feb 10 13:52:16.27: [29624]: <-- [9:AT+FRH=3\r] Feb 10 13:52:16.59: [29624]: --> [7:CONNECT] Feb 10 13:52:16.69: [29624]: --> [2:OK] Feb 10 13:52:16.69: [29624]: REMOTE best rate 14400 bit/s Feb 10 13:52:16.69: [29624]: REMOTE max A4 page width (215 mm) Feb 10 13:52:16.69: [29624]: REMOTE max unlimited page length Feb 10 13:52:16.69: [29624]: REMOTE best vres 7.7 line/mm Feb 10 13:52:16.69: [29624]: REMOTE format support: MH, MR Feb 10 13:52:16.69: [29624]: REMOTE best 0 ms/scanline Feb 10 13:52:16.69: [29624]: USE 14400 bit/s Feb 10 13:52:16.69: [29624]: SEND file "docq/cover2089439;f0" Feb 10 13:52:16.69: [29624]: USE A4 page width (215 mm) Feb 10 13:52:16.69: [29624]: USE unlimited page length Feb 10 13:52:16.69: [29624]: USE 3.85 line/mm Feb 10 13:52:16.69: [29624]: USE 2-D MR Feb 10 13:52:16.69: [29624]: USE 0 ms/scanline Feb 10 13:52:16.69: [29624]: SEND training at v.17 14400 bit/s Feb 10 13:52:16.69: [29624]: <-- [9:AT+FRS=7\r] Feb 10 13:52:16.75: [29624]: --> [2:OK] Feb 10 13:52:16.75: [29624]: <-- [9:AT+FTH=3\r] Feb 10 13:52:16.77: [29624]: --> [7:CONNECT] Feb 10 13:52:16.77: [29624]: <-- data [23] Feb 10 13:52:16.77: [29624]: <-- data [2] Feb 10 13:52:18.31: [29624]: --> [7:CONNECT] Feb 10 13:52:18.31: [29624]: <-- data [6] Feb 10 13:52:18.31: [29624]: <-- data [2] Feb 10 13:52:18.67: [29624]: --> [2:OK] Feb 10 13:52:18.67: [29624]: <-- [9:AT+FTS=7\r] Feb 10 13:52:18.77: [29624]: --> [2:OK] Feb 10 13:52:18.77: [29624]: <-- [11:AT+FTM=145\r] Feb 10 13:52:18.79: [29624]: --> [7:CONNECT] Feb 10 13:52:18.79: [29624]: DELAY 400 ms Feb 10 13:52:19.19: [29624]: <-- data [1024] Feb 10 13:52:19.19: [29624]: <-- data [1024] Feb 10 13:52:19.19: [29624]: <-- data [652] Feb 10 13:52:19.19: [29624]: <-- data [2] Feb 10 13:52:21.75: [29624]: --> [2:OK] Feb 10 13:52:21.75: [29624]: <-- [9:AT+FRH=3\r] Feb 10 13:52:24.85: [29624]: --> [0:] Feb 10 13:52:24.85: [29624]: MODEM <Empty line> Feb 10 13:52:24.85: [29624]: <-- data [1] Feb 10 13:52:24.87: [29624]: --> [2:OK] Feb 10 13:52:24.87: [29624]: <-- [9:AT+FRS=7\r] Feb 10 13:52:24.89: [29624]: --> [2:OK] Feb 10 13:52:24.89: [29624]: SEND training at v.17 12000 bit/s Feb 10 13:52:24.89: [29624]: <-- [9:AT+FTH=3\r] Feb 10 13:52:24.91: [29624]: --> [7:CONNECT] Feb 10 13:52:24.91: [29624]: <-- data [23] Feb 10 13:52:24.91: [29624]: <-- data [2] Feb 10 13:52:26.45: [29624]: --> [7:CONNECT] Feb 10 13:52:26.45: [29624]: <-- data [6] Feb 10 13:52:26.45: [29624]: <-- data [2] Feb 10 13:52:26.81: [29624]: --> [2:OK] Feb 10 13:52:26.81: [29624]: <-- [9:AT+FTS=7\r] Feb 10 13:52:26.91: [29624]: --> [2:OK] Feb 10 13:52:26.91: [29624]: <-- [11:AT+FTM=121\r] Feb 10 13:52:26.93: [29624]: --> [7:CONNECT] Feb 10 13:52:26.93: [29624]: DELAY 400 ms Feb 10 13:52:27.33: [29624]: <-- data [1024] Feb 10 13:52:27.33: [29624]: <-- data [1024] Feb 10 13:52:27.33: [29624]: <-- data [202] Feb 10 13:52:27.33: [29624]: <-- data [2] Feb 10 13:52:29.89: [29624]: --> [2:OK] Feb 10 13:52:29.89: [29624]: <-- [9:AT+FRH=3\r] Feb 10 13:52:30.87: [29624]: --> [7:CONNECT] Feb 10 13:52:31.85: [29624]: --> [2:OK] Feb 10 13:52:31.85: [29624]: SEND training at v.29 9600 bit/s Feb 10 13:52:31.85: [29624]: <-- [9:AT+FRS=7\r] Feb 10 13:52:31.91: [29624]: --> [2:OK] Feb 10 13:52:31.91: [29624]: <-- [9:AT+FTH=3\r] Feb 10 13:52:31.93: [29624]: --> [7:CONNECT] Feb 10 13:52:31.94: [29624]: <-- data [23] Feb 10 13:52:31.94: [29624]: <-- data [2] Feb 10 13:52:33.47: [29624]: --> [7:CONNECT] Feb 10 13:52:33.47: [29624]: <-- data [6] Feb 10 13:52:33.47: [29624]: <-- data [2] Feb 10 13:52:33.83: [29624]: --> [2:OK] Feb 10 13:52:33.83: [29624]: <-- [9:AT+FTS=7\r] Feb 10 13:52:33.93: [29624]: --> [2:OK] Feb 10 13:52:33.93: [29624]: <-- [10:AT+FTM=96\r] Feb 10 13:52:33.95: [29624]: --> [7:CONNECT] Feb 10 13:52:33.95: [29624]: DELAY 400 ms Feb 10 13:52:34.35: [29624]: <-- data [1024] Feb 10 13:52:34.35: [29624]: <-- data [776] Feb 10 13:52:34.35: [29624]: <-- data [2] Feb 10 13:52:35.91: [29624]: --> [2:OK] Feb 10 13:52:35.91: [29624]: <-- [9:AT+FRH=3\r] Feb 10 13:52:39.01: [29624]: --> [0:] Feb 10 13:52:39.01: [29624]: MODEM <Empty line> Feb 10 13:52:39.01: [29624]: <-- data [1] Feb 10 13:52:39.03: [29624]: --> [2:OK] Feb 10 13:52:39.03: [29624]: <-- [9:AT+FRS=7\r] Feb 10 13:52:39.05: [29624]: --> [2:OK] Feb 10 13:52:39.05: [29624]: SEND training at v.29 7200 bit/s Feb 10 13:52:39.05: [29624]: <-- [9:AT+FTH=3\r] Feb 10 13:52:39.07: [29624]: --> [7:CONNECT] Feb 10 13:52:39.07: [29624]: <-- data [23] Feb 10 13:52:39.07: [29624]: <-- data [2] Feb 10 13:52:40.61: [29624]: --> [7:CONNECT] Feb 10 13:52:40.61: [29624]: <-- data [6] Feb 10 13:52:40.61: [29624]: <-- data [2] Feb 10 13:52:40.97: [29624]: --> [2:OK] Feb 10 13:52:40.97: [29624]: <-- [9:AT+FTS=7\r] Feb 10 13:52:41.07: [29624]: --> [2:OK] Feb 10 13:52:41.07: [29624]: <-- [10:AT+FTM=72\r] Feb 10 13:52:41.09: [29624]: --> [7:CONNECT] Feb 10 13:52:41.09: [29624]: DELAY 400 ms Feb 10 13:52:41.49: [29624]: <-- data [1024] Feb 10 13:52:41.49: [29624]: <-- data [326] Feb 10 13:52:41.49: [29624]: <-- data [2] Feb 10 13:52:43.05: [29624]: --> [2:OK] Feb 10 13:52:43.05: [29624]: <-- [9:AT+FRH=3\r] Feb 10 13:52:45.13: [29624]: --> [7:CONNECT] Feb 10 13:52:46.11: [29624]: --> [2:OK] Feb 10 13:52:46.11: [29624]: SEND training at v.27ter 4800 bit/s Feb 10 13:52:46.11: [29624]: <-- [9:AT+FRS=7\r] Feb 10 13:52:46.17: [29624]: --> [2:OK] Feb 10 13:52:46.17: [29624]: <-- [9:AT+FTH=3\r] Feb 10 13:52:46.19: [29624]: --> [7:CONNECT] Feb 10 13:52:46.19: [29624]: <-- data [23] Feb 10 13:52:46.19: [29624]: <-- data [2] Feb 10 13:52:47.73: [29624]: --> [7:CONNECT] Feb 10 13:52:47.73: [29624]: <-- data [6] Feb 10 13:52:47.73: [29624]: <-- data [2] Feb 10 13:52:48.09: [29624]: --> [2:OK] Feb 10 13:52:48.09: [29624]: <-- [9:AT+FTS=7\r] Feb 10 13:52:48.19: [29624]: --> [2:OK] Feb 10 13:52:48.19: [29624]: <-- [10:AT+FTM=48\r] Feb 10 13:52:48.21: [29624]: --> [7:CONNECT] Feb 10 13:52:48.21: [29624]: DELAY 400 ms Feb 10 13:52:48.61: [29624]: <-- data [900] Feb 10 13:52:48.61: [29624]: <-- data [2] Feb 10 13:52:50.49: [29624]: --> [2:OK] Feb 10 13:52:50.49: [29624]: <-- [9:AT+FRH=3\r] Feb 10 13:52:53.59: [29624]: --> [0:] Feb 10 13:52:53.59: [29624]: MODEM <Empty line> Feb 10 13:52:53.59: [29624]: <-- data [1] Feb 10 13:52:53.60: [29624]: --> [2:OK] Feb 10 13:52:53.60: [29624]: <-- [9:AT+FRS=7\r] Feb 10 13:52:53.62: [29624]: --> [2:OK] Feb 10 13:52:53.62: [29624]: SEND training at v.27ter fallback mode 2400 bit/s Feb 10 13:52:53.62: [29624]: <-- [9:AT+FTH=3\r] Feb 10 13:52:53.64: [29624]: --> [7:CONNECT] Feb 10 13:52:53.64: [29624]: <-- data [23] Feb 10 13:52:53.64: [29624]: <-- data [2] Feb 10 13:52:55.18: [29624]: --> [7:CONNECT] Feb 10 13:52:55.18: [29624]: <-- data [6] Feb 10 13:52:55.18: [29624]: <-- data [2] Feb 10 13:52:55.54: [29624]: --> [2:OK] Feb 10 13:52:55.54: [29624]: <-- [9:AT+FTS=7\r] Feb 10 13:52:55.64: [29624]: --> [2:OK] Feb 10 13:52:55.64: [29624]: <-- [10:AT+FTM=24\r] Feb 10 13:52:55.66: [29624]: --> [7:CONNECT] Feb 10 13:52:55.66: [29624]: DELAY 400 ms Feb 10 13:52:56.06: [29624]: <-- data [450] Feb 10 13:52:56.06: [29624]: <-- data [2] Feb 10 13:52:58.18: [29624]: --> [2:OK] Feb 10 13:52:58.18: [29624]: <-- [9:AT+FRH=3\r] Feb 10 13:52:58.72: [29624]: --> [7:CONNECT] Feb 10 13:52:59.72: [29624]: --> [2:OK] Feb 10 13:52:59.72: [29624]: TRAINING failed Feb 10 13:52:59.72: [29624]: <-- [9:AT+FRS=7\r] Feb 10 13:52:59.78: [29624]: --> [2:OK] Feb 10 13:52:59.78: [29624]: <-- [9:AT+FTH=3\r] Feb 10 13:52:59.83: [29624]: --> [7:CONNECT] Feb 10 13:52:59.83: [29624]: <-- data [3] Feb 10 13:52:59.83: [29624]: <-- data [2] Feb 10 13:53:00.88: [29624]: --> [2:OK] Feb 10 13:53:01.88: [29624]: <-- [5:ATH0\r] Feb 10 13:53:02.00: [29624]: --> [2:OK] Feb 10 13:53:02.00: [29624]: SESSION END I have googled and followed leads but nothing I have tried is working. Any ideas would be greatly appreciated. Thanks, Brian ____________________ HylaFAX(tm) Users Mailing List _______________________ To subscribe/unsubscribe, click http://lists.hylafax.org/cgi-bin/lsg2.cgi On UNIX: mail -s unsubscribe [email protected] < /dev/null *To learn about commercial HylaFAX(tm) support, mail [email protected].*