flexfax: SEND: No response to MPS or EOP repeated 3 tries

[Gildas Perrot <perrot@francenet.fr>]

Hi,

I am using HylaFAX 4.0pl1 which comes on BSD/OS 2.1. I am using it to send fax 
to fax machines using a USR Courier v34 Fax modem. I am using Class1 since I 
had problems with Class2.0 in the past (error "DCS sent 3 times without 
response" and someone suggested me to go to Class1).
Few faxes don't receive the faxes and I have the following error message :

Jun 22 17:01:21.56: [24722]: REMOTE best rate 14400 bit/s
Jun 22 17:01:21.56: [24722]: REMOTE max page width 1728 pixels in 215 mm
Jun 22 17:01:21.56: [24722]: REMOTE max A4 page length (297 mm)
Jun 22 17:01:21.56: [24722]: REMOTE best vres 7.7 line/mm
Jun 22 17:01:21.56: [24722]: REMOTE best format 2-D MR
Jun 22 17:01:21.56: [24722]: REMOTE supports T.30 Annex A, ECM
Jun 22 17:01:21.56: [24722]: REMOTE best 10 ms, 5 ms/scanline
Jun 22 17:01:21.56: [24722]: USE 14400 bit/s
Jun 22 17:01:21.57: [24722]: USE 10 ms, 5 ms/scanline
Jun 22 17:01:21.57: [24722]: SEND file "docq/doc3064.ps;01"
Jun 22 17:01:21.57: [24722]: USE page width 1728 pixels in 215 mm
Jun 22 17:01:21.57: [24722]: USE A4 page length (297 mm)
Jun 22 17:01:21.57: [24722]: USE 7.7 line/mm
Jun 22 17:01:21.57: [24722]: USE 1-D MR
Jun 22 17:01:21.57: [24722]: SEND training at v.17 14400 bit/s
Jun 22 17:01:21.57: [24722]: <-- [9:AT+FTH=3\r]
Jun 22 17:01:21.69: [24722]: --> [7:CONNECT]
Jun 22 17:01:23.43: [24722]: --> [7:CONNECT]
Jun 22 17:01:24.13: [24722]: --> [2:OK]
Jun 22 17:01:24.13: [24722]: DELAY 75 ms
Jun 22 17:01:24.19: [24722]: <-- [11:AT+FTM=145\r]
Jun 22 17:01:25.86: [24722]: --> [7:CONNECT]
Jun 22 17:01:27.38: [24722]: --> [2:OK]
Jun 22 17:01:27.38: [24722]: <-- [9:AT+FRH=3\r]
Jun 22 17:01:28.79: [24722]: --> [7:CONNECT]
Jun 22 17:01:28.93: [24722]: --> [2:OK]
Jun 22 17:01:28.93: [24722]: DELAY 1500 ms
Jun 22 17:01:30.43: [24722]: SEND training at v.17 14400 bit/s
Jun 22 17:01:30.43: [24722]: <-- [9:AT+FTH=3\r]
Jun 22 17:01:30.54: [24722]: --> [7:CONNECT]
Jun 22 17:01:32.28: [24722]: --> [7:CONNECT]
Jun 22 17:01:32.98: [24722]: --> [2:OK]
Jun 22 17:01:32.98: [24722]: DELAY 75 ms
Jun 22 17:01:33.05: [24722]: <-- [11:AT+FTM=145\r]
Jun 22 17:01:34.72: [24722]: --> [7:CONNECT]
Jun 22 17:01:36.24: [24722]: --> [2:OK]
Jun 22 17:01:36.24: [24722]: <-- [9:AT+FRH=3\r]
Jun 22 17:01:37.45: [24722]: --> [7:CONNECT]
Jun 22 17:01:37.59: [24722]: --> [2:OK]
Jun 22 17:01:37.59: [24722]: DELAY 1500 ms
Jun 22 17:01:39.09: [24722]: SEND training at v.17 12000 bit/s
Jun 22 17:01:39.09: [24722]: <-- [9:AT+FTH=3\r]
Jun 22 17:01:39.20: [24722]: --> [7:CONNECT]
Jun 22 17:01:40.94: [24722]: --> [7:CONNECT]
Jun 22 17:01:41.64: [24722]: --> [2:OK]
Jun 22 17:01:41.64: [24722]: DELAY 75 ms
Jun 22 17:01:41.71: [24722]: <-- [11:AT+FTM=121\r]
Jun 22 17:01:43.37: [24722]: --> [7:CONNECT]
Jun 22 17:01:44.90: [24722]: --> [2:OK]
Jun 22 17:01:44.90: [24722]: <-- [9:AT+FRH=3\r]
Jun 22 17:01:46.10: [24722]: --> [7:CONNECT]
Jun 22 17:01:46.24: [24722]: --> [2:OK]
Jun 22 17:01:46.24: [24722]: DELAY 1500 ms
Jun 22 17:01:47.74: [24722]: SEND training at v.17 12000 bit/s
Jun 22 17:01:47.74: [24722]: <-- [9:AT+FTH=3\r]
Jun 22 17:01:47.85: [24722]: --> [7:CONNECT]
Jun 22 17:01:49.59: [24722]: --> [7:CONNECT]
Jun 22 17:01:50.29: [24722]: --> [2:OK]
Jun 22 17:01:50.29: [24722]: DELAY 75 ms
Jun 22 17:01:50.36: [24722]: <-- [11:AT+FTM=121\r]
Jun 22 17:01:52.03: [24722]: --> [7:CONNECT]
Jun 22 17:01:53.55: [24722]: --> [2:OK]
Jun 22 17:01:53.56: [24722]: <-- [9:AT+FRH=3\r]
Jun 22 17:01:54.75: [24722]: --> [7:CONNECT]
Jun 22 17:01:54.89: [24722]: --> [2:OK]
Jun 22 17:01:54.89: [24722]: DELAY 1500 ms
Jun 22 17:01:56.39: [24722]: SEND training at v.17 9600 bit/s
Jun 22 17:01:56.39: [24722]: <-- [9:AT+FTH=3\r]
Jun 22 17:01:56.50: [24722]: --> [7:CONNECT]
Jun 22 17:01:58.24: [24722]: --> [7:CONNECT]
Jun 22 17:01:58.94: [24722]: --> [2:OK]
Jun 22 17:01:58.94: [24722]: DELAY 75 ms
Jun 22 17:01:59.01: [24722]: <-- [10:AT+FTM=97\r]
Jun 22 17:02:00.67: [24722]: --> [7:CONNECT]
Jun 22 17:02:02.20: [24722]: --> [2:OK]
Jun 22 17:02:02.20: [24722]: <-- [9:AT+FRH=3\r]
Jun 22 17:02:03.40: [24722]: --> [7:CONNECT]
Jun 22 17:02:03.54: [24722]: --> [2:OK]
Jun 22 17:02:03.54: [24722]: TRAINING succeeded
Jun 22 17:02:03.54: [24722]: <-- [10:AT+FTM=98\r]
Jun 22 17:02:03.73: [24722]: --> [7:CONNECT]
Jun 22 17:02:03.73: [24722]: SEND begin page
Jun 22 17:02:12.62: [24722]: SENT 13735 bytes of data
Jun 22 17:02:25.97: [24722]: SENT 13756 bytes of data
Jun 22 17:02:25.97: [24722]: SENT 2598 bytes of data
Jun 22 17:02:25.97: [24722]: SEND 1D RTC
Jun 22 17:02:25.98: [24722]: SEND end page
Jun 22 17:02:28.92: [24722]: --> [2:OK]
Jun 22 17:02:28.92: [24722]: DELAY 95 ms
Jun 22 17:02:29.00: [24722]: SEND send EOP (no more pages or documents)
Jun 22 17:02:29.00: [24722]: <-- [9:AT+FTH=3\r]
Jun 22 17:02:29.12: [24722]: --> [7:CONNECT]
Jun 22 17:02:30.33: [24722]: --> [2:OK]
Jun 22 17:02:30.33: [24722]: <-- [9:AT+FRH=3\r]
Jun 22 17:02:33.42: [24722]: --> [0:]
Jun 22 17:02:33.42: [24722]: MODEM <Empty line>
Jun 22 17:02:33.43: [24722]: --> [2:OK]
Jun 22 17:02:33.43: [24722]: SEND send EOP (no more pages or documents)
Jun 22 17:02:33.43: [24722]: <-- [9:AT+FTH=3\r]
Jun 22 17:02:33.54: [24722]: --> [7:CONNECT]
Jun 22 17:02:34.75: [24722]: --> [2:OK]
Jun 22 17:02:34.75: [24722]: <-- [9:AT+FRH=3\r]
Jun 22 17:02:37.85: [24722]: --> [0:]
Jun 22 17:02:37.85: [24722]: MODEM <Empty line>
Jun 22 17:02:37.85: [24722]: --> [2:OK]
Jun 22 17:02:37.85: [24722]: SEND send EOP (no more pages or documents)
Jun 22 17:02:37.85: [24722]: <-- [9:AT+FTH=3\r]
Jun 22 17:02:37.96: [24722]: --> [7:CONNECT]
Jun 22 17:02:39.17: [24722]: --> [2:OK]
Jun 22 17:02:39.17: [24722]: <-- [9:AT+FRH=3\r]
Jun 22 17:02:42.27: [24722]: --> [0:]
Jun 22 17:02:42.27: [24722]: MODEM <Empty line>
Jun 22 17:02:42.27: [24722]: --> [2:OK]
Jun 22 17:02:42.28: [24722]: SEND: No response to MPS or EOP repeated 3 tries; 
Giving up after 3 attempts to send same page "docq/doc3064.ps;01", dirnum 0
Jun 22 17:02:42.28: [24722]: <-- [9:AT+FTH=3\r]
Jun 22 17:02:42.39: [24722]: --> [7:CONNECT]
Jun 22 17:02:43.60: [24722]: --> [2:OK]
Jun 22 17:02:43.60: [24722]: <-- [5:ATH0\r]
Jun 22 17:02:44.00: [24722]: --> [2:OK]
Jun 22 17:02:44.04: [24722]: SESSION END

Any idea about that problem ? Is there any chance that 4.0pl2 solves it ?

	Thanks in advance for your help.		Gildas.
-- 
Gildas PERROT, perrot@francenet.fr         __o
FranceNet, 28 rue Desaix, 75015 Paris ---_ \<,_
http://www.francenet.fr            ---- (_)/ (_)