[hylafax-users] Failure to train remote modem at 2400 ...

[Michele Petrazzo <michele.petrazzo@xxxxxxxxx>]

I have this problem than I send a fax. I have seen that there is a bug
in hylafax (http://bugs.hylafax.org/bugzilla/show_bug.cgi?id=419) with
old Rockwells modems, but I have a new zyxel modem!
His page is:
http://www.zyxel.com/product/model.php?indexcate=1021948331&indexFlagvalue=1021876983

I use hylafax 4.2.0 on debian testing

Thanks,
Michele Petrazzo


My config is: (I delete commented lines)

ModemType:              Class1          # use this to supply a hint
Class1Cmd:              AT+FCLASS=1     # command to enter class 1
Class1PPMWaitCmd:       AT+FTS=7        # command to stop and wait
before PPM
Class1TCFWaitCmd:       AT+FTS=7        # command to stop and wait
before TCF
Class1EOPWaitCmd:       AT+FTS=9        # command to stop and wait
before EOP
Class1MsgRecvHackCmd:   ""      # command to avoid +FCERROR before image
Class1TCFResponseDelay: 75              # 75ms delay between recv TCF &
response
Class1SendMsgDelay:     200             # 75ms delay after training
Class1SwitchingCmd:     AT+FRS=7        # silence between HDLC recv and
trans
Class1TrainingRecovery: 1500            # 1.5sec delay after training
failure
Class1RecvAbortOK:      200             # wait 200ms for abort response
Class1FrameOverhead:    4               # 4 byte overhead in recvd HDLC
frames
Class1RecvIdentTimer:   40000           # 35+5secs waiting for ident frames
Class1TCFMaxNonZero:    10              # max 10% of data may be non-zero
Class1TCFMinRun:        1000            # min run is 2/3rds of TCF duration


I delete the number and the remote id and the fax numbers for privacy
problems.

Jan 26 10:00:37.74: [ 8903]: SESSION BEGIN 000001565 003904310432XXXXX
Jan 26 10:00:37.74: [ 8903]: HylaFAX (tm) Version 4.2.0
Jan 26 10:00:37.74: [ 8903]: SEND FAX: JOB 613 DEST 0432xxxxx COMMID
000001565 DEVICE '/dev/ttyS0'
Jan 26 10:00:37.74: [ 8903]: <-- [12:AT+FCLASS=1\r]
Jan 26 10:00:37.87: [ 8903]: --> [2:OK]
Jan 26 10:00:37.87: [ 8903]: DIAL 0432xxxxx
Jan 26 10:00:37.87: [ 8903]: <-- [15:ATDT0432xxxxx\r]
Jan 26 10:00:50.23: [ 8903]: --> [7:CONNECT]
Jan 26 10:00:52.06: [ 8903]: --> [2:OK]
Jan 26 10:00:52.06: [ 8903]: REMOTE NSF "00 00 11 80 00 8A 49 10 44 41
4E 49 45 4C 49 20 43 4F 52 50 4F 52 41 54 00 4F 00 80 80 D8 8E 01 01 01"
Jan 26 10:00:52.06: [ 8903]: NSF remote fax equipment: Canon Laser Class
9000 Series
Jan 26 10:00:52.06: [ 8903]: NSF remote station ID: "xxxxxx"
Jan 26 10:00:52.06: [ 8903]: <-- [9:AT+FRH=3\r]
Jan 26 10:00:52.10: [ 8903]: --> [7:CONNECT]
Jan 26 10:00:52.75: [ 8903]: --> [2:OK]
Jan 26 10:00:52.75: [ 8903]: REMOTE CSI "+39 0432 xxxxx"
Jan 26 10:00:52.75: [ 8903]: <-- [9:AT+FRH=3\r]
Jan 26 10:00:52.79: [ 8903]: --> [7:CONNECT]
Jan 26 10:00:53.02: [ 8903]: --> [2:OK]
Jan 26 10:00:53.02: [ 8903]: REMOTE best rate 14400 bit/s
Jan 26 10:00:53.02: [ 8903]: REMOTE max A4 page width (215 mm)
Jan 26 10:00:53.02: [ 8903]: REMOTE max unlimited page length
Jan 26 10:00:53.02: [ 8903]: REMOTE best vres 7.7 line/mm
Jan 26 10:00:53.02: [ 8903]: REMOTE best format 2-D MR
Jan 26 10:00:53.02: [ 8903]: REMOTE best 10 ms/scanline
Jan 26 10:00:53.02: [ 8903]: USE 14400 bit/s
Jan 26 10:00:53.02: [ 8903]: USE 10 ms/scanline
Jan 26 10:00:53.02: [ 8903]: SEND file "docq/doc787.tif;41"
Jan 26 10:00:53.02: [ 8903]: USE A4 page width (215 mm)
Jan 26 10:00:53.02: [ 8903]: USE unlimited page length
Jan 26 10:00:53.02: [ 8903]: USE 7.7 line/mm
Jan 26 10:00:53.02: [ 8903]: USE 2-D MR
Jan 26 10:00:53.02: [ 8903]: SEND training at v.17 14400 bit/s
Jan 26 10:00:53.02: [ 8903]: <-- [9:AT+FTH=3\r]
Jan 26 10:00:54.06: [ 8903]: --> [7:CONNECT]
Jan 26 10:00:54.06: [ 8903]: <-- data [23]
Jan 26 10:00:54.06: [ 8903]: <-- data [2]
Jan 26 10:00:55.07: [ 8903]: --> [7:CONNECT]
Jan 26 10:00:55.07: [ 8903]: <-- data [6]
Jan 26 10:00:55.07: [ 8903]: <-- data [2]
Jan 26 10:00:55.39: [ 8903]: --> [2:OK]
Jan 26 10:00:55.39: [ 8903]: <-- [9:AT+FTS=7\r]
Jan 26 10:00:55.50: [ 8903]: --> [2:OK]
Jan 26 10:00:55.50: [ 8903]: <-- [11:AT+FTM=145\r]
Jan 26 10:00:57.28: [ 8903]: --> [7:CONNECT]
Jan 26 10:00:57.28: [ 8903]: <-- data [1024]
Jan 26 10:00:57.28: [ 8903]: <-- data [1024]
Jan 26 10:00:57.28: [ 8903]: <-- data [652]
Jan 26 10:00:57.28: [ 8903]: <-- data [2]
Jan 26 10:00:58.96: [ 8903]: --> [2:OK]
Jan 26 10:00:58.96: [ 8903]: <-- [9:AT+FRH=3\r]
Jan 26 10:00:59.25: [ 8903]: --> [7:CONNECT]
Jan 26 10:01:00.13: [ 8903]: --> [2:OK]
Jan 26 10:01:00.13: [ 8903]: HDLC frame too short (3 bytes)
Jan 26 10:01:00.13: [ 8903]: DELAY 1500 ms
Jan 26 10:01:01.63: [ 8903]: SEND training at v.17 12000 bit/s
Jan 26 10:01:01.63: [ 8903]: <-- [9:AT+FTH=3\r]
Jan 26 10:01:02.67: [ 8903]: --> [7:CONNECT]
Jan 26 10:01:02.67: [ 8903]: <-- data [23]
Jan 26 10:01:02.67: [ 8903]: <-- data [2]
Jan 26 10:01:03.68: [ 8903]: --> [7:CONNECT]
Jan 26 10:01:03.68: [ 8903]: <-- data [6]
Jan 26 10:01:03.68: [ 8903]: <-- data [2]
Jan 26 10:01:04.00: [ 8903]: --> [2:OK]
Jan 26 10:01:04.00: [ 8903]: <-- [9:AT+FTS=7\r]
Jan 26 10:01:04.11: [ 8903]: --> [2:OK]
Jan 26 10:01:04.11: [ 8903]: <-- [11:AT+FTM=121\r]
Jan 26 10:01:05.89: [ 8903]: --> [7:CONNECT]
Jan 26 10:01:05.89: [ 8903]: <-- data [1024]
Jan 26 10:01:05.89: [ 8903]: <-- data [1024]
Jan 26 10:01:05.89: [ 8903]: <-- data [202]
Jan 26 10:01:05.89: [ 8903]: <-- data [2]
Jan 26 10:01:07.57: [ 8903]: --> [2:OK]
Jan 26 10:01:07.57: [ 8903]: <-- [9:AT+FRH=3\r]
Jan 26 10:01:07.86: [ 8903]: --> [7:CONNECT]
Jan 26 10:01:08.74: [ 8903]: --> [2:OK]
Jan 26 10:01:08.74: [ 8903]: HDLC frame too short (3 bytes)
Jan 26 10:01:08.74: [ 8903]: DELAY 1500 ms
Jan 26 10:01:10.24: [ 8903]: SEND training at v.17 9600 bit/s
Jan 26 10:01:10.24: [ 8903]: <-- [9:AT+FTH=3\r]
Jan 26 10:01:11.28: [ 8903]: --> [7:CONNECT]
Jan 26 10:01:11.28: [ 8903]: <-- data [23]
Jan 26 10:01:11.28: [ 8903]: <-- data [2]
Jan 26 10:01:12.29: [ 8903]: --> [7:CONNECT]
Jan 26 10:01:12.29: [ 8903]: <-- data [6]
Jan 26 10:01:12.29: [ 8903]: <-- data [2]
Jan 26 10:01:12.61: [ 8903]: --> [2:OK]
Jan 26 10:01:12.61: [ 8903]: <-- [9:AT+FTS=7\r]
Jan 26 10:01:12.72: [ 8903]: --> [2:OK]
Jan 26 10:01:12.72: [ 8903]: <-- [10:AT+FTM=97\r]
Jan 26 10:01:14.50: [ 8903]: --> [7:CONNECT]
Jan 26 10:01:14.50: [ 8903]: <-- data [1024]
Jan 26 10:01:14.50: [ 8903]: <-- data [776]
Jan 26 10:01:14.50: [ 8903]: <-- data [2]
Jan 26 10:01:16.42: [ 8903]: --> [2:OK]
Jan 26 10:01:16.42: [ 8903]: <-- [9:AT+FRH=3\r]
Jan 26 10:01:16.70: [ 8903]: --> [7:CONNECT]
Jan 26 10:01:17.34: [ 8903]: --> [2:OK]
Jan 26 10:01:17.34: [ 8903]: HDLC frame too short (3 bytes)
Jan 26 10:01:17.34: [ 8903]: DELAY 1500 ms
Jan 26 10:01:18.84: [ 8903]: SEND training at v.29 9600 bit/s
Jan 26 10:01:18.84: [ 8903]: <-- [9:AT+FTH=3\r]
Jan 26 10:01:19.88: [ 8903]: --> [7:CONNECT]
Jan 26 10:01:19.88: [ 8903]: <-- data [23]
Jan 26 10:01:19.88: [ 8903]: <-- data [2]
Jan 26 10:01:20.89: [ 8903]: --> [7:CONNECT]
Jan 26 10:01:20.89: [ 8903]: <-- data [6]
Jan 26 10:01:20.89: [ 8903]: <-- data [2]
Jan 26 10:01:21.21: [ 8903]: --> [2:OK]
Jan 26 10:01:21.21: [ 8903]: <-- [9:AT+FTS=7\r]
Jan 26 10:01:21.32: [ 8903]: --> [2:OK]
Jan 26 10:01:21.32: [ 8903]: <-- [10:AT+FTM=96\r]
Jan 26 10:01:21.94: [ 8903]: --> [7:CONNECT]
Jan 26 10:01:21.94: [ 8903]: <-- data [1024]
Jan 26 10:01:21.94: [ 8903]: <-- data [776]
Jan 26 10:01:21.94: [ 8903]: <-- data [2]
Jan 26 10:01:23.86: [ 8903]: --> [2:OK]
Jan 26 10:01:23.86: [ 8903]: <-- [9:AT+FRH=3\r]
Jan 26 10:01:24.14: [ 8903]: --> [7:CONNECT]
Jan 26 10:01:24.78: [ 8903]: --> [2:OK]
Jan 26 10:01:24.78: [ 8903]: HDLC frame too short (3 bytes)
Jan 26 10:01:24.78: [ 8903]: DELAY 1500 ms
Jan 26 10:01:26.28: [ 8903]: SEND training at v.29 7200 bit/s
Jan 26 10:01:26.28: [ 8903]: <-- [9:AT+FTH=3\r]
Jan 26 10:01:27.32: [ 8903]: --> [7:CONNECT]
Jan 26 10:01:27.32: [ 8903]: <-- data [23]
Jan 26 10:01:27.32: [ 8903]: <-- data [2]
Jan 26 10:01:28.32: [ 8903]: --> [7:CONNECT]
Jan 26 10:01:28.32: [ 8903]: <-- data [6]
Jan 26 10:01:28.32: [ 8903]: <-- data [2]
Jan 26 10:01:28.62: [ 8903]: --> [2:OK]
Jan 26 10:01:28.62: [ 8903]: <-- [9:AT+FTS=7\r]
Jan 26 10:01:28.72: [ 8903]: --> [2:OK]
Jan 26 10:01:28.72: [ 8903]: <-- [10:AT+FTM=72\r]
Jan 26 10:01:29.34: [ 8903]: --> [7:CONNECT]
Jan 26 10:01:29.34: [ 8903]: <-- data [1024]
Jan 26 10:01:29.34: [ 8903]: <-- data [326]
Jan 26 10:01:29.34: [ 8903]: <-- data [2]
Jan 26 10:01:30.99: [ 8903]: --> [2:OK]
Jan 26 10:01:30.99: [ 8903]: <-- [9:AT+FRH=3\r]
Jan 26 10:01:31.27: [ 8903]: --> [7:CONNECT]
Jan 26 10:01:32.15: [ 8903]: --> [2:OK]
Jan 26 10:01:32.15: [ 8903]: HDLC frame too short (3 bytes)
Jan 26 10:01:32.15: [ 8903]: DELAY 1500 ms
Jan 26 10:01:33.65: [ 8903]: SEND training at v.27ter 4800 bit/s
Jan 26 10:01:33.65: [ 8903]: <-- [9:AT+FTH=3\r]
Jan 26 10:01:34.69: [ 8903]: --> [7:CONNECT]
Jan 26 10:01:34.69: [ 8903]: <-- data [23]
Jan 26 10:01:34.69: [ 8903]: <-- data [2]
Jan 26 10:01:35.71: [ 8903]: --> [7:CONNECT]
Jan 26 10:01:35.71: [ 8903]: <-- data [6]
Jan 26 10:01:35.71: [ 8903]: <-- data [2]
Jan 26 10:01:36.01: [ 8903]: --> [2:OK]
Jan 26 10:01:36.01: [ 8903]: <-- [9:AT+FTS=7\r]
Jan 26 10:01:36.11: [ 8903]: --> [2:OK]
Jan 26 10:01:36.11: [ 8903]: <-- [10:AT+FTM=48\r]
Jan 26 10:01:37.14: [ 8903]: --> [7:CONNECT]
Jan 26 10:01:37.14: [ 8903]: <-- data [900]
Jan 26 10:01:37.14: [ 8903]: <-- data [2]
Jan 26 10:01:38.71: [ 8903]: --> [2:OK]
Jan 26 10:01:38.71: [ 8903]: <-- [9:AT+FRH=3\r]
Jan 26 10:01:39.00: [ 8903]: --> [7:CONNECT]
Jan 26 10:01:39.96: [ 8903]: --> [2:OK]
Jan 26 10:01:39.96: [ 8903]: HDLC frame too short (3 bytes)
Jan 26 10:01:39.96: [ 8903]: DELAY 1500 ms
Jan 26 10:01:41.46: [ 8903]: SEND training at v.27ter fallback mode 2400
bit/s
Jan 26 10:01:41.46: [ 8903]: <-- [9:AT+FTH=3\r]
Jan 26 10:01:42.51: [ 8903]: --> [7:CONNECT]
Jan 26 10:01:42.51: [ 8903]: <-- data [23]
Jan 26 10:01:42.51: [ 8903]: <-- data [2]
Jan 26 10:01:43.53: [ 8903]: --> [7:CONNECT]
Jan 26 10:01:43.53: [ 8903]: <-- data [6]
Jan 26 10:01:43.53: [ 8903]: <-- data [2]
Jan 26 10:01:43.86: [ 8903]: --> [2:OK]
Jan 26 10:01:43.86: [ 8903]: <-- [9:AT+FTS=7\r]
Jan 26 10:01:43.97: [ 8903]: --> [2:OK]
Jan 26 10:01:43.97: [ 8903]: <-- [10:AT+FTM=24\r]
Jan 26 10:01:45.25: [ 8903]: --> [7:CONNECT]
Jan 26 10:01:45.25: [ 8903]: <-- data [450]
Jan 26 10:01:45.25: [ 8903]: <-- data [2]
Jan 26 10:01:46.81: [ 8903]: --> [2:OK]
Jan 26 10:01:46.81: [ 8903]: <-- [9:AT+FRH=3\r]
Jan 26 10:01:47.10: [ 8903]: --> [7:CONNECT]
Jan 26 10:01:48.06: [ 8903]: --> [2:OK]
Jan 26 10:01:48.06: [ 8903]: HDLC frame too short (3 bytes)
Jan 26 10:01:48.06: [ 8903]: DELAY 1500 ms
Jan 26 10:01:49.56: [ 8903]: TRAINING failed
Jan 26 10:01:49.56: [ 8903]: SEND: Failure to train remote modem at 2400
bps or minimum speed; Giving up after 3 attempts to send same page
"docq/doc787.tif;41", dirnum 0
Jan 26 10:01:49.56: [ 8903]: <-- [9:AT+FTH=3\r]
Jan 26 10:01:50.60: [ 8903]: --> [7:CONNECT]
Jan 26 10:01:50.60: [ 8903]: <-- data [3]
Jan 26 10:01:50.60: [ 8903]: <-- data [2]
Jan 26 10:01:50.84: [ 8903]: --> [2:OK]
Jan 26 10:01:50.84: [ 8903]: <-- [5:ATH0\r]
Jan 26 10:01:51.05: [ 8903]: --> [2:OK]
Jan 26 10:01:51.05: [ 8903]: 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*