HylaFAX The world's most advanced open source fax server

[Date Prev][Date Next][Thread Prev][Thread Next] [Date Index] [Thread Index]

[hylafax-users] Fwd: Sudden problem with: No response to PPS repeated 3 times.; Giving up after 3 attempts to send same page



Hi guys,

I have been running Hylafax for a little while now and the same fax
being sent to the same customer started generating the:

   No response to PPS repeated 3 times.; Giving up after 3 attempts to
send same page


Looking at the log output, it feels like it fails near the end of the
connection, but it is capable to reconnect to the fax twice before
failing with the PPS error.

The problem I can see is that the PPS counter is not being reset when we
get a new CONNECT. So on the 3rd NO CARRIER, even though we had a
CONNECT between each AT+FRH=3, you bail out. That causes a problem since
the fax was actually sent properly (the "SEND end page" works!) this fax
is just one page so it should really not be this bad.

Looking at the code, the failing call seems to be this one:

gotppr = recvFrame(pprframe, FCF_SNDR, conf.t4Timer, false, false)

but I'm not too sure what PPR is. Is this much more than just the CONNECT?

What's your take on this one?

Thank you.
Alexis Wilke

P.S. As I was looking at a diff output between 6.0.3 and 6.0.5, I
noticed that the hylafax.spec file had version 6.0.4. You may also want
to make sure to update the hylafax.spec file with the correct version (I
would suggest a script to check the version automatically and "break"
when the versions don't match.)

--- hylafax-6.0.3/rpm/hylafax.spec      2009-07-10 08:43:35.000000000 -0700
+++ hylafax-6.0.5/rpm/hylafax.spec      2010-09-15 07:42:36.000000000 -0700
@@ -1,5 +1,5 @@
 %define name        hylafax
-%define version     6.0.3
+%define version     6.0.4
 %define pkg_release 1
 
 #


    ---- Transcript of session follows ----

Jan 13 17:16:06.36: [10842]: SESSION BEGIN 000000066 19164875142
Jan 13 17:16:06.36: [10842]: HylaFAX (tm) Version 6.0.3
Jan 13 17:16:06.36: [10842]: SEND FAX: JOB 239 DEST 19164875142 COMMID 000000066 DEVICE '/dev/ttyS0' FROM '***' USER ***
Jan 13 17:16:06.36: [10842]: <-- [12:AT+FCLASS=1\r]
Jan 13 17:16:06.37: [10842]: --> [2:OK]
Jan 13 17:16:06.37: [10842]: DIAL 123456789
Jan 13 17:16:06.37: [10842]: <-- [16:ATDT123456789\r]
Jan 13 17:16:23.93: [10842]: --> [7:CONNECT]
Jan 13 17:16:25.49: [10842]: --> [2:OK]
Jan 13 17:16:25.49: [10842]: REMOTE CSI "123456789"
Jan 13 17:16:25.49: [10842]: <-- [9:AT+FRH=3\r]
Jan 13 17:16:25.51: [10842]: --> [7:CONNECT]
Jan 13 17:16:25.84: [10842]: --> [2:OK]
Jan 13 17:16:25.84: [10842]: REMOTE best rate 33600 bit/s
Jan 13 17:16:25.84: [10842]: REMOTE max A4 page width (215 mm)
Jan 13 17:16:25.84: [10842]: REMOTE max unlimited page length
Jan 13 17:16:25.84: [10842]: REMOTE best vres 15.4 line/mm
Jan 13 17:16:25.84: [10842]: REMOTE format support: MH, MR, MMR
Jan 13 17:16:25.84: [10842]: REMOTE supports T.30 Annex A, 256-byte ECM
Jan 13 17:16:25.84: [10842]: REMOTE best 10 ms/scanline
Jan 13 17:16:25.84: [10842]: USE 14400 bit/s
Jan 13 17:16:25.84: [10842]: USE error correction mode
Jan 13 17:16:25.84: [10842]: SEND file "docq/doc41.ps;31"
Jan 13 17:16:25.85: [10842]: USE A4 page width (215 mm)
Jan 13 17:16:25.85: [10842]: USE unlimited page length
Jan 13 17:16:25.85: [10842]: USE 7.7 line/mm
Jan 13 17:16:25.85: [10842]: USE 2-D MMR
Jan 13 17:16:25.85: [10842]: USE 0 ms/scanline
Jan 13 17:16:25.85: [10842]: SEND training at v.17 14400 bit/s
Jan 13 17:16:25.85: [10842]: DELAY 70 ms
Jan 13 17:16:25.92: [10842]: <-- [9:AT+FTH=3\r]
Jan 13 17:16:26.12: [10842]: --> [7:CONNECT]
Jan 13 17:16:26.12: [10842]: <-- data [23]
Jan 13 17:16:26.12: [10842]: <-- data [2]
Jan 13 17:16:26.14: [10842]: --> [7:CONNECT]
Jan 13 17:16:26.14: [10842]: <-- data [7]
Jan 13 17:16:26.14: [10842]: <-- data [2]
Jan 13 17:16:28.12: [10842]: --> [2:OK]
Jan 13 17:16:28.12: [10842]: <-- [9:AT+FTS=7\r]
Jan 13 17:16:28.19: [10842]: --> [2:OK]
Jan 13 17:16:28.19: [10842]: <-- [11:AT+FTM=145\r]
Jan 13 17:16:29.67: [10842]: --> [7:CONNECT]
Jan 13 17:16:29.67: [10842]: <-- data [1024]
Jan 13 17:16:29.67: [10842]: <-- data [1024]
Jan 13 17:16:29.67: [10842]: <-- data [652]
Jan 13 17:16:29.67: [10842]: <-- data [2]
Jan 13 17:16:31.20: [10842]: --> [2:OK]
Jan 13 17:16:31.20: [10842]: <-- [9:AT+FRS=1\r]
Jan 13 17:16:31.22: [10842]: --> [2:OK]
Jan 13 17:16:31.22: [10842]: <-- [9:AT+FRH=3\r]
Jan 13 17:16:31.94: [10842]: --> [7:CONNECT]
Jan 13 17:16:32.73: [10842]: --> [2:OK]
Jan 13 17:16:32.73: [10842]: TRAINING succeeded
Jan 13 17:16:32.73: [10842]: SEND begin page
Jan 13 17:16:32.73: [10842]: Reading MH-compressed image file
Jan 13 17:16:32.74: [10842]: SEND send frame number 0
Jan 13 17:16:32.74: [10842]: SEND send frame number 1
Jan 13 17:16:32.74: [10842]: SEND send frame number 2
Jan 13 17:16:32.74: [10842]: SEND send frame number 3
Jan 13 17:16:32.74: [10842]: SEND send frame number 4
Jan 13 17:16:32.74: [10842]: SEND send frame number 5
Jan 13 17:16:32.74: [10842]: SEND send frame number 6
Jan 13 17:16:32.74: [10842]: SEND send frame number 7
Jan 13 17:16:32.74: [10842]: SEND send frame number 8
Jan 13 17:16:32.74: [10842]: SEND send frame number 9
Jan 13 17:16:32.74: [10842]: SEND send frame number 10
Jan 13 17:16:32.74: [10842]: SEND send frame number 11
Jan 13 17:16:32.74: [10842]: SEND send frame number 12
Jan 13 17:16:32.74: [10842]: SEND send frame number 13
Jan 13 17:16:32.74: [10842]: SEND send frame number 14
Jan 13 17:16:32.74: [10842]: SEND send frame number 15
Jan 13 17:16:32.74: [10842]: SEND send frame number 16
Jan 13 17:16:32.74: [10842]: SEND send frame number 17
Jan 13 17:16:32.74: [10842]: SEND send frame number 18
Jan 13 17:16:32.74: [10842]: SEND send frame number 19
Jan 13 17:16:32.74: [10842]: SEND send frame number 20
Jan 13 17:16:32.74: [10842]: SEND send frame number 21
Jan 13 17:16:32.74: [10842]: SEND send frame number 22
Jan 13 17:16:32.74: [10842]: SEND send frame number 23
Jan 13 17:16:32.74: [10842]: SEND send frame number 24
Jan 13 17:16:32.74: [10842]: SEND send frame number 25
Jan 13 17:16:32.74: [10842]: SEND send frame number 26
Jan 13 17:16:32.74: [10842]: SEND send frame number 27
Jan 13 17:16:32.74: [10842]: SEND send frame number 28
Jan 13 17:16:32.74: [10842]: SEND send frame number 29
Jan 13 17:16:32.74: [10842]: SEND send frame number 30
Jan 13 17:16:32.74: [10842]: SEND send frame number 31
Jan 13 17:16:32.74: [10842]: SEND send frame number 32
Jan 13 17:16:32.74: [10842]: SEND send frame number 33
Jan 13 17:16:32.74: [10842]: SEND send frame number 34
Jan 13 17:16:32.74: [10842]: SEND send frame number 35
Jan 13 17:16:32.74: [10842]: SEND send frame number 36
Jan 13 17:16:32.74: [10842]: SEND send frame number 37
Jan 13 17:16:32.74: [10842]: SEND send frame number 38
Jan 13 17:16:32.74: [10842]: DELAY 70 ms
Jan 13 17:16:32.82: [10842]: <-- [11:AT+FTM=146\r]
Jan 13 17:16:33.08: [10842]: --> [7:CONNECT]
Jan 13 17:16:33.08: [10842]: <-- data [1028]
Jan 13 17:16:33.08: [10842]: <-- data [1035]
Jan 13 17:16:33.08: [10842]: <-- data [1033]
Jan 13 17:16:33.08: [10842]: <-- data [1038]
Jan 13 17:16:34.71: [10842]: <-- data [1031]
Jan 13 17:16:34.71: [10842]: <-- data [1035]
Jan 13 17:16:34.71: [10842]: <-- data [1034]
Jan 13 17:16:34.71: [10842]: <-- data [1035]
Jan 13 17:16:36.36: [10842]: <-- data [1034]
Jan 13 17:16:36.36: [10842]: <-- data [1036]
Jan 13 17:16:36.36: [10842]: <-- data [688]
Jan 13 17:16:36.36: [10842]: <-- data [2]
Jan 13 17:16:39.18: [10842]: --> [2:OK]
Jan 13 17:16:39.18: [10842]: <-- [9:AT+FTS=9\r]
Jan 13 17:16:39.28: [10842]: --> [2:OK]
Jan 13 17:16:39.28: [10842]: <-- [9:AT+FTH=3\r]
Jan 13 17:16:39.44: [10842]: --> [7:CONNECT]
Jan 13 17:16:39.44: [10842]: <-- data [7]
Jan 13 17:16:39.44: [10842]: <-- data [2]
Jan 13 17:16:40.72: [10842]: --> [2:OK]
Jan 13 17:16:40.72: [10842]: SEND send PPS (partial page signal)
Jan 13 17:16:40.72: [10842]: SEND send EOP (no more pages or documents)
Jan 13 17:16:40.72: [10842]: <-- [9:AT+FRH=3\r]
Jan 13 17:16:41.18: [10842]: --> [10:NO CARRIER]
Jan 13 17:16:41.18: [10842]: MODEM No carrier
Jan 13 17:16:41.18: [10842]: <-- [9:AT+FTH=3\r]
Jan 13 17:16:41.37: [10842]: --> [7:CONNECT]
Jan 13 17:16:41.37: [10842]: <-- data [7]
Jan 13 17:16:41.37: [10842]: <-- data [2]
Jan 13 17:16:42.66: [10842]: --> [2:OK]
Jan 13 17:16:42.66: [10842]: SEND send PPS (partial page signal)
Jan 13 17:16:42.66: [10842]: SEND send EOP (no more pages or documents)
Jan 13 17:16:42.66: [10842]: <-- [9:AT+FRH=3\r]
Jan 13 17:16:43.13: [10842]: --> [10:NO CARRIER]
Jan 13 17:16:43.13: [10842]: MODEM No carrier
Jan 13 17:16:43.13: [10842]: <-- [9:AT+FTH=3\r]
Jan 13 17:16:43.34: [10842]: --> [7:CONNECT]
Jan 13 17:16:43.34: [10842]: <-- data [7]
Jan 13 17:16:43.34: [10842]: <-- data [2]
Jan 13 17:16:44.62: [10842]: --> [2:OK]
Jan 13 17:16:44.62: [10842]: SEND send PPS (partial page signal)
Jan 13 17:16:44.62: [10842]: SEND send EOP (no more pages or documents)
Jan 13 17:16:44.62: [10842]: <-- [9:AT+FRH=3\r]
Jan 13 17:16:45.17: [10842]: --> [10:NO CARRIER]
Jan 13 17:16:45.17: [10842]: MODEM No carrier
Jan 13 17:16:45.17: [10842]: No response to PPS repeated 3 times.
Jan 13 17:16:45.17: [10842]: SEND end page
Jan 13 17:16:45.17: [10842]: SEND: Giving up after 3 attempts to send same page "docq/doc41.ps;31", dirnum 0
Jan 13 17:16:45.17: [10842]: DELAY 70 ms
Jan 13 17:16:45.24: [10842]: <-- [9:AT+FTH=3\r]
Jan 13 17:16:45.44: [10842]: --> [7:CONNECT]
Jan 13 17:16:45.44: [10842]: <-- data [3]
Jan 13 17:16:45.44: [10842]: <-- data [2]
Jan 13 17:16:46.61: [10842]: --> [2:OK]
Jan 13 17:16:47.61: [10842]: <-- [5:ATH0\r]
Jan 13 17:16:47.72: [10842]: --> [2:OK]
Jan 13 17:16:47.72: [10842]: SESSION END




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




Project hosted by iFAX Solutions