[hylafax-users] Communication problems

["dev.malst" <dev.malst@xxxxxxxxxxxx>]

Can anyone help with this communication log ? We set Class1, so why our modem use RTN (class 2 command) ?

In this log, remote fax machine honors retrain command ? or transmit new page ignoring RTN ?

 

Nov 29 08:55:42.12: [ 3154]: SESSION BEGIN 000000414 390297226432

Nov 29 08:55:42.12: [ 3154]: HylaFAX (tm) Version 6.0.5

Nov 29 08:55:42.12: [ 3154]: <-- [4:ATA\r]

Nov 29 08:55:52.81: [ 3154]: --> [7:CONNECT]

Nov 29 08:55:52.81: [ 3154]: ANSWER: FAX CONNECTION  DEVICE '/dev/apsfax432'

Nov 29 08:55:52.81: [ 3154]: RECV FAX: begin

Nov 29 08:55:52.81: [ 3154]: <-- data [32]

Nov 29 08:55:52.81: [ 3154]: <-- data [2]

Nov 29 08:55:52.84: [ 3154]: --> [7:CONNECT]

Nov 29 08:55:52.84: [ 3154]: <-- data [23]

Nov 29 08:55:52.84: [ 3154]: <-- data [2]

Nov 29 08:55:52.86: [ 3154]: --> [7:CONNECT]

Nov 29 08:55:52.86: [ 3154]: <-- data [13]

Nov 29 08:55:52.86: [ 3154]: <-- data [2]

Nov 29 08:55:55.07: [ 3154]: --> [2:OK]

Nov 29 08:55:55.07: [ 3154]: <-- [9:AT+FRH=3\r]

Nov 29 08:55:56.24: [ 3154]: --> [7:CONNECT]

Nov 29 08:55:58.06: [ 3154]: --> [2:OK]

Nov 29 08:55:58.06: [ 3154]: RECV recv TSI (sender id)

Nov 29 08:55:58.06: [ 3154]: REMOTE TSI "050701846"

Nov 29 08:55:58.06: [ 3154]: <-- [9:AT+FRH=3\r]

Nov 29 08:55:58.08: [ 3154]: --> [7:CONNECT]

Nov 29 08:55:58.35: [ 3154]: --> [2:OK]

Nov 29 08:55:58.35: [ 3154]: RECV recv DCS (command signal)

Nov 29 08:55:58.35: [ 3154]: REMOTE wants 14400 bit/s

Nov 29 08:55:58.35: [ 3154]: REMOTE wants A4 page width (215 mm)

Nov 29 08:55:58.35: [ 3154]: REMOTE wants unlimited page length

Nov 29 08:55:58.35: [ 3154]: REMOTE wants 3.85 line/mm

Nov 29 08:55:58.35: [ 3154]: REMOTE wants 2-D MR

Nov 29 08:55:58.35: [ 3154]: RECV training at v.17 14400 bit/s

Nov 29 08:55:58.35: [ 3154]: <-- [11:AT+FRM=145\r]

Nov 29 08:56:00.12: [ 3154]: --> [7:CONNECT]

Nov 29 08:56:01.43: [ 3154]: RECV: TCF 2352 bytes, 0% non-zero, 2348 zero-run

Nov 29 08:56:01.44: [ 3154]: --> [10:NO CARRIER]

Nov 29 08:56:01.44: [ 3154]: <-- [9:AT+FRS=7\r]

Nov 29 08:56:01.52: [ 3154]: --> [2:OK]

Nov 29 08:56:01.52: [ 3154]: TRAINING succeeded

Nov 29 08:56:01.52: [ 3154]: <-- [9:AT+FTH=3\r]

Nov 29 08:56:02.38: [ 3154]: --> [7:CONNECT]

Nov 29 08:56:02.38: [ 3154]: <-- data [3]

Nov 29 08:56:02.38: [ 3154]: <-- data [2]

Nov 29 08:56:02.70: [ 3154]: --> [2:OK]

Nov 29 08:56:02.70: [ 3154]: <-- [11:AT+FRM=146\r]

Nov 29 08:56:04.51: [ 3154]: --> [7:CONNECT]

Nov 29 08:56:04.51: [ 3154]: RECV: begin page

Nov 29 08:56:17.84: [ 3154]: RECV: 59 total lines, 39 bad lines, 7 consecutive bad lines

Nov 29 08:56:17.84: [ 3154]: RECV: REJECT page quality, 33% good lines (95% required)

Nov 29 08:56:17.84: [ 3154]: RECV: end page

Nov 29 08:56:17.84: [ 3154]: --> [10:NO CARRIER]

Nov 29 08:56:17.84: [ 3154]: <-- [9:AT+FRH=3\r]

Nov 29 08:56:17.98: [ 3154]: --> [7:CONNECT]

Nov 29 08:56:19.01: [ 3154]: --> [2:OK]

Nov 29 08:56:19.01: [ 3154]: RECV recv MPS (more pages, same document)

Nov 29 08:56:19.01: [ 3154]: <-- [9:AT+FRS=7\r]

Nov 29 08:56:19.19: [ 3154]: --> [2:OK]

Nov 29 08:56:19.19: [ 3154]: RECV send RTN (retrain negative)

Nov 29 08:56:19.19: [ 3154]: <-- [9:AT+FTH=3\r]

Nov 29 08:56:20.16: [ 3154]: --> [7:CONNECT]

Nov 29 08:56:20.16: [ 3154]: RECV FAX (000000414): from 050701846, page 1 in 0:00:25, INF, 3.85 line/mm, 2-D MR, 14400 bit/s

Nov 29 08:56:20.16: [ 3154]: <-- data [3]

Nov 29 08:56:20.16: [ 3154]: <-- data [2]

Nov 29 08:56:20.58: [ 3154]: --> [2:OK]

Nov 29 08:56:20.58: [ 3154]: <-- [9:AT+FRH=3\r]

Nov 29 08:56:21.63: [ 3154]: --> [7:CONNECT]

Nov 29 08:56:23.45: [ 3154]: --> [2:OK]

Nov 29 08:56:23.45: [ 3154]: RECV recv TSI (sender id)

Nov 29 08:56:23.45: [ 3154]: REMOTE TSI "050701846"

Nov 29 08:56:23.45: [ 3154]: <-- [9:AT+FRH=3\r]

Nov 29 08:56:23.57: [ 3154]: --> [7:CONNECT]

Nov 29 08:56:23.75: [ 3154]: --> [2:OK]

Nov 29 08:56:23.75: [ 3154]: RECV recv DCS (command signal)

Nov 29 08:56:23.75: [ 3154]: REMOTE wants 12000 bit/s

Nov 29 08:56:23.75: [ 3154]: REMOTE wants A4 page width (215 mm)

Nov 29 08:56:23.75: [ 3154]: REMOTE wants unlimited page length

Nov 29 08:56:23.75: [ 3154]: REMOTE wants 3.85 line/mm

Nov 29 08:56:23.75: [ 3154]: REMOTE wants 2-D MR

Nov 29 08:56:23.75: [ 3154]: RECV training at v.17 12000 bit/s

Nov 29 08:56:23.75: [ 3154]: <-- [11:AT+FRM=121\r]

Nov 29 08:56:25.49: [ 3154]: --> [7:CONNECT]

Nov 29 08:56:26.82: [ 3154]: RECV: TCF 1952 bytes, 0% non-zero, 1952 zero-run

Nov 29 08:56:26.82: [ 3154]: --> [10:NO CARRIER]

Nov 29 08:56:26.82: [ 3154]: <-- [9:AT+FRS=7\r]

Nov 29 08:56:27.00: [ 3154]: --> [2:OK]

Nov 29 08:56:27.00: [ 3154]: TRAINING succeeded

Nov 29 08:56:27.00: [ 3154]: <-- [9:AT+FTH=3\r]

Nov 29 08:56:27.97: [ 3154]: --> [7:CONNECT]

Nov 29 08:56:27.97: [ 3154]: <-- data [3]

Nov 29 08:56:27.97: [ 3154]: <-- data [2]

Nov 29 08:56:28.40: [ 3154]: --> [2:OK]

Nov 29 08:56:28.40: [ 3154]: <-- [11:AT+FRM=122\r]

Nov 29 08:56:30.13: [ 3154]: --> [7:CONNECT]

Nov 29 08:56:30.13: [ 3154]: RECV: begin page

Nov 29 08:56:44.49: [ 3154]: RECV: 40 total lines, 30 bad lines, 10 consecutive bad lines

Nov 29 08:56:44.49: [ 3154]: RECV: REJECT page quality, 25% good lines (95% required)

Nov 29 08:56:44.49: [ 3154]: RECV: end page

Nov 29 08:56:44.49: [ 3154]: --> [10:NO CARRIER]

Nov 29 08:56:44.49: [ 3154]: <-- [9:AT+FRH=3\r]

Nov 29 08:56:44.63: [ 3154]: --> [7:CONNECT]

Nov 29 08:56:45.66: [ 3154]: --> [2:OK]

Nov 29 08:56:45.66: [ 3154]: RECV recv MPS (more pages, same document)

Nov 29 08:56:45.66: [ 3154]: <-- [9:AT+FRS=7\r]

Nov 29 08:56:45.84: [ 3154]: --> [2:OK]

Nov 29 08:56:45.84: [ 3154]: RECV send RTN (retrain negative)

Nov 29 08:56:45.84: [ 3154]: <-- [9:AT+FTH=3\r]

Nov 29 08:56:46.81: [ 3154]: --> [7:CONNECT]

Nov 29 08:56:46.81: [ 3154]: RECV FAX (000000414): from 050701846, page 2 in 0:00:27, INF, 3.85 line/mm, 2-D MR, 12000 bit/s

Nov 29 08:56:46.81: [ 3154]: <-- data [3]

Nov 29 08:56:46.81: [ 3154]: <-- data [2]

Nov 29 08:56:47.23: [ 3154]: --> [2:OK]

Nov 29 08:56:47.23: [ 3154]: <-- [9:AT+FRH=3\r]

Nov 29 08:56:48.30: [ 3154]: --> [7:CONNECT]

Nov 29 08:56:50.12: [ 3154]: --> [2:OK]

Nov 29 08:56:50.12: [ 3154]: RECV recv TSI (sender id)

Nov 29 08:56:50.12: [ 3154]: REMOTE TSI "050701846"

Nov 29 08:56:50.12: [ 3154]: <-- [9:AT+FRH=3\r]

Nov 29 08:56:50.24: [ 3154]: --> [7:CONNECT]

Nov 29 08:56:50.42: [ 3154]: --> [2:OK]

Nov 29 08:56:50.42: [ 3154]: RECV recv DCS (command signal)

Nov 29 08:56:50.42: [ 3154]: REMOTE wants 9600 bit/s

Nov 29 08:56:50.42: [ 3154]: REMOTE wants A4 page width (215 mm)

Nov 29 08:56:50.42: [ 3154]: REMOTE wants unlimited page length

Nov 29 08:56:50.42: [ 3154]: REMOTE wants 3.85 line/mm

Nov 29 08:56:50.42: [ 3154]: REMOTE wants 2-D MR

Nov 29 08:56:50.42: [ 3154]: RECV training at v.17 9600 bit/s

Nov 29 08:56:50.42: [ 3154]: <-- [10:AT+FRM=97\r]

Nov 29 08:56:52.18: [ 3154]: --> [7:CONNECT]

Nov 29 08:56:53.50: [ 3154]: RECV: TCF 1568 bytes, 0% non-zero, 1566 zero-run

Nov 29 08:56:53.50: [ 3154]: --> [10:NO CARRIER]

Nov 29 08:56:53.50: [ 3154]: <-- [9:AT+FRS=7\r]

Nov 29 08:56:53.68: [ 3154]: --> [2:OK]

Nov 29 08:56:53.68: [ 3154]: TRAINING succeeded

Nov 29 08:56:53.68: [ 3154]: <-- [9:AT+FTH=3\r]

Nov 29 08:56:54.64: [ 3154]: --> [7:CONNECT]

Nov 29 08:56:54.64: [ 3154]: <-- data [3]

Nov 29 08:56:54.64: [ 3154]: <-- data [2]

Nov 29 08:56:55.07: [ 3154]: --> [2:OK]

Nov 29 08:56:55.07: [ 3154]: <-- [10:AT+FRM=98\r]

Nov 29 08:56:56.82: [ 3154]: --> [7:CONNECT]

Nov 29 08:56:56.82: [ 3154]: RECV: begin page

Nov 29 08:57:14.27: [ 3154]: RECV: 36 total lines, 26 bad lines, 7 consecutive bad lines

Nov 29 08:57:14.27: [ 3154]: RECV: REJECT page quality, 27% good lines (95% required)

Nov 29 08:57:14.27: [ 3154]: RECV: end page

Nov 29 08:57:14.27: [ 3154]: --> [10:NO CARRIER]

Nov 29 08:57:14.27: [ 3154]: <-- [9:AT+FRH=3\r]

Nov 29 08:57:14.40: [ 3154]: --> [7:CONNECT]

Nov 29 08:57:15.43: [ 3154]: --> [2:OK]

Nov 29 08:57:15.43: [ 3154]: RECV recv MPS (more pages, same document)

Nov 29 08:57:15.43: [ 3154]: <-- [9:AT+FRS=7\r]

Nov 29 08:57:15.62: [ 3154]: --> [2:OK]

Nov 29 08:57:15.62: [ 3154]: RECV send RTN (retrain negative)

Nov 29 08:57:15.62: [ 3154]: <-- [9:AT+FTH=3\r]

Nov 29 08:57:16.58: [ 3154]: --> [7:CONNECT]

Nov 29 08:57:16.58: [ 3154]: RECV FAX (000000414): from 050701846, page 3 in 0:00:30, INF, 3.85 line/mm, 2-D MR, 9600 bit/s

Nov 29 08:57:16.58: [ 3154]: <-- data [3]

Nov 29 08:57:16.58: [ 3154]: <-- data [2]

Nov 29 08:57:17.00: [ 3154]: --> [2:OK]

Nov 29 08:57:17.00: [ 3154]: <-- [9:AT+FRH=3\r]

Nov 29 08:57:18.03: [ 3154]: --> [7:CONNECT]

Nov 29 08:57:19.88: [ 3154]: --> [2:OK]

Nov 29 08:57:19.88: [ 3154]: RECV recv TSI (sender id)

Nov 29 08:57:19.88: [ 3154]: REMOTE TSI "050701846"

Nov 29 08:57:19.88: [ 3154]: <-- [9:AT+FRH=3\r]

Nov 29 08:57:19.99: [ 3154]: --> [7:CONNECT]

Nov 29 08:57:20.17: [ 3154]: --> [2:OK]

Nov 29 08:57:20.17: [ 3154]: RECV recv DCS (command signal)

Nov 29 08:57:20.17: [ 3154]: REMOTE wants 7200 bit/s

Nov 29 08:57:20.17: [ 3154]: REMOTE wants A4 page width (215 mm)

Nov 29 08:57:20.17: [ 3154]: REMOTE wants unlimited page length

Nov 29 08:57:20.17: [ 3154]: REMOTE wants 3.85 line/mm

Nov 29 08:57:20.17: [ 3154]: REMOTE wants 2-D MR

Nov 29 08:57:20.17: [ 3154]: RECV training at v.17 7200 bit/s

Nov 29 08:57:20.17: [ 3154]: <-- [10:AT+FRM=73\r]

Nov 29 08:57:21.97: [ 3154]: --> [7:CONNECT]

Nov 29 08:57:23.26: [ 3154]: RECV: TCF 1184 bytes, 0% non-zero, 1183 zero-run

Nov 29 08:57:23.26: [ 3154]: --> [10:NO CARRIER]

Nov 29 08:57:23.26: [ 3154]: <-- [9:AT+FRS=7\r]

Nov 29 08:57:23.44: [ 3154]: --> [2:OK]

Nov 29 08:57:23.44: [ 3154]: TRAINING succeeded

Nov 29 08:57:23.44: [ 3154]: <-- [9:AT+FTH=3\r]

Nov 29 08:57:24.41: [ 3154]: --> [7:CONNECT]

Nov 29 08:57:24.41: [ 3154]: <-- data [3]

Nov 29 08:57:24.41: [ 3154]: <-- data [2]

Nov 29 08:57:24.83: [ 3154]: --> [2:OK]

Nov 29 08:57:24.83: [ 3154]: <-- [10:AT+FRM=74\r]

Nov 29 08:57:26.61: [ 3154]: --> [7:CONNECT]

Nov 29 08:57:26.61: [ 3154]: RECV: begin page

Nov 29 08:57:50.12: [ 3154]: RECV: 40 total lines, 25 bad lines, 5 consecutive bad lines

Nov 29 08:57:50.13: [ 3154]: RECV: REJECT page quality, 37% good lines (95% required)

Nov 29 08:57:50.13: [ 3154]: RECV: end page

Nov 29 08:57:50.13: [ 3154]: --> [10:NO CARRIER]

Nov 29 08:57:50.13: [ 3154]: <-- [9:AT+FRH=3\r]

Nov 29 08:57:50.25: [ 3154]: --> [7:CONNECT]

Nov 29 08:57:51.28: [ 3154]: --> [2:OK]

Nov 29 08:57:51.28: [ 3154]: RECV recv MPS (more pages, same document)

Nov 29 08:57:51.28: [ 3154]: <-- [9:AT+FRS=7\r]

Nov 29 08:57:51.46: [ 3154]: --> [2:OK]

Nov 29 08:57:51.47: [ 3154]: RECV send RTN (retrain negative)

Nov 29 08:57:51.47: [ 3154]: <-- [9:AT+FTH=3\r]

Nov 29 08:57:52.43: [ 3154]: --> [7:CONNECT]

Nov 29 08:57:52.43: [ 3154]: RECV FAX (000000414): from 050701846, page 4 in 0:00:36, INF, 3.85 line/mm, 2-D MR, 7200 bit/s

Nov 29 08:57:52.43: [ 3154]: <-- data [3]

Nov 29 08:57:52.43: [ 3154]: <-- data [2]

Nov 29 08:57:52.85: [ 3154]: --> [2:OK]

Nov 29 08:57:52.85: [ 3154]: <-- [9:AT+FRH=3\r]

Nov 29 08:57:53.88: [ 3154]: --> [7:CONNECT]

Nov 29 08:57:55.74: [ 3154]: --> [5:ERROR]

Nov 29 08:57:55.74: [ 3154]: MODEM Command error

Nov 29 08:57:55.74: [ 3154]: FCS error

Nov 29 08:57:55.74: [ 3154]: <-- [10:AT+FRS=21\r]

Nov 29 08:57:58.87: [ 3154]: --> [2:OK]

Nov 29 08:57:58.87: [ 3154]: <-- [9:AT+FTH=3\r]

Nov 29 08:57:59.83: [ 3154]: --> [7:CONNECT]

Nov 29 08:57:59.83: [ 3154]: <-- data [3]

Nov 29 08:57:59.83: [ 3154]: <-- data [2]

Nov 29 08:58:00.26: [ 3154]: --> [2:OK]

Nov 29 08:58:00.26: [ 3154]: RECV send CRP (command repeat)

Nov 29 08:58:00.26: [ 3154]: <-- [9:AT+FRH=3\r]

Nov 29 08:58:01.33: [ 3154]: --> [7:CONNECT]

Nov 29 08:58:03.17: [ 3154]: --> [2:OK]

Nov 29 08:58:03.17: [ 3154]: RECV recv TSI (sender id)

Nov 29 08:58:03.17: [ 3154]: REMOTE TSI "050701846"

Nov 29 08:58:03.17: [ 3154]: <-- [9:AT+FRH=3\r]

Nov 29 08:58:03.29: [ 3154]: --> [7:CONNECT]

Nov 29 08:58:03.46: [ 3154]: --> [2:OK]

Nov 29 08:58:03.46: [ 3154]: RECV recv DCS (command signal)

Nov 29 08:58:03.46: [ 3154]: REMOTE wants 2400 bit/s

Nov 29 08:58:03.46: [ 3154]: REMOTE wants A4 page width (215 mm)

Nov 29 08:58:03.46: [ 3154]: REMOTE wants unlimited page length

Nov 29 08:58:03.46: [ 3154]: REMOTE wants 3.85 line/mm

Nov 29 08:58:03.46: [ 3154]: REMOTE wants 2-D MR

Nov 29 08:58:03.46: [ 3154]: RECV training at v.27ter fallback mode 2400 bit/s

Nov 29 08:58:03.46: [ 3154]: <-- [10:AT+FRM=24\r]

Nov 29 08:58:05.10: [ 3154]: --> [7:CONNECT]

Nov 29 08:58:05.95: [ 3154]: RECV: TCF 368 bytes, 0% non-zero, 364 zero-run

Nov 29 08:58:06.05: [ 3154]: --> [10:NO CARRIER]

Nov 29 08:58:06.05: [ 3154]: <-- [9:AT+FRS=7\r]

Nov 29 08:58:06.23: [ 3154]: --> [2:OK]

Nov 29 08:58:06.23: [ 3154]: TRAINING succeeded

Nov 29 08:58:06.23: [ 3154]: <-- [9:AT+FTH=3\r]

Nov 29 08:58:07.20: [ 3154]: --> [7:CONNECT]

Nov 29 08:58:07.20: [ 3154]: <-- data [3]

Nov 29 08:58:07.20: [ 3154]: <-- data [2]

Nov 29 08:58:07.63: [ 3154]: --> [2:OK]

Nov 29 08:58:07.63: [ 3154]: <-- [10:AT+FRM=24\r]

Nov 29 08:58:10.43: [ 3154]: --> [7:CONNECT]

Nov 29 08:58:10.43: [ 3154]: RECV: begin page

Nov 29 08:59:07.84: [ 3154]: RECV: 504 total lines, 346 bad lines, 19 consecutive bad lines

Nov 29 08:59:07.84: [ 3154]: RECV: REJECT page quality, 31% good lines (95% required)

Nov 29 08:59:07.84: [ 3154]: RECV: end page

Nov 29 08:59:07.84: [ 3154]: --> [10:NO CARRIER]

Nov 29 08:59:07.84: [ 3154]: <-- [9:AT+FRH=3\r]

Nov 29 08:59:07.98: [ 3154]: --> [7:CONNECT]

Nov 29 08:59:09.55: [ 3154]: --> [2:OK]

Nov 29 08:59:09.55: [ 3154]: RECV recv EOP (no more pages or documents)

Nov 29 08:59:09.55: [ 3154]: <-- [9:AT+FRS=7\r]

Nov 29 08:59:09.73: [ 3154]: --> [2:OK]

Nov 29 08:59:09.73: [ 3154]: RECV send RTN (retrain negative)

Nov 29 08:59:09.73: [ 3154]: <-- [9:AT+FTH=3\r]

Nov 29 08:59:10.70: [ 3154]: --> [7:CONNECT]

Nov 29 08:59:10.70: [ 3154]: RECV FAX (000000414): from 050701846, page 5 in 0:01:17, INF, 3.85 line/mm, 2-D MR, 2400 bit/s

Nov 29 08:59:10.70: [ 3154]: <-- data [3]

Nov 29 08:59:10.70: [ 3154]: <-- data [2]

Nov 29 08:59:11.12: [ 3154]: --> [2:OK]

Nov 29 08:59:11.12: [ 3154]: <-- [9:AT+FRH=3\r]

Nov 29 08:59:12.15: [ 3154]: --> [7:CONNECT]

Nov 29 08:59:13.45: [ 3154]: --> [2:OK]

Nov 29 08:59:13.45: [ 3154]: RECV recv DCN (disconnect)

Nov 29 08:59:13.45: [ 3154]: RECV FAX (000000414): recvq/fax000000414.tif from 050701846, route to <unspecified>, 5 pages in 0:03:21

Nov 29 08:59:13.45: [ 3154]: RECV FAX: RSPREC error/got DCN (sender abort)

Nov 29 08:59:13.45: [ 3154]: RECV FAX (000000414): session with 050701846 terminated abnormally: RSPREC error/got DCN (sender abort)

Nov 29 08:59:13.45: [ 3154]: RECV FAX: bin/faxrcvd "recvq/fax000000414.tif" "apsfax432" "000000414" "RSPREC error/got DCN (sender abort)"

Nov 29 08:59:13.45: [ 3154]: RECV FAX: end

Nov 29 08:59:13.45: [ 3154]: RSPREC error/got DCN (sender abort)

Nov 29 08:59:13.45: [ 3154]: SESSION END