Re: Failure To Train Modem Speed

Anyone?


On 2/10/2017 2:41 PM, Brian wrote:
> 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].*
>


____________________ 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].*