Re: [hylafax-users] Last Page of Multi-Page Fax is Garbage

[Frank Griffith <glassdude45@xxxxxxxxx>]

I'm not too familiar with how to read this log, but I took one the e-mails that contained the problem fax and copied the transcript session from it. I notice that something says it terminated abnormally. This was supposed to have been a 4 page fax. Two pages came in okay, the third one whacked (ended up with a bunch of black lines at the top of the page only, and the fourth one never made it. Here is the log:

 

Feb 26 16:00:07.66: [ 1770]: SESSION BEGIN 00000354 13055342384
Feb 26 16:00:07.66: [ 1770]: HylaFAX (tm) Version 4.1.8
Feb 26 16:00:07.66: [ 1770]: <-- [4:ATA\r]
Feb 26 16:00:13.03: [ 1770]: --> [7:CONNECT]
Feb 26 16:00:13.03: [ 1770]: ANSWER: FAX CONNECTION DEVICE '/dev/cuaa2'
Feb 26 16:00:13.03: [ 1770]: RECV FAX: begin
Feb 26 16:00:13.03: [ 1770]: <-- data [23]
Feb 26 16:00:13.03: [ 1770]: <-- data [2]
Feb 26 16:00:14.67: [ 1770]: --> [7:CONNECT]
Feb 26 16:00:14.67: [ 1770]: <-- data [10]
Feb 26 16:00:14.67: [ 1770]: <-- data [2]
Feb 26 16:00:15.49: [ 1770]: --> [2:OK]
Feb 26 16:00:15.49: [ 1770]: <-- [9:AT+FRH=3\r]
Feb 26 16:00:16.65: [ 1770]: --> [7:CONNECT]
Feb 26 16:00:17.32: [ 1770]: --> [2:OK] Feb 26 16:00:17.32: [ 1770]: REMOTE TSI "21239456126"
Feb 26 16:00:17.32: [ 1770]: <-- [9:AT+FRH=3\r]
Feb 26 16:00:17.37: [ 1770]: --> [7:CONNECT]
Feb 26 16:00:17.67: [ 1770]: --> [2:OK]
Feb 26 16:00:17.67: [ 1770]: REMOTE wants 14400 bit/s
Feb 26 16:00:17.67: [ 1770]: REMOTE wants page width 1728 pixels in 215 mm
Feb 26 16:00:17.67: [ 1770]: REMOTE wants unlimited page length
Feb 26 16:00:17.67: [ 1770]: REMOTE wants 3.85 line/mm
Feb 26 16:00:17.67: [ 1770]: REMOTE wants 2-D MR
Feb 26 16:00:17.67: [ 1770]: RECV training at v.17 14400 bit/s
Feb 26 16:00:17.67: [ 1770]: <-- [11:AT+FRM=145\r]
Feb 26 16:00:19.45: [ 1770]: --> [7:CONNECT]
Feb 26 16:00:21.01: [ 1770]: RECV: TCF 2827 bytes, 3% non-zero, 2702 zero-run
Feb 26 16:00:21.04: [ 1770]: --> [10:NO CARRIER]
Feb 26 16:00:21.04: [ 1770]: DELAY 75 ms
Feb 26 16:00:21.13: [ 1770]: TRAINING succeeded
Feb 26 16:00:21.13: [ 1770]: <-- [9:AT+FTH=3\r]
Feb 26 16:00:21.25: [ 1770]: --> [7:CONNECT] Feb 26 16:00:21.25: [ 1770]: <-- data [3]
Feb 26 16:00:21.25: [ 1770]: <-- data [2] Feb 26 16:00:22.47: [ 1770]: --> [2:OK]
Feb 26 16:00:22.47: [ 1770]: <-- [11:AT+FRM=146\r]
Feb 26 16:00:23.40: [ 1770]: --> [7:CONNECT]
Feb 26 16:00:23.40: [ 1770]: RECV: begin page
Feb 26 16:00:36.05: [ 1770]: RECV: 1053 total lines, 0 bad lines, 0 consecutive bad lines
Feb 26 16:00:36.05: [ 1770]: RECV: end page
Feb 26 16:00:36.17: [ 1770]: --> [10:NO CARRIER]
Feb 26 16:00:36.17: [ 1770]: <-- [9:AT+FRH=3\r]
Feb 26 16:00:40.08: [ 1770]: --> [7:CONNECT]
Feb 26 16:00:40.21: [ 1770]: --> [2:OK]
Feb 26 16:00:40.21: [ 1770]: RECV recv MPS (more pages, same document)
Feb 26 16:00:40.21: [ 1770]: <-- [9:AT+FRS=7\r]
Feb 26 16:00:40.39: [ 1770]: --> [2:OK]
Feb 26 16:00:40.39: [ 1770]: <-- [9:AT+FTH=3\r]
Feb 26 16:00:40.51: [ 1770]: --> [7:CONNECT]
Feb 26 16:00:40.51: [ 1770]: RECV send MCF (message confirmation)
Feb 26 16:00:40.51: [ 1770]: RECV FAX (00000354): from 2123983516, page 1 in 0:19, INF, 3.85 line/mm, 2-D MR, 14400 bit/s
Feb 26 16:00:40.51: [ 1770]: <-- data [3]
Feb 26 16:00:40.51: [ 1770]: <-- data [2]
Feb 26 16:00:41.72: [ 1770]: --> [2:OK]
Feb 26 16:00:41.72: [ 1770]: <-- [11:AT+FRM=146\r]
Feb 26 16:00:42.59: [ 1770]: --> [7:CONNECT]
Feb 26 16:00:42.59: [ 1770]: RECV: begin page
Feb 26 16:00:58.57: [ 1770]: RECV: 59 total lines, 40 bad lines, 13 consecutive bad lines Feb 26 16:00:58.57: [ 1770]: RECV: REJECT page quality, 32% good lines (95% required)
Feb 26 16:00:58.57: [ 1770]: RECV: end page
Feb 26 16:00:58.57: [ 1770]: --> [10:NO CARRIER]
Feb 26 16:00:58.57: [ 1770]: <-- [9:AT+FRH=3\r]
Feb 26 16:01:02.48: [ 1770]: --> [7:CONNECT]
Feb 26 16:01:02.61: [ 1770]: --> [2:OK]
Feb 26 16:01:02.61: [ 1770]: RECV recv MPS (more pages, same document)
Feb 26 16:01:02.61: [ 1770]: <-- [9:AT+FRS=7\r]
Feb 26 16:01:02.79: [ 1770]: --> [2:OK]
Feb 26 16:01:02.79: [ 1770]: <-- [9:AT+FTH=3\r]
Feb 26 16:01:02.91: [ 1770]: --> [7:CONNECT]
Feb 26 16:01:02.91: [ 1770]: <-- data [3]
Feb 26 16:01:02.91: [ 1770]: <-- data [2]
Feb 26 16:01:04.12: [ 1770]: --> [2:OK]
Feb 26 16:01:04.12: [ 1770]: RECV send RTN (retrain negative)
Feb 26 16:01:04.12: [ 1770]: <-- [9:AT+FRH=3\r]
Feb 26 16:01:05.29: [ 1770]: --> [7:CONNECT]
Feb 26 16:01:05.95: [ 1770]: --> [2:OK]
Feb 26 16:01:05.95: [ 1770]: REMOTE TSI "2123934516"
Feb 26 16:01:05.95: [ 1770]: <-- [9:AT+FRH=3\r]
Feb 26 16:01:06.00: [ 1770]: --> [7:CONNECT]
Feb 26 16:01:06.31: [ 1770]: --> [2:OK]
Feb 26 16:01:06.31: [ 1770]: REMOTE wants 14400 bit/s
Feb 26 16:01:06.31: [ 1770]: REMOTE wants page width 1728 pixels in 215 mm
Feb 26 16:01:06.31: [ 1770]: REMOTE wants unlimited page length
Feb 26 16:01:06.31: [ 1770]: REMOTE wants 3.85 line/mm
Feb 26 16:01:06.31: [ 1770]: REMOTE wants 2-D MR
Feb 26 16:01:06.31: [ 1770]: RECV training at v.17 14400 bit/s
Feb 26 16:01:06.31: [ 1770]: <-- [11:AT+FRM=145\r]
Feb 26 16:01:08.09: [ 1770]: --> [7:CONNECT]
Feb 26 16:01:09.65: [ 1770]: RECV: TCF 2826 bytes, 3% non-zero, 2702 zero-run
Feb 26 16:01:09.68: [ 1770]: --> [10:NO CARRIER]
Feb 26 16:01:09.68: [ 1770]: DELAY 75 ms
Feb 26 16:01:09.77: [ 1770]: TRAINING succeeded
Feb 26 16:01:09.77: [ 1770]: <-- [9:AT+FTH=3\r]
Feb 26 16:01:09.89: [ 1770]: --> [7:CONNECT]
Feb 26 16:01:09.89: [ 1770]: <-- data [3]
Feb 26 16:01:09.89: [ 1770]: <-- data [2]
Feb 26 16:01:11.11: [ 1770]: --> [2:OK]
Feb 26 16:01:11.11: [ 1770]: <-- [11:AT+FRM=146\r]
Feb 26 16:01:12.04: [ 1770]: --> [7:CONNECT]
Feb 26 16:01:12.04: [ 1770]: RECV: begin page
Feb 26 16:01:24.51: [ 1770]: RECV: 1055 total lines, 0 bad lines, 0 consecutive bad lines Feb 26 16:01:24.51: [ 1770]: RECV: end page
Feb 26 16:01:24.51: [ 1770]: --> [10:NO CARRIER]
Feb 26 16:01:24.51: [ 1770]: <-- [9:AT+FRH=3\r]
Feb 26 16:01:28.43: [ 1770]: --> [7:CONNECT]
Feb 26 16:01:28.57: [ 1770]: --> [2:OK]
Feb 26 16:01:28.57: [ 1770]: RECV recv MPS (more pages, same document)
Feb 26 16:01:28.57: [ 1770]: <-- [9:AT+FRS=7\r]
Feb 26 16:01:28.75: [ 1770]: --> [2:OK]
Feb 26 16:01:28.75: [ 1770]: <-- [9:AT+FTH=3\r]
Feb 26 16:01:28.87: [ 1770]: --> [7:CONNECT]
Feb 26 16:01:28.87: [ 1770]: RECV send MCF (message confirmation)
Feb 26 16:01:28.87: [ 1770]: RECV FAX (00000354): from 2126782116, page 2 in 0:48, INF, 3.85 line/mm, 2-D MR, 14400 bit/s
Feb 26 16:01:28.87: [ 1770]: <-- data [3]
Feb 26 16:01:28.87: [ 1770]: <-- data [2]
Feb 26 16:01:30.08: [ 1770]: --> [2:OK]
Feb 26 16:01:30.08: [ 1770]: <-- [11:AT+FRM=146\r]
Feb 26 16:01:30.99: [ 1770]: --> [7:CONNECT] Feb 26 16:01:30.99: [ 1770]: RECV: begin page
Feb 26 16:01:45.96: [ 1770]: RECV: 60 total lines, 40 bad lines, 9 consecutive bad lines
Feb 26 16:01:45.96: [ 1770]: RECV: REJECT page quality, 33% good lines (95% required)
Feb 26 16:01:45.96: [ 1770]: RECV: end page
Feb 26 16:01:45.96: [ 1770]: --> [10:NO CARRIER]
Feb 26 16:01:45.96: [ 1770]: <-- [9:AT+FRH=3\r]
Feb 26 16:01:49.86: [ 1770]: --> [7:CONNECT]
Feb 26 16:01:50.00: [ 1770]: --> [2:OK]
Feb 26 16:01:50.00: [ 1770]: RECV recv EOP (no more pages or documents)
Feb 26 16:01:50.00: [ 1770]: <-- [9:AT+FRS=7\r]
Feb 26 16:01:50.18: [ 1770]: --> [2:OK]
Feb 26 16:01:50.18: [ 1770]: <-- [9:AT+FTH=3\r]
Feb 26 16:01:50.30: [ 1770]: --> [7:CONNECT]
Feb 26 16:01:50.30: [ 1770]: <-- data [3]
Feb 26 16:01:50.30: [ 1770]: <-- data [2]
Feb 26 16:01:51.51: [ 1770]: --> [2:OK]
Feb 26 16:01:51.51: [ 1770]: RECV send RTN (retrain negative)
Feb 26 16:01:51.51: [ 1770]: <-- [9:AT+FRH=3\r]
Feb 26 16:01:52.65: [ 1770]: --> [7:CONNECT]
Feb 26 16:01:52.79: [ 1770]: --> [2:OK]
Feb 26 16:01:52.79: [ 1770]: RECV recv DCN
Feb 26 16:01:52.79: [ 1770]: RECV FAX (00000354): recvq/fax00192.tif from 2126783216, route to <unspecified>, 2 pages in 1:39
Feb 26 16:01:52.79: [ 1770]: RECV FAX: COMREC received DCN
Feb 26 16:01:52.79: [ 1770]: <-- [9:AT+FTH=3\r]
Feb 26 16:01:52.90: [ 1770]: --> [7:CONNECT]
Feb 26 16:01:52.90: [ 1770]: <-- data [3]
Feb 26 16:01:52.90: [ 1770]: <-- data [2]
Feb 26 16:01:54.12: [ 1770]: --> [2:OK]
Feb 26 16:01:54.12: [ 1770]: RECV FAX (00000354): session with 2123678436 terminated abnormally: COMREC received DCN
Feb 26 16:01:54.12: [ 1770]: RECV FAX: bin/faxrcvd "recvq/fax00192.tif" "cuaa2" "00000354" "COMREC received DCN" "" ""
Feb 26 16:02:03.39: [ 1770]: RECV FAX: end
Feb 26 16:02:03.39: [ 1770]: SESSION END

 

Lee Howard <faxguy@xxxxxxxxxxxxxxxx> wrote:

> On 2004.03.01 06:43 Frank Griffith wrote:
> > We run HylaFAX-4.1.8 on FreeBSD-4.9 sever. The server runs great for
> > both sending and receiving, but I have begun to notice that some
> > multi-page faxes are arriving with the last page as just a bunch of
> > garbage at the top of the page. Nothing is legible.
> > 
> > I noticed at first that it was one particular fax machine from our
> > branch office in NYC that was doing this. I tested from a fax machine
> > in the same office and from my HylaFAX server at home. No problem.
> > This morning I noticed that another one our branch offices in NJ has
> > started the same problem. A one page fax works fine but anything
> > longer than one page ends up with the last page whacked. These same
> > branch offices can fax to our normal fax machine and everything works
> > fine. It's appears
>  to be only the HylaFax server and only on long
> > distance calls.
> > 
> > Any ideas as to what might be happening? If we can't resolve this,
> > management will never buy into making HylaFax a company wide standard.
>
> Logs would be quite helpful.
>
> Lee.
>
> ____________________ 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@xxxxxxxxxxxx*

---

Do you Yahoo!?
Get better spam protection with Yahoo! Mail