Topic: Hylafax error recive fax

[iulian.cristea]

Helo!

I have a big problem with hylafax contrib.I'm able to send fax but I can't receive
This is my log:

Nov 25 21:16:28.61: [ 7676]: SESSION BEGIN 000000002 4010213113693
Nov 25 21:16:28.61: [ 7676]: HylaFAX (tm) Version 4.4.4
Nov 25 21:16:28.61: [ 7676]: <-- [4:ATA\r]
Nov 25 21:16:37.20: [ 7676]: --> [7:CONNECT]
Nov 25 21:16:37.20: [ 7676]: ANSWER: FAX CONNECTION  DEVICE '/dev/ttyS0'
Nov 25 21:16:37.20: [ 7676]: RECV FAX: begin
Nov 25 21:16:37.22: [ 7676]: <-- data [32]
Nov 25 21:16:37.22: [ 7676]: <-- data [2]
Nov 25 21:16:37.25: [ 7676]: --> [7:CONNECT]
Nov 25 21:16:37.25: [ 7676]: <-- data [23]
Nov 25 21:16:37.25: [ 7676]: <-- data [2]
Nov 25 21:16:37.27: [ 7676]: --> [7:CONNECT]
Nov 25 21:16:37.27: [ 7676]: <-- data [13]
Nov 25 21:16:37.27: [ 7676]: <-- data [2]
Nov 25 21:16:39.48: [ 7676]: --> [2:OK]
Nov 25 21:16:39.48: [ 7676]: <-- [9:AT+FRH=3\r]
Nov 25 21:16:39.79: [ 7676]: --> [7:CONNECT]
Nov 25 21:16:41.52: [ 7676]: --> [2:OK]
Nov 25 21:16:41.52: [ 7676]: REMOTE TSI "0314254682"
Nov 25 21:16:41.52: [ 7676]: RECV recv TSI (sender id)
Nov 25 21:16:41.52: [ 7676]: <-- [9:AT+FRH=3\r]
Nov 25 21:16:41.53: [ 7676]: --> [7:CONNECT]
Nov 25 21:16:42.37: [ 7676]: --> [2:OK]
Nov 25 21:16:42.37: [ 7676]: REMOTE wants 14400 bit/s
Nov 25 21:16:42.37: [ 7676]: REMOTE wants A4 page width (215 mm)
Nov 25 21:16:42.37: [ 7676]: REMOTE wants unlimited page length
Nov 25 21:16:42.37: [ 7676]: REMOTE wants 3.85 line/mm
Nov 25 21:16:42.37: [ 7676]: REMOTE wants 2-D MMR
Nov 25 21:16:42.37: [ 7676]: REMOTE wants T.30 Annex A, 256-byte ECM
Nov 25 21:16:42.37: [ 7676]: RECV recv DCS (command signal)
Nov 25 21:16:42.37: [ 7676]: RECV training at v.17 14400 bit/s
Nov 25 21:16:42.37: [ 7676]: <-- [11:AT+FRM=145\r]
Nov 25 21:16:44.31: [ 7676]: --> [7:CONNECT]
Nov 25 21:16:44.31: [ 7676]: RECV: TCF 0 bytes, 0% non-zero, 0 zero-run
Nov 25 21:16:44.31: [ 7676]: RECV: reject TCF (zero run too short, min 1800)
Nov 25 21:16:44.31: [ 7676]: --> [10:NO CARRIER]
Nov 25 21:16:44.31: [ 7676]: <-- [9:AT+FRS=7\r]
Nov 25 21:16:45.98: [ 7676]: --> [2:OK]
Nov 25 21:16:45.98: [ 7676]: <-- [9:AT+FTH=3\r]
Nov 25 21:16:46.85: [ 7676]: --> [7:CONNECT]
Nov 25 21:16:46.85: [ 7676]: <-- data [3]
Nov 25 21:16:46.85: [ 7676]: <-- data [2]
Nov 25 21:16:47.17: [ 7676]: --> [2:OK]
Nov 25 21:16:47.17: [ 7676]: TRAINING failed
Nov 25 21:16:47.17: [ 7676]: <-- [9:AT+FRH=3\r]
Nov 25 21:16:47.48: [ 7676]: --> [7:CONNECT]
Nov 25 21:16:49.22: [ 7676]: --> [2:OK]
Nov 25 21:16:49.22: [ 7676]: REMOTE TSI "0314254682"
Nov 25 21:16:49.22: [ 7676]: RECV recv TSI (sender id)
Nov 25 21:16:49.22: [ 7676]: <-- [9:AT+FRH=3\r]
Nov 25 21:16:49.23: [ 7676]: --> [7:CONNECT]
Nov 25 21:16:50.08: [ 7676]: --> [2:OK]
Nov 25 21:16:50.08: [ 7676]: REMOTE wants 12000 bit/s
Nov 25 21:16:50.08: [ 7676]: REMOTE wants A4 page width (215 mm)
Nov 25 21:16:50.08: [ 7676]: REMOTE wants unlimited page length
Nov 25 21:16:50.08: [ 7676]: REMOTE wants 3.85 line/mm
Nov 25 21:16:50.08: [ 7676]: REMOTE wants 2-D MMR
Nov 25 21:16:50.08: [ 7676]: REMOTE wants T.30 Annex A, 256-byte ECM
Nov 25 21:16:50.08: [ 7676]: RECV recv DCS (command signal)
Nov 25 21:16:50.08: [ 7676]: RECV training at v.17 12000 bit/s
Nov 25 21:16:50.08: [ 7676]: <-- [11:AT+FRM=121\r]
Nov 25 21:16:52.02: [ 7676]: --> [7:CONNECT]
Nov 25 21:16:53.61: [ 7676]: RECV: TCF 2384 bytes, 64% non-zero, 801 zero-run
Nov 25 21:16:53.61: [ 7676]: RECV: reject TCF (too many non-zero, max 10%)
Nov 25 21:16:53.61: [ 7676]: RECV: reject TCF (zero run too short, min 1500)
Nov 25 21:16:53.61: [ 7676]: --> [10:NO CARRIER]
Nov 25 21:16:53.61: [ 7676]: <-- [9:AT+FRS=7\r]
Nov 25 21:16:53.70: [ 7676]: --> [2:OK]
Nov 25 21:16:53.70: [ 7676]: <-- [9:AT+FTH=3\r]
Nov 25 21:16:54.56: [ 7676]: --> [7:CONNECT]
Nov 25 21:16:54.56: [ 7676]: <-- data [3]
Nov 25 21:16:54.56: [ 7676]: <-- data [2]
Nov 25 21:16:54.88: [ 7676]: --> [2:OK]
Nov 25 21:16:54.88: [ 7676]: TRAINING failed
Nov 25 21:16:54.88: [ 7676]: <-- [9:AT+FRH=3\r]
Nov 25 21:16:55.19: [ 7676]: --> [7:CONNECT]
Nov 25 21:16:56.92: [ 7676]: --> [2:OK]
Nov 25 21:16:56.92: [ 7676]: REMOTE TSI "0314254682"
Nov 25 21:16:56.92: [ 7676]: RECV recv TSI (sender id)
Nov 25 21:16:56.92: [ 7676]: <-- [9:AT+FRH=3\r]
Nov 25 21:16:56.94: [ 7676]: --> [7:CONNECT]
Nov 25 21:16:57.78: [ 7676]: --> [2:OK]
Nov 25 21:16:57.78: [ 7676]: REMOTE wants 9600 bit/s
Nov 25 21:16:57.78: [ 7676]: REMOTE wants A4 page width (215 mm)
Nov 25 21:16:57.78: [ 7676]: REMOTE wants unlimited page length
Nov 25 21:16:57.78: [ 7676]: REMOTE wants 3.85 line/mm
Nov 25 21:16:57.78: [ 7676]: REMOTE wants 2-D MMR
Nov 25 21:16:57.78: [ 7676]: REMOTE wants T.30 Annex A, 256-byte ECM
Nov 25 21:16:57.78: [ 7676]: RECV recv DCS (command signal)
Nov 25 21:16:57.78: [ 7676]: RECV training at v.17 9600 bit/s
Nov 25 21:16:57.78: [ 7676]: <-- [10:AT+FRM=97\r]
Nov 25 21:16:59.72: [ 7676]: --> [7:CONNECT]
Nov 25 21:16:59.72: [ 7676]: RECV: TCF 0 bytes, 0% non-zero, 0 zero-run
Nov 25 21:16:59.72: [ 7676]: RECV: reject TCF (zero run too short, min 1200)
Nov 25 21:16:59.73: [ 7676]: --> [10:NO CARRIER]
Nov 25 21:16:59.73: [ 7676]: <-- [9:AT+FRS=7\r]
Nov 25 21:17:01.41: [ 7676]: --> [2:OK]
Nov 25 21:17:01.41: [ 7676]: <-- [9:AT+FTH=3\r]
Nov 25 21:17:02.28: [ 7676]: --> [7:CONNECT]
Nov 25 21:17:02.28: [ 7676]: <-- data [3]
Nov 25 21:17:02.28: [ 7676]: <-- data [2]
Nov 25 21:17:02.60: [ 7676]: --> [2:OK]
Nov 25 21:17:02.60: [ 7676]: TRAINING failed
Nov 25 21:17:02.60: [ 7676]: <-- [9:AT+FRH=3\r]
Nov 25 21:17:02.90: [ 7676]: --> [7:CONNECT]
Nov 25 21:17:04.64: [ 7676]: --> [2:OK]
Nov 25 21:17:04.64: [ 7676]: REMOTE TSI "0314254682"
Nov 25 21:17:04.64: [ 7676]: RECV recv TSI (sender id)
Nov 25 21:17:04.64: [ 7676]: <-- [9:AT+FRH=3\r]
Nov 25 21:17:04.66: [ 7676]: --> [7:CONNECT]
Nov 25 21:17:05.50: [ 7676]: --> [2:OK]
Nov 25 21:17:05.50: [ 7676]: REMOTE wants 7200 bit/s
Nov 25 21:17:05.50: [ 7676]: REMOTE wants A4 page width (215 mm)
Nov 25 21:17:05.50: [ 7676]: REMOTE wants unlimited page length
Nov 25 21:17:05.50: [ 7676]: REMOTE wants 3.85 line/mm
Nov 25 21:17:05.50: [ 7676]: REMOTE wants 2-D MMR
Nov 25 21:17:05.50: [ 7676]: REMOTE wants T.30 Annex A, 256-byte ECM
Nov 25 21:17:05.50: [ 7676]: RECV recv DCS (command signal)
Nov 25 21:17:05.50: [ 7676]: RECV training at v.17 7200 bit/s
Nov 25 21:17:05.50: [ 7676]: <-- [10:AT+FRM=73\r]
Nov 25 21:17:07.43: [ 7676]: --> [7:CONNECT]
Nov 25 21:17:09.00: [ 7676]: RECV: TCF 1408 bytes, 20% non-zero, 811 zero-run
Nov 25 21:17:09.00: [ 7676]: RECV: reject TCF (too many non-zero, max 10%)
Nov 25 21:17:09.00: [ 7676]: RECV: reject TCF (zero run too short, min 900)
Nov 25 21:17:09.00: [ 7676]: --> [10:NO CARRIER]
Nov 25 21:17:09.00: [ 7676]: <-- [9:AT+FRS=7\r]
Nov 25 21:17:09.08: [ 7676]: --> [2:OK]
Nov 25 21:17:09.08: [ 7676]: <-- [9:AT+FTH=3\r]
Nov 25 21:17:09.95: [ 7676]: --> [7:CONNECT]
Nov 25 21:17:09.95: [ 7676]: <-- data [3]
Nov 25 21:17:09.95: [ 7676]: <-- data [2]
Nov 25 21:17:10.27: [ 7676]: --> [2:OK]
Nov 25 21:17:10.27: [ 7676]: TRAINING failed
Nov 25 21:17:10.27: [ 7676]: <-- [9:AT+FRH=3\r]
Nov 25 21:17:10.59: [ 7676]: --> [7:CONNECT]
Nov 25 21:17:12.31: [ 7676]: --> [2:OK]
Nov 25 21:17:12.31: [ 7676]: REMOTE TSI "0314254682"
Nov 25 21:17:12.31: [ 7676]: RECV recv TSI (sender id)
Nov 25 21:17:12.31: [ 7676]: <-- [9:AT+FRH=3\r]
Nov 25 21:17:12.32: [ 7676]: --> [7:CONNECT]
Nov 25 21:17:13.16: [ 7676]: --> [2:OK]
Nov 25 21:17:13.16: [ 7676]: REMOTE wants 4800 bit/s
Nov 25 21:17:13.16: [ 7676]: REMOTE wants A4 page width (215 mm)
Nov 25 21:17:13.16: [ 7676]: REMOTE wants unlimited page length
Nov 25 21:17:13.16: [ 7676]: REMOTE wants 3.85 line/mm
Nov 25 21:17:13.16: [ 7676]: REMOTE wants 2-D MMR
Nov 25 21:17:13.16: [ 7676]: REMOTE wants T.30 Annex A, 256-byte ECM
Nov 25 21:17:13.16: [ 7676]: RECV recv DCS (command signal)
Nov 25 21:17:13.16: [ 7676]: RECV training at v.27ter 4800 bit/s
Nov 25 21:17:13.16: [ 7676]: <-- [10:AT+FRM=48\r]
Nov 25 21:17:14.41: [ 7676]: --> [7:CONNECT]
Nov 25 21:17:14.41: [ 7676]: RECV: TCF 0 bytes, 0% non-zero, 0 zero-run
Nov 25 21:17:14.41: [ 7676]: RECV: reject TCF (zero run too short, min 600)
Nov 25 21:17:14.42: [ 7676]: --> [10:NO CARRIER]
Nov 25 21:17:14.42: [ 7676]: <-- [9:AT+FRS=7\r]
Nov 25 21:17:16.07: [ 7676]: --> [2:OK]
Nov 25 21:17:16.07: [ 7676]: <-- [9:AT+FTH=3\r]
Nov 25 21:17:16.94: [ 7676]: --> [7:CONNECT]
Nov 25 21:17:16.94: [ 7676]: <-- data [3]
Nov 25 21:17:16.94: [ 7676]: <-- data [2]
Nov 25 21:17:17.26: [ 7676]: --> [2:OK]
Nov 25 21:17:17.26: [ 7676]: TRAINING failed
Nov 25 21:17:17.26: [ 7676]: <-- [9:AT+FRH=3\r]
Nov 25 21:17:17.59: [ 7676]: --> [7:CONNECT]
Nov 25 21:17:19.30: [ 7676]: --> [2:OK]
Nov 25 21:17:19.30: [ 7676]: REMOTE TSI "0314254682"
Nov 25 21:17:19.30: [ 7676]: RECV recv TSI (sender id)
Nov 25 21:17:19.30: [ 7676]: <-- [9:AT+FRH=3\r]
Nov 25 21:17:19.32: [ 7676]: --> [7:CONNECT]
Nov 25 21:17:20.16: [ 7676]: --> [2:OK]
Nov 25 21:17:20.16: [ 7676]: REMOTE wants 4800 bit/s
Nov 25 21:17:20.16: [ 7676]: REMOTE wants A4 page width (215 mm)
Nov 25 21:17:20.16: [ 7676]: REMOTE wants unlimited page length
Nov 25 21:17:20.16: [ 7676]: REMOTE wants 3.85 line/mm
Nov 25 21:17:20.16: [ 7676]: REMOTE wants 2-D MMR
Nov 25 21:17:20.16: [ 7676]: REMOTE wants T.30 Annex A, 256-byte ECM
Nov 25 21:17:20.16: [ 7676]: RECV recv DCS (command signal)
Nov 25 21:17:20.16: [ 7676]: RECV training at v.27ter 4800 bit/s
Nov 25 21:17:20.16: [ 7676]: <-- [10:AT+FRM=48\r]
Nov 25 21:17:21.41: [ 7676]: --> [7:CONNECT]
Nov 25 21:17:21.41: [ 7676]: RECV: TCF 0 bytes, 0% non-zero, 0 zero-run
Nov 25 21:17:21.41: [ 7676]: RECV: reject TCF (zero run too short, min 600)
Nov 25 21:17:21.42: [ 7676]: --> [10:NO CARRIER]
Nov 25 21:17:21.42: [ 7676]: <-- [9:AT+FRS=7\r]
Nov 25 21:17:23.07: [ 7676]: --> [2:OK]
Nov 25 21:17:23.07: [ 7676]: <-- [9:AT+FTH=3\r]
Nov 25 21:17:23.94: [ 7676]: --> [7:CONNECT]
Nov 25 21:17:23.94: [ 7676]: <-- data [3]
Nov 25 21:17:23.94: [ 7676]: <-- data [2]
Nov 25 21:17:24.26: [ 7676]: --> [2:OK]
Nov 25 21:17:24.26: [ 7676]: TRAINING failed
Nov 25 21:17:24.26: [ 7676]: <-- [9:AT+FRH=3\r]
Nov 25 21:17:24.57: [ 7676]: --> [7:CONNECT]
Nov 25 21:17:26.30: [ 7676]: --> [2:OK]
Nov 25 21:17:26.30: [ 7676]: REMOTE TSI "0314254682"
Nov 25 21:17:26.30: [ 7676]: RECV recv TSI (sender id)
Nov 25 21:17:26.30: [ 7676]: <-- [9:AT+FRH=3\r]
Nov 25 21:17:26.32: [ 7676]: --> [7:CONNECT]
Nov 25 21:17:27.16: [ 7676]: --> [2:OK]
Nov 25 21:17:27.16: [ 7676]: REMOTE wants 2400 bit/s
Nov 25 21:17:27.16: [ 7676]: REMOTE wants A4 page width (215 mm)
Nov 25 21:17:27.16: [ 7676]: REMOTE wants unlimited page length
Nov 25 21:17:27.16: [ 7676]: REMOTE wants 3.85 line/mm
Nov 25 21:17:27.16: [ 7676]: REMOTE wants 2-D MMR
Nov 25 21:17:27.16: [ 7676]: REMOTE wants T.30 Annex A, 256-byte ECM
Nov 25 21:17:27.16: [ 7676]: RECV recv DCS (command signal)
Nov 25 21:17:27.16: [ 7676]: RECV training at v.27ter fallback mode 2400 bit/s
Nov 25 21:17:27.16: [ 7676]: <-- [10:AT+FRM=24\r]
Nov 25 21:17:28.65: [ 7676]: --> [7:CONNECT]
Nov 25 21:17:28.65: [ 7676]: RECV: TCF 0 bytes, 0% non-zero, 0 zero-run
Nov 25 21:17:28.66: [ 7676]: RECV: reject TCF (zero run too short, min 300)
Nov 25 21:17:28.66: [ 7676]: --> [10:NO CARRIER]
Nov 25 21:17:28.66: [ 7676]: <-- [9:AT+FRS=7\r]
Nov 25 21:17:30.23: [ 7676]: --> [2:OK]
Nov 25 21:17:30.23: [ 7676]: <-- [9:AT+FTH=3\r]
Nov 25 21:17:31.09: [ 7676]: --> [7:CONNECT]
Nov 25 21:17:31.09: [ 7676]: <-- data [3]
Nov 25 21:17:31.09: [ 7676]: <-- data [2]
Nov 25 21:17:31.41: [ 7676]: --> [2:OK]
Nov 25 21:17:31.41: [ 7676]: TRAINING failed
Nov 25 21:17:31.41: [ 7676]: <-- [9:AT+FRH=3\r]
Nov 25 21:17:31.72: [ 7676]: --> [7:CONNECT]
Nov 25 21:17:33.46: [ 7676]: --> [2:OK]
Nov 25 21:17:33.46: [ 7676]: REMOTE TSI "0314254682"
Nov 25 21:17:33.46: [ 7676]: RECV recv TSI (sender id)
Nov 25 21:17:33.46: [ 7676]: <-- [9:AT+FRH=3\r]
Nov 25 21:17:33.47: [ 7676]: --> [7:CONNECT]
Nov 25 21:17:34.32: [ 7676]: --> [2:OK]
Nov 25 21:17:34.32: [ 7676]: REMOTE wants 2400 bit/s
Nov 25 21:17:34.32: [ 7676]: REMOTE wants A4 page width (215 mm)
Nov 25 21:17:34.32: [ 7676]: REMOTE wants unlimited page length
Nov 25 21:17:34.32: [ 7676]: REMOTE wants 3.85 line/mm
Nov 25 21:17:34.32: [ 7676]: REMOTE wants 2-D MMR
Nov 25 21:17:34.32: [ 7676]: REMOTE wants T.30 Annex A, 256-byte ECM
Nov 25 21:17:34.32: [ 7676]: RECV recv DCS (command signal)
Nov 25 21:17:34.32: [ 7676]: RECV training at v.27ter fallback mode 2400 bit/s
Nov 25 21:17:34.32: [ 7676]: <-- [10:AT+FRM=24\r]
Nov 25 21:17:35.82: [ 7676]: --> [7:CONNECT]
Nov 25 21:17:35.82: [ 7676]: RECV: TCF 0 bytes, 0% non-zero, 0 zero-run
Nov 25 21:17:35.82: [ 7676]: RECV: reject TCF (zero run too short, min 300)
Nov 25 21:17:35.82: [ 7676]: --> [10:NO CARRIER]
Nov 25 21:17:35.82: [ 7676]: <-- [9:AT+FRS=7\r]
Nov 25 21:17:37.39: [ 7676]: --> [2:OK]
Nov 25 21:17:37.39: [ 7676]: <-- [9:AT+FTH=3\r]
Nov 25 21:17:38.26: [ 7676]: --> [7:CONNECT]
Nov 25 21:17:38.26: [ 7676]: <-- data [3]
Nov 25 21:17:38.26: [ 7676]: <-- data [2]
Nov 25 21:17:38.58: [ 7676]: --> [2:OK]
Nov 25 21:17:38.58: [ 7676]: TRAINING failed
Nov 25 21:17:38.58: [ 7676]: <-- [9:AT+FRH=3\r]
Nov 25 21:17:38.89: [ 7676]: --> [7:CONNECT]
Nov 25 21:17:40.08: [ 7676]: --> [2:OK]
Nov 25 21:17:40.08: [ 7676]: RECV recv DCN (disconnect)
Nov 25 21:17:40.08: [ 7676]: RECV FAX: RSPREC error/got DCN (sender abort)
Nov 25 21:17:40.08: [ 7676]: RECV FAX: end
Nov 25 21:17:40.08: [ 7676]: RSPREC error/got DCN (sender abort)
Nov 25 21:17:40.08: [ 7676]: SESSION END

Please help , modem is Agere OCM V.92 and connection is good ( I send fax to the same machine )
Thank you

[Fumetto]

It appears that the sender hangs on to the v.27ter carrier for some time after the DCS signal ends...

Nov 25 21:17:34.32: [ 7676]: RECV training at v.27ter fallback mode 2400 bit/s
...
Nov 25 21:17:35.82: [ 7676]: --> [10:NO CARRIER]
...
Nov 25 21:17:40.08: [ 7676]: RSPREC error/got DCN (sender abort)

In your place I would try to change the modem, Agere have personally given a lot of problems in the past...