[hylafax-users] receiving: logic of RTN negative followed by MPS

[Giulio Orsero <giulioo@xxxxxxxxx>]

HylaFAX-4.2.5 receiving on class1.

I had a case in which:
- HylaFAX told the remote end: MCF (confirm received OK)
- the received tiff file was corrupt

I think the sender sent 2 pages and HylaFAX put them in 1 page.

Looking at the attached logs you can see:
Sender sends 1st page
Sender sends MPS
HylaFAX rejects 1st page and sends RTN
Sender sends what I think is its 2nd page
HylaFAX confirms with MCF (and receive 1 corrupt page)

So, since sender said MPS and then sent just 1 page after RTN we can assume
sender ignored RTN; however, in this particular case maybe HylaFAX could
have known something was wrong since it received an MPS but in the end it
MCFed with just 1 page on hand?

Since the tiff is corrupt, is there a method to clean it? I tried tiffcp w/o
success.
$ tiffcp -c none fax000011111.tif 1.tif
Fax4Decode: Warning, fax000035905.tif: Line length mismatch at scanline 151
(got 1729, expected 1728).
Fax4Decode: Warning, fax000035905.tif: Line length mismatch at scanline 151
(got 1882, expected 1728).
Fax4Decode: Warning, fax000035905.tif: Line length mismatch at scanline 151
(got 1740, expected 1728).


===== faxinfo
fax000011111.tif:
    Sender: 00 123456789
     Pages: 1
   Quality: Normal
      Page: 1728 by 332
  Received: 2006:04:21 13:41:23
TimeToRecv: 0:48
SignalRate: 7200 bit/s
DataFormat: 2-D MR
ErrCorrect: No
=====


===== session log
Apr 21 13:40:13.77: [10308]: SESSION BEGIN 000077168 39023456781
Apr 21 13:40:13.77: [10308]: HylaFAX (tm) Version 4.2.5
Apr 21 13:40:13.77: [10308]: <-- [4:ATA\r]
Apr 21 13:40:19.73: [10308]: --> [7:CONNECT]
Apr 21 13:40:19.73: [10308]: ANSWER: FAX CONNECTION  DEVICE '/dev/ttyS3'
Apr 21 13:40:19.73: [10308]: sched policy=1, priority=1
Apr 21 13:40:19.73: [10308]: RECV FAX: begin
Apr 21 13:40:19.73: [10308]: <-- data [32]
Apr 21 13:40:19.73: [10308]: <-- data [2]
Apr 21 13:40:21.75: [10308]: --> [7:CONNECT]
Apr 21 13:40:21.75: [10308]: <-- data [23]
Apr 21 13:40:21.75: [10308]: <-- data [2]
Apr 21 13:40:22.58: [10308]: --> [7:CONNECT]
Apr 21 13:40:22.58: [10308]: <-- data [13]
Apr 21 13:40:22.58: [10308]: <-- data [2]
Apr 21 13:40:23.21: [10308]: --> [2:OK]
Apr 21 13:40:23.21: [10308]: <-- [9:AT+FRH=3\r]
Apr 21 13:40:24.13: [10308]: --> [7:CONNECT]
Apr 21 13:40:25.62: [10308]: --> [2:OK]
Apr 21 13:40:25.62: [10308]: REMOTE TSI "00 123456789"
Apr 21 13:40:25.62: [10308]: <-- [9:AT+FRH=3\r]
Apr 21 13:40:25.64: [10308]: --> [7:CONNECT]
Apr 21 13:40:25.94: [10308]: --> [2:OK]
Apr 21 13:40:25.94: [10308]: REMOTE wants 9600 bit/s
Apr 21 13:40:25.94: [10308]: REMOTE wants A4 page width (215 mm)
Apr 21 13:40:25.94: [10308]: REMOTE wants unlimited page length
Apr 21 13:40:25.94: [10308]: REMOTE wants 3.85 line/mm
Apr 21 13:40:25.94: [10308]: REMOTE wants 2-D MR
Apr 21 13:40:25.94: [10308]: RECV training at v.29 9600 bit/s
Apr 21 13:40:25.94: [10308]: <-- [10:AT+FRM=96\r]
Apr 21 13:40:26.53: [10308]: --> [7:CONNECT]
Apr 21 13:40:28.11: [10308]: RECV: TCF 1873 bytes, 2% non-zero, 1828 zero-run
Apr 21 13:40:28.12: [10308]: --> [10:NO CARRIER]
Apr 21 13:40:28.12: [10308]: <-- [9:AT+FRS=9\r]
Apr 21 13:40:28.26: [10308]: --> [2:OK]
Apr 21 13:40:28.26: [10308]: TRAINING succeeded
Apr 21 13:40:28.26: [10308]: <-- [9:AT+FTH=3\r]
Apr 21 13:40:28.31: [10308]: --> [7:CONNECT]
Apr 21 13:40:28.31: [10308]: <-- data [3]
Apr 21 13:40:28.31: [10308]: <-- data [2]
Apr 21 13:40:29.61: [10308]: --> [2:OK]
Apr 21 13:40:29.61: [10308]: DELAY 40 ms
Apr 21 13:40:29.65: [10308]: <-- [10:AT+FRM=96\r]
Apr 21 13:40:30.33: [10308]: --> [7:CONNECT]
Apr 21 13:40:30.33: [10308]: RECV: begin page
Apr 21 13:41:14.30: [10308]: RECV: 741 total lines, 49 bad lines, 12 consecutive bad lines
Apr 21 13:41:14.30: [10308]: RECV: REJECT page quality, 93% good lines (95% required)
Apr 21 13:41:14.30: [10308]: RECV: end page
Apr 21 13:41:14.30: [10308]: --> [10:NO CARRIER]
Apr 21 13:41:14.30: [10308]: <-- [9:AT+FRH=3\r]
Apr 21 13:41:14.77: [10308]: --> [7:CONNECT]
Apr 21 13:41:15.63: [10308]: --> [2:OK]
Apr 21 13:41:15.63: [10308]: RECV recv MPS (more pages, same document)
Apr 21 13:41:15.63: [10308]: <-- [9:AT+FRS=9\r]
Apr 21 13:41:15.87: [10308]: --> [2:OK]
Apr 21 13:41:15.87: [10308]: <-- [9:AT+FTH=3\r]
Apr 21 13:41:16.01: [10308]: --> [7:CONNECT]
Apr 21 13:41:16.01: [10308]: <-- data [3]
Apr 21 13:41:16.01: [10308]: <-- data [2]
Apr 21 13:41:17.31: [10308]: --> [2:OK]
Apr 21 13:41:17.31: [10308]: RECV send RTN (retrain negative)
Apr 21 13:41:17.31: [10308]: <-- [9:AT+FRH=3\r]
Apr 21 13:41:18.21: [10308]: --> [7:CONNECT]
Apr 21 13:41:19.72: [10308]: --> [2:OK]
Apr 21 13:41:19.72: [10308]: REMOTE TSI "00 123456789"
Apr 21 13:41:19.72: [10308]: <-- [9:AT+FRH=3\r]
Apr 21 13:41:20.04: [10308]: --> [7:CONNECT]
Apr 21 13:41:20.04: [10308]: --> [2:OK]
Apr 21 13:41:20.04: [10308]: REMOTE wants 7200 bit/s
Apr 21 13:41:20.04: [10308]: REMOTE wants A4 page width (215 mm)
Apr 21 13:41:20.04: [10308]: REMOTE wants unlimited page length
Apr 21 13:41:20.04: [10308]: REMOTE wants 3.85 line/mm
Apr 21 13:41:20.04: [10308]: REMOTE wants 2-D MR
Apr 21 13:41:20.04: [10308]: RECV training at v.29 7200 bit/s
Apr 21 13:41:20.04: [10308]: <-- [10:AT+FRM=72\r]
Apr 21 13:41:20.65: [10308]: --> [7:CONNECT]
Apr 21 13:41:22.11: [10308]: RECV: TCF 1400 bytes, 2% non-zero, 1365 zero-run
Apr 21 13:41:22.11: [10308]: --> [10:NO CARRIER]
Apr 21 13:41:22.11: [10308]: <-- [9:AT+FRS=9\r]
Apr 21 13:41:22.34: [10308]: --> [2:OK]
Apr 21 13:41:22.34: [10308]: TRAINING succeeded
Apr 21 13:41:22.34: [10308]: <-- [9:AT+FTH=3\r]
Apr 21 13:41:22.48: [10308]: --> [7:CONNECT]
Apr 21 13:41:22.48: [10308]: <-- data [3]
Apr 21 13:41:22.48: [10308]: <-- data [2]
Apr 21 13:41:23.78: [10308]: --> [2:OK]
Apr 21 13:41:23.78: [10308]: DELAY 40 ms
Apr 21 13:41:23.82: [10308]: <-- [10:AT+FRM=72\r]
Apr 21 13:41:24.45: [10308]: --> [7:CONNECT]
Apr 21 13:41:24.45: [10308]: RECV: begin page
Apr 21 13:42:02.29: [10308]: RECV: 1281 total lines, 5 bad lines, 2 consecutive bad lines
Apr 21 13:42:02.29: [10308]: RECV: end page
Apr 21 13:42:02.29: [10308]: --> [10:NO CARRIER]
Apr 21 13:42:02.29: [10308]: <-- [9:AT+FRH=3\r]
Apr 21 13:42:02.76: [10308]: --> [7:CONNECT]
Apr 21 13:42:03.60: [10308]: --> [2:OK]
Apr 21 13:42:03.60: [10308]: RECV recv EOP (no more pages or documents)
Apr 21 13:42:03.60: [10308]: <-- [9:AT+FRS=9\r]
Apr 21 13:42:03.84: [10308]: --> [2:OK]
Apr 21 13:42:03.84: [10308]: <-- [9:AT+FTH=3\r]
Apr 21 13:42:03.98: [10308]: --> [7:CONNECT]
Apr 21 13:42:03.98: [10308]: <-- data [3]
Apr 21 13:42:03.98: [10308]: <-- data [2]
Apr 21 13:42:05.28: [10308]: --> [2:OK]
Apr 21 13:42:05.28: [10308]: RECV send MCF (message confirmation)
Apr 21 13:42:05.28: [10308]: <-- [9:AT+FRH=3\r]
Apr 21 13:42:05.28: [10308]: RECV FAX (000077168): recvq/fax000011111.tif from 00 123456789, route to <unspecified>, 1 pages in 1:46
Apr 21 13:42:05.28: [10308]: RECV FAX (000077168): from 00 123456789, page 1 in 0:48, INF, 3.85 line/mm, 2-D MR, 7200 bit/s
Apr 21 13:42:06.37: [10308]: --> [7:CONNECT]
Apr 21 13:42:07.37: [10308]: --> [2:OK]
Apr 21 13:42:07.37: [10308]: RECV recv DCN (disconnect)
Apr 21 13:42:07.37: [10308]: RECV FAX: bin/faxrcvd.golden "recvq/fax000011111.tif" "ttyS3" "000077168" ""
Apr 21 13:42:07.37: [10308]: sched policy=0, priority=0
Apr 21 13:42:07.37: [10308]: sched policy=1, priority=1
Apr 21 13:42:07.37: [10308]: RECV FAX: end
Apr 21 13:42:07.37: [10308]: SESSION END
-- 
giulioo@xxxxxxxxx

____________________ 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*