![]() |
We just purchased a new MultiTech MT5634ZBA modem (running class 1) for testing purposes and we've had a total of 1 error since last Thursday (0.4%). However, for the sake of being a perfectionist, does anyone have any suggestions on why this happened (log attached)? Nothing special in my config file except rtscts flow-control and 19200 baud. -- Michael T. Babcock CTO, FibreSpeed Ltd. (Hosting, Security, Consultation, Database, etc) http://www.fibrespeed.net/~mbabcock/
Oct 26 01:05:50.12: [ 9168]: SESSION BEGIN 00153707 1xxxxxxxxxx Oct 26 01:05:50.12: [ 9168]: HylaFAX (tm) Version 4.1.3 Oct 26 01:05:50.12: [ 9168]: SEND FAX: JOB 18547 DEST 1xxx-xxx-xxxx COMMID 00153707 DEVICE '/dev/ttyS1' Oct 26 01:05:50.12: [ 9168]: MODEM set DTR OFF Oct 26 01:05:50.12: [ 9168]: DELAY 75 ms Oct 26 01:05:50.21: [ 9168]: MODEM set DTR ON Oct 26 01:05:50.21: [ 9168]: DELAY 2600 ms Oct 26 01:05:52.81: [ 9168]: MODEM set baud rate: 19200 baud, input flow RTS/CTS, output flow RTS/CTS Oct 26 01:05:52.81: [ 9168]: MODEM flush i/o Oct 26 01:05:52.81: [ 9168]: <-- [4:ATZ\r] Oct 26 01:05:53.75: [ 9168]: --> [2:OK] Oct 26 01:05:53.75: [ 9168]: DELAY 3000 ms Oct 26 01:05:56.75: [ 9168]: <-- [5:ATE0\r] Oct 26 01:05:56.76: [ 9168]: --> [4:ATE0] Oct 26 01:05:56.76: [ 9168]: --> [2:OK] Oct 26 01:05:56.76: [ 9168]: <-- [5:ATV1\r] Oct 26 01:05:56.77: [ 9168]: --> [2:OK] Oct 26 01:05:56.77: [ 9168]: <-- [5:ATQ0\r] Oct 26 01:05:56.78: [ 9168]: --> [2:OK] Oct 26 01:05:56.78: [ 9168]: <-- [7:ATS0=0\r] Oct 26 01:05:56.80: [ 9168]: --> [2:OK] Oct 26 01:05:56.80: [ 9168]: <-- [7:ATS8=2\r] Oct 26 01:05:56.82: [ 9168]: --> [2:OK] Oct 26 01:05:56.82: [ 9168]: <-- [8:ATS7=60\r] Oct 26 01:05:56.84: [ 9168]: --> [2:OK] Oct 26 01:05:56.84: [ 9168]: <-- [12:AT+FCLASS=1\r] Oct 26 01:05:56.86: [ 9168]: --> [2:OK] Oct 26 01:05:56.86: [ 9168]: <-- [7:ATL1M1\r] Oct 26 01:05:56.88: [ 9168]: --> [2:OK] Oct 26 01:05:56.88: [ 9168]: STATE CHANGE: RUNNING -> SENDING Oct 26 01:05:56.88: [ 9168]: MODEM input buffering enabled Oct 26 01:05:56.88: [ 9168]: <-- [12:AT+FCLASS=1\r] Oct 26 01:05:57.00: [ 9168]: --> [2:OK] Oct 26 01:05:57.00: [ 9168]: DIAL 1xxxxxxxxxx Oct 26 01:05:57.00: [ 9168]: <-- [16:ATDT1xxxxxxxxxx\r] Oct 26 01:06:17.11: [ 9168]: --> [7:CONNECT] Oct 26 01:06:17.11: [ 9168]: MODEM input buffering disabled Oct 26 01:06:19.11: [ 9168]: --> HDLC<38:FF C0 04 00 00 88 01 00 51 92 08 82 32 32 B2 82 4A 04 22 92 CA 2A 4A 92 42 AA 2A 00 CA 00 01 01 01 30 80 60 7F F2> Oct 26 01:06:19.11: [ 9168]: --> [2:OK] Oct 26 01:06:19.11: [ 9168]: REMOTE NSF "00 00 11 80 00 8A 49 10 41 4C 4C 4D 41 52 20 44 49 53 54 52 49 42 55 54 00 53 00 80 80 80 0C 01 06 FE" Oct 26 01:06:19.11: [ 9168]: NSF remote fax equipment: Canon Laser Class 9000 Series Oct 26 01:06:19.11: [ 9168]: NSF remote station ID: "yyyyyyyyyyyyyyyy" Oct 26 01:06:19.11: [ 9168]: <-- [9:AT+FRH=3\r] Oct 26 01:06:19.13: [ 9168]: --> [7:CONNECT] Oct 26 01:06:19.80: [ 9168]: --> HDLC<25:FF C0 02 AC 0C 9C 8C 04 EC 2C 2C 04 0C 1C EC 04 04 04 04 04 04 04 04 20 59> Oct 26 01:06:19.80: [ 9168]: --> [2:OK] Oct 26 01:06:19.80: [ 9168]: REMOTE CSI "xxx xxx xxxx" Oct 26 01:06:19.80: [ 9168]: <-- [9:AT+FRH=3\r] Oct 26 01:06:19.82: [ 9168]: --> [7:CONNECT] Oct 26 01:06:20.23: [ 9168]: --> HDLC<15:FF C8 01 04 77 95 23 01 A9 01 01 01 1C 99 07> Oct 26 01:06:20.23: [ 9168]: --> [2:OK] Oct 26 01:06:20.23: [ 9168]: REMOTE best rate 33600 bit/s Oct 26 01:06:20.23: [ 9168]: REMOTE max page width 2048 pixels in 255 mm Oct 26 01:06:20.23: [ 9168]: REMOTE max unlimited page length Oct 26 01:06:20.23: [ 9168]: REMOTE best vres 7.7 line/mm Oct 26 01:06:20.23: [ 9168]: REMOTE best format 2-D MMR Oct 26 01:06:20.23: [ 9168]: REMOTE supports T.30 Annex A, ECM Oct 26 01:06:20.23: [ 9168]: REMOTE best 10 ms/scanline Oct 26 01:06:20.23: [ 9168]: USE 14400 bit/s Oct 26 01:06:20.23: [ 9168]: USE 10 ms/scanline Oct 26 01:06:20.23: [ 9168]: SEND file "docq/doc18736.ps;71" Oct 26 01:06:20.23: [ 9168]: USE page width 1728 pixels in 215 mm Oct 26 01:06:20.23: [ 9168]: USE unlimited page length Oct 26 01:06:20.23: [ 9168]: USE 7.7 line/mm Oct 26 01:06:20.23: [ 9168]: USE 2-D MR Oct 26 01:06:20.23: [ 9168]: SEND training at v.17 14400 bit/s Oct 26 01:06:20.23: [ 9168]: <-- [9:AT+FTH=3\r] Oct 26 01:06:21.21: [ 9168]: --> [7:CONNECT] Oct 26 01:06:21.21: [ 9168]: <-- HDLC<23:FF C0 C2 8C C4 04 86 26 86 76 86 C2 04 9E F6 36 46 82 04 86 CE CE 82> Oct 26 01:06:21.21: [ 9168]: <-- data [23] Oct 26 01:06:21.21: [ 9168]: <-- data [2] Oct 26 01:06:21.23: [ 9168]: --> [7:CONNECT] Oct 26 01:06:21.24: [ 9168]: <-- HDLC<6:FF C8 C1 00 47 14> Oct 26 01:06:21.24: [ 9168]: <-- data [6] Oct 26 01:06:21.24: [ 9168]: <-- data [2] Oct 26 01:06:22.50: [ 9168]: --> [2:OK] Oct 26 01:06:22.51: [ 9168]: <-- [9:AT+FTS=7\r] Oct 26 01:06:22.57: [ 9168]: --> [2:OK] Oct 26 01:06:22.58: [ 9168]: <-- [11:AT+FTM=145\r] Oct 26 01:06:22.59: [ 9168]: --> [7:CONNECT] Oct 26 01:06:22.60: [ 9168]: <-- data [1024] Oct 26 01:06:22.60: [ 9168]: <-- data [1024] Oct 26 01:06:22.60: [ 9168]: <-- data [652] Oct 26 01:06:22.60: [ 9168]: <-- data [2] Oct 26 01:06:25.76: [ 9168]: --> [2:OK] Oct 26 01:06:25.77: [ 9168]: <-- [9:AT+FRH=3\r] Oct 26 01:06:25.95: [ 9168]: --> [7:CONNECT] Oct 26 01:06:27.15: [ 9168]: --> HDLC<5:FF C8 21 57 BE> Oct 26 01:06:27.15: [ 9168]: --> [2:OK] Oct 26 01:06:27.15: [ 9168]: TRAINING succeeded Oct 26 01:06:27.16: [ 9168]: DELAY 75 ms Oct 26 01:06:27.23: [ 9168]: <-- [11:AT+FTM=146\r] Oct 26 01:06:27.25: [ 9168]: --> [7:CONNECT] Oct 26 01:06:27.25: [ 9168]: SEND begin page Oct 26 01:06:27.26: [ 9168]: <-- data [1029] Oct 26 01:06:27.26: [ 9168]: <-- data [1030] Oct 26 01:06:27.26: [ 9168]: <-- data [1029] Oct 26 01:06:27.26: [ 9168]: <-- data [1026] Oct 26 01:06:29.28: [ 9168]: <-- data [1036] Oct 26 01:06:29.28: [ 9168]: <-- data [1032] Oct 26 01:06:29.28: [ 9168]: <-- data [1028] Oct 26 01:06:29.28: [ 9168]: <-- data [1030] Oct 26 01:06:31.28: [ 9168]: <-- data [1027] Oct 26 01:06:31.28: [ 9168]: <-- data [1029] Oct 26 01:06:31.28: [ 9168]: <-- data [1030] Oct 26 01:06:31.28: [ 9168]: <-- data [1033] Oct 26 01:06:33.28: [ 9168]: <-- data [1027] Oct 26 01:06:33.28: [ 9168]: <-- data [1031] Oct 26 01:06:33.28: [ 9168]: <-- data [1030] Oct 26 01:06:33.28: [ 9168]: <-- data [1027] Oct 26 01:06:35.28: [ 9168]: <-- data [1028] Oct 26 01:06:35.28: [ 9168]: <-- data [1031] Oct 26 01:06:35.28: [ 9168]: <-- data [1026] Oct 26 01:06:37.28: [ 9168]: <-- data [1028] Oct 26 01:06:37.28: [ 9168]: <-- data [1031] Oct 26 01:06:37.28: [ 9168]: <-- data [1024] Oct 26 01:06:37.28: [ 9168]: <-- data [1024] Oct 26 01:06:39.28: [ 9168]: <-- data [1024] Oct 26 01:06:39.28: [ 9168]: <-- data [1024] Oct 26 01:06:39.28: [ 9168]: <-- data [1024] Oct 26 01:06:39.28: [ 9168]: <-- data [1024] Oct 26 01:06:41.28: [ 9168]: <-- data [1024] Oct 26 01:06:41.28: [ 9168]: <-- data [1024] Oct 26 01:06:41.28: [ 9168]: <-- data [1024] Oct 26 01:06:41.28: [ 9168]: <-- data [1024] Oct 26 01:06:41.87: [ 9168]: <-- data [1024] Oct 26 01:06:42.95: [ 9168]: <-- data [1024] Oct 26 01:06:42.95: [ 9168]: <-- data [1024] Oct 26 01:06:44.03: [ 9168]: <-- data [1024] Oct 26 01:06:45.12: [ 9168]: <-- data [1024] Oct 26 01:06:45.12: [ 9168]: <-- data [1024] Oct 26 01:06:46.24: [ 9168]: <-- data [905] Oct 26 01:06:46.24: [ 9168]: SENT 38793 bytes of data Oct 26 01:06:46.24: [ 9168]: <-- data [1024] Oct 26 01:06:47.33: [ 9168]: <-- data [1024] Oct 26 01:06:47.33: [ 9168]: <-- data [1024] Oct 26 01:06:48.44: [ 9168]: <-- data [1024] Oct 26 01:06:48.44: [ 9168]: <-- data [1024] Oct 26 01:06:49.52: [ 9168]: <-- data [1024] Oct 26 01:06:49.52: [ 9168]: <-- data [1026] Oct 26 01:06:50.60: [ 9168]: <-- data [1030] Oct 26 01:06:50.60: [ 9168]: <-- data [1028] Oct 26 01:06:51.69: [ 9168]: <-- data [1028] Oct 26 01:06:51.69: [ 9168]: <-- data [1027] Oct 26 01:06:52.81: [ 9168]: <-- data [1030] Oct 26 01:06:52.81: [ 9168]: <-- data [1025] Oct 26 01:06:53.98: [ 9168]: <-- data [1029] Oct 26 01:06:53.98: [ 9168]: <-- data [1029] Oct 26 01:06:55.25: [ 9168]: <-- data [657] Oct 26 01:06:55.25: [ 9168]: SENT 16016 bytes of data Oct 26 01:06:55.25: [ 9168]: SEND 2D RTC Oct 26 01:06:55.25: [ 9168]: <-- data [30] Oct 26 01:06:55.25: [ 9168]: <-- data [2] Oct 26 01:06:55.25: [ 9168]: SEND end page Oct 26 01:06:58.14: [ 9168]: --> [2:OK] Oct 26 01:06:58.14: [ 9168]: <-- [9:AT+FTS=9\r] Oct 26 01:06:58.23: [ 9168]: --> [2:OK] Oct 26 01:06:58.23: [ 9168]: SEND send EOP (no more pages or documents) Oct 26 01:06:58.23: [ 9168]: <-- [9:AT+FTH=3\r] Oct 26 01:06:59.20: [ 9168]: --> [7:CONNECT] Oct 26 01:06:59.20: [ 9168]: <-- HDLC<3:FF C8 F4> Oct 26 01:06:59.20: [ 9168]: <-- data [3] Oct 26 01:06:59.20: [ 9168]: <-- data [2] Oct 26 01:06:59.69: [ 9168]: --> [2:OK] Oct 26 01:06:59.69: [ 9168]: <-- [9:AT+FRH=3\r] Oct 26 01:07:02.79: [ 9168]: --> [0:] Oct 26 01:07:02.79: [ 9168]: MODEM <Empty line> Oct 26 01:07:02.79: [ 9168]: MODEM TIMEOUT: waiting for v.21 carrier Oct 26 01:07:02.79: [ 9168]: <-- data [1] Oct 26 01:07:02.80: [ 9168]: --> [2:] Oct 26 01:07:02.80: [ 9168]: --> [2:OK] Oct 26 01:07:02.80: [ 9168]: SEND send EOP (no more pages or documents) Oct 26 01:07:02.80: [ 9168]: <-- [9:AT+FTH=3\r] Oct 26 01:07:03.77: [ 9168]: --> [7:CONNECT] Oct 26 01:07:03.77: [ 9168]: <-- HDLC<3:FF C8 F4> Oct 26 01:07:03.77: [ 9168]: <-- data [3] Oct 26 01:07:03.77: [ 9168]: <-- data [2] Oct 26 01:07:04.27: [ 9168]: --> [2:OK] Oct 26 01:07:04.27: [ 9168]: <-- [9:AT+FRH=3\r] Oct 26 01:07:07.37: [ 9168]: --> [0:] Oct 26 01:07:07.37: [ 9168]: MODEM <Empty line> Oct 26 01:07:07.37: [ 9168]: MODEM TIMEOUT: waiting for v.21 carrier Oct 26 01:07:07.37: [ 9168]: <-- data [1] Oct 26 01:07:07.38: [ 9168]: --> [2:] Oct 26 01:07:07.38: [ 9168]: --> [2:OK] Oct 26 01:07:07.38: [ 9168]: SEND send EOP (no more pages or documents) Oct 26 01:07:07.38: [ 9168]: <-- [9:AT+FTH=3\r] Oct 26 01:07:08.35: [ 9168]: --> [7:CONNECT] Oct 26 01:07:08.35: [ 9168]: <-- HDLC<3:FF C8 F4> Oct 26 01:07:08.35: [ 9168]: <-- data [3] Oct 26 01:07:08.35: [ 9168]: <-- data [2] Oct 26 01:07:08.84: [ 9168]: --> [2:OK] Oct 26 01:07:08.84: [ 9168]: <-- [9:AT+FRH=3\r] Oct 26 01:07:11.96: [ 9168]: --> [0:] Oct 26 01:07:11.96: [ 9168]: MODEM <Empty line> Oct 26 01:07:11.96: [ 9168]: MODEM TIMEOUT: waiting for v.21 carrier Oct 26 01:07:11.96: [ 9168]: <-- data [1] Oct 26 01:07:11.97: [ 9168]: --> [2:] Oct 26 01:07:11.97: [ 9168]: --> [2:OK] Oct 26 01:07:11.97: [ 9168]: No response to MPS or EOP repeated 3 tries Oct 26 01:07:11.98: [ 9168]: <-- [9:AT+FTH=3\r] Oct 26 01:07:12.95: [ 9168]: --> [7:CONNECT] Oct 26 01:07:12.95: [ 9168]: <-- HDLC<3:FF C8 DF> Oct 26 01:07:12.95: [ 9168]: <-- data [3] Oct 26 01:07:12.95: [ 9168]: <-- data [2] Oct 26 01:07:13.44: [ 9168]: --> [2:OK] Oct 26 01:07:13.44: [ 9168]: MODEM input buffering enabled Oct 26 01:07:13.44: [ 9168]: <-- [5:ATH0\r] Oct 26 01:07:13.60: [ 9168]: --> [2:OK] Oct 26 01:07:13.60: [ 9168]: MODEM set DTR OFF Oct 26 01:07:13.60: [ 9168]: STATE CHANGE: SENDING -> MODEMWAIT (timeout 5) Oct 26 01:07:13.60: [ 9168]: SESSION END