Re: [hylafax-users] V.21 signal reception timeout +FCERROR

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

We enabled “BadPageHandlingMethod: DCN” as you suggested. So why this communication is not aborted ?

hylafax received corrupted fax, we can find REJECT by page quality but hylafax does not abort communication.

 

Dec 09 09:48:24.20: [ 7678]: SESSION BEGIN 000000724 390297226427

Dec 09 09:48:24.20: [ 7678]: HylaFAX (tm) Version 6.0.5

Dec 09 09:48:24.20: [ 7678]: <-- [4:ATA\r]

Dec 09 09:48:32.07: [ 7678]: --> [7:CONNECT]

Dec 09 09:48:32.07: [ 7678]: ANSWER: FAX CONNECTION  DEVICE '/dev/apsfax427'

Dec 09 09:48:32.07: [ 7678]: RECV FAX: begin

Dec 09 09:48:32.08: [ 7678]: <-- data [32]

Dec 09 09:48:32.08: [ 7678]: <-- data [2]

Dec 09 09:48:32.10: [ 7678]: --> [7:CONNECT]

Dec 09 09:48:32.10: [ 7678]: <-- data [23]

Dec 09 09:48:32.10: [ 7678]: <-- data [2]

Dec 09 09:48:32.13: [ 7678]: --> [7:CONNECT]

Dec 09 09:48:32.13: [ 7678]: <-- data [13]

Dec 09 09:48:32.13: [ 7678]: <-- data [2]

Dec 09 09:48:34.33: [ 7678]: --> [2:OK]

Dec 09 09:48:34.33: [ 7678]: <-- [9:AT+FRH=3\r]

Dec 09 09:48:35.43: [ 7678]: --> [7:CONNECT]

Dec 09 09:48:37.25: [ 7678]: --> [2:OK]

Dec 09 09:48:37.25: [ 7678]: RECV recv TSI (sender id)

Dec 09 09:48:37.25: [ 7678]: REMOTE TSI "029989022"

Dec 09 09:48:37.25: [ 7678]: <-- [9:AT+FRH=3\r]

Dec 09 09:48:37.27: [ 7678]: --> [7:CONNECT]

Dec 09 09:48:37.60: [ 7678]: --> [2:OK]

Dec 09 09:48:37.60: [ 7678]: RECV recv DCS (command signal)

Dec 09 09:48:37.60: [ 7678]: REMOTE wants 14400 bit/s

Dec 09 09:48:37.60: [ 7678]: REMOTE wants A4 page width (215 mm)

Dec 09 09:48:37.60: [ 7678]: REMOTE wants unlimited page length

Dec 09 09:48:37.60: [ 7678]: REMOTE wants 3.85 line/mm

Dec 09 09:48:37.60: [ 7678]: REMOTE wants 2-D MR

Dec 09 09:48:37.60: [ 7678]: RECV training at v.17 14400 bit/s

Dec 09 09:48:37.60: [ 7678]: <-- [11:AT+FRM=145\r]

Dec 09 09:48:39.36: [ 7678]: --> [7:CONNECT]

Dec 09 09:48:40.92: [ 7678]: RECV: TCF 2800 bytes, 91% non-zero, 161 zero-run

Dec 09 09:48:40.92: [ 7678]: RECV: reject TCF (too many non-zero, max 10%)

Dec 09 09:48:40.92: [ 7678]: RECV: reject TCF (zero run too short, min 1800)

Dec 09 09:48:40.92: [ 7678]: --> [10:NO CARRIER]

Dec 09 09:48:40.92: [ 7678]: <-- [9:AT+FRS=7\r]

Dec 09 09:48:41.01: [ 7678]: --> [2:OK]

Dec 09 09:48:41.01: [ 7678]: <-- [9:AT+FTH=3\r]

Dec 09 09:48:41.87: [ 7678]: --> [7:CONNECT]

Dec 09 09:48:41.87: [ 7678]: <-- data [3]

Dec 09 09:48:41.87: [ 7678]: <-- data [2]

Dec 09 09:48:42.20: [ 7678]: --> [2:OK]

Dec 09 09:48:42.20: [ 7678]: TRAINING failed

Dec 09 09:48:42.20: [ 7678]: <-- [9:AT+FRH=3\r]

Dec 09 09:48:43.14: [ 7678]: --> [7:CONNECT]

Dec 09 09:48:45.00: [ 7678]: --> [5:ERROR]

Dec 09 09:48:45.00: [ 7678]: MODEM Command error

Dec 09 09:48:45.00: [ 7678]: FCS error

Dec 09 09:48:45.00: [ 7678]: <-- [10:AT+FRS=21\r]

Dec 09 09:48:48.74: [ 7678]: --> [2:OK]

Dec 09 09:48:48.74: [ 7678]: <-- [9:AT+FTH=3\r]

Dec 09 09:48:49.61: [ 7678]: --> [7:CONNECT]

Dec 09 09:48:49.61: [ 7678]: <-- data [3]

Dec 09 09:48:49.61: [ 7678]: <-- data [2]

Dec 09 09:48:49.93: [ 7678]: --> [2:OK]

Dec 09 09:48:49.93: [ 7678]: RECV send CRP (command repeat)

Dec 09 09:48:49.93: [ 7678]: <-- [9:AT+FRH=3\r]

Dec 09 09:48:50.88: [ 7678]: --> [7:CONNECT]

Dec 09 09:48:52.74: [ 7678]: --> [5:ERROR]

Dec 09 09:48:52.74: [ 7678]: MODEM Command error

Dec 09 09:48:52.74: [ 7678]: FCS error

Dec 09 09:48:52.74: [ 7678]: <-- [10:AT+FRS=21\r]

Dec 09 09:48:56.65: [ 7678]: --> [2:OK]

Dec 09 09:48:56.65: [ 7678]: <-- [9:AT+FTH=3\r]

Dec 09 09:48:57.51: [ 7678]: --> [7:CONNECT]

Dec 09 09:48:57.51: [ 7678]: <-- data [3]

Dec 09 09:48:57.51: [ 7678]: <-- data [2]

Dec 09 09:48:57.83: [ 7678]: --> [2:OK]

Dec 09 09:48:57.83: [ 7678]: RECV send CRP (command repeat)

Dec 09 09:48:57.83: [ 7678]: <-- [9:AT+FRH=3\r]

Dec 09 09:48:58.77: [ 7678]: --> [7:CONNECT]

Dec 09 09:49:00.62: [ 7678]: --> [2:OK]

Dec 09 09:49:00.62: [ 7678]: RECV recv TSI (sender id)

Dec 09 09:49:00.62: [ 7678]: REMOTE TSI "029989022"

Dec 09 09:49:00.62: [ 7678]: <-- [9:AT+FRH=3\r]

Dec 09 09:49:00.64: [ 7678]: --> [7:CONNECT]

Dec 09 09:49:00.97: [ 7678]: --> [2:OK]

Dec 09 09:49:00.97: [ 7678]: RECV recv DCS (command signal)

Dec 09 09:49:00.97: [ 7678]: REMOTE wants 12000 bit/s

Dec 09 09:49:00.97: [ 7678]: REMOTE wants A4 page width (215 mm)

Dec 09 09:49:00.97: [ 7678]: REMOTE wants unlimited page length

Dec 09 09:49:00.97: [ 7678]: REMOTE wants 3.85 line/mm

Dec 09 09:49:00.97: [ 7678]: REMOTE wants 2-D MR

Dec 09 09:49:00.97: [ 7678]: RECV training at v.17 12000 bit/s

Dec 09 09:49:00.97: [ 7678]: <-- [11:AT+FRM=121\r]

Dec 09 09:49:02.73: [ 7678]: --> [7:CONNECT]

Dec 09 09:49:04.14: [ 7678]: RECV: TCF 2112 bytes, 36% non-zero, 836 zero-run

Dec 09 09:49:04.14: [ 7678]: RECV: reject TCF (too many non-zero, max 10%)

Dec 09 09:49:04.14: [ 7678]: RECV: reject TCF (zero run too short, min 1500)

Dec 09 09:49:04.15: [ 7678]: --> [10:NO CARRIER]

Dec 09 09:49:04.15: [ 7678]: <-- [9:AT+FRS=7\r]

Dec 09 09:49:04.23: [ 7678]: --> [2:OK]

Dec 09 09:49:04.23: [ 7678]: <-- [9:AT+FTH=3\r]

Dec 09 09:49:05.10: [ 7678]: --> [7:CONNECT]

Dec 09 09:49:05.10: [ 7678]: <-- data [3]

Dec 09 09:49:05.10: [ 7678]: <-- data [2]

Dec 09 09:49:05.42: [ 7678]: --> [2:OK]

Dec 09 09:49:05.42: [ 7678]: TRAINING failed

Dec 09 09:49:05.42: [ 7678]: <-- [9:AT+FRH=3\r]

Dec 09 09:49:06.36: [ 7678]: --> [7:CONNECT]

Dec 09 09:49:08.21: [ 7678]: --> [2:OK]

Dec 09 09:49:08.21: [ 7678]: RECV recv TSI (sender id)

Dec 09 09:49:08.21: [ 7678]: REMOTE TSI "029989022"

Dec 09 09:49:08.21: [ 7678]: <-- [9:AT+FRH=3\r]

Dec 09 09:49:08.23: [ 7678]: --> [7:CONNECT]

Dec 09 09:49:08.56: [ 7678]: --> [2:OK]

Dec 09 09:49:08.56: [ 7678]: RECV recv DCS (command signal)

Dec 09 09:49:08.56: [ 7678]: REMOTE wants 9600 bit/s

Dec 09 09:49:08.56: [ 7678]: REMOTE wants A4 page width (215 mm)

Dec 09 09:49:08.56: [ 7678]: REMOTE wants unlimited page length

Dec 09 09:49:08.56: [ 7678]: REMOTE wants 3.85 line/mm

Dec 09 09:49:08.56: [ 7678]: REMOTE wants 2-D MR

Dec 09 09:49:08.56: [ 7678]: RECV training at v.17 9600 bit/s

Dec 09 09:49:08.56: [ 7678]: <-- [10:AT+FRM=97\r]

Dec 09 09:49:10.32: [ 7678]: --> [7:CONNECT]

Dec 09 09:49:11.90: [ 7678]: RECV: TCF 1888 bytes, 11% non-zero, 1259 zero-run

Dec 09 09:49:11.90: [ 7678]: RECV: reject TCF (too many non-zero, max 10%)

Dec 09 09:49:11.90: [ 7678]: --> [10:NO CARRIER]

Dec 09 09:49:11.90: [ 7678]: <-- [9:AT+FRS=7\r]

Dec 09 09:49:11.99: [ 7678]: --> [2:OK]

Dec 09 09:49:11.99: [ 7678]: <-- [9:AT+FTH=3\r]

Dec 09 09:49:12.85: [ 7678]: --> [7:CONNECT]

Dec 09 09:49:12.85: [ 7678]: <-- data [3]

Dec 09 09:49:12.85: [ 7678]: <-- data [2]

Dec 09 09:49:13.17: [ 7678]: --> [2:OK]

Dec 09 09:49:13.17: [ 7678]: TRAINING failed

Dec 09 09:49:13.17: [ 7678]: <-- [9:AT+FRH=3\r]

Dec 09 09:49:14.10: [ 7678]: --> [7:CONNECT]

Dec 09 09:49:15.93: [ 7678]: --> [2:OK]

Dec 09 09:49:15.93: [ 7678]: RECV recv TSI (sender id)

Dec 09 09:49:15.93: [ 7678]: REMOTE TSI "029989022"

Dec 09 09:49:15.93: [ 7678]: <-- [9:AT+FRH=3\r]

Dec 09 09:49:15.94: [ 7678]: --> [7:CONNECT]

Dec 09 09:49:16.27: [ 7678]: --> [2:OK]

Dec 09 09:49:16.27: [ 7678]: RECV recv DCS (command signal)

Dec 09 09:49:16.27: [ 7678]: REMOTE wants 7200 bit/s

Dec 09 09:49:16.27: [ 7678]: REMOTE wants A4 page width (215 mm)

Dec 09 09:49:16.27: [ 7678]: REMOTE wants unlimited page length

Dec 09 09:49:16.27: [ 7678]: REMOTE wants 3.85 line/mm

Dec 09 09:49:16.27: [ 7678]: REMOTE wants 2-D MR

Dec 09 09:49:16.27: [ 7678]: RECV training at v.17 7200 bit/s

Dec 09 09:49:16.27: [ 7678]: <-- [10:AT+FRM=73\r]

Dec 09 09:49:18.03: [ 7678]: --> [7:CONNECT]

Dec 09 09:49:19.60: [ 7678]: RECV: TCF 1408 bytes, 5% non-zero, 1109 zero-run

Dec 09 09:49:19.61: [ 7678]: --> [10:NO CARRIER]

Dec 09 09:49:19.61: [ 7678]: <-- [9:AT+FRS=7\r]

Dec 09 09:49:19.69: [ 7678]: --> [2:OK]

Dec 09 09:49:19.69: [ 7678]: TRAINING succeeded

Dec 09 09:49:19.69: [ 7678]: <-- [9:AT+FTH=3\r]

Dec 09 09:49:20.55: [ 7678]: --> [7:CONNECT]

Dec 09 09:49:20.55: [ 7678]: <-- data [3]

Dec 09 09:49:20.55: [ 7678]: <-- data [2]

Dec 09 09:49:20.87: [ 7678]: --> [2:OK]

Dec 09 09:49:20.87: [ 7678]: <-- [10:AT+FRM=74\r]

Dec 09 09:49:22.91: [ 7678]: --> [7:CONNECT]

Dec 09 09:49:22.91: [ 7678]: RECV: begin page

Dec 09 09:49:47.04: [ 7678]: RECV: 971 total lines, 48 bad lines, 5 consecutive bad lines

Dec 09 09:49:47.04: [ 7678]: RECV: end page

Dec 09 09:49:47.04: [ 7678]: --> [10:NO CARRIER]

Dec 09 09:49:47.04: [ 7678]: <-- [9:AT+FRH=3\r]

Dec 09 09:49:47.17: [ 7678]: --> [7:CONNECT]

Dec 09 09:49:48.20: [ 7678]: --> [5:ERROR]

Dec 09 09:49:48.20: [ 7678]: MODEM Command error

Dec 09 09:49:48.20: [ 7678]: FCS error

Dec 09 09:49:48.20: [ 7678]: <-- [10:AT+FRS=21\r]

Dec 09 09:49:48.52: [ 7678]: --> [2:OK]

Dec 09 09:49:48.52: [ 7678]: <-- [9:AT+FTH=3\r]

Dec 09 09:49:49.49: [ 7678]: --> [7:CONNECT]

Dec 09 09:49:49.49: [ 7678]: <-- data [3]

Dec 09 09:49:49.49: [ 7678]: <-- data [2]

Dec 09 09:49:49.91: [ 7678]: --> [2:OK]

Dec 09 09:49:49.91: [ 7678]: RECV send CRP (command repeat)

Dec 09 09:49:49.91: [ 7678]: <-- [9:AT+FRH=3\r]

Dec 09 09:49:50.83: [ 7678]: --> [7:CONNECT]

Dec 09 09:49:52.14: [ 7678]: --> [2:OK]

Dec 09 09:49:52.14: [ 7678]: RECV recv MPS (more pages, same document)

Dec 09 09:49:52.14: [ 7678]: <-- [9:AT+FRS=7\r]

Dec 09 09:49:52.32: [ 7678]: --> [2:OK]

Dec 09 09:49:52.32: [ 7678]: RECV send MCF (message confirmation)

Dec 09 09:49:52.32: [ 7678]: <-- [9:AT+FTH=3\r]

Dec 09 09:49:53.28: [ 7678]: --> [7:CONNECT]

Dec 09 09:49:53.28: [ 7678]: RECV FAX (000000724): from 029989022, page 1 in 0:01:15, INF, 3.85 line/mm, 2-D MR, 7200 bit/s

Dec 09 09:49:53.28: [ 7678]: <-- data [3]

Dec 09 09:49:53.28: [ 7678]: <-- data [2]

Dec 09 09:49:53.71: [ 7678]: --> [2:OK]

Dec 09 09:49:53.71: [ 7678]: <-- [10:AT+FRM=74\r]

Dec 09 09:49:55.78: [ 7678]: --> [7:CONNECT]

Dec 09 09:49:55.78: [ 7678]: RECV: begin page

Dec 09 09:50:19.70: [ 7678]: RECV: 883 total lines, 50 bad lines, 5 consecutive bad lines

Dec 09 09:50:19.70: [ 7678]: RECV: REJECT page quality, 94% good lines (95% required)

Dec 09 09:50:19.70: [ 7678]: RECV: end page

Dec 09 09:50:19.70: [ 7678]: --> [10:NO CARRIER]

Dec 09 09:50:19.70: [ 7678]: <-- [9:AT+FRH=3\r]

Dec 09 09:50:19.83: [ 7678]: --> [7:CONNECT]

Dec 09 09:50:20.86: [ 7678]: --> [2:OK]

Dec 09 09:50:20.86: [ 7678]: RECV recv MPS (more pages, same document)

Dec 09 09:50:20.86: [ 7678]: <-- [9:AT+FRS=7\r]

Dec 09 09:50:21.04: [ 7678]: --> [2:OK]

Dec 09 09:50:21.04: [ 7678]: <-- [9:AT+FTH=3\r]

Dec 09 09:50:22.01: [ 7678]: --> [7:CONNECT]

Dec 09 09:50:22.01: [ 7678]: <-- data [3]

Dec 09 09:50:22.01: [ 7678]: <-- data [2]

Dec 09 09:50:22.44: [ 7678]: --> [2:OK]

Dec 09 09:50:22.44: [ 7678]: RECV send DCN (disconnect)

Dec 09 09:50:22.44: [ 7678]: RECV FAX (000000724): recvq/fax000022902.tif from 029989022, route to <unspecified>, 1 pages in 0:01:50

Dec 09 09:50:22.44: [ 7678]: RECV FAX: PPM received with no image data.  To continue risks receipt confirmation.

Dec 09 09:50:22.44: [ 7678]: RECV FAX (000000724): session with 029989022 terminated abnormally: PPM received with no image data.  To continue risks receipt confirmation.

Dec 09 09:50:22.44: [ 7678]: RECV FAX: bin/faxrcvd "recvq/fax000022902.tif" "apsfax427" "000000724" "PPM received with no image data.  To continue risks receipt confirmation."

Dec 09 09:50:22.44: [ 7678]: RECV FAX: end

Dec 09 09:50:22.44: [ 7678]: PPM received with no image data.  To continue risks receipt confirmation.

Dec 09 09:50:22.44: [ 7678]: SESSION END