V.17 issue using Davicom modem

Good day

We have an embedded system with a Davicom fax modem using the latest
HylaFAX+ 5.5.9.  It goes through a SLIC device from SiLabs directly to the
fax machine.  I'm using fax lab to test the system but sometimes I do
encounter issues.  Ons of these is the log provided below.

Any insight on why we could have such large failures and the was the
terminal failure because of the AT command error at the end?

Kind regards
Christo

Mar 27 09:34:27.83: [23191]: SESSION BEGIN 000000533 0
Mar 27 09:34:27.83: [23191]: HylaFAX (tm) Version 5.5.9
Mar 27 09:34:27.83: [23191]: <-- [4:ATA\r]
Mar 27 09:34:33.35: [23191]: --> [7:CONNECT]
Mar 27 09:34:33.36: [23191]: ANSWER: FAX CONNECTION  DEVICE '/dev/ttyS1'
Mar 27 09:34:33.36: [23191]: RECV FAX: begin
Mar 27 09:34:33.36: [23191]: <-- data [32]
Mar 27 09:34:33.37: [23191]: <-- data [2]
Mar 27 09:34:35.16: [23191]: --> [7:CONNECT]
Mar 27 09:34:35.16: [23191]: <-- data [23]
Mar 27 09:34:35.16: [23191]: <-- data [2]
Mar 27 09:34:36.15: [23191]: --> [7:CONNECT]
Mar 27 09:34:36.15: [23191]: <-- data [13]
Mar 27 09:34:36.15: [23191]: <-- data [2]
Mar 27 09:34:36.90: [23191]: --> [2:OK]
Mar 27 09:34:36.90: [23191]: <-- [9:AT+FRH=3\r]
Mar 27 09:34:37.30: [23191]: --> [7:CONNECT]
Mar 27 09:34:38.70: [23191]: --> [2:OK]
Mar 27 09:34:38.70: [23191]: RECV recv TSI (sender id)
Mar 27 09:34:38.70: [23191]: REMOTE TSI "5550123"
Mar 27 09:34:38.70: [23191]: <-- [9:AT+FRH=3\r]
Mar 27 09:34:38.71: [23191]: --> [7:CONNECT]
Mar 27 09:34:39.82: [23191]: --> [2:OK]
Mar 27 09:34:39.82: [23191]: RECV recv DCS (command signal)
Mar 27 09:34:39.82: [23191]: REMOTE wants 14400 bit/s
Mar 27 09:34:39.82: [23191]: REMOTE wants A4 page width (215 mm)
Mar 27 09:34:39.82: [23191]: REMOTE wants A4 page length (297 mm)
Mar 27 09:34:39.82: [23191]: REMOTE wants 15.4 line/mm
Mar 27 09:34:39.82: [23191]: REMOTE wants 2-D MMR
Mar 27 09:34:39.83: [23191]: REMOTE wants T.30 Annex A, 256-byte ECM
Mar 27 09:34:39.83: [23191]: RECV training at v.17 14400 bit/s
Mar 27 09:34:39.83: [23191]: <-- [11:AT+FRM=145\r]
Mar 27 09:34:41.59: [23191]: --> [7:CONNECT]
Mar 27 09:34:43.09: [23191]: RECV: TCF 2682 bytes, 0% non-zero, 2680
zero-run
Mar 27 09:34:43.09: [23191]: --> [10:NO CARRIER]
Mar 27 09:34:43.09: [23191]: <-- [9:AT+FRS=7\r]
Mar 27 09:34:43.14: [23191]: --> [2:OK]
Mar 27 09:34:43.14: [23191]: TRAINING succeeded
Mar 27 09:34:43.17: [23191]: <-- [9:AT+FTH=3\r]
Mar 27 09:34:43.18: [23191]: --> [7:CONNECT]
Mar 27 09:34:43.18: [23191]: <-- data [3]
Mar 27 09:34:43.18: [23191]: <-- data [2]
Mar 27 09:34:44.46: [23191]: --> [2:OK]
Mar 27 09:34:44.46: [23191]: <-- [11:AT+FRM=146\r]
Mar 27 09:34:45.05: [23191]: --> [7:CONNECT]
Mar 27 09:34:45.08: [23191]: Bad HDLC terminating flag received.
Mar 27 09:34:45.45: [23191]: RECV received frame number 1
Mar 27 09:34:45.68: [23191]: RECV received frame number 2
Mar 27 09:34:45.81: [23191]: RECV received frame number 3
Mar 27 09:34:45.99: [23191]: RECV received frame number 4
Mar 27 09:34:46.17: [23191]: RECV received frame number 5
Mar 27 09:34:46.34: [23191]: RECV received frame number 6
Mar 27 09:34:46.53: [23191]: RECV received frame number 7
Mar 27 09:34:46.61: [23191]: RECV received frame number 8
Mar 27 09:34:46.62: [23191]: RECV received RCP frame
Mar 27 09:34:46.75: [23191]: --> [10:NO CARRIER]
Mar 27 09:34:46.75: [23191]: <-- [9:AT+FRH=3\r]
Mar 27 09:34:47.25: [23191]: --> [7:CONNECT]
Mar 27 09:34:48.30: [23191]: --> [2:OK]
Mar 27 09:34:48.30: [23191]: RECV recv PPS (partial page signal)
Mar 27 09:34:48.30: [23191]: RECV recv EOP (no more pages or documents)
Mar 27 09:34:48.31: [23191]: RECV received 9 frames of block 1 of page 1
Mar 27 09:34:48.32: [23191]: Block incomplete: 1 frames (11%) corrupt or
missing
Mar 27 09:34:48.32: [23191]: <-- [9:AT+FRS=7\r]
Mar 27 09:34:48.37: [23191]: --> [2:OK]
Mar 27 09:34:48.37: [23191]: <-- [9:AT+FTH=3\r]
Mar 27 09:34:48.38: [23191]: --> [7:CONNECT]
Mar 27 09:34:48.38: [23191]: <-- data [35]
Mar 27 09:34:48.38: [23191]: <-- data [2]
Mar 27 09:34:50.67: [23191]: --> [2:OK]
Mar 27 09:34:50.67: [23191]: RECV send PPR (partial page request)
Mar 27 09:34:50.67: [23191]: <-- [11:AT+FRM=146\r]
Mar 27 09:34:51.25: [23191]: --> [7:CONNECT]
Mar 27 09:34:51.25: [23191]: HDLC frame not byte-oriented.  Trailing byte:
0xf8
Mar 27 09:34:51.28: [23191]: HDLC frame not byte-oriented.  Trailing byte:
0x80
Mar 27 09:34:51.30: [23191]: Bad HDLC terminating flag received.
Mar 27 09:34:51.44: [23191]: Bad HDLC terminating flag received.
Mar 27 09:34:51.50: [23191]: Bad HDLC terminating flag received.
Mar 27 09:34:51.52: [23191]: HDLC frame not byte-oriented.  Trailing byte:
0xe0
Mar 27 09:34:51.61: [23191]: RECV assumed RCP frame with block end
Mar 27 09:34:51.62: [23191]: --> [10:NO CARRIER]
Mar 27 09:34:51.62: [23191]: <-- [9:AT+FRH=3\r]
Mar 27 09:34:52.11: [23191]: --> [7:CONNECT]
Mar 27 09:34:53.16: [23191]: --> [2:OK]
Mar 27 09:34:53.19: [23191]: RECV recv PPS (partial page signal)
Mar 27 09:34:53.19: [23191]: RECV recv EOP (no more pages or documents)
Mar 27 09:34:53.19: [23191]: RECV received 1 frames of block 1 of page 1
Mar 27 09:34:53.19: [23191]: Block incomplete: 1 frames (11%) corrupt or
missing
Mar 27 09:34:53.19: [23191]: <-- [9:AT+FRS=7\r]
Mar 27 09:34:53.24: [23191]: --> [2:OK]
Mar 27 09:34:53.26: [23191]: <-- [9:AT+FTH=3\r]
Mar 27 09:34:53.27: [23191]: --> [7:CONNECT]
Mar 27 09:34:53.27: [23191]: <-- data [35]
Mar 27 09:34:53.27: [23191]: <-- data [2]
Mar 27 09:34:55.56: [23191]: --> [2:OK]
Mar 27 09:34:55.56: [23191]: RECV send PPR (partial page request)
Mar 27 09:34:55.56: [23191]: <-- [11:AT+FRM=146\r]
Mar 27 09:34:56.13: [23191]: --> [7:CONNECT]
Mar 27 09:34:56.17: [23191]: HDLC frame not byte-oriented.  Trailing byte:
0xf8
Mar 27 09:34:56.18: [23191]: HDLC frame not byte-oriented.  Trailing byte:
0xe0
Mar 27 09:34:56.22: [23191]: HDLC frame not byte-oriented.  Trailing byte:
0xf0
Mar 27 09:34:56.35: [23191]: HDLC frame not byte-oriented.  Trailing byte:
0x80
Mar 27 09:34:56.39: [23191]: RECV received RCP frame
Mar 27 09:34:56.50: [23191]: --> [10:NO CARRIER]
Mar 27 09:34:56.50: [23191]: <-- [9:AT+FRH=3\r]
Mar 27 09:34:57.00: [23191]: --> [7:CONNECT]
Mar 27 09:34:58.05: [23191]: --> [2:OK]
Mar 27 09:34:58.05: [23191]: RECV recv PPS (partial page signal)
Mar 27 09:34:58.05: [23191]: RECV recv EOP (no more pages or documents)
Mar 27 09:34:58.05: [23191]: RECV received 1 frames of block 1 of page 1
Mar 27 09:34:58.05: [23191]: Block incomplete: 1 frames (11%) corrupt or
missing
Mar 27 09:34:58.05: [23191]: <-- [9:AT+FRS=7\r]
Mar 27 09:34:58.11: [23191]: --> [2:OK]
Mar 27 09:34:58.11: [23191]: <-- [9:AT+FTH=3\r]
Mar 27 09:34:58.13: [23191]: --> [7:CONNECT]
Mar 27 09:34:58.13: [23191]: <-- data [35]
Mar 27 09:34:58.13: [23191]: <-- data [2]
Mar 27 09:35:00.42: [23191]: --> [2:OK]
Mar 27 09:35:00.42: [23191]: RECV send PPR (partial page request)
Mar 27 09:35:00.42: [23191]: <-- [11:AT+FRM=146\r]
Mar 27 09:35:01.00: [23191]: --> [7:CONNECT]
Mar 27 09:35:01.22: [23191]: RECV received frame number 0
Mar 27 09:35:01.23: [23191]: RECV received RCP frame
Mar 27 09:35:01.36: [23191]: --> [10:NO CARRIER]
Mar 27 09:35:01.36: [23191]: <-- [9:AT+FRH=3\r]
Mar 27 09:35:04.44: [23191]: --> [5:ERROR]
Mar 27 09:35:04.44: [23191]: MODEM Command error
Mar 27 09:35:04.50: [23191]: RECV: 2304 bytes of data, 4402 total lines
Mar 27 09:35:04.51: [23191]: RECV keeping unconfirmed page
Mar 27 09:35:04.57: [23191]: RECV FAX (000000533): from 5550123, page 1 in
0:00:31, A4 x A4, 15.4 line/mm, 2-D MMR, 14400 bit/s
Mar 27 09:35:05.58: [23191]: RECV FAX: T.30 T2 timeout, expected signal not
received {E118}
Mar 27 09:35:05.58: [23191]: RECV FAX (000000533): session with 5550123
terminated abnormally: T.30 T2 timeout, expected signal not received {E118}
Mar 27 09:35:05.58: [23191]: RECV FAX: bin/faxrcvd 'recvq/fax000000358.tif'
'ttyS1' '000000533' 'T.30 T2 timeout, expected signal not received {E118}'
Mar 27 09:35:05.59: [23191]: RECV FAX: end
Mar 27 09:35:05.59: [23191]: <-- [5:ATH0\r]
Mar 27 09:35:05.61: [23191]: RECV FAX (000000533): recvq/fax000000358.tif
from 5550123, subaddress <unspecified>, 1 pages in 0:00:31
Mar 27 09:35:05.64: [23191]: --> [2:OK]
Mar 27 09:35:05.64: [23191]: SESSION END