Re: [hylafax-users] Fax received ok, but sender thinks it failed and resends

["Chris 'Xenon' Hanson" <xenon@xxxxxxxxxxxxxx>]

  Ok, per the recommendations of this list, I have upgraded to the Debian 4.4 packages
from highrise.ca. However, the original symptoms (below) persist. The fax receives fine,
but the other end mistakenly believes it did not receive ok, and continues to redial and
try to re-send. I think it's probably just something amiss in the post-send chat, seen in
the log below. Any tips on troubleshooting this configuration?

Chris 'Xenon' Hanson wrote:
>   I have installed HylaFAX on my Ubuntu 6.06.2 LTS box from Ubuntu's packages (version
> reports HylaFAX (tm) Version 4.2.5). I have a Compaq (Actiontec) PM560LKI 56K PCI modem
> (manifests as a COM port, ttyS4). Hylafax seems to be working well so far. I have received
> a couple of faxes just fine, but there seems to be a prevailing issue. First, I was
> getting an error about "failure to receive silence", which I found here:
> http://www.hylafax.org/archive/2002-08/msg00260.php
> 
>   I did the change recommended there, and that error has gone away.
> 
>   However, everyone who has sent me a fax so far has experienced that their fax machine
> keeps retrying sending the fax, believing it didn't go through. My end got it ok, it
> appears. "Brother" made MFC fax devices sending to me report "Poor line condition" though
> the received fax looks great here.
> 
>   Here is a log entry for one of these receives. For brevity I've edited out the
> repetitive middle parts where it was all the same "RECV received frame number n" with no
> errors, and I masked out the phone numbers involved.
> 
> 
> Dec 12 16:08:20.22: [16428]: SESSION BEGIN 000000009 1303xxxxxxx
> Dec 12 16:08:20.22: [16428]: HylaFAX (tm) Version 4.2.5
> Dec 12 16:08:20.22: [16428]: <-- [4:ATA\r]
> Dec 12 16:08:26.22: [16428]: --> [7:CONNECT]
> Dec 12 16:08:26.22: [16428]: ANSWER: FAX CONNECTION  DEVICE '/dev/ttyS4'
> Dec 12 16:08:26.22: [16428]: RECV FAX: begin
> Dec 12 16:08:26.22: [16428]: <-- data [32]
> Dec 12 16:08:26.22: [16428]: <-- data [2]
> Dec 12 16:08:26.24: [16428]: --> [7:CONNECT]
> Dec 12 16:08:26.24: [16428]: <-- data [23]
> Dec 12 16:08:26.24: [16428]: <-- data [2]
> Dec 12 16:08:26.26: [16428]: --> [7:CONNECT]
> Dec 12 16:08:26.26: [16428]: <-- data [13]
> Dec 12 16:08:26.26: [16428]: <-- data [2]
> Dec 12 16:08:28.47: [16428]: --> [2:OK]
> Dec 12 16:08:28.47: [16428]: <-- [9:AT+FRH=3\r]
> Dec 12 16:08:28.61: [16428]: --> [7:CONNECT]
> Dec 12 16:08:30.28: [16428]: --> [2:OK]
> Dec 12 16:08:30.28: [16428]: REMOTE TSI "303-xxx-xxxx"
> Dec 12 16:08:30.28: [16428]: <-- [9:AT+FRH=3\r]
> Dec 12 16:08:30.30: [16428]: --> [7:CONNECT]
> Dec 12 16:08:30.72: [16428]: --> [2:OK]
> Dec 12 16:08:30.72: [16428]: REMOTE wants 14400 bit/s
> Dec 12 16:08:30.72: [16428]: REMOTE wants A4 page width (215 mm)
> Dec 12 16:08:30.72: [16428]: REMOTE wants unlimited page length
> Dec 12 16:08:30.72: [16428]: REMOTE wants 7.7 line/mm
> Dec 12 16:08:30.72: [16428]: REMOTE wants 1-D MH
> Dec 12 16:08:30.72: [16428]: REMOTE wants T.30 Annex A, 256-byte ECM
> Dec 12 16:08:30.72: [16428]: RECV training at v.17 14400 bit/s
> Dec 12 16:08:30.72: [16428]: <-- [11:AT+FRM=145\r]
> Dec 12 16:08:32.45: [16428]: --> [7:CONNECT]
> Dec 12 16:08:33.93: [16428]: RECV: TCF 2672 bytes, 0% non-zero, 2668 zero-run
> Dec 12 16:08:33.93: [16428]: --> [10:NO CARRIER]
> Dec 12 16:08:33.93: [16428]: DELAY 70 ms
> Dec 12 16:08:34.00: [16428]: TRAINING succeeded
> Dec 12 16:08:34.00: [16428]: <-- [9:AT+FTH=3\r]
> Dec 12 16:08:35.07: [16428]: --> [7:CONNECT]
> Dec 12 16:08:35.07: [16428]: <-- data [3]
> Dec 12 16:08:35.07: [16428]: <-- data [2]
> Dec 12 16:08:35.39: [16428]: --> [2:OK]
> Dec 12 16:08:35.39: [16428]: <-- [11:AT+FRM=146\r]
> Dec 12 16:08:36.22: [16428]: --> [7:CONNECT]
> Dec 12 16:08:36.62: [16428]: RECV received frame number 0
> Dec 12 16:08:36.78: [16428]: RECV received frame number 1
> Dec 12 16:08:36.94: [16428]: RECV received frame number 2
> ...
> Dec 12 16:08:54.05: [16428]: RECV received frame number 119
> Dec 12 16:08:54.20: [16428]: RECV received frame number 120
> Dec 12 16:08:54.20: [16428]: RECV received RCP frame
> Dec 12 16:08:54.20: [16428]: --> [10:NO CARRIER]
> Dec 12 16:08:54.20: [16428]: <-- [9:AT+FRH=3\r]
> Dec 12 16:08:54.34: [16428]: --> [7:CONNECT]
> Dec 12 16:08:55.57: [16428]: --> [2:OK]
> Dec 12 16:08:55.57: [16428]: RECV recv PPS (partial page signal)
> Dec 12 16:08:55.57: [16428]: RECV recv EOP (no more pages or documents)
> Dec 12 16:08:55.57: [16428]: RECV received 121 frames of block 1 of page 1
> Dec 12 16:08:55.65: [16428]: RECV: 30776 bytes of data, 2164 total lines
> Dec 12 16:08:55.65: [16428]: DELAY 70 ms
> Dec 12 16:08:55.72: [16428]: <-- [9:AT+FTH=3\r]
> Dec 12 16:08:55.72: [16428]: --> [10:NO CARRIER]
> Dec 12 16:08:55.72: [16428]: RECV send MCF (message confirmation)
> Dec 12 16:08:55.72: [16428]: RECV FAX (000000009): from 303-xxx-xxxx, page 1 in 0:29, INF,
> 7.7 line/mm, 1-D MH, 14400 bit/s
> Dec 12 16:08:55.72: [16428]: RECV FAX (000000009): recvq/fax000000009.tif from
> 303-xxx-xxxx, route to <unspecified>, 1 pages in 0:29
> Dec 12 16:08:55.72: [16428]: <-- [9:AT+FRH=3\r]
> Dec 12 16:08:58.72: [16428]: --> [7:CONNECT]
> Dec 12 16:09:00.00: [16428]: --> [2:OK]
> Dec 12 16:09:00.00: [16428]: RECV recv PPS (partial page signal)
> Dec 12 16:09:00.00: [16428]: DELAY 70 ms
> Dec 12 16:09:00.07: [16428]: <-- [9:AT+FTH=3\r]
> Dec 12 16:09:00.07: [16428]: --> [10:NO CARRIER]
> Dec 12 16:09:00.07: [16428]: RECV send MCF (message confirmation)
> Dec 12 16:09:00.07: [16428]: <-- [9:AT+FRH=3\r]
> Dec 12 16:09:03.14: [16428]: --> [7:CONNECT]
> Dec 12 16:09:04.42: [16428]: --> [2:OK]
> Dec 12 16:09:04.42: [16428]: RECV recv PPS (partial page signal)
> Dec 12 16:09:04.42: [16428]: DELAY 70 ms
> Dec 12 16:09:04.49: [16428]: <-- [9:AT+FTH=3\r]
> Dec 12 16:09:04.49: [16428]: --> [10:NO CARRIER]
> Dec 12 16:09:04.49: [16428]: RECV send MCF (message confirmation)
> Dec 12 16:09:04.49: [16428]: <-- [9:AT+FRH=3\r]
> Dec 12 16:09:07.57: [16428]: --> [7:CONNECT]
> Dec 12 16:09:08.74: [16428]: --> [2:OK]
> Dec 12 16:09:08.74: [16428]: RECV recv DCN (disconnect)
> Dec 12 16:09:08.74: [16428]: RECV FAX: bin/faxrcvd "recvq/fax000000009.tif" "ttyS4"
> "000000009" ""
> Dec 12 16:09:08.74: [16428]: RECV FAX: end
> Dec 12 16:09:08.74: [16428]: SESSION END
> 
>   I'm guessing something in the final song & dance goes in the wrong order and leads to
> the other end not getting the all-clear, but I don't know diddly about Class 1 fax
> protocol, so I'm appealing here for help.
> 
>   I can post other config files if you want, but I wasn't sure which was relevant and
> didn't want to just spam you with config crap.
> 
> 
>   Thanks in advance for any advice.
> 
>   (Hylafax is replacing a 1997-vintage "Gateway 2000" Pentium Pro 200 with 64Mb of RAM
> that has been running WinNT and WinFax Pro. This machine has reliably run more or less
> continuously since its purchase in 1997. It is the oldest running machine in our company,
> and deserves retirement with full benefits to a beach in Cabo where it will receive free
> umbrella drinks for life. ;)
> 
> 


-- 
Chris 'Xenon' Hanson, omo sanza lettere                  Xenon AlphaPixel.com
PixelSense Landsat processing now available! http://www.alphapixel.com/demos/
"There is no Truth. There is only Perception. To Perceive is to Exist." - Xen


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