Re: [hylafax-users] Problem Faxes - Where to start

["Fred Newtz" <fnewtz@xxxxxxxxxx>]

Here are a couple of log files.  One is from a fax that failed and did not 
generate a TIFF file.  The second will be from one that generated a blank 
TIFF file.

---- BEGIN FIRST LOG FILE ----


Jun 01 09:31:58.69: [ 8550]: SESSION BEGIN 000000059 17136220354
Jun 01 09:31:58.69: [ 8550]: HylaFAX (tm) Version 4.2.5
Jun 01 09:31:58.69: [ 8550]: <-- [4:ATA\r]
Jun 01 09:32:04.54: [ 8550]: --> [7:CONNECT]
Jun 01 09:32:04.54: [ 8550]: ANSWER: FAX CONNECTION DEVICE '/dev/ttyS0'
Jun 01 09:32:04.54: [ 8550]: RECV FAX: begin
Jun 01 09:32:04.54: [ 8550]: <-- data [32]
Jun 01 09:32:04.54: [ 8550]: <-- data [2]
Jun 01 09:32:06.57: [ 8550]: --> [7:CONNECT]
Jun 01 09:32:06.57: [ 8550]: <-- data [23]
Jun 01 09:32:06.57: [ 8550]: <-- data [2]
Jun 01 09:32:07.39: [ 8550]: --> [7:CONNECT]
Jun 01 09:32:07.39: [ 8550]: <-- data [13]
Jun 01 09:32:07.39: [ 8550]: <-- data [2]
Jun 01 09:32:08.02: [ 8550]: --> [2:OK]
Jun 01 09:32:08.02: [ 8550]: <-- [9:AT+FRH=3\r]
Jun 01 09:32:15.02: [ 8550]: --> [0:]
Jun 01 09:32:15.02: [ 8550]: MODEM <Empty line>
Jun 01 09:32:15.02: [ 8550]: <-- data [1]
Jun 01 09:32:15.07: [ 8550]: --> [2:OK]
Jun 01 09:32:15.07: [ 8550]: DELAY 1500 ms
Jun 01 09:32:16.57: [ 8550]: <-- [9:AT+FTH=3\r]
Jun 01 09:32:16.67: [ 8550]: --> [7:CONNECT]
Jun 01 09:32:16.67: [ 8550]: <-- data [32]
Jun 01 09:32:16.67: [ 8550]: <-- data [2]
Jun 01 09:32:18.69: [ 8550]: --> [7:CONNECT]
Jun 01 09:32:18.69: [ 8550]: <-- data [23]
Jun 01 09:32:18.69: [ 8550]: <-- data [2]
Jun 01 09:32:19.52: [ 8550]: --> [7:CONNECT]
Jun 01 09:32:19.52: [ 8550]: <-- data [13]
Jun 01 09:32:19.52: [ 8550]: <-- data [2]
Jun 01 09:32:20.15: [ 8550]: --> [2:OK]
Jun 01 09:32:20.15: [ 8550]: <-- [9:AT+FRH=3\r]
Jun 01 09:32:27.15: [ 8550]: --> [0:]
Jun 01 09:32:27.15: [ 8550]: MODEM <Empty line>
Jun 01 09:32:27.15: [ 8550]: <-- data [1]
Jun 01 09:32:27.20: [ 8550]: --> [2:OK]
Jun 01 09:32:27.20: [ 8550]: DELAY 1500 ms
Jun 01 09:32:28.70: [ 8550]: <-- [9:AT+FTH=3\r]
Jun 01 09:32:28.80: [ 8550]: --> [7:CONNECT]
Jun 01 09:32:28.80: [ 8550]: <-- data [32]
Jun 01 09:32:28.80: [ 8550]: <-- data [2]
Jun 01 09:32:30.83: [ 8550]: --> [7:CONNECT]
Jun 01 09:32:30.83: [ 8550]: <-- data [23]
Jun 01 09:32:30.83: [ 8550]: <-- data [2]
Jun 01 09:32:31.65: [ 8550]: --> [7:CONNECT]
Jun 01 09:32:31.65: [ 8550]: <-- data [13]
Jun 01 09:32:31.65: [ 8550]: <-- data [2]
Jun 01 09:32:32.28: [ 8550]: --> [2:OK]
Jun 01 09:32:32.28: [ 8550]: <-- [9:AT+FRH=3\r]
Jun 01 09:32:39.28: [ 8550]: --> [0:]
Jun 01 09:32:39.28: [ 8550]: MODEM <Empty line>
Jun 01 09:32:39.28: [ 8550]: <-- data [1]
Jun 01 09:32:39.33: [ 8550]: --> [2:OK]
Jun 01 09:32:39.33: [ 8550]: DELAY 1500 ms
Jun 01 09:32:40.83: [ 8550]: <-- [9:AT+FTH=3\r]
Jun 01 09:32:40.92: [ 8550]: --> [7:CONNECT]
Jun 01 09:32:40.92: [ 8550]: <-- data [32]
Jun 01 09:32:40.92: [ 8550]: <-- data [2]
Jun 01 09:32:42.95: [ 8550]: --> [7:CONNECT]
Jun 01 09:32:42.95: [ 8550]: <-- data [23]
Jun 01 09:32:42.95: [ 8550]: <-- data [2]
Jun 01 09:32:43.78: [ 8550]: --> [7:CONNECT]
Jun 01 09:32:43.78: [ 8550]: <-- data [13]
Jun 01 09:32:43.78: [ 8550]: <-- data [2]
Jun 01 09:32:44.40: [ 8550]: --> [2:OK]
Jun 01 09:32:44.40: [ 8550]: <-- [9:AT+FRH=3\r]
Jun 01 09:32:51.40: [ 8550]: --> [0:]
Jun 01 09:32:51.40: [ 8550]: MODEM <Empty line>
Jun 01 09:32:51.40: [ 8550]: <-- data [1]
Jun 01 09:32:51.45: [ 8550]: --> [2:OK]
Jun 01 09:32:51.45: [ 8550]: RECV FAX: No answer (T.30 T1 timeout)
Jun 01 09:32:51.45: [ 8550]: RECV FAX: end
Jun 01 09:32:51.45: [ 8550]: SESSION END



---- END FIRST LOG FILE ----

---- BEGIN SECOND LOG FILE ----


May 16 09:18:24.69: [ 6546]: SESSION BEGIN 000000016 17136220354
May 16 09:18:24.69: [ 6546]: HylaFAX (tm) Version 4.2.5
May 16 09:18:24.69: [ 6546]: <-- [4:ATA\r]
May 16 09:18:30.57: [ 6546]: --> [7:CONNECT]
May 16 09:18:30.57: [ 6546]: ANSWER: FAX CONNECTION DEVICE '/dev/ttyS0'
May 16 09:18:30.57: [ 6546]: RECV FAX: begin
May 16 09:18:30.57: [ 6546]: <-- data [32]
May 16 09:18:30.57: [ 6546]: <-- data [2]
May 16 09:18:32.60: [ 6546]: --> [7:CONNECT]
May 16 09:18:32.60: [ 6546]: <-- data [23]
May 16 09:18:32.60: [ 6546]: <-- data [2]
May 16 09:18:33.42: [ 6546]: --> [7:CONNECT]
May 16 09:18:33.42: [ 6546]: <-- data [13]
May 16 09:18:33.42: [ 6546]: <-- data [2]
May 16 09:18:34.06: [ 6546]: --> [2:OK]
May 16 09:18:34.06: [ 6546]: <-- [9:AT+FRH=3\r]
May 16 09:18:34.54: [ 6546]: --> [7:CONNECT]
May 16 09:18:35.96: [ 6546]: --> [2:OK]
May 16 09:18:35.96: [ 6546]: REMOTE TSI "7139770119"
May 16 09:18:35.96: [ 6546]: <-- [9:AT+FRH=3\r]
May 16 09:18:36.04: [ 6546]: --> [7:CONNECT]
May 16 09:18:36.40: [ 6546]: --> [2:OK]
May 16 09:18:36.40: [ 6546]: REMOTE wants 14400 bit/s
May 16 09:18:36.40: [ 6546]: REMOTE wants A4 page width (215 mm)
May 16 09:18:36.40: [ 6546]: REMOTE wants unlimited page length
May 16 09:18:36.40: [ 6546]: REMOTE wants 3.85 line/mm
May 16 09:18:36.40: [ 6546]: REMOTE wants JBIG
May 16 09:18:36.40: [ 6546]: REMOTE wants T.30 Annex A, 256-byte ECM
May 16 09:18:36.40: [ 6546]: RECV training at v.17 14400 bit/s
May 16 09:18:36.40: [ 6546]: <-- [11:AT+FRM=145\r]
May 16 09:18:38.09: [ 6546]: --> [7:CONNECT]
May 16 09:18:39.63: [ 6546]: RECV: TCF 2730 bytes, 0% non-zero, 2699 
zero-run
May 16 09:18:39.64: [ 6546]: --> [10:NO CARRIER]
May 16 09:18:39.64: [ 6546]: <-- [9:AT+FRS=7\r]
May 16 09:18:39.80: [ 6546]: --> [2:OK]
May 16 09:18:39.80: [ 6546]: TRAINING succeeded
May 16 09:18:39.80: [ 6546]: <-- [9:AT+FTH=3\r]
May 16 09:18:39.89: [ 6546]: --> [7:CONNECT]
May 16 09:18:39.89: [ 6546]: <-- data [3]
May 16 09:18:39.89: [ 6546]: <-- data [2]
May 16 09:18:41.19: [ 6546]: --> [2:OK]
May 16 09:18:41.19: [ 6546]: <-- [11:AT+FRM=146\r]
May 16 09:18:41.77: [ 6546]: --> [7:CONNECT]
May 16 09:18:43.47: [ 6546]: RECV received frame number 0
May 16 09:18:43.61: [ 6546]: RECV received frame number 1
May 16 09:18:43.75: [ 6546]: RECV received frame number 2
May 16 09:18:43.90: [ 6546]: RECV received frame number 3
May 16 09:18:44.04: [ 6546]: RECV received frame number 4
May 16 09:18:44.18: [ 6546]: RECV received frame number 5
May 16 09:18:44.39: [ 6546]: RECV received frame number 6
May 16 09:18:44.53: [ 6546]: RECV received frame number 7
May 16 09:18:44.68: [ 6546]: RECV received frame number 8
May 16 09:18:44.82: [ 6546]: RECV received frame number 9
May 16 09:18:44.96: [ 6546]: RECV received frame number 10
May 16 09:18:45.10: [ 6546]: RECV received frame number 11
May 16 09:18:45.25: [ 6546]: RECV received frame number 12
May 16 09:18:45.39: [ 6546]: RECV received frame number 13
May 16 09:18:45.53: [ 6546]: RECV received frame number 14
May 16 09:18:45.67: [ 6546]: RECV received frame number 15
May 16 09:18:45.88: [ 6546]: RECV received frame number 16
May 16 09:18:46.02: [ 6546]: RECV received frame number 17
May 16 09:18:46.16: [ 6546]: RECV received frame number 18
May 16 09:18:46.31: [ 6546]: RECV received frame number 19
May 16 09:18:46.45: [ 6546]: RECV received frame number 20
May 16 09:18:46.59: [ 6546]: RECV received frame number 21
May 16 09:18:46.73: [ 6546]: RECV received frame number 22
May 16 09:18:46.87: [ 6546]: RECV received frame number 23
May 16 09:18:47.01: [ 6546]: RECV received frame number 24
May 16 09:18:47.22: [ 6546]: RECV received frame number 25
May 16 09:18:47.37: [ 6546]: RECV received frame number 26
May 16 09:18:47.51: [ 6546]: RECV received frame number 27
May 16 09:18:47.65: [ 6546]: RECV received frame number 28
May 16 09:18:47.79: [ 6546]: RECV received frame number 29
May 16 09:18:47.93: [ 6546]: RECV received frame number 30
May 16 09:18:48.08: [ 6546]: RECV received frame number 31
May 16 09:18:48.08: [ 6546]: RECV received RCP frame
May 16 09:18:48.16: [ 6546]: --> [10:NO CARRIER]
May 16 09:18:48.16: [ 6546]: <-- [9:AT+FRH=3\r]
May 16 09:18:48.54: [ 6546]: --> [7:CONNECT]
May 16 09:18:49.54: [ 6546]: --> [2:OK]
May 16 09:18:49.54: [ 6546]: RECV recv PPS (partial page signal)
May 16 09:18:49.54: [ 6546]: RECV recv MPS (more pages, same document)
May 16 09:18:49.54: [ 6546]: RECV received 32 frames of block 1 of page 1
May 16 09:18:49.54: [ 6546]: RECV: 8170 bytes of data, 1059 total lines
May 16 09:18:49.54: [ 6546]: <-- [9:AT+FRS=7\r]
May 16 09:18:49.71: [ 6546]: --> [2:OK]
May 16 09:18:49.71: [ 6546]: <-- [9:AT+FTH=3\r]
May 16 09:18:49.81: [ 6546]: --> [7:CONNECT]
May 16 09:18:49.81: [ 6546]: RECV send MCF (message confirmation)
May 16 09:18:49.81: [ 6546]: RECV FAX (000000016): from 7139770119, page 1 
in 0:19, INF, 3.85 line/mm, JBIG, 14400 bit/s
May 16 09:18:49.81: [ 6546]: <-- data [3]
May 16 09:18:49.81: [ 6546]: <-- data [2]
May 16 09:18:51.10: [ 6546]: --> [2:OK]
May 16 09:18:51.10: [ 6546]: <-- [11:AT+FRM=146\r]
May 16 09:18:51.70: [ 6546]: --> [7:CONNECT]
May 16 09:18:53.40: [ 6546]: RECV received frame number 0
May 16 09:18:53.54: [ 6546]: RECV received frame number 1
May 16 09:18:53.69: [ 6546]: RECV received frame number 2
May 16 09:18:53.83: [ 6546]: RECV received frame number 3
May 16 09:18:54.04: [ 6546]: RECV received frame number 4
May 16 09:18:54.18: [ 6546]: RECV received frame number 5
May 16 09:18:54.32: [ 6546]: RECV received frame number 6
May 16 09:18:54.46: [ 6546]: RECV received frame number 7
May 16 09:18:54.61: [ 6546]: RECV received frame number 8
May 16 09:18:54.75: [ 6546]: RECV received frame number 9
May 16 09:18:54.89: [ 6546]: RECV received frame number 10
May 16 09:18:55.03: [ 6546]: RECV received frame number 11
May 16 09:18:55.18: [ 6546]: RECV received frame number 12
May 16 09:18:55.32: [ 6546]: RECV received frame number 13
May 16 09:18:55.53: [ 6546]: RECV received frame number 14
May 16 09:18:55.67: [ 6546]: RECV received frame number 15
May 16 09:18:55.81: [ 6546]: RECV received frame number 16
May 16 09:18:55.95: [ 6546]: RECV received frame number 17
May 16 09:18:56.10: [ 6546]: RECV received frame number 18
May 16 09:18:56.24: [ 6546]: RECV received frame number 19
May 16 09:18:56.38: [ 6546]: RECV received frame number 20
May 16 09:18:56.52: [ 6546]: RECV received frame number 21
May 16 09:18:56.66: [ 6546]: RECV received frame number 22
May 16 09:18:56.87: [ 6546]: RECV received frame number 23
May 16 09:18:57.01: [ 6546]: RECV received frame number 24
May 16 09:18:57.15: [ 6546]: RECV received frame number 25
May 16 09:18:57.30: [ 6546]: RECV received frame number 26
May 16 09:18:57.44: [ 6546]: RECV received frame number 27
May 16 09:18:57.58: [ 6546]: RECV received frame number 28
May 16 09:18:57.72: [ 6546]: RECV received frame number 29
May 16 09:18:57.86: [ 6546]: RECV received frame number 30
May 16 09:18:58.01: [ 6546]: RECV received frame number 31
May 16 09:18:58.15: [ 6546]: RECV received frame number 32
May 16 09:18:58.36: [ 6546]: RECV received frame number 33
May 16 09:18:58.50: [ 6546]: RECV received frame number 34
May 16 09:18:58.64: [ 6546]: RECV received frame number 35
May 16 09:18:58.79: [ 6546]: RECV received frame number 36
May 16 09:18:58.93: [ 6546]: RECV received frame number 37
May 16 09:18:59.07: [ 6546]: RECV received frame number 38
May 16 09:18:59.21: [ 6546]: RECV received frame number 39
May 16 09:18:59.36: [ 6546]: RECV received frame number 40
May 16 09:18:59.50: [ 6546]: RECV received frame number 41
May 16 09:18:59.71: [ 6546]: RECV received frame number 42
May 16 09:18:59.85: [ 6546]: RECV received frame number 43
May 16 09:18:59.99: [ 6546]: RECV received frame number 44
May 16 09:19:00.13: [ 6546]: RECV received frame number 45
May 16 09:19:00.14: [ 6546]: RECV received RCP frame
May 16 09:19:00.18: [ 6546]: --> [10:NO CARRIER]
May 16 09:19:00.18: [ 6546]: <-- [9:AT+FRH=3\r]
May 16 09:19:00.58: [ 6546]: --> [7:CONNECT]
May 16 09:19:01.56: [ 6546]: --> [2:OK]
May 16 09:19:01.56: [ 6546]: RECV recv PPS (partial page signal)
May 16 09:19:01.56: [ 6546]: RECV recv EOP (no more pages or documents)
May 16 09:19:01.56: [ 6546]: RECV received 46 frames of block 1 of page 2
May 16 09:19:01.56: [ 6546]: RECV: 11530 bytes of data, 1058 total lines
May 16 09:19:01.56: [ 6546]: <-- [9:AT+FRS=7\r]
May 16 09:19:01.73: [ 6546]: --> [2:OK]
May 16 09:19:01.73: [ 6546]: <-- [9:AT+FTH=3\r]
May 16 09:19:01.82: [ 6546]: --> [7:CONNECT]
May 16 09:19:01.82: [ 6546]: <-- data [3]
May 16 09:19:01.82: [ 6546]: <-- data [2]
May 16 09:19:03.12: [ 6546]: --> [2:OK]
May 16 09:19:03.12: [ 6546]: RECV send MCF (message confirmation)
May 16 09:19:03.12: [ 6546]: RECV FAX (000000016): from 7139770119, page 2 
in 0:12, INF, 3.85 line/mm, JBIG, 14400 bit/s
May 16 09:19:03.12: [ 6546]: RECV FAX (000000016): recvq/fax000000015.tif 
from 7139770119, route to <unspecified>, 2 pages in 0:33
May 16 09:19:03.12: [ 6546]: <-- [9:AT+FRH=3\r]
May 16 09:19:03.56: [ 6546]: --> [7:CONNECT]
May 16 09:19:04.46: [ 6546]: --> [2:OK]
May 16 09:19:04.46: [ 6546]: RECV recv DCN (disconnect)
May 16 09:19:04.46: [ 6546]: RECV FAX: bin/faxrcvd "recvq/fax000000015.tif" 
"ttyS0" "000000016" ""
May 16 09:19:04.47: [ 6546]: RECV FAX: end
May 16 09:19:04.47: [ 6546]: SESSION END


---- END SECOND LOG FILE ----

Any and all help is greatly appreciated.

Thanks,

Fred
----- Original Message ----- 
From: "Lee Howard" <faxguy@xxxxxxxxxxxxxxxx>
To: "Fred Newtz" <fnewtz@xxxxxxxxxx>
Cc: <hylafax-users@xxxxxxxxxxx>
Sent: Monday, August 14, 2006 11:22 AM
Subject: Re: [hylafax-users] Problem Faxes - Where to start


> Fred Newtz wrote:
>
> > So I am still on 4.2.5, should I upgrade to 4.3.0 first?  From there, I 
> > would assume it would be my modem configuration and/or my TIFF generation 
> > would be the key places that are misconfigured?
>
>
> I would generally doubt that any of the problems you mentioned would be 
> resolved by an upgrade from 4.2.5 to 4.3.0.  However, you should upgrade 
> to 4.3.0 anyway just to make sure... and then progress from there.  I 
> would strongly recommend sending us session logs of problematic faxes so 
> that we can help better.
>
> 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@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*