[hylafax-users] Stop and wait failure on FTS=7

[V.Dormeyer@t-online.de (Volker Dormeyer)]

Hi,

my fax system is based on GNU/Linux with a Linux kernel version of
2.4.17. Fax hardware is a Sedlbauer Speedfax PCI ISDN adapter. Hylafax
version is 4.1.1.

The problem is, if I send a fax to some receivers (not all, but most
of them) the following error is shown in the logs:

May 23 01:43:39.05: [ 3713]: SEND training at v.17 14400 bit/s
May 23 01:43:39.05: [ 3713]: <-- [9:AT+FTH=3\r]
May 23 01:43:40.85: [ 3713]: --> [7:CONNECT]
May 23 01:43:40.85: [ 3713]: <-- data [23]
May 23 01:43:40.85: [ 3713]: <-- data [2]
May 23 01:43:40.87: [ 3713]: --> [7:CONNECT]
May 23 01:43:40.87: [ 3713]: <-- data [6]
May 23 01:43:40.87: [ 3713]: <-- data [2]
May 23 01:43:41.87: [ 3713]: --> [2:OK]
May 23 01:43:41.87: [ 3713]: <-- [9:AT+FTS=7\r]
May 23 01:43:41.88: [ 3713]: --> [5:ERROR]
May 23 01:43:41.88: [ 3713]: MODEM Command error
May 23 01:43:41.88: [ 3713]: SEND: Stop and wait failure (modem on
hook); Giving up after 3 attempts to send same page "docq/doc2.ps;00",
dirnum 0
May 23 01:43:41.88: [ 3713]: <-- [9:AT+FTH=3\r]
May 23 01:43:43.51: [ 3713]: --> [7:CONNECT]
May 23 01:43:43.51: [ 3713]: <-- data [3]
May 23 01:43:43.51: [ 3713]: <-- data [2]
May 23 01:43:43.77: [ 3713]: --> [2:OK]
May 23 01:43:43.77: [ 3713]: MODEM input buffering enabled
May 23 01:43:43.77: [ 3713]: <-- [5:ATH0\r]

I tried to increase the FTS value from 70 to 90 ms but it doesn't
help. Any ideas?

You will find attached the complete log file.

Thanks and regards,
Volker


-- 
 Volker Dormeyer # v.dormeyer@t-online.de

May 23 01:43:19.60: [ 3713]: SESSION BEGIN 00000004 49721372580
May 23 01:43:19.60: [ 3713]: HylaFAX (tm) Version 4.1.1
May 23 01:43:19.60: [ 3713]: SEND FAX: JOB 2 DEST 0721372580 COMMID 00000004 DEVICE '/dev/ttyI0'
May 23 01:43:19.60: [ 3713]: MODEM set DTR OFF
May 23 01:43:19.60: [ 3713]: DELAY 75 ms
May 23 01:43:19.68: [ 3713]: MODEM set DTR ON
May 23 01:43:19.68: [ 3713]: DELAY 2600 ms
May 23 01:43:22.28: [ 3713]: MODEM set baud rate: 19200 baud, input flow XON/XOFF, output flow XON/XOFF
May 23 01:43:22.28: [ 3713]: MODEM flush i/o
May 23 01:43:22.28: [ 3713]: <-- [4:ATZ\r]
May 23 01:43:22.29: [ 3713]: --> [2:OK]
May 23 01:43:22.29: [ 3713]: DELAY 3000 ms
May 23 01:43:25.29: [ 3713]: <-- [20:AT&E805812+FCLASS=1\r]
May 23 01:43:25.30: [ 3713]: --> [19:AT&E805812+FCLASS=1]
May 23 01:43:25.30: [ 3713]: --> [2:OK]
May 23 01:43:25.30: [ 3713]: <-- [5:ATE0\r]
May 23 01:43:25.31: [ 3713]: --> [4:ATE0]
May 23 01:43:25.31: [ 3713]: --> [2:OK]
May 23 01:43:25.31: [ 3713]: <-- [5:ATV1\r]
May 23 01:43:25.32: [ 3713]: --> [2:OK]
May 23 01:43:25.32: [ 3713]: <-- [5:ATQ0\r]
May 23 01:43:25.33: [ 3713]: --> [2:OK]
May 23 01:43:25.33: [ 3713]: <-- [7:ATS0=0\r]
May 23 01:43:25.34: [ 3713]: --> [2:OK]
May 23 01:43:25.34: [ 3713]: <-- [7:ATS8=2\r]
May 23 01:43:25.35: [ 3713]: --> [2:OK]
May 23 01:43:25.35: [ 3713]: <-- [8:ATS7=60\r]
May 23 01:43:25.36: [ 3713]: --> [2:OK]
May 23 01:43:25.36: [ 3713]: <-- [12:AT+FCLASS=1\r]
May 23 01:43:25.37: [ 3713]: --> [2:OK]
May 23 01:43:25.37: [ 3713]: <-- [5:ATM0\r]
May 23 01:43:25.38: [ 3713]: --> [2:OK]
May 23 01:43:25.38: [ 3713]: MODEM input buffering enabled
May 23 01:43:25.38: [ 3713]: <-- [12:AT+FCLASS=1\r]
May 23 01:43:25.49: [ 3713]: --> [2:OK]
May 23 01:43:25.49: [ 3713]: MODEM set XON/XOFF/FLUSH: input ignored, output disabled
May 23 01:43:25.49: [ 3713]: DIAL 0721372580
May 23 01:43:25.49: [ 3713]: <-- [15:ATDT0xxxxxxxxx\r]
May 23 01:43:36.54: [ 3713]: --> [7:CONNECT]
May 23 01:43:36.54: [ 3713]: MODEM input buffering disabled
May 23 01:43:37.91: [ 3713]: --> [2:OK]
May 23 01:43:37.91: [ 3713]: REMOTE NSF "AD 00 36 20 00 45 00 0C 00 D5 C2 30 00 7F 93"
May 23 01:43:37.91: [ 3713]: NSF remote fax equipment: HP LaserJet 3150
May 23 01:43:37.91: [ 3713]: <-- [9:AT+FRH=3\r]
May 23 01:43:37.92: [ 3713]: --> [7:CONNECT]
May 23 01:43:38.61: [ 3713]: --> [2:OK]
May 23 01:43:38.61: [ 3713]: REMOTE CSI "0xxx xxxxxx"
May 23 01:43:38.61: [ 3713]: <-- [9:AT+FRH=3\r]
May 23 01:43:38.62: [ 3713]: --> [7:CONNECT]
May 23 01:43:39.05: [ 3713]: --> [2:OK]
May 23 01:43:39.05: [ 3713]: REMOTE best rate 14400 bit/s
May 23 01:43:39.05: [ 3713]: REMOTE max page width 1728 pixels in 215 mm
May 23 01:43:39.05: [ 3713]: REMOTE max unlimited page length 
May 23 01:43:39.05: [ 3713]: REMOTE best vres 7.7 line/mm
May 23 01:43:39.05: [ 3713]: REMOTE best format 2-D MMR
May 23 01:43:39.05: [ 3713]: REMOTE supports T.30 Annex A, ECM
May 23 01:43:39.05: [ 3713]: REMOTE best 0 ms/scanline
May 23 01:43:39.05: [ 3713]: USE 14400 bit/s
May 23 01:43:39.05: [ 3713]: USE 0 ms/scanline
May 23 01:43:39.05: [ 3713]: SEND file "docq/doc2.ps;00"
May 23 01:43:39.05: [ 3713]: USE page width 1728 pixels in 215 mm
May 23 01:43:39.05: [ 3713]: USE unlimited page length 
May 23 01:43:39.05: [ 3713]: USE 3.85 line/mm
May 23 01:43:39.05: [ 3713]: USE 1-D MR
May 23 01:43:39.05: [ 3713]: SEND training at v.17 14400 bit/s
May 23 01:43:39.05: [ 3713]: <-- [9:AT+FTH=3\r]
May 23 01:43:40.85: [ 3713]: --> [7:CONNECT]
May 23 01:43:40.85: [ 3713]: <-- data [23]
May 23 01:43:40.85: [ 3713]: <-- data [2]
May 23 01:43:40.87: [ 3713]: --> [7:CONNECT]
May 23 01:43:40.87: [ 3713]: <-- data [6]
May 23 01:43:40.87: [ 3713]: <-- data [2]
May 23 01:43:41.87: [ 3713]: --> [2:OK]
May 23 01:43:41.87: [ 3713]: <-- [9:AT+FTS=7\r]
May 23 01:43:41.88: [ 3713]: --> [5:ERROR]
May 23 01:43:41.88: [ 3713]: MODEM Command error
May 23 01:43:41.88: [ 3713]: SEND: Stop and wait failure (modem on hook); Giving up after 3 attempts to send same page "docq/doc2.ps;00", dirnum 0
May 23 01:43:41.88: [ 3713]: <-- [9:AT+FTH=3\r]
May 23 01:43:43.51: [ 3713]: --> [7:CONNECT]
May 23 01:43:43.51: [ 3713]: <-- data [3]
May 23 01:43:43.51: [ 3713]: <-- data [2]
May 23 01:43:43.77: [ 3713]: --> [2:OK]
May 23 01:43:43.77: [ 3713]: MODEM input buffering enabled
May 23 01:43:43.77: [ 3713]: <-- [5:ATH0\r]
May 23 01:43:43.89: [ 3713]: --> [10:NO CARRIER]
May 23 01:43:43.89: [ 3713]: MODEM No carrier
May 23 01:43:43.89: [ 3713]: MODEM set DTR OFF
May 23 01:43:43.89: [ 3713]: SESSION END