Re: [hylafax-users] excessive "delay 1500 ms" errors

[Jordan Kojouharov <hylafax@xxxxxxxxxxx>]

Mike,

just a note - small chance, at best, but worth mentioning - this may 
also be related to the version of t38modem you are using, although I 
can't deduce the version from the logs below.

the latest version is 0.8.2, available on sourceforge 
(http://sourceforge.net/projects/t38modem)

The version available on www.openh323.org is 0.6.2, and has long been 
super-seeded.

cheeers,
jordan

mnoel@xxxxxxxxxxx wrote:
> I'm running a couple of hylafax servers and things are working great for 
> the most part.   I'm using the t38modem.
>  
> I have noticed that for some inbound calls the fax never comes though.  
> It seems that the faxmodem answers but then the call gets dropped after 
> several seconds.  In almost all cases I see a long sequence of "DELAY 
> 1500 ms" errors.  I've pasted a complete log file below for reference. 
>  
> This seems to be isolated to particular callers since I am often 
> receiving "good" faxes at the same time that these bad ones are coming 
> in.  I am seeing this behavior on both my fax servers.
>  
> What might the problem be?  What can I do to try to fix this.
>  
> -Mike Noel
>  
> Apr 22 19:11:54.96: [10928]: SESSION BEGIN 000400296
> Apr 22 19:11:54.96: [10928]: HylaFAX (tm) Version 4.2.0
> Apr 22 19:11:54.96: [10928]: <-- [4:ATA\r]
> Apr 22 19:11:59.32: [10928]: --> [7:CONNECT]
> Apr 22 19:11:59.32: [10928]: ANSWER: FAX CONNECTION  DEVICE '/dev/ttyxd'
> Apr 22 19:11:59.32: [10928]: STATE CHANGE: ANSWERING -> RECEIVING
> Apr 22 19:11:59.32: [10928]: MODEM input buffering enabled
> Apr 22 19:11:59.32: [10928]: RECV FAX: begin
> Apr 22 19:11:59.32: [10928]: MODEM input buffering disabled
> Apr 22 19:11:59.32: [10928]: <-- HDLC<32:FF C0 04 AD 00 55 12 9E 36 86 
> 62 82 1A 04 14 2E B6 94 04 6A A6 4E CE 96 F6 76 04 2C 74 4C 74 0C>
> Apr 22 19:11:59.32: [10928]: <-- data [32]
> Apr 22 19:11:59.32: [10928]: <-- data [2]
> Apr 22 19:12:01.11: [10928]: --> [7:CONNECT]
> Apr 22 19:12:01.11: [10928]: <-- HDLC<23:FF C0 02 04 04 04 04 04 04 04 
> 04 04 04 04 04 04 04 04 04 04 04 04 04>
> Apr 22 19:12:01.11: [10928]: <-- data [23]
> Apr 22 19:12:01.11: [10928]: <-- data [2]
> Apr 22 19:12:01.83: [10928]: --> [7:CONNECT]
> Apr 22 19:12:01.83: [10928]: <-- HDLC<10:FF C8 01 00 76 5F 23 01 FB C0>
> Apr 22 19:12:01.83: [10928]: <-- data [10]
> Apr 22 19:12:01.83: [10928]: <-- data [2]
> Apr 22 19:12:02.24: [10928]: --> [2:OK]
> Apr 22 19:12:02.24: [10928]: <-- [9:AT+FRH=3\r]
> Apr 22 19:12:03.07: [10928]: --> [7:CONNECT]
> Apr 22 19:12:08.07: [10928]: MODEM TIMEOUT: receiving HDLC frame data
> Apr 22 19:12:08.07: [10928]: <-- data [1]
> Apr 22 19:12:08.07: [10928]: --> [5:ERROR]
> Apr 22 19:12:08.07: [10928]: MODEM Command error
> Apr 22 19:12:08.07: [10928]: DELAY 1500 ms
> Apr 22 19:12:09.57: [10928]: <-- [9:AT+FTH=3\r]
> Apr 22 19:12:12.56: [10928]: --> [0:]
> Apr 22 19:12:12.56: [10928]: MODEM TIMEOUT: sending HDLC frame
> Apr 22 19:12:12.56: [10928]: DELAY 1500 ms
> Apr 22 19:12:14.06: [10928]: <-- [9:AT+FTH=3\r]
> Apr 22 19:12:14.16: [10928]: --> [5:ERROR]
> Apr 22 19:12:14.16: [10928]: DELAY 1500 ms
> Apr 22 19:12:15.66: [10928]: <-- [9:AT+FTH=3\r]
> Apr 22 19:12:15.76: [10928]: --> [5:ERROR]
> Apr 22 19:12:15.76: [10928]: DELAY 1500 ms
> Apr 22 19:12:17.26: [10928]: <-- [9:AT+FTH=3\r]
> Apr 22 19:12:17.36: [10928]: --> [5:ERROR]
> Apr 22 19:12:17.36: [10928]: DELAY 1500 ms
> Apr 22 19:12:18.86: [10928]: <-- [9:AT+FTH=3\r]
> Apr 22 19:12:18.96: [10928]: --> [5:ERROR]
> Apr 22 19:12:18.96: [10928]: DELAY 1500 ms
> Apr 22 19:12:20.46: [10928]: <-- [9:AT+FTH=3\r]
> Apr 22 19:12:20.56: [10928]: --> [5:ERROR]
> Apr 22 19:12:20.56: [10928]: DELAY 1500 ms
> Apr 22 19:12:22.06: [10928]: <-- [9:AT+FTH=3\r]
> Apr 22 19:12:22.16: [10928]: --> [5:ERROR]
> Apr 22 19:12:22.16: [10928]: DELAY 1500 ms
> Apr 22 19:12:23.66: [10928]: <-- [9:AT+FTH=3\r]
> Apr 22 19:12:23.76: [10928]: --> [5:ERROR]
> Apr 22 19:12:23.76: [10928]: DELAY 1500 ms
> Apr 22 19:12:25.26: [10928]: <-- [9:AT+FTH=3\r]
> Apr 22 19:12:25.36: [10928]: --> [5:ERROR]
> Apr 22 19:12:25.36: [10928]: DELAY 1500 ms
> Apr 22 19:12:26.86: [10928]: <-- [9:AT+FTH=3\r]
> Apr 22 19:12:26.96: [10928]: --> [5:ERROR]
> Apr 22 19:12:26.96: [10928]: DELAY 1500 ms
> Apr 22 19:12:28.46: [10928]: <-- [9:AT+FTH=3\r]
> Apr 22 19:12:28.56: [10928]: --> [5:ERROR]
> Apr 22 19:12:28.56: [10928]: DELAY 1500 ms
> Apr 22 19:12:30.06: [10928]: <-- [9:AT+FTH=3\r]
> Apr 22 19:12:30.16: [10928]: --> [5:ERROR]
> Apr 22 19:12:30.16: [10928]: DELAY 1500 ms
> Apr 22 19:12:31.66: [10928]: <-- [9:AT+FTH=3\r]
> Apr 22 19:12:31.76: [10928]: --> [5:ERROR]
> Apr 22 19:12:31.76: [10928]: DELAY 1500 ms
> Apr 22 19:12:33.26: [10928]: <-- [9:AT+FTH=3\r]
> Apr 22 19:12:33.36: [10928]: --> [5:ERROR]
> Apr 22 19:12:33.36: [10928]: DELAY 1500 ms
> Apr 22 19:12:34.86: [10928]: <-- [9:AT+FTH=3\r]
> Apr 22 19:12:34.96: [10928]: --> [5:ERROR]
> Apr 22 19:12:34.96: [10928]: DELAY 1500 ms
> Apr 22 19:12:36.46: [10928]: <-- [9:AT+FTH=3\r]
> Apr 22 19:12:36.56: [10928]: --> [5:ERROR]
> Apr 22 19:12:36.56: [10928]: DELAY 1500 ms
> Apr 22 19:12:38.06: [10928]: <-- [9:AT+FTH=3\r]
> Apr 22 19:12:38.16: [10928]: --> [5:ERROR]
> Apr 22 19:12:38.16: [10928]: RECV FAX: No answer (T.30 T1 timeout)
> Apr 22 19:12:38.16: [10928]: RECV FAX: end
> Apr 22 19:12:38.16: [10928]: 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*

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