[hylafax-users] USR Courier V. Everything Performance

["Donald, Adam" <adam.donald@xxxxxxxxxxx>]

Hello

 

I appreciated Lee's input on my USR 33.6 External issue, that modem has since returned to the bin in which it was found.

I performed a garbage upgrade and attached a USR Courier V. Everything to hylafax, which has actually managed to receive all pages sent to it so far (still using the 13 page test) from the HP T45xi/deskjet FAX.

My problem now is performance.

With the 56K voice and 33.6 fax modems, I could expect to receive all 13 pages (on occasions when errors were absent) in around 3 minutes.

With the Courier V. configured as a class 1 device, I can expect 13+ minutes to receive all 13 pages.

With the Courier V. configured as a class 2.0 device, I can expect a receive time of just over 5 minutes (still significantly longer than the sportsters).

 

I have noticed that as a class 1 device, there appears to be some difficulties encountered with HDLC frames/termination, and perhaps this is "fixable".

Here is a typical log from Courier V. configured as a class 1:

Dec 21 09:01:08.32: [ 6380]: SESSION BEGIN 000000395 14146152598

Dec 21 09:01:08.32: [ 6380]: HylaFAX (tm) Version 4.2.0

Dec 21 09:01:08.32: [ 6380]: <-- [22:AT+FCLASS=1&H1&I0&R2A\r]

Dec 21 09:01:13.69: [ 6380]: --> [7:CONNECT]

Dec 21 09:01:13.69: [ 6380]: ANSWER: FAX CONNECTION  DEVICE '/dev/ttyS0'

Dec 21 09:01:13.69: [ 6380]: RECV FAX: begin

Dec 21 09:01:13.69: [ 6380]: <-- data [32]

Dec 21 09:01:13.69: [ 6380]: <-- data [2]

Dec 21 09:01:15.57: [ 6380]: --> [7:CONNECT]

Dec 21 09:01:15.57: [ 6380]: <-- data [23]

Dec 21 09:01:15.57: [ 6380]: <-- data [2]

Dec 21 09:01:16.73: [ 6380]: --> [7:CONNECT]

Dec 21 09:01:16.73: [ 6380]: <-- data [10]

Dec 21 09:01:16.73: [ 6380]: <-- data [2]

Dec 21 09:01:17.55: [ 6380]: --> [2:OK]

Dec 21 09:01:17.55: [ 6380]: <-- [9:AT+FRH=3\r]

Dec 21 09:01:19.17: [ 6380]: --> [7:CONNECT]

Dec 21 09:01:19.84: [ 6380]: --> [2:OK]

Dec 21 09:01:19.84: [ 6380]: REMOTE TSI "4146152434"

Dec 21 09:01:19.84: [ 6380]: <-- [9:AT+FRH=3\r]

Dec 21 09:01:19.88: [ 6380]: --> [7:CONNECT]

Dec 21 09:01:20.19: [ 6380]: --> [2:OK]

Dec 21 09:01:20.19: [ 6380]: REMOTE wants 14400 bit/s

Dec 21 09:01:20.20: [ 6380]: REMOTE wants A4 page width (215 mm)

Dec 21 09:01:20.20: [ 6380]: REMOTE wants unlimited page length

Dec 21 09:01:20.20: [ 6380]: REMOTE wants 7.7 line/mm

Dec 21 09:01:20.20: [ 6380]: REMOTE wants 2-D MMR

Dec 21 09:01:20.20: [ 6380]: REMOTE wants T.30 Annex A, 256-byte ECM

Dec 21 09:01:20.20: [ 6380]: RECV training at v.17 14400 bit/s

Dec 21 09:01:20.20: [ 6380]: <-- [11:AT+FRM=145\r]

Dec 21 09:01:21.91: [ 6380]: --> [7:CONNECT]

Dec 21 09:01:23.47: [ 6380]: RECV: TCF 2827 bytes, 3% non-zero, 2710 zero-run

Dec 21 09:01:23.49: [ 6380]: --> [10:NO CARRIER]

Dec 21 09:01:23.49: [ 6380]: DELAY 75 ms

Dec 21 09:01:23.57: [ 6380]: TRAINING succeeded

Dec 21 09:01:23.57: [ 6380]: <-- [9:AT+FTH=3\r]

Dec 21 09:01:23.68: [ 6380]: --> [7:CONNECT]

Dec 21 09:01:23.68: [ 6380]: <-- data [3]

Dec 21 09:01:23.68: [ 6380]: <-- data [2]

Dec 21 09:01:24.90: [ 6380]: --> [2:OK]

Dec 21 09:01:24.90: [ 6380]: <-- [11:AT+FRM=146\r]

Dec 21 09:01:25.93: [ 6380]: --> [7:CONNECT]

Dec 21 09:01:26.28: [ 6380]: RECV received frame number 0

Dec 21 09:01:26.42: [ 6380]: RECV received frame number 1

...

Dec 21 09:01:37.40: [ 6380]: RECV received frame number 41

Dec 21 09:01:37.40: [ 6380]: RECV received RCP frame

Dec 21 09:01:37.55: [ 6380]: --> [10:NO CARRIER]

Dec 21 09:01:37.55: [ 6380]: <-- [9:AT+FRH=3\r]

Dec 21 09:01:38.53: [ 6380]: --> [7:CONNECT]

Dec 21 09:01:38.78: [ 6380]: --> [2:OK]

Dec 21 09:01:38.78: [ 6380]: RECV recv PPS (partial page signal)

Dec 21 09:01:38.78: [ 6380]: RECV recv MPS (more pages, same document)

Dec 21 09:01:38.78: [ 6380]: RECV received 42 frames of block 1 of page 1

Dec 21 09:01:38.78: [ 6380]: <-- [9:AT+FRS=7\r]

Dec 21 09:01:38.89: [ 6380]: --> [2:OK]

Dec 21 09:01:38.96: [ 6380]: RECV: 10576 bytes of data, 2190 total lines

Dec 21 09:01:38.96: [ 6380]: <-- [9:AT+FTH=3\r]

Dec 21 09:01:39.08: [ 6380]: --> [7:CONNECT]

Dec 21 09:01:39.08: [ 6380]: RECV send MCF (message confirmation)

Dec 21 09:01:39.08: [ 6380]: RECV FAX (000000395): from 4146152434, page 1 in 0:16, INF, 7.7 line/mm, 2-D MMR, 14400 bit/s

Dec 21 09:01:39.08: [ 6380]: <-- data [3]

Dec 21 09:01:39.08: [ 6380]: <-- data [2]

Dec 21 09:01:40.29: [ 6380]: --> [2:OK]

Dec 21 09:01:40.29: [ 6380]: <-- [11:AT+FRM=146\r]

Dec 21 09:01:41.26: [ 6380]: --> [7:CONNECT]

Dec 21 09:01:42.40: [ 6380]: HDLC frame not byte-oriented.  Trailing byte: 0x80

Dec 21 09:01:42.68: [ 6380]: HDLC frame not byte-oriented.  Trailing byte: 0

Dec 21 09:01:43.25: [ 6380]: Bad HDLC terminating flag received.

Dec 21 09:01:43.39: [ 6380]: Bad HDLC terminating flag received.

Dec 21 09:01:43.53: [ 6380]: HDLC frame with bad address field 0x8a

Dec 21 09:01:43.68: [ 6380]: HDLC frame not byte-oriented.  Trailing byte: 0xfc

Dec 21 09:01:43.68: [ 6380]: Bad HDLC terminating flag received.

Dec 21 09:01:43.82: [ 6380]: HDLC frame not byte-oriented.  Trailing byte: 0

Dec 21 09:01:43.89: [ 6380]: HDLC frame not byte-oriented.  Trailing byte: 0x80

Dec 21 09:01:43.96: [ 6380]: Bad HDLC terminating flag received.

Dec 21 09:01:44.10: [ 6380]: Bad HDLC terminating flag received.

Dec 21 09:01:44.31: [ 6380]: Bad HDLC terminating flag received.

Dec 21 09:01:44.38: [ 6380]: HDLC frame not byte-oriented.  Trailing byte: 0

...

(132 more lines of HDLC errors)

...

Dec 21 09:01:55.24: [ 6380]: RECV assumed RCP frame with block end

Dec 21 09:01:55.24: [ 6380]: --> [10:NO CARRIER]

Dec 21 09:01:55.24: [ 6380]: <-- [9:AT+FRH=3\r]

Dec 21 09:01:56.28: [ 6380]: --> [7:CONNECT]

Dec 21 09:01:56.52: [ 6380]: --> [2:OK]

Dec 21 09:01:56.52: [ 6380]: RECV recv PPS (partial page signal)

Dec 21 09:01:56.52: [ 6380]: RECV recv MPS (more pages, same document)

Dec 21 09:01:56.52: [ 6380]: RECV received 90 frames of block 1 of page 2

Dec 21 09:01:56.52: [ 6380]: <-- [9:AT+FRS=7\r]

Dec 21 09:01:56.63: [ 6380]: --> [2:OK]

Dec 21 09:01:56.63: [ 6380]: <-- [9:AT+FTH=3\r]

Dec 21 09:01:56.75: [ 6380]: --> [7:CONNECT]

Dec 21 09:01:56.75: [ 6380]: <-- data [35]

Dec 21 09:01:56.75: [ 6380]: <-- data [2]

Dec 21 09:01:58.99: [ 6380]: --> [2:OK]

Dec 21 09:01:58.99: [ 6380]: RECV send PPR (partial page request)

Dec 21 09:01:58.99: [ 6380]: <-- [11:AT+FRM=146\r]

Dec 21 09:01:59.97: [ 6380]: --> [7:CONNECT]

Dec 21 09:02:01.67: [ 6380]: HDLC frame not byte-oriented.  Trailing byte: 0xe0

Dec 21 09:02:01.89: [ 6380]: HDLC frame with bad address field 0x91

Dec 21 09:02:02.03: [ 6380]: HDLC frame not byte-oriented.  Trailing byte: 0x80

Dec 21 09:02:02.38: [ 6380]: Bad HDLC terminating flag received.

Dec 21 09:02:02.60: [ 6380]: Bad HDLC terminating flag received.

Dec 21 09:02:02.74: [ 6380]: HDLC frame not byte-oriented.  Trailing byte: 0xf0

Dec 21 09:02:02.88: [ 6380]: Bad HDLC terminating flag received.

Dec 21 09:02:02.95: [ 6380]: Bad HDLC terminating flag received.

Dec 21 09:02:03.23: [ 6380]: HDLC frame not byte-oriented.  Trailing byte: 0xfc

...

(109 more lines of HDLC errors)

...

Dec 21 09:02:13.72: [ 6380]: Bad HDLC terminating flag received.

Dec 21 09:02:14.05: [ 6380]: RECV assumed RCP frame with block end

Dec 21 09:02:14.05: [ 6380]: --> [10:NO CARRIER]

Dec 21 09:02:14.05: [ 6380]: <-- [9:AT+FRH=3\r]

Dec 21 09:02:15.01: [ 6380]: --> [7:CONNECT]

Dec 21 09:02:15.26: [ 6380]: --> [2:OK]

Dec 21 09:02:15.26: [ 6380]: RECV recv PPS (partial page signal)

Dec 21 09:02:15.26: [ 6380]: RECV recv MPS (more pages, same document)

Dec 21 09:02:15.26: [ 6380]: RECV received 90 frames of block 1 of page 2

Dec 21 09:02:15.26: [ 6380]: <-- [9:AT+FRS=7\r]

Dec 21 09:02:15.37: [ 6380]: --> [2:OK]

Dec 21 09:02:15.37: [ 6380]: <-- [9:AT+FTH=3\r]

Dec 21 09:02:15.49: [ 6380]: --> [7:CONNECT]

Dec 21 09:02:15.49: [ 6380]: <-- data [35]

Dec 21 09:02:15.49: [ 6380]: <-- data [2]

Dec 21 09:02:17.72: [ 6380]: --> [2:OK]

Dec 21 09:02:17.72: [ 6380]: RECV send PPR (partial page request)

Dec 21 09:02:17.72: [ 6380]: <-- [11:AT+FRM=146\r]

Dec 21 09:02:18.71: [ 6380]: --> [7:CONNECT]

Dec 21 09:02:20.77: [ 6380]: Bad HDLC terminating flag received.

Dec 21 09:02:21.13: [ 6380]: Bad HDLC terminating flag received.

Dec 21 09:02:21.41: [ 6380]: HDLC frame not byte-oriented.  Trailing byte: 0xe0

...

Dec 21 09:02:51.12: [ 6380]: HDLC frame not byte-oriented.  Trailing byte: 0x80

Dec 21 09:02:51.50: [ 6380]: RECV assumed RCP frame with block end

Dec 21 09:02:51.50: [ 6380]: --> [10:NO CARRIER]

Dec 21 09:02:51.50: [ 6380]: <-- [9:AT+FRH=3\r]

Dec 21 09:02:52.43: [ 6380]: --> [7:CONNECT]

Dec 21 09:02:52.68: [ 6380]: --> [2:OK]

Dec 21 09:02:52.68: [ 6380]: RECV recv PPS (partial page signal)

Dec 21 09:02:52.68: [ 6380]: RECV recv MPS (more pages, same document)

Dec 21 09:02:52.68: [ 6380]: RECV received 90 frames of block 1 of page 2

Dec 21 09:02:52.68: [ 6380]: <-- [9:AT+FRS=7\r]

Dec 21 09:02:52.79: [ 6380]: --> [2:OK]

Dec 21 09:02:52.79: [ 6380]: <-- [9:AT+FTH=3\r]

Dec 21 09:02:52.91: [ 6380]: --> [7:CONNECT]

Dec 21 09:02:52.91: [ 6380]: <-- data [35]

Dec 21 09:02:52.91: [ 6380]: <-- data [2]

Dec 21 09:02:55.14: [ 6380]: --> [2:OK]

Dec 21 09:02:55.15: [ 6380]: RECV send PPR (partial page request)

Dec 21 09:02:55.15: [ 6380]: RECV sent fourth PPR

Dec 21 09:02:55.15: [ 6380]: <-- [9:AT+FRH=3\r]

Dec 21 09:02:56.27: [ 6380]: --> [7:CONNECT]

Dec 21 09:02:56.46: [ 6380]: --> [2:OK]

Dec 21 09:02:56.46: [ 6380]: RECV recv CTC (continue to correct)

Dec 21 09:02:56.46: [ 6380]: <-- [9:AT+FRS=7\r]

Dec 21 09:02:56.57: [ 6380]: --> [2:OK]

Dec 21 09:02:56.57: [ 6380]: <-- [9:AT+FTH=3\r]

Dec 21 09:02:56.69: [ 6380]: --> [7:CONNECT]

Dec 21 09:02:56.69: [ 6380]: <-- data [3]

Dec 21 09:02:56.69: [ 6380]: <-- data [2]

Dec 21 09:02:57.91: [ 6380]: --> [2:OK]

Dec 21 09:02:57.91: [ 6380]: RECV send CTR (confirm continue to correct)

Dec 21 09:02:57.91: [ 6380]: <-- [11:AT+FRM=121\r]

Dec 21 09:03:00.21: [ 6380]: --> [7:CONNECT]

Dec 21 09:03:00.55: [ 6380]: RECV received frame number 0

Dec 21 09:03:00.80: [ 6380]: RECV received frame number 1

Dec 21 09:03:00.97: [ 6380]: RECV received frame number 2

...

Dec 21 09:14:04.20: [ 6380]: RECV received frame number 62

Dec 21 09:14:04.20: [ 6380]: RECV received RCP frame

Dec 21 09:14:07.65: [ 6380]: MODEM <Timeout>

Dec 21 09:14:09.65: [ 6380]: MODEM <Empty line>

Dec 21 09:14:11.65: [ 6380]: MODEM <Empty line>

Dec 21 09:14:11.65: [ 6380]: <-- [9:AT+FRH=3\r]

Dec 21 09:14:11.66: [ 6380]: --> [2:OK]

Dec 21 09:14:11.66: [ 6380]: <-- [9:AT+FRH=3\r]

Dec 21 09:14:13.93: [ 6380]: --> [7:CONNECT]

Dec 21 09:14:14.18: [ 6380]: --> [2:OK]

Dec 21 09:14:14.18: [ 6380]: RECV recv PPS (partial page signal)

Dec 21 09:14:14.18: [ 6380]: RECV recv EOP (no more pages or documents)

Dec 21 09:14:14.18: [ 6380]: RECV received 63 frames of block 1 of page 13

Dec 21 09:14:14.18: [ 6380]: <-- [9:AT+FRS=7\r]

Dec 21 09:14:14.29: [ 6380]: --> [2:OK]

Dec 21 09:14:14.41: [ 6380]: RECV: 16101 bytes of data, 2189 total lines

Dec 21 09:14:14.41: [ 6380]: <-- [9:AT+FTH=3\r]

Dec 21 09:14:14.52: [ 6380]: --> [7:CONNECT]

Dec 21 09:14:14.52: [ 6380]: <-- data [3]

Dec 21 09:14:14.52: [ 6380]: <-- data [2]

Dec 21 09:14:15.73: [ 6380]: --> [2:OK]

Dec 21 09:14:15.73: [ 6380]: RECV send MCF (message confirmation)

Dec 21 09:14:15.73: [ 6380]: RECV FAX (000000395): from 4146152434, page 13 in 1:12, INF, 7.7 line/mm, 2-D MMR, 14400 bit/s

Dec 21 09:14:15.73: [ 6380]: RECV FAX (000000395): recvq/fax000000373.tif from 4146152434, route to <unspecified>, 13 pages in 13:02

Dec 21 09:14:15.73: [ 6380]: <-- [9:AT+FRH=3\r]

Dec 21 09:14:16.90: [ 6380]: --> [7:CONNECT]

Dec 21 09:14:17.03: [ 6380]: --> [2:OK]

Dec 21 09:14:17.03: [ 6380]: RECV FAX: bin/faxrcvd "recvq/fax000000373.tif" "ttyS0" "000000395" "" "" ""

Dec 21 09:14:17.04: [ 6380]: RECV FAX: end

Dec 21 09:14:17.04: [ 6380]: SESSION END

 

A typical class 1 log contains 4300 lines, as opposed to 200 lines in a class 2 log.

 

Questions:

1. Is there something that I can do to address the HDLC errors (apart from purchasing another modem) so I can run in class 1?
2. When in class 1, the remote wants 2-D MMR, but in class 2.0 the same remote wants 1-D MH.  Does this account for the 2 minute speed difference between sportster receives and courier class 2.0 receives?  Is there anything that would remedy this behavior?

 

Thank you again for your much appreciated help!

 

 

AD