HylaFAX The world's most advanced open source fax server

[Date Prev][Date Next][Thread Prev][Thread Next] [Date Index] [Thread Index]

Re: [hylafax-users] Trouble receiving faxes from a Brother MFC-440CN



Here is more verbose logging. To recap, my problem is difficulty receiving faxes from a Brother MFC-440CN.

Sep 04 10:01:40.72: [ 3424]: SESSION BEGIN 000007695 +19195809200
Sep 04 10:01:40.72: [ 3424]: HylaFAX (tm) Version 5.1.8
Sep 04 10:01:40.72: [ 3424]: MODEM set XON/XOFF/FLUSH: input ignored, output disabled
Sep 04 10:01:40.72: [ 3424]: <-- [4:ATA\r]
Sep 04 10:01:46.92: [ 3424]: --> [7:CONNECT]
Sep 04 10:01:46.92: [ 3424]: ANSWER: FAX CONNECTION DEVICE '/dev/ttyS4'
Sep 04 10:01:46.92: [ 3424]: STATE CHANGE: ANSWERING -> RECEIVING
Sep 04 10:01:46.92: [ 3424]: RECV FAX: begin
Sep 04 10:01:46.92: [ 3424]: <-- HDLC<32:FF C0 04 B5 00 AA 12 9E 36 86 62 82 1A 04 14 2E B6 94 04 6A A6 4E CE 96 F6 76 04 AC 74 8C 74 1C>
Sep 04 10:01:46.93: [ 3424]: --> [7:CONNECT]
Sep 04 10:01:46.93: [ 3424]: <-- HDLC<23:FF C0 02 B2 C2 C2 A2 04 04 04 04 04 04 04 04 04 04 04 04 04 04 04 04>
Sep 04 10:01:46.93: [ 3424]: --> [7:CONNECT]
Sep 04 10:01:46.93: [ 3424]: <-- HDLC<13:FF C8 01 00 77 5F 23 01 FB C1 01 01 18>
Sep 04 10:01:49.16: [ 3424]: --> [2:OK]
Sep 04 10:01:49.16: [ 3424]: <-- [9:AT+FRH=3\r]
Sep 04 10:01:49.44: [ 3424]: --> [7:CONNECT]
Sep 04 10:01:51.17: [ 3424]: --> HDLC<25:FF C0 C2 0C 0C 4C 9C 0C 1C AC 9C 8C 9C 04 04 04 04 04 04 04 04 04 04 F9 C9>
Sep 04 10:01:51.17: [ 3424]: --> [2:OK]
Sep 04 10:01:51.17: [ 3424]: RECV recv TSI (sender id)
Sep 04 10:01:51.17: [ 3424]: REMOTE TSI "9195809200"
Sep 04 10:01:51.17: [ 3424]: <-- [9:AT+FRH=3\r]
Sep 04 10:01:51.18: [ 3424]: --> [7:CONNECT]
Sep 04 10:01:51.61: [ 3424]: --> HDLC<15:FF C8 C1 00 61 1F 01 01 01 01 01 01 00 69 27>
Sep 04 10:01:51.61: [ 3424]: --> [2:OK]
Sep 04 10:01:51.61: [ 3424]: RECV recv DCS (command signal)
Sep 04 10:01:51.61: [ 3424]: REMOTE wants 9600 bit/s
Sep 04 10:01:51.61: [ 3424]: REMOTE wants A4 page width (215 mm)
Sep 04 10:01:51.61: [ 3424]: REMOTE wants unlimited page length
Sep 04 10:01:51.61: [ 3424]: REMOTE wants 3.85 line/mm
Sep 04 10:01:51.61: [ 3424]: REMOTE wants 2-D MR
Sep 04 10:01:51.61: [ 3424]: RECV training at v.29 9600 bit/s
Sep 04 10:01:51.61: [ 3424]: MODEM set XON/XOFF/DRAIN: input ignored, output generated
Sep 04 10:01:51.61: [ 3424]: <-- [10:AT+FRM=96\r]
Sep 04 10:01:51.98: [ 3424]: --> [7:CONNECT]
Sep 04 10:01:53.47: [ 3424]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled
Sep 04 10:01:53.47: [ 3424]: RECV: TCF 1792 bytes, 0% non-zero, 1773 zero-run
Sep 04 10:01:53.47: [ 3424]: --> [10:NO CARRIER]
Sep 04 10:01:53.47: [ 3424]: <-- [9:AT+FRS=7\r]
Sep 04 10:01:53.55: [ 3424]: --> [2:OK]
Sep 04 10:01:53.55: [ 3424]: TRAINING succeeded
Sep 04 10:01:53.55: [ 3424]: <-- [9:AT+FTH=3\r]
Sep 04 10:01:54.40: [ 3424]: --> [7:CONNECT]
Sep 04 10:01:54.40: [ 3424]: <-- HDLC<3:FF C8 21>
Sep 04 10:01:54.73: [ 3424]: --> [2:OK]
Sep 04 10:01:54.73: [ 3424]: <-- [10:AT+FRM=96\r]
Sep 04 10:01:55.65: [ 3424]: --> [7:CONNECT]
Sep 04 10:01:55.65: [ 3424]: MODEM input buffering enabled
Sep 04 10:01:55.65: [ 3424]: MODEM set XON/XOFF/FLUSH: input ignored, output generated
Sep 04 10:01:55.65: [ 3424]: RECV: begin page
Sep 04 10:02:15.38: [ 3424]: RECV/CQ: Bad 1D pixel count, row 1100, got 0, expected 1728
Sep 04 10:02:15.38: [ 3424]: RECV/CQ: Bad 1D pixel count, row 1101, got 0, expected 1728
Sep 04 10:02:15.38: [ 3424]: RECV/CQ: Bad 1D pixel count, row 1102, got 0, expected 1728
Sep 04 10:02:15.38: [ 3424]: RECV/CQ: Bad 1D pixel count, row 1103, got 0, expected 1728
Sep 04 10:02:15.56: [ 3424]: RECV/CQ: Adjusting for RTC found at row 1100
Sep 04 10:02:15.57: [ 3424]: RECV: 1100 total lines, 0 bad lines, 0 consecutive bad lines
Sep 04 10:02:15.57: [ 3424]: RECV: end page
Sep 04 10:02:15.57: [ 3424]: --> [10:NO CARRIER]
Sep 04 10:02:15.57: [ 3424]: <-- [9:AT+FRH=3\r]
Sep 04 10:02:15.75: [ 3424]: --> [7:CONNECT]
Sep 04 10:02:16.84: [ 3424]: --> HDLC<5:FF C8 F2 AC A0>
Sep 04 10:02:16.84: [ 3424]: --> [2:OK]
Sep 04 10:02:16.84: [ 3424]: RECV recv MPS (more pages, same document)
Sep 04 10:02:16.84: [ 3424]: <-- [9:AT+FRS=7\r]
Sep 04 10:02:17.02: [ 3424]: --> [2:OK]
Sep 04 10:02:17.02: [ 3424]: RECV send MCF (message confirmation)
Sep 04 10:02:17.02: [ 3424]: <-- [9:AT+FTH=3\r]
Sep 04 10:02:17.97: [ 3424]: --> [7:CONNECT]
Sep 04 10:02:17.97: [ 3424]: RECV FAX (000007695): from 9195809200, page 1 in 0:29, INF, 3.85 line/mm, 2-D MR, 9600 bit/s
Sep 04 10:02:17.97: [ 3424]: <-- HDLC<3:FF C8 31>
Sep 04 10:02:18.40: [ 3424]: --> [2:OK]
Sep 04 10:02:18.40: [ 3424]: <-- [10:AT+FRM=96\r]
Sep 04 10:02:19.31: [ 3424]: --> [7:CONNECT]
Sep 04 10:02:19.31: [ 3424]: MODEM set XON/XOFF/FLUSH: input ignored, output generated
Sep 04 10:02:19.31: [ 3424]: RECV: begin page
Sep 04 10:02:35.84: [ 3424]: RECV/CQ: Bad 1D pixel count, row 1071, got 0, expected 1728
Sep 04 10:02:35.84: [ 3424]: RECV/CQ: Bad 1D pixel count, row 1072, got 0, expected 1728
Sep 04 10:02:35.84: [ 3424]: RECV/CQ: Bad 1D pixel count, row 1073, got 0, expected 1728
Sep 04 10:02:35.84: [ 3424]: RECV/CQ: Bad 1D pixel count, row 1074, got 0, expected 1728
Sep 04 10:02:36.02: [ 3424]: RECV/CQ: Adjusting for RTC found at row 1071
Sep 04 10:02:36.02: [ 3424]: RECV: 1071 total lines, 0 bad lines, 0 consecutive bad lines
Sep 04 10:02:36.02: [ 3424]: RECV: end page
Sep 04 10:02:36.02: [ 3424]: --> [10:NO CARRIER]
Sep 04 10:02:36.02: [ 3424]: <-- [9:AT+FRH=3\r]
Sep 04 10:02:36.21: [ 3424]: --> [7:CONNECT]
Sep 04 10:02:37.30: [ 3424]: --> HDLC<5:FF C8 F2 AC A0>
Sep 04 10:02:37.30: [ 3424]: --> [2:OK]
Sep 04 10:02:37.30: [ 3424]: RECV recv MPS (more pages, same document)
Sep 04 10:02:37.30: [ 3424]: <-- [9:AT+FRS=7\r]
Sep 04 10:02:37.47: [ 3424]: --> [2:OK]
Sep 04 10:02:37.48: [ 3424]: RECV send MCF (message confirmation)
Sep 04 10:02:37.48: [ 3424]: <-- [9:AT+FTH=3\r]
Sep 04 10:02:38.43: [ 3424]: --> [7:CONNECT]
Sep 04 10:02:38.43: [ 3424]: RECV FAX (000007695): from 9195809200, page 2 in 0:21, INF, 3.85 line/mm, 2-D MR, 9600 bit/s
Sep 04 10:02:38.43: [ 3424]: <-- HDLC<3:FF C8 31>
Sep 04 10:02:38.86: [ 3424]: --> [2:OK]
Sep 04 10:02:38.86: [ 3424]: <-- [10:AT+FRM=96\r]
Sep 04 10:02:39.77: [ 3424]: --> [7:CONNECT]
Sep 04 10:02:39.77: [ 3424]: MODEM set XON/XOFF/FLUSH: input ignored, output generated
Sep 04 10:02:39.77: [ 3424]: RECV: begin page
Sep 04 10:03:06.54: [ 3424]: RECV/CQ: Bad 1D pixel count, row 1071, got 0, expected 1728
Sep 04 10:03:06.54: [ 3424]: RECV/CQ: Bad 1D pixel count, row 1072, got 0, expected 1728
Sep 04 10:03:06.54: [ 3424]: RECV/CQ: Bad 1D pixel count, row 1073, got 0, expected 1728
Sep 04 10:03:06.54: [ 3424]: RECV/CQ: Bad 1D pixel count, row 1074, got 0, expected 1728
Sep 04 10:03:06.64: [ 3424]: RECV/CQ: Adjusting for RTC found at row 1071
Sep 04 10:03:06.64: [ 3424]: RECV: 1071 total lines, 0 bad lines, 0 consecutive bad lines
Sep 04 10:03:06.64: [ 3424]: RECV: end page
Sep 04 10:03:06.64: [ 3424]: --> [10:NO CARRIER]
Sep 04 10:03:06.64: [ 3424]: <-- [9:AT+FRH=3\r]
Sep 04 10:03:06.88: [ 3424]: --> [7:CONNECT]
Sep 04 10:03:08.02: [ 3424]: --> HDLC<5:FF C8 F2 AC A0>
Sep 04 10:03:08.02: [ 3424]: --> [2:OK]
Sep 04 10:03:08.02: [ 3424]: RECV recv MPS (more pages, same document)
Sep 04 10:03:08.02: [ 3424]: <-- [9:AT+FRS=7\r]
Sep 04 10:03:08.19: [ 3424]: --> [2:OK]
Sep 04 10:03:08.19: [ 3424]: RECV send MCF (message confirmation)
Sep 04 10:03:08.19: [ 3424]: <-- [9:AT+FTH=3\r]
Sep 04 10:03:09.15: [ 3424]: --> [7:CONNECT]
Sep 04 10:03:09.15: [ 3424]: RECV FAX (000007695): from 9195809200, page 3 in 0:30, INF, 3.85 line/mm, 2-D MR, 9600 bit/s
Sep 04 10:03:09.15: [ 3424]: <-- HDLC<3:FF C8 31>
Sep 04 10:03:09.58: [ 3424]: --> [2:OK]
Sep 04 10:03:09.58: [ 3424]: <-- [10:AT+FRM=96\r]
Sep 04 10:03:10.49: [ 3424]: --> [7:CONNECT]
Sep 04 10:03:10.49: [ 3424]: MODEM set XON/XOFF/FLUSH: input ignored, output generated
Sep 04 10:03:10.49: [ 3424]: RECV: begin page
Sep 04 10:03:36.62: [ 3424]: RECV/CQ: Bad 1D pixel count, row 1071, got 0, expected 1728
Sep 04 10:03:36.62: [ 3424]: RECV/CQ: Bad 1D pixel count, row 1072, got 0, expected 1728
Sep 04 10:03:36.62: [ 3424]: RECV/CQ: Bad 1D pixel count, row 1073, got 0, expected 1728
Sep 04 10:03:36.62: [ 3424]: RECV/CQ: Bad 1D pixel count, row 1074, got 0, expected 1728
Sep 04 10:03:36.77: [ 3424]: RECV/CQ: Adjusting for RTC found at row 1071
Sep 04 10:03:36.77: [ 3424]: RECV: 1071 total lines, 0 bad lines, 0 consecutive bad lines
Sep 04 10:03:36.77: [ 3424]: RECV: end page
Sep 04 10:03:36.77: [ 3424]: --> [10:NO CARRIER]
Sep 04 10:03:36.77: [ 3424]: <-- [9:AT+FRH=3\r]
Sep 04 10:03:36.95: [ 3424]: --> [7:CONNECT]
Sep 04 10:03:38.04: [ 3424]: --> HDLC<5:FF C8 F2 AC A0>
Sep 04 10:03:38.04: [ 3424]: --> [2:OK]
Sep 04 10:03:38.04: [ 3424]: RECV recv MPS (more pages, same document)
Sep 04 10:03:38.04: [ 3424]: <-- [9:AT+FRS=7\r]
Sep 04 10:03:38.22: [ 3424]: --> [2:OK]
Sep 04 10:03:38.22: [ 3424]: RECV send MCF (message confirmation)
Sep 04 10:03:38.22: [ 3424]: <-- [9:AT+FTH=3\r]
Sep 04 10:03:39.18: [ 3424]: --> [7:CONNECT]
Sep 04 10:03:39.18: [ 3424]: RECV FAX (000007695): from 9195809200, page 4 in 0:30, INF, 3.85 line/mm, 2-D MR, 9600 bit/s
Sep 04 10:03:39.18: [ 3424]: <-- HDLC<3:FF C8 31>
Sep 04 10:03:39.61: [ 3424]: --> [2:OK]
Sep 04 10:03:39.61: [ 3424]: <-- [10:AT+FRM=96\r]
Sep 04 10:03:40.54: [ 3424]: --> [7:CONNECT]
Sep 04 10:03:40.54: [ 3424]: MODEM set XON/XOFF/FLUSH: input ignored, output generated
Sep 04 10:03:40.54: [ 3424]: RECV: begin page
Sep 04 10:04:06.53: [ 3424]: RECV: 796 total lines, 0 bad lines, 0 consecutive bad lines
Sep 04 10:04:06.53: [ 3424]: RECV: end page
Sep 04 10:04:06.53: [ 3424]: --> [10:NO CARRIER]
Sep 04 10:04:06.53: [ 3424]: <-- [9:AT+FRH=3\r]
Sep 04 10:04:06.84: [ 3424]: --> [7:CONNECT]
Sep 04 10:04:16.84: [ 3424]: MODEM TIMEOUT: receiving HDLC frame data
Sep 04 10:04:16.95: [ 3424]: --> [2:]
Sep 04 10:04:16.95: [ 3424]: --> [2:OK]
Sep 04 10:04:16.95: [ 3424]: RECV keeping unconfirmed page
Sep 04 10:04:16.95: [ 3424]: RECV FAX (000007695): from 9195809200, page 5 in 0:30, INF, 3.85 line/mm, 2-D MR, 9600 bit/s
Sep 04 10:04:17.95: [ 3424]: RECV FAX (000007695): recvq/fax000003347.tif from 9195809200, subaddress <unspecified>, 5 pages in 2:30
Sep 04 10:04:17.95: [ 3424]: RECV FAX: T.30 T2 timeout, expected page not received {E111}
Sep 04 10:04:17.95: [ 3424]: <-- [9:AT+FTH=3\r]
Sep 04 10:04:18.91: [ 3424]: --> [7:CONNECT]
Sep 04 10:04:18.91: [ 3424]: <-- HDLC<3:FF C8 5F>
Sep 04 10:04:19.34: [ 3424]: --> [2:OK]
Sep 04 10:04:19.34: [ 3424]: RECV FAX (000007695): session with 9195809200 terminated abnormally: T.30 T2 timeout, expected page not received {E111}
Sep 04 10:04:19.34: [ 3424]: RECV FAX: bin/faxrcvd 'recvq/fax000003347.tif' 'ttyS4' '000007695' 'T.30 T2 timeout, expected page not received {E111}'
Sep 04 10:04:19.34: [ 3424]: RECV FAX: end
Sep 04 10:04:19.34: [ 3424]: SESSION END


Davie Lynn
IT Administrator
East Carolina Case Management, Inc.
1505 E. Ash St.
Goldsboro, NC 27530
Ph: (919) 580-9500 x 4701
Fx: (919) 580-9200



Lee Howard wrote:
Davie Lynn wrote:

Aug 28 19:59:03.01: [ 3467]: SESSION BEGIN 000007146 19195809200
Aug 28 19:59:03.01: [ 3467]: HylaFAX (tm) Version 4.4.1
Aug 28 19:59:09.22: [ 3467]: ANSWER: FAX CONNECTION DEVICE '/dev/ttyS4'
Aug 28 19:59:37.59: [ 3467]: RECV FAX (000007146): from 9195809200, page 1 in 0:26, INF, 3.85 line/mm, 2-D MR, 9600 bit/s
Aug 28 19:59:49.90: [ 3467]: RECV FAX (000007146): from 9195809200, page 2 in 0:12, INF, 3.85 line/mm, 2-D MR, 9600 bit/s
Aug 28 20:00:08.41: [ 3467]: RECV FAX (000007146): from 9195809200, page 3 in 0:19, INF, 3.85 line/mm, 2-D MR, 9600 bit/s
Aug 28 20:00:38.04: [ 3467]: RECV FAX (000007146): from 9195809200, page 4 in 0:29, INF, 3.85 line/mm, 2-D MR, 9600 bit/s
Aug 28 20:01:07.19: [ 3467]: RECV FAX (000007146): from 9195809200, page 5 in 0:29, INF, 3.85 line/mm, 2-D MR, 9600 bit/s
Aug 28 20:01:39.04: [ 3467]: RECV FAX (000007146): recvq/fax000003203.tif from 9195809200, route to <unspecified>, 6 pages in 2:30
Aug 28 20:01:39.04: [ 3467]: RECV FAX (000007146): from 9195809200, page 6 in 0:24, INF, 3.85 line/mm, 2-D MR, 9600 bit/s
Aug 28 20:01:40.43: [ 3467]: RECV FAX (000007146): session with 9195809200 terminated abnormally: T.30 T2 timeout, expected page not received
Aug 28 20:01:40.43: [ 3467]: RECV FAX: bin/faxrcvd "recvq/fax000003203.tif" "ttyS4" "000007146" "T.30 T2 timeout, expected page not received"
Aug 28 20:01:40.43: [ 3467]: SESSION END


This logging isn't verbose enough for us to help you. Please set SessionTracing in your modem config file to 0xFFF, restart faxgetty, and reproduce the error. Then show us the associated session log.

Thanks,

Lee.



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




Project hosted by iFAX Solutions