[hylafax-users] Assertion failed "Invalid Str[] index", file "../util/Str.h" line 109.

[Davide Pagnin <pagnin@xxxxxxxxxxxxxx>]

	Hi All

I've run into this problem and after searching on the list, I've found 
no useful hint of the possible problem.

We are using hylafax 4.2.1, a MultitechISI ISI5634PCI/8 fax board, on
Red Hat Enterprise Linux 3.0 update 5.
All the 8 lines are set up and running through faxgetty as ttyM1a-h as 
class 1 modem, with the default configuration proposed by faxsetup.

(this is from /var/log/messages)

Jul 26 15:13:24 srvfax FaxSend[8247]: MODEM LT V.92 1.0 ISI5634PCI 
DATA/FAX MODEM VERSION 4.32D LT V.92 1.0 ISI5634PCI Data/Fax Modem 
Version 4.32d/
Jul 26 15:13:24 srvfax FaxSend[8247]: SEND FAX: JOB 515 DEST 0292112519 
COMMID 000000981 DEVICE '/dev/ttyM1b'
Jul 26 15:14:08 srvfax FaxSend[8247]: SEND FAX: JOB 515 SENT in 0:15
Jul 26 15:14:36 srvfax FaxSend[8247]: Assertion failed "Invalid Str[] 
index", file "../util/Str.h" line 109.

(this is from /var/spool/hylafax/doneq)
tts:1122383677
killtime:1122394333
retrytime:0
state:8
npages:3
totpages:2
ntries:0
ndials:0
totdials:1
maxdials:12
tottries:1
maxtries:3
pagewidth:209
resolution:196
pagelength:296
priority:127
schedpri:127
minbr:0
desiredbr:13
desiredst:0
desiredec:2
desireddf:3
desiredtl:0
useccover:1
usexvres:0
external:<removed>
number:<removed>
mailaddr:<removed>
sender:<removed>
jobid:515
jobtag:000000000000478
pagehandling:
modem:any
receiver:
company:<removed>
location:
voice:<removed>
fromcompany:000000000000478
fromlocation:
fromvoice:
regarding:Conf. Ordine n.9256/05 Del 25/07/05
comments:
cover:
client:localhost
owner:<removed>
groupid:515
signalrate:
dataformat:
jobtype:facsimile
tagline:
subaddr:
passwd:
doneop:default
commid:000000981
status:Send program terminated abnormally with exit status 0x6
returned:1
notify:none
pagechop:default
chopthreshold:3
!postscript:0::docq/doc515.cover
!postscript:0::docq/doc1004.ps

(I've put <removed> instead on some field because those information are 
reserved)

 (this is from /var/spool/hylafax/log)

lug 26 15:13:24.48: [ 8247]: SESSION BEGIN 000000981 <removed>
lug 26 15:13:24.48: [ 8247]: HylaFAX (tm) Version 4.2.1
lug 26 15:13:24.48: [ 8247]: SEND FAX: JOB 515 DEST <removed> COMMID 
000000981
DEVICE '/dev/ttyM1b'
lug 26 15:13:24.48: [ 8247]: <-- [12:AT+FCLASS=1\r]
lug 26 15:13:24.63: [ 8247]: --> [2:OK]
lug 26 15:13:24.63: [ 8247]: DIAL <removed>
lug 26 15:13:24.63: [ 8247]: <-- [15:ATDT<removed>\r]
lug 26 15:13:50.58: [ 8247]: --> [7:CONNECT]
lug 26 15:13:51.87: [ 8247]: --> [2:OK]
lug 26 15:13:51.87: [ 8247]: REMOTE NSF "AD 00 36 20 00 00 00 00"
lug 26 15:13:51.87: [ 8247]: NSF remote fax equipment: HP
lug 26 15:13:51.87: [ 8247]: <-- [9:AT+FRH=3\r]
lug 26 15:13:51.93: [ 8247]: --> [7:CONNECT]
lug 26 15:13:52.58: [ 8247]: --> [2:OK]
lug 26 15:13:52.58: [ 8247]: REMOTE CSI "<removed>"
lug 26 15:13:52.58: [ 8247]: <-- [9:AT+FRH=3\r]
lug 26 15:13:52.64: [ 8247]: --> [7:CONNECT]
lug 26 15:13:53.01: [ 8247]: --> [2:OK]
lug 26 15:13:53.01: [ 8247]: REMOTE best rate 14400 bit/s
lug 26 15:13:53.01: [ 8247]: REMOTE max A4 page width (215 mm)
lug 26 15:13:53.01: [ 8247]: REMOTE max unlimited page length
lug 26 15:13:53.01: [ 8247]: REMOTE best vres 300 x 300 dpi
lug 26 15:13:53.01: [ 8247]: REMOTE best format 2-D MMR
lug 26 15:13:53.01: [ 8247]: REMOTE supports T.30 Annex A, 256-byte ECM
lug 26 15:13:53.01: [ 8247]: REMOTE best 0 ms/scanline
lug 26 15:13:53.01: [ 8247]: USE 14400 bit/s
lug 26 15:13:53.01: [ 8247]: USE error correction mode
lug 26 15:13:53.01: [ 8247]: USE 0 ms/scanline
lug 26 15:13:53.01: [ 8247]: SEND file "docq/doc515.cover;c1"
lug 26 15:13:53.01: [ 8247]: USE A4 page width (215 mm)
lug 26 15:13:53.01: [ 8247]: USE unlimited page length
lug 26 15:13:53.01: [ 8247]: USE 7.7 line/mm
lug 26 15:13:53.01: [ 8247]: USE 2-D MMR
lug 26 15:13:53.01: [ 8247]: SEND training at v.17 14400 bit/s
lug 26 15:13:53.01: [ 8247]: <-- [9:AT+FTH=3\r]
lug 26 15:13:54.02: [ 8247]: --> [7:CONNECT]
lug 26 15:13:54.02: [ 8247]: <-- data [23]
lug 26 15:13:54.02: [ 8247]: <-- data [2]
lug 26 15:13:54.09: [ 8247]: --> [7:CONNECT]
lug 26 15:13:54.09: [ 8247]: <-- data [7]
lug 26 15:13:54.09: [ 8247]: <-- data [2]
lug 26 15:13:55.28: [ 8247]: --> [2:OK]
lug 26 15:13:55.28: [ 8247]: <-- [9:AT+FTS=7\r]
lug 26 15:13:55.38: [ 8247]: --> [2:OK]
lug 26 15:13:55.38: [ 8247]: <-- [11:AT+FTM=145\r]
lug 26 15:13:55.44: [ 8247]: --> [7:CONNECT]
lug 26 15:13:55.44: [ 8247]: DELAY 400 ms
lug 26 15:13:55.84: [ 8247]: <-- data [1024]
lug 26 15:13:55.84: [ 8247]: <-- data [1024]
lug 26 15:13:55.84: [ 8247]: <-- data [652]
lug 26 15:13:55.84: [ 8247]: <-- data [2]
lug 26 15:13:58.61: [ 8247]: --> [2:OK]
lug 26 15:13:58.61: [ 8247]: <-- [9:AT+FRH=3\r]
lug 26 15:13:58.74: [ 8247]: --> [7:CONNECT]
lug 26 15:13:59.92: [ 8247]: --> [2:OK]
lug 26 15:13:59.92: [ 8247]: TRAINING succeeded
lug 26 15:13:59.92: [ 8247]: SEND begin page
lug 26 15:13:59.93: [ 8247]: SEND EOFB
lug 26 15:13:59.93: [ 8247]: SEND send frame number 0

<skipped>

lug 26 15:13:59.93: [ 8247]: DELAY 200 ms
lug 26 15:14:00.12: [ 8247]: <-- [11:AT+FTM=146\r]
lug 26 15:14:00.18: [ 8247]: --> [7:CONNECT]
lug 26 15:14:00.18: [ 8247]: DELAY 400 ms
lug 26 15:14:00.58: [ 8247]: <-- data [1036]
lug 26 15:14:00.58: [ 8247]: <-- data [1035]
lug 26 15:14:00.58: [ 8247]: <-- data [1035]
lug 26 15:14:00.58: [ 8247]: <-- data [1035]
lug 26 15:14:00.74: [ 8247]: <-- data [1035]
lug 26 15:14:00.74: [ 8247]: <-- data [1031]
lug 26 15:14:00.74: [ 8247]: <-- data [1038]
lug 26 15:14:00.74: [ 8247]: <-- data [640]
lug 26 15:14:00.74: [ 8247]: <-- data [2]
lug 26 15:14:05.03: [ 8247]: --> [2:OK]
lug 26 15:14:05.03: [ 8247]: <-- [9:AT+FTS=7\r]
lug 26 15:14:05.13: [ 8247]: --> [2:OK]
lug 26 15:14:05.13: [ 8247]: <-- [9:AT+FTH=3\r]
lug 26 15:14:06.14: [ 8247]: --> [7:CONNECT]
lug 26 15:14:06.14: [ 8247]: <-- data [7]
lug 26 15:14:06.14: [ 8247]: <-- data [2]
lug 26 15:14:06.69: [ 8247]: --> [2:OK]
lug 26 15:14:06.69: [ 8247]: SEND send PPS (partial page signal)
lug 26 15:14:06.69: [ 8247]: SEND send MPS (more pages, same document)
lug 26 15:14:06.69: [ 8247]: <-- [9:AT+FRH=3\r]
lug 26 15:14:06.83: [ 8247]: --> [7:CONNECT]
lug 26 15:14:08.00: [ 8247]: --> [2:OK]
lug 26 15:14:08.00: [ 8247]: SEND recv MCF (message confirmation)
lug 26 15:14:08.00: [ 8247]: <-- [9:AT+FRS=7\r]
lug 26 15:14:08.13: [ 8247]: --> [2:OK]
lug 26 15:14:08.13: [ 8247]: SEND end page
lug 26 15:14:08.14: [ 8247]: SEND FAX (000000981): FROM <removed> (page 
1 of 2 sent in 0:15)
lug 26 15:14:08.14: [ 8247]: SEND FAX (000000981): FROM <removed> 
(docq/doc515.cover;c1 sent in 0:15)
lug 26 15:14:09.15: [ 8247]: SEND file "docq/doc1004.ps;c1"
lug 26 15:14:09.15: [ 8247]: SEND begin page
lug 26 15:14:09.17: [ 8247]: SEND EOFB
lug 26 15:14:09.17: [ 8247]: SEND send frame number 0

<skipped>

lug 26 15:14:09.18: [ 8247]: DELAY 200 ms
lug 26 15:14:09.37: [ 8247]: <-- [11:AT+FTM=146\r]
lug 26 15:14:09.43: [ 8247]: --> [7:CONNECT]
lug 26 15:14:09.43: [ 8247]: DELAY 400 ms
lug 26 15:14:09.83: [ 8247]: <-- data [1030]

<skipped>

lug 26 15:14:33.13: [ 8247]: --> [2:OK]
lug 26 15:14:33.13: [ 8247]: <-- [9:AT+FTS=9\r]
lug 26 15:14:33.25: [ 8247]: --> [2:OK]
lug 26 15:14:33.25: [ 8247]: <-- [9:AT+FTH=3\r]
lug 26 15:14:34.25: [ 8247]: --> [7:CONNECT]
lug 26 15:14:34.25: [ 8247]: <-- data [7]
lug 26 15:14:34.25: [ 8247]: <-- data [2]
lug 26 15:14:34.78: [ 8247]: --> [2:OK]
lug 26 15:14:34.78: [ 8247]: SEND send PPS (partial page signal)
lug 26 15:14:34.78: [ 8247]: SEND send EOP (no more pages or documents)
lug 26 15:14:34.78: [ 8247]: <-- [9:AT+FRH=3\r]
lug 26 15:14:34.91: [ 8247]: --> [7:CONNECT]
lug 26 15:14:36.08: [ 8247]: --> [2:OK]
lug 26 15:14:36.08: [ 8247]: SEND recv MCF (message confirmation)
lug 26 15:14:36.08: [ 8247]: <-- [9:AT+FRS=7\r]
lug 26 15:14:36.20: [ 8247]: --> [2:OK]
lug 26 15:14:36.20: [ 8247]: SEND end page
lug 26 15:14:36.20: [ 8247]: SEND FAX (000000981): <removed> (page 2 of 
2 sent in 0:27)
lug 26 15:14:36.20: [ 8247]: SEND FAX (000000981): <removed> (page 3 of 
2 sent in 0:00)

The problem (In my understanding) is that there is no "page 3"!!!
(the fax is composed by 2 pages).

2 hours later, I've sent the same fax, at the same phone number, with 
all the exact same field, and is has succeeded without problems (it has 
used another port ttyM1e, put all ports are configured in the same why).

I put only the end of the sending log:

lug 26 17:29:41.64: [ 9898]: SEND send PPS (partial page signal)
lug 26 17:29:41.64: [ 9898]: SEND send EOP (no more pages or documents)
lug 26 17:29:41.64: [ 9898]: <-- [9:AT+FRH=3\r]
lug 26 17:29:41.77: [ 9898]: --> [7:CONNECT]
lug 26 17:29:42.94: [ 9898]: --> [2:OK]
lug 26 17:29:42.94: [ 9898]: SEND recv MCF (message confirmation)
lug 26 17:29:42.94: [ 9898]: <-- [9:AT+FRS=7\r]
lug 26 17:29:43.06: [ 9898]: --> [2:OK]
lug 26 17:29:43.06: [ 9898]: SEND end page
lug 26 17:29:43.06: [ 9898]: SEND FAX (000001020): <removed> (page 2 of 
2 sent in 0:27)
lug 26 17:29:43.06: [ 9898]: SEND FAX (000001020): <removed> 
(docq/doc1024.ps;c1 sent in 0:27)
lug 26 17:29:44.07: [ 9898]: <-- [9:AT+FTH=3\r]
lug 26 17:29:45.07: [ 9898]: --> [7:CONNECT]
lug 26 17:29:45.07: [ 9898]: <-- data [3]
lug 26 17:29:45.07: [ 9898]: <-- data [2]
lug 26 17:29:45.49: [ 9898]: --> [2:OK]
lug 26 17:29:45.49: [ 9898]: <-- [5:ATH0\r]
lug 26 17:29:45.88: [ 9898]: --> [2:OK]
lug 26 17:29:45.88: [ 9898]: SESSION END

I've checked docq/doc1004.ps and docq/doc1024.ps and they are exactly 
the same.

I have really no clue of where this error came from, but perhaps there 
is a subtle bug laying around...


Thanks in advance for any kind of help.

Regards

Davide Pagnin

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