 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] hylafax + iaxmodem recv fax, not successful
- To: "Lee Howard" <faxguy@xxxxxxxxxxxxxxxx>
- Subject: Re: [hylafax-users] hylafax + iaxmodem recv fax, not successful
- From: "Ma Zhiyong" <zhiyong.m@xxxxxxxxx>
- Cc: hylafax-users@xxxxxxxxxxx
- Date: Tue, 29 Aug 2006 10:51:24 +0800
Hi, Lee.
I got two traces from two different fax machine, but both of them failled. Please see the attachments.
Thank you.
btw. I run hylafax-4.2.3-1 on redhat 9. and libtiff-3.5.7-11, iaxmodem-0.1.14, asterisk 1.2.4
In this case as well, the logging is not high enough for us to get any useful information here. Please use SessionTracing at 0xFFF.
Thanks,
Lee.
Aug 28 23:58:15.87: [18663]: SESSION BEGIN 000000041 6499695555 Aug 28 23:58:15.87: [18663]: HylaFAX (tm) Version 4.2.3 Aug 28 23:58:15.88: [18663]: CallID: "0000" "<NONE>" "<NONE>" "6499181595" Aug 28 23:58:15.88: [18663]: MODEM set XON/XOFF/FLUSH: input ignored, output disabled Aug 28 23:58:15.88: [18663]: <-- [4:ATA\r] Aug 28 23:58:19.68: [18663]: --> [7:CONNECT] Aug 28 23:58:19.68: [18663]: ANSWER: FAX CONNECTION DEVICE '/dev/ttyIAX1' Aug 28 23:58:19.68: [18663]: STATE CHANGE: ANSWERING -> RECEIVING Aug 28 23:58:19.68: [18663]: RECV FAX: begin Aug 28 23:58:19.68: [18663]: <-- HDLC<32:FF C0 04 AD 00 55 12 9E 36 86 62 82 1A 04 14 2E B6 94 04 6A A6 4E CE 96 F6 76 04 2C 74 4C 74 CC> Aug 28 23:58:19.68: [18663]: <-- data [32] Aug 28 23:58:19.69: [18663]: <-- data [2] Aug 28 23:58:20.74: [18663]: --> [7:CONNECT] Aug 28 23:58:20.74: [18663]: <-- HDLC<23:FF C0 02 8C B6 A6 26 F6 B6 1A 82 92 04 04 04 04 04 04 04 04 04 04 04> Aug 28 23:58:20.74: [18663]: <-- data [23] Aug 28 23:58:20.74: [18663]: <-- data [2] Aug 28 23:58:21.58: [18663]: --> [7:CONNECT] Aug 28 23:58:21.58: [18663]: <-- HDLC<13:FF C8 01 00 73 5F 23 01 FB C1 01 01 18> Aug 28 23:58:21.58: [18663]: <-- data [13] Aug 28 23:58:21.58: [18663]: <-- data [2] Aug 28 23:58:22.16: [18663]: --> [2:OK] Aug 28 23:58:22.16: [18663]: <-- [9:AT+FRH=3\r] Aug 28 23:58:29.16: [18663]: --> [0:] Aug 28 23:58:29.16: [18663]: MODEM <Empty line> Aug 28 23:58:29.16: [18663]: MODEM TIMEOUT: waiting for v.21 carrier Aug 28 23:58:29.16: [18663]: <-- data [1] Aug 28 23:58:29.17: [18663]: --> [2:OK] Aug 28 23:58:29.17: [18663]: DELAY 1500 ms Aug 28 23:58:30.67: [18663]: <-- [9:AT+FTH=3\r] Aug 28 23:58:30.68: [18663]: --> [7:CONNECT] Aug 28 23:58:30.68: [18663]: <-- HDLC<32:FF C0 04 AD 00 55 12 9E 36 86 62 82 1A 04 14 2E B6 94 04 6A A6 4E CE 96 F6 76 04 2C 74 4C 74 CC> Aug 28 23:58:30.68: [18663]: <-- data [32] Aug 28 23:58:30.68: [18663]: <-- data [2] Aug 28 23:58:32.64: [18663]: --> [7:CONNECT] Aug 28 23:58:32.64: [18663]: <-- HDLC<23:FF C0 02 8C B6 A6 26 F6 B6 1A 82 92 04 04 04 04 04 04 04 04 04 04 04> Aug 28 23:58:32.64: [18663]: <-- data [23] Aug 28 23:58:32.64: [18663]: <-- data [2] Aug 28 23:58:33.46: [18663]: --> [7:CONNECT] Aug 28 23:58:33.46: [18663]: <-- HDLC<13:FF C8 01 00 73 5F 23 01 FB C1 01 01 18> Aug 28 23:58:33.46: [18663]: <-- data [13] Aug 28 23:58:33.46: [18663]: <-- data [2] Aug 28 23:58:34.06: [18663]: --> [2:OK] Aug 28 23:58:34.06: [18663]: <-- [9:AT+FRH=3\r] Aug 28 23:58:35.40: [18663]: --> [7:CONNECT] Aug 28 23:58:36.48: [18663]: --> HDLC<5:FF C8 58 B8 00> Aug 28 23:58:36.61: [18663]: --> [2:OK] Aug 28 23:58:36.61: [18663]: DELAY 1500 ms Aug 28 23:58:38.11: [18663]: <-- [9:AT+FTH=3\r] Aug 28 23:58:38.12: [18663]: --> [7:CONNECT] Aug 28 23:58:38.12: [18663]: <-- HDLC<32:FF C0 04 AD 00 55 12 9E 36 86 62 82 1A 04 14 2E B6 94 04 6A A6 4E CE 96 F6 76 04 2C 74 4C 74 CC> Aug 28 23:58:38.12: [18663]: <-- data [32] Aug 28 23:58:38.12: [18663]: <-- data [2] Aug 28 23:58:40.08: [18663]: --> [7:CONNECT] Aug 28 23:58:40.08: [18663]: <-- HDLC<23:FF C0 02 8C B6 A6 26 F6 B6 1A 82 92 04 04 04 04 04 04 04 04 04 04 04> Aug 28 23:58:40.08: [18663]: <-- data [23] Aug 28 23:58:40.08: [18663]: <-- data [2] Aug 28 23:58:40.90: [18663]: --> [7:CONNECT] Aug 28 23:58:40.90: [18663]: <-- HDLC<13:FF C8 01 00 73 5F 23 01 FB C1 01 01 18> Aug 28 23:58:40.90: [18663]: <-- data [13] Aug 28 23:58:40.90: [18663]: <-- data [2] Aug 28 23:58:41.50: [18663]: --> [2:OK] Aug 28 23:58:41.50: [18663]: <-- [9:AT+FRH=3\r] Aug 28 23:58:43.33: [18663]: --> [7:CONNECT] Aug 28 23:58:44.43: [18663]: --> HDLC<6:FF C8 58 B8 00 7D> Aug 28 23:58:44.43: [18663]: --> [5:ERROR] Aug 28 23:58:44.43: [18663]: MODEM Command error Aug 28 23:58:44.43: [18663]: FCS error Aug 28 23:58:44.43: [18663]: <-- [9:AT+FRS=7\r] Aug 28 23:58:44.60: [18663]: --> [2:OK] Aug 28 23:58:44.60: [18663]: <-- [9:AT+FTH=3\r] Aug 28 23:58:44.62: [18663]: --> [7:CONNECT] Aug 28 23:58:44.62: [18663]: <-- HDLC<3:FF C8 58> Aug 28 23:58:44.62: [18663]: <-- data [3] Aug 28 23:58:44.62: [18663]: <-- data [2] Aug 28 23:58:45.80: [18663]: --> [2:OK] Aug 28 23:58:45.80: [18663]: RECV send CRP (command repeat) Aug 28 23:58:45.80: [18663]: <-- [9:AT+FRH=3\r] Aug 28 23:58:49.40: [18663]: --> [10:NO CARRIER] Aug 28 23:58:49.40: [18663]: MODEM No carrier Aug 28 23:58:49.40: [18663]: DELAY 1500 ms Aug 28 23:58:50.90: [18663]: <-- [9:AT+FTH=3\r] Aug 28 23:58:50.90: [18663]: --> [5:ERROR] Aug 28 23:58:50.90: [18663]: RECV FAX: RSPREC error/got EOT Aug 28 23:58:50.90: [18663]: RECV FAX: end Aug 28 23:58:50.90: [18663]: SESSION END
Aug 29 14:40:21.50: [18663]: SESSION BEGIN 000000042 6499695555 Aug 29 14:40:21.50: [18663]: HylaFAX (tm) Version 4.2.3 Aug 29 14:40:21.50: [18663]: CallID: "0000" "<NONE>" "<NONE>" "6499181595" Aug 29 14:40:21.50: [18663]: MODEM set XON/XOFF/FLUSH: input ignored, output disabled Aug 29 14:40:21.50: [18663]: <-- [4:ATA\r] Aug 29 14:40:25.30: [18663]: --> [7:CONNECT] Aug 29 14:40:25.30: [18663]: ANSWER: FAX CONNECTION DEVICE '/dev/ttyIAX1' Aug 29 14:40:25.30: [18663]: STATE CHANGE: ANSWERING -> RECEIVING Aug 29 14:40:25.31: [18663]: RECV FAX: begin Aug 29 14:40:25.31: [18663]: <-- HDLC<32:FF C0 04 AD 00 55 12 9E 36 86 62 82 1A 04 14 2E B6 94 04 6A A6 4E CE 96 F6 76 04 2C 74 4C 74 CC> Aug 29 14:40:25.31: [18663]: <-- data [32] Aug 29 14:40:25.31: [18663]: <-- data [2] Aug 29 14:40:26.40: [18663]: --> [7:CONNECT] Aug 29 14:40:26.40: [18663]: <-- HDLC<23:FF C0 02 8C B6 A6 26 F6 B6 1A 82 92 04 04 04 04 04 04 04 04 04 04 04> Aug 29 14:40:26.40: [18663]: <-- data [23] Aug 29 14:40:26.40: [18663]: <-- data [2] Aug 29 14:40:27.23: [18663]: --> [7:CONNECT] Aug 29 14:40:27.23: [18663]: <-- HDLC<13:FF C8 01 00 73 5F 23 01 FB C1 01 01 18> Aug 29 14:40:27.23: [18663]: <-- data [13] Aug 29 14:40:27.23: [18663]: <-- data [2] Aug 29 14:40:27.84: [18663]: --> [2:OK] Aug 29 14:40:27.84: [18663]: <-- [9:AT+FRH=3\r] Aug 29 14:40:34.84: [18663]: --> [0:] Aug 29 14:40:34.84: [18663]: MODEM <Empty line> Aug 29 14:40:34.84: [18663]: MODEM TIMEOUT: waiting for v.21 carrier Aug 29 14:40:34.84: [18663]: <-- data [1] Aug 29 14:40:34.85: [18663]: --> [2:OK] Aug 29 14:40:34.85: [18663]: DELAY 1500 ms Aug 29 14:40:36.35: [18663]: <-- [9:AT+FTH=3\r] Aug 29 14:40:36.36: [18663]: --> [7:CONNECT] Aug 29 14:40:36.36: [18663]: <-- HDLC<32:FF C0 04 AD 00 55 12 9E 36 86 62 82 1A 04 14 2E B6 94 04 6A A6 4E CE 96 F6 76 04 2C 74 4C 74 CC> Aug 29 14:40:36.36: [18663]: <-- data [32] Aug 29 14:40:36.37: [18663]: <-- data [2] Aug 29 14:40:38.32: [18663]: --> [7:CONNECT] Aug 29 14:40:38.32: [18663]: <-- HDLC<23:FF C0 02 8C B6 A6 26 F6 B6 1A 82 92 04 04 04 04 04 04 04 04 04 04 04> Aug 29 14:40:38.32: [18663]: <-- data [23] Aug 29 14:40:38.32: [18663]: <-- data [2] Aug 29 14:40:39.14: [18663]: --> [7:CONNECT] Aug 29 14:40:39.14: [18663]: <-- HDLC<13:FF C8 01 00 73 5F 23 01 FB C1 01 01 18> Aug 29 14:40:39.14: [18663]: <-- data [13] Aug 29 14:40:39.14: [18663]: <-- data [2] Aug 29 14:40:39.74: [18663]: --> [2:OK] Aug 29 14:40:39.74: [18663]: <-- [9:AT+FRH=3\r] Aug 29 14:40:41.67: [18663]: --> [7:CONNECT] Aug 29 14:40:43.28: [18663]: --> HDLC<25:FF C0 C2 EC 2C 0C CC 1C 1C 4C 1C 0C 8C 0C 04 04 04 04 04 04 04 04 04 C7 AA> Aug 29 14:40:43.28: [18663]: --> [2:OK] Aug 29 14:40:43.28: [18663]: REMOTE TSI "01082883047" Aug 29 14:40:43.28: [18663]: <-- [9:AT+FRH=3\r] Aug 29 14:40:43.28: [18663]: --> [7:CONNECT] Aug 29 14:40:43.60: [18663]: --> HDLC<9:FF C8 C1 00 60 19 00 A3 5B> Aug 29 14:40:43.71: [18663]: --> [2:OK] Aug 29 14:40:43.71: [18663]: REMOTE wants 9600 bit/s Aug 29 14:40:43.71: [18663]: REMOTE wants A4 page width (215 mm) Aug 29 14:40:43.71: [18663]: REMOTE wants unlimited page length Aug 29 14:40:43.71: [18663]: REMOTE wants 3.85 line/mm Aug 29 14:40:43.71: [18663]: REMOTE wants 1-D MH Aug 29 14:40:43.71: [18663]: RECV training at v.29 9600 bit/s Aug 29 14:40:43.71: [18663]: MODEM set XON/XOFF/DRAIN: input ignored, output generated Aug 29 14:40:43.71: [18663]: <-- [10:AT+FRM=96\r] Aug 29 14:40:44.28: [18663]: --> [7:CONNECT] Aug 29 14:40:45.87: [18663]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled Aug 29 14:40:45.87: [18663]: RECV: TCF 1881 bytes, 2% non-zero, 1831 zero-run Aug 29 14:40:45.87: [18663]: --> [10:NO CARRIER] Aug 29 14:40:45.87: [18663]: <-- [9:AT+FRS=7\r] Aug 29 14:40:45.92: [18663]: --> [2:OK] Aug 29 14:40:45.92: [18663]: TRAINING succeeded Aug 29 14:40:45.93: [18663]: <-- [9:AT+FTH=3\r] Aug 29 14:40:45.94: [18663]: --> [7:CONNECT] Aug 29 14:40:45.95: [18663]: <-- HDLC<3:FF C8 21> Aug 29 14:40:45.95: [18663]: <-- data [3] Aug 29 14:40:45.95: [18663]: <-- data [2] Aug 29 14:40:47.12: [18663]: --> [2:OK] Aug 29 14:40:47.12: [18663]: <-- [10:AT+FRM=96\r] Aug 29 14:40:49.06: [18663]: --> [6:+FRH:3] Aug 29 14:40:49.07: [18663]: --> [7:CONNECT] Aug 29 14:40:50.66: [18663]: --> HDLC<25:FF C0 C2 EC 2C 0C CC 1C 1C 4C 1C 0C 8C 0C 04 04 04 04 04 04 04 04 04 C7 AA> Aug 29 14:40:50.66: [18663]: --> [2:OK] Aug 29 14:40:50.66: [18663]: RECV recv TSI (sender id) Aug 29 14:40:50.66: [18663]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled Aug 29 14:40:50.66: [18663]: REMOTE TSI "01082883047" Aug 29 14:40:50.66: [18663]: <-- [9:AT+FRH=3\r] Aug 29 14:40:50.70: [18663]: --> [7:CONNECT] Aug 29 14:40:51.01: [18663]: --> HDLC<9:FF C8 C1 00 70 19 00 E0 38> Aug 29 14:40:51.13: [18663]: --> [2:OK] Aug 29 14:40:51.13: [18663]: REMOTE wants 7200 bit/s Aug 29 14:40:51.13: [18663]: REMOTE wants A4 page width (215 mm) Aug 29 14:40:51.13: [18663]: REMOTE wants unlimited page length Aug 29 14:40:51.13: [18663]: REMOTE wants 3.85 line/mm Aug 29 14:40:51.13: [18663]: REMOTE wants 1-D MH Aug 29 14:40:51.13: [18663]: RECV training at v.29 7200 bit/s Aug 29 14:40:51.13: [18663]: MODEM set XON/XOFF/DRAIN: input ignored, output generated Aug 29 14:40:51.13: [18663]: <-- [10:AT+FRM=72\r] Aug 29 14:40:51.70: [18663]: --> [7:CONNECT] Aug 29 14:40:53.29: [18663]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled Aug 29 14:40:53.29: [18663]: RECV: TCF 1426 bytes, 2% non-zero, 1392 zero-run Aug 29 14:40:53.29: [18663]: --> [10:NO CARRIER] Aug 29 14:40:53.29: [18663]: <-- [9:AT+FRS=7\r] Aug 29 14:40:53.34: [18663]: --> [2:OK] Aug 29 14:40:53.34: [18663]: TRAINING succeeded Aug 29 14:40:53.34: [18663]: <-- [9:AT+FTH=3\r] Aug 29 14:40:53.36: [18663]: --> [7:CONNECT] Aug 29 14:40:53.36: [18663]: <-- HDLC<3:FF C8 21> Aug 29 14:40:53.36: [18663]: <-- data [3] Aug 29 14:40:53.36: [18663]: <-- data [2] Aug 29 14:40:54.54: [18663]: --> [2:OK] Aug 29 14:40:54.54: [18663]: <-- [10:AT+FRM=72\r] Aug 29 14:40:56.80: [18663]: --> [7:CONNECT] Aug 29 14:40:56.80: [18663]: MODEM input buffering enabled Aug 29 14:40:56.80: [18663]: MODEM set XON/XOFF/FLUSH: input ignored, output generated Aug 29 14:40:56.80: [18663]: RECV: begin page Aug 29 14:41:00.68: [18663]: RECV/CQ: Bad 1D pixel count, row 155, got 1743, expected 1728 Aug 29 14:41:00.68: [18663]: RECV/CQ: Invalid WhiteTable code word, row 156, x 1390 Aug 29 14:41:00.68: [18663]: RECV/CQ: Bad 1D pixel count, row 156, got 1390, expected 1728 Aug 29 14:41:00.96: [18663]: RECV/CQ: Bad 1D pixel count, row 157, got 3631, expected 1728 Aug 29 14:41:00.96: [18663]: RECV/CQ: Bad 1D pixel count, row 158, got 3013, expected 1728 Aug 29 14:41:01.59: [18663]: RECV/CQ: Bad 1D pixel count, row 189, got 2457, expected 1728 Aug 29 14:41:02.07: [18663]: RECV/CQ: Bad 1D pixel count, row 190, got 1732, expected 1728 Aug 29 14:41:05.67: [18663]: RECV/CQ: Bad 1D pixel count, row 308, got 1919, expected 1728 Aug 29 14:41:11.21: [18663]: RECV/CQ: Bad 1D pixel count, row 531, got 2345, expected 1728 Aug 29 14:41:11.47: [18663]: RECV/CQ: Bad 1D pixel count, row 532, got 1734, expected 1728 Aug 29 14:41:12.30: [18663]: RECV/CQ: Bad 1D pixel count, row 533, got 1903, expected 1728 Aug 29 14:41:12.30: [18663]: RECV/CQ: Bad 1D pixel count, row 534, got 2889, expected 1728 Aug 29 14:41:12.58: [18663]: RECV/CQ: Bad 1D pixel count, row 535, got 3273, expected 1728 Aug 29 14:41:13.15: [18663]: RECV/CQ: Bad 1D pixel count, row 536, got 2361, expected 1728 Aug 29 14:41:13.97: [18663]: RECV/CQ: Bad 1D pixel count, row 537, got 1903, expected 1728 Aug 29 14:41:15.90: [18663]: RECV/CQ: Bad 1D pixel count, row 538, got 2267, expected 1728 Aug 29 14:41:16.18: [18663]: RECV/CQ: Bad 1D pixel count, row 539, got 1779, expected 1728 Aug 29 14:41:17.03: [18663]: RECV/CQ: Bad 1D pixel count, row 540, got 1953, expected 1728 Aug 29 14:41:18.12: [18663]: RECV/CQ: Bad 1D pixel count, row 541, got 1773, expected 1728 Aug 29 14:41:18.68: [18663]: RECV/CQ: Bad 1D pixel count, row 542, got 1821, expected 1728 Aug 29 14:41:19.79: [18663]: RECV/CQ: Bad 1D pixel count, row 543, got 1943, expected 1728 Aug 29 14:41:20.36: [18663]: RECV/CQ: Bad 1D pixel count, row 544, got 2213, expected 1728 Aug 29 14:41:20.64: [18663]: RECV/CQ: Invalid WhiteTable code word, row 545, x 1477 Aug 29 14:41:20.64: [18663]: RECV/CQ: Bad 1D pixel count, row 545, got 1477, expected 1728 Aug 29 14:41:21.44: [18663]: RECV/CQ: Bad 1D pixel count, row 546, got 3398, expected 1728 Aug 29 14:41:21.93: [18663]: RECV/CQ: Adjusting for trailing noise (16 run) Aug 29 14:41:21.93: [18663]: RECV: 531 total lines, 7 bad lines, 16 consecutive bad lines Aug 29 14:41:21.94: [18663]: RECV: REJECT page quality, 16-line run (max 5) Aug 29 14:41:21.94: [18663]: RECV: end page Aug 29 14:41:21.94: [18663]: --> [10:NO CARRIER] Aug 29 14:41:21.94: [18663]: <-- [9:AT+FRH=3\r] Aug 29 14:41:22.24: [18663]: --> [7:CONNECT] Aug 29 14:41:23.32: [18663]: --> HDLC<5:FF C8 F4 CC 66> Aug 29 14:41:23.44: [18663]: --> [2:OK] Aug 29 14:41:23.44: [18663]: RECV recv EOP (no more pages or documents) Aug 29 14:41:23.44: [18663]: <-- [9:AT+FRS=7\r] Aug 29 14:41:23.55: [18663]: --> [2:OK] Aug 29 14:41:23.55: [18663]: <-- [9:AT+FTH=3\r] Aug 29 14:41:23.65: [18663]: --> [7:CONNECT] Aug 29 14:41:23.65: [18663]: <-- HDLC<3:FF C8 32> Aug 29 14:41:23.65: [18663]: <-- data [3] Aug 29 14:41:23.65: [18663]: <-- data [2] Aug 29 14:41:24.82: [18663]: --> [2:OK] Aug 29 14:41:24.82: [18663]: RECV send RTN (retrain negative) Aug 29 14:41:24.82: [18663]: <-- [9:AT+FRH=3\r] Aug 29 14:41:26.85: [18663]: --> [7:CONNECT] Aug 29 14:41:27.93: [18663]: --> HDLC<5:FF C8 DF 59 6F> Aug 29 14:41:28.05: [18663]: --> [2:OK] Aug 29 14:41:28.05: [18663]: RECV recv DCN Aug 29 14:41:28.06: [18663]: RECV keeping unconfirmed page Aug 29 14:41:28.06: [18663]: RECV FAX (000000042): from 01082883047, page 1 in 0:56, INF, 3.85 line/mm, 1-D MH, 7200 bit/s Aug 29 14:41:28.06: [18663]: RECV FAX (000000042): recvq/fax000000016.tif from 01082883047, route to <unspecified>, 1 pages in 1:03 Aug 29 14:41:28.06: [18663]: RECV FAX: COMREC received DCN Aug 29 14:41:28.06: [18663]: RECV FAX (000000042): session with 01082883047 terminated abnormally: COMREC received DCN Aug 29 14:41:28.06: [18663]: RECV FAX: bin/faxrcvd "recvq/fax000000016.tif" "ttyIAX1" "000000042" "COMREC received DCN" "0000" "<NONE>" "<NONE>" "6499181595" Aug 29 14:41:28.06: [18663]: RECV FAX: end Aug 29 14:41:28.06: [18663]: SESSION END
- Prev by Date: Re: [hylafax-users] No answer (T.30 T1 timeout)
- Next by Date: Re: [hylafax-users] Turn off incoming faxes
- Previous by thread: Re: [hylafax-users] No answer (T.30 T1 timeout)
- Next by thread: [hylafax-users] Diva server errors
- Index(es):
Project hosted by iFAX Solutions