[hylafax-users] receive failure

[Matthias Reich <rei@xxxxxxxx>]

Hi list,

we run hylafax 4.2.1 (debian sarge).
we switched to Multitech MT5634ba(USB) modems.
sending is quite fine.
receiving results in an error about every 10th fax.
modem ist configured as class1.
as I am not so familiar with the fax-protocol,
I would like to know if this is a modem problem,
a line problem or a problem on the other end.

any hint appreciated

TIA
Matthias

a receive-log (others are quite the same) follows:

    Failed to properly detect high-speed data carrier.

    ---- Transcript of session follows ----

Apr 04 10:40:35.93: [12667]: SESSION BEGIN 000418060 49711XXXXXXXXX
Apr 04 10:40:35.93: [12667]: HylaFAX (tm) Version 4.2.1
Apr 04 10:40:35.93: [12667]: <-- [4:ATA\r]
Apr 04 10:40:45.55: [12667]: --> [7:CONNECT]
Apr 04 10:40:45.55: [12667]: ANSWER: FAX CONNECTION  DEVICE '/dev/ttyACM2'
Apr 04 10:40:45.55: [12667]: MODEM input buffering enabled
Apr 04 10:40:45.55: [12667]: RECV FAX: begin
Apr 04 10:40:45.55: [12667]: MODEM input buffering disabled
Apr 04 10:40:45.55: [12667]: <-- HDLC<32:FF C0 04 AD 00 55 12 9E 36 86 62 82 
1A 04 14 2E B6 94 04 6A A6 4E CE 96 F6 76 04 2C 74 4C 74 8C>
Apr 04 10:40:45.56: [12667]: --> [7:CONNECT]
Apr 04 10:40:45.56: [12667]: <-- HDLC<23:FF C0 02 2E 4E 86 E6 2E 2E AE 2E CA 
04 E2 A6 04 CA F2 E2 82 12 04 04>
Apr 04 10:40:45.56: [12667]: --> [7:CONNECT]
Apr 04 10:40:45.56: [12667]: <-- HDLC<10:FF C8 01 00 76 5F 23 01 FB C0>
Apr 04 10:40:47.86: [12667]: --> [2:OK]
Apr 04 10:40:47.86: [12667]: <-- [9:AT+FRH=3\r]
Apr 04 10:40:48.00: [12667]: --> [7:CONNECT]
Apr 04 10:40:49.62: [12667]: --> HDLC<25:FF C0 C2 2C 8C 04 8C 2C 2C 9C 04 4C 
4C 2C AC 04 9C 2C D4 04 04 04 04 62 78>
Apr 04 10:40:49.62: [12667]: --> [2:OK]
Apr 04 10:40:49.62: [12667]: REMOTE TSI "+49 542XXXXXXXXX"
Apr 04 10:40:49.62: [12667]: <-- [9:AT+FRH=3\r]
Apr 04 10:40:49.63: [12667]: --> [7:CONNECT]
Apr 04 10:40:49.90: [12667]: --> HDLC<9:FF C8 C1 00 46 1F 22 39 BB>
Apr 04 10:40:49.90: [12667]: --> [2:OK]
Apr 04 10:40:49.90: [12667]: REMOTE wants 14400 bit/s
Apr 04 10:40:49.90: [12667]: REMOTE wants A4 page width (215 mm)
Apr 04 10:40:49.90: [12667]: REMOTE wants unlimited page length
Apr 04 10:40:49.90: [12667]: REMOTE wants 7.7 line/mm
Apr 04 10:40:49.90: [12667]: REMOTE wants 2-D MMR
Apr 04 10:40:49.90: [12667]: REMOTE wants T.30 Annex A, 256-byte ECM
Apr 04 10:40:49.90: [12667]: RECV training at v.17 14400 bit/s
Apr 04 10:40:49.90: [12667]: <-- [11:AT+FRM=145\r]
Apr 04 10:40:51.43: [12667]: --> [7:CONNECT]
Apr 04 10:40:52.79: [12667]: RECV: TCF 2448 bytes, 0% non-zero, 2448 zero-run
Apr 04 10:40:52.79: [12667]: --> [10:NO CARRIER]
Apr 04 10:40:52.79: [12667]: DELAY 75 ms
Apr 04 10:40:52.87: [12667]: TRAINING succeeded
Apr 04 10:40:52.87: [12667]: <-- [9:AT+FTH=3\r]
Apr 04 10:40:53.82: [12667]: --> [7:CONNECT]
Apr 04 10:40:53.82: [12667]: <-- HDLC<3:FF C8 21>
Apr 04 10:40:54.32: [12667]: --> [2:OK]
Apr 04 10:40:54.32: [12667]: MODEM input buffering enabled
Apr 04 10:40:54.32: [12667]: <-- [11:AT+FRM=146\r]
Apr 04 10:40:54.68: [12667]: --> [7:CONNECT]
Apr 04 10:40:54.96: [12667]: --> HDLC<262:FF C0 60 00 93 50 32 86 E5 36 6A 1B 
93 50 32 86 E5 8A 29 35 02 A8 2C A2 05 06 A2 5C 32 01 E4 D4 16 C1 82 38 19 86 
D9 1C 0D 32 38 64 04 23 86 48 35 11 C0 F0 5A 23 86 24 32 59 A9 4A 0C 90 D8 8A 
C4 8A C4 8A C4 8A C4 8A C4 8A AC 81 E0 5E 08 29 92 93 50 5A 89 CD 85 44 E6 C9 
42 79 40 9D 75 62 7D 92 90 5D D0 23 0E 40 F0 6C 35 29 5C AC CA CC AC CA CC AC 
CA AD 20 41 32 38 32 C9 A8 2D 47 1C 71 C7 20 BB 90 5A E2 1F BA 08 2C 81 E1 AE 
6A 52 B3 3C BE 5E 23 F2 B2 6B 09 2B 26 02 4A C9 80 49 59 95 93 C2 4A AC 8A 8F 
EF 20 CA 4A 4D 41 6B A0 8A 1C 20 85 47 2A 6E 39 53 71 CA 43 8C D8 39 1C 12 3E 
41 77 83 FE 82 0B 20 78 6B 9A 94 AD 25 6C 8F CA CF 72 B3 DC AC FC AC CA 21 92 
39 59 E5 57 7E D3 44 14 89 49 A8 2D 74 11 43 84 10 A8 CC 5C 22 3F 1F 08 8F 9D 
28 FE 3F E3 E4 17 7B F6 88 1E 0D A6 A5 2B 09 84>
Apr 04 10:40:54.96: [12667]: RECV received frame number 0
...
receiving frames 0 to 81 properly
...
Apr 04 10:41:07.40: [12667]: --> HDLC<262:FF C0 60 4A B0 7A C9 F4 C2 5C DD FB 
E0 DC C7 C3 FF BF FD CC 7B AD EA EB 77 F8 5E E0 FE 35 E2 E3 D5 30 9C 69 A6 DC 
54 54 6C 69 A6 B7 F7 0E 35 5E 87 1B 0D 8A 5D 93 B6 AC 76 9C 54 18 4D 8A 43 58 
AB 61 84 D8 82 23 FA CF 0E 18 88 88 88 88 88 8A 3D C4 44 44 46 55 C4 45 1E E2 
22 39 44 05 2A 21 84 22 30 40 E2 22 18 42 22 22 22 22 22 23 58 FA FF 11 48 38 
88 8A BF FF ED 74 A2 98 A8 AD AB 1B 54 D4 30 C4 44 63 FF FF FF FF F9 4D 51 4A 
61 2D 19 E7 06 47 8C 03 24 B5 3D 11 AC DA 2E 8E 44 76 47 8B E5 E3 11 1C 53 08 
8E 0B 61 96 55 02 88 88 8C B4 17 46 F3 C8 D1 1D 11 F4 44 22 1A 3C C8 EC F2 23 
A2 38 67 23 E5 CC BB 23 83 51 1C C8 E1 92 06 13 08 44 47 42 22 24 8C 11 66 C9 
C9 A8 28 30 CB 83 69 3E 61 11 E3 8C 8E 19 01 9A 76 91 17 0D 05 73 E4 7C 44 44 
44 44 5B 88 9F 62 08 9B 34 22 B3 49 CB D9 20 E5>
Apr 04 10:41:07.40: [12667]: RECV received frame number 82
Apr 04 10:41:07.40: [12667]: --> HDLC<56:FF C0 60 CA 31 29 B9 AC EC 4C C4 4F 
C2 22 3B 23 A3 B1 B0 E5 B8 F8 9E 45 F1 C5 88 88 E1 1B 47 6B 07 82 11 D4 7E 4D 
7F FF FE 3F FF FF FF FF FF FF FF F0 01 00 10 00 66 1D>
Apr 04 10:41:07.40: [12667]: Bad HDLC terminating flag received.
Apr 04 10:41:07.40: [12667]: RECV assumed RCP frame with block end
Apr 04 10:41:07.40: [12667]: --> [10:NO CARRIER]
Apr 04 10:41:07.40: [12667]: MODEM input buffering disabled
Apr 04 10:41:07.40: [12667]: <-- [9:AT+FRH=3\r]
Apr 04 10:41:07.49: [12667]: --> [7:CONNECT]
Apr 04 10:41:08.70: [12667]: --> HDLC<9:FF C8 FD F4 00 00 CA 2B A4>
Apr 04 10:41:08.70: [12667]: --> [2:OK]
Apr 04 10:41:08.70: [12667]: RECV recv PPS (partial page signal)
Apr 04 10:41:08.70: [12667]: RECV recv EOP (no more pages or documents)
Apr 04 10:41:08.70: [12667]: RECV received 84 frames of block 1 of page 1
Apr 04 10:41:08.70: [12667]: DELAY 70 ms
Apr 04 10:41:08.77: [12667]: <-- [9:AT+FTH=3\r]
Apr 04 10:41:09.72: [12667]: --> [7:CONNECT]
Apr 04 10:41:09.72: [12667]: <-- HDLC<35:FF C8 3D 00 00 00 00 00 00 00 00 00 
00 1F FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF>
Apr 04 10:41:11.18: [12667]: --> [2:OK]
Apr 04 10:41:11.18: [12667]: RECV send PPR (partial page request)
Apr 04 10:41:11.18: [12667]: MODEM input buffering enabled
Apr 04 10:41:11.18: [12667]: <-- [11:AT+FRM=146\r]
Apr 04 10:41:11.54: [12667]: --> [7:CONNECT]
Apr 04 10:41:11.68: [12667]: --> HDLC<56:FF C0 60 CA 31 29 B9 AC EC 4C C4 4F 
C2 22 3B 23 A3 B1 B0 E5 B8 F8 9E 45 F1 C5 88 88 E1 1B 47 6B 07 82 11 D4 7E 4D 
7F FF FE 3F FF FF FF FF FF FF FF F0 01 00 10 00 66 1D>
Apr 04 10:41:11.68: [12667]: Bad HDLC terminating flag received.
Apr 04 10:41:11.68: [12667]: RECV assumed RCP frame with block end
Apr 04 10:41:11.68: [12667]: --> [10:NO CARRIER]
Apr 04 10:41:11.68: [12667]: MODEM input buffering disabled
Apr 04 10:41:11.68: [12667]: <-- [9:AT+FRH=3\r]
Apr 04 10:41:11.77: [12667]: --> [7:CONNECT]
Apr 04 10:41:12.97: [12667]: --> HDLC<9:FF C8 FD F4 00 00 00 53 A2>
Apr 04 10:41:12.97: [12667]: --> [2:OK]
Apr 04 10:41:12.97: [12667]: RECV recv PPS (partial page signal)
Apr 04 10:41:12.97: [12667]: RECV recv EOP (no more pages or documents)
Apr 04 10:41:12.97: [12667]: RECV received 1 frames of block 1 of page 1
Apr 04 10:41:12.97: [12667]: DELAY 70 ms
Apr 04 10:41:13.04: [12667]: <-- [9:AT+FTH=3\r]
Apr 04 10:41:14.00: [12667]: --> [7:CONNECT]
Apr 04 10:41:14.00: [12667]: <-- HDLC<35:FF C8 3D 00 00 00 00 00 00 00 00 00 
00 1F FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF>
Apr 04 10:41:15.46: [12667]: --> [2:OK]
Apr 04 10:41:15.46: [12667]: RECV send PPR (partial page request)
Apr 04 10:41:15.46: [12667]: MODEM input buffering enabled
Apr 04 10:41:15.46: [12667]: <-- [11:AT+FRM=146\r]
Apr 04 10:41:15.81: [12667]: --> [7:CONNECT]
Apr 04 10:41:15.95: [12667]: RECV assumed RCP frame with block end
Apr 04 10:41:15.95: [12667]: --> [10:NO CARRIER]
Apr 04 10:41:15.95: [12667]: MODEM input buffering disabled
Apr 04 10:41:15.95: [12667]: <-- [9:AT+FRH=3\r]
Apr 04 10:41:16.04: [12667]: --> [7:CONNECT]
Apr 04 10:41:17.24: [12667]: --> HDLC<9:FF C8 FD F4 00 00 00 53 A2>
Apr 04 10:41:17.24: [12667]: --> [2:OK]
Apr 04 10:41:17.24: [12667]: RECV recv PPS (partial page signal)
Apr 04 10:41:17.24: [12667]: RECV recv EOP (no more pages or documents)
Apr 04 10:41:17.24: [12667]: RECV received 1 frames of block 1 of page 1
Apr 04 10:41:17.24: [12667]: DELAY 70 ms
Apr 04 10:41:17.32: [12667]: <-- [9:AT+FTH=3\r]
Apr 04 10:41:18.27: [12667]: --> [7:CONNECT]
Apr 04 10:41:18.27: [12667]: <-- HDLC<35:FF C8 3D 00 00 00 00 00 00 00 00 00 
00 1F FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF>
Apr 04 10:41:19.73: [12667]: --> [2:OK]
Apr 04 10:41:19.73: [12667]: RECV send PPR (partial page request)
Apr 04 10:41:19.73: [12667]: MODEM input buffering enabled
Apr 04 10:41:19.73: [12667]: <-- [11:AT+FRM=146\r]
Apr 04 10:41:20.09: [12667]: --> [7:CONNECT]
Apr 04 10:41:20.22: [12667]: --> HDLC<56:FF C0 60 CA 31 29 B9 AC EC 4C C4 4F 
C2 22 3B 23 A3 B1 B0 E5 B8 F8 9E 45 F1 C5 88 88 E1 1B 47 6B 07 82 11 D4 7E 4D 
7F FF FE 3F FF FF FF FF FF FF FF F0 01 00 10 00 66 1D>
Apr 04 10:41:20.22: [12667]: Bad HDLC terminating flag received.
Apr 04 10:41:20.22: [12667]: RECV assumed RCP frame with block end
Apr 04 10:41:20.22: [12667]: --> [10:NO CARRIER]
Apr 04 10:41:20.22: [12667]: MODEM input buffering disabled
Apr 04 10:41:20.22: [12667]: <-- [9:AT+FRH=3\r]
Apr 04 10:41:20.31: [12667]: --> [7:CONNECT]
Apr 04 10:41:21.52: [12667]: --> HDLC<9:FF C8 FD F4 00 00 00 53 A2>
Apr 04 10:41:21.52: [12667]: --> [2:OK]
Apr 04 10:41:21.52: [12667]: RECV recv PPS (partial page signal)
Apr 04 10:41:21.52: [12667]: RECV recv EOP (no more pages or documents)
Apr 04 10:41:21.52: [12667]: RECV received 1 frames of block 1 of page 1
Apr 04 10:41:21.52: [12667]: DELAY 70 ms
Apr 04 10:41:21.59: [12667]: <-- [9:AT+FTH=3\r]
Apr 04 10:41:22.54: [12667]: --> [7:CONNECT]
Apr 04 10:41:22.54: [12667]: <-- HDLC<35:FF C8 3D 00 00 00 00 00 00 00 00 00 
00 1F FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF>
Apr 04 10:41:24.00: [12667]: --> [2:OK]
Apr 04 10:41:24.00: [12667]: RECV send PPR (partial page request)
Apr 04 10:41:24.00: [12667]: RECV sent fourth PPR
Apr 04 10:41:24.00: [12667]: <-- [9:AT+FRH=3\r]
Apr 04 10:41:24.14: [12667]: --> [7:CONNECT]
Apr 04 10:41:25.28: [12667]: --> HDLC<7:FF C8 C8 00 04 4D E9>
Apr 04 10:41:25.28: [12667]: --> [2:OK]
Apr 04 10:41:25.28: [12667]: RECV recv CTC (continue to correct)
Apr 04 10:41:25.28: [12667]: DELAY 70 ms
Apr 04 10:41:25.35: [12667]: <-- [9:AT+FTH=3\r]
Apr 04 10:41:26.30: [12667]: --> [7:CONNECT]
Apr 04 10:41:26.30: [12667]: <-- HDLC<3:FF C8 23>
Apr 04 10:41:26.80: [12667]: --> [2:OK]
Apr 04 10:41:26.80: [12667]: RECV send CTR (confirm continue to correct)
Apr 04 10:41:26.80: [12667]: MODEM input buffering enabled
Apr 04 10:41:26.80: [12667]: <-- [11:AT+FRM=145\r]
Apr 04 10:41:33.80: [12667]: MODEM TIMEOUT: reading line from modem
Apr 04 10:41:33.80: [12667]: RECV keeping unconfirmed page
Apr 04 10:41:33.88: [12667]: RECV: 21504 bytes of data, 2302 total lines
Apr 04 10:41:34.08: [12667]: MODEM TIMEOUT: reading line from modem
Apr 04 10:41:34.08: [12667]: MODEM <Timeout>
Apr 04 10:41:34.08: [12667]: MODEM input buffering disabled
Apr 04 10:41:34.08: [12667]: DELAY 70 ms
Apr 04 10:41:34.15: [12667]: RECV FAX (000418060): from +49 5422 9441 14, page 
1 in 0:42, INF, 7.7 line/mm, 2-D MMR, 14400 bit/s
Apr 04 10:41:34.16: [12667]: RECV FAX (000418060): recvq/fax000088069.tif from 
+49 5422 9441 14, route to <unspecified>, 1 pages in 0:49
Apr 04 10:41:34.16: [12667]: RECV FAX: Failed to properly detect high-speed 
data carrier.
Apr 04 10:41:34.16: [12667]: <-- [9:AT+FTH=3\r]
Apr 04 10:41:35.11: [12667]: --> [7:CONNECT]
Apr 04 10:41:35.11: [12667]: <-- HDLC<3:FF C8 5F>
Apr 04 10:41:35.61: [12667]: --> [2:OK]
Apr 04 10:41:35.61: [12667]: MODEM input buffering enabled
Apr 04 10:41:35.61: [12667]: RECV FAX (000418060): session with +49 XXXXXXXX 
terminated abnormally: Failed to properly detect high-speed data carrier.
Apr 04 10:41:35.61: [12667]: RECV FAX: bin/faxrcvd.hagos 
"recvq/fax000088069.tif" "ttyACM2" "000418060" "Failed to properly detect 
high-speed data carrier." "" ""
Apr 04 10:41:35.61: [12667]: RECV FAX: end
Apr 04 10:41:35.61: [12667]: SESSION END
-- 
HAGOS eG                 phone: +49 711 7880592
Matthias Reich             fax: +49 711 7880535
Industriestr. 62           web: http://www.hagos.de
D-70565 Stuttgart         mail: rei@xxxxxxxx
Germany

Attachment: pgp00001.pgp
Description: PGP signature