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

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

  It doesn't sound like anyone knows the exact answer to my dilemma. Does anyone have any
pointers to how I would go about troubleshooting the issue? It's very close to perfect,
but still not usable since it puts the sender into an infinite retry loop.

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*