[hylafax-users] Problem with hylafax

[Andi Limberger <andi@xxxxxxxxxxxx>]

-----BEGIN PGP SIGNED MESSAGE-----
Hash: SHA1

Hi there,

I have a problem with Hylafax and - surprise surprise - an USR
Sportster 56K FAX Modem. Sending is no problem and works fine, but
receiving is the big problem:

I get also the "T.30 T2 timeout, expected signal not received" error.
I checked google about that and there was a note in the mailinglist,
that trying the new version 4.2.3 should be done first. However, I
build the sources and installed it. Works also fine for sending, but
not for receiving.

I run Hylafax on a FreeBSD 5.4 with this crappy Sportster. I tried
Classes 1 with xonxoff with rctcts and Class 2. With Class 2, the
handshake fails. With Class 1, handshake works fine, but after 1 or 6
pages, I got the error. Even not the whole page 1 is transmitted properly.

Can anyone help me with this problem? That's would be nice!

Here one log file for Class1 rtscts, which seems to be the best setting.


-----BEGIN PGP SIGNATURE-----
Version: GnuPG v1.4.2 (MingW32)
Comment: Using GnuPG with Thunderbird - http://enigmail.mozdev.org

iD8DBQFDijfz6E2r941QJAcRAmi1AJsEMO6OPr8SszfUnNCxujfECnKzvQCggcfh
7kWBDY5Bv08oKBNymNyCbzs=
=gTGb
-----END PGP SIGNATURE-----


Nov 27 23:05:24.37: [29340]: SESSION BEGIN 000000137 4361357978
Nov 27 23:05:24.37: [29340]: HylaFAX (tm) Version 4.2.3
Nov 27 23:05:24.37: [29340]: <-- [22:AT+FCLASS=1&H1&I0&R2A\r]
Nov 27 23:05:29.56: [29340]: --> [7:CONNECT]
Nov 27 23:05:29.56: [29340]: ANSWER: FAX CONNECTION  DEVICE '/dev/cuaa0'
Nov 27 23:05:29.56: [29340]: RECV FAX: begin
Nov 27 23:05:29.56: [29340]: <-- data [32]
Nov 27 23:05:29.56: [29340]: <-- data [2]
Nov 27 23:05:29.73: [29340]: --> [7:CONNECT]
Nov 27 23:05:29.73: [29340]: <-- data [23]
Nov 27 23:05:29.73: [29340]: <-- data [2]
Nov 27 23:05:30.45: [29340]: --> [7:CONNECT]
Nov 27 23:05:30.45: [29340]: <-- data [13]
Nov 27 23:05:30.45: [29340]: <-- data [2]
Nov 27 23:05:32.71: [29340]: --> [2:OK]
Nov 27 23:05:32.71: [29340]: <-- [9:AT+FRH=3\r]
Nov 27 23:05:33.58: [29340]: --> [7:CONNECT]
Nov 27 23:05:34.97: [29340]: --> [2:OK]
Nov 27 23:05:34.97: [29340]: REMOTE TSI "061357978"
Nov 27 23:05:34.97: [29340]: <-- [9:AT+FRH=3\r]
Nov 27 23:05:34.99: [29340]: --> [7:CONNECT]
Nov 27 23:05:35.32: [29340]: --> [2:OK]
Nov 27 23:05:35.32: [29340]: REMOTE wants 9600 bit/s
Nov 27 23:05:35.32: [29340]: REMOTE wants A4 page width (215 mm)
Nov 27 23:05:35.32: [29340]: REMOTE wants unlimited page length
Nov 27 23:05:35.32: [29340]: REMOTE wants 3.85 line/mm
Nov 27 23:05:35.32: [29340]: REMOTE wants 2-D MMR
Nov 27 23:05:35.32: [29340]: REMOTE wants T.30 Annex A, 256-byte ECM
Nov 27 23:05:35.32: [29340]: RECV training at v.29 9600 bit/s
Nov 27 23:05:35.32: [29340]: <-- [10:AT+FRM=96\r]
Nov 27 23:05:35.67: [29340]: --> [7:CONNECT]
Nov 27 23:05:37.25: [29340]: RECV: TCF 1893 bytes, 4% non-zero, 1808 zero-run
Nov 27 23:05:37.25: [29340]: --> [10:NO CARRIER]
Nov 27 23:05:37.25: [29340]: <-- [9:AT+FRS=7\r]
Nov 27 23:05:37.35: [29340]: --> [2:OK]
Nov 27 23:05:37.35: [29340]: TRAINING succeeded
Nov 27 23:05:37.35: [29340]: <-- [9:AT+FTH=3\r]
Nov 27 23:05:37.55: [29340]: --> [7:CONNECT]
Nov 27 23:05:37.55: [29340]: <-- data [3]
Nov 27 23:05:37.55: [29340]: <-- data [2]
Nov 27 23:05:38.72: [29340]: --> [2:OK]
Nov 27 23:05:38.72: [29340]: <-- [10:AT+FRM=96\r]
Nov 27 23:05:39.97: [29340]: --> [7:CONNECT]
Nov 27 23:05:40.52: [29340]: RECV received frame number 0
Nov 27 23:05:40.74: [29340]: RECV received frame number 1
Nov 27 23:05:40.95: [29340]: RECV received frame number 2
Nov 27 23:05:41.16: [29340]: RECV received frame number 3
Nov 27 23:05:41.37: [29340]: RECV received frame number 4
Nov 27 23:05:41.58: [29340]: RECV received frame number 5
Nov 27 23:05:41.80: [29340]: RECV received frame number 6
Nov 27 23:05:42.02: [29340]: RECV received frame number 7
Nov 27 23:05:42.23: [29340]: RECV received frame number 8
Nov 27 23:05:42.44: [29340]: RECV received frame number 9
Nov 27 23:05:42.75: [29340]: RECV received frame number 10
Nov 27 23:05:42.97: [29340]: RECV received frame number 11
Nov 27 23:05:43.18: [29340]: RECV received frame number 12
Nov 27 23:05:43.40: [29340]: RECV received frame number 13
Nov 27 23:05:43.61: [29340]: RECV received frame number 14
Nov 27 23:05:43.83: [29340]: RECV received frame number 15
Nov 27 23:05:44.04: [29340]: RECV received frame number 16
Nov 27 23:05:44.25: [29340]: RECV received frame number 17
Nov 27 23:05:44.47: [29340]: RECV received frame number 18
Nov 27 23:05:44.68: [29340]: RECV received frame number 19
Nov 27 23:05:44.90: [29340]: RECV received frame number 20
Nov 27 23:05:45.12: [29340]: RECV received frame number 21
Nov 27 23:05:45.34: [29340]: RECV received frame number 22
Nov 27 23:05:45.56: [29340]: RECV received frame number 23
Nov 27 23:05:45.77: [29340]: RECV received frame number 24
Nov 27 23:05:45.99: [29340]: RECV received frame number 25
Nov 27 23:05:46.20: [29340]: RECV received frame number 26
Nov 27 23:05:46.42: [29340]: RECV received frame number 27
Nov 27 23:05:46.74: [29340]: RECV received frame number 28
Nov 27 23:05:46.96: [29340]: RECV received frame number 29
Nov 27 23:05:47.17: [29340]: RECV received frame number 30
Nov 27 23:05:47.38: [29340]: RECV received frame number 31
Nov 27 23:05:47.60: [29340]: RECV received frame number 32
Nov 27 23:05:47.81: [29340]: RECV received frame number 33
Nov 27 23:05:48.03: [29340]: RECV received frame number 34
Nov 27 23:05:48.24: [29340]: RECV received frame number 35
Nov 27 23:05:48.46: [29340]: RECV received frame number 36
Nov 27 23:05:48.66: [29340]: RECV received frame number 37
Nov 27 23:05:48.87: [29340]: RECV received frame number 38
Nov 27 23:05:49.09: [29340]: RECV received frame number 39
Nov 27 23:05:49.31: [29340]: RECV received frame number 40
Nov 27 23:05:49.52: [29340]: RECV received frame number 41
Nov 27 23:05:49.74: [29340]: RECV received frame number 42
Nov 27 23:05:49.96: [29340]: RECV received frame number 43
Nov 27 23:05:50.28: [29340]: RECV received frame number 44
Nov 27 23:05:50.50: [29340]: RECV received frame number 45
Nov 27 23:05:50.72: [29340]: RECV received frame number 46
Nov 27 23:05:50.93: [29340]: RECV received frame number 47
Nov 27 23:05:51.15: [29340]: RECV received frame number 48
Nov 27 23:05:51.36: [29340]: RECV received frame number 49
Nov 27 23:05:51.57: [29340]: RECV received frame number 50
Nov 27 23:05:51.79: [29340]: RECV received frame number 51
Nov 27 23:05:52.00: [29340]: RECV received frame number 52
Nov 27 23:05:52.22: [29340]: RECV received frame number 53
Nov 27 23:05:52.44: [29340]: RECV received frame number 54
Nov 27 23:05:52.66: [29340]: RECV received frame number 55
Nov 27 23:05:52.88: [29340]: RECV received frame number 56
Nov 27 23:05:53.09: [29340]: RECV received frame number 57
Nov 27 23:05:53.31: [29340]: RECV received frame number 58
Nov 27 23:05:53.53: [29340]: RECV received frame number 59
Nov 27 23:05:53.75: [29340]: RECV received frame number 60
Nov 27 23:05:53.97: [29340]: RECV received frame number 61
Nov 27 23:05:54.18: [29340]: RECV received frame number 62
Nov 27 23:05:54.40: [29340]: RECV received frame number 63
Nov 27 23:05:54.62: [29340]: RECV received frame number 64
Nov 27 23:05:54.84: [29340]: RECV received frame number 65
Nov 27 23:05:55.06: [29340]: RECV received frame number 66
Nov 27 23:05:55.28: [29340]: RECV received frame number 67
Nov 27 23:05:55.50: [29340]: RECV received frame number 68
Nov 27 23:05:55.83: [29340]: RECV received frame number 69
Nov 27 23:05:56.04: [29340]: HDLC frame not byte-oriented.  Trailing byte: 0xfc
Nov 27 23:05:56.48: [29340]: HDLC frame not byte-oriented.  Trailing byte: 0xfc
Nov 27 23:05:56.80: [29340]: HDLC frame not byte-oriented.  Trailing byte: 0xc0
Nov 27 23:05:57.12: [29340]: RECV received frame number 75
Nov 27 23:05:57.34: [29340]: RECV received frame number 76
Nov 27 23:05:57.34: [29340]: RECV frame FCS check failed
Nov 27 23:05:57.54: [29340]: RECV received frame number 77
Nov 27 23:05:57.75: [29340]: HDLC frame not byte-oriented.  Trailing byte: 0
Nov 27 23:05:58.07: [29340]: HDLC frame not byte-oriented.  Trailing byte: 0xf0
Nov 27 23:05:58.40: [29340]: Bad HDLC terminating flag received.
Nov 27 23:05:58.62: [29340]: RECV received frame number 82
Nov 27 23:05:58.62: [29340]: RECV frame FCS check failed
Nov 27 23:05:58.83: [29340]: HDLC frame not byte-oriented.  Trailing byte: 0xc0
Nov 27 23:05:59.05: [29340]: Bad HDLC terminating flag received.
Nov 27 23:05:59.37: [29340]: HDLC frame not byte-oriented.  Trailing byte: 0xf8
Nov 27 23:05:59.80: [29340]: RECV received frame number 87
Nov 27 23:06:00.02: [29340]: RECV received frame number 88
Nov 27 23:06:00.24: [29340]: RECV received frame number 89
Nov 27 23:06:00.46: [29340]: RECV received frame number 90
Nov 27 23:06:00.67: [29340]: RECV received frame number 91
Nov 27 23:06:00.93: [29340]: RECV received frame number 92
Nov 27 23:06:00.93: [29340]: RECV assumed RCP frame with block end
Nov 27 23:06:00.93: [29340]: --> [10:NO CARRIER]
Nov 27 23:06:00.93: [29340]: <-- [9:AT+FRH=3\r]
Nov 27 23:06:03.25: [29340]: --> [7:CONNECT]
Nov 27 23:06:04.70: [29340]: --> [5:ERROR]
Nov 27 23:06:04.70: [29340]: MODEM Command error
Nov 27 23:06:04.70: [29340]: FCS error
Nov 27 23:06:04.70: [29340]: <-- [9:AT+FRS=7\r]
Nov 27 23:06:33.48: [29340]: --> [2:OK]
Nov 27 23:06:33.48: [29340]: <-- [9:AT+FTH=3\r]
Nov 27 23:06:33.68: [29340]: --> [7:CONNECT]
Nov 27 23:06:33.68: [29340]: <-- data [3]
Nov 27 23:06:33.68: [29340]: <-- data [2]
Nov 27 23:06:34.85: [29340]: --> [2:OK]
Nov 27 23:06:34.85: [29340]: RECV send CRP (command repeat)
Nov 27 23:06:34.85: [29340]: <-- [9:AT+FRH=3\r]
Nov 27 23:06:35.17: [29340]: --> [7:CONNECT]
Nov 27 23:06:36.28: [29340]: --> [2:OK]
Nov 27 23:06:36.28: [29340]: RECV recv PPS (partial page signal)
Nov 27 23:06:36.28: [29340]: RECV recv MPS (more pages, same document)
Nov 27 23:06:36.28: [29340]: RECV received 233 frames of block 1 of page 1
Nov 27 23:06:36.28: [29340]: <-- [9:AT+FRS=7\r]
Nov 27 23:06:36.78: [29340]: --> [2:OK]
Nov 27 23:06:36.78: [29340]: <-- [9:AT+FTH=3\r]
Nov 27 23:06:36.98: [29340]: --> [7:CONNECT]
Nov 27 23:06:36.98: [29340]: <-- data [35]
Nov 27 23:06:36.98: [29340]: <-- data [2]
Nov 27 23:06:39.11: [29340]: --> [2:OK]
Nov 27 23:06:39.11: [29340]: RECV send PPR (partial page request)
Nov 27 23:06:39.11: [29340]: <-- [10:AT+FRM=96\r]
Nov 27 23:06:39.78: [29340]: --> [7:CONNECT]
Nov 27 23:06:39.88: [29340]: HDLC frame not byte-oriented.  Trailing byte: 0xfc
Nov 27 23:06:39.89: [29340]: Bad HDLC terminating flag received.
Nov 27 23:06:39.89: [29340]: Bad HDLC terminating flag received.
Nov 27 23:06:39.89: [29340]: Bad HDLC terminating flag received.
Nov 27 23:06:39.89: [29340]: HDLC frame not byte-oriented.  Trailing byte: 0xfc
Nov 27 23:06:39.89: [29340]: Bad HDLC terminating flag received.
Nov 27 23:06:40.00: [29340]: Bad HDLC terminating flag received.
Nov 27 23:06:40.00: [29340]: Bad HDLC terminating flag received.
Nov 27 23:06:40.00: [29340]: Bad HDLC terminating flag received.
Nov 27 23:06:40.32: [29340]: HDLC frame not byte-oriented.  Trailing byte: 0xf8
Nov 27 23:06:40.74: [29340]: RECV received frame number 72
Nov 27 23:06:40.74: [29340]: RECV frame FCS check failed
Nov 27 23:06:40.96: [29340]: HDLC frame not byte-oriented.  Trailing byte: 0
Nov 27 23:06:41.29: [29340]: Bad HDLC terminating flag received.
Nov 27 23:06:41.61: [29340]: RECV received frame number 78
Nov 27 23:06:41.61: [29340]: RECV frame FCS check failed
Nov 27 23:06:41.75: [29340]: RECV assumed RCP frame with block end
Nov 27 23:06:41.75: [29340]: --> [10:NO CARRIER]
Nov 27 23:06:41.75: [29340]: <-- [9:AT+FRH=3\r]
Nov 27 23:06:44.77: [29340]: --> [7:CONNECT]
Nov 27 23:06:49.78: [29340]: <-- data [1]
Nov 27 23:06:49.79: [29340]: --> [2:OK]
Nov 27 23:06:49.79: [29340]: RECV keeping unconfirmed page
Nov 27 23:06:49.90: [29340]: RECV: 59648 bytes of data, 303 total lines
Nov 27 23:06:49.90: [29340]: RECV FAX: T.30 T2 timeout, expected signal not received
Nov 27 23:06:49.90: [29340]: RECV FAX (000000137): from 061357978, page 1 in 1:20, INF, 3.85 line/mm, 2-D MMR, 9600 bit/s
Nov 27 23:06:49.90: [29340]: RECV FAX (000000137): recvq/fax000000033.tif from 061357978, route to <unspecified>, 1 pages in 1:20
Nov 27 23:06:49.90: [29340]: <-- [9:AT+FTH=3\r]
Nov 27 23:06:49.95: [29340]: --> [7:CONNECT]
Nov 27 23:06:49.95: [29340]: <-- data [3]
Nov 27 23:06:49.95: [29340]: <-- data [2]
Nov 27 23:06:51.11: [29340]: --> [2:OK]
Nov 27 23:06:51.11: [29340]: RECV FAX (000000137): session with 061357978 terminated abnormally: T.30 T2 timeout, expected signal not received
Nov 27 23:06:51.11: [29340]: RECV FAX: bin/faxrcvd "recvq/fax000000033.tif" "cuaa0" "000000137" "T.30 T2 timeout, expected signal not received"
Nov 27 23:06:51.11: [29340]: RECV FAX: end
Nov 27 23:06:51.11: [29340]: SESSION END