![]() |
Hello there. We have a HP/Compaq Proliant DL380 G2 with an Eicon Diva ISDN30 PRI 2.0 adapter running SuSE Linux SLES8 fp3, HylaFAX v4.2.0 and Eicon driver divas4linux_EICON-104.429-1. Most of the time it performs ok, but recently we discovered some errors when trying to establish a connection to a certain (external) fax-server. The system sends faxes to other fax-servers without any other known problems and according to the external part the external fax-server receives faxes from other fax-servers without any known problems. Thus it appears only to be a problem between the HP-DL380G2 fax-server and the external fax-server. The following is a log from one erroneous session (I have replaced the fax-number with xxxxxxxx's): Jul 19 13:42:51.65: [ 6880]: SESSION BEGIN 000417978 45xxxxxxxx Jul 19 13:42:51.65: [ 6880]: HylaFAX (tm) Version 4.2.0 Jul 19 13:42:51.65: [ 6880]: SEND FAX: JOB 308934 DEST xxxxxxxx COMMID 000417978 DEVICE '/dev/ttyds11' Jul 19 13:42:51.65: [ 6880]: STATE CHANGE: RUNNING -> SENDING Jul 19 13:42:51.65: [ 6880]: MODEM input buffering enabled Jul 19 13:42:51.65: [ 6880]: <-- [12:AT+FCLASS=1\r] Jul 19 13:42:51.75: [ 6880]: --> [2:OK] Jul 19 13:42:51.75: [ 6880]: MODEM set XON/XOFF/FLUSH: input ignored, output disabled Jul 19 13:42:51.75: [ 6880]: DIAL xxxxxxxx Jul 19 13:42:51.75: [ 6880]: <-- [13:ATDTxxxxxxxx\r] Jul 19 13:42:59.33: [ 6880]: --> [7:CONNECT] Jul 19 13:42:59.33: [ 6880]: MODEM input buffering disabled Jul 19 13:43:01.16: [ 6880]: --> HDLC<31:FF C0 04 00 00 90 00 10 92 A2 19 2C 68 9A FF FF FF FF FF FF FF 80 E2 80 41 00 00 00 96 D5 E4> Jul 19 13:43:01.16: [ 6880]: --> [2:OK] Jul 19 13:43:01.16: [ 6880]: REMOTE NSF "00 00 09 00 08 49 45 xx xx xx xx FF FF FF FF FF FF FF 01 47 01 82 00 00 00 69" Jul 19 13:43:01.16: [ 6880]: NSF remote fax equipment: Xerox/Toshiba Jul 19 13:43:01.16: [ 6880]: <-- [9:AT+FRH=3\r] Jul 19 13:43:01.16: [ 6880]: --> [7:CONNECT] Jul 19 13:43:01.89: [ 6880]: --> HDLC<25:FF C0 02 9C AC 6C 8C 2C CC 1C 9C AC 2C D4 04 04 04 04 04 04 04 04 04 AC 60> Jul 19 13:43:01.89: [ 6880]: --> [2:OK] Jul 19 13:43:01.89: [ 6880]: REMOTE CSI "+45xxxxxxxx" Jul 19 13:43:01.89: [ 6880]: <-- [9:AT+FRH=3\r] Jul 19 13:43:01.89: [ 6880]: --> [7:CONNECT] Jul 19 13:43:02.35: [ 6880]: --> HDLC<15:FF C8 01 00 77 19 23 01 0B C1 01 01 04 96 7E> Jul 19 13:43:02.35: [ 6880]: --> [2:OK] Jul 19 13:43:02.35: [ 6880]: REMOTE best rate 14400 bit/s Jul 19 13:43:02.35: [ 6880]: REMOTE max A4 page width (215 mm) Jul 19 13:43:02.35: [ 6880]: REMOTE max unlimited page length Jul 19 13:43:02.35: [ 6880]: REMOTE best vres 7.7 line/mm Jul 19 13:43:02.35: [ 6880]: REMOTE best format 2-D MMR Jul 19 13:43:02.35: [ 6880]: REMOTE supports T.30 Annex A, 256-byte ECM Jul 19 13:43:02.35: [ 6880]: REMOTE best 5 ms/scanline Jul 19 13:43:02.35: [ 6880]: USE 14400 bit/s Jul 19 13:43:02.35: [ 6880]: USE error correction mode Jul 19 13:43:02.35: [ 6880]: USE 0 ms/scanline Jul 19 13:43:02.35: [ 6880]: SEND file "docq/doc308935.ps;c1" Jul 19 13:43:02.35: [ 6880]: USE A4 page width (215 mm) Jul 19 13:43:02.35: [ 6880]: USE unlimited page length Jul 19 13:43:02.35: [ 6880]: USE 7.7 line/mm Jul 19 13:43:02.35: [ 6880]: USE 2-D MMR Jul 19 13:43:02.35: [ 6880]: SEND training at v.17 14400 bit/s Jul 19 13:43:02.35: [ 6880]: <-- [9:AT+FTH=3\r] Jul 19 13:43:02.59: [ 6880]: --> [7:CONNECT] Jul 19 13:43:02.59: [ 6880]: <-- HDLC<23:FF C0 C2 B4 04 4E A6 6E 4E A6 CE 04 8C 0C 1A 82 62 4A B2 22 04 CA 22> Jul 19 13:43:02.59: [ 6880]: <-- data [23] Jul 19 13:43:02.59: [ 6880]: <-- data [2] Jul 19 13:43:03.32: [ 6880]: --> [7:CONNECT] Jul 19 13:43:03.32: [ 6880]: <-- HDLC<7:FF C8 C1 00 46 1F 22> Jul 19 13:43:03.32: [ 6880]: <-- data [7] Jul 19 13:43:03.32: [ 6880]: <-- data [2] Jul 19 13:43:04.70: [ 6880]: --> [2:OK] Jul 19 13:43:04.70: [ 6880]: <-- [9:AT+FTS=7\r] Jul 19 13:43:04.70: [ 6880]: --> [2:OK] Jul 19 13:43:04.70: [ 6880]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled Jul 19 13:43:04.70: [ 6880]: <-- [11:AT+FTM=145\r] Jul 19 13:43:06.39: [ 6880]: --> [7:CONNECT] Jul 19 13:43:06.39: [ 6880]: <-- data [1024] Jul 19 13:43:06.39: [ 6880]: <-- data [1024] Jul 19 13:43:06.39: [ 6880]: <-- data [652] Jul 19 13:43:06.39: [ 6880]: <-- data [2] Jul 19 13:43:07.94: [ 6880]: --> [2:OK] Jul 19 13:43:07.94: [ 6880]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled Jul 19 13:43:07.94: [ 6880]: <-- [9:AT+FRH=3\r] Jul 19 13:43:09.01: [ 6880]: --> [7:CONNECT] Jul 19 13:43:14.01: [ 6880]: --> [2:10 03] Jul 19 13:43:14.01: [ 6880]: MODEM TIMEOUT: receiving HDLC frame data Jul 19 13:43:14.01: [ 6880]: <-- data [1] Jul 19 13:43:14.21: [ 6880]: MODEM TIMEOUT: reading line from modem Jul 19 13:43:14.21: [ 6880]: MODEM <Timeout> Jul 19 13:43:14.21: [ 6880]: DELAY 1500 ms Jul 19 13:43:15.71: [ 6880]: SEND training at v.17 12000 bit/s Jul 19 13:43:15.71: [ 6880]: <-- [9:AT+FTH=3\r] Jul 19 13:43:15.72: [ 6880]: --> [7:CONNECT] Jul 19 13:43:15.72: [ 6880]: <-- HDLC<23:FF C0 C2 B4 04 4E A6 6E 4E A6 CE 04 8C 0C 1A 82 62 4A B2 22 04 CA 22> Jul 19 13:43:15.72: [ 6880]: <-- data [23] Jul 19 13:43:15.72: [ 6880]: <-- data [2] Jul 19 13:43:16.46: [ 6880]: --> [7:CONNECT] Jul 19 13:43:16.46: [ 6880]: <-- HDLC<7:FF C8 C1 00 56 1F 22> Jul 19 13:43:16.46: [ 6880]: <-- data [7] Jul 19 13:43:16.46: [ 6880]: <-- data [2] Jul 19 13:43:17.83: [ 6880]: --> [2:OK] Jul 19 13:43:17.83: [ 6880]: <-- [9:AT+FTS=7\r] Jul 19 13:43:17.83: [ 6880]: --> [2:OK] Jul 19 13:43:17.83: [ 6880]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled Jul 19 13:43:17.83: [ 6880]: <-- [11:AT+FTM=121\r] Jul 19 13:43:19.51: [ 6880]: --> [7:CONNECT] Jul 19 13:43:19.52: [ 6880]: <-- data [1024] Jul 19 13:43:19.52: [ 6880]: <-- data [1024] Jul 19 13:43:19.52: [ 6880]: <-- data [202] Jul 19 13:43:19.52: [ 6880]: <-- data [2] Jul 19 13:43:21.07: [ 6880]: --> [2:OK] Jul 19 13:43:21.07: [ 6880]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled Jul 19 13:43:21.07: [ 6880]: <-- [9:AT+FRH=3\r] Jul 19 13:43:24.17: [ 6880]: --> [0:] Jul 19 13:43:24.17: [ 6880]: MODEM <Empty line> Jul 19 13:43:24.17: [ 6880]: MODEM TIMEOUT: waiting for v.21 carrier Jul 19 13:43:24.17: [ 6880]: <-- data [1] Jul 19 13:43:24.17: [ 6880]: --> [2:] Jul 19 13:43:24.17: [ 6880]: --> [2:OK] Jul 19 13:43:24.17: [ 6880]: DELAY 1500 ms Jul 19 13:43:25.67: [ 6880]: SEND training at v.17 9600 bit/s Jul 19 13:43:25.67: [ 6880]: <-- [9:AT+FTH=3\r] Jul 19 13:43:25.75: [ 6880]: --> [7:CONNECT] Jul 19 13:43:25.75: [ 6880]: <-- HDLC<23:FF C0 C2 B4 04 4E A6 6E 4E A6 CE 04 8C 0C 1A 82 62 4A B2 22 04 CA 22> Jul 19 13:43:25.75: [ 6880]: <-- data [23] Jul 19 13:43:25.75: [ 6880]: <-- data [2] Jul 19 13:43:26.48: [ 6880]: --> [7:CONNECT] Jul 19 13:43:26.48: [ 6880]: <-- HDLC<7:FF C8 C1 00 66 1F 22> Jul 19 13:43:26.48: [ 6880]: <-- data [7] Jul 19 13:43:26.48: [ 6880]: <-- data [2] Jul 19 13:43:27.86: [ 6880]: --> [2:OK] Jul 19 13:43:27.86: [ 6880]: <-- [9:AT+FTS=7\r] Jul 19 13:43:27.86: [ 6880]: --> [2:OK] Jul 19 13:43:27.86: [ 6880]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled Jul 19 13:43:27.86: [ 6880]: <-- [10:AT+FTM=97\r] Jul 19 13:43:29.55: [ 6880]: --> [7:CONNECT] Jul 19 13:43:29.55: [ 6880]: <-- data [1024] Jul 19 13:43:29.55: [ 6880]: <-- data [776] Jul 19 13:43:29.55: [ 6880]: <-- data [2] Jul 19 13:43:31.11: [ 6880]: --> [2:OK] Jul 19 13:43:31.11: [ 6880]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled Jul 19 13:43:31.11: [ 6880]: <-- [9:AT+FRH=3\r] Jul 19 13:43:34.21: [ 6880]: --> [0:] Jul 19 13:43:34.21: [ 6880]: MODEM <Empty line> Jul 19 13:43:34.21: [ 6880]: MODEM TIMEOUT: waiting for v.21 carrier Jul 19 13:43:34.21: [ 6880]: <-- data [1] Jul 19 13:43:34.21: [ 6880]: --> [2:] Jul 19 13:43:34.21: [ 6880]: --> [2:OK] Jul 19 13:43:34.21: [ 6880]: DELAY 1500 ms Jul 19 13:43:35.71: [ 6880]: SEND training at v.29 9600 bit/s Jul 19 13:43:35.71: [ 6880]: <-- [9:AT+FTH=3\r] Jul 19 13:43:35.79: [ 6880]: --> [7:CONNECT] Jul 19 13:43:35.79: [ 6880]: <-- HDLC<23:FF C0 C2 B4 04 4E A6 6E 4E A6 CE 04 8C 0C 1A 82 62 4A B2 22 04 CA 22> Jul 19 13:43:35.79: [ 6880]: <-- data [23] Jul 19 13:43:35.79: [ 6880]: <-- data [2] Jul 19 13:43:36.52: [ 6880]: --> [7:CONNECT] Jul 19 13:43:36.52: [ 6880]: <-- HDLC<7:FF C8 C1 00 62 1F 22> Jul 19 13:43:36.52: [ 6880]: <-- data [7] Jul 19 13:43:36.52: [ 6880]: <-- data [2] Jul 19 13:43:37.90: [ 6880]: --> [2:OK] Jul 19 13:43:37.90: [ 6880]: <-- [9:AT+FTS=7\r] Jul 19 13:43:37.90: [ 6880]: --> [2:OK] Jul 19 13:43:37.90: [ 6880]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled Jul 19 13:43:37.90: [ 6880]: <-- [10:AT+FTM=96\r] Jul 19 13:43:38.23: [ 6880]: --> [7:CONNECT] Jul 19 13:43:38.23: [ 6880]: <-- data [1024] Jul 19 13:43:38.23: [ 6880]: <-- data [776] Jul 19 13:43:38.23: [ 6880]: <-- data [2] Jul 19 13:43:39.78: [ 6880]: --> [2:OK] Jul 19 13:43:39.78: [ 6880]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled Jul 19 13:43:39.78: [ 6880]: <-- [9:AT+FRH=3\r] Jul 19 13:43:42.88: [ 6880]: --> [0:] Jul 19 13:43:42.88: [ 6880]: MODEM <Empty line> Jul 19 13:43:42.88: [ 6880]: MODEM TIMEOUT: waiting for v.21 carrier Jul 19 13:43:42.88: [ 6880]: <-- data [1] Jul 19 13:43:42.88: [ 6880]: --> [2:] Jul 19 13:43:42.88: [ 6880]: --> [2:OK] Jul 19 13:43:42.88: [ 6880]: DELAY 1500 ms Jul 19 13:43:44.38: [ 6880]: SEND training at v.29 7200 bit/s Jul 19 13:43:44.38: [ 6880]: <-- [9:AT+FTH=3\r] Jul 19 13:43:44.46: [ 6880]: --> [7:CONNECT] Jul 19 13:43:44.46: [ 6880]: <-- HDLC<23:FF C0 C2 B4 04 4E A6 6E 4E A6 CE 04 8C 0C 1A 82 62 4A B2 22 04 CA 22> Jul 19 13:43:44.46: [ 6880]: <-- data [23] Jul 19 13:43:44.46: [ 6880]: <-- data [2] Jul 19 13:43:45.20: [ 6880]: --> [7:CONNECT] Jul 19 13:43:45.20: [ 6880]: <-- HDLC<7:FF C8 C1 00 72 1F 22> Jul 19 13:43:45.20: [ 6880]: <-- data [7] Jul 19 13:43:45.20: [ 6880]: <-- data [2] Jul 19 13:43:46.57: [ 6880]: --> [2:OK] Jul 19 13:43:46.57: [ 6880]: <-- [9:AT+FTS=7\r] Jul 19 13:43:46.57: [ 6880]: --> [2:OK] Jul 19 13:43:46.57: [ 6880]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled Jul 19 13:43:46.57: [ 6880]: <-- [10:AT+FTM=72\r] Jul 19 13:43:46.90: [ 6880]: --> [7:CONNECT] Jul 19 13:43:46.90: [ 6880]: <-- data [1024] Jul 19 13:43:46.90: [ 6880]: <-- data [326] Jul 19 13:43:46.90: [ 6880]: <-- data [2] Jul 19 13:43:48.45: [ 6880]: --> [2:OK] Jul 19 13:43:48.45: [ 6880]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled Jul 19 13:43:48.45: [ 6880]: <-- [9:AT+FRH=3\r] Jul 19 13:43:51.55: [ 6880]: --> [0:] Jul 19 13:43:51.55: [ 6880]: MODEM <Empty line> Jul 19 13:43:51.55: [ 6880]: MODEM TIMEOUT: waiting for v.21 carrier Jul 19 13:43:51.55: [ 6880]: <-- data [1] Jul 19 13:43:51.55: [ 6880]: --> [2:] Jul 19 13:43:51.55: [ 6880]: --> [2:OK] Jul 19 13:43:51.55: [ 6880]: DELAY 1500 ms Jul 19 13:43:53.05: [ 6880]: SEND training at v.27ter 4800 bit/s Jul 19 13:43:53.05: [ 6880]: <-- [9:AT+FTH=3\r] Jul 19 13:43:53.13: [ 6880]: --> [7:CONNECT] Jul 19 13:43:53.13: [ 6880]: <-- HDLC<23:FF C0 C2 B4 04 4E A6 6E 4E A6 CE 04 8C 0C 1A 82 62 4A B2 22 04 CA 22> Jul 19 13:43:53.13: [ 6880]: <-- data [23] Jul 19 13:43:53.13: [ 6880]: <-- data [2] Jul 19 13:43:53.86: [ 6880]: --> [7:CONNECT] Jul 19 13:43:53.86: [ 6880]: <-- HDLC<7:FF C8 C1 00 52 1F 22> Jul 19 13:43:53.86: [ 6880]: <-- data [7] Jul 19 13:43:53.86: [ 6880]: <-- data [2] Jul 19 13:43:55.24: [ 6880]: --> [2:OK] Jul 19 13:43:55.24: [ 6880]: <-- [9:AT+FTS=7\r] Jul 19 13:43:55.24: [ 6880]: --> [2:OK] Jul 19 13:43:55.24: [ 6880]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled Jul 19 13:43:55.24: [ 6880]: <-- [10:AT+FTM=48\r] Jul 19 13:43:56.24: [ 6880]: --> [7:CONNECT] Jul 19 13:43:56.24: [ 6880]: <-- data [900] Jul 19 13:43:56.24: [ 6880]: <-- data [2] Jul 19 13:43:57.80: [ 6880]: --> [2:OK] Jul 19 13:43:57.80: [ 6880]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled Jul 19 13:43:57.80: [ 6880]: <-- [9:AT+FRH=3\r] Jul 19 13:44:00.89: [ 6880]: --> [0:] Jul 19 13:44:00.89: [ 6880]: MODEM <Empty line> Jul 19 13:44:00.89: [ 6880]: MODEM TIMEOUT: waiting for v.21 carrier Jul 19 13:44:00.89: [ 6880]: <-- data [1] Jul 19 13:44:00.89: [ 6880]: --> [2:] Jul 19 13:44:00.89: [ 6880]: --> [2:OK] Jul 19 13:44:00.89: [ 6880]: DELAY 1500 ms Jul 19 13:44:02.39: [ 6880]: SEND training at v.27ter fallback mode 2400 bit/s Jul 19 13:44:02.39: [ 6880]: <-- [9:AT+FTH=3\r] Jul 19 13:44:02.47: [ 6880]: --> [7:CONNECT] Jul 19 13:44:02.47: [ 6880]: <-- HDLC<23:FF C0 C2 B4 04 4E A6 6E 4E A6 CE 04 8C 0C 1A 82 62 4A B2 22 04 CA 22> Jul 19 13:44:02.47: [ 6880]: <-- data [23] Jul 19 13:44:02.47: [ 6880]: <-- data [2] Jul 19 13:44:03.20: [ 6880]: --> [7:CONNECT] Jul 19 13:44:03.20: [ 6880]: <-- HDLC<7:FF C8 C1 00 42 1F 22> Jul 19 13:44:03.20: [ 6880]: <-- data [7] Jul 19 13:44:03.20: [ 6880]: <-- data [2] Jul 19 13:44:04.58: [ 6880]: --> [2:OK] Jul 19 13:44:04.58: [ 6880]: <-- [9:AT+FTS=7\r] Jul 19 13:44:04.58: [ 6880]: --> [2:OK] Jul 19 13:44:04.58: [ 6880]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled Jul 19 13:44:04.58: [ 6880]: <-- [10:AT+FTM=24\r] Jul 19 13:44:05.83: [ 6880]: --> [7:CONNECT] Jul 19 13:44:05.83: [ 6880]: <-- data [450] Jul 19 13:44:05.83: [ 6880]: <-- data [2] Jul 19 13:44:07.39: [ 6880]: --> [2:OK] Jul 19 13:44:07.39: [ 6880]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled Jul 19 13:44:07.39: [ 6880]: <-- [9:AT+FRH=3\r] Jul 19 13:44:10.48: [ 6880]: --> [0:] Jul 19 13:44:10.48: [ 6880]: MODEM <Empty line> Jul 19 13:44:10.48: [ 6880]: MODEM TIMEOUT: waiting for v.21 carrier Jul 19 13:44:10.48: [ 6880]: <-- data [1] Jul 19 13:44:10.48: [ 6880]: --> [2:] Jul 19 13:44:10.48: [ 6880]: --> [2:OK] Jul 19 13:44:10.48: [ 6880]: DELAY 1500 ms Jul 19 13:44:11.98: [ 6880]: TRAINING failed Jul 19 13:44:11.98: [ 6880]: SEND: Failure to train remote modem at 2400 bps or minimum speed; Giving up after 3 attempts to send same page "docq/doc308935.ps;c1", dirnum 0 Jul 19 13:44:11.98: [ 6880]: <-- [9:AT+FTH=3\r] Jul 19 13:44:12.06: [ 6880]: --> [7:CONNECT] Jul 19 13:44:12.06: [ 6880]: <-- HDLC<3:FF C8 DF> Jul 19 13:44:12.06: [ 6880]: <-- data [3] Jul 19 13:44:12.06: [ 6880]: <-- data [2] Jul 19 13:44:13.36: [ 6880]: --> [2:OK] Jul 19 13:44:13.36: [ 6880]: MODEM input buffering enabled Jul 19 13:44:13.36: [ 6880]: <-- [5:ATH0\r] Jul 19 13:44:13.46: [ 6880]: --> [2:OK] Jul 19 13:44:13.46: [ 6880]: MODEM set DTR OFF Jul 19 13:44:13.46: [ 6880]: STATE CHANGE: SENDING -> MODEMWAIT (timeout 5) Jul 19 13:44:13.46: [ 6880]: SESSION END Does anybody knows why we get the above error (Failure to train modem at 2400 bps or minimum speed) and what we have to do to correct this error? Best regards / Med venlig hilsen Ib-Michael Martinsen IBM, Bytoften 1, DK-8240 Risskov, Denmark Phone: +45 8673 5526 / Mobile: +45 2560 5526 / Fax: +45 7013 4006 e-mail: imm@xxxxxxxxxx ____________________ 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*