[hylafax-users] T.30 T2 timeout, expected page not received

[Ross Vandegrift <ross@willow.seitz.com>]

Hello,

	Ever since we put our HylaFAX machine into service for recieving
faxes, we've been loosing faxes to this error.  I have no idea what
causes it, and googling on the string returns lots of people asking, but
no useful answers.

	Is this caused by HylaFAX, the modem, or something else
entirely?

Thanks - complete log is included below, with a question.

Ross Vandegrift
ross@willow.seitz.com

Aug 09 08:47:05.90: [ 7592]: SESSION BEGIN 00000121 16102682229
Aug 09 08:47:05.90: [ 7592]: HylaFAX (tm) Version 4.1.1
Aug 09 08:47:05.91: [ 7592]: <-- [4:ATA\r]
Aug 09 08:47:14.08: [ 7592]: --> [7:CONNECT]
Aug 09 08:47:14.08: [ 7592]: ANSWER: FAX CONNECTION  DEVICE '/dev/ttyS0'
Aug 09 08:47:14.09: [ 7592]: RECV FAX: begin
Aug 09 08:47:14.34: [ 7592]: <-- data [23]
Aug 09 08:47:14.34: [ 7592]: <-- data [2]
Aug 09 08:47:15.77: [ 7592]: --> [7:CONNECT]
Aug 09 08:47:15.77: [ 7592]: <-- data [10]
Aug 09 08:47:15.77: [ 7592]: <-- data [2]
Aug 09 08:47:16.28: [ 7592]: --> [2:OK]
Aug 09 08:47:16.28: [ 7592]: <-- [9:AT+FRH=3\r]
Aug 09 08:47:17.30: [ 7592]: --> [7:CONNECT]
Aug 09 08:47:18.64: [ 7592]: --> [2:OK]
Aug 09 08:47:18.64: [ 7592]: REMOTE TSI "508 248 1240"
Aug 09 08:47:18.64: [ 7592]: <-- [9:AT+FRH=3\r]
Aug 09 08:47:18.66: [ 7592]: --> [7:CONNECT]
Aug 09 08:47:18.98: [ 7592]: --> [2:OK]
Aug 09 08:47:18.98: [ 7592]: REMOTE wants 14400 bit/s
Aug 09 08:47:18.98: [ 7592]: REMOTE wants page width 1728 pixels in 215 mm
Aug 09 08:47:18.98: [ 7592]: REMOTE wants unlimited page length 
Aug 09 08:47:18.98: [ 7592]: REMOTE wants 3.85 line/mm
Aug 09 08:47:18.98: [ 7592]: REMOTE wants 2-D MR
Aug 09 08:47:18.98: [ 7592]: RECV training at v.17 14400 bit/s
Aug 09 08:47:18.98: [ 7592]: <-- [11:AT+FRM=145\r]
Aug 09 08:47:20.69: [ 7592]: --> [7:CONNECT]
Aug 09 08:47:22.21: [ 7592]: RECV: TCF 2722 bytes, 1% non-zero, 2679 zero-run
Aug 09 08:47:22.22: [ 7592]: --> [10:NO CARRIER]
Aug 09 08:47:22.22: [ 7592]: DELAY 75 ms
Aug 09 08:47:22.29: [ 7592]: TRAINING succeeded
Aug 09 08:47:22.30: [ 7592]: <-- [9:AT+FTH=3\r]
Aug 09 08:47:22.44: [ 7592]: --> [7:CONNECT]
Aug 09 08:47:22.45: [ 7592]: <-- data [3]
Aug 09 08:47:22.45: [ 7592]: <-- data [2]
Aug 09 08:47:23.80: [ 7592]: --> [2:OK]
Aug 09 08:47:23.80: [ 7592]: <-- [11:AT+FRM=146\r]
Aug 09 08:47:28.88: [ 7592]: --> [8:+FCERROR]
Aug 09 08:47:28.88: [ 7592]: <-- [9:AT+FRH=3\r]
Aug 09 08:47:29.38: [ 7592]: --> [7:CONNECT]
Aug 09 08:47:30.53: [ 7592]: --> [2:OK]
Aug 09 08:47:30.53: [ 7592]: REMOTE TSI "508 248 1240"
Aug 09 08:47:30.53: [ 7592]: <-- [9:AT+FRH=3\r]
Aug 09 08:47:30.55: [ 7592]: --> [7:CONNECT]
Aug 09 08:47:30.88: [ 7592]: --> [2:OK]
Aug 09 08:47:30.88: [ 7592]: REMOTE wants 14400 bit/s
Aug 09 08:47:30.88: [ 7592]: REMOTE wants page width 1728 pixels in 215 mm
Aug 09 08:47:30.88: [ 7592]: REMOTE wants unlimited page length 
Aug 09 08:47:30.88: [ 7592]: REMOTE wants 3.85 line/mm
Aug 09 08:47:30.88: [ 7592]: REMOTE wants 2-D MR
Aug 09 08:47:30.89: [ 7592]: RECV training at v.17 14400 bit/s
Aug 09 08:47:30.89: [ 7592]: <-- [11:AT+FRM=145\r]
Aug 09 08:47:32.60: [ 7592]: --> [7:CONNECT]
Aug 09 08:47:34.10: [ 7592]: RECV: TCF 2686 bytes, 1% non-zero, 2643 zero-run
Aug 09 08:47:34.10: [ 7592]: --> [10:NO CARRIER]
Aug 09 08:47:34.10: [ 7592]: DELAY 75 ms
Aug 09 08:47:34.18: [ 7592]: TRAINING succeeded
Aug 09 08:47:34.18: [ 7592]: <-- [9:AT+FTH=3\r]
Aug 09 08:47:34.34: [ 7592]: --> [7:CONNECT]
Aug 09 08:47:34.34: [ 7592]: <-- data [3]
Aug 09 08:47:34.34: [ 7592]: <-- data [2]
Aug 09 08:47:35.69: [ 7592]: --> [2:OK]
Aug 09 08:47:35.69: [ 7592]: <-- [11:AT+FRM=146\r]
Aug 09 08:47:36.86: [ 7592]: --> [7:CONNECT]
Aug 09 08:47:36.86: [ 7592]: RECV: begin page
Aug 09 08:47:57.25: [ 7592]: RECV: 1075 total lines, 1 bad lines, 2 consecutive bad lines
Aug 09 08:47:57.25: [ 7592]: RECV: end page
Aug 09 08:47:57.25: [ 7592]: --> [10:NO CARRIER]
Aug 09 08:47:57.25: [ 7592]: <-- [9:AT+FRH=3\r]
Aug 09 08:48:37.23: [ 7592]: --> [0:]
Aug 09 08:48:37.23: [ 7592]: MODEM <Empty line>
Aug 09 08:48:37.23: [ 7592]: <-- data [1]
Aug 09 08:48:37.43: [ 7592]: MODEM <Timeout>

Is the modem really supposed to timeout after .2 seconds?  That seems a
bit suspect...

Aug 09 08:48:37.48: [ 7592]: RECV FAX (00000121): recvq/fax00063.tif from 508 248 1240, route to <unspecified>, 0 pages in 1:23
Aug 09 08:48:37.51: [ 7592]: RECV FAX: T.30 T2 timeout, expected page not received
Aug 09 08:48:37.51: [ 7592]: <-- [9:AT+FTH=3\r]
Aug 09 08:48:37.64: [ 7592]: --> [7:CONNECT]
Aug 09 08:48:37.64: [ 7592]: <-- data [3]
Aug 09 08:48:37.64: [ 7592]: <-- data [2]
Aug 09 08:48:39.00: [ 7592]: --> [2:OK]
Aug 09 08:48:39.00: [ 7592]: RECV FAX (00000121): session with 508 248 1240 terminated abnormally: T.30 T2 timeout, expected page not received
Aug 09 08:48:39.01: [ 7592]: RECV FAX: bin/faxrcvd "recvq/fax00063.tif" "ttyS0" "00000121" "T.30 T2 timeout, expected page not received" "" ""
Aug 09 08:48:47.67: [ 7592]: RECV FAX: end
Aug 09 08:48:47.67: [ 7592]: 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@hylafax.org < /dev/null
  *To learn about commercial HylaFAX(tm) support, mail sales@hylafax.org.*