Hi
Arun Dev wrote:
>
> My hylafax server runs successfully, until I realized that only the
> first page of a multiple page fax gets printed!
>
> The logs look OK to me, the fax machine on the sending side
> (a conventional one) says everything is OK too.
> ...
You guys need more details I suppose. Hier comes the log file.
I've erased the actual tel. number and deleted some data lines
(markted [...]) which I believe unimportant.
The tif file created is a clean *one* page rather than 5.
I am using a PCI (analog) modem on a good phone line.
Appreciate your help!
Arun
=======================================================================
Aug 09 11:02:42.33: [ 148]: SESSION BEGIN 00000248 41523383835
Aug 09 11:02:42.33: [ 148]: HylaFAX (tm) Version 4.1.5
Aug 09 11:02:42.33: [ 148]: MODEM set XON/XOFF/FLUSH: input ignored,
output disabled
Aug 09 11:02:42.33: [ 148]: <-- [4:ATA\r]
Aug 09 11:02:48.32: [ 148]: --> [7:CONNECT]
Aug 09 11:02:48.32: [ 148]: ANSWER: FAX CONNECTION DEVICE '/dev/modem'
Aug 09 11:02:48.32: [ 148]: STATE CHANGE: ANSWERING -> RECEIVING
Aug 09 11:02:48.32: [ 148]: MODEM input buffering enabled
Aug 09 11:02:48.32: [ 148]: RECV FAX: begin
Aug 09 11:02:48.33: [ 148]: MODEM input buffering disabled
Aug 09 11:02:48.33: [ 148]: <-- HDLC<23:FF C0 02 AC CC 04 1C CC 04 1C
CC CC 04 4C AC 04 8C 2C D4 04 1E 86 62>
Aug 09 11:02:48.33: [ 148]: <-- data [23]
Aug 09 11:02:48.33: [ 148]: <-- data [2]
Aug 09 11:02:48.35: [ 148]: --> [7:CONNECT]
Aug 09 11:02:48.35: [ 148]: <-- HDLC<10:FF C8 01 00 77 5F 01 79 03 C0>
Aug 09 11:02:48.35: [ 148]: <-- data [10]
Aug 09 11:02:48.35: [ 148]: <-- data [2]
Aug 09 11:02:49.56: [ 148]: --> [2:OK]
Aug 09 11:02:49.56: [ 148]: <-- [9:AT+FRH=3\r]
Aug 09 11:02:49.70: [ 148]: --> [7:CONNECT]
Aug 09 11:02:51.40: [ 148]: --> HDLC<25:FF C0 C5 04 04 04 04 04 04 04
04 04 04 04 04 04 04 04 04 04 04 04 04 80 E9>
Aug 09 11:02:51.40: [ 148]: --> [2:OK]
Aug 09 11:02:51.40: [ 148]: REMOTE PWD ""
Aug 09 11:02:51.40: [ 148]: <-- [9:AT+FRH=3\r]
Aug 09 11:02:51.43: [ 148]: --> [7:CONNECT]
Aug 09 11:02:52.13: [ 148]: --> HDLC<25:FF C0 C2 6C AC 04 AC 9C 04 4C
0C 4C 04 4C AC 04 8C 2C D4 04 04 04 04 7F C8>
Aug 09 11:02:52.13: [ 148]: --> [2:OK]
Aug 09 11:02:52.13: [ 148]: REMOTE TSI "+41 52 202 95 56"
Aug 09 11:02:52.13: [ 148]: <-- [9:AT+FRH=3\r]
Aug 09 11:02:52.16: [ 148]: --> [7:CONNECT]
Aug 09 11:02:52.43: [ 148]: --> HDLC<9:FF C8 C1 00 62 15 00 88 56>
Aug 09 11:02:52.43: [ 148]: --> [2:OK]
Aug 09 11:02:52.43: [ 148]: REMOTE wants 9600 bit/s
Aug 09 11:02:52.43: [ 148]: REMOTE wants page width 1728 pixels in 215 mm
Aug 09 11:02:52.43: [ 148]: REMOTE wants unlimited page length
Aug 09 11:02:52.43: [ 148]: REMOTE wants 7.7 line/mm
Aug 09 11:02:52.43: [ 148]: REMOTE wants 1-D MR
Aug 09 11:02:52.43: [ 148]: RECV training at v.29 9600 bit/s
Aug 09 11:02:52.43: [ 148]: MODEM set XON/XOFF/DRAIN: input ignored,
output generated
Aug 09 11:02:52.43: [ 148]: <-- [10:AT+FRM=96\r]
Aug 09 11:02:52.83: [ 148]: --> [7:CONNECT]
Aug 09 11:02:54.33: [ 148]: MODEM set XON/XOFF/DRAIN: input ignored,
output disabled
Aug 09 11:02:54.33: [ 148]: RECV: TCF 1792 bytes, 0% non-zero, 1779
zero-run
Aug 09 11:02:54.33: [ 148]: --> [10:NO CARRIER]
Aug 09 11:02:54.33: [ 148]: DELAY 75 ms
Aug 09 11:02:54.41: [ 148]: TRAINING succeeded
Aug 09 11:02:54.41: [ 148]: <-- [9:AT+FTH=3\r]
Aug 09 11:02:55.29: [ 148]: --> [7:CONNECT]
Aug 09 11:02:55.29: [ 148]: <-- HDLC<3:FF C8 21>
Aug 09 11:02:55.29: [ 148]: <-- data [3]
Aug 09 11:02:55.29: [ 148]: <-- data [2]
Aug 09 11:02:55.65: [ 148]: --> [2:OK]
Aug 09 11:02:55.65: [ 148]: MODEM input buffering enabled
Aug 09 11:02:55.65: [ 148]: MODEM set XON/XOFF/FLUSH: input ignored,
output generated
Aug 09 11:02:55.65: [ 148]: <-- [10:AT+FRM=96\r]
Aug 09 11:02:56.16: [ 148]: --> [7:CONNECT]
Aug 09 11:02:56.16: [ 148]: RECV: begin page
Aug 09 11:04:13.22: [ 148]: RECV/CQ: Bad 1D pixel count, row 2279, got
0, expected 1728
Aug 09 11:04:13.22: [ 148]: RECV/CQ: Bad 1D pixel count, row 2280, got
0, expected 1728
Aug 09 11:04:13.22: [ 148]: RECV/CQ: Bad 1D pixel count, row 2281, got
0, expected 1728
Aug 09 11:04:13.22: [ 148]: RECV/CQ: Bad 1D pixel count, row 2282, got
0, expected 1728
Aug 09 11:04:13.22: [ 148]: RECV/CQ: Bad 1D pixel count, row 2283, got
0, expected 1728
Aug 09 11:04:13.22: [ 148]: RECV/CQ: Adjusting for RTC found at row 2278
Aug 09 11:04:13.22: [ 148]: RECV: 2278 total lines, 0 bad lines, 0
consecutive bad lines
Aug 09 11:04:13.22: [ 148]: RECV: end page
Aug 09 11:04:13.22: [ 148]: --> [10:NO CARRIER]
Aug 09 11:04:13.22: [ 148]: MODEM set XON/XOFF/DRAIN: input ignored,
output disabled
Aug 09 11:04:13.22: [ 148]: MODEM input buffering disabled
Aug 09 11:04:13.22: [ 148]: <-- [9:AT+FRH=3\r]
Aug 09 11:04:13.29: [ 148]: --> [7:CONNECT]
Aug 09 11:04:14.35: [ 148]: --> HDLC<5:FF C8 F2 AC A0>
Aug 09 11:04:14.35: [ 148]: --> [2:OK]
Aug 09 11:04:14.35: [ 148]: RECV recv MPS (more pages, same document)
Aug 09 11:04:14.35: [ 148]: <-- [9:AT+FRS=7\r]
Aug 09 11:04:14.50: [ 148]: --> [2:OK]
Aug 09 11:04:14.50: [ 148]: <-- [9:AT+FTH=3\r]
Aug 09 11:04:15.39: [ 148]: --> [7:CONNECT]
Aug 09 11:04:15.39: [ 148]: RECV send MCF (message confirmation)
Aug 09 11:04:15.39: [ 148]: RECV FAX (00000248): from +41 52 202 95 56,
page 1 in 1:21, INF, 7.7 line/mm, 1-D MR, 9600 bit/s
Aug 09 11:04:15.39: [ 148]: <-- HDLC<3:FF C8 31>
Aug 09 11:04:15.39: [ 148]: <-- data [3]
Aug 09 11:04:15.39: [ 148]: <-- data [2]
Aug 09 11:04:15.73: [ 148]: --> [2:OK]
Aug 09 11:04:15.73: [ 148]: MODEM input buffering enabled
Aug 09 11:04:15.73: [ 148]: MODEM set XON/XOFF/FLUSH: input ignored,
output generated
Aug 09 11:04:15.73: [ 148]: <-- [10:AT+FRM=96\r]
Aug 09 11:04:16.25: [ 148]: --> [7:CONNECT]
Aug 09 11:04:16.25: [ 148]: RECV: begin page
Aug 09 11:05:23.23: [ 148]: RECV/CQ: Bad 1D pixel count, row 2293, got
0, expected 1728
Aug 09 11:05:23.36: [ 148]: RECV/CQ: Bad 1D pixel count, row 2294, got
0, expected 1728
Aug 09 11:05:23.36: [ 148]: RECV/CQ: Bad 1D pixel count, row 2295, got
0, expected 1728
Aug 09 11:05:23.36: [ 148]: RECV/CQ: Bad 1D pixel count, row 2296, got
0, expected 1728
Aug 09 11:05:23.36: [ 148]: RECV/CQ: Bad 1D pixel count, row 2297, got
0, expected 1728
Aug 09 11:05:23.36: [ 148]: RECV/CQ: Adjusting for RTC found at row 2292
Aug 09 11:05:23.36: [ 148]: RECV: 2292 total lines, 0 bad lines, 0
consecutive bad lines
Aug 09 11:05:23.36: [ 148]: RECV: end page
Aug 09 11:05:23.36: [ 148]: --> [10:NO CARRIER]
Aug 09 11:05:23.36: [ 148]: MODEM set XON/XOFF/DRAIN: input ignored,
output disabled
Aug 09 11:05:23.36: [ 148]: MODEM input buffering disabled
Aug 09 11:05:23.36: [ 148]: <-- [9:AT+FRH=3\r]
Aug 09 11:05:23.43: [ 148]: --> [7:CONNECT]
Aug 09 11:05:24.49: [ 148]: --> HDLC<5:FF C8 F2 AC A0>
Aug 09 11:05:24.49: [ 148]: --> [2:OK]
Aug 09 11:05:24.49: [ 148]: RECV recv MPS (more pages, same document)
Aug 09 11:05:24.49: [ 148]: <-- [9:AT+FRS=7\r]
Aug 09 11:05:24.65: [ 148]: --> [2:OK]
Aug 09 11:05:24.65: [ 148]: <-- [9:AT+FTH=3\r]
Aug 09 11:05:25.54: [ 148]: --> [7:CONNECT]
Aug 09 11:05:25.54: [ 148]: RECV send MCF (message confirmation)
Aug 09 11:05:25.54: [ 148]: RECV FAX (00000248): from +41 52 202 95 56,
page 2 in 1:10, INF, 7.7 line/mm, 1-D MR, 9600 bit/s
Aug 09 11:05:25.54: [ 148]: <-- HDLC<3:FF C8 31>
Aug 09 11:05:25.54: [ 148]: <-- data [3]
Aug 09 11:05:25.54: [ 148]: <-- data [2]
Aug 09 11:05:25.88: [ 148]: --> [2:OK]
Aug 09 11:05:25.88: [ 148]: MODEM input buffering enabled
Aug 09 11:05:25.88: [ 148]: MODEM set XON/XOFF/FLUSH: input ignored,
output generated
Aug 09 11:05:25.88: [ 148]: <-- [10:AT+FRM=96\r]
Aug 09 11:05:26.40: [ 148]: --> [7:CONNECT]
Aug 09 11:05:26.40: [ 148]: RECV: begin page
Aug 09 11:06:26.10: [ 148]: RECV/CQ: Bad 1D pixel count, row 2306, got
0, expected 1728
Aug 09 11:06:26.10: [ 148]: RECV/CQ: Bad 1D pixel count, row 2307, got
0, expected 1728
Aug 09 11:06:26.10: [ 148]: RECV/CQ: Bad 1D pixel count, row 2308, got
0, expected 1728
Aug 09 11:06:26.10: [ 148]: RECV/CQ: Bad 1D pixel count, row 2309, got
0, expected 1728
Aug 09 11:06:26.10: [ 148]: RECV/CQ: Bad 1D pixel count, row 2310, got
0, expected 1728
Aug 09 11:06:26.10: [ 148]: RECV/CQ: Adjusting for RTC found at row 2305
Aug 09 11:06:26.10: [ 148]: RECV: 2305 total lines, 0 bad lines, 0
consecutive bad lines
Aug 09 11:06:26.10: [ 148]: RECV: end page
Aug 09 11:06:26.10: [ 148]: --> [10:NO CARRIER]
Aug 09 11:06:26.10: [ 148]: MODEM set XON/XOFF/DRAIN: input ignored,
output disabled
Aug 09 11:06:26.10: [ 148]: MODEM input buffering disabled
Aug 09 11:06:26.10: [ 148]: <-- [9:AT+FRH=3\r]
Aug 09 11:06:26.17: [ 148]: --> [7:CONNECT]
Aug 09 11:06:27.23: [ 148]: --> HDLC<5:FF C8 F2 AC A0>
Aug 09 11:06:27.23: [ 148]: --> [2:OK]
Aug 09 11:06:27.23: [ 148]: RECV recv MPS (more pages, same document)
Aug 09 11:06:27.23: [ 148]: <-- [9:AT+FRS=7\r]
Aug 09 11:06:27.39: [ 148]: --> [2:OK]
Aug 09 11:06:27.39: [ 148]: <-- [9:AT+FTH=3\r]
Aug 09 11:06:28.27: [ 148]: --> [7:CONNECT]
Aug 09 11:06:28.27: [ 148]: RECV send MCF (message confirmation)
Aug 09 11:06:28.27: [ 148]: RECV FAX (00000248): from +41 52 202 95 56,
page 3 in 1:03, INF, 7.7 line/mm, 1-D MR, 9600 bit/s
Aug 09 11:06:28.27: [ 148]: <-- HDLC<3:FF C8 31>
Aug 09 11:06:28.27: [ 148]: <-- data [3]
Aug 09 11:06:28.27: [ 148]: <-- data [2]
Aug 09 11:06:28.62: [ 148]: --> [2:OK]
Aug 09 11:06:28.62: [ 148]: MODEM input buffering enabled
Aug 09 11:06:28.62: [ 148]: MODEM set XON/XOFF/FLUSH: input ignored,
output generated
Aug 09 11:06:28.62: [ 148]: <-- [10:AT+FRM=96\r]
Aug 09 11:06:29.14: [ 148]: --> [7:CONNECT]
Aug 09 11:06:29.14: [ 148]: RECV: begin page
Aug 09 11:07:29.12: [ 148]: RECV/CQ: Bad 1D pixel count, row 2280, got
0, expected 1728
Aug 09 11:07:29.12: [ 148]: RECV/CQ: Bad 1D pixel count, row 2281, got
0, expected 1728
Aug 09 11:07:29.12: [ 148]: RECV/CQ: Bad 1D pixel count, row 2282, got
0, expected 1728
Aug 09 11:07:29.12: [ 148]: RECV/CQ: Bad 1D pixel count, row 2283, got
0, expected 1728
Aug 09 11:07:29.12: [ 148]: RECV/CQ: Bad 1D pixel count, row 2284, got
0, expected 1728
Aug 09 11:07:29.12: [ 148]: RECV/CQ: Adjusting for RTC found at row 2279
Aug 09 11:07:29.12: [ 148]: RECV: 2279 total lines, 0 bad lines, 0
consecutive bad lines
Aug 09 11:07:29.12: [ 148]: RECV: end page
Aug 09 11:07:29.12: [ 148]: --> [10:NO CARRIER]
Aug 09 11:07:29.12: [ 148]: MODEM set XON/XOFF/DRAIN: input ignored,
output disabled
Aug 09 11:07:29.12: [ 148]: MODEM input buffering disabled
Aug 09 11:07:29.12: [ 148]: <-- [9:AT+FRH=3\r]
Aug 09 11:07:29.19: [ 148]: --> [7:CONNECT]
Aug 09 11:07:30.25: [ 148]: --> HDLC<5:FF C8 F2 AC A0>
Aug 09 11:07:30.25: [ 148]: --> [2:OK]
Aug 09 11:07:30.25: [ 148]: RECV recv MPS (more pages, same document)
Aug 09 11:07:30.25: [ 148]: <-- [9:AT+FRS=7\r]
Aug 09 11:07:30.40: [ 148]: --> [2:OK]
Aug 09 11:07:30.40: [ 148]: <-- [9:AT+FTH=3\r]
Aug 09 11:07:31.28: [ 148]: --> [7:CONNECT]
Aug 09 11:07:31.28: [ 148]: RECV send MCF (message confirmation)
Aug 09 11:07:31.28: [ 148]: RECV FAX (00000248): from +41 52 202 95 56,
page 4 in 1:03, INF, 7.7 line/mm, 1-D MR, 9600 bit/s
Aug 09 11:07:31.28: [ 148]: <-- HDLC<3:FF C8 31>
Aug 09 11:07:31.28: [ 148]: <-- data [3]
Aug 09 11:07:31.28: [ 148]: <-- data [2]
Aug 09 11:07:31.63: [ 148]: --> [2:OK]
Aug 09 11:07:31.63: [ 148]: MODEM input buffering enabled
Aug 09 11:07:31.63: [ 148]: MODEM set XON/XOFF/FLUSH: input ignored,
output generated
Aug 09 11:07:31.63: [ 148]: <-- [10:AT+FRM=96\r]
Aug 09 11:07:32.14: [ 148]: --> [7:CONNECT]
Aug 09 11:07:32.14: [ 148]: RECV: begin page
Aug 09 11:08:00.83: [ 148]: RECV/CQ: Bad 1D pixel count, row 2278, got
0, expected 1728
Aug 09 11:08:00.83: [ 148]: RECV/CQ: Bad 1D pixel count, row 2279, got
0, expected 1728
Aug 09 11:08:00.83: [ 148]: RECV/CQ: Bad 1D pixel count, row 2280, got
0, expected 1728
Aug 09 11:08:00.83: [ 148]: RECV/CQ: Bad 1D pixel count, row 2281, got
0, expected 1728
Aug 09 11:08:00.83: [ 148]: RECV/CQ: Bad 1D pixel count, row 2282, got
0, expected 1728
Aug 09 11:08:00.83: [ 148]: RECV/CQ: Adjusting for RTC found at row 2277
Aug 09 11:08:00.83: [ 148]: RECV: 2277 total lines, 0 bad lines, 0
consecutive bad lines
Aug 09 11:08:00.83: [ 148]: RECV: end page
Aug 09 11:08:00.83: [ 148]: --> [10:NO CARRIER]
Aug 09 11:08:00.83: [ 148]: MODEM set XON/XOFF/DRAIN: input ignored,
output disabled
Aug 09 11:08:00.83: [ 148]: MODEM input buffering disabled
Aug 09 11:08:00.83: [ 148]: <-- [9:AT+FRH=3\r]
Aug 09 11:08:00.90: [ 148]: --> [7:CONNECT]
Aug 09 11:08:02.06: [ 148]: --> HDLC<5:FF C8 F4 CC 66>
Aug 09 11:08:02.06: [ 148]: --> [2:OK]
Aug 09 11:08:02.06: [ 148]: RECV recv EOP (no more pages or documents)
Aug 09 11:08:02.06: [ 148]: <-- [9:AT+FRS=7\r]
Aug 09 11:08:02.22: [ 148]: --> [2:OK]
Aug 09 11:08:02.22: [ 148]: <-- [9:AT+FTH=3\r]
Aug 09 11:08:03.10: [ 148]: --> [7:CONNECT]
Aug 09 11:08:03.10: [ 148]: <-- HDLC<3:FF C8 31>
Aug 09 11:08:03.10: [ 148]: <-- data [3]
Aug 09 11:08:03.10: [ 148]: <-- data [2]
Aug 09 11:08:03.45: [ 148]: --> [2:OK]
Aug 09 11:08:03.45: [ 148]: RECV send MCF (message confirmation)
Aug 09 11:08:03.45: [ 148]: RECV FAX (00000248): from +41 52 202 95 56,
page 5 in 0:32, INF, 7.7 line/mm, 1-D MR, 9600 bit/s
Aug 09 11:08:03.45: [ 148]: RECV FAX (00000248): recvq/fax00084.tif
from +41 52 202 95 56, route to <unspecified>, 5 pages in 5:15
Aug 09 11:08:03.47: [ 148]: <-- [9:AT+FRH=3\r]
Aug 09 11:08:03.59: [ 148]: --> [7:CONNECT]
Aug 09 11:08:04.76: [ 148]: --> HDLC<5:FF C8 DF 59 6F>
Aug 09 11:08:04.76: [ 148]: --> [2:OK]
Aug 09 11:08:04.76: [ 148]: MODEM input buffering enabled
Aug 09 11:08:04.76: [ 148]: RECV FAX: bin/faxrcvd "recvq/fax00084.tif"
"modem" "00000248" "" "" ""
Aug 09 11:08:07.37: [ 148]: RECV FAX: end
Aug 09 11:08:07.37: [ 148]: SESSION END
===========================================================================