[hylafax-users] capi4hyla: Discrepance between CAPI's (2) and internal (1) number of pages!

["Filippo Carletti" <carletti@xxxxxxxxxx>]

This isn't an hylafax problem, but I'm looking for a broader audience.

I'm using CAPI4HylaFAX 01.02.02 with HylaFAX 4.1.8 and an AVM Fritz PCI.

Sometimes I get the "Discrepance..." error (see subject) and the received
fax tiff is
corrupt.

It happened also when the sender was a remote analog HylaFAX installation
and I was able to
obtain logs from remote (see below).
Fax was one page, sender sent first and only page twice because of RTN
(retrain negative), but capi4hylafax got confused.

I didn't dive into capi4hyla sources, I think I have set tracing at max.
Any hint on what to look for?

Local log from capi4hyla at maximum log level:

Feb 27 10:12:34.80: [19299]: SESSION BEGIN 00000005 +39.0xxxx
Feb 27 10:12:34.80: [19299]: Incoming analog call on controller 1 from xxxx
to yyyy.
Feb 27 10:12:44.20: [19299]: Connection established.
Feb 27 10:12:44.20: [19299]:  StationID = xxx
Feb 27 10:12:44.20: [19299]:  BaudRate  = 14400
Feb 27 10:12:44.20: [19299]:  Flags     = HighRes, MR_compr, No_ECM
Feb 27 10:12:44.20: [19299]: Write fax in path /var/spool/hylafax to file
recvq/fax00003.tif.
Feb 27 10:13:01.85: [19299]: Page 1 was received. - Last Page!
Feb 27 10:13:27.80: [19299]: Discrepance between CAPI's (2) and internal (1)
number of pages!
Feb 27 10:13:27.85: [19299]: Fax received and calling
'/var/spool/hylafax/bin/faxrcvd "recvq/fax00003.tif" "faxCAPI" "00000005" ""
"xxx" "" "xxx"'.
Feb 27 10:13:28.33: [19299]: Connection is droped with reason 0x3400 (No
additional information).
Feb 27 10:13:28.33: [19299]: SESSION END


Remote log (analog modem):

Feb 27 10:12:20.44: [ 7341]: SESSION BEGIN 000000146 390xxxxx
Feb 27 10:12:20.44: [ 7341]: HylaFAX (tm) Version 4.1.8
Feb 27 10:12:20.44: [ 7341]: SEND FAX: JOB 29 DEST 0xxxx COMMID 000000146
DEVICE '/dev/ttyS0'
Feb 27 10:12:20.44: [ 7341]: DELAY 75 ms
Feb 27 10:12:20.52: [ 7341]: DELAY 2600 ms
Feb 27 10:12:24.13: [ 7341]: DELAY 10 ms
Feb 27 10:12:24.14: [ 7341]: <-- [4:ATZ\r]
Feb 27 10:12:25.08: [ 7341]: --> [2:OK]
Feb 27 10:12:25.08: [ 7341]: DELAY 3000 ms
Feb 27 10:12:28.08: [ 7341]: <-- [5:ATE0\r]
Feb 27 10:12:28.09: [ 7341]: --> [4:ATE0]
Feb 27 10:12:28.09: [ 7341]: --> [2:OK]
Feb 27 10:12:28.09: [ 7341]: <-- [5:ATV1\r]
Feb 27 10:12:28.10: [ 7341]: --> [2:OK]
Feb 27 10:12:28.10: [ 7341]: <-- [5:ATQ0\r]
Feb 27 10:12:28.11: [ 7341]: --> [2:OK]
Feb 27 10:12:28.11: [ 7341]: <-- [7:ATS0=0\r]
Feb 27 10:12:28.12: [ 7341]: --> [2:OK]
Feb 27 10:12:28.12: [ 7341]: <-- [7:ATS8=2\r]
Feb 27 10:12:28.13: [ 7341]: --> [2:OK]
Feb 27 10:12:28.13: [ 7341]: <-- [8:ATS7=60\r]
Feb 27 10:12:28.15: [ 7341]: --> [2:OK]
Feb 27 10:12:28.15: [ 7341]: <-- [6:AT&K3\r]
Feb 27 10:12:28.16: [ 7341]: --> [2:OK]
Feb 27 10:12:28.16: [ 7341]: <-- [6:AT&D2\r]
Feb 27 10:12:28.17: [ 7341]: --> [2:OK]
Feb 27 10:12:28.17: [ 7341]: <-- [6:AT&C1\r]
Feb 27 10:12:28.18: [ 7341]: --> [2:OK]
Feb 27 10:12:28.18: [ 7341]: <-- [12:AT+FCLASS=1\r]
Feb 27 10:12:28.20: [ 7341]: --> [2:OK]
Feb 27 10:12:28.20: [ 7341]: <-- [5:ATM0\r]
Feb 27 10:12:28.21: [ 7341]: --> [2:OK]
Feb 27 10:12:28.21: [ 7341]: <-- [12:AT+FCLASS=1\r]
Feb 27 10:12:28.33: [ 7341]: --> [2:OK]
Feb 27 10:12:28.33: [ 7341]: DIAL 0xxxx
Feb 27 10:12:28.33: [ 7341]: <-- [17:ATX3DT0xxxx\r]
Feb 27 10:12:40.16: [ 7341]: --> [7:CONNECT]
Feb 27 10:12:41.03: [ 7341]: --> [2:OK]
Feb 27 10:12:41.03: [ 7341]: REMOTE NSF "20 81 80 80 C0 04 F8"
Feb 27 10:12:41.03: [ 7341]: NSF remote fax equipment: unknown
Feb 27 10:12:41.03: [ 7341]: <-- [9:AT+FRH=3\r]
Feb 27 10:12:41.05: [ 7341]: --> [7:CONNECT]
Feb 27 10:12:41.72: [ 7341]: --> [2:OK]
Feb 27 10:12:41.72: [ 7341]: REMOTE CSI "+39 0xxx xxxxx"
Feb 27 10:12:41.72: [ 7341]: <-- [9:AT+FRH=3\r]
Feb 27 10:12:41.74: [ 7341]: --> [7:CONNECT]
Feb 27 10:12:41.99: [ 7341]: --> [2:OK]
Feb 27 10:12:41.99: [ 7341]: REMOTE best rate 14400 bit/s
Feb 27 10:12:41.99: [ 7341]: REMOTE max page width 1728 pixels in 215 mm
Feb 27 10:12:41.99: [ 7341]: REMOTE max unlimited page length
Feb 27 10:12:41.99: [ 7341]: REMOTE best vres 7.7 line/mm
Feb 27 10:12:41.99: [ 7341]: REMOTE best format 2-D MMR
Feb 27 10:12:41.99: [ 7341]: REMOTE supports T.30 Annex A, ECM
Feb 27 10:12:41.99: [ 7341]: REMOTE best 0 ms/scanline
Feb 27 10:12:41.99: [ 7341]: USE 14400 bit/s
Feb 27 10:12:41.99: [ 7341]: USE 0 ms/scanline
Feb 27 10:12:41.99: [ 7341]: SEND file "docq/doc27.ps;41"
Feb 27 10:12:41.99: [ 7341]: USE page width 1728 pixels in 215 mm
Feb 27 10:12:41.99: [ 7341]: USE unlimited page length
Feb 27 10:12:41.99: [ 7341]: USE 7.7 line/mm
Feb 27 10:12:41.99: [ 7341]: USE 2-D MR
Feb 27 10:12:41.99: [ 7341]: SEND training at v.17 14400 bit/s
Feb 27 10:12:41.99: [ 7341]: <-- [9:AT+FTH=3\r]
Feb 27 10:12:42.04: [ 7341]: --> [7:CONNECT]
Feb 27 10:12:42.04: [ 7341]: <-- data [23]
Feb 27 10:12:42.04: [ 7341]: <-- data [2]
Feb 27 10:12:43.87: [ 7341]: --> [7:CONNECT]
Feb 27 10:12:43.87: [ 7341]: <-- data [6]
Feb 27 10:12:43.87: [ 7341]: <-- data [2]
Feb 27 10:12:44.26: [ 7341]: --> [2:OK]
Feb 27 10:12:44.26: [ 7341]: <-- [9:AT+FTS=7\r]
Feb 27 10:12:44.38: [ 7341]: --> [2:OK]
Feb 27 10:12:44.38: [ 7341]: <-- [11:AT+FTM=145\r]
Feb 27 10:12:44.42: [ 7341]: --> [7:CONNECT]
Feb 27 10:12:44.42: [ 7341]: <-- data [1024]
Feb 27 10:12:44.42: [ 7341]: <-- data [1024]
Feb 27 10:12:44.42: [ 7341]: <-- data [652]
Feb 27 10:12:44.42: [ 7341]: <-- data [2]
Feb 27 10:12:47.53: [ 7341]: --> [2:OK]
Feb 27 10:12:47.53: [ 7341]: <-- [9:AT+FRH=3\r]
Feb 27 10:12:47.97: [ 7341]: --> [7:CONNECT]
Feb 27 10:12:48.81: [ 7341]: --> [2:OK]
Feb 27 10:12:48.81: [ 7341]: TRAINING succeeded
Feb 27 10:12:48.81: [ 7341]: DELAY 75 ms
Feb 27 10:12:48.89: [ 7341]: <-- [11:AT+FTM=146\r]
Feb 27 10:12:48.93: [ 7341]: --> [7:CONNECT]
Feb 27 10:12:48.93: [ 7341]: SEND begin page
Feb 27 10:12:48.93: [ 7341]: <-- data [1027]
Feb 27 10:12:48.93: [ 7341]: <-- data [1037]
Feb 27 10:12:48.93: [ 7341]: <-- data [1031]
Feb 27 10:12:48.93: [ 7341]: <-- data [1030]
Feb 27 10:12:50.93: [ 7341]: <-- data [1032]
Feb 27 10:12:50.93: [ 7341]: <-- data [1037]
Feb 27 10:12:50.93: [ 7341]: <-- data [1032]
Feb 27 10:12:50.93: [ 7341]: <-- data [1037]
Feb 27 10:12:52.92: [ 7341]: <-- data [1035]
Feb 27 10:12:52.92: [ 7341]: <-- data [1037]
Feb 27 10:12:52.92: [ 7341]: <-- data [1030]
Feb 27 10:12:52.92: [ 7341]: <-- data [1033]
Feb 27 10:12:55.04: [ 7341]: <-- data [1036]
Feb 27 10:12:55.04: [ 7341]: <-- data [1037]
Feb 27 10:12:55.04: [ 7341]: <-- data [1033]
Feb 27 10:12:57.04: [ 7341]: <-- data [1033]
Feb 27 10:12:57.04: [ 7341]: <-- data [1028]
Feb 27 10:12:57.04: [ 7341]: <-- data [1034]
Feb 27 10:12:57.04: [ 7341]: <-- data [1030]
Feb 27 10:12:59.04: [ 7341]: <-- data [530]
Feb 27 10:12:59.04: [ 7341]: SENT 19982 bytes of data
Feb 27 10:12:59.04: [ 7341]: SEND 2D RTC
Feb 27 10:12:59.04: [ 7341]: <-- data [30]
Feb 27 10:12:59.04: [ 7341]: <-- data [2]
Feb 27 10:12:59.04: [ 7341]: SEND end page
Feb 27 10:13:00.42: [ 7341]: --> [2:OK]
Feb 27 10:13:00.42: [ 7341]: <-- [9:AT+FTS=9\r]
Feb 27 10:13:00.55: [ 7341]: --> [2:OK]
Feb 27 10:13:00.55: [ 7341]: SEND send EOP (no more pages or documents)
Feb 27 10:13:00.55: [ 7341]: <-- [9:AT+FTH=3\r]
Feb 27 10:13:00.60: [ 7341]: --> [7:CONNECT]
Feb 27 10:13:00.60: [ 7341]: <-- data [3]
Feb 27 10:13:00.60: [ 7341]: <-- data [2]
Feb 27 10:13:01.91: [ 7341]: --> [2:OK]
Feb 27 10:13:01.91: [ 7341]: <-- [9:AT+FRH=3\r]
Feb 27 10:13:02.36: [ 7341]: --> [7:CONNECT]
Feb 27 10:13:03.13: [ 7341]: --> [2:OK]
Feb 27 10:13:03.13: [ 7341]: SEND recv RTN (retrain negative)
Feb 27 10:13:03.13: [ 7341]: SEND training at v.17 12000 bit/s
Feb 27 10:13:03.13: [ 7341]: <-- [9:AT+FTH=3\r]
Feb 27 10:13:03.18: [ 7341]: --> [7:CONNECT]
Feb 27 10:13:03.18: [ 7341]: <-- data [23]
Feb 27 10:13:03.18: [ 7341]: <-- data [2]
Feb 27 10:13:05.02: [ 7341]: --> [7:CONNECT]
Feb 27 10:13:05.02: [ 7341]: <-- data [6]
Feb 27 10:13:05.02: [ 7341]: <-- data [2]
Feb 27 10:13:05.39: [ 7341]: --> [2:OK]
Feb 27 10:13:05.39: [ 7341]: <-- [9:AT+FTS=7\r]
Feb 27 10:13:05.51: [ 7341]: --> [2:OK]
Feb 27 10:13:05.51: [ 7341]: <-- [11:AT+FTM=121\r]
Feb 27 10:13:05.55: [ 7341]: --> [7:CONNECT]
Feb 27 10:13:05.55: [ 7341]: <-- data [1024]
Feb 27 10:13:05.55: [ 7341]: <-- data [1024]
Feb 27 10:13:05.55: [ 7341]: <-- data [202]
Feb 27 10:13:05.55: [ 7341]: <-- data [2]
Feb 27 10:13:08.67: [ 7341]: --> [2:OK]
Feb 27 10:13:08.67: [ 7341]: <-- [9:AT+FRH=3\r]
Feb 27 10:13:09.14: [ 7341]: --> [7:CONNECT]
Feb 27 10:13:09.95: [ 7341]: --> [2:OK]
Feb 27 10:13:09.95: [ 7341]: TRAINING succeeded
Feb 27 10:13:09.95: [ 7341]: DELAY 75 ms
Feb 27 10:13:10.03: [ 7341]: <-- [11:AT+FTM=122\r]
Feb 27 10:13:10.07: [ 7341]: --> [7:CONNECT]
Feb 27 10:13:10.07: [ 7341]: SEND begin page
Feb 27 10:13:10.07: [ 7341]: <-- data [1027]
Feb 27 10:13:10.07: [ 7341]: <-- data [1037]
Feb 27 10:13:10.07: [ 7341]: <-- data [1031]
Feb 27 10:13:10.07: [ 7341]: <-- data [1030]
Feb 27 10:13:12.11: [ 7341]: <-- data [1032]
Feb 27 10:13:12.11: [ 7341]: <-- data [1037]
Feb 27 10:13:12.11: [ 7341]: <-- data [1032]
Feb 27 10:13:12.11: [ 7341]: <-- data [1037]
Feb 27 10:13:13.37: [ 7341]: <-- data [1035]
Feb 27 10:13:13.37: [ 7341]: <-- data [1037]
Feb 27 10:13:14.63: [ 7341]: <-- data [1030]
Feb 27 10:13:15.90: [ 7341]: <-- data [1033]
Feb 27 10:13:15.90: [ 7341]: <-- data [1036]
Feb 27 10:13:17.16: [ 7341]: <-- data [1037]
Feb 27 10:13:17.16: [ 7341]: <-- data [1033]
Feb 27 10:13:18.42: [ 7341]: <-- data [1033]
Feb 27 10:13:18.42: [ 7341]: <-- data [1028]
Feb 27 10:13:19.68: [ 7341]: <-- data [1034]
Feb 27 10:13:19.68: [ 7341]: <-- data [1030]
Feb 27 10:13:20.93: [ 7341]: <-- data [530]
Feb 27 10:13:20.93: [ 7341]: SENT 19982 bytes of data
Feb 27 10:13:20.93: [ 7341]: SEND 2D RTC
Feb 27 10:13:20.93: [ 7341]: <-- data [30]
Feb 27 10:13:20.93: [ 7341]: <-- data [2]
Feb 27 10:13:20.93: [ 7341]: SEND end page
Feb 27 10:13:23.78: [ 7341]: --> [2:OK]
Feb 27 10:13:23.78: [ 7341]: <-- [9:AT+FTS=9\r]
Feb 27 10:13:23.92: [ 7341]: --> [2:OK]
Feb 27 10:13:23.92: [ 7341]: SEND send EOP (no more pages or documents)
Feb 27 10:13:23.92: [ 7341]: <-- [9:AT+FTH=3\r]
Feb 27 10:13:23.97: [ 7341]: --> [7:CONNECT]
Feb 27 10:13:23.97: [ 7341]: <-- data [3]
Feb 27 10:13:23.97: [ 7341]: <-- data [2]
Feb 27 10:13:25.28: [ 7341]: --> [2:OK]
Feb 27 10:13:25.28: [ 7341]: <-- [9:AT+FRH=3\r]
Feb 27 10:13:25.71: [ 7341]: --> [7:CONNECT]
Feb 27 10:13:26.50: [ 7341]: --> [2:OK]
Feb 27 10:13:26.50: [ 7341]: SEND recv MCF (message confirmation)
Feb 27 10:13:26.50: [ 7341]: SEND FAX (000000146): FROM root@xxxxxxxxxxxxxx
TO 0xxxx (page 1 of 1 sent in 0:45)
Feb 27 10:13:26.50: [ 7341]: SEND FAX (000000146): FROM root@xxxxxxxxxxxxxx
TO 0xxxx (docq/doc27.ps;41 sent in 0:45)
Feb 27 10:13:26.50: [ 7341]: <-- [9:AT+FTH=3\r]
Feb 27 10:13:26.55: [ 7341]: --> [7:CONNECT]
Feb 27 10:13:26.55: [ 7341]: <-- data [3]
Feb 27 10:13:26.55: [ 7341]: <-- data [2]
Feb 27 10:13:27.86: [ 7341]: --> [2:OK]
Feb 27 10:13:27.86: [ 7341]: <-- [5:ATH0\r]
Feb 27 10:13:28.55: [ 7341]: --> [2:OK]
Feb 27 10:13:28.55: [ 7341]: SESSION END


Ciao,
Filippo


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