[hylafax-users] Getting error -- Internal botch; bad post-page handling string "" (updated)

["Bjorn Stabell" <bjorn@exoweb.net>]

Hello,

I'm using Hylafax 4.1.1 on Linux.  Every now and then when transmitting
faxes I get the following error:

    Internal botch; bad post-page handling string ""

Sometimes retransmitting the same fax will not result in this error.
What does it mean?

Note: The fax was actually transmitted successfully, even though it is
recorded as "failed" by Hylafax.

I tried searching the web, but the only recommendation I found was to
upgrade to 4.1.1 (which I am already using) and set RTNHandlingMethod to
Ignore (which is not acceptable; I need to transmit the page, and it can
be transmitted if I retransmit).

See log of sample session and status file attached below.  Any clues?

It's kind-of strange that the mailaddr is on the same line as the
number, isn't it?  Is this because I'm using a URL as the mailaddr?  (I
have a custom NotifyCmd that uses HTTP POST to put all the status
results to the URL specified by the mailaddr field.)

Regards,
-- 
Bjorn Stabell bjorn@exoweb.net
Tel +86 (10) 65918490

************** .../log/c*
Apr 02 12:32:57.20: [18700]: SESSION BEGIN 00002181 1165032688 Apr 02
12:32:57.20: [18700]: HylaFAX (tm) Version 4.1.1 Apr 02 12:32:57.20:
[18700]: SEND FAX: JOB 1487 DEST 65032688 COMMID 00002181 DEVICE
'/dev/ttyS1' Apr 02 12:32:57.20: [18700]: DELAY 75 ms Apr 02
12:32:57.28: [18700]: DELAY 2600 ms Apr 02 12:32:59.88: [18700]: <--
[4:ATZ\r] Apr 02 12:33:00.01: [18700]: --> [2:OK] Apr 02 12:33:00.01:
[18700]: DELAY 3000 ms Apr 02 12:33:03.01: [18700]: <-- [5:ATE0\r] Apr
02 12:33:03.02: [18700]: --> [4:ATE0] Apr 02 12:33:03.02: [18700]: -->
[2:OK] Apr 02 12:33:03.02: [18700]: <-- [5:ATV1\r] Apr 02 12:33:03.03:
[18700]: --> [2:OK] Apr 02 12:33:03.03: [18700]: <-- [5:ATQ0\r] Apr 02
12:33:03.04: [18700]: --> [2:OK] Apr 02 12:33:03.04: [18700]: <--
[7:ATS0=0\r] Apr 02 12:33:03.05: [18700]: --> [2:OK] Apr 02 12:33:03.05:
[18700]: <-- [7:ATS8=2\r] Apr 02 12:33:03.06: [18700]: --> [2:OK] Apr 02
12:33:03.06: [18700]: <-- [8:ATS7=60\r] Apr 02 12:33:03.07: [18700]: -->
[2:OK] Apr 02 12:33:03.07: [18700]: <-- [6:AT&K3\r] Apr 02 12:33:03.08:
[18700]: --> [2:OK] Apr 02 12:33:03.08: [18700]: <-- [6:AT&D2\r] Apr 02
12:33:03.09: [18700]: --> [2:OK] Apr 02 12:33:03.09: [18700]: <--
[6:AT&C1\r] Apr 02 12:33:03.10: [18700]: --> [2:OK] Apr 02 12:33:03.10:
[18700]: <-- [12:AT+FCLASS=1\r] Apr 02 12:33:03.11: [18700]: --> [2:OK]
Apr 02 12:33:03.11: [18700]: <-- [5:ATM0\r] Apr 02 12:33:03.12: [18700]:
--> [2:OK] Apr 02 12:33:03.12: [18700]: <-- [12:AT+FCLASS=1\r] Apr 02
12:33:03.23: [18700]: --> [2:OK] Apr 02 12:33:03.23: [18700]: DIAL
65032683 Apr 02 12:33:03.23: [18700]: <-- [13:ATDT65032683\r] Apr 02
12:33:20.84: [18700]: --> [7:CONNECT] Apr 02 12:33:21.87: [18700]: -->
[2:OK] Apr 02 12:33:21.87: [18700]: REMOTE NSF "86 00 B2 00 00 C0 00 4F"
Apr 02 12:33:21.87: [18700]: NSF remote fax equipment: unknown 
Apr 02 12:33:21.87: [18700]: <-- [9:AT+FRH=3\r]
Apr 02 12:33:21.88: [18700]: --> [7:CONNECT]
Apr 02 12:33:22.59: [18700]: --> [2:OK]
Apr 02 12:33:22.59: [18700]: REMOTE CSI ""
Apr 02 12:33:22.59: [18700]: <-- [9:AT+FRH=3\r]
Apr 02 12:33:22.60: [18700]: --> [7:CONNECT]
Apr 02 12:33:22.93: [18700]: --> [2:OK]
Apr 02 12:33:22.93: [18700]: REMOTE best rate 14400 bit/s
Apr 02 12:33:22.93: [18700]: REMOTE max page width 1728 pixels in 215 mm
Apr 02 12:33:22.93: [18700]: REMOTE max unlimited page length 
Apr 02 12:33:22.93: [18700]: REMOTE best vres 7.7 line/mm
Apr 02 12:33:22.93: [18700]: REMOTE best format 2-D MMR
Apr 02 12:33:22.93: [18700]: REMOTE supports T.30 Annex A, ECM Apr 02
12:33:22.93: [18700]: REMOTE best 10 ms, 5 ms/scanline Apr 02
12:33:22.93: [18700]: USE 14400 bit/s Apr 02 12:33:22.93: [18700]: USE
10 ms, 5 ms/scanline Apr 02 12:33:22.93: [18700]: SEND file
"docq/doc1497.ps;31" Apr 02 12:33:22.93: [18700]: USE page width 1728
pixels in 215 mm Apr 02 12:33:22.93: [18700]: USE unlimited page length 
Apr 02 12:33:22.93: [18700]: USE 7.7 line/mm
Apr 02 12:33:22.93: [18700]: USE 1-D MR
Apr 02 12:33:22.93: [18700]: SEND training at v.17 14400 bit/s Apr 02
12:33:22.93: [18700]: <-- [9:AT+FTH=3\r] Apr 02 12:33:22.98: [18700]:
--> [7:CONNECT] Apr 02 12:33:22.98: [18700]: <-- data [23] Apr 02
12:33:22.98: [18700]: <-- data [2] Apr 02 12:33:24.76: [18700]: -->
[7:CONNECT] Apr 02 12:33:24.76: [18700]: <-- data [6] Apr 02
12:33:24.76: [18700]: <-- data [2] Apr 02 12:33:25.20: [18700]: -->
[2:OK] Apr 02 12:33:25.20: [18700]: <-- [9:AT+FTS=7\r] Apr 02
12:33:25.28: [18700]: --> [2:OK] Apr 02 12:33:25.28: [18700]: <--
[11:AT+FTM=145\r] Apr 02 12:33:25.31: [18700]: --> [7:CONNECT] Apr 02
12:33:25.31: [18700]: <-- data [1024] Apr 02 12:33:25.31: [18700]: <--
data [1024] Apr 02 12:33:25.31: [18700]: <-- data [652] Apr 02
12:33:25.31: [18700]: <-- data [2] Apr 02 12:33:28.42: [18700]: -->
[2:OK] Apr 02 12:33:28.42: [18700]: <-- [9:AT+FRH=3\r] Apr 02
12:33:28.85: [18700]: --> [7:CONNECT] Apr 02 12:33:29.80: [18700]: -->
[2:OK] Apr 02 12:33:29.80: [18700]: TRAINING succeeded Apr 02
12:33:29.80: [18700]: DELAY 75 ms Apr 02 12:33:29.88: [18700]: <--
[11:AT+FTM=146\r] Apr 02 12:33:29.91: [18700]: --> [7:CONNECT] Apr 02
12:33:29.91: [18700]: SEND begin page Apr 02 12:33:29.91: [18700]: <--
data [1026] Apr 02 12:33:29.91: [18700]: <-- data [1024] Apr 02
12:33:29.91: [18700]: <-- data [1024] Apr 02 12:33:29.91: [18700]: <--
data [1025] Apr 02 12:33:29.91: [18700]: <-- data [1025] Apr 02
12:33:30.47: [18700]: <-- data [1025] Apr 02 12:33:31.33: [18700]: <--
data [1027] Apr 02 12:33:31.75: [18700]: <-- data [1026] Apr 02
12:33:32.18: [18700]: <-- data [1024] Apr 02 12:33:33.03: [18700]: <--
data [1025] Apr 02 12:33:33.46: [18700]: <-- data [1028] Apr 02
12:33:33.88: [18700]: <-- data [1026] Apr 02 12:33:34.74: [18700]: <--
data [1024] Apr 02 12:33:35.16: [18700]: <-- data [1026] Apr 02
12:33:35.59: [18700]: <-- data [1025] Apr 02 12:33:36.44: [18700]: <--
data [1026] Apr 02 12:33:36.86: [18700]: <-- data [1026] Apr 02
12:33:37.29: [18700]: <-- data [1024] Apr 02 12:33:38.14: [18700]: <--
data [964] Apr 02 12:33:38.57: [18700]: SENT 19394 bytes of data Apr 02
12:33:38.57: [18700]: <-- data [1028] Apr 02 12:33:39.00: [18700]: <--
data [1027] Apr 02 12:33:39.85: [18700]: <-- data [1027] Apr 02
12:33:40.27: [18700]: <-- data [1027] Apr 02 12:33:40.70: [18700]: <--
data [1025] Apr 02 12:33:41.55: [18700]: <-- data [1027] Apr 02
12:33:41.97: [18700]: <-- data [1024] Apr 02 12:33:42.40: [18700]: <--
data [1024] Apr 02 12:33:43.25: [18700]: <-- data [1024] Apr 02
12:33:43.68: [18700]: <-- data [1024] Apr 02 12:33:44.10: [18700]: <--
data [1024] Apr 02 12:33:44.96: [18700]: <-- data [1024] Apr 02
12:33:45.38: [18700]: <-- data [1024] Apr 02 12:33:45.81: [18700]: <--
data [49] Apr 02 12:33:45.81: [18700]: SENT 13361 bytes of data Apr 02
12:33:45.81: [18700]: SEND 1D RTC Apr 02 12:33:45.81: [18700]: <-- data
[29] Apr 02 12:33:45.81: [18700]: <-- data [2] Apr 02 12:33:45.81:
[18700]: SEND end page Apr 02 12:33:48.49: [18700]: --> [2:OK] Apr 02
12:33:48.49: [18700]: <-- [9:AT+FTH=3\r] Apr 02 12:33:48.54: [18700]:
--> [7:CONNECT] Apr 02 12:33:48.54: [18700]: <-- data [3] Apr 02
12:33:48.54: [18700]: <-- data [2] Apr 02 12:33:49.85: [18700]: -->
[2:OK] Apr 02 12:33:49.86: [18700]: <-- [5:ATH0\r] Apr 02 12:33:50.58:
[18700]: --> [2:OK] Apr 02 12:33:50.59: [18700]: SESSION END


******************
tts:1049258030
killtime:1049268541
retrytime:0
state:8
npages:0
totpages:0
ntries:1
ndials:0
totdials:2
maxdials:12
tottries:1
maxtries:3
pagewidth:215
resolution:196
pagelength:279
priority:127
schedpri:126
minsp:0
desiredbr:5
desiredst:0
desiredec:1
desireddf:3
desiredtl:0
useccover:1
external:65032683
number:65032683 mailaddr:http://somewebsiteurl/jakdslf
sender:Test
jobid:1487
jobtag:order479841931
pagehandling:
modem:any
receiver:Test
company:
location:
cover:
client:localhost
owner:root
groupid:1487
signalrate:14400 bit/s
dataformat:1-D MR
jobtype:facsimile
tagline:
subaddr:
passwd:
doneop:default
commid:00002181
status:Internal botch; bad post-page handling string "" notify:when
done+requeued pagechop:default chopthreshold:3
!postscript:0::docq/doc1497.ps

____________________ 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@hylafax.org < /dev/null
  *To learn about commercial HylaFAX(tm) support, mail sales@hylafax.org.*