[hylafax-users] receive error

[Dirk Salva <dsalva@xxxxxx>]

Hi there!

Can anybody explain what this means? Normal communication error?
hylafax 4.2.1-5sarge3 on Debian Sarge on both sides.
Creatix LC144VF with Class1 on both sides, too.


recvq/fax000000003.tif (ftp://rechner:4559/recvq/fax000000003.tif):
Sender: xxxxx Fon xxxxxxxxx
Pages: 1
Quality: Normal
Page: 1728 by 1
Received: 2006:06:18 12:05:42
TimeToRecv: 1:04
SignalRate: 14400 bit/s
DataFormat: 2-D MMR
ErrCorrect: Yes
ReceivedOn: ttyS0
                    
The full document was not received because:

Failed to properly detect high-speed data carrier.

    ---- Transcript of session follows ----
    
Jun 18 12:05:27.29: [ 4609]: SESSION BEGIN 000000003 492312267014
Jun 18 12:05:27.29: [ 4609]: HylaFAX (tm) Version 4.2.1
Jun 18 12:05:27.29: [ 4609]: <-- [4:ATA\r]
Jun 18 12:05:32.77: [ 4609]: --> [7:CONNECT]
Jun 18 12:05:32.77: [ 4609]: ANSWER: FAX CONNECTION  DEVICE '/dev/ttyS0'
Jun 18 12:05:32.77: [ 4609]: RECV FAX: begin
Jun 18 12:05:34.61: [ 4609]: --> [7:CONNECT]
Jun 18 12:05:35.36: [ 4609]: --> [7:CONNECT]
Jun 18 12:05:35.87: [ 4609]: --> [2:OK]
Jun 18 12:05:35.88: [ 4609]: <-- [9:AT+FRH=3\r]
Jun 18 12:05:36.25: [ 4609]: --> [7:CONNECT]
Jun 18 12:05:37.72: [ 4609]: --> [2:OK]
Jun 18 12:05:37.72: [ 4609]: REMOTE TSI "xxxxx Fon xxxxxxxxxxx"
Jun 18 12:05:37.72: [ 4609]: <-- [9:AT+FRH=3\r]
Jun 18 12:05:37.74: [ 4609]: --> [7:CONNECT]
Jun 18 12:05:38.05: [ 4609]: --> [2:OK]
Jun 18 12:05:38.05: [ 4609]: REMOTE wants 14400 bit/s
Jun 18 12:05:38.05: [ 4609]: REMOTE wants A4 page width (215 mm)
Jun 18 12:05:38.05: [ 4609]: REMOTE wants unlimited page length
Jun 18 12:05:38.05: [ 4609]: REMOTE wants 3.85 line/mm
Jun 18 12:05:38.05: [ 4609]: REMOTE wants 2-D MMR
Jun 18 12:05:38.05: [ 4609]: REMOTE wants T.30 Annex A, 256-byte ECM
Jun 18 12:05:38.05: [ 4609]: RECV training at v.17 14400 bit/s
Jun 18 12:05:38.05: [ 4609]: <-- [11:AT+FRM=145\r]
Jun 18 12:05:39.88: [ 4609]: --> [7:CONNECT]
Jun 18 12:05:41.48: [ 4609]: RECV: TCF 2795 bytes, 4% non-zero, 2661 zero-run
Jun 18 12:05:41.49: [ 4609]: --> [10:NO CARRIER]
Jun 18 12:05:41.49: [ 4609]: DELAY 75 ms
Jun 18 12:05:41.56: [ 4609]: TRAINING succeeded
Jun 18 12:05:41.56: [ 4609]: <-- [9:AT+FTH=3\r]
Jun 18 12:05:41.60: [ 4609]: --> [7:CONNECT]
Jun 18 12:05:42.88: [ 4609]: --> [2:OK]
Jun 18 12:05:42.88: [ 4609]: <-- [11:AT+FRM=146\r]
Jun 18 12:05:43.59: [ 4609]: --> [7:CONNECT]
Jun 18 12:05:43.88: [ 4609]: HDLC frame not byte-oriented.  Trailing byte: 0
Jun 18 12:05:44.23: [ 4609]: RECV received frame number 2
Jun 18 12:05:44.23: [ 4609]: RECV frame FCS check failed
Jun 18 12:05:44.37: [ 4609]: RECV received frame number 3
Jun 18 12:05:44.37: [ 4609]: RECV frame FCS check failed
Jun 18 12:05:44.44: [ 4609]: HDLC frame not byte-oriented.  Trailing byte: 0xc0
Jun 18 12:05:44.65: [ 4609]: RECV received frame number 5
Jun 18 12:05:44.79: [ 4609]: HDLC frame not byte-oriented.  Trailing byte: 0xfc
Jun 18 12:05:45.08: [ 4609]: RECV received frame number 8
Jun 18 12:05:45.08: [ 4609]: RECV frame FCS check failed
Jun 18 12:05:45.22: [ 4609]: RECV received frame number 9
Jun 18 12:05:45.22: [ 4609]: RECV frame FCS check failed
Jun 18 12:05:45.36: [ 4609]: RECV received frame number 10
Jun 18 12:05:45.36: [ 4609]: RECV frame FCS check failed
Jun 18 12:05:45.50: [ 4609]: RECV received frame number 11
Jun 18 12:05:45.50: [ 4609]: RECV frame FCS check failed
Jun 18 12:05:45.71: [ 4609]: RECV received frame number 12
Jun 18 12:05:45.71: [ 4609]: RECV frame FCS check failed
Jun 18 12:05:45.86: [ 4609]: HDLC frame not byte-oriented.  Trailing byte: 0
Jun 18 12:05:46.14: [ 4609]: HDLC frame not byte-oriented.  Trailing byte: 0
Jun 18 12:05:46.43: [ 4609]: HDLC frame not byte-oriented.  Trailing byte: 0xfc
Jun 18 12:05:46.71: [ 4609]: RECV received frame number 19
Jun 18 12:05:46.71: [ 4609]: RECV frame FCS check failed
Jun 18 12:05:46.86: [ 4609]: RECV received frame number 20
Jun 18 12:05:46.86: [ 4609]: RECV frame FCS check failed
Jun 18 12:05:47.00: [ 4609]: RECV received frame number 21
Jun 18 12:05:47.00: [ 4609]: RECV frame FCS check failed
Jun 18 12:05:47.15: [ 4609]: RECV received frame number 22
Jun 18 12:05:47.15: [ 4609]: RECV frame FCS check failed
Jun 18 12:05:47.29: [ 4609]: HDLC frame not byte-oriented.  Trailing byte: 0
Jun 18 12:05:47.43: [ 4609]: RECV received RCP frame
Jun 18 12:05:47.53: [ 4609]: --> [10:NO CARRIER]
Jun 18 12:05:47.53: [ 4609]: <-- [9:AT+FRH=3\r]
Jun 18 12:05:47.99: [ 4609]: --> [7:CONNECT]
Jun 18 12:05:49.07: [ 4609]: --> [2:OK]
Jun 18 12:05:49.07: [ 4609]: RECV recv PPS (partial page signal)
Jun 18 12:05:49.07: [ 4609]: RECV recv EOP (no more pages or documents)
Jun 18 12:05:49.07: [ 4609]: RECV received 25 frames of block 1 of page 1
Jun 18 12:05:49.07: [ 4609]: <-- [9:AT+FRS=7\r]
Jun 18 12:05:49.20: [ 4609]: --> [2:OK]
Jun 18 12:05:49.20: [ 4609]: <-- [9:AT+FTH=3\r]
Jun 18 12:05:49.26: [ 4609]: --> [7:CONNECT]
Jun 18 12:05:51.54: [ 4609]: --> [2:OK]
Jun 18 12:05:51.54: [ 4609]: RECV send PPR (partial page request)
Jun 18 12:05:51.54: [ 4609]: <-- [11:AT+FRM=146\r]
Jun 18 12:05:52.34: [ 4609]: --> [7:CONNECT]
Jun 18 12:05:52.63: [ 4609]: RECV received frame number 0
Jun 18 12:05:52.85: [ 4609]: HDLC frame not byte-oriented.  Trailing byte: 0
Jun 18 12:05:53.13: [ 4609]: RECV received frame number 3
Jun 18 12:05:53.13: [ 4609]: RECV frame FCS check failed
Jun 18 12:05:53.27: [ 4609]: RECV received frame number 4
Jun 18 12:05:53.41: [ 4609]: RECV received frame number 6
Jun 18 12:05:53.55: [ 4609]: RECV received frame number 7
Jun 18 12:05:53.55: [ 4609]: RECV frame FCS check failed
Jun 18 12:05:53.70: [ 4609]: HDLC frame not byte-oriented.  Trailing byte: 0
Jun 18 12:05:53.98: [ 4609]: HDLC frame not byte-oriented.  Trailing byte: 0x80
Jun 18 12:05:54.27: [ 4609]: HDLC frame not byte-oriented.  Trailing byte: 0
Jun 18 12:05:54.55: [ 4609]: RECV received frame number 14
Jun 18 12:05:54.63: [ 4609]: HDLC frame not byte-oriented.  Trailing byte: 0xf0
Jun 18 12:05:54.91: [ 4609]: RECV received frame number 16
Jun 18 12:05:54.91: [ 4609]: RECV frame FCS check failed
Jun 18 12:05:54.98: [ 4609]: HDLC frame not byte-oriented.  Trailing byte: 0xf0
Jun 18 12:05:55.19: [ 4609]: RECV received frame number 18
Jun 18 12:05:55.33: [ 4609]: RECV received frame number 19
Jun 18 12:05:55.33: [ 4609]: RECV frame FCS check failed
Jun 18 12:05:55.48: [ 4609]: RECV received frame number 20
Jun 18 12:05:55.48: [ 4609]: RECV frame FCS check failed
Jun 18 12:05:55.62: [ 4609]: HDLC frame not byte-oriented.  Trailing byte: 0x80
Jun 18 12:05:55.90: [ 4609]: RECV received frame number 23
Jun 18 12:05:55.90: [ 4609]: RECV frame FCS check failed
Jun 18 12:05:56.05: [ 4609]: RECV received frame number 24
Jun 18 12:05:56.05: [ 4609]: RECV frame FCS check failed
Jun 18 12:05:56.05: [ 4609]: RECV received RCP frame
Jun 18 12:05:56.23: [ 4609]: --> [10:NO CARRIER]
Jun 18 12:05:56.23: [ 4609]: <-- [9:AT+FRH=3\r]
Jun 18 12:05:56.58: [ 4609]: --> [7:CONNECT]
Jun 18 12:05:57.66: [ 4609]: --> [2:OK]
Jun 18 12:05:57.66: [ 4609]: RECV recv PPS (partial page signal)
Jun 18 12:05:57.66: [ 4609]: RECV recv EOP (no more pages or documents)
Jun 18 12:05:57.66: [ 4609]: RECV received 24 frames of block 1 of page 1
Jun 18 12:05:57.66: [ 4609]: <-- [9:AT+FRS=7\r]
Jun 18 12:05:57.79: [ 4609]: --> [2:OK]
Jun 18 12:05:57.79: [ 4609]: <-- [9:AT+FTH=3\r]
Jun 18 12:05:57.84: [ 4609]: --> [7:CONNECT]
Jun 18 12:06:00.13: [ 4609]: --> [2:OK]
Jun 18 12:06:00.13: [ 4609]: RECV send PPR (partial page request)
Jun 18 12:06:00.13: [ 4609]: <-- [11:AT+FRM=146\r]
Jun 18 12:06:00.95: [ 4609]: --> [7:CONNECT]
Jun 18 12:06:01.24: [ 4609]: HDLC frame not byte-oriented.  Trailing byte: 0
Jun 18 12:06:01.59: [ 4609]: RECV received frame number 3
Jun 18 12:06:01.59: [ 4609]: RECV frame FCS check failed
Jun 18 12:06:01.73: [ 4609]: RECV received frame number 7
Jun 18 12:06:01.73: [ 4609]: RECV frame FCS check failed
Jun 18 12:06:01.87: [ 4609]: HDLC frame not byte-oriented.  Trailing byte: 0
Jun 18 12:06:02.16: [ 4609]: RECV received frame number 10
Jun 18 12:06:02.16: [ 4609]: RECV frame FCS check failed
Jun 18 12:06:02.30: [ 4609]: HDLC frame not byte-oriented.  Trailing byte: 0x80
Jun 18 12:06:02.59: [ 4609]: RECV received frame number 13
Jun 18 12:06:02.59: [ 4609]: RECV frame FCS check failed
Jun 18 12:06:02.73: [ 4609]: RECV received frame number 15
Jun 18 12:06:02.87: [ 4609]: HDLC frame not byte-oriented.  Trailing byte: 0
Jun 18 12:06:03.23: [ 4609]: HDLC frame with bad control field 0xc3
Jun 18 12:06:03.51: [ 4609]: HDLC frame not byte-oriented.  Trailing byte: 0
Jun 18 12:06:03.80: [ 4609]: RECV received frame number 23
Jun 18 12:06:03.80: [ 4609]: RECV frame FCS check failed
Jun 18 12:06:03.94: [ 4609]: RECV received frame number 24
Jun 18 12:06:03.94: [ 4609]: RECV frame FCS check failed
Jun 18 12:06:03.94: [ 4609]: RECV received RCP frame
Jun 18 12:06:04.10: [ 4609]: --> [10:NO CARRIER]
Jun 18 12:06:04.10: [ 4609]: <-- [9:AT+FRH=3\r]
Jun 18 12:06:04.46: [ 4609]: --> [7:CONNECT]
Jun 18 12:06:05.53: [ 4609]: --> [2:OK]
Jun 18 12:06:05.53: [ 4609]: RECV recv PPS (partial page signal)
Jun 18 12:06:05.53: [ 4609]: RECV recv EOP (no more pages or documents)
Jun 18 12:06:05.53: [ 4609]: RECV received 19 frames of block 1 of page 1
Jun 18 12:06:05.53: [ 4609]: <-- [9:AT+FRS=7\r]
Jun 18 12:06:05.66: [ 4609]: --> [2:OK]
Jun 18 12:06:05.66: [ 4609]: <-- [9:AT+FTH=3\r]
Jun 18 12:06:05.71: [ 4609]: --> [7:CONNECT]
Jun 18 12:06:08.00: [ 4609]: --> [2:OK]
Jun 18 12:06:08.00: [ 4609]: RECV send PPR (partial page request)
Jun 18 12:06:08.00: [ 4609]: <-- [11:AT+FRM=146\r]
Jun 18 12:06:08.84: [ 4609]: --> [7:CONNECT]
Jun 18 12:06:09.14: [ 4609]: HDLC frame not byte-oriented.  Trailing byte: 0
Jun 18 12:06:09.49: [ 4609]: RECV received frame number 3
Jun 18 12:06:09.49: [ 4609]: RECV frame FCS check failed
Jun 18 12:06:09.63: [ 4609]: HDLC frame not byte-oriented.  Trailing byte: 0
Jun 18 12:06:09.77: [ 4609]: Bad HDLC terminating flag received.
Jun 18 12:06:10.06: [ 4609]: RECV received frame number 10
Jun 18 12:06:10.06: [ 4609]: RECV frame FCS check failed
Jun 18 12:06:10.20: [ 4609]: RECV received frame number 11
Jun 18 12:06:10.20: [ 4609]: RECV frame FCS check failed
Jun 18 12:06:10.34: [ 4609]: RECV received frame number 12
Jun 18 12:06:10.34: [ 4609]: RECV frame FCS check failed
Jun 18 12:06:10.48: [ 4609]: HDLC frame not byte-oriented.  Trailing byte: 0x80
Jun 18 12:06:10.77: [ 4609]: HDLC frame not byte-oriented.  Trailing byte: 0xf8
Jun 18 12:06:11.26: [ 4609]: RECV received frame number 21
Jun 18 12:06:11.26: [ 4609]: RECV frame FCS check failed
Jun 18 12:06:11.40: [ 4609]: RECV received frame number 22
Jun 18 12:06:11.40: [ 4609]: RECV frame FCS check failed
Jun 18 12:06:11.56: [ 4609]: HDLC frame not byte-oriented.  Trailing byte: 0xfc
Jun 18 12:06:11.70: [ 4609]: RECV received RCP frame
Jun 18 12:06:11.85: [ 4609]: --> [10:NO CARRIER]
Jun 18 12:06:11.85: [ 4609]: <-- [9:AT+FRH=3\r]
Jun 18 12:06:12.21: [ 4609]: --> [7:CONNECT]
Jun 18 12:06:13.28: [ 4609]: --> [2:OK]
Jun 18 12:06:13.28: [ 4609]: RECV recv PPS (partial page signal)
Jun 18 12:06:13.28: [ 4609]: RECV recv EOP (no more pages or documents)
Jun 18 12:06:13.28: [ 4609]: RECV received 18 frames of block 1 of page 1
Jun 18 12:06:13.28: [ 4609]: <-- [9:AT+FRS=7\r]
Jun 18 12:06:13.41: [ 4609]: --> [2:OK]
Jun 18 12:06:13.41: [ 4609]: <-- [9:AT+FTH=3\r]
Jun 18 12:06:13.47: [ 4609]: --> [7:CONNECT]
Jun 18 12:06:15.75: [ 4609]: --> [2:OK]
Jun 18 12:06:15.75: [ 4609]: RECV send PPR (partial page request)
Jun 18 12:06:15.75: [ 4609]: RECV sent fourth PPR
Jun 18 12:06:15.75: [ 4609]: <-- [9:AT+FRH=3\r]
Jun 18 12:06:16.24: [ 4609]: --> [7:CONNECT]
Jun 18 12:06:17.24: [ 4609]: --> [2:OK]
Jun 18 12:06:17.24: [ 4609]: RECV recv CTC (continue to correct)
Jun 18 12:06:17.24: [ 4609]: <-- [9:AT+FRS=7\r]
Jun 18 12:06:17.37: [ 4609]: --> [2:OK]
Jun 18 12:06:17.37: [ 4609]: <-- [9:AT+FTH=3\r]
Jun 18 12:06:17.42: [ 4609]: --> [7:CONNECT]
Jun 18 12:06:18.70: [ 4609]: --> [2:OK]
Jun 18 12:06:18.70: [ 4609]: RECV send CTR (confirm continue to correct)
Jun 18 12:06:18.70: [ 4609]: <-- [10:AT+FRM=96\r]
Jun 18 12:06:24.31: [ 4609]: --> [8:+FCERROR]
Jun 18 12:06:24.31: [ 4609]: <-- [10:AT+FRM=96\r]
Jun 18 12:06:24.77: [ 4609]: --> [8:+FCERROR]
Jun 18 12:06:24.77: [ 4609]: <-- [10:AT+FRM=96\r]
Jun 18 12:06:28.85: [ 4609]: --> [8:+FCERROR]
Jun 18 12:06:28.85: [ 4609]: <-- [10:AT+FRM=96\r]
Jun 18 12:06:29.31: [ 4609]: --> [8:+FCERROR]
Jun 18 12:06:29.31: [ 4609]: <-- [10:AT+FRM=96\r]
Jun 18 12:06:33.40: [ 4609]: --> [8:+FCERROR]
Jun 18 12:06:33.40: [ 4609]: <-- [10:AT+FRM=96\r]
Jun 18 12:06:33.86: [ 4609]: --> [8:+FCERROR]
Jun 18 12:06:33.86: [ 4609]: <-- [10:AT+FRM=96\r]
Jun 18 12:06:37.94: [ 4609]: --> [8:+FCERROR]
Jun 18 12:06:37.94: [ 4609]: <-- [10:AT+FRM=96\r]
Jun 18 12:06:38.40: [ 4609]: --> [8:+FCERROR]
Jun 18 12:06:38.40: [ 4609]: <-- [10:AT+FRM=96\r]
Jun 18 12:06:45.40: [ 4609]: RECV keeping unconfirmed page
Jun 18 12:06:45.43: [ 4609]: RECV: 6400 bytes of data, 7 total lines
Jun 18 12:06:45.55: [ 4609]: --> [2:OK]
Jun 18 12:06:45.55: [ 4609]: <-- [9:AT+FRS=7\r]
Jun 18 12:06:45.66: [ 4609]: --> [2:OK]
Jun 18 12:06:45.67: [ 4609]: RECV FAX (000000003): from xxxxx Fon xxxxxxxxxxx, page 1 in 1:04, INF, 3.85 line/mm, 2-D MMR, 14400 bit/s
Jun 18 12:06:45.67: [ 4609]: RECV FAX (000000003): recvq/fax000000003.tif from xxxxx Fon xxxxxxxxxx, route to <unspecified>, 1 pages in 1:13
Jun 18 12:06:45.67: [ 4609]: RECV FAX: Failed to properly detect high-speed data carrier.
Jun 18 12:06:45.67: [ 4609]: <-- [9:AT+FTH=3\r]
Jun 18 12:06:45.73: [ 4609]: --> [7:CONNECT]
Jun 18 12:06:47.00: [ 4609]: --> [2:OK]
Jun 18 12:06:47.00: [ 4609]: RECV FAX (000000003): session with xxxxx Fon xxxxxxxxxxx terminated abnormally: Failed to properly detect high-speed data carrier.
Jun 18 12:06:47.00: [ 4609]: RECV FAX: bin/faxrcvd "recvq/fax000000003.tif" "ttyS0" "000000003" "Failed to properly detect high-speed data carrier." "" ""
Jun 18 12:06:47.01: [ 4609]: RECV FAX: end
Jun 18 12:06:47.01: [ 4609]: SESSION END

The facsimile was automatically dispatched to: harald.


Hylafax tried communication again 2 minutes later, and then it worked
fine:

recvq/fax000000004.tif (ftp://deltern:4559/recvq/fax000000004.tif):
Sender: xxxxx Fon xxxxxxxxxx
Pages: 1
Quality: Normal
Page: 1728 by 82
Received: 2006:06:18 12:08:12
TimeToRecv: 0:07
SignalRate: 14400 bit/s
DataFormat: 2-D MMR
ErrCorrect: Yes
ReceivedOn: ttyS0
CommID: c000000004
(ftp://rechner:4559/log/c000000004)

The facsimile was automatically dispatched to: harald.


ciao, Dirk
-- 
|      Akkuschrauber Kaufberatung and AEG GSM stuff       |
|   Visit my homepage:   http://www.nutrimatic.ping.de/   |
| FIDO: Dirk Salva 2:244/6305.10 Internet: dsalvaATgmx.de |
|    The "Ruhrgebiet", best place to live in Germany!     |

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