[hylafax-users] log files and tif files don't stay in sequence

To: "Hylafax-Users@Hylafax. Org" <hylafax-users@hylafax.org>
Subject: [hylafax-users] log files and tif files don't stay in sequence
From: "Jeremy Fowler" <jfowler@westrope.com>
Date: Fri, 8 Jun 2001 15:25:46 -0500
I've noticed on occasion the log files and the received .tif files start to go
out of sync with each other. I have a daily cron script that archives all my
received faxes and logs and it sets both log/seqf and recvq/seqf to 1 everyday.
The faxes and logs stay in order and synced initially (example: fax00114.tif &
c00000114); however, sometimes they get out of order. We are in a receive only
environment so the only thing being logged is incoming faxes. This is a minor
issue, but I think it would be easier if log files and tiff filenames were
consecutive and matched. Also I noticed faxgetty doesn't restart with a new
process after every fax, is it supposed to still do this?

It looks like they get out of sync if I get a fax from the same number on the
same port immediately after I received a fax from them:

recvq/fax00114.tif:
    Sender: 316 682 0770
     Pages: 1
   Quality: Normal
      Page: North American Letter
  Received: 2001:06:08 11:53:03
TimeToRecv: 0:14
SignalRate: 9600 bit/s
DataFormat: 2-D MR
  Log File: c00000114
    Device: ttyS3

recvq/fax00115.tif:
    Sender: 316 682 0770
     Pages: 1
   Quality: Normal
      Page: North American Letter
  Received: 2001:06:08 11:53:39
TimeToRecv: 0:32
SignalRate: 9600 bit/s
DataFormat: 2-D MR
  Log File: c00000114
    Device: ttyS3

recvq/fax00116.tif:
    Sender: 316 682 0770
     Pages: 1
   Quality: Normal
      Page: North American Letter
  Received: 2001:06:08 11:54:15
TimeToRecv: 0:35
SignalRate: 9600 bit/s
DataFormat: 2-D MR
  Log File: c00000114
    Device: ttyS3

recvq/fax00117.tif:
    Sender: 316 682 0770
     Pages: 1
   Quality: Normal
      Page: North American Letter
  Received: 2001:06:08 11:54:52
TimeToRecv: 1:12
SignalRate: 9600 bit/s
DataFormat: 2-D MR
  Log File: c00000114
    Device: ttyS3

Here is the log file c00000114:

Jun 08 11:52:48.98: [15250]: SESSION BEGIN 00000114 18168423081
Jun 08 11:52:48.98: [15250]: <-- [22:AT+FCLASS=1&H1&I0&R2A\r]
Jun 08 11:52:54.66: [15250]: --> [7:CONNECT]
Jun 08 11:52:54.66: [15250]: ANSWER: FAX CONNECTION
Jun 08 11:52:54.66: [15250]: RECV FAX: begin
Jun 08 11:52:54.66: [15250]: <-- data [23]
Jun 08 11:52:54.66: [15250]: <-- data [2]
Jun 08 11:52:54.67: [15250]: --> [7:CONNECT]
Jun 08 11:52:54.67: [15250]: <-- data [10]
Jun 08 11:52:54.67: [15250]: <-- data [2]
Jun 08 11:52:56.65: [15250]: --> [2:OK]
Jun 08 11:52:56.65: [15250]: <-- [9:AT+FRH=3\r]
Jun 08 11:52:57.35: [15250]: --> [7:CONNECT]
Jun 08 11:52:58.75: [15250]: --> [2:OK]
Jun 08 11:52:58.75: [15250]: REMOTE TSI "316 682 0770"
Jun 08 11:52:58.75: [15250]: <-- [9:AT+FRH=3\r]
Jun 08 11:52:58.76: [15250]: --> [7:CONNECT]
Jun 08 11:52:59.07: [15250]: --> [2:OK]
Jun 08 11:52:59.07: [15250]: REMOTE wants 9600 bit/s
Jun 08 11:52:59.07: [15250]: REMOTE wants page width 1728 pixels in 215 mm
Jun 08 11:52:59.07: [15250]: REMOTE wants unlimited page length
Jun 08 11:52:59.07: [15250]: REMOTE wants 3.85 line/mm
Jun 08 11:52:59.07: [15250]: REMOTE wants 2-D MR
Jun 08 11:52:59.07: [15250]: RECV training at v.29 9600 bit/s
Jun 08 11:52:59.07: [15250]: <-- [10:AT+FRM=96\r]
Jun 08 11:52:59.42: [15250]: --> [7:CONNECT]
Jun 08 11:53:01.00: [15250]: RECV: TCF 1893 bytes, 4% non-zero, 1800 zero-run
Jun 08 11:53:01.00: [15250]: --> [10:NO CARRIER]
Jun 08 11:53:01.00: [15250]: DELAY 75 ms
Jun 08 11:53:01.08: [15250]: <-- [9:AT+FTH=3\r]
Jun 08 11:53:01.27: [15250]: --> [7:CONNECT]
Jun 08 11:53:01.27: [15250]: <-- data [3]
Jun 08 11:53:01.27: [15250]: <-- data [2]
Jun 08 11:53:02.45: [15250]: --> [2:OK]
Jun 08 11:53:02.45: [15250]: TRAINING succeeded
Jun 08 11:53:02.45: [15250]: <-- [10:AT+FRM=96\r]
Jun 08 11:53:03.37: [15250]: --> [7:CONNECT]
Jun 08 11:53:03.37: [15250]: RECV: begin page
Jun 08 11:53:16.60: [15250]: RECV: 1070 total lines, 0 bad lines, 0 consecutive
bad lines
Jun 08 11:53:16.60: [15250]: RECV: end page
Jun 08 11:53:16.60: [15250]: --> [10:NO CARRIER]
Jun 08 11:53:16.60: [15250]: <-- [9:AT+FRH=3\r]
Jun 08 11:53:16.90: [15250]: --> [7:CONNECT]
Jun 08 11:53:17.79: [15250]: --> [2:OK]
Jun 08 11:53:17.79: [15250]: RECV recv EOM (more documents)
Jun 08 11:53:17.79: [15250]: DELAY 150 ms
Jun 08 11:53:17.94: [15250]: <-- [9:AT+FTH=3\r]
Jun 08 11:53:18.13: [15250]: --> [7:CONNECT]
Jun 08 11:53:18.13: [15250]: <-- data [3]
Jun 08 11:53:18.13: [15250]: <-- data [2]
Jun 08 11:53:19.31: [15250]: --> [2:OK]
Jun 08 11:53:19.31: [15250]: RECV send MCF (message confirmation)
Jun 08 11:53:19.31: [15250]: RECV FAX (00000114): from 316 682 0770, page 1 in
0:17, INF, 3.85 line/mm, 2-D MR, 9600 bit/s
Jun 08 11:53:19.31: [15250]: RECV FAX (00000114): recvq/fax00114.tif from 316
682 0770, route to <unspecified>, 1 pages in 0:25
Jun 08 11:53:19.31: [15250]: <-- [9:AT+FRH=3\r]
Jun 08 11:53:26.31: [15250]: --> [0:]
Jun 08 11:53:26.31: [15250]: MODEM <Empty line>
Jun 08 11:53:26.31: [15250]: <-- data [1]
Jun 08 11:53:26.32: [15250]: --> [2:OK]
Jun 08 11:53:26.32: [15250]: <-- data [23]
Jun 08 11:53:26.32: [15250]: <-- data [2]
Jun 08 11:53:29.32: [15250]: --> [0:]
Jun 08 11:53:29.32: [15250]: MODEM <Empty line>
Jun 08 11:53:29.32: [15250]: DELAY 1500 ms
Jun 08 11:53:30.82: [15250]: <-- [9:AT+FTH=3\r]
Jun 08 11:53:30.85: [15250]: --> [7:CONNECT]
Jun 08 11:53:30.85: [15250]: <-- data [23]
Jun 08 11:53:30.85: [15250]: <-- data [2]
Jun 08 11:53:30.86: [15250]: --> [7:CONNECT]
Jun 08 11:53:30.86: [15250]: <-- data [10]
Jun 08 11:53:30.86: [15250]: <-- data [2]
Jun 08 11:53:32.94: [15250]: --> [2:OK]
Jun 08 11:53:32.94: [15250]: <-- [9:AT+FRH=3\r]
Jun 08 11:53:33.35: [15250]: --> [7:CONNECT]
Jun 08 11:53:35.00: [15250]: --> [2:OK]
Jun 08 11:53:35.00: [15250]: REMOTE TSI "316 682 0770"
Jun 08 11:53:35.00: [15250]: <-- [9:AT+FRH=3\r]
Jun 08 11:53:35.01: [15250]: --> [7:CONNECT]
Jun 08 11:53:35.32: [15250]: --> [2:OK]
Jun 08 11:53:35.32: [15250]: REMOTE wants 9600 bit/s
Jun 08 11:53:35.32: [15250]: REMOTE wants page width 1728 pixels in 215 mm
Jun 08 11:53:35.32: [15250]: REMOTE wants unlimited page length
Jun 08 11:53:35.32: [15250]: REMOTE wants 3.85 line/mm
Jun 08 11:53:35.32: [15250]: REMOTE wants 2-D MR
Jun 08 11:53:35.32: [15250]: RECV training at v.29 9600 bit/s
Jun 08 11:53:35.32: [15250]: <-- [10:AT+FRM=96\r]
Jun 08 11:53:35.68: [15250]: --> [7:CONNECT]
Jun 08 11:53:37.26: [15250]: RECV: TCF 1893 bytes, 4% non-zero, 1800 zero-run
Jun 08 11:53:37.26: [15250]: --> [10:NO CARRIER]
Jun 08 11:53:37.26: [15250]: DELAY 75 ms
Jun 08 11:53:37.34: [15250]: <-- [9:AT+FTH=3\r]
Jun 08 11:53:37.53: [15250]: --> [7:CONNECT]
Jun 08 11:53:37.53: [15250]: <-- data [3]
Jun 08 11:53:37.53: [15250]: <-- data [2]
Jun 08 11:53:38.71: [15250]: --> [2:OK]
Jun 08 11:53:38.71: [15250]: TRAINING succeeded
Jun 08 11:53:38.71: [15250]: <-- [10:AT+FRM=96\r]
Jun 08 11:53:39.65: [15250]: --> [7:CONNECT]
Jun 08 11:53:39.65: [15250]: RECV: begin page
Jun 08 11:53:51.97: [15250]: RECV: 1075 total lines, 0 bad lines, 0 consecutive
bad lines
Jun 08 11:53:51.97: [15250]: RECV: end page
Jun 08 11:53:52.08: [15250]: --> [10:NO CARRIER]
Jun 08 11:53:52.08: [15250]: <-- [9:AT+FRH=3\r]
Jun 08 11:53:52.55: [15250]: --> [7:CONNECT]
Jun 08 11:53:53.21: [15250]: --> [2:OK]
Jun 08 11:53:53.21: [15250]: RECV recv EOM (more documents)
Jun 08 11:53:53.21: [15250]: DELAY 150 ms
Jun 08 11:53:53.36: [15250]: <-- [9:AT+FTH=3\r]
Jun 08 11:53:53.55: [15250]: --> [7:CONNECT]
Jun 08 11:53:53.55: [15250]: <-- data [3]
Jun 08 11:53:53.55: [15250]: <-- data [2]
Jun 08 11:53:54.73: [15250]: --> [2:OK]
Jun 08 11:53:54.73: [15250]: RECV send MCF (message confirmation)
Jun 08 11:53:54.73: [15250]: RECV FAX (00000114): from 316 682 0770, page 1 in
0:35, INF, 3.85 line/mm, 2-D MR, 9600 bit/s
Jun 08 11:53:54.73: [15250]: RECV FAX (00000114): recvq/fax00115.tif from 316
682 0770, route to <unspecified>, 1 pages in 0:35
Jun 08 11:53:54.73: [15250]: <-- [9:AT+FRH=3\r]
Jun 08 11:54:01.73: [15250]: --> [0:]
Jun 08 11:54:01.73: [15250]: MODEM <Empty line>
Jun 08 11:54:01.73: [15250]: <-- data [1]
Jun 08 11:54:01.74: [15250]: --> [2:OK]
Jun 08 11:54:01.74: [15250]: <-- data [23]
Jun 08 11:54:01.74: [15250]: <-- data [2]
Jun 08 11:54:04.74: [15250]: --> [0:]
Jun 08 11:54:04.74: [15250]: MODEM <Empty line>
Jun 08 11:54:04.74: [15250]: DELAY 1500 ms
Jun 08 11:54:06.24: [15250]: <-- [9:AT+FTH=3\r]
Jun 08 11:54:06.27: [15250]: --> [7:CONNECT]
Jun 08 11:54:06.27: [15250]: <-- data [23]
Jun 08 11:54:06.27: [15250]: <-- data [2]
Jun 08 11:54:06.28: [15250]: --> [7:CONNECT]
Jun 08 11:54:06.28: [15250]: <-- data [10]
Jun 08 11:54:06.28: [15250]: <-- data [2]
Jun 08 11:54:08.36: [15250]: --> [2:OK]
Jun 08 11:54:08.36: [15250]: <-- [9:AT+FRH=3\r]
Jun 08 11:54:08.90: [15250]: --> [7:CONNECT]
Jun 08 11:54:10.43: [15250]: --> [2:OK]
Jun 08 11:54:10.43: [15250]: REMOTE TSI "316 682 0770"
Jun 08 11:54:10.43: [15250]: <-- [9:AT+FRH=3\r]
Jun 08 11:54:10.44: [15250]: --> [7:CONNECT]
Jun 08 11:54:10.77: [15250]: --> [2:OK]
Jun 08 11:54:10.77: [15250]: REMOTE wants 9600 bit/s
Jun 08 11:54:10.77: [15250]: REMOTE wants page width 1728 pixels in 215 mm
Jun 08 11:54:10.77: [15250]: REMOTE wants unlimited page length
Jun 08 11:54:10.77: [15250]: REMOTE wants 3.85 line/mm
Jun 08 11:54:10.77: [15250]: REMOTE wants 2-D MR
Jun 08 11:54:10.77: [15250]: RECV training at v.29 9600 bit/s
Jun 08 11:54:10.77: [15250]: <-- [10:AT+FRM=96\r]
Jun 08 11:54:11.12: [15250]: --> [7:CONNECT]
Jun 08 11:54:12.70: [15250]: RECV: TCF 1892 bytes, 4% non-zero, 1800 zero-run
Jun 08 11:54:12.70: [15250]: --> [10:NO CARRIER]
Jun 08 11:54:12.70: [15250]: DELAY 75 ms
Jun 08 11:54:12.78: [15250]: <-- [9:AT+FTH=3\r]
Jun 08 11:54:12.97: [15250]: --> [7:CONNECT]
Jun 08 11:54:12.97: [15250]: <-- data [3]
Jun 08 11:54:12.97: [15250]: <-- data [2]
Jun 08 11:54:14.15: [15250]: --> [2:OK]
Jun 08 11:54:14.15: [15250]: TRAINING succeeded
Jun 08 11:54:14.15: [15250]: <-- [10:AT+FRM=96\r]
Jun 08 11:54:15.11: [15250]: --> [7:CONNECT]
Jun 08 11:54:15.11: [15250]: RECV: begin page
Jun 08 11:54:29.13: [15250]: RECV: 1075 total lines, 0 bad lines, 0 consecutive
bad lines
Jun 08 11:54:29.13: [15250]: RECV: end page
Jun 08 11:54:29.13: [15250]: --> [10:NO CARRIER]
Jun 08 11:54:29.13: [15250]: <-- [9:AT+FRH=3\r]
Jun 08 11:54:29.35: [15250]: --> [7:CONNECT]
Jun 08 11:54:30.29: [15250]: --> [2:OK]
Jun 08 11:54:30.29: [15250]: RECV recv EOM (more documents)
Jun 08 11:54:30.29: [15250]: DELAY 150 ms
Jun 08 11:54:30.44: [15250]: <-- [9:AT+FTH=3\r]
Jun 08 11:54:30.63: [15250]: --> [7:CONNECT]
Jun 08 11:54:30.63: [15250]: <-- data [3]
Jun 08 11:54:30.63: [15250]: <-- data [2]
Jun 08 11:54:31.81: [15250]: --> [2:OK]
Jun 08 11:54:31.81: [15250]: RECV send MCF (message confirmation)
Jun 08 11:54:31.81: [15250]: RECV FAX (00000114): from 316 682 0770, page 1 in
0:37, INF, 3.85 line/mm, 2-D MR, 9600 bit/s
Jun 08 11:54:31.81: [15250]: RECV FAX (00000114): recvq/fax00116.tif from 316
682 0770, route to <unspecified>, 1 pages in 0:37
Jun 08 11:54:31.81: [15250]: <-- [9:AT+FRH=3\r]
Jun 08 11:54:38.81: [15250]: --> [0:]
Jun 08 11:54:38.81: [15250]: MODEM <Empty line>
Jun 08 11:54:38.81: [15250]: <-- data [1]
Jun 08 11:54:38.82: [15250]: --> [2:OK]
Jun 08 11:54:38.82: [15250]: <-- data [23]
Jun 08 11:54:38.82: [15250]: <-- data [2]
Jun 08 11:54:41.82: [15250]: --> [0:]
Jun 08 11:54:41.82: [15250]: MODEM <Empty line>
Jun 08 11:54:41.82: [15250]: DELAY 1500 ms
Jun 08 11:54:43.32: [15250]: <-- [9:AT+FTH=3\r]
Jun 08 11:54:43.35: [15250]: --> [7:CONNECT]
Jun 08 11:54:43.35: [15250]: <-- data [23]
Jun 08 11:54:43.35: [15250]: <-- data [2]
Jun 08 11:54:43.36: [15250]: --> [7:CONNECT]
Jun 08 11:54:43.36: [15250]: <-- data [10]
Jun 08 11:54:43.36: [15250]: <-- data [2]
Jun 08 11:54:45.44: [15250]: --> [2:OK]
Jun 08 11:54:45.44: [15250]: <-- [9:AT+FRH=3\r]
Jun 08 11:54:46.15: [15250]: --> [7:CONNECT]
Jun 08 11:54:47.51: [15250]: --> [2:OK]
Jun 08 11:54:47.51: [15250]: REMOTE TSI "316 682 0770"
Jun 08 11:54:47.51: [15250]: <-- [9:AT+FRH=3\r]
Jun 08 11:54:47.52: [15250]: --> [7:CONNECT]
Jun 08 11:54:47.83: [15250]: --> [2:OK]
Jun 08 11:54:47.83: [15250]: REMOTE wants 9600 bit/s
Jun 08 11:54:47.83: [15250]: REMOTE wants page width 1728 pixels in 215 mm
Jun 08 11:54:47.83: [15250]: REMOTE wants unlimited page length
Jun 08 11:54:47.83: [15250]: REMOTE wants 3.85 line/mm
Jun 08 11:54:47.83: [15250]: REMOTE wants 2-D MR
Jun 08 11:54:47.83: [15250]: RECV training at v.29 9600 bit/s
Jun 08 11:54:47.83: [15250]: <-- [10:AT+FRM=96\r]
Jun 08 11:54:48.19: [15250]: --> [7:CONNECT]
Jun 08 11:54:49.77: [15250]: RECV: TCF 1893 bytes, 4% non-zero, 1800 zero-run
Jun 08 11:54:49.77: [15250]: --> [10:NO CARRIER]
Jun 08 11:54:49.77: [15250]: DELAY 75 ms
Jun 08 11:54:49.85: [15250]: <-- [9:AT+FTH=3\r]
Jun 08 11:54:50.04: [15250]: --> [7:CONNECT]
Jun 08 11:54:50.04: [15250]: <-- data [3]
Jun 08 11:54:50.04: [15250]: <-- data [2]
Jun 08 11:54:51.22: [15250]: --> [2:OK]
Jun 08 11:54:51.22: [15250]: TRAINING succeeded
Jun 08 11:54:51.22: [15250]: <-- [10:AT+FRM=96\r]
Jun 08 11:54:52.16: [15250]: --> [7:CONNECT]
Jun 08 11:54:52.16: [15250]: RECV: begin page
Jun 08 11:55:43.49: [15250]: RECV: 1073 total lines, 0 bad lines, 0 consecutive
bad lines
Jun 08 11:55:43.49: [15250]: RECV: end page
Jun 08 11:55:43.49: [15250]: --> [10:NO CARRIER]
Jun 08 11:55:43.49: [15250]: <-- [9:AT+FRH=3\r]
Jun 08 11:55:43.74: [15250]: --> [7:CONNECT]
Jun 08 11:55:44.62: [15250]: --> [2:OK]
Jun 08 11:55:44.62: [15250]: RECV recv EOP (no more pages or documents)
Jun 08 11:55:44.62: [15250]: DELAY 150 ms
Jun 08 11:55:44.77: [15250]: <-- [9:AT+FTH=3\r]
Jun 08 11:55:44.96: [15250]: --> [7:CONNECT]
Jun 08 11:55:44.96: [15250]: <-- data [3]
Jun 08 11:55:44.96: [15250]: <-- data [2]
Jun 08 11:55:46.14: [15250]: --> [2:OK]
Jun 08 11:55:46.14: [15250]: RECV send MCF (message confirmation)
Jun 08 11:55:46.14: [15250]: RECV FAX (00000114): from 316 682 0770, page 1 in
1:15, INF, 3.85 line/mm, 2-D MR, 9600 bit/s
Jun 08 11:55:46.14: [15250]: RECV FAX (00000114): recvq/fax00117.tif from 316
682 0770, route to <unspecified>, 1 pages in 1:15
Jun 08 11:55:46.14: [15250]: <-- [9:AT+FRH=3\r]
Jun 08 11:55:46.94: [15250]: --> [7:CONNECT]
Jun 08 11:55:47.65: [15250]: --> [2:OK]
Jun 08 11:55:47.65: [15250]: RECV FAX: bin/faxrcvd "recvq/fax00114.tif" "ttyS3"
"00000114" "" ""
Jun 08 11:55:48.24: [15250]: RECV FAX: bin/faxrcvd "recvq/fax00115.tif" "ttyS3"
"00000114" "" ""
Jun 08 11:55:48.85: [15250]: RECV FAX: bin/faxrcvd "recvq/fax00116.tif" "ttyS3"
"00000114" "" ""
Jun 08 11:55:49.46: [15250]: RECV FAX: bin/faxrcvd "recvq/fax00117.tif" "ttyS3"
"00000114" "" ""
Jun 08 11:55:50.18: [15250]: RECV FAX: end
Jun 08 11:55:50.18: [15250]: SESSION END



____________________ HylaFAX(tm) Users Mailing List _______________________
 To unsub: mail -s unsubscribe hylafax-users-request@hylafax.org < /dev/null
Follow-Ups:
- Re: [hylafax-users] log files and tif files don't stay in sequence
  - From: Giulio Orsero
Prev by Date: [hylafax-users] Fax server reports ok, however no fax appears at destination
Next by Date: Re: [hylafax-users] modem cannot be detect automatically
Previous by thread: Re: [hylafax-users] Fax server reports ok, however no fax appears at destination
Next by thread: Re: [hylafax-users] log files and tif files don't stay in sequence
Index(es):
- Main
- Thread
Report any problems to webmaster@hylafax.org

HylaFAX is a trademark of Silicon Graphics Corporation.
Internet connectivity for hylafax.org is provided by:
VirtuALL Private Host Services