[hylafax-users] remote sending fax machine receives error while Hylafax receives a clean fax

[Angelo Scagnetti <as@xxxxxxxxxxxxxxxxxxx>]

Hi all,

I'm having problems with an installation of Hylafax version 4.2.1:

sometimes, 4 or 5 times a day, when someone sends me a fax,
Hylafax receives a clean fax, but it is like the remote fax machine
receives an error and so it sends me that fax again. So every time
this happens I have 2 copies of the same document.

Here, I enclose 2 samples of log of 2 consecutive received faxes,
I don't see any error in the first one, but the remote fax machine
sends me the same fax again (??) :

first fax

May 14 11:27:58.36: [ 4391]: SESSION BEGIN 000020573 xxxxxxxxxxxx
May 14 11:27:58.36: [ 4391]: HylaFAX (tm) Version 4.2.1
May 14 11:27:58.36: [ 4391]: <-- [13:AT+FCLASS=1A\r]
May 14 11:28:03.64: [ 4391]: --> [7:CONNECT]
May 14 11:28:03.64: [ 4391]: ANSWER: FAX CONNECTION  DEVICE '/dev/ttyS0'
May 14 11:28:03.64: [ 4391]: RECV FAX: begin
May 14 11:28:03.64: [ 4391]: <-- data [32]
May 14 11:28:03.64: [ 4391]: <-- data [2]
May 14 11:28:03.71: [ 4391]: --> [7:CONNECT]
May 14 11:28:03.71: [ 4391]: <-- data [23]
May 14 11:28:03.71: [ 4391]: <-- data [2]
May 14 11:28:04.43: [ 4391]: --> [7:CONNECT]
May 14 11:28:04.43: [ 4391]: <-- data [10]
May 14 11:28:04.43: [ 4391]: <-- data [2]
May 14 11:28:06.61: [ 4391]: --> [2:OK]
May 14 11:28:06.61: [ 4391]: <-- [9:AT+FRH=3\r]
May 14 11:28:07.13: [ 4391]: --> [7:CONNECT]
May 14 11:28:08.59: [ 4391]: --> [2:OK]
May 14 11:28:08.59: [ 4391]: REMOTE TSI ""
May 14 11:28:08.59: [ 4391]: <-- [9:AT+FRH=3\r]
May 14 11:28:08.61: [ 4391]: --> [7:CONNECT]
May 14 11:28:08.91: [ 4391]: --> [2:OK]
May 14 11:28:08.91: [ 4391]: REMOTE wants 14400 bit/s
May 14 11:28:08.91: [ 4391]: REMOTE wants A4 page width (215 mm)
May 14 11:28:08.91: [ 4391]: REMOTE wants unlimited page length
May 14 11:28:08.91: [ 4391]: REMOTE wants 7.7 line/mm
May 14 11:28:08.91: [ 4391]: REMOTE wants 2-D MMR
May 14 11:28:08.91: [ 4391]: REMOTE wants T.30 Annex A, 256-byte ECM
May 14 11:28:08.91: [ 4391]: RECV training at v.17 14400 bit/s
May 14 11:28:08.91: [ 4391]: <-- [11:AT+FRM=145\r]
May 14 11:28:10.58: [ 4391]: --> [7:CONNECT]
May 14 11:28:12.13: [ 4391]: RECV: TCF 2813 bytes, 3% non-zero, 2699 zero-run
May 14 11:28:12.14: [ 4391]: --> [10:NO CARRIER]
May 14 11:28:12.14: [ 4391]: DELAY 75 ms
May 14 11:28:12.22: [ 4391]: TRAINING succeeded
May 14 11:28:12.22: [ 4391]: <-- [9:AT+FTH=3\r]
May 14 11:28:12.42: [ 4391]: --> [7:CONNECT]
May 14 11:28:12.42: [ 4391]: <-- data [3]
May 14 11:28:12.42: [ 4391]: <-- data [2]
May 14 11:28:13.60: [ 4391]: --> [2:OK]
May 14 11:28:13.60: [ 4391]: <-- [11:AT+FRM=146\r]
May 14 11:28:14.32: [ 4391]: --> [7:CONNECT]
May 14 11:28:14.67: [ 4391]: RECV received frame number 0
May 14 11:28:14.81: [ 4391]: RECV received frame number 1
May 14 11:28:14.95: [ 4391]: RECV received frame number 2
May 14 11:28:15.09: [ 4391]: RECV received frame number 3
May 14 11:28:15.23: [ 4391]: RECV received frame number 4
May 14 11:28:15.45: [ 4391]: RECV received frame number 5
May 14 11:28:15.61: [ 4391]: RECV received frame number 6
May 14 11:28:15.69: [ 4391]: RECV received frame number 7
May 14 11:28:15.85: [ 4391]: RECV received frame number 8
May 14 11:28:16.00: [ 4391]: RECV received frame number 9
May 14 11:28:16.15: [ 4391]: RECV received frame number 10
May 14 11:28:16.30: [ 4391]: RECV received frame number 11
May 14 11:28:16.45: [ 4391]: RECV received frame number 12
May 14 11:28:16.61: [ 4391]: RECV received frame number 13
May 14 11:28:16.75: [ 4391]: RECV received frame number 14
May 14 11:28:16.90: [ 4391]: RECV received frame number 15
May 14 11:28:17.06: [ 4391]: RECV received frame number 16
May 14 11:28:17.28: [ 4391]: RECV received frame number 17
May 14 11:28:17.42: [ 4391]: RECV received frame number 18
May 14 11:28:17.58: [ 4391]: RECV received frame number 19
May 14 11:28:17.66: [ 4391]: RECV received frame number 20
May 14 11:28:17.81: [ 4391]: RECV received frame number 21
May 14 11:28:18.04: [ 4391]: RECV received frame number 22
May 14 11:28:18.19: [ 4391]: RECV received frame number 23
May 14 11:28:18.34: [ 4391]: RECV received frame number 24
May 14 11:28:18.49: [ 4391]: RECV received frame number 25
May 14 11:28:18.63: [ 4391]: RECV received frame number 26
May 14 11:28:18.79: [ 4391]: RECV received frame number 27
May 14 11:28:18.95: [ 4391]: RECV received frame number 28
May 14 11:28:19.11: [ 4391]: RECV received frame number 29
May 14 11:28:19.11: [ 4391]: RECV received RCP frame
May 14 11:28:19.23: [ 4391]: --> [10:NO CARRIER]
May 14 11:28:19.23: [ 4391]: <-- [9:AT+FRH=3\r]
May 14 11:28:19.58: [ 4391]: --> [7:CONNECT]
May 14 11:28:20.48: [ 4391]: --> [2:OK]
May 14 11:28:20.48: [ 4391]: RECV recv PPS (partial page signal)
May 14 11:28:20.48: [ 4391]: RECV recv EOP (no more pages or documents)
May 14 11:28:20.48: [ 4391]: RECV received 30 frames of block 1 of page 1
May 14 11:28:20.49: [ 4391]: RECV: 7429 bytes of data, 2256 total lines
May 14 11:28:20.49: [ 4391]: <-- [9:AT+FRS=7\r]
May 14 11:28:20.60: [ 4391]: --> [2:OK]
May 14 11:28:20.60: [ 4391]: <-- [9:AT+FTH=3\r]
May 14 11:28:28.15: [ 4391]: --> [0:]
May 14 11:28:28.15: [ 4391]: RECV send MCF (message confirmation)
May 14 11:28:28.15: [ 4391]: <-- [9:AT+FRH=3\r]
May 14 11:28:28.15: [ 4391]: RECV FAX (000020573): from , page 1 in 0:16, INF, 7.7 line/mm, 2-D MMR, 14400 bit/s
May 14 11:28:28.15: [ 4391]: RECV FAX (000020573): recvq/fax000000632.tif from , route to <unspecified>, 1 pages in 0:25
May 14 11:28:28.29: [ 4391]: --> [8:AT+FRH=3]
May 14 11:28:28.29: [ 4391]: RECV FAX: bin/faxrcvd "recvq/fax000000632.tif" "ttyS0" "000020573" "" "" ""
May 14 11:28:28.29: [ 4391]: RECV FAX: end
May 14 11:28:28.29: [ 4391]: SESSION END


second fax

May 14 11:30:35.17: [ 4391]: SESSION BEGIN 000020574 xxxxxxxxxxxxx
May 14 11:30:35.17: [ 4391]: HylaFAX (tm) Version 4.2.1
May 14 11:30:35.17: [ 4391]: <-- [13:AT+FCLASS=1A\r]
May 14 11:30:40.45: [ 4391]: --> [7:CONNECT]
May 14 11:30:40.45: [ 4391]: ANSWER: FAX CONNECTION  DEVICE '/dev/ttyS0'
May 14 11:30:40.45: [ 4391]: RECV FAX: begin
May 14 11:30:40.45: [ 4391]: <-- data [32]
May 14 11:30:40.45: [ 4391]: <-- data [2]
May 14 11:30:40.52: [ 4391]: --> [7:CONNECT]
May 14 11:30:40.52: [ 4391]: <-- data [23]
May 14 11:30:40.52: [ 4391]: <-- data [2]
May 14 11:30:41.24: [ 4391]: --> [7:CONNECT]
May 14 11:30:41.24: [ 4391]: <-- data [10]
May 14 11:30:41.24: [ 4391]: <-- data [2]
May 14 11:30:43.42: [ 4391]: --> [2:OK]
May 14 11:30:43.42: [ 4391]: <-- [9:AT+FRH=3\r]
May 14 11:30:44.15: [ 4391]: --> [7:CONNECT]
May 14 11:30:45.44: [ 4391]: --> [2:OK]
May 14 11:30:45.44: [ 4391]: REMOTE TSI ""
May 14 11:30:45.44: [ 4391]: <-- [9:AT+FRH=3\r]
May 14 11:30:45.46: [ 4391]: --> [7:CONNECT]
May 14 11:30:45.74: [ 4391]: --> [2:OK]
May 14 11:30:45.74: [ 4391]: REMOTE wants 14400 bit/s
May 14 11:30:45.74: [ 4391]: REMOTE wants A4 page width (215 mm)
May 14 11:30:45.74: [ 4391]: REMOTE wants unlimited page length
May 14 11:30:45.74: [ 4391]: REMOTE wants 7.7 line/mm
May 14 11:30:45.74: [ 4391]: REMOTE wants 2-D MMR
May 14 11:30:45.74: [ 4391]: REMOTE wants T.30 Annex A, 256-byte ECM
May 14 11:30:45.74: [ 4391]: RECV training at v.17 14400 bit/s
May 14 11:30:45.74: [ 4391]: <-- [11:AT+FRM=145\r]
May 14 11:30:47.42: [ 4391]: --> [7:CONNECT]
May 14 11:30:48.98: [ 4391]: RECV: TCF 2813 bytes, 3% non-zero, 2699 zero-run
May 14 11:30:48.98: [ 4391]: --> [10:NO CARRIER]
May 14 11:30:48.98: [ 4391]: DELAY 75 ms
May 14 11:30:49.06: [ 4391]: TRAINING succeeded
May 14 11:30:49.06: [ 4391]: <-- [9:AT+FTH=3\r]
May 14 11:30:49.26: [ 4391]: --> [7:CONNECT]
May 14 11:30:49.26: [ 4391]: <-- data [3]
May 14 11:30:49.26: [ 4391]: <-- data [2]
May 14 11:30:50.44: [ 4391]: --> [2:OK]
May 14 11:30:50.44: [ 4391]: <-- [11:AT+FRM=146\r]
May 14 11:30:51.16: [ 4391]: --> [7:CONNECT]
May 14 11:30:51.52: [ 4391]: RECV received frame number 0
May 14 11:30:51.66: [ 4391]: RECV received frame number 1
May 14 11:30:51.81: [ 4391]: RECV received frame number 2
May 14 11:30:51.95: [ 4391]: RECV received frame number 3
May 14 11:30:52.10: [ 4391]: RECV received frame number 4
May 14 11:30:52.24: [ 4391]: RECV received frame number 5
May 14 11:30:52.45: [ 4391]: RECV received frame number 6
May 14 11:30:52.59: [ 4391]: RECV received frame number 7
May 14 11:30:52.73: [ 4391]: RECV received frame number 8
May 14 11:30:52.88: [ 4391]: RECV received frame number 9
May 14 11:30:53.03: [ 4391]: RECV received frame number 10
May 14 11:30:53.18: [ 4391]: RECV received frame number 11
May 14 11:30:53.32: [ 4391]: RECV received frame number 12
May 14 11:30:53.47: [ 4391]: RECV received frame number 13
May 14 11:30:53.62: [ 4391]: RECV received frame number 14
May 14 11:30:53.77: [ 4391]: RECV received frame number 15
May 14 11:30:53.92: [ 4391]: RECV received frame number 16
May 14 11:30:54.07: [ 4391]: RECV received frame number 17
May 14 11:30:54.21: [ 4391]: RECV received frame number 18
May 14 11:30:54.37: [ 4391]: RECV received frame number 19
May 14 11:30:54.53: [ 4391]: RECV received frame number 20
May 14 11:30:54.68: [ 4391]: RECV received frame number 21
May 14 11:30:54.84: [ 4391]: RECV received frame number 22
May 14 11:30:54.99: [ 4391]: RECV received frame number 23
May 14 11:30:55.14: [ 4391]: RECV received frame number 24
May 14 11:30:55.30: [ 4391]: RECV received frame number 25
May 14 11:30:55.45: [ 4391]: RECV received frame number 26
May 14 11:30:55.59: [ 4391]: RECV received frame number 27
May 14 11:30:55.81: [ 4391]: RECV received frame number 28
May 14 11:30:55.89: [ 4391]: RECV received frame number 29
May 14 11:30:55.89: [ 4391]: RECV received RCP frame
May 14 11:30:55.97: [ 4391]: --> [10:NO CARRIER]
May 14 11:30:55.97: [ 4391]: <-- [9:AT+FRH=3\r]
May 14 11:30:56.15: [ 4391]: --> [7:CONNECT]
May 14 11:30:57.32: [ 4391]: --> [2:OK]
May 14 11:30:57.32: [ 4391]: RECV recv PPS (partial page signal)
May 14 11:30:57.32: [ 4391]: RECV recv EOP (no more pages or documents)
May 14 11:30:57.32: [ 4391]: RECV received 30 frames of block 1 of page 1
May 14 11:30:57.33: [ 4391]: RECV: 7427 bytes of data, 2256 total lines
May 14 11:30:57.34: [ 4391]: <-- [9:AT+FRS=7\r]
May 14 11:30:57.44: [ 4391]: --> [2:OK]
May 14 11:30:57.44: [ 4391]: <-- [9:AT+FTH=3\r]
May 14 11:30:57.64: [ 4391]: --> [7:CONNECT]
May 14 11:30:57.64: [ 4391]: <-- data [3]
May 14 11:30:57.64: [ 4391]: <-- data [2]
May 14 11:30:58.82: [ 4391]: --> [2:OK]
May 14 11:30:58.82: [ 4391]: RECV send MCF (message confirmation)
May 14 11:30:58.82: [ 4391]: <-- [9:AT+FRH=3\r]
May 14 11:30:58.82: [ 4391]: RECV FAX (000020574): from , page 1 in 0:09, INF, 7.7 line/mm, 2-D MMR, 14400 bit/s
May 14 11:30:58.82: [ 4391]: RECV FAX (000020574): recvq/fax000000633.tif from , route to <unspecified>, 1 pages in 0:18
May 14 11:30:59.35: [ 4391]: --> [7:CONNECT]
May 14 11:31:00.20: [ 4391]: --> [2:OK]
May 14 11:31:00.20: [ 4391]: RECV FAX: bin/faxrcvd "recvq/fax000000633.tif" "ttyS0" "000020574" "" "" ""
May 14 11:31:00.20: [ 4391]: RECV FAX: end


May 14 11:31:00.20: [ 4391]: SESSION END


Many thanks in advance for your help.

Cheers,
 Angelo




____________________ HylaFAX(tm) Users Mailing List _______________________
 To subscribe/unsubscribe, click http://lists.hylafax.org/cgi-bin/lsg2.cgi
On UNIX: mail -s unsubscribe hylafax-users-request@xxxxxxxxxxx < /dev/null
 *To learn about commercial HylaFAX(tm) support, mail sales@xxxxxxxxx*