[hylafax-users] More High speed data carrier issues

[Rubin Bennett <rbennett@xxxxxxxxxxxxx>]

Hello all (again).

The saga of receipt errors continues.  A short history:  Ran into severe
issues with receiving faxes from some clients on a RocketModem III
board.  After many conversations with Comtrol and many helpful posts
from Lee and others, I got Comtrol to swap the RMIII for an RMII.

I immediately ran into issues because I'm running Mandrake 10.1 on the
server, which has kernel 2.6.8.1.  Comtrol only "officially" supports
2.4 and 2.2 kernels (?!).  I installed their "beta" driver and all
appeared OK.

Now I'm seeing a few errors (nothing like what I was seeing with the
RMIII board, but still more than I'd like).

One of the more puzzling ones is below (and sorry it's so munged- my
client runs Exchange which "helpfully" trashes the "extra" carriage
returns):

Mar 17 10:36:19.91: [ 5185]: SESSION BEGIN 000045390 18028022295669
Mar 17 10:36:19.92: [ 5185]: HylaFAX (tm) Version 4.2.0
Mar 17 10:36:19.92: [ 5185]: <-- [4:ATA\r]
Mar 17 10:36:25.98: [ 5185]: --> [7:CONNECT]Mar
17 10:36:25.98: [ 5185]: ANSWER: FAX CONNECTION  DEVICE '/dev/ttyR5'
Mar 17 10:36:25.98: [ 5185]: RECV FAX: begin Mar 17 10:36:27.91: [
5185]: --> [7:CONNECT] Mar 17 10:36:28.74: [ 5185]: --> [7:CONNECT] Mar
17 10:36:29.31: [ 5185]: --> [2:OK] Mar 17 10:36:29.31: [ 5185]: <--
[9:AT+FRH=3\r] Mar 17 10:36:29.83: [ 5185]: --> [7:CONNECT] Mar 17
10:36:31.28: [ 5185]: --> [2:OK] Mar 17 10:36:31.28: [ 5185]: REMOTE TSI
"4018494980"
Mar 17 10:36:31.28: [ 5185]: <-- [9:AT+FRH=3\r] Mar 17 10:36:31.30: [
5185]: --> [7:CONNECT] Mar 17 10:36:31.63: [ 5185]: --> [2:OK] Mar 17
10:36:31.63: [ 5185]: REMOTE wants 14400 bit/s Mar 17 10:36:31.63: [
5185]: REMOTE wants A4 page width (215 mm) Mar 17 10:36:31.63: [ 5185]:
REMOTE wants unlimited page length Mar 17 10:36:31.63: [ 5185]: REMOTE
wants 3.85 line/mm Mar 17 10:36:31.63: [ 5185]: REMOTE wants 2-D MMR Mar
17 10:36:31.63: [ 5185]: REMOTE wants T.30 Annex A, 256-byte ECM Mar 17
10:36:31.63: [ 5185]: RECV training at v.17 14400 bit/s Mar 17
10:36:31.63: [ 5185]: <-- [11:AT+FRM=145\r] Mar 17 10:36:33.32: [ 5185]:
--> [7:CONNECT] Mar 17 10:36:34.88: [ 5185]: RECV: TCF 2762 bytes, 1%
non-zero, 1720 zero-run Mar 17 10:36:34.88: [ 5185]: RECV: reject TCF
(zero run too short, min 1800) Mar 17 10:36:34.88: [ 5185]: --> [10:NO
CARRIER] Mar 17 10:36:34.88: [ 5185]: DELAY 75 ms Mar 17 10:36:34.96: [
5185]: <-- [9:AT+FTH=3\r] Mar 17 10:36:35.00: [ 5185]: --> [7:CONNECT]
Mar 17 10:36:36.32: [ 5185]: --> [2:OK] Mar 17 10:36:36.32: [ 5185]:
TRAINING failed Mar 17 10:36:36.32: [ 5185]: <-- [9:AT+FRH=3\r] Mar 17
10:36:36.84: [ 5185]: --> [7:CONNECT] Mar 17 10:36:38.29: [ 5185]: -->
[2:OK] Mar 17 10:36:38.29: [ 5185]: REMOTE TSI "4018494980"
Mar 17 10:36:38.29: [ 5185]: <-- [9:AT+FRH=3\r] Mar 17 10:36:38.31: [
5185]: --> [7:CONNECT] Mar 17 10:36:38.64: [ 5185]: --> [2:OK] Mar 17
10:36:38.64: [ 5185]: REMOTE wants 14400 bit/s Mar 17 10:36:38.64: [
5185]: REMOTE wants A4 page width (215 mm) Mar 17 10:36:38.64: [ 5185]:
REMOTE wants unlimited page length Mar 17 10:36:38.64: [ 5185]: REMOTE
wants 3.85 line/mm Mar 17 10:36:38.64: [ 5185]: REMOTE wants 2-D MMR Mar
17 10:36:38.64: [ 5185]: REMOTE wants T.30 Annex A, 256-byte ECM Mar 17
10:36:38.64: [ 5185]: RECV training at v.17 14400 bit/s Mar 17
10:36:38.64: [ 5185]: <-- [11:AT+FRM=145\r] Mar 17 10:36:40.33: [ 5185]:
--> [7:CONNECT] Mar 17 10:36:41.89: [ 5185]: RECV: TCF 2761 bytes, 4%
non-zero, 1294 zero-run Mar 17 10:36:41.89: [ 5185]: RECV: reject TCF
(zero run too short, min 1800) Mar 17 10:36:41.90: [ 5185]: --> [10:NO
CARRIER] Mar 17 10:36:41.90: [ 5185]: DELAY 75 ms Mar 17 10:36:41.97: [
5185]: <-- [9:AT+FTH=3\r] Mar 17 10:36:42.03: [ 5185]: --> [7:CONNECT]
Mar 17 10:36:43.34: [ 5185]: --> [2:OK] Mar 17 10:36:43.34: [ 5185]:
TRAINING failed Mar 17 10:36:43.34: [ 5185]: <-- [9:AT+FRH=3\r] Mar 17
10:36:43.90: [ 5185]: --> [7:CONNECT] Mar 17 10:36:45.32: [ 5185]: -->
[2:OK] Mar 17 10:36:45.32: [ 5185]: REMOTE TSI "4018494980"
Mar 17 10:36:45.32: [ 5185]: <-- [9:AT+FRH=3\r] Mar 17 10:36:45.34: [
5185]: --> [7:CONNECT] Mar 17 10:36:45.66: [ 5185]: --> [2:OK] Mar 17
10:36:45.66: [ 5185]: REMOTE wants 12000 bit/s Mar 17 10:36:45.66: [
5185]: REMOTE wants A4 page width (215 mm) Mar 17 10:36:45.66: [ 5185]:
REMOTE wants unlimited page length Mar 17 10:36:45.66: [ 5185]: REMOTE
wants 3.85 line/mm Mar 17 10:36:45.66: [ 5185]: REMOTE wants 2-D MMR Mar
17 10:36:45.66: [ 5185]: REMOTE wants T.30 Annex A, 256-byte ECM Mar 17
10:36:45.66: [ 5185]: RECV training at v.17 12000 bit/s Mar 17
10:36:45.66: [ 5185]: <-- [11:AT+FRM=121\r] Mar 17 10:36:47.32: [ 5185]:
--> [7:CONNECT] Mar 17 10:36:48.92: [ 5185]: RECV: TCF 2364 bytes, 92%
non-zero, 1 zero-run Mar 17 10:36:48.92: [ 5185]: RECV: reject TCF (too
many non-zero, max 10%) Mar 17 10:36:48.92: [ 5185]: RECV: reject TCF
(zero run too short, min 1500) Mar 17 10:36:48.93: [ 5185]: --> [10:NO
CARRIER] Mar 17 10:36:48.93: [ 5185]: DELAY 75 ms Mar 17 10:36:49.00: [
5185]: <-- [9:AT+FTH=3\r] Mar 17 10:36:49.06: [ 5185]: --> [7:CONNECT]
Mar 17 10:36:50.38: [ 5185]: --> [2:OK] Mar 17 10:36:50.38: [ 5185]:
TRAINING failed Mar 17 10:36:50.38: [ 5185]: <-- [9:AT+FRH=3\r] Mar 17
10:36:50.93: [ 5185]: --> [7:CONNECT] Mar 17 10:36:52.36: [ 5185]: -->
[2:OK] Mar 17 10:36:52.36: [ 5185]: REMOTE TSI "4018494980"
Mar 17 10:36:52.36: [ 5185]: <-- [9:AT+FRH=3\r] Mar 17 10:36:52.38: [
5185]: --> [7:CONNECT] Mar 17 10:36:52.71: [ 5185]: --> [2:OK] Mar 17
10:36:52.71: [ 5185]: REMOTE wants 12000 bit/s Mar 17 10:36:52.71: [
5185]: REMOTE wants A4 page width (215 mm) Mar 17 10:36:52.71: [ 5185]:
REMOTE wants unlimited page length Mar 17 10:36:52.71: [ 5185]: REMOTE
wants 3.85 line/mm Mar 17 10:36:52.71: [ 5185]: REMOTE wants 2-D MMR Mar
17 10:36:52.71: [ 5185]: REMOTE wants T.30 Annex A, 256-byte ECM Mar 17
10:36:52.71: [ 5185]: RECV training at v.17 12000 bit/s Mar 17
10:36:52.71: [ 5185]: <-- [11:AT+FRM=121\r] Mar 17 10:36:54.39: [ 5185]:
--> [7:CONNECT] Mar 17 10:36:55.95: [ 5185]: RECV: TCF 2302 bytes, 2%
non-zero, 1015 zero-run Mar 17 10:36:55.95: [ 5185]: RECV: reject TCF
(zero run too short, min 1500) Mar 17 10:36:55.96: [ 5185]: --> [10:NO
CARRIER] Mar 17 10:36:55.96: [ 5185]: DELAY 75 ms Mar 17 10:36:56.03: [
5185]: <-- [9:AT+FTH=3\r] Mar 17 10:36:56.08: [ 5185]: --> [7:CONNECT]
Mar 17 10:36:57.40: [ 5185]: --> [2:OK] Mar 17 10:36:57.40: [ 5185]:
TRAINING failed Mar 17 10:36:57.40: [ 5185]: <-- [9:AT+FRH=3\r] Mar 17
10:36:57.94: [ 5185]: --> [7:CONNECT] Mar 17 10:36:59.36: [ 5185]: -->
[2:OK] Mar 17 10:36:59.36: [ 5185]: REMOTE TSI "4018494980"
Mar 17 10:36:59.36: [ 5185]: <-- [9:AT+FRH=3\r] Mar 17 10:36:59.38: [
5185]: --> [7:CONNECT] Mar 17 10:36:59.73: [ 5185]: --> [2:OK] Mar 17
10:36:59.73: [ 5185]: REMOTE wants 9600 bit/s Mar 17 10:36:59.73: [
5185]: REMOTE wants A4 page width (215 mm) Mar 17 10:36:59.73: [ 5185]:
REMOTE wants unlimited page length Mar 17 10:36:59.73: [ 5185]: REMOTE
wants 3.85 line/mm Mar 17 10:36:59.73: [ 5185]: REMOTE wants 2-D MMR Mar
17 10:36:59.73: [ 5185]: REMOTE wants T.30 Annex A, 256-byte ECM Mar 17
10:36:59.73: [ 5185]: RECV training at v.17 9600 bit/s Mar 17
10:36:59.73: [ 5185]: <-- [10:AT+FRM=97\r] Mar 17 10:37:01.42: [ 5185]:
--> [7:CONNECT] Mar 17 10:37:02.96: [ 5185]: RECV: TCF 1834 bytes, 1%
non-zero, 681 zero-run Mar 17 10:37:02.96: [ 5185]: RECV: reject TCF
(zero run too short, min 1200) Mar 17 10:37:02.97: [ 5185]: --> [10:NO
CARRIER] Mar 17 10:37:02.97: [ 5185]: DELAY 75 ms Mar 17 10:37:03.04: [
5185]: <-- [9:AT+FTH=3\r] Mar 17 10:37:03.10: [ 5185]: --> [7:CONNECT]
Mar 17 10:37:04.42: [ 5185]: --> [2:OK] Mar 17 10:37:04.42: [ 5185]:
TRAINING failed Mar 17 10:37:04.42: [ 5185]: <-- [9:AT+FRH=3\r] Mar 17
10:37:04.95: [ 5185]: --> [7:CONNECT] Mar 17 10:37:06.40: [ 5185]: -->
[2:OK] Mar 17 10:37:06.40: [ 5185]: REMOTE TSI "4018494980"
Mar 17 10:37:06.40: [ 5185]: <-- [9:AT+FRH=3\r] Mar 17 10:37:06.42: [
5185]: --> [7:CONNECT] Mar 17 10:37:06.75: [ 5185]: --> [2:OK] Mar 17
10:37:06.75: [ 5185]: REMOTE wants 9600 bit/s Mar 17 10:37:06.75: [
5185]: REMOTE wants A4 page width (215 mm) Mar 17 10:37:06.75: [ 5185]:
REMOTE wants unlimited page length Mar 17 10:37:06.75: [ 5185]: REMOTE
wants 3.85 line/mm Mar 17 10:37:06.75: [ 5185]: REMOTE wants 2-D MMR Mar
17 10:37:06.75: [ 5185]: REMOTE wants T.30 Annex A, 256-byte ECM Mar 17
10:37:06.75: [ 5185]: RECV training at v.17 9600 bit/s Mar 17
10:37:06.75: [ 5185]: <-- [10:AT+FRM=97\r] Mar 17 10:37:08.44: [ 5185]:
--> [7:CONNECT] Mar 17 10:37:09.98: [ 5185]: RECV: TCF 1832 bytes, 1%
non-zero, 1332 zero-run Mar 17 10:37:09.99: [ 5185]: --> [10:NO CARRIER]
Mar 17 10:37:09.99: [ 5185]: DELAY 75 ms Mar 17 10:37:10.06: [ 5185]:
TRAINING succeeded Mar 17 10:37:10.06: [ 5185]: <-- [9:AT+FTH=3\r] Mar
17 10:37:10.12: [ 5185]: --> [7:CONNECT] Mar 17 10:37:11.43: [ 5185]:
--> [2:OK] Mar 17 10:37:11.44: [ 5185]: <-- [10:AT+FRM=98\r] Mar 17
10:37:12.45: [ 5185]: --> [7:CONNECT] Mar 17 10:37:12.79: [ 5185]: HDLC
frame not byte-oriented.  Trailing byte: 0xfc Mar 17 10:37:13.33: [
5185]: RECV received frame number 2 Mar 17 10:37:13.55: [ 5185]: RECV
received frame number 3 Mar 17 10:37:13.77: [ 5185]: RECV received frame
number 4 Mar 17 10:37:13.99: [ 5185]: RECV received frame number 5 Mar
17 10:37:14.21: [ 5185]: RECV received frame number 6 Mar 17
10:37:14.43: [ 5185]: RECV received frame number 7 Mar 17 10:37:14.65: [
5185]: HDLC frame not byte-oriented.  Trailing byte: 0xfc Mar 17
10:37:15.09: [ 5185]: RECV received frame number 10 Mar 17 10:37:15.10:
[ 5185]: HDLC frame not byte-oriented.  Trailing byte: 0xf0 Mar 17
10:37:15.32: [ 5185]: HDLC frame not byte-oriented.  Trailing byte: 0xf8
Mar 17 10:37:15.75: [ 5185]: RECV received frame number 13 Mar 17
10:37:15.75: [ 5185]: RECV frame FCS check failed Mar 17 10:37:15.87: [
5185]: Bad HDLC terminating flag received.
Mar 17 10:37:15.87: [ 5185]: Bad HDLC terminating flag received.
Mar 17 10:37:16.19: [ 5185]: RECV received frame number 15 Mar 17
10:37:16.41: [ 5185]: HDLC frame not byte-oriented.  Trailing byte: 0xfc
Mar 17 10:37:16.85: [ 5185]: RECV received frame number 18 Mar 17
10:37:17.07: [ 5185]: RECV received frame number 19 Mar 17 10:37:17.29:
[ 5185]: RECV received frame number 20 Mar 17 10:37:17.51: [ 5185]: RECV
received frame number 21 Mar 17 10:37:17.73: [ 5185]: RECV received
frame number 22 Mar 17 10:37:17.95: [ 5185]: RECV received frame number
23 Mar 17 10:37:18.17: [ 5185]: RECV received frame number 24 Mar 17
10:37:18.39: [ 5185]: RECV received frame number 25 Mar 17 10:37:18.61:
[ 5185]: RECV received frame number 26 Mar 17 10:37:18.83: [ 5185]: RECV
received frame number 27 Mar 17 10:37:19.05: [ 5185]: RECV received
frame number 28 Mar 17 10:37:19.27: [ 5185]: RECV received frame number
29 Mar 17 10:37:19.49: [ 5185]: RECV received frame number 30 Mar 17
10:37:19.49: [ 5185]: RECV frame FCS check failed Mar 17 10:37:19.71: [
5185]: RECV received frame number 31 Mar 17 10:37:19.71: [ 5185]: RECV
frame FCS check failed Mar 17 10:37:19.93: [ 5185]: RECV received frame
number 32 Mar 17 10:37:20.15: [ 5185]: RECV received frame number 33 Mar
17 10:37:20.15: [ 5185]: RECV frame FCS check failed Mar 17 10:37:20.37:
[ 5185]: RECV received frame number 34 Mar 17 10:37:20.59: [ 5185]: RECV
received frame number 35 Mar 17 10:37:20.81: [ 5185]: RECV received
frame number 36 Mar 17 10:37:21.03: [ 5185]: RECV received frame number
37 Mar 17 10:37:21.03: [ 5185]: RECV frame FCS check failed Mar 17
10:37:21.25: [ 5185]: RECV received frame number 38 Mar 17 10:37:21.25:
[ 5185]: RECV frame FCS check failed Mar 17 10:37:21.47: [ 5185]: HDLC
frame not byte-oriented.  Trailing byte: 0xf0 Mar 17 10:37:21.69: [
5185]: Bad HDLC terminating flag received.
Mar 17 10:37:21.69: [ 5185]: RECV received RCP frame Mar 17 10:37:21.86:
[ 5185]: --> [10:NO CARRIER] Mar 17 10:37:21.86: [ 5185]: <--
[9:AT+FRH=3\r] Mar 17 10:37:22.18: [ 5185]: --> [7:CONNECT] Mar 17
10:37:23.13: [ 5185]: --> [2:OK] Mar 17 10:37:23.13: [ 5185]: RECV recv
PPS (partial page signal) Mar 17 10:37:23.13: [ 5185]: RECV recv MPS
(more pages, same document) Mar 17 10:37:23.13: [ 5185]: RECV received
41 frames of block 1 of page 1 Mar 17 10:37:23.13: [ 5185]: <--
[9:AT+FRS=7\r] Mar 17 10:37:23.25: [ 5185]: --> [2:OK] Mar 17
10:37:23.25: [ 5185]: <-- [9:AT+FTH=3\r] Mar 17 10:37:23.30: [ 5185]:
--> [7:CONNECT] Mar 17 10:37:25.60: [ 5185]: --> [2:OK] Mar 17
10:37:25.60: [ 5185]: RECV send PPR (partial page request) Mar 17
10:37:25.60: [ 5185]: <-- [10:AT+FRM=98\r] Mar 17 10:37:26.60: [ 5185]:
--> [7:CONNECT] Mar 17 10:37:27.04: [ 5185]: RECV received frame number
0 Mar 17 10:37:27.26: [ 5185]: RECV received frame number 1 Mar 17
10:37:27.48: [ 5185]: RECV received frame number 8 Mar 17 10:37:27.70: [
5185]: HDLC frame not byte-oriented.  Trailing byte: 0 Mar 17
10:37:28.14: [ 5185]: RECV received frame number 12 Mar 17 10:37:28.36:
[ 5185]: RECV received frame number 13 Mar 17 10:37:28.58: [ 5185]: RECV
received frame number 14 Mar 17 10:37:28.80: [ 5185]: HDLC frame not
byte-oriented.  Trailing byte: 0 Mar 17 10:37:29.24: [ 5185]: RECV
received frame number 30 Mar 17 10:37:29.46: [ 5185]: RECV received
frame number 31 Mar 17 10:37:29.68: [ 5185]: RECV received frame number
33 Mar 17 10:37:29.90: [ 5185]: RECV received frame number 37 Mar 17
10:37:30.12: [ 5185]: HDLC frame not byte-oriented.  Trailing byte: 0xfc
Mar 17 10:37:30.68: [ 5185]: RECV received frame number 40 Mar 17
10:37:30.68: [ 5185]: RECV received RCP frame Mar 17 10:37:30.68: [
5185]: --> [10:NO CARRIER] Mar 17 10:37:30.68: [ 5185]: <--
[9:AT+FRH=3\r] Mar 17 10:37:31.04: [ 5185]: --> [7:CONNECT] Mar 17
10:37:31.95: [ 5185]: --> [2:OK] Mar 17 10:37:31.95: [ 5185]: RECV recv
PPS (partial page signal) Mar 17 10:37:31.95: [ 5185]: RECV recv MPS
(more pages, same document) Mar 17 10:37:31.95: [ 5185]: RECV received
17 frames of block 1 of page 1 Mar 17 10:37:31.95: [ 5185]: <--
[9:AT+FRS=7\r] Mar 17 10:37:32.07: [ 5185]: --> [2:OK] Mar 17
10:37:32.07: [ 5185]: <-- [9:AT+FTH=3\r] Mar 17 10:37:32.12: [ 5185]:
--> [7:CONNECT] Mar 17 10:37:34.42: [ 5185]: --> [2:OK] Mar 17
10:37:34.42: [ 5185]: RECV send PPR (partial page request) Mar 17
10:37:34.42: [ 5185]: <-- [10:AT+FRM=98\r] Mar 17 10:37:35.43: [ 5185]:
--> [7:CONNECT] Mar 17 10:37:35.76: [ 5185]: RECV received frame number
9 Mar 17 10:37:35.98: [ 5185]: RECV received frame number 11 Mar 17
10:37:36.31: [ 5185]: RECV received frame number 16 Mar 17 10:37:36.53:
[ 5185]: RECV received frame number 17 Mar 17 10:37:36.75: [ 5185]: RECV
received frame number 38 Mar 17 10:37:37.05: [ 5185]: RECV received
frame number 39 Mar 17 10:37:37.05: [ 5185]: RECV received RCP frame Mar
17 10:37:37.05: [ 5185]: --> [10:NO CARRIER] Mar 17 10:37:37.05: [
5185]: <-- [9:AT+FRH=3\r] Mar 17 10:37:37.40: [ 5185]: --> [7:CONNECT]
Mar 17 10:37:38.30: [ 5185]: --> [2:OK] Mar 17 10:37:38.30: [ 5185]:
RECV recv PPS (partial page signal) Mar 17 10:37:38.30: [ 5185]: RECV
recv MPS (more pages, same document) Mar 17 10:37:38.30: [ 5185]: RECV
received 6 frames of block 1 of page 1 Mar 17 10:37:38.30: [ 5185]: <--
[9:AT+FRS=7\r] Mar 17 10:37:38.42: [ 5185]: --> [2:OK] Mar 17
10:37:38.45: [ 5185]: RECV: 10406 bytes of data, 1062 total lines Mar 17
10:37:38.45: [ 5185]: <-- [9:AT+FTH=3\r] Mar 17 10:37:38.50: [ 5185]:
--> [7:CONNECT] Mar 17 10:37:38.50: [ 5185]: RECV send MCF (message
confirmation) Mar 17 10:37:38.50: [ 5185]: RECV FAX (000045390): from
4018494980, page 1 in 0:28, INF, 3.85 line/mm, 2-D MMR, 9600 bit/s Mar
17 10:37:39.81: [ 5185]: --> [2:OK] Mar 17 10:37:39.81: [ 5185]: <--
[10:AT+FRM=98\r] Mar 17 10:37:40.82: [ 5185]: --> [7:CONNECT] Mar 17
10:37:40.93: [ 5185]: Bad HDLC terminating flag received.
Mar 17 10:37:40.93: [ 5185]: HDLC frame not byte-oriented.  Trailing
byte: 0xfc Mar 17 10:37:41.26: [ 5185]: RECV received frame number 0 Mar
17 10:37:41.48: [ 5185]: RECV received frame number 1 Mar 17
10:37:41.70: [ 5185]: RECV received frame number 2 Mar 17 10:37:41.70: [
5185]: RECV frame FCS check failed Mar 17 10:37:41.92: [ 5185]: HDLC
frame not byte-oriented.  Trailing byte: 0xfc Mar 17 10:37:42.36: [
5185]: RECV received frame number 5 Mar 17 10:37:42.58: [ 5185]: RECV
received frame number 6 Mar 17 10:37:42.80: [ 5185]: RECV received frame
number 7 Mar 17 10:37:43.02: [ 5185]: RECV received frame number 8 Mar
17 10:37:43.24: [ 5185]: RECV received frame number 9 Mar 17
10:37:43.46: [ 5185]: RECV received frame number 10 Mar 17 10:37:43.68:
[ 5185]: RECV received frame number 11 Mar 17 10:37:43.90: [ 5185]: HDLC
frame not byte-oriented.  Trailing byte: 0 Mar 17 10:37:44.34: [ 5185]:
RECV received frame number 14 Mar 17 10:37:44.56: [ 5185]: RECV received
frame number 15 Mar 17 10:37:44.78: [ 5185]: RECV received frame number
16 Mar 17 10:37:45.00: [ 5185]: RECV received frame number 17 Mar 17
10:37:45.22: [ 5185]: RECV received frame number 18 Mar 17 10:37:45.44:
[ 5185]: HDLC frame not byte-oriented.  Trailing byte: 0xfc Mar 17
10:37:45.88: [ 5185]: RECV received frame number 21 Mar 17 10:37:46.10:
[ 5185]: RECV received frame number 22 Mar 17 10:37:46.32: [ 5185]: RECV
received frame number 23 Mar 17 10:37:46.54: [ 5185]: RECV received
frame number 24 Mar 17 10:37:46.76: [ 5185]: RECV received frame number
25 Mar 17 10:37:47.08: [ 5185]: RECV received frame number 26 Mar 17
10:37:47.30: [ 5185]: RECV received frame number 27 Mar 17 10:37:47.52:
[ 5185]: RECV received frame number 28 Mar 17 10:37:47.75: [ 5185]: RECV
received frame number 29 Mar 17 10:37:47.97: [ 5185]: RECV received
frame number 30 Mar 17 10:37:47.97: [ 5185]: RECV frame FCS check failed
Mar 17 10:37:48.18: [ 5185]: RECV received frame number 31 Mar 17
10:37:48.40: [ 5185]: HDLC frame not byte-oriented.  Trailing byte: 0xfc
Mar 17 10:37:48.84: [ 5185]: RECV received frame number 34 Mar 17
10:37:48.84: [ 5185]: RECV frame FCS check failed Mar 17 10:37:49.06: [
5185]: RECV received frame number 35 Mar 17 10:37:49.28: [ 5185]: RECV
received frame number 36 Mar 17 10:37:49.50: [ 5185]: RECV received
frame number 37 Mar 17 10:37:49.72: [ 5185]: RECV received frame number
38 Mar 17 10:37:49.94: [ 5185]: RECV received frame number 39 Mar 17
10:37:50.16: [ 5185]: RECV received frame number 40 Mar 17 10:37:50.38:
[ 5185]: HDLC frame not byte-oriented.  Trailing byte: 0x80 Mar 17
10:37:50.82: [ 5185]: HDLC frame not byte-oriented.  Trailing byte: 0
Mar 17 10:37:51.26: [ 5185]: RECV received frame number 45 Mar 17
10:37:51.48: [ 5185]: RECV received frame number 46 Mar 17 10:37:51.70:
[ 5185]: RECV received frame number 47 Mar 17 10:37:51.92: [ 5185]: RECV
received frame number 48 Mar 17 10:37:52.24: [ 5185]: RECV received
frame number 49 Mar 17 10:37:52.46: [ 5185]: RECV received frame number
50 Mar 17 10:37:52.68: [ 5185]: RECV received frame number 51 Mar 17
10:37:52.90: [ 5185]: RECV received frame number 52 Mar 17 10:37:53.12:
[ 5185]: RECV received frame number 53 Mar 17 10:37:53.34: [ 5185]: RECV
received frame number 54 Mar 17 10:37:53.56: [ 5185]: Bad HDLC
terminating flag received.
Mar 17 10:37:54.00: [ 5185]: RECV received frame number 57 Mar 17
10:37:54.22: [ 5185]: RECV received frame number 58 Mar 17 10:37:54.23:
[ 5185]: RECV frame FCS check failed Mar 17 10:37:54.45: [ 5185]: RECV
received frame number 59 Mar 17 10:37:54.67: [ 5185]: HDLC frame not
byte-oriented.  Trailing byte: 0 Mar 17 10:37:55.33: [ 5185]: HDLC frame
not byte-oriented.  Trailing byte: 0xc0 Mar 17 10:37:55.77: [ 5185]:
RECV received frame number 65 Mar 17 10:37:55.77: [ 5185]: RECV frame
FCS check failed Mar 17 10:37:55.99: [ 5185]: RECV received frame number
66 Mar 17 10:37:56.21: [ 5185]: RECV received frame number 67 Mar 17
10:37:56.43: [ 5185]: RECV received frame number 68 Mar 17 10:37:56.65:
[ 5185]: RECV received frame number 69 Mar 17 10:37:56.76: [ 5185]: HDLC
frame not byte-oriented.  Trailing byte: 0xc0 Mar 17 10:37:57.09: [
5185]: RECV received frame number 71 Mar 17 10:37:57.31: [ 5185]: RECV
received frame number 72 Mar 17 10:37:57.63: [ 5185]: RECV received
frame number 73 Mar 17 10:37:57.85: [ 5185]: RECV received frame number
74 Mar 17 10:37:58.07: [ 5185]: RECV received frame number 75 Mar 17
10:37:58.07: [ 5185]: RECV frame FCS check failed Mar 17 10:37:58.29: [
5185]: HDLC frame not byte-oriented.  Trailing byte: 0xc0 Mar 17
10:37:58.73: [ 5185]: RECV received frame number 78 Mar 17 10:37:58.95:
[ 5185]: RECV received frame number 79 Mar 17 10:37:59.17: [ 5185]: RECV
received frame number 80 Mar 17 10:37:59.39: [ 5185]: RECV received
frame number 81 Mar 17 10:37:59.61: [ 5185]: RECV received frame number
82 Mar 17 10:37:59.83: [ 5185]: RECV received frame number 83 Mar 17
10:38:00.05: [ 5185]: HDLC frame not byte-oriented.  Trailing byte: 0
Mar 17 10:38:00.49: [ 5185]: RECV received frame number 86 Mar 17
10:38:00.71: [ 5185]: RECV received frame number 87 Mar 17 10:38:00.93:
[ 5185]: RECV received frame number 88 Mar 17 10:38:01.15: [ 5185]: RECV
received frame number 89 Mar 17 10:38:01.37: [ 5185]: HDLC frame not
byte-oriented.  Trailing byte: 0 Mar 17 10:38:01.81: [ 5185]: RECV
received frame number 92 Mar 17 10:38:02.03: [ 5185]: RECV received
frame number 93 Mar 17 10:38:02.03: [ 5185]: RECV frame FCS check failed
Mar 17 10:38:02.25: [ 5185]: RECV received frame number 94 Mar 17
10:38:02.47: [ 5185]: RECV received frame number 95 Mar 17 10:38:02.80:
[ 5185]: RECV received frame number 96 Mar 17 10:38:03.02: [ 5185]: RECV
received frame number 97 Mar 17 10:38:03.13: [ 5185]: HDLC frame not
byte-oriented.  Trailing byte: 0xf8 Mar 17 10:38:03.46: [ 5185]: HDLC
frame not byte-oriented.  Trailing byte: 0xfc Mar 17 10:38:03.90: [
5185]: RECV received frame number 101 Mar 17 10:38:04.12: [ 5185]: RECV
received frame number 102 Mar 17 10:38:04.34: [ 5185]: RECV received
frame number 103 Mar 17 10:38:04.34: [ 5185]: RECV frame FCS check
failed Mar 17 10:38:04.45: [ 5185]: HDLC frame not byte-oriented.
Trailing byte: 0xf0 Mar 17 10:38:04.56: [ 5185]: Bad HDLC terminating
flag received.
Mar 17 10:38:05.00: [ 5185]: HDLC frame not byte-oriented.  Trailing
byte: 0xfc Mar 17 10:38:05.44: [ 5185]: RECV received frame number 108
Mar 17 10:38:05.66: [ 5185]: RECV received frame number 109 Mar 17
10:38:05.88: [ 5185]: HDLC frame not byte-oriented.  Trailing byte: 0xfc
Mar 17 10:38:06.54: [ 5185]: HDLC frame not byte-oriented.  Trailing
byte: 0x80 Mar 17 10:38:06.98: [ 5185]: RECV received frame number 115
Mar 17 10:38:07.20: [ 5185]: RECV received frame number 116 Mar 17
10:38:07.20: [ 5185]: RECV frame FCS check failed Mar 17 10:38:07.42: [
5185]: RECV received frame number 117 Mar 17 10:38:07.64: [ 5185]: RECV
received frame number 118 Mar 17 10:38:07.86: [ 5185]: RECV received
frame number 119 Mar 17 10:38:07.86: [ 5185]: RECV frame FCS check
failed Mar 17 10:38:08.08: [ 5185]: Bad HDLC terminating flag received.
Mar 17 10:38:08.30: [ 5185]: RECV received frame number 121 Mar 17
10:38:08.30: [ 5185]: RECV frame FCS check failed Mar 17 10:38:08.30: [
5185]: RECV received frame number 122 Mar 17 10:38:08.30: [ 5185]: RECV
frame FCS check failed Mar 17 10:38:08.52: [ 5185]: HDLC frame not
byte-oriented.  Trailing byte: 0x80 Mar 17 10:38:08.85: [ 5185]: Bad
HDLC terminating flag received.
Mar 17 10:38:09.18: [ 5185]: Bad HDLC terminating flag received.
Mar 17 10:38:09.51: [ 5185]: HDLC frame not byte-oriented.  Trailing
byte: 0x80 Mar 17 10:38:09.95: [ 5185]: RECV received frame number 128
Mar 17 10:38:10.17: [ 5185]: RECV received frame number 129 Mar 17
10:38:10.39: [ 5185]: RECV received frame number 130 Mar 17 10:38:10.61:
[ 5185]: RECV received frame number 131 Mar 17 10:38:10.83: [ 5185]:
RECV received frame number 132 Mar 17 10:38:11.05: [ 5185]: RECV
received frame number 133 Mar 17 10:38:11.27: [ 5185]: RECV received
frame number 134 Mar 17 10:38:11.49: [ 5185]: RECV received frame number
135 Mar 17 10:38:11.49: [ 5185]: RECV frame FCS check failed Mar 17
10:38:11.71: [ 5185]: RECV received frame number 136 Mar 17 10:38:11.71:
[ 5185]: Bad HDLC terminating flag received.
Mar 17 10:38:12.15: [ 5185]: RECV received frame number 138 Mar 17
10:38:12.37: [ 5185]: RECV received frame number 139 Mar 17 10:38:12.70:
[ 5185]: RECV received frame number 140 Mar 17 10:38:12.70: [ 5185]:
RECV received RCP frame Mar 17 10:38:12.70: [ 5185]: --> [10:NO CARRIER]
Mar 17 10:38:12.70: [ 5185]: <-- [9:AT+FRH=3\r] Mar 17 10:38:13.06: [
5185]: --> [7:CONNECT] Mar 17 10:38:13.97: [ 5185]: --> [2:OK] Mar 17
10:38:13.97: [ 5185]: RECV recv PPS (partial page signal) Mar 17
10:38:13.97: [ 5185]: RECV recv MPS (more pages, same document) Mar 17
10:38:13.97: [ 5185]: RECV received 141 frames of block 1 of page 2 Mar
17 10:38:13.97: [ 5185]: <-- [9:AT+FRS=7\r] Mar 17 10:38:14.09: [ 5185]:
--> [2:OK] Mar 17 10:38:14.09: [ 5185]: <-- [9:AT+FTH=3\r] Mar 17
10:38:14.14: [ 5185]: --> [7:CONNECT] Mar 17 10:38:16.38: [ 5185]: -->
[2:OK] Mar 17 10:38:16.38: [ 5185]: RECV send PPR (partial page request)
Mar 17 10:38:16.38: [ 5185]: <-- [10:AT+FRM=98\r] Mar 17 10:38:16.99: [
5185]: --> [8:+FCERROR] Mar 17 10:38:16.99: [ 5185]: <--
[10:AT+FRM=98\r] Mar 17 10:38:17.47: [ 5185]: --> [8:+FCERROR] Mar 17
10:38:17.47: [ 5185]: <-- [10:AT+FRM=98\r] Mar 17 10:38:21.69: [ 5185]:
--> [8:+FCERROR] Mar 17 10:38:21.69: [ 5185]: <-- [10:AT+FRM=98\r] Mar
17 10:38:26.04: [ 5185]: --> [8:+FCERROR] Mar 17 10:38:26.04: [ 5185]:
<-- [10:AT+FRM=98\r] Mar 17 10:38:30.36: [ 5185]: --> [8:+FCERROR] Mar
17 10:38:30.36: [ 5185]: <-- [10:AT+FRM=98\r] Mar 17 10:38:37.36: [
5185]: RECV keeping unconfirmed page Mar 17 10:38:37.38: [ 5185]: RECV:
36096 bytes of data, 98 total lines Mar 17 10:38:37.49: [ 5185]: -->
[2:OK] Mar 17 10:38:37.49: [ 5185]: RECV FAX (000045390): from
4018494980, page 2 in 0:59, INF, 3.85 line/mm, 2-D MMR, 9600 bit/s Mar
17 10:38:37.49: [ 5185]: RECV FAX (000045390): recvq/fax000045410.tif
from 4018494980, route to <unspecified>, 2 pages in 2:12 Mar 17
10:38:37.49: [ 5185]: RECV FAX: Failed to properly detect high-speed
data carrier.
Mar 17 10:38:37.49: [ 5185]: <-- [9:AT+FTH=3\r] Mar 17 10:38:37.54: [
5185]: --> [7:CONNECT] Mar 17 10:38:38.85: [ 5185]: --> [2:OK] Mar 17
10:38:38.86: [ 5185]: RECV FAX (000045390): session with 4018494980
terminated abnormally: Failed to properly detect high-speed data
carrier.
Mar 17 10:38:38.86: [ 5185]: RECV FAX: bin/faxrcvd
"recvq/fax000045410.tif" "ttyR5" "000045390" "Failed to properly detect
high-speed data carrier." "" ""
Mar 17 10:38:38.86: [ 5185]: RECV FAX: end Mar 17 10:38:38.86: [ 5185]:
SESSION END

Any ideas on this one?  Is it just a bad connection or is my RM
misbehaving somehow?

Thanks as always,
Rubin

Attachment: signature.asc
Description: This is a digitally signed message part