[hylafax-users] beta 3 and Cirrus chipset

[Paul van der Vlis <paul@vandervlis.nl>]

Hello! 

Nice to see that there is a new Hylafax version!

I am using a Best 3314VH internal 33k6 modem with Cirrus chipset
(faxclass 1) and the new beta3 of Hylafax for a customer, but I see the
following problem while sending a fax:

The fax is send, an EOP is send, but no responce from the other side, a
timeout follows. This happens 3 times, then the session ends, but
Hylafax thinks the fax was not send OK, so after some time Hylafax tries
again... 

Bye,
Paul van der Vlis

Mar 26 17:31:40.19: [ 1338]: SESSION BEGIN 00000003 31208738934
Mar 26 17:31:40.21: [ 1338]: SEND FAX: JOB 1 DEST 020-8738934 COMMID
00000003
Mar 26 17:31:40.21: [ 1338]: DELAY 2600 ms
Mar 26 17:31:42.80: [ 1338]: <-- [4:ATZ\r]
Mar 26 17:31:42.96: [ 1338]: --> [3:ATZ]
Mar 26 17:31:42.96: [ 1338]: --> [2:OK]
Mar 26 17:31:42.96: [ 1338]: <-- [15:ATE0V1Q0S0=0H0\r]
Mar 26 17:31:45.14: [ 1338]: --> [14:ATE0V1Q0S0=0H0]
Mar 26 17:31:45.14: [ 1338]: --> [2:OK]
Mar 26 17:31:45.14: [ 1338]: <-- [21:ATS8=2S7=60\Q3&D3&C1\r]
Mar 26 17:31:45.22: [ 1338]: --> [2:OK]
Mar 26 17:31:45.22: [ 1338]: <-- [12:AT+FCLASS=1\r]
Mar 26 17:31:45.29: [ 1338]: --> [2:OK]
Mar 26 17:31:45.29: [ 1338]: <-- [5:ATM0\r]
Mar 26 17:31:45.35: [ 1338]: --> [2:OK]
Mar 26 17:31:45.35: [ 1338]: <-- [12:AT+FCLASS=1\r]
Mar 26 17:31:45.52: [ 1338]: --> [2:OK]
Mar 26 17:31:45.52: [ 1338]: DIAL 0208738934
Mar 26 17:31:45.52: [ 1338]: <-- [15:ATDT0208738934\r]
Mar 26 17:32:01.13: [ 1338]: --> [7:CONNECT]
Mar 26 17:32:02.62: [ 1338]: --> [2:OK]
Mar 26 17:32:02.62: [ 1338]: REMOTE CSI "31208738934"
Mar 26 17:32:02.62: [ 1338]: <-- [9:AT+FRH=3\r]
Mar 26 17:32:02.65: [ 1338]: --> [7:CONNECT]
Mar 26 17:32:02.90: [ 1338]: --> [2:OK]
Mar 26 17:32:02.90: [ 1338]: REMOTE best rate 14400 bit/s
Mar 26 17:32:02.90: [ 1338]: REMOTE max page width 1728 pixels in 215 mm
Mar 26 17:32:02.90: [ 1338]: REMOTE max unlimited page length 
Mar 26 17:32:02.90: [ 1338]: REMOTE best vres 7.7 line/mm
Mar 26 17:32:02.90: [ 1338]: REMOTE best format 2-D MMR
Mar 26 17:32:02.90: [ 1338]: REMOTE supports T.30 Annex A, ECM
Mar 26 17:32:02.90: [ 1338]: REMOTE best 0 ms/scanline
Mar 26 17:32:02.90: [ 1338]: USE 14400 bit/s
Mar 26 17:32:02.90: [ 1338]: USE 0 ms/scanline
Mar 26 17:32:02.90: [ 1338]: SEND file "docq/doc1.cover;00"
Mar 26 17:32:02.90: [ 1338]: USE page width 1728 pixels in 215 mm
Mar 26 17:32:02.90: [ 1338]: USE unlimited page length 
Mar 26 17:32:02.90: [ 1338]: USE 3.85 line/mm
Mar 26 17:32:02.90: [ 1338]: USE 1-D MR
Mar 26 17:32:02.90: [ 1338]: SEND training at v.17 14400 bit/s
Mar 26 17:32:02.90: [ 1338]: <-- [9:AT+FTH=3\r]
Mar 26 17:32:03.03: [ 1338]: --> [7:CONNECT]
Mar 26 17:32:03.03: [ 1338]: <-- data [23]
Mar 26 17:32:03.03: [ 1338]: <-- data [2]
Mar 26 17:32:04.27: [ 1338]: --> [7:CONNECT]
Mar 26 17:32:04.27: [ 1338]: <-- data [6]
Mar 26 17:32:04.27: [ 1338]: <-- data [2]
Mar 26 17:32:04.61: [ 1338]: --> [2:OK]
Mar 26 17:32:04.61: [ 1338]: DELAY 75 ms
Mar 26 17:32:04.69: [ 1338]: <-- [11:AT+FTM=145\r]
Mar 26 17:32:04.83: [ 1338]: --> [7:CONNECT]
Mar 26 17:32:04.83: [ 1338]: <-- data [1024]
Mar 26 17:32:04.83: [ 1338]: <-- data [1024]
Mar 26 17:32:04.83: [ 1338]: <-- data [652]
Mar 26 17:32:04.83: [ 1338]: <-- data [2]
Mar 26 17:32:07.97: [ 1338]: --> [2:OK]
Mar 26 17:32:07.97: [ 1338]: <-- [9:AT+FRH=3\r]
Mar 26 17:32:08.14: [ 1338]: --> [7:CONNECT]
Mar 26 17:32:09.73: [ 1338]: --> [2:OK]
Mar 26 17:32:09.73: [ 1338]: REMOTE CSI "31208738934"
Mar 26 17:32:09.73: [ 1338]: <-- [9:AT+FRH=3\r]
Mar 26 17:32:09.76: [ 1338]: --> [7:CONNECT]
Mar 26 17:32:10.02: [ 1338]: --> [2:OK]
Mar 26 17:32:10.02: [ 1338]: SEND training at v.29 9600 bit/s
Mar 26 17:32:10.02: [ 1338]: <-- [9:AT+FTH=3\r]
Mar 26 17:32:10.15: [ 1338]: --> [7:CONNECT]
Mar 26 17:32:10.15: [ 1338]: <-- data [23]
Mar 26 17:32:10.15: [ 1338]: <-- data [2]
Mar 26 17:32:11.39: [ 1338]: --> [7:CONNECT]
Mar 26 17:32:11.39: [ 1338]: <-- data [6]
Mar 26 17:32:11.39: [ 1338]: <-- data [2]
Mar 26 17:32:11.73: [ 1338]: --> [2:OK]
Mar 26 17:32:11.73: [ 1338]: DELAY 75 ms
Mar 26 17:32:11.81: [ 1338]: <-- [10:AT+FTM=96\r]
Mar 26 17:32:11.94: [ 1338]: --> [7:CONNECT]
Mar 26 17:32:11.94: [ 1338]: <-- data [1024]
Mar 26 17:32:11.94: [ 1338]: <-- data [776]
Mar 26 17:32:11.94: [ 1338]: <-- data [2]
Mar 26 17:32:13.73: [ 1338]: --> [2:OK]
Mar 26 17:32:13.73: [ 1338]: <-- [9:AT+FRH=3\r]
Mar 26 17:32:13.89: [ 1338]: --> [7:CONNECT]
Mar 26 17:32:14.95: [ 1338]: --> [2:OK]
Mar 26 17:32:14.95: [ 1338]: TRAINING succeeded
Mar 26 17:32:14.95: [ 1338]: <-- [10:AT+FTM=96\r]
Mar 26 17:32:15.08: [ 1338]: --> [7:CONNECT]
Mar 26 17:32:15.08: [ 1338]: SEND begin page
Mar 26 17:32:15.08: [ 1338]: <-- data [1028]
Mar 26 17:32:15.08: [ 1338]: <-- data [1024]
Mar 26 17:32:15.08: [ 1338]: <-- data [1028]
Mar 26 17:32:15.08: [ 1338]: <-- data [1025]
Mar 26 17:32:15.09: [ 1338]: <-- data [1028]
Mar 26 17:32:15.63: [ 1338]: <-- data [1029]
Mar 26 17:32:16.43: [ 1338]: <-- data [1025]
Mar 26 17:32:17.36: [ 1338]: <-- data [1024]
Mar 26 17:32:18.16: [ 1338]: <-- data [1024]
Mar 26 17:32:18.96: [ 1338]: <-- data [1024]
Mar 26 17:32:19.90: [ 1338]: <-- data [1024]
Mar 26 17:32:20.70: [ 1338]: <-- data [921]
Mar 26 17:32:21.50: [ 1338]: SENT 12183 bytes of data
Mar 26 17:32:21.50: [ 1338]: SEND 2D RTC
Mar 26 17:32:21.50: [ 1338]: <-- data [30]
Mar 26 17:32:21.50: [ 1338]: <-- data [2]
Mar 26 17:32:21.50: [ 1338]: SEND end page
Mar 26 17:32:25.55: [ 1338]: --> [2:OK]
Mar 26 17:32:25.55: [ 1338]: DELAY 95 ms
Mar 26 17:32:25.65: [ 1338]: SEND send EOP (no more pages or documents)
Mar 26 17:32:25.65: [ 1338]: <-- [9:AT+FTH=3\r]
Mar 26 17:32:25.78: [ 1338]: --> [7:CONNECT]
Mar 26 17:32:25.78: [ 1338]: <-- data [3]
Mar 26 17:32:25.78: [ 1338]: <-- data [2]
Mar 26 17:32:26.52: [ 1338]: --> [2:OK]
Mar 26 17:32:26.52: [ 1338]: <-- [9:AT+FRH=3\r]
Mar 26 17:32:26.69: [ 1338]: --> [7:CONNECT]
Mar 26 17:32:27.74: [ 1338]: --> [2:OK]
Mar 26 17:32:27.74: [ 1338]: SEND recv RTN (retrain negative)
Mar 26 17:32:27.74: [ 1338]: SEND training at v.29 7200 bit/s
Mar 26 17:32:27.74: [ 1338]: <-- [9:AT+FTH=3\r]
Mar 26 17:32:27.87: [ 1338]: --> [7:CONNECT]
Mar 26 17:32:27.87: [ 1338]: <-- data [23]
Mar 26 17:32:27.87: [ 1338]: <-- data [2]
Mar 26 17:32:29.11: [ 1338]: --> [7:CONNECT]
Mar 26 17:32:29.11: [ 1338]: <-- data [6]
Mar 26 17:32:29.11: [ 1338]: <-- data [2]
Mar 26 17:32:29.45: [ 1338]: --> [2:OK]
Mar 26 17:32:29.45: [ 1338]: DELAY 75 ms
Mar 26 17:32:29.53: [ 1338]: <-- [10:AT+FTM=72\r]
Mar 26 17:32:29.66: [ 1338]: --> [7:CONNECT]
Mar 26 17:32:29.66: [ 1338]: <-- data [1024]
Mar 26 17:32:29.66: [ 1338]: <-- data [326]
Mar 26 17:32:29.66: [ 1338]: <-- data [2]
Mar 26 17:32:31.45: [ 1338]: --> [2:OK]
Mar 26 17:32:31.45: [ 1338]: <-- [9:AT+FRH=3\r]
Mar 26 17:32:31.61: [ 1338]: --> [7:CONNECT]
Mar 26 17:32:32.67: [ 1338]: --> [2:OK]
Mar 26 17:32:32.67: [ 1338]: TRAINING succeeded
Mar 26 17:32:32.67: [ 1338]: <-- [10:AT+FTM=72\r]
Mar 26 17:32:32.80: [ 1338]: --> [7:CONNECT]
Mar 26 17:32:32.80: [ 1338]: SEND begin page
Mar 26 17:32:32.80: [ 1338]: <-- data [1028]
Mar 26 17:32:32.80: [ 1338]: <-- data [1026]
Mar 26 17:32:32.80: [ 1338]: <-- data [1028]
Mar 26 17:32:32.80: [ 1338]: <-- data [1025]
Mar 26 17:32:32.80: [ 1338]: <-- data [1028]
Mar 26 17:32:33.45: [ 1338]: <-- data [1029]
Mar 26 17:32:34.51: [ 1338]: <-- data [1025]
Mar 26 17:32:35.76: [ 1338]: <-- data [1024]
Mar 26 17:32:36.82: [ 1338]: <-- data [1024]
Mar 26 17:32:37.89: [ 1338]: <-- data [1024]
Mar 26 17:32:39.14: [ 1338]: <-- data [1024]
Mar 26 17:32:40.20: [ 1338]: <-- data [924]
Mar 26 17:32:41.27: [ 1338]: SENT 12186 bytes of data
Mar 26 17:32:41.27: [ 1338]: SEND 2D RTC
Mar 26 17:32:41.27: [ 1338]: <-- data [30]
Mar 26 17:32:41.27: [ 1338]: <-- data [2]
Mar 26 17:32:41.27: [ 1338]: SEND end page
Mar 26 17:32:46.66: [ 1338]: --> [2:OK]
Mar 26 17:32:46.66: [ 1338]: DELAY 95 ms
Mar 26 17:32:46.76: [ 1338]: SEND send EOP (no more pages or documents)
Mar 26 17:32:46.76: [ 1338]: <-- [9:AT+FTH=3\r]
Mar 26 17:32:46.89: [ 1338]: --> [7:CONNECT]
Mar 26 17:32:46.89: [ 1338]: <-- data [3]
Mar 26 17:32:46.89: [ 1338]: <-- data [2]
Mar 26 17:32:47.63: [ 1338]: --> [2:OK]
Mar 26 17:32:47.63: [ 1338]: <-- [9:AT+FRH=3\r]
Mar 26 17:32:47.67: [ 1338]: --> [7:CONNECT]
Mar 26 17:32:52.67: [ 1338]: <-- data [1]
Mar 26 17:32:52.87: [ 1338]: MODEM <Timeout>
Mar 26 17:32:52.87: [ 1338]: SEND send EOP (no more pages or documents)
Mar 26 17:32:52.87: [ 1338]: <-- [9:AT+FTH=3\r]
Mar 26 17:32:53.00: [ 1338]: --> [7:CONNECT]
Mar 26 17:32:53.00: [ 1338]: <-- data [3]
Mar 26 17:32:53.00: [ 1338]: <-- data [2]
Mar 26 17:32:53.75: [ 1338]: --> [2:OK]
Mar 26 17:32:53.75: [ 1338]: <-- [9:AT+FRH=3\r]
Mar 26 17:32:53.85: [ 1338]: --> [7:CONNECT]
Mar 26 17:32:58.85: [ 1338]: <-- data [1]
Mar 26 17:32:59.05: [ 1338]: MODEM <Timeout>
Mar 26 17:32:59.05: [ 1338]: SEND send EOP (no more pages or documents)
Mar 26 17:32:59.05: [ 1338]: <-- [9:AT+FTH=3\r]
Mar 26 17:32:59.18: [ 1338]: --> [7:CONNECT]
Mar 26 17:32:59.18: [ 1338]: <-- data [3]
Mar 26 17:32:59.18: [ 1338]: <-- data [2]
Mar 26 17:32:59.93: [ 1338]: --> [2:OK]
Mar 26 17:32:59.93: [ 1338]: <-- [9:AT+FRH=3\r]
Mar 26 17:33:00.10: [ 1338]: --> [7:CONNECT]
Mar 26 17:33:05.10: [ 1338]: <-- data [1]
Mar 26 17:33:05.30: [ 1338]: MODEM <Timeout>
Mar 26 17:33:05.30: [ 1338]: No response to MPS or EOP repeated 3 tries
Mar 26 17:33:05.30: [ 1338]: <-- [9:AT+FTH=3\r]
Mar 26 17:33:05.43: [ 1338]: --> [7:CONNECT]
Mar 26 17:33:05.43: [ 1338]: <-- data [3]
Mar 26 17:33:05.43: [ 1338]: <-- data [2]
Mar 26 17:33:06.18: [ 1338]: --> [2:OK]
Mar 26 17:33:06.18: [ 1338]: <-- [5:ATH0\r]
Mar 26 17:33:08.32: [ 1338]: --> [2:OK]
Mar 26 17:33:08.32: [ 1338]: SESSION END


____________________ HylaFAX(tm) Users Mailing List _______________________
 To unsub: mail -s unsubscribe hylafax-users-request@hylafax.org < /dev/null