Re: [hylafax-users] EOP problems with t38modem and Cisco 5850

["Glenn Dalgliesh" <glenn@xxxxxxxxxxxxx>]

This is a trace with default timers

Aug 17 19:48:00.34: [27198]: SESSION BEGIN 00000380 4102959745
Aug 17 19:48:00.34: [27198]: HylaFAX (tm) Version 4.1.8
Aug 17 19:48:00.34: [27198]: SEND FAX: JOB 141 DEST 4102959745 COMMID
00000380 DEVICE '/dev/ttyx0'
Aug 17 19:48:00.34: [27198]: MODEM set DTR OFF
Aug 17 19:48:00.34: [27198]: MODEM set baud rate: 0 baud (flow control
unchanged)
Aug 17 19:48:00.34: [27198]: DELAY 75 ms
Aug 17 19:48:00.42: [27198]: MODEM set DTR ON
Aug 17 19:48:00.42: [27198]: DELAY 2600 ms
Aug 17 19:48:04.03: [27198]: MODEM set baud rate: 19200 baud, input flow
RTS/CTS, output flow RTS/CTS
Aug 17 19:48:04.03: [27198]: DELAY 10 ms
Aug 17 19:48:04.04: [27198]: MODEM flush i/o
Aug 17 19:48:04.04: [27198]: <-- [4:ATZ\r]
Aug 17 19:48:04.04: [27198]: --> [2:OK]
Aug 17 19:48:04.04: [27198]: DELAY 3000 ms
Aug 17 19:48:07.04: [27198]: <-- [5:ATE0\r]
Aug 17 19:48:07.04: [27198]: --> [4:ATE0]
Aug 17 19:48:07.04: [27198]: --> [2:OK]
Aug 17 19:48:07.04: [27198]: <-- [5:ATV1\r]
Aug 17 19:48:07.04: [27198]: --> [2:OK]
Aug 17 19:48:07.04: [27198]: <-- [5:ATQ0\r]
Aug 17 19:48:07.04: [27198]: --> [2:OK]
Aug 17 19:48:07.04: [27198]: <-- [7:ATS0=0\r]
Aug 17 19:48:07.04: [27198]: --> [2:OK]
Aug 17 19:48:07.04: [27198]: <-- [7:ATS8=2\r]
Aug 17 19:48:07.04: [27198]: --> [2:OK]
Aug 17 19:48:07.04: [27198]: <-- [8:ATS7=60\r]
Aug 17 19:48:07.04: [27198]: --> [2:OK]
Aug 17 19:48:07.04: [27198]: <-- [12:AT+FCLASS=1\r]
Aug 17 19:48:07.04: [27198]: --> [2:OK]
Aug 17 19:48:07.04: [27198]: <-- [7:ATL0M1\r]
Aug 17 19:48:07.04: [27198]: --> [2:OK]
Aug 17 19:48:07.04: [27198]: MODEM input buffering enabled
Aug 17 19:48:07.04: [27198]: <-- [12:AT+FCLASS=1\r]
Aug 17 19:48:07.14: [27198]: --> [2:OK]
Aug 17 19:48:07.14: [27198]: DIAL 4102959745
Aug 17 19:48:07.14: [27198]: <-- [15:ATDT4102959745\r]
Aug 17 19:48:33.74: [27198]: --> [7:CONNECT]
Aug 17 19:48:33.74: [27198]: MODEM input buffering disabled
Aug 17 19:48:33.97: [27198]: --> [2:OK]
Aug 17 19:48:33.97: [27198]: REMOTE best rate 14400 bit/s
Aug 17 19:48:33.97: [27198]: REMOTE max page width 1728 pixels in 215 mm
Aug 17 19:48:33.97: [27198]: REMOTE max unlimited page length
Aug 17 19:48:33.97: [27198]: REMOTE best vres 7.7 line/mm
Aug 17 19:48:33.97: [27198]: REMOTE best format 1-D MR
Aug 17 19:48:33.97: [27198]: REMOTE best 20 ms, 10 ms/scanline
Aug 17 19:48:33.97: [27198]: USE 14400 bit/s
Aug 17 19:48:33.97: [27198]: USE 20 ms, 10 ms/scanline
Aug 17 19:48:33.97: [27198]: SEND file "docq/doc141.tif;31"
Aug 17 19:48:33.97: [27198]: USE page width 1728 pixels in 215 mm
Aug 17 19:48:33.97: [27198]: USE unlimited page length
Aug 17 19:48:33.97: [27198]: USE 7.7 line/mm
Aug 17 19:48:33.97: [27198]: USE 1-D MR
Aug 17 19:48:33.97: [27198]: SEND training at v.17 14400 bit/s
Aug 17 19:48:33.97: [27198]: <-- [9:AT+FTH=3\r]
Aug 17 19:48:33.98: [27198]: --> [7:CONNECT]
Aug 17 19:48:33.98: [27198]: <-- data [23]
Aug 17 19:48:33.98: [27198]: <-- data [2]
Aug 17 19:48:35.62: [27198]: --> [7:CONNECT]
Aug 17 19:48:35.62: [27198]: <-- data [6]
Aug 17 19:48:35.62: [27198]: <-- data [2]
Aug 17 19:48:36.00: [27198]: --> [2:OK]
Aug 17 19:48:36.00: [27198]: <-- [9:AT+FTS=7\r]
Aug 17 19:48:36.16: [27198]: --> [2:OK]
Aug 17 19:48:36.16: [27198]: <-- [11:AT+FTM=145\r]
Aug 17 19:48:36.16: [27198]: --> [7:CONNECT]
Aug 17 19:48:36.16: [27198]: <-- data [1024]
Aug 17 19:48:36.16: [27198]: <-- data [1024]
Aug 17 19:48:36.16: [27198]: <-- data [652]
Aug 17 19:48:36.16: [27198]: <-- data [2]
Aug 17 19:48:39.26: [27198]: --> [2:OK]
Aug 17 19:48:39.26: [27198]: <-- [9:AT+FRH=3\r]
Aug 17 19:48:40.26: [27198]: --> [7:CONNECT]
Aug 17 19:48:41.21: [27198]: --> [2:OK]
Aug 17 19:48:41.21: [27198]: TRAINING succeeded
Aug 17 19:48:41.21: [27198]: DELAY 75 ms
Aug 17 19:48:41.29: [27198]: <-- [11:AT+FTM=146\r]
Aug 17 19:48:41.29: [27198]: --> [7:CONNECT]
Aug 17 19:48:41.29: [27198]: SEND begin page
Aug 17 19:48:41.29: [27198]: <-- data [1027]
Aug 17 19:48:41.29: [27198]: <-- data [1031]
Aug 17 19:48:41.29: [27198]: <-- data [1024]
Aug 17 19:48:41.29: [27198]: <-- data [1024]
Aug 17 19:48:41.32: [27198]: <-- data [1024]
Aug 17 19:48:41.32: [27198]: <-- data [1027]
Aug 17 19:48:41.32: [27198]: <-- data [1026]
Aug 17 19:48:41.32: [27198]: <-- data [1026]
Aug 17 19:48:43.39: [27198]: <-- data [1024]
Aug 17 19:48:43.39: [27198]: <-- data [1026]
Aug 17 19:48:43.39: [27198]: <-- data [1024]
Aug 17 19:48:43.39: [27198]: <-- data [1024]
Aug 17 19:48:45.61: [27198]: <-- data [1024]
Aug 17 19:48:45.61: [27198]: <-- data [1024]
Aug 17 19:48:45.61: [27198]: <-- data [1024]
Aug 17 19:48:45.61: [27198]: <-- data [1024]
Aug 17 19:48:48.16: [27198]: <-- data [1024]
Aug 17 19:48:48.16: [27198]: <-- data [1024]
Aug 17 19:48:48.16: [27198]: <-- data [1024]
Aug 17 19:48:48.16: [27198]: <-- data [1024]
Aug 17 19:48:50.38: [27198]: <-- data [1024]
Aug 17 19:48:50.38: [27198]: <-- data [1024]
Aug 17 19:48:50.38: [27198]: <-- data [1024]
Aug 17 19:48:50.38: [27198]: <-- data [1024]
Aug 17 19:48:52.60: [27198]: <-- data [1024]
Aug 17 19:48:52.60: [27198]: <-- data [1024]
Aug 17 19:48:52.60: [27198]: <-- data [1024]
Aug 17 19:48:52.60: [27198]: <-- data [1024]
Aug 17 19:48:54.81: [27198]: <-- data [1024]
Aug 17 19:48:54.81: [27198]: <-- data [1024]
Aug 17 19:48:54.81: [27198]: <-- data [1024]
Aug 17 19:48:54.81: [27198]: <-- data [1024]
Aug 17 19:48:57.02: [27198]: <-- data [1024]
Aug 17 19:48:57.02: [27198]: <-- data [1024]
Aug 17 19:48:57.02: [27198]: <-- data [1024]
Aug 17 19:48:57.02: [27198]: <-- data [1024]
Aug 17 19:48:59.57: [27198]: <-- data [1024]
Aug 17 19:48:59.57: [27198]: <-- data [1024]
Aug 17 19:48:59.57: [27198]: <-- data [1024]
Aug 17 19:48:59.57: [27198]: <-- data [1024]
Aug 17 19:49:01.79: [27198]: <-- data [417]
Aug 17 19:49:01.79: [27198]: SENT 41377 bytes of data
Aug 17 19:49:01.79: [27198]: SEND 1D RTC
Aug 17 19:49:01.79: [27198]: <-- data [29]
Aug 17 19:49:01.79: [27198]: <-- data [2]
Aug 17 19:49:01.79: [27198]: SEND end page
Aug 17 19:49:04.69: [27198]: --> [2:OK]
Aug 17 19:49:04.69: [27198]: <-- [9:AT+FTS=9\r]
Aug 17 19:49:04.87: [27198]: --> [2:OK]
Aug 17 19:49:04.87: [27198]: SEND send EOP (no more pages or documents)
Aug 17 19:49:04.87: [27198]: <-- [9:AT+FTH=3\r]
Aug 17 19:49:04.87: [27198]: --> [7:CONNECT]
Aug 17 19:49:04.87: [27198]: <-- data [3]
Aug 17 19:49:04.87: [27198]: <-- data [2]
Aug 17 19:49:05.98: [27198]: --> [2:OK]
Aug 17 19:49:05.98: [27198]: <-- [9:AT+FRH=3\r]
Aug 17 19:49:09.08: [27198]: --> [0:]
Aug 17 19:49:09.08: [27198]: MODEM <Empty line>
Aug 17 19:49:09.08: [27198]: MODEM TIMEOUT: waiting for v.21 carrier
Aug 17 19:49:09.08: [27198]: <-- data [1]
Aug 17 19:49:09.08: [27198]: --> [2:OK]
Aug 17 19:49:09.08: [27198]: SEND send EOP (no more pages or documents)
Aug 17 19:49:09.08: [27198]: <-- [9:AT+FTH=3\r]
Aug 17 19:49:09.08: [27198]: --> [7:CONNECT]
Aug 17 19:49:09.08: [27198]: <-- data [3]
Aug 17 19:49:09.08: [27198]: <-- data [2]
Aug 17 19:49:10.19: [27198]: --> [2:OK]
Aug 17 19:49:10.19: [27198]: <-- [9:AT+FRH=3\r]
Aug 17 19:49:13.29: [27198]: --> [0:]
Aug 17 19:49:13.29: [27198]: MODEM <Empty line>
Aug 17 19:49:13.29: [27198]: MODEM TIMEOUT: waiting for v.21 carrier
Aug 17 19:49:13.29: [27198]: <-- data [1]
Aug 17 19:49:13.29: [27198]: --> [2:OK]
Aug 17 19:49:13.29: [27198]: SEND send EOP (no more pages or documents)
Aug 17 19:49:13.29: [27198]: <-- [9:AT+FTH=3\r]
Aug 17 19:49:13.29: [27198]: --> [7:CONNECT]
Aug 17 19:49:13.29: [27198]: <-- data [3]
Aug 17 19:49:13.29: [27198]: <-- data [2]
Aug 17 19:49:14.40: [27198]: --> [2:OK]
Aug 17 19:49:14.40: [27198]: <-- [9:AT+FRH=3\r]
Aug 17 19:49:17.50: [27198]: --> [0:]
Aug 17 19:49:17.50: [27198]: MODEM <Empty line>
Aug 17 19:49:17.50: [27198]: MODEM TIMEOUT: waiting for v.21 carrier
Aug 17 19:49:17.50: [27198]: <-- data [1]
Aug 17 19:49:17.50: [27198]: --> [2:OK]
Aug 17 19:49:17.50: [27198]: No response to MPS or EOP repeated 3 tries
Aug 17 19:49:17.50: [27198]: <-- [9:AT+FTH=3\r]
Aug 17 19:49:17.50: [27198]: --> [7:CONNECT]
Aug 17 19:49:17.50: [27198]: <-- data [3]
Aug 17 19:49:17.50: [27198]: <-- data [2]
Aug 17 19:49:18.61: [27198]: --> [2:OK]
Aug 17 19:49:18.61: [27198]: MODEM input buffering enabled
Aug 17 19:49:18.61: [27198]: <-- [5:ATH0\r]
Aug 17 19:49:18.71: [27198]: --> [2:OK]
Aug 17 19:49:18.71: [27198]: MODEM set DTR OFF
Aug 17 19:49:18.71: [27198]: MODEM set baud rate: 0 baud (flow control
unchanged)
Aug 17 19:49:18.71: [27198]: SESSION END
----- Original Message ----- 
From: "Lee Howard" <faxguy@xxxxxxxxxxxxxxxx>
To: "Glenn Dalgliesh" <glenn@xxxxxxxxxxxxx>
Cc: <hylafax-users@xxxxxxxxxxx>
Sent: Tuesday, August 17, 2004 7:10 PM
Subject: Re: [hylafax-users] EOP problems with t38modem and Cisco 5850


> On 2004.08.17 15:50 Glenn Dalgliesh wrote:
>
> > Aug 17 14:48:00.48: [27049]: <-- [10:AT+FTS=50\r]
> > Aug 17 14:48:01.07: [27049]: --> [2:OK]
>
> Uh, are you sure that 0.5 second is not too long of a wait?
>
> Lee.
>
> ____________________ 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*
>


____________________ 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*