 HylaFAX The world's
most advanced open source fax server
|
[Date Prev][Date Next][Thread Prev][Thread Next] [Date Index] [Thread Index]
[hylafax-users] failure to train remote modem at 2400 bps
- To: hylafax-users@xxxxxxxxxxx
- Subject: [hylafax-users] failure to train remote modem at 2400 bps
- From: "Christian G. von Busse" <CvB@xxxxxxxxxxx>
- Date: Sat, 20 Dec 2008 23:27:59 +0100
Hi - now that I got my USR 56k USB Modem to talk to faxgetty, it seems
I can't send faxes because I always get the error message mentioned in
the subject.
I've searched through the archives and tried
Class1TMConnectDelay: 400
Class1ECMSupport: no
Class1RMQueryCmd: "!24,48,72,96"
RTNHandlingMethod: giveup
ModemFlowControl: rtscts
Nothing helps. The log looks as follows:
Your job to XXX failed because:
Failure to train remote modem at 2400 bps or minimum speed; Giving up after 3 attempts to send same page
---- Transcript of session follows ----
Dez 20 23:17:29.11: [18162]: SESSION BEGIN 000000085 XXX
Dez 20 23:17:29.11: [18162]: HylaFAX (tm) Version 4.4.3
Dez 20 23:17:29.11: [18162]: SEND FAX: JOB 19 DEST XXX COMMID
000000085 DEVICE '/dev/ttyACM0' FROM 'XXX' USER
XXX
Dez 20 23:17:29.11: [18162]: STATE CHANGE: RUNNING -> SENDING
Dez 20 23:17:29.11: [18162]: <-- [12:AT+FCLASS=1\r]
Dez 20 23:17:29.11: [18162]: --> [2:OK]
Dez 20 23:17:29.11: [18162]: MODEM set XON/XOFF/FLUSH: input ignored, output disabled
Dez 20 23:17:29.11: [18162]: DIAL XXX
Dez 20 23:17:29.11: [18162]: <-- [16:ATDTXXX\r]
Dez 20 23:17:42.89: [18162]: --> [7:CONNECT]
Dez 20 23:17:44.55: [18162]: --> HDLC<25:FF C0 02 04 04 04 04 04 9C 4C 9C 4C 9C 4C CC 04 8C CC 2C 04 9C 2C D4 AC 95>
Dez 20 23:17:44.55: [18162]: --> [2:OK]
Dez 20 23:17:44.55: [18162]: REMOTE CSI "XXX"
Dez 20 23:17:44.55: [18162]: <-- [9:AT+FRH=3\r]
Dez 20 23:17:44.55: [18162]: --> [7:CONNECT]
Dez 20 23:17:44.93: [18162]: --> HDLC<9:FF C8 01 00 76 1F 00 CB 86>
Dez 20 23:17:44.93: [18162]: --> [2:OK]
Dez 20 23:17:44.93: [18162]: REMOTE best rate 14400 bit/s
Dez 20 23:17:44.93: [18162]: REMOTE max A4 page width (215 mm)
Dez 20 23:17:44.93: [18162]: REMOTE max unlimited page length
Dez 20 23:17:44.93: [18162]: REMOTE best vres 7.7 line/mm
Dez 20 23:17:44.93: [18162]: REMOTE format support: MH
Dez 20 23:17:44.93: [18162]: REMOTE best 0 ms/scanline
Dez 20 23:17:44.93: [18162]: USE 14400 bit/s
Dez 20 23:17:44.93: [18162]: SEND file "docq/doc18.tif;c0"
Dez 20 23:17:44.93: [18162]: USE A4 page width (215 mm)
Dez 20 23:17:44.93: [18162]: USE unlimited page length
Dez 20 23:17:44.93: [18162]: USE 3.85 line/mm
Dez 20 23:17:44.93: [18162]: USE 1-D MH
Dez 20 23:17:44.93: [18162]: USE 0 ms/scanline
Dez 20 23:17:44.93: [18162]: SEND training at v.17 14400 bit/s
Dez 20 23:17:44.93: [18162]: <-- [9:AT+FRS=7\r]
Dez 20 23:17:45.14: [18162]: --> [2:OK]
Dez 20 23:17:45.14: [18162]: <-- [9:AT+FTH=3\r]
Dez 20 23:17:45.94: [18162]: --> [7:CONNECT]
Dez 20 23:17:45.94: [18162]: <-- HDLC<23:FF C0 C2 A6 CE CE AE 42 04 76 F6 6E 04 74 4E 22 04 82 4A 04 04 04 04>
Dez 20 23:17:45.94: [18162]: <-- data [23]
Dez 20 23:17:45.94: [18162]: <-- data [2]
Dez 20 23:17:45.95: [18162]: --> [7:CONNECT]
Dez 20 23:17:45.95: [18162]: <-- HDLC<6:FF C8 C1 00 44 1E>
Dez 20 23:17:45.95: [18162]: <-- data [6]
Dez 20 23:17:45.95: [18162]: <-- data [2]
Dez 20 23:17:47.04: [18162]: --> [2:OK]
Dez 20 23:17:47.04: [18162]: <-- [9:AT+FTS=7\r]
Dez 20 23:17:47.09: [18162]: --> [2:OK]
Dez 20 23:17:47.09: [18162]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled
Dez 20 23:17:47.09: [18162]: <-- [11:AT+FTM=145\r]
Dez 20 23:17:47.10: [18162]: --> [7:CONNECT]
Dez 20 23:17:47.10: [18162]: DELAY 400 ms
Dez 20 23:17:47.50: [18162]: <-- data [1024]
Dez 20 23:17:47.50: [18162]: <-- data [1024]
Dez 20 23:17:47.51: [18162]: <-- data [652]
Dez 20 23:17:47.52: [18162]: <-- data [2]
Dez 20 23:17:50.23: [18162]: --> [2:OK]
Dez 20 23:17:50.23: [18162]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled
Dez 20 23:17:50.23: [18162]: <-- [9:AT+FRS=1\r]
Dez 20 23:17:51.32: [18162]: --> [2:OK]
Dez 20 23:17:51.32: [18162]: <-- [9:AT+FRH=3\r]
Dez 20 23:17:54.42: [18162]: --> [0:]
Dez 20 23:17:54.42: [18162]: MODEM <Empty line>
Dez 20 23:17:54.42: [18162]: MODEM TIMEOUT: waiting for v.21 carrier
Dez 20 23:17:54.42: [18162]: <-- data [1]
Dez 20 23:17:54.42: [18162]: --> [2:]
Dez 20 23:17:54.42: [18162]: --> [2:OK]
Dez 20 23:17:54.42: [18162]: <-- [9:AT+FRS=7\r]
Dez 20 23:17:54.52: [18162]: --> [2:OK]
Dez 20 23:17:54.52: [18162]: SEND training at v.17 12000 bit/s
Dez 20 23:17:54.52: [18162]: <-- [9:AT+FTH=3\r]
Dez 20 23:17:55.32: [18162]: --> [7:CONNECT]
Dez 20 23:17:55.32: [18162]: <-- HDLC<23:FF C0 C2 A6 CE CE AE 42 04 76 F6 6E 04 74 4E 22 04 82 4A 04 04 04 04>
Dez 20 23:17:55.32: [18162]: <-- data [23]
Dez 20 23:17:55.32: [18162]: <-- data [2]
Dez 20 23:17:55.32: [18162]: --> [7:CONNECT]
Dez 20 23:17:55.32: [18162]: <-- HDLC<6:FF C8 C1 00 54 1E>
Dez 20 23:17:55.32: [18162]: <-- data [6]
Dez 20 23:17:55.32: [18162]: <-- data [2]
Dez 20 23:17:56.42: [18162]: --> [2:OK]
Dez 20 23:17:56.42: [18162]: <-- [9:AT+FTS=7\r]
Dez 20 23:17:56.47: [18162]: --> [2:OK]
Dez 20 23:17:56.47: [18162]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled
Dez 20 23:17:56.47: [18162]: <-- [11:AT+FTM=121\r]
Dez 20 23:17:56.48: [18162]: --> [7:CONNECT]
Dez 20 23:17:56.48: [18162]: DELAY 400 ms
Dez 20 23:17:56.87: [18162]: <-- data [1024]
Dez 20 23:17:56.87: [18162]: <-- data [1024]
Dez 20 23:17:56.88: [18162]: <-- data [202]
Dez 20 23:17:56.88: [18162]: <-- data [2]
Dez 20 23:17:59.61: [18162]: --> [2:OK]
Dez 20 23:17:59.61: [18162]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled
Dez 20 23:17:59.61: [18162]: <-- [9:AT+FRS=1\r]
Dez 20 23:17:59.64: [18162]: --> [2:OK]
Dez 20 23:17:59.64: [18162]: <-- [9:AT+FRH=3\r]
Dez 20 23:18:02.74: [18162]: --> [0:]
Dez 20 23:18:02.74: [18162]: MODEM <Empty line>
Dez 20 23:18:02.74: [18162]: MODEM TIMEOUT: waiting for v.21 carrier
Dez 20 23:18:02.74: [18162]: <-- data [1]
Dez 20 23:18:02.75: [18162]: --> [2:]
Dez 20 23:18:02.75: [18162]: --> [2:OK]
Dez 20 23:18:02.75: [18162]: <-- [9:AT+FRS=7\r]
Dez 20 23:18:02.84: [18162]: --> [2:OK]
Dez 20 23:18:02.84: [18162]: SEND training at v.29 9600 bit/s
Dez 20 23:18:02.84: [18162]: <-- [9:AT+FTH=3\r]
Dez 20 23:18:03.65: [18162]: --> [7:CONNECT]
Dez 20 23:18:03.65: [18162]: <-- HDLC<23:FF C0 C2 A6 CE CE AE 42 04 76 F6 6E 04 74 4E 22 04 82 4A 04 04 04 04>
Dez 20 23:18:03.65: [18162]: <-- data [23]
Dez 20 23:18:03.65: [18162]: <-- data [2]
Dez 20 23:18:03.65: [18162]: --> [7:CONNECT]
Dez 20 23:18:03.65: [18162]: <-- HDLC<6:FF C8 C1 00 60 1E>
Dez 20 23:18:03.65: [18162]: <-- data [6]
Dez 20 23:18:03.65: [18162]: <-- data [2]
Dez 20 23:18:04.74: [18162]: --> [2:OK]
Dez 20 23:18:04.75: [18162]: <-- [9:AT+FTS=7\r]
Dez 20 23:18:04.80: [18162]: --> [2:OK]
Dez 20 23:18:04.80: [18162]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled
Dez 20 23:18:04.80: [18162]: <-- [10:AT+FTM=96\r]
Dez 20 23:18:04.93: [18162]: --> [7:CONNECT]
Dez 20 23:18:04.93: [18162]: DELAY 400 ms
Dez 20 23:18:05.32: [18162]: <-- data [1024]
Dez 20 23:18:05.32: [18162]: <-- data [776]
Dez 20 23:18:05.33: [18162]: <-- data [2]
Dez 20 23:18:06.89: [18162]: --> [2:OK]
Dez 20 23:18:06.89: [18162]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled
Dez 20 23:18:06.89: [18162]: <-- [9:AT+FRS=1\r]
Dez 20 23:18:06.92: [18162]: --> [2:OK]
Dez 20 23:18:06.92: [18162]: <-- [9:AT+FRH=3\r]
Dez 20 23:18:10.02: [18162]: --> [0:]
Dez 20 23:18:10.02: [18162]: MODEM <Empty line>
Dez 20 23:18:10.02: [18162]: MODEM TIMEOUT: waiting for v.21 carrier
Dez 20 23:18:10.02: [18162]: <-- data [1]
Dez 20 23:18:10.02: [18162]: --> [2:]
Dez 20 23:18:10.02: [18162]: --> [2:OK]
Dez 20 23:18:10.02: [18162]: <-- [9:AT+FRS=7\r]
Dez 20 23:18:10.18: [18162]: --> [2:OK]
Dez 20 23:18:10.18: [18162]: SEND training at v.29 7200 bit/s
Dez 20 23:18:10.18: [18162]: <-- [9:AT+FTH=3\r]
Dez 20 23:18:10.98: [18162]: --> [7:CONNECT]
Dez 20 23:18:10.98: [18162]: <-- HDLC<23:FF C0 C2 A6 CE CE AE 42 04 76 F6 6E 04 74 4E 22 04 82 4A 04 04 04 04>
Dez 20 23:18:10.98: [18162]: <-- data [23]
Dez 20 23:18:10.98: [18162]: <-- data [2]
Dez 20 23:18:10.99: [18162]: --> [7:CONNECT]
Dez 20 23:18:10.99: [18162]: <-- HDLC<6:FF C8 C1 00 70 1E>
Dez 20 23:18:10.99: [18162]: <-- data [6]
Dez 20 23:18:10.99: [18162]: <-- data [2]
Dez 20 23:18:12.09: [18162]: --> [2:OK]
Dez 20 23:18:12.09: [18162]: <-- [9:AT+FTS=7\r]
Dez 20 23:18:12.14: [18162]: --> [2:OK]
Dez 20 23:18:12.14: [18162]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled
Dez 20 23:18:12.14: [18162]: <-- [10:AT+FTM=72\r]
Dez 20 23:18:12.15: [18162]: --> [7:CONNECT]
Dez 20 23:18:12.15: [18162]: DELAY 400 ms
Dez 20 23:18:12.54: [18162]: <-- data [1024]
Dez 20 23:18:12.54: [18162]: <-- data [326]
Dez 20 23:18:12.54: [18162]: <-- data [2]
Dez 20 23:18:14.11: [18162]: --> [2:OK]
Dez 20 23:18:14.11: [18162]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled
Dez 20 23:18:14.11: [18162]: <-- [9:AT+FRS=1\r]
Dez 20 23:18:14.15: [18162]: --> [2:OK]
Dez 20 23:18:14.15: [18162]: <-- [9:AT+FRH=3\r]
Dez 20 23:18:17.25: [18162]: --> [0:]
Dez 20 23:18:17.25: [18162]: MODEM <Empty line>
Dez 20 23:18:17.25: [18162]: MODEM TIMEOUT: waiting for v.21 carrier
Dez 20 23:18:17.25: [18162]: <-- data [1]
Dez 20 23:18:17.25: [18162]: --> [2:]
Dez 20 23:18:17.25: [18162]: --> [2:OK]
Dez 20 23:18:17.25: [18162]: <-- [9:AT+FRS=7\r]
Dez 20 23:18:17.34: [18162]: --> [2:OK]
Dez 20 23:18:17.34: [18162]: SEND training at v.27ter 4800 bit/s
Dez 20 23:18:17.34: [18162]: <-- [9:AT+FTH=3\r]
Dez 20 23:18:18.15: [18162]: --> [7:CONNECT]
Dez 20 23:18:18.15: [18162]: <-- HDLC<23:FF C0 C2 A6 CE CE AE 42 04 76 F6 6E 04 74 4E 22 04 82 4A 04 04 04 04>
Dez 20 23:18:18.15: [18162]: <-- data [23]
Dez 20 23:18:18.15: [18162]: <-- data [2]
Dez 20 23:18:18.15: [18162]: --> [7:CONNECT]
Dez 20 23:18:18.15: [18162]: <-- HDLC<6:FF C8 C1 00 50 1E>
Dez 20 23:18:18.15: [18162]: <-- data [6]
Dez 20 23:18:18.15: [18162]: <-- data [2]
Dez 20 23:18:19.25: [18162]: --> [2:OK]
Dez 20 23:18:19.25: [18162]: <-- [9:AT+FTS=7\r]
Dez 20 23:18:19.30: [18162]: --> [2:OK]
Dez 20 23:18:19.30: [18162]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled
Dez 20 23:18:19.30: [18162]: <-- [10:AT+FTM=48\r]
Dez 20 23:18:19.30: [18162]: --> [7:CONNECT]
Dez 20 23:18:19.30: [18162]: DELAY 400 ms
Dez 20 23:18:19.70: [18162]: <-- data [900]
Dez 20 23:18:19.70: [18162]: <-- data [2]
Dez 20 23:18:21.79: [18162]: --> [2:OK]
Dez 20 23:18:21.79: [18162]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled
Dez 20 23:18:21.79: [18162]: <-- [9:AT+FRS=1\r]
Dez 20 23:18:21.82: [18162]: --> [2:OK]
Dez 20 23:18:21.82: [18162]: <-- [9:AT+FRH=3\r]
Dez 20 23:18:24.92: [18162]: --> [0:]
Dez 20 23:18:24.92: [18162]: MODEM <Empty line>
Dez 20 23:18:24.92: [18162]: MODEM TIMEOUT: waiting for v.21 carrier
Dez 20 23:18:24.92: [18162]: <-- data [1]
Dez 20 23:18:24.92: [18162]: --> [2:]
Dez 20 23:18:24.92: [18162]: --> [2:OK]
Dez 20 23:18:24.92: [18162]: <-- [9:AT+FRS=7\r]
Dez 20 23:18:25.02: [18162]: --> [2:OK]
Dez 20 23:18:25.02: [18162]: SEND training at v.27ter fallback mode 2400 bit/s
Dez 20 23:18:25.02: [18162]: <-- [9:AT+FTH=3\r]
Dez 20 23:18:25.82: [18162]: --> [7:CONNECT]
Dez 20 23:18:25.82: [18162]: <-- HDLC<23:FF C0 C2 A6 CE CE AE 42 04 76 F6 6E 04 74 4E 22 04 82 4A 04 04 04 04>
Dez 20 23:18:25.82: [18162]: <-- data [23]
Dez 20 23:18:25.82: [18162]: <-- data [2]
Dez 20 23:18:25.83: [18162]: --> [7:CONNECT]
Dez 20 23:18:25.83: [18162]: <-- HDLC<6:FF C8 C1 00 40 1E>
Dez 20 23:18:25.83: [18162]: <-- data [6]
Dez 20 23:18:25.83: [18162]: <-- data [2]
Dez 20 23:18:26.93: [18162]: --> [2:OK]
Dez 20 23:18:26.93: [18162]: <-- [9:AT+FTS=7\r]
Dez 20 23:18:26.98: [18162]: --> [2:OK]
Dez 20 23:18:26.98: [18162]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled
Dez 20 23:18:26.98: [18162]: <-- [10:AT+FTM=24\r]
Dez 20 23:18:26.98: [18162]: --> [7:CONNECT]
Dez 20 23:18:26.98: [18162]: DELAY 400 ms
Dez 20 23:18:27.38: [18162]: <-- data [450]
Dez 20 23:18:27.38: [18162]: <-- data [2]
Dez 20 23:18:29.76: [18162]: --> [2:OK]
Dez 20 23:18:29.76: [18162]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled
Dez 20 23:18:29.76: [18162]: <-- [9:AT+FRS=1\r]
Dez 20 23:18:29.80: [18162]: --> [2:OK]
Dez 20 23:18:29.80: [18162]: <-- [9:AT+FRH=3\r]
Dez 20 23:18:32.90: [18162]: --> [0:]
Dez 20 23:18:32.90: [18162]: MODEM <Empty line>
Dez 20 23:18:32.90: [18162]: MODEM TIMEOUT: waiting for v.21 carrier
Dez 20 23:18:32.90: [18162]: <-- data [1]
Dez 20 23:18:32.90: [18162]: --> [2:]
Dez 20 23:18:32.90: [18162]: --> [2:OK]
Dez 20 23:18:32.90: [18162]: <-- [9:AT+FRS=7\r]
Dez 20 23:18:32.99: [18162]: --> [2:OK]
Dez 20 23:18:32.99: [18162]: TRAINING failed
Dez 20 23:18:32.99: [18162]: SEND: Giving up after 3 attempts to send same page "docq/doc18.tif;c0", dirnum 0
Dez 20 23:18:32.99: [18162]: <-- [9:AT+FTH=3\r]
Dez 20 23:18:33.80: [18162]: --> [7:CONNECT]
Dez 20 23:18:33.80: [18162]: <-- HDLC<3:FF C8 DF>
Dez 20 23:18:33.80: [18162]: <-- data [3]
Dez 20 23:18:33.80: [18162]: <-- data [2]
Dez 20 23:18:34.12: [18162]: --> [2:OK]
Dez 20 23:18:34.12: [18162]: MODEM input buffering enabled
Dez 20 23:18:35.12: [18162]: <-- [5:ATH0\r]
Dez 20 23:18:35.94: [18162]: --> [2:OK]
Dez 20 23:18:35.94: [18162]: MODEM set DTR OFF
Dez 20 23:18:35.94: [18162]: STATE CHANGE: SENDING -> MODEMWAIT (timeout 5)
Dez 20 23:18:35.94: [18162]: SESSION END
My config.ttyACM0 looks as follows:
CountryCode: 49
AreaCode: XXX
FAXNumber: XXX
LongDistancePrefix: 0
InternationalPrefix: 00
DialStringRules: etc/dialrules
ServerTracing: 1
SessionTracing: 11
RecvFileMode: 0644
LogFileMode: 0644
DeviceMode: 0666
RingsBeforeAnswer: 1
SpeakerVolume: High
GettyArgs: "-h %l dx_%s"
LocalIdentifier: "XXX"
TagLineFont: etc/lutRS18.pcf
TagLineFormat: "From %%l|%c|Page %%P of %%T"
PercentGoodLines: 90
MaxConsecutiveBadLines: 8
MaxRecvPages: 100
ModemType: Class1 # use class 1 interface
ModemRate: 19200 # rate for DCE-DTE communication
ModemFlowControl: xonxoff # software flow control
#ModemFlowControl: rtscts # software flow control
ModemSetupDTRCmd: ATS13=1&D2 # setup so DTR drop resets modem
ModemSetupDCDCmd: AT&C1 # setup so DCD reflects carrier (or not)
ModemNoFlowCmd: AT&H0 # setup modem for no flow control
ModemHardFlowCmd: AT&H1 # setup modem for hardware flow control
ModemSoftFlowCmd: AT&H2 # setup modem for software flow control
ModemResultCodesCmd: ATX4 # enable result codes
ModemMfrQueryCmd: !USR
ModemModelQueryCmd: ATI3
ModemRevQueryCmd: ATI7 # XXX returns a multi-line result
Class1NFLOCmd: AT&H0 # setup modem for no flow control
Class1HFLOCmd: AT&H1 # setup modem for hardware flow control
Class1SFLOCmd: "" # modem does this automatically
Class1ResponseWaitCmd: AT+FRS=1 # wait after sending TCF for response
ModemSetupAACmd: AT+FCLASS=0 # leave modem idling in class 0
ModemAnswerCmd: AT+FCLASS=1A # answer in Class 1
#Class1SwitchingCmd: "<delay\0727>"
Class1TMConnectDelay: 400
#Class1ECMSupport: no
#Class1RMQueryCmd: "!24,48,72,96" # V.17 fast-train recv doesn't work well
#RTNHandlingMethod: giveup #or ignore
SessionTracing: 0xFFF
ServerTracing: 0xFFF
I get this error with a remote site as well as with calling another
Fax solution receiving faxes on my own ISDN port.
Any hint would be much appreciated.
Thanks, Christian
____________________ 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*
- Follow-Ups:
- Re: [hylafax-users] failure to train remote modem at 2400 bps
- From: Lee Howard
- Re: [hylafax-users] failure to train remote modem at 2400 bps
- Prev by Date: [hylafax-users] suppoted modems
- Next by Date: Re: [hylafax-users] suppoted modems
- Previous by thread: Re: [hylafax-users] suppoted modems
- Next by thread: Re: [hylafax-users] failure to train remote modem at 2400 bps
- Index(es):
Project hosted by iFAX Solutions