Google Groups no longer supports new Usenet posts or subscriptions. Historical content remains viewable.
Dismiss
[hylafax-users] "Failure to train remote modem at 2400 bps or minimum speed {E137}"
1,323 views
Skip to first unread message
Rehman
Jul 1, 2015, 7:26:18 AM7/1/15
to hylafa...@hylafax.org, Lee Howard
Hi there,
I am using Hylafax + 5.5.5 with IAX modem 1.2 and Asterisk 11.9 on a CentOS
6.5 server
On some of my faxes, I get this error *"Failure to train remote modem at
2400 bps or minimum speed {E137}; Giving up after 3 attempts to send same
page"*,
I was thinking of setting a special group of modems with specific settings
that can minimize this. How can I minimize this error or eliminate this
completely.
Thanks, any help would be greatly appreciated!
Here is a fresh sample from my logs,
Jul 01 07:14:31.30: [28039]: SESSION BEGIN 000020811 6313298291
Jul 01 07:14:31.30: [28039]: HylaFAX (tm) Version 5.5.5
Jul 01 07:14:31.30: [28039]: SEND FAX: JOB 9976 DEST 6313298291 COMMID
000020811 DEVICE '/dev/ttyIAX6048' FROM 'Asterisk User <asterisk@localhost>'
USER asterisk
Jul 01 07:14:31.30: [28039]: STATE CHANGE: RUNNING -> SENDING
Jul 01 07:14:31.30: [28039]: <-- [12:AT+FCLASS=1\r]
Jul 01 07:14:31.30: [28039]: --> [2:OK]
Jul 01 07:14:31.30: [28039]: MODEM set XON/XOFF/FLUSH: input ignored,
output disabled
Jul 01 07:14:31.36: [28039]: DIAL 6313298291
Jul 01 07:14:31.36: [28039]: <-- [15:ATDT6313298291\r]
Jul 01 07:14:43.63: [28039]: --> [7:CONNECT]
Jul 01 07:14:45.05: [28039]: --> HDLC<25:FF C0 02 04 04 04 04 04 04 04 04
04 04 04 04 04 04 04 04 04 1E 86 62 51 CB>
Jul 01 07:14:45.05: [28039]: --> [2:OK]
Jul 01 07:14:45.05: [28039]: REMOTE CSI "Fax"
Jul 01 07:14:45.05: [28039]: <-- [9:AT+FRH=3\r]
Jul 01 07:14:45.39: [28039]: --> [7:CONNECT]
Jul 01 07:14:45.39: [28039]: --> HDLC<8:FF C8 01 00 77 1E 80 40>
Jul 01 07:14:45.47: [28039]: --> [2:OK]
Jul 01 07:14:45.47: [28039]: REMOTE best rate 14400 bit/s
Jul 01 07:14:45.47: [28039]: REMOTE max A4 page width (215 mm)
Jul 01 07:14:45.47: [28039]: REMOTE max unlimited page length
Jul 01 07:14:45.47: [28039]: REMOTE best vres 7.7 line/mm
Jul 01 07:14:45.47: [28039]: REMOTE format support: MH, MR
Jul 01 07:14:45.47: [28039]: REMOTE best 0 ms/scanline
Jul 01 07:14:45.47: [28039]: USE 14400 bit/s
Jul 01 07:14:45.47: [28039]: SEND file "docq/doc16109.pdf;1d81"
Jul 01 07:14:45.47: [28039]: USE A4 page width (215 mm)
Jul 01 07:14:45.47: [28039]: USE unlimited page length
Jul 01 07:14:45.47: [28039]: USE 7.7 line/mm
Jul 01 07:14:45.47: [28039]: USE 2-D MR
Jul 01 07:14:45.47: [28039]: USE 0 ms/scanline
Jul 01 07:14:45.47: [28039]: SEND training at v.17 14400 bit/s
Jul 01 07:14:45.47: [28039]: <-- [9:AT+FRS=7\r]
Jul 01 07:14:45.55: [28039]: --> [2:OK]
Jul 01 07:14:45.55: [28039]: <-- [9:AT+FTH=3\r]
Jul 01 07:14:45.57: [28039]: --> [7:CONNECT]
Jul 01 07:14:45.57: [28039]: <-- HDLC<23:FF C0 C2 2E 96 76 AE B6 B6 F6 C2
04 D6 F6 F6 4E 42 04 9E 76 F6 2E CA>
Jul 01 07:14:45.57: [28039]: <-- data [23]
Jul 01 07:14:45.57: [28039]: <-- data [2]
Jul 01 07:14:47.11: [28039]: --> [7:CONNECT]
Jul 01 07:14:47.11: [28039]: <-- HDLC<6:FF C8 C1 00 47 1E>
Jul 01 07:14:47.12: [28039]: <-- data [6]
Jul 01 07:14:47.12: [28039]: <-- data [2]
Jul 01 07:14:47.47: [28039]: --> [2:OK]
Jul 01 07:14:47.47: [28039]: <-- [9:AT+FTS=7\r]
Jul 01 07:14:47.57: [28039]: --> [2:OK]
Jul 01 07:14:47.57: [28039]: MODEM set XON/XOFF/FLUSH: input interpreted,
output disabled
Jul 01 07:14:47.57: [28039]: <-- [11:AT+FTM=145\r]
Jul 01 07:14:47.59: [28039]: --> [7:CONNECT]
Jul 01 07:14:47.59: [28039]: DELAY 400 ms
Jul 01 07:14:47.99: [28039]: <-- data [1024]
Jul 01 07:14:47.99: [28039]: <-- data [1024]
Jul 01 07:14:47.99: [28039]: <-- data [652]
Jul 01 07:14:47.99: [28039]: <-- data [2]
Jul 01 07:14:50.55: [28039]: --> [2:OK]
Jul 01 07:14:50.55: [28039]: MODEM set XON/XOFF/DRAIN: input ignored,
output disabled
Jul 01 07:14:50.55: [28039]: <-- [9:AT+FRH=3\r]
Jul 01 07:14:53.65: [28039]: --> [0:]
Jul 01 07:14:53.65: [28039]: MODEM <Empty line>
Jul 01 07:14:53.65: [28039]: MODEM TIMEOUT: waiting for v.21 carrier
Jul 01 07:14:53.65: [28039]: <-- data [1]
Jul 01 07:14:53.67: [28039]: --> [2:OK]
Jul 01 07:14:53.67: [28039]: <-- [9:AT+FRS=7\r]
Jul 01 07:14:53.69: [28039]: --> [2:OK]
Jul 01 07:14:53.69: [28039]: SEND training at v.17 12000 bit/s
Jul 01 07:14:53.69: [28039]: <-- [9:AT+FTH=3\r]
Jul 01 07:14:53.71: [28039]: --> [7:CONNECT]
Jul 01 07:14:53.71: [28039]: <-- HDLC<23:FF C0 C2 2E 96 76 AE B6 B6 F6 C2
04 D6 F6 F6 4E 42 04 9E 76 F6 2E CA>
Jul 01 07:14:53.71: [28039]: <-- data [23]
Jul 01 07:14:53.71: [28039]: <-- data [2]
Jul 01 07:14:55.25: [28039]: --> [7:CONNECT]
Jul 01 07:14:55.25: [28039]: <-- HDLC<6:FF C8 C1 00 57 1E>
Jul 01 07:14:55.25: [28039]: <-- data [6]
Jul 01 07:14:55.25: [28039]: <-- data [2]
Jul 01 07:14:55.61: [28039]: --> [2:OK]
Jul 01 07:14:55.61: [28039]: <-- [9:AT+FTS=7\r]
Jul 01 07:14:55.71: [28039]: --> [2:OK]
Jul 01 07:14:55.71: [28039]: MODEM set XON/XOFF/FLUSH: input interpreted,
output disabled
Jul 01 07:14:55.71: [28039]: <-- [11:AT+FTM=121\r]
Jul 01 07:14:55.73: [28039]: --> [7:CONNECT]
Jul 01 07:14:55.73: [28039]: DELAY 400 ms
Jul 01 07:14:56.13: [28039]: <-- data [1024]
Jul 01 07:14:56.13: [28039]: <-- data [1024]
Jul 01 07:14:56.13: [28039]: <-- data [202]
Jul 01 07:14:56.13: [28039]: <-- data [2]
Jul 01 07:14:58.69: [28039]: --> [2:OK]
Jul 01 07:14:58.69: [28039]: MODEM set XON/XOFF/DRAIN: input ignored,
output disabled
Jul 01 07:14:58.69: [28039]: <-- [9:AT+FRH=3\r]
Jul 01 07:15:00.05: [28039]: --> [7:CONNECT]
Jul 01 07:15:00.93: [28039]: --> HDLC<5:FF C8 22 67 DD>
Jul 01 07:15:01.03: [28039]: --> [2:OK]
Jul 01 07:15:01.03: [28039]: SEND training at v.29 9600 bit/s
Jul 01 07:15:01.03: [28039]: <-- [9:AT+FRS=7\r]
Jul 01 07:15:01.11: [28039]: --> [2:OK]
Jul 01 07:15:01.11: [28039]: <-- [9:AT+FTH=3\r]
Jul 01 07:15:01.13: [28039]: --> [7:CONNECT]
Jul 01 07:15:01.13: [28039]: <-- HDLC<23:FF C0 C2 2E 96 76 AE B6 B6 F6 C2
04 D6 F6 F6 4E 42 04 9E 76 F6 2E CA>
Jul 01 07:15:01.13: [28039]: <-- data [23]
Jul 01 07:15:01.13: [28039]: <-- data [2]
Jul 01 07:15:02.67: [28039]: --> [7:CONNECT]
Jul 01 07:15:02.67: [28039]: <-- HDLC<6:FF C8 C1 00 63 1E>
Jul 01 07:15:02.67: [28039]: <-- data [6]
Jul 01 07:15:02.67: [28039]: <-- data [2]
Jul 01 07:15:03.03: [28039]: --> [2:OK]
Jul 01 07:15:03.03: [28039]: <-- [9:AT+FTS=7\r]
Jul 01 07:15:03.13: [28039]: --> [2:OK]
Jul 01 07:15:03.13: [28039]: MODEM set XON/XOFF/FLUSH: input interpreted,
output disabled
Jul 01 07:15:03.13: [28039]: <-- [10:AT+FTM=96\r]
Jul 01 07:15:03.15: [28039]: --> [7:CONNECT]
Jul 01 07:15:03.15: [28039]: DELAY 400 ms
Jul 01 07:15:03.55: [28039]: <-- data [1024]
Jul 01 07:15:03.55: [28039]: <-- data [776]
Jul 01 07:15:03.55: [28039]: <-- data [2]
Jul 01 07:15:05.11: [28039]: --> [2:OK]
Jul 01 07:15:05.11: [28039]: MODEM set XON/XOFF/DRAIN: input ignored,
output disabled
Jul 01 07:15:05.11: [28039]: <-- [9:AT+FRH=3\r]
Jul 01 07:15:08.21: [28039]: --> [0:]
Jul 01 07:15:08.21: [28039]: MODEM <Empty line>
Jul 01 07:15:08.21: [28039]: MODEM TIMEOUT: waiting for v.21 carrier
Jul 01 07:15:08.21: [28039]: <-- data [1]
Jul 01 07:15:08.23: [28039]: --> [2:OK]
Jul 01 07:15:08.23: [28039]: <-- [9:AT+FRS=7\r]
Jul 01 07:15:08.25: [28039]: --> [2:OK]
Jul 01 07:15:08.25: [28039]: SEND training at v.29 7200 bit/s
Jul 01 07:15:08.25: [28039]: <-- [9:AT+FTH=3\r]
Jul 01 07:15:08.27: [28039]: --> [7:CONNECT]
Jul 01 07:15:08.27: [28039]: <-- HDLC<23:FF C0 C2 2E 96 76 AE B6 B6 F6 C2
04 D6 F6 F6 4E 42 04 9E 76 F6 2E CA>
Jul 01 07:15:08.27: [28039]: <-- data [23]
Jul 01 07:15:08.27: [28039]: <-- data [2]
Jul 01 07:15:09.81: [28039]: --> [7:CONNECT]
Jul 01 07:15:09.81: [28039]: <-- HDLC<6:FF C8 C1 00 73 1E>
Jul 01 07:15:09.82: [28039]: <-- data [6]
Jul 01 07:15:09.82: [28039]: <-- data [2]
Jul 01 07:15:10.17: [28039]: --> [2:OK]
Jul 01 07:15:10.17: [28039]: <-- [9:AT+FTS=7\r]
Jul 01 07:15:10.27: [28039]: --> [2:OK]
Jul 01 07:15:10.27: [28039]: MODEM set XON/XOFF/FLUSH: input interpreted,
output disabled
Jul 01 07:15:10.27: [28039]: <-- [10:AT+FTM=72\r]
Jul 01 07:15:10.29: [28039]: --> [7:CONNECT]
Jul 01 07:15:10.29: [28039]: DELAY 400 ms
Jul 01 07:15:10.69: [28039]: <-- data [1024]
Jul 01 07:15:10.69: [28039]: <-- data [326]
Jul 01 07:15:10.69: [28039]: <-- data [2]
Jul 01 07:15:12.25: [28039]: --> [2:OK]
Jul 01 07:15:12.25: [28039]: MODEM set XON/XOFF/DRAIN: input ignored,
output disabled
Jul 01 07:15:12.25: [28039]: <-- [9:AT+FRH=3\r]
Jul 01 07:15:14.59: [28039]: --> [7:CONNECT]
Jul 01 07:15:15.49: [28039]: --> HDLC<5:FF C8 22 67 DD>
Jul 01 07:15:15.59: [28039]: --> [2:OK]
Jul 01 07:15:15.59: [28039]: SEND training at v.27ter 4800 bit/s
Jul 01 07:15:15.59: [28039]: <-- [9:AT+FRS=7\r]
Jul 01 07:15:15.65: [28039]: --> [2:OK]
Jul 01 07:15:15.65: [28039]: <-- [9:AT+FTH=3\r]
Jul 01 07:15:15.67: [28039]: --> [7:CONNECT]
Jul 01 07:15:15.67: [28039]: <-- HDLC<23:FF C0 C2 2E 96 76 AE B6 B6 F6 C2
04 D6 F6 F6 4E 42 04 9E 76 F6 2E CA>
Jul 01 07:15:15.67: [28039]: <-- data [23]
Jul 01 07:15:15.67: [28039]: <-- data [2]
Jul 01 07:15:17.21: [28039]: --> [7:CONNECT]
Jul 01 07:15:17.21: [28039]: <-- HDLC<6:FF C8 C1 00 53 1E>
Jul 01 07:15:17.21: [28039]: <-- data [6]
Jul 01 07:15:17.21: [28039]: <-- data [2]
Jul 01 07:15:17.57: [28039]: --> [2:OK]
Jul 01 07:15:17.57: [28039]: <-- [9:AT+FTS=7\r]
Jul 01 07:15:17.67: [28039]: --> [2:OK]
Jul 01 07:15:17.67: [28039]: MODEM set XON/XOFF/FLUSH: input interpreted,
output disabled
Jul 01 07:15:17.67: [28039]: <-- [10:AT+FTM=48\r]
Jul 01 07:15:17.69: [28039]: --> [7:CONNECT]
Jul 01 07:15:17.69: [28039]: DELAY 400 ms
Jul 01 07:15:18.09: [28039]: <-- data [900]
Jul 01 07:15:18.09: [28039]: <-- data [2]
Jul 01 07:15:19.97: [28039]: --> [2:OK]
Jul 01 07:15:19.97: [28039]: MODEM set XON/XOFF/DRAIN: input ignored,
output disabled
Jul 01 07:15:19.97: [28039]: <-- [9:AT+FRH=3\r]
Jul 01 07:15:20.85: [28039]: --> [7:CONNECT]
Jul 01 07:15:21.75: [28039]: --> HDLC<5:FF C8 22 67 DD>
Jul 01 07:15:21.85: [28039]: --> [2:OK]
Jul 01 07:15:21.85: [28039]: SEND training at v.27ter fallback mode 2400
bit/s
Jul 01 07:15:21.85: [28039]: <-- [9:AT+FRS=7\r]
Jul 01 07:15:21.91: [28039]: --> [2:OK]
Jul 01 07:15:21.91: [28039]: <-- [9:AT+FTH=3\r]
Jul 01 07:15:21.93: [28039]: --> [7:CONNECT]
Jul 01 07:15:21.93: [28039]: <-- HDLC<23:FF C0 C2 2E 96 76 AE B6 B6 F6 C2
04 D6 F6 F6 4E 42 04 9E 76 F6 2E CA>
Jul 01 07:15:21.93: [28039]: <-- data [23]
Jul 01 07:15:21.93: [28039]: <-- data [2]
Jul 01 07:15:23.47: [28039]: --> [7:CONNECT]
Jul 01 07:15:23.47: [28039]: <-- HDLC<6:FF C8 C1 00 43 1E>
Jul 01 07:15:23.47: [28039]: <-- data [6]
Jul 01 07:15:23.47: [28039]: <-- data [2]
Jul 01 07:15:23.83: [28039]: --> [2:OK]
Jul 01 07:15:23.83: [28039]: <-- [9:AT+FTS=7\r]
Jul 01 07:15:23.93: [28039]: --> [2:OK]
Jul 01 07:15:23.93: [28039]: MODEM set XON/XOFF/FLUSH: input interpreted,
output disabled
Jul 01 07:15:23.93: [28039]: <-- [10:AT+FTM=24\r]
Jul 01 07:15:23.95: [28039]: --> [7:CONNECT]
Jul 01 07:15:23.95: [28039]: DELAY 400 ms
Jul 01 07:15:24.35: [28039]: <-- data [450]
Jul 01 07:15:24.35: [28039]: <-- data [2]
Jul 01 07:15:26.47: [28039]: --> [2:OK]
Jul 01 07:15:26.47: [28039]: MODEM set XON/XOFF/DRAIN: input ignored,
output disabled
Jul 01 07:15:26.47: [28039]: <-- [9:AT+FRH=3\r]
Jul 01 07:15:27.35: [28039]: --> [7:CONNECT]
Jul 01 07:15:28.25: [28039]: --> HDLC<5:FF C8 22 67 DD>
Jul 01 07:15:28.35: [28039]: --> [2:OK]
Jul 01 07:15:28.35: [28039]: TRAINING failed
Jul 01 07:15:28.35: [28039]: SEND: Failure to train remote modem at 2400
bps or minimum speed {E137}; Giving up after 3 attempts to send same page
"docq/doc16109.pdf;1d81", dirnum 0
Jul 01 07:15:28.35: [28039]: <-- [9:AT+FRS=7\r]
Jul 01 07:15:28.41: [28039]: --> [2:OK]
Jul 01 07:15:28.41: [28039]: <-- [9:AT+FTH=3\r]
Jul 01 07:15:28.43: [28039]: --> [7:CONNECT]
Jul 01 07:15:28.43: [28039]: <-- HDLC<3:FF C8 DF>
Jul 01 07:15:28.43: [28039]: <-- data [3]
Jul 01 07:15:28.43: [28039]: <-- data [2]
Jul 01 07:15:29.51: [28039]: --> [2:OK]
Jul 01 07:15:29.51: [28039]: MODEM input buffering enabled
Jul 01 07:15:30.51: [28039]: <-- [5:ATH0\r]
Jul 01 07:15:30.63: [28039]: --> [2:OK]
Jul 01 07:15:30.63: [28039]: MODEM set DTR OFF
Jul 01 07:15:30.63: [28039]: MODEM set baud rate: 0 baud (flow control
unchanged)
Jul 01 07:15:30.63: [28039]: STATE CHANGE: SENDING -> MODEMWAIT (timeout 5)
Jul 01 07:15:30.63: [28039]: SESSION END
I am using Hylafax + 5.5.5 with IAX modem 1.2 and Asterisk 11.9 on a CentOS
6.5 server
On some of my faxes, I get this error *"Failure to train remote modem at
2400 bps or minimum speed {E137}; Giving up after 3 attempts to send same
page"*,
I was thinking of setting a special group of modems with specific settings
that can minimize this. How can I minimize this error or eliminate this
completely.
Thanks, any help would be greatly appreciated!
Here is a fresh sample from my logs,
Jul 01 07:14:31.30: [28039]: SESSION BEGIN 000020811 6313298291
Jul 01 07:14:31.30: [28039]: HylaFAX (tm) Version 5.5.5
Jul 01 07:14:31.30: [28039]: SEND FAX: JOB 9976 DEST 6313298291 COMMID
000020811 DEVICE '/dev/ttyIAX6048' FROM 'Asterisk User <asterisk@localhost>'
USER asterisk
Jul 01 07:14:31.30: [28039]: STATE CHANGE: RUNNING -> SENDING
Jul 01 07:14:31.30: [28039]: <-- [12:AT+FCLASS=1\r]
Jul 01 07:14:31.30: [28039]: --> [2:OK]
Jul 01 07:14:31.30: [28039]: MODEM set XON/XOFF/FLUSH: input ignored,
output disabled
Jul 01 07:14:31.36: [28039]: DIAL 6313298291
Jul 01 07:14:31.36: [28039]: <-- [15:ATDT6313298291\r]
Jul 01 07:14:43.63: [28039]: --> [7:CONNECT]
Jul 01 07:14:45.05: [28039]: --> HDLC<25:FF C0 02 04 04 04 04 04 04 04 04
04 04 04 04 04 04 04 04 04 1E 86 62 51 CB>
Jul 01 07:14:45.05: [28039]: --> [2:OK]
Jul 01 07:14:45.05: [28039]: REMOTE CSI "Fax"
Jul 01 07:14:45.05: [28039]: <-- [9:AT+FRH=3\r]
Jul 01 07:14:45.39: [28039]: --> [7:CONNECT]
Jul 01 07:14:45.39: [28039]: --> HDLC<8:FF C8 01 00 77 1E 80 40>
Jul 01 07:14:45.47: [28039]: --> [2:OK]
Jul 01 07:14:45.47: [28039]: REMOTE best rate 14400 bit/s
Jul 01 07:14:45.47: [28039]: REMOTE max A4 page width (215 mm)
Jul 01 07:14:45.47: [28039]: REMOTE max unlimited page length
Jul 01 07:14:45.47: [28039]: REMOTE best vres 7.7 line/mm
Jul 01 07:14:45.47: [28039]: REMOTE format support: MH, MR
Jul 01 07:14:45.47: [28039]: REMOTE best 0 ms/scanline
Jul 01 07:14:45.47: [28039]: USE 14400 bit/s
Jul 01 07:14:45.47: [28039]: SEND file "docq/doc16109.pdf;1d81"
Jul 01 07:14:45.47: [28039]: USE A4 page width (215 mm)
Jul 01 07:14:45.47: [28039]: USE unlimited page length
Jul 01 07:14:45.47: [28039]: USE 7.7 line/mm
Jul 01 07:14:45.47: [28039]: USE 2-D MR
Jul 01 07:14:45.47: [28039]: USE 0 ms/scanline
Jul 01 07:14:45.47: [28039]: SEND training at v.17 14400 bit/s
Jul 01 07:14:45.47: [28039]: <-- [9:AT+FRS=7\r]
Jul 01 07:14:45.55: [28039]: --> [2:OK]
Jul 01 07:14:45.55: [28039]: <-- [9:AT+FTH=3\r]
Jul 01 07:14:45.57: [28039]: --> [7:CONNECT]
Jul 01 07:14:45.57: [28039]: <-- HDLC<23:FF C0 C2 2E 96 76 AE B6 B6 F6 C2
04 D6 F6 F6 4E 42 04 9E 76 F6 2E CA>
Jul 01 07:14:45.57: [28039]: <-- data [23]
Jul 01 07:14:45.57: [28039]: <-- data [2]
Jul 01 07:14:47.11: [28039]: --> [7:CONNECT]
Jul 01 07:14:47.11: [28039]: <-- HDLC<6:FF C8 C1 00 47 1E>
Jul 01 07:14:47.12: [28039]: <-- data [6]
Jul 01 07:14:47.12: [28039]: <-- data [2]
Jul 01 07:14:47.47: [28039]: --> [2:OK]
Jul 01 07:14:47.47: [28039]: <-- [9:AT+FTS=7\r]
Jul 01 07:14:47.57: [28039]: --> [2:OK]
Jul 01 07:14:47.57: [28039]: MODEM set XON/XOFF/FLUSH: input interpreted,
output disabled
Jul 01 07:14:47.57: [28039]: <-- [11:AT+FTM=145\r]
Jul 01 07:14:47.59: [28039]: --> [7:CONNECT]
Jul 01 07:14:47.59: [28039]: DELAY 400 ms
Jul 01 07:14:47.99: [28039]: <-- data [1024]
Jul 01 07:14:47.99: [28039]: <-- data [1024]
Jul 01 07:14:47.99: [28039]: <-- data [652]
Jul 01 07:14:47.99: [28039]: <-- data [2]
Jul 01 07:14:50.55: [28039]: --> [2:OK]
Jul 01 07:14:50.55: [28039]: MODEM set XON/XOFF/DRAIN: input ignored,
output disabled
Jul 01 07:14:50.55: [28039]: <-- [9:AT+FRH=3\r]
Jul 01 07:14:53.65: [28039]: --> [0:]
Jul 01 07:14:53.65: [28039]: MODEM <Empty line>
Jul 01 07:14:53.65: [28039]: MODEM TIMEOUT: waiting for v.21 carrier
Jul 01 07:14:53.65: [28039]: <-- data [1]
Jul 01 07:14:53.67: [28039]: --> [2:OK]
Jul 01 07:14:53.67: [28039]: <-- [9:AT+FRS=7\r]
Jul 01 07:14:53.69: [28039]: --> [2:OK]
Jul 01 07:14:53.69: [28039]: SEND training at v.17 12000 bit/s
Jul 01 07:14:53.69: [28039]: <-- [9:AT+FTH=3\r]
Jul 01 07:14:53.71: [28039]: --> [7:CONNECT]
Jul 01 07:14:53.71: [28039]: <-- HDLC<23:FF C0 C2 2E 96 76 AE B6 B6 F6 C2
04 D6 F6 F6 4E 42 04 9E 76 F6 2E CA>
Jul 01 07:14:53.71: [28039]: <-- data [23]
Jul 01 07:14:53.71: [28039]: <-- data [2]
Jul 01 07:14:55.25: [28039]: --> [7:CONNECT]
Jul 01 07:14:55.25: [28039]: <-- HDLC<6:FF C8 C1 00 57 1E>
Jul 01 07:14:55.25: [28039]: <-- data [6]
Jul 01 07:14:55.25: [28039]: <-- data [2]
Jul 01 07:14:55.61: [28039]: --> [2:OK]
Jul 01 07:14:55.61: [28039]: <-- [9:AT+FTS=7\r]
Jul 01 07:14:55.71: [28039]: --> [2:OK]
Jul 01 07:14:55.71: [28039]: MODEM set XON/XOFF/FLUSH: input interpreted,
output disabled
Jul 01 07:14:55.71: [28039]: <-- [11:AT+FTM=121\r]
Jul 01 07:14:55.73: [28039]: --> [7:CONNECT]
Jul 01 07:14:55.73: [28039]: DELAY 400 ms
Jul 01 07:14:56.13: [28039]: <-- data [1024]
Jul 01 07:14:56.13: [28039]: <-- data [1024]
Jul 01 07:14:56.13: [28039]: <-- data [202]
Jul 01 07:14:56.13: [28039]: <-- data [2]
Jul 01 07:14:58.69: [28039]: --> [2:OK]
Jul 01 07:14:58.69: [28039]: MODEM set XON/XOFF/DRAIN: input ignored,
output disabled
Jul 01 07:14:58.69: [28039]: <-- [9:AT+FRH=3\r]
Jul 01 07:15:00.05: [28039]: --> [7:CONNECT]
Jul 01 07:15:00.93: [28039]: --> HDLC<5:FF C8 22 67 DD>
Jul 01 07:15:01.03: [28039]: --> [2:OK]
Jul 01 07:15:01.03: [28039]: SEND training at v.29 9600 bit/s
Jul 01 07:15:01.03: [28039]: <-- [9:AT+FRS=7\r]
Jul 01 07:15:01.11: [28039]: --> [2:OK]
Jul 01 07:15:01.11: [28039]: <-- [9:AT+FTH=3\r]
Jul 01 07:15:01.13: [28039]: --> [7:CONNECT]
Jul 01 07:15:01.13: [28039]: <-- HDLC<23:FF C0 C2 2E 96 76 AE B6 B6 F6 C2
04 D6 F6 F6 4E 42 04 9E 76 F6 2E CA>
Jul 01 07:15:01.13: [28039]: <-- data [23]
Jul 01 07:15:01.13: [28039]: <-- data [2]
Jul 01 07:15:02.67: [28039]: --> [7:CONNECT]
Jul 01 07:15:02.67: [28039]: <-- HDLC<6:FF C8 C1 00 63 1E>
Jul 01 07:15:02.67: [28039]: <-- data [6]
Jul 01 07:15:02.67: [28039]: <-- data [2]
Jul 01 07:15:03.03: [28039]: --> [2:OK]
Jul 01 07:15:03.03: [28039]: <-- [9:AT+FTS=7\r]
Jul 01 07:15:03.13: [28039]: --> [2:OK]
Jul 01 07:15:03.13: [28039]: MODEM set XON/XOFF/FLUSH: input interpreted,
output disabled
Jul 01 07:15:03.13: [28039]: <-- [10:AT+FTM=96\r]
Jul 01 07:15:03.15: [28039]: --> [7:CONNECT]
Jul 01 07:15:03.15: [28039]: DELAY 400 ms
Jul 01 07:15:03.55: [28039]: <-- data [1024]
Jul 01 07:15:03.55: [28039]: <-- data [776]
Jul 01 07:15:03.55: [28039]: <-- data [2]
Jul 01 07:15:05.11: [28039]: --> [2:OK]
Jul 01 07:15:05.11: [28039]: MODEM set XON/XOFF/DRAIN: input ignored,
output disabled
Jul 01 07:15:05.11: [28039]: <-- [9:AT+FRH=3\r]
Jul 01 07:15:08.21: [28039]: --> [0:]
Jul 01 07:15:08.21: [28039]: MODEM <Empty line>
Jul 01 07:15:08.21: [28039]: MODEM TIMEOUT: waiting for v.21 carrier
Jul 01 07:15:08.21: [28039]: <-- data [1]
Jul 01 07:15:08.23: [28039]: --> [2:OK]
Jul 01 07:15:08.23: [28039]: <-- [9:AT+FRS=7\r]
Jul 01 07:15:08.25: [28039]: --> [2:OK]
Jul 01 07:15:08.25: [28039]: SEND training at v.29 7200 bit/s
Jul 01 07:15:08.25: [28039]: <-- [9:AT+FTH=3\r]
Jul 01 07:15:08.27: [28039]: --> [7:CONNECT]
Jul 01 07:15:08.27: [28039]: <-- HDLC<23:FF C0 C2 2E 96 76 AE B6 B6 F6 C2
04 D6 F6 F6 4E 42 04 9E 76 F6 2E CA>
Jul 01 07:15:08.27: [28039]: <-- data [23]
Jul 01 07:15:08.27: [28039]: <-- data [2]
Jul 01 07:15:09.81: [28039]: --> [7:CONNECT]
Jul 01 07:15:09.81: [28039]: <-- HDLC<6:FF C8 C1 00 73 1E>
Jul 01 07:15:09.82: [28039]: <-- data [6]
Jul 01 07:15:09.82: [28039]: <-- data [2]
Jul 01 07:15:10.17: [28039]: --> [2:OK]
Jul 01 07:15:10.17: [28039]: <-- [9:AT+FTS=7\r]
Jul 01 07:15:10.27: [28039]: --> [2:OK]
Jul 01 07:15:10.27: [28039]: MODEM set XON/XOFF/FLUSH: input interpreted,
output disabled
Jul 01 07:15:10.27: [28039]: <-- [10:AT+FTM=72\r]
Jul 01 07:15:10.29: [28039]: --> [7:CONNECT]
Jul 01 07:15:10.29: [28039]: DELAY 400 ms
Jul 01 07:15:10.69: [28039]: <-- data [1024]
Jul 01 07:15:10.69: [28039]: <-- data [326]
Jul 01 07:15:10.69: [28039]: <-- data [2]
Jul 01 07:15:12.25: [28039]: --> [2:OK]
Jul 01 07:15:12.25: [28039]: MODEM set XON/XOFF/DRAIN: input ignored,
output disabled
Jul 01 07:15:12.25: [28039]: <-- [9:AT+FRH=3\r]
Jul 01 07:15:14.59: [28039]: --> [7:CONNECT]
Jul 01 07:15:15.49: [28039]: --> HDLC<5:FF C8 22 67 DD>
Jul 01 07:15:15.59: [28039]: --> [2:OK]
Jul 01 07:15:15.59: [28039]: SEND training at v.27ter 4800 bit/s
Jul 01 07:15:15.59: [28039]: <-- [9:AT+FRS=7\r]
Jul 01 07:15:15.65: [28039]: --> [2:OK]
Jul 01 07:15:15.65: [28039]: <-- [9:AT+FTH=3\r]
Jul 01 07:15:15.67: [28039]: --> [7:CONNECT]
Jul 01 07:15:15.67: [28039]: <-- HDLC<23:FF C0 C2 2E 96 76 AE B6 B6 F6 C2
04 D6 F6 F6 4E 42 04 9E 76 F6 2E CA>
Jul 01 07:15:15.67: [28039]: <-- data [23]
Jul 01 07:15:15.67: [28039]: <-- data [2]
Jul 01 07:15:17.21: [28039]: --> [7:CONNECT]
Jul 01 07:15:17.21: [28039]: <-- HDLC<6:FF C8 C1 00 53 1E>
Jul 01 07:15:17.21: [28039]: <-- data [6]
Jul 01 07:15:17.21: [28039]: <-- data [2]
Jul 01 07:15:17.57: [28039]: --> [2:OK]
Jul 01 07:15:17.57: [28039]: <-- [9:AT+FTS=7\r]
Jul 01 07:15:17.67: [28039]: --> [2:OK]
Jul 01 07:15:17.67: [28039]: MODEM set XON/XOFF/FLUSH: input interpreted,
output disabled
Jul 01 07:15:17.67: [28039]: <-- [10:AT+FTM=48\r]
Jul 01 07:15:17.69: [28039]: --> [7:CONNECT]
Jul 01 07:15:17.69: [28039]: DELAY 400 ms
Jul 01 07:15:18.09: [28039]: <-- data [900]
Jul 01 07:15:18.09: [28039]: <-- data [2]
Jul 01 07:15:19.97: [28039]: --> [2:OK]
Jul 01 07:15:19.97: [28039]: MODEM set XON/XOFF/DRAIN: input ignored,
output disabled
Jul 01 07:15:19.97: [28039]: <-- [9:AT+FRH=3\r]
Jul 01 07:15:20.85: [28039]: --> [7:CONNECT]
Jul 01 07:15:21.75: [28039]: --> HDLC<5:FF C8 22 67 DD>
Jul 01 07:15:21.85: [28039]: --> [2:OK]
Jul 01 07:15:21.85: [28039]: SEND training at v.27ter fallback mode 2400
bit/s
Jul 01 07:15:21.85: [28039]: <-- [9:AT+FRS=7\r]
Jul 01 07:15:21.91: [28039]: --> [2:OK]
Jul 01 07:15:21.91: [28039]: <-- [9:AT+FTH=3\r]
Jul 01 07:15:21.93: [28039]: --> [7:CONNECT]
Jul 01 07:15:21.93: [28039]: <-- HDLC<23:FF C0 C2 2E 96 76 AE B6 B6 F6 C2
04 D6 F6 F6 4E 42 04 9E 76 F6 2E CA>
Jul 01 07:15:21.93: [28039]: <-- data [23]
Jul 01 07:15:21.93: [28039]: <-- data [2]
Jul 01 07:15:23.47: [28039]: --> [7:CONNECT]
Jul 01 07:15:23.47: [28039]: <-- HDLC<6:FF C8 C1 00 43 1E>
Jul 01 07:15:23.47: [28039]: <-- data [6]
Jul 01 07:15:23.47: [28039]: <-- data [2]
Jul 01 07:15:23.83: [28039]: --> [2:OK]
Jul 01 07:15:23.83: [28039]: <-- [9:AT+FTS=7\r]
Jul 01 07:15:23.93: [28039]: --> [2:OK]
Jul 01 07:15:23.93: [28039]: MODEM set XON/XOFF/FLUSH: input interpreted,
output disabled
Jul 01 07:15:23.93: [28039]: <-- [10:AT+FTM=24\r]
Jul 01 07:15:23.95: [28039]: --> [7:CONNECT]
Jul 01 07:15:23.95: [28039]: DELAY 400 ms
Jul 01 07:15:24.35: [28039]: <-- data [450]
Jul 01 07:15:24.35: [28039]: <-- data [2]
Jul 01 07:15:26.47: [28039]: --> [2:OK]
Jul 01 07:15:26.47: [28039]: MODEM set XON/XOFF/DRAIN: input ignored,
output disabled
Jul 01 07:15:26.47: [28039]: <-- [9:AT+FRH=3\r]
Jul 01 07:15:27.35: [28039]: --> [7:CONNECT]
Jul 01 07:15:28.25: [28039]: --> HDLC<5:FF C8 22 67 DD>
Jul 01 07:15:28.35: [28039]: --> [2:OK]
Jul 01 07:15:28.35: [28039]: TRAINING failed
Jul 01 07:15:28.35: [28039]: SEND: Failure to train remote modem at 2400
bps or minimum speed {E137}; Giving up after 3 attempts to send same page
"docq/doc16109.pdf;1d81", dirnum 0
Jul 01 07:15:28.35: [28039]: <-- [9:AT+FRS=7\r]
Jul 01 07:15:28.41: [28039]: --> [2:OK]
Jul 01 07:15:28.41: [28039]: <-- [9:AT+FTH=3\r]
Jul 01 07:15:28.43: [28039]: --> [7:CONNECT]
Jul 01 07:15:28.43: [28039]: <-- HDLC<3:FF C8 DF>
Jul 01 07:15:28.43: [28039]: <-- data [3]
Jul 01 07:15:28.43: [28039]: <-- data [2]
Jul 01 07:15:29.51: [28039]: --> [2:OK]
Jul 01 07:15:29.51: [28039]: MODEM input buffering enabled
Jul 01 07:15:30.51: [28039]: <-- [5:ATH0\r]
Jul 01 07:15:30.63: [28039]: --> [2:OK]
Jul 01 07:15:30.63: [28039]: MODEM set DTR OFF
Jul 01 07:15:30.63: [28039]: MODEM set baud rate: 0 baud (flow control
unchanged)
Jul 01 07:15:30.63: [28039]: STATE CHANGE: SENDING -> MODEMWAIT (timeout 5)
Jul 01 07:15:30.63: [28039]: SESSION END
Lee Howard
Jul 1, 2015, 12:29:52 PM7/1/15
to Rehman, hylafa...@hylafax.org
On 07/01/2015 04:25 AM, Rehman wrote:
> Hi there,
>
> I am using Hylafax + 5.5.5 with IAX modem 1.2 and Asterisk 11.9 on a
> CentOS 6.5 server
>
>
> On some of my faxes, I get this error*"Failure to train remote modem
> Hi there,
>
> I am using Hylafax + 5.5.5 with IAX modem 1.2 and Asterisk 11.9 on a
> CentOS 6.5 server
>
>
> at 2400 bps or minimum speed {E137}; Giving up after 3 attempts to
> send same page"*,
>
> I was thinking of setting a special group of modems with specific
> settings that can minimize this. How can I minimize this error or
> eliminate this completely.
Line audio quality is too poor (as heard at the receiver) to train the
> send same page"*,
>
> I was thinking of setting a special group of modems with specific
> settings that can minimize this. How can I minimize this error or
> eliminate this completely.
fax signaling. To fix it you'll need to improve the call audio quality
wherever it is a problem. (That may not be on your end.)
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-us...@hylafax.org < /dev/null
*To learn about commercial HylaFAX(tm) support, mail sa...@ifax.com.*
Rehman
Jul 1, 2015, 12:33:35 PM7/1/15
to Lee Howard, hylafa...@hylafax.org
Thanks Lee!
That should be the only cause for this error ?
Any setting within IAXmodem config that can enhance training ?
Thanks,
On Wed, Jul 1, 2015 at 12:29 PM, Lee Howard <fax...@howardsilvan.com> wrote:
> On 07/01/2015 04:25 AM, Rehman wrote:
>
>> Hi there,
>>
>> I am using Hylafax + 5.5.5 with IAX modem 1.2 and Asterisk 11.9 on a
>> CentOS 6.5 server
>>
>>
>> On some of my faxes, I get this error*"Failure to train remote modem at
>> 2400 bps or minimum speed {E137}; Giving up after 3 attempts to send same
>> page"*,
>>
>> I was thinking of setting a special group of modems with specific
>> settings that can minimize this. How can I minimize this error or eliminate
>> this completely.
>>
>
> Line audio quality is too poor (as heard at the receiver) to train the fax
> signaling. To fix it you'll need to improve the call audio quality
> wherever it is a problem. (That may not be on your end.)
>
> Thanks,
>
> Lee.
>
>
--
--
Best Regards,
Rehman
Voice : +971-50-2013500
IM: a.re...@gmail.com
That should be the only cause for this error ?
Any setting within IAXmodem config that can enhance training ?
Thanks,
On Wed, Jul 1, 2015 at 12:29 PM, Lee Howard <fax...@howardsilvan.com> wrote:
> On 07/01/2015 04:25 AM, Rehman wrote:
>
>> Hi there,
>>
>> I am using Hylafax + 5.5.5 with IAX modem 1.2 and Asterisk 11.9 on a
>> CentOS 6.5 server
>>
>>
>> On some of my faxes, I get this error*"Failure to train remote modem at
>> 2400 bps or minimum speed {E137}; Giving up after 3 attempts to send same
>> page"*,
>>
>> I was thinking of setting a special group of modems with specific
>> settings that can minimize this. How can I minimize this error or eliminate
>> this completely.
>>
>
> Line audio quality is too poor (as heard at the receiver) to train the fax
> signaling. To fix it you'll need to improve the call audio quality
> wherever it is a problem. (That may not be on your end.)
>
> Thanks,
>
> Lee.
>
>
--
Best Regards,
Rehman
Voice : +971-50-2013500
IM: a.re...@gmail.com
Lee Howard
Jul 1, 2015, 12:52:06 PM7/1/15
to Rehman, hylafa...@hylafax.org
On 07/01/2015 09:32 AM, Rehman wrote:
> That should be the only cause for this error ?
Given the log file that you provided, yes. The receiver is rejecting
> That should be the only cause for this error ?
the training.
> Any setting within IAXmodem config that can enhance training ?
Rehman
Jul 1, 2015, 1:06:01 PM7/1/15
to Lee Howard, hylafa...@hylafax.org
Well here is another log with the same error, is that the same too ?
Jul 01 12:50:28.09: [ 4428]: SESSION BEGIN 000021471 3607347409
Jul 01 12:50:28.09: [ 4428]: HylaFAX (tm) Version 5.5.5
Jul 01 12:50:28.09: [ 4428]: SEND FAX: JOB 10293 DEST 3607347409 COMMID
000021471 DEVICE '/dev/ttyIAX6050' FROM 'Asterisk User <asterisk@localhost>'
USER asterisk
Jul 01 12:50:28.09: [ 4428]: STATE CHANGE: RUNNING -> SENDING
Jul 01 12:50:28.09: [ 4428]: <-- [12:AT+FCLASS=1\r]
Jul 01 12:50:28.09: [ 4428]: --> [2:OK]
Jul 01 12:50:28.09: [ 4428]: MODEM set XON/XOFF/FLUSH: input ignored,
output disabled
Jul 01 12:50:28.17: [ 4428]: 1-D encoding outperforms 2-D by 18%. Prefer MH
format.
Jul 01 12:50:28.17: [ 4428]: DIAL 3607347409
Jul 01 12:50:28.17: [ 4428]: <-- [15:ATDT3607347409\r]
Jul 01 12:50:46.29: [ 4428]: --> [7:CONNECT]
Jul 01 12:50:47.71: [ 4428]: --> HDLC<25:FF C0 02 04 04 04 04 04 04 04 04
9C 0C 2C EC B4 2C CC EC B4 0C 6C CC D3 E8>
Jul 01 12:50:47.71: [ 4428]: --> [2:OK]
Jul 01 12:50:47.71: [ 4428]: REMOTE CSI "360-734-7409"
Jul 01 12:50:47.71: [ 4428]: <-- [9:AT+FRH=3\r]
Jul 01 12:50:48.05: [ 4428]: --> [7:CONNECT]
Jul 01 12:50:48.05: [ 4428]: --> HDLC<8:FF C8 01 00 77 1E 80 40>
Jul 01 12:50:48.13: [ 4428]: --> [2:OK]
Jul 01 12:50:48.13: [ 4428]: REMOTE best rate 14400 bit/s
Jul 01 12:50:48.13: [ 4428]: REMOTE max A4 page width (215 mm)
Jul 01 12:50:48.13: [ 4428]: REMOTE max unlimited page length
Jul 01 12:50:48.13: [ 4428]: REMOTE best vres 7.7 line/mm
Jul 01 12:50:48.13: [ 4428]: REMOTE format support: MH, MR
Jul 01 12:50:48.13: [ 4428]: REMOTE best 0 ms/scanline
Jul 01 12:50:48.13: [ 4428]: USE 14400 bit/s
Jul 01 12:50:48.13: [ 4428]: SEND file "docq/doc16622.pdf;1d81"
Jul 01 12:50:48.13: [ 4428]: USE A4 page width (215 mm)
Jul 01 12:50:48.13: [ 4428]: USE unlimited page length
Jul 01 12:50:48.13: [ 4428]: USE 7.7 line/mm
Jul 01 12:50:48.13: [ 4428]: USE 1-D MH
Jul 01 12:50:48.13: [ 4428]: USE 0 ms/scanline
Jul 01 12:50:48.13: [ 4428]: SEND training at v.17 14400 bit/s
Jul 01 12:50:48.13: [ 4428]: <-- [9:AT+FRS=7\r]
Jul 01 12:50:48.19: [ 4428]: --> [2:OK]
Jul 01 12:50:48.19: [ 4428]: <-- [9:AT+FTH=3\r]
Jul 01 12:50:48.21: [ 4428]: --> [7:CONNECT]
Jul 01 12:50:48.21: [ 4428]: <-- HDLC<23:FF C0 C2 F6 36 F6 2E 86 B6 AE A6
16 4A 04 4E A6 D6 86 42 04 74 2A B2>
Jul 01 12:50:48.21: [ 4428]: <-- data [23]
Jul 01 12:50:48.21: [ 4428]: <-- data [2]
Jul 01 12:50:49.75: [ 4428]: --> [7:CONNECT]
Jul 01 12:50:49.75: [ 4428]: <-- HDLC<6:FF C8 C1 00 46 1E>
Jul 01 12:50:49.75: [ 4428]: <-- data [6]
Jul 01 12:50:49.75: [ 4428]: <-- data [2]
Jul 01 12:50:50.11: [ 4428]: --> [2:OK]
Jul 01 12:50:50.11: [ 4428]: <-- [9:AT+FTS=7\r]
Jul 01 12:50:50.21: [ 4428]: --> [2:OK]
Jul 01 12:50:50.21: [ 4428]: MODEM set XON/XOFF/FLUSH: input interpreted,
output disabled
Jul 01 12:50:50.21: [ 4428]: <-- [11:AT+FTM=145\r]
Jul 01 12:50:50.23: [ 4428]: --> [7:CONNECT]
Jul 01 12:50:50.23: [ 4428]: DELAY 400 ms
Jul 01 12:50:50.63: [ 4428]: <-- data [1024]
Jul 01 12:50:50.63: [ 4428]: <-- data [1024]
Jul 01 12:50:50.63: [ 4428]: <-- data [652]
Jul 01 12:50:50.63: [ 4428]: <-- data [2]
Jul 01 12:50:53.19: [ 4428]: --> [2:OK]
Jul 01 12:50:53.19: [ 4428]: MODEM set XON/XOFF/DRAIN: input ignored,
output disabled
Jul 01 12:50:53.19: [ 4428]: <-- [9:AT+FRH=3\r]
Jul 01 12:50:56.29: [ 4428]: --> [0:]
Jul 01 12:50:56.29: [ 4428]: MODEM <Empty line>
Jul 01 12:50:56.29: [ 4428]: MODEM TIMEOUT: waiting for v.21 carrier
Jul 01 12:50:56.29: [ 4428]: <-- data [1]
Jul 01 12:50:56.31: [ 4428]: --> [2:OK]
Jul 01 12:50:56.31: [ 4428]: <-- [9:AT+FRS=7\r]
Jul 01 12:50:56.33: [ 4428]: --> [2:OK]
Jul 01 12:50:56.33: [ 4428]: SEND training at v.17 12000 bit/s
Jul 01 12:50:56.33: [ 4428]: <-- [9:AT+FTH=3\r]
Jul 01 12:50:56.35: [ 4428]: --> [7:CONNECT]
Jul 01 12:50:56.35: [ 4428]: <-- HDLC<23:FF C0 C2 F6 36 F6 2E 86 B6 AE A6
16 4A 04 4E A6 D6 86 42 04 74 2A B2>
Jul 01 12:50:56.35: [ 4428]: <-- data [23]
Jul 01 12:50:56.35: [ 4428]: <-- data [2]
Jul 01 12:50:57.89: [ 4428]: --> [7:CONNECT]
Jul 01 12:50:57.89: [ 4428]: <-- HDLC<6:FF C8 C1 00 56 1E>
Jul 01 12:50:57.89: [ 4428]: <-- data [6]
Jul 01 12:50:57.89: [ 4428]: <-- data [2]
Jul 01 12:50:58.25: [ 4428]: --> [2:OK]
Jul 01 12:50:58.25: [ 4428]: <-- [9:AT+FTS=7\r]
Jul 01 12:50:58.33: [ 4428]: --> [2:OK]
Jul 01 12:50:58.33: [ 4428]: MODEM set XON/XOFF/FLUSH: input interpreted,
output disabled
Jul 01 12:50:58.33: [ 4428]: <-- [11:AT+FTM=121\r]
Jul 01 12:50:58.35: [ 4428]: --> [7:CONNECT]
Jul 01 12:50:58.35: [ 4428]: DELAY 400 ms
Jul 01 12:50:58.75: [ 4428]: <-- data [1024]
Jul 01 12:50:58.75: [ 4428]: <-- data [1024]
Jul 01 12:50:58.75: [ 4428]: <-- data [202]
Jul 01 12:50:58.75: [ 4428]: <-- data [2]
Jul 01 12:51:01.31: [ 4428]: --> [2:OK]
Jul 01 12:51:01.31: [ 4428]: MODEM set XON/XOFF/DRAIN: input ignored,
output disabled
Jul 01 12:51:01.31: [ 4428]: <-- [9:AT+FRH=3\r]
Jul 01 12:51:02.59: [ 4428]: --> [7:CONNECT]
Jul 01 12:51:03.49: [ 4428]: --> HDLC<5:FF C8 22 67 DD>
Jul 01 12:51:03.57: [ 4428]: --> [2:OK]
Jul 01 12:51:03.57: [ 4428]: SEND training at v.29 9600 bit/s
Jul 01 12:51:03.57: [ 4428]: <-- [9:AT+FRS=7\r]
Jul 01 12:51:03.65: [ 4428]: --> [2:OK]
Jul 01 12:51:03.65: [ 4428]: <-- [9:AT+FTH=3\r]
Jul 01 12:51:03.67: [ 4428]: --> [7:CONNECT]
Jul 01 12:51:03.67: [ 4428]: <-- HDLC<23:FF C0 C2 F6 36 F6 2E 86 B6 AE A6
16 4A 04 4E A6 D6 86 42 04 74 2A B2>
Jul 01 12:51:03.67: [ 4428]: <-- data [23]
Jul 01 12:51:03.67: [ 4428]: <-- data [2]
Jul 01 12:51:05.21: [ 4428]: --> [7:CONNECT]
Jul 01 12:51:05.21: [ 4428]: <-- HDLC<6:FF C8 C1 00 62 1E>
Jul 01 12:51:05.21: [ 4428]: <-- data [6]
Jul 01 12:51:05.21: [ 4428]: <-- data [2]
Jul 01 12:51:05.57: [ 4428]: --> [2:OK]
Jul 01 12:51:05.57: [ 4428]: <-- [9:AT+FTS=7\r]
Jul 01 12:51:05.67: [ 4428]: --> [2:OK]
Jul 01 12:51:05.67: [ 4428]: MODEM set XON/XOFF/FLUSH: input interpreted,
output disabled
Jul 01 12:51:05.67: [ 4428]: <-- [10:AT+FTM=96\r]
Jul 01 12:51:05.69: [ 4428]: --> [7:CONNECT]
Jul 01 12:51:05.69: [ 4428]: DELAY 400 ms
Jul 01 12:51:06.09: [ 4428]: <-- data [1024]
Jul 01 12:51:06.09: [ 4428]: <-- data [776]
Jul 01 12:51:06.09: [ 4428]: <-- data [2]
Jul 01 12:51:07.65: [ 4428]: --> [2:OK]
Jul 01 12:51:07.65: [ 4428]: MODEM set XON/XOFF/DRAIN: input ignored,
output disabled
Jul 01 12:51:07.65: [ 4428]: <-- [9:AT+FRH=3\r]
Jul 01 12:51:10.75: [ 4428]: --> [0:]
Jul 01 12:51:10.75: [ 4428]: MODEM <Empty line>
Jul 01 12:51:10.75: [ 4428]: MODEM TIMEOUT: waiting for v.21 carrier
Jul 01 12:51:10.75: [ 4428]: <-- data [1]
Jul 01 12:51:10.77: [ 4428]: --> [2:OK]
Jul 01 12:51:10.77: [ 4428]: <-- [9:AT+FRS=7\r]
Jul 01 12:51:10.79: [ 4428]: --> [2:OK]
Jul 01 12:51:10.79: [ 4428]: SEND training at v.29 7200 bit/s
Jul 01 12:51:10.79: [ 4428]: <-- [9:AT+FTH=3\r]
Jul 01 12:51:10.81: [ 4428]: --> [7:CONNECT]
Jul 01 12:51:10.81: [ 4428]: <-- HDLC<23:FF C0 C2 F6 36 F6 2E 86 B6 AE A6
16 4A 04 4E A6 D6 86 42 04 74 2A B2>
Jul 01 12:51:10.81: [ 4428]: <-- data [23]
Jul 01 12:51:10.81: [ 4428]: <-- data [2]
Jul 01 12:51:12.35: [ 4428]: --> [7:CONNECT]
Jul 01 12:51:12.35: [ 4428]: <-- HDLC<6:FF C8 C1 00 72 1E>
Jul 01 12:51:12.36: [ 4428]: <-- data [6]
Jul 01 12:51:12.36: [ 4428]: <-- data [2]
Jul 01 12:51:12.71: [ 4428]: --> [2:OK]
Jul 01 12:51:12.71: [ 4428]: <-- [9:AT+FTS=7\r]
Jul 01 12:51:12.81: [ 4428]: --> [2:OK]
Jul 01 12:51:12.81: [ 4428]: MODEM set XON/XOFF/FLUSH: input interpreted,
output disabled
Jul 01 12:51:12.81: [ 4428]: <-- [10:AT+FTM=72\r]
Jul 01 12:51:12.83: [ 4428]: --> [7:CONNECT]
Jul 01 12:51:12.83: [ 4428]: DELAY 400 ms
Jul 01 12:51:13.23: [ 4428]: <-- data [1024]
Jul 01 12:51:13.23: [ 4428]: <-- data [326]
Jul 01 12:51:13.23: [ 4428]: <-- data [2]
Jul 01 12:51:14.79: [ 4428]: --> [2:OK]
Jul 01 12:51:14.79: [ 4428]: MODEM set XON/XOFF/DRAIN: input ignored,
output disabled
Jul 01 12:51:14.79: [ 4428]: <-- [9:AT+FRH=3\r]
Jul 01 12:51:17.05: [ 4428]: --> [7:CONNECT]
Jul 01 12:51:17.95: [ 4428]: --> HDLC<5:FF C8 22 67 DD>
Jul 01 12:51:18.03: [ 4428]: --> [2:OK]
Jul 01 12:51:18.03: [ 4428]: SEND training at v.27ter 4800 bit/s
Jul 01 12:51:18.03: [ 4428]: <-- [9:AT+FRS=7\r]
Jul 01 12:51:18.11: [ 4428]: --> [2:OK]
Jul 01 12:51:18.11: [ 4428]: <-- [9:AT+FTH=3\r]
Jul 01 12:51:18.13: [ 4428]: --> [7:CONNECT]
Jul 01 12:51:18.13: [ 4428]: <-- HDLC<23:FF C0 C2 F6 36 F6 2E 86 B6 AE A6
16 4A 04 4E A6 D6 86 42 04 74 2A B2>
Jul 01 12:51:18.13: [ 4428]: <-- data [23]
Jul 01 12:51:18.13: [ 4428]: <-- data [2]
Jul 01 12:51:19.67: [ 4428]: --> [7:CONNECT]
Jul 01 12:51:19.67: [ 4428]: <-- HDLC<6:FF C8 C1 00 52 1E>
Jul 01 12:51:19.67: [ 4428]: <-- data [6]
Jul 01 12:51:19.67: [ 4428]: <-- data [2]
Jul 01 12:51:20.03: [ 4428]: --> [2:OK]
Jul 01 12:51:20.03: [ 4428]: <-- [9:AT+FTS=7\r]
Jul 01 12:51:20.13: [ 4428]: --> [2:OK]
Jul 01 12:51:20.13: [ 4428]: MODEM set XON/XOFF/FLUSH: input interpreted,
output disabled
Jul 01 12:51:20.13: [ 4428]: <-- [10:AT+FTM=48\r]
Jul 01 12:51:20.15: [ 4428]: --> [7:CONNECT]
Jul 01 12:51:20.15: [ 4428]: DELAY 400 ms
Jul 01 12:51:20.55: [ 4428]: <-- data [900]
Jul 01 12:51:20.55: [ 4428]: <-- data [2]
Jul 01 12:51:22.43: [ 4428]: --> [2:OK]
Jul 01 12:51:22.43: [ 4428]: MODEM set XON/XOFF/DRAIN: input ignored,
output disabled
Jul 01 12:51:22.43: [ 4428]: <-- [9:AT+FRH=3\r]
Jul 01 12:51:25.53: [ 4428]: --> [0:]
Jul 01 12:51:25.53: [ 4428]: MODEM <Empty line>
Jul 01 12:51:25.53: [ 4428]: MODEM TIMEOUT: waiting for v.21 carrier
Jul 01 12:51:25.53: [ 4428]: <-- data [1]
Jul 01 12:51:25.55: [ 4428]: --> [2:OK]
Jul 01 12:51:25.55: [ 4428]: <-- [9:AT+FRS=7\r]
Jul 01 12:51:25.57: [ 4428]: --> [2:OK]
Jul 01 12:51:25.57: [ 4428]: SEND training at v.27ter fallback mode 2400
bit/s
Jul 01 12:51:25.57: [ 4428]: <-- [9:AT+FTH=3\r]
Jul 01 12:51:25.59: [ 4428]: --> [7:CONNECT]
Jul 01 12:51:25.59: [ 4428]: <-- HDLC<23:FF C0 C2 F6 36 F6 2E 86 B6 AE A6
16 4A 04 4E A6 D6 86 42 04 74 2A B2>
Jul 01 12:51:25.59: [ 4428]: <-- data [23]
Jul 01 12:51:25.59: [ 4428]: <-- data [2]
Jul 01 12:51:27.13: [ 4428]: --> [7:CONNECT]
Jul 01 12:51:27.13: [ 4428]: <-- HDLC<6:FF C8 C1 00 42 1E>
Jul 01 12:51:27.13: [ 4428]: <-- data [6]
Jul 01 12:51:27.13: [ 4428]: <-- data [2]
Jul 01 12:51:27.49: [ 4428]: --> [2:OK]
Jul 01 12:51:27.49: [ 4428]: <-- [9:AT+FTS=7\r]
Jul 01 12:51:27.59: [ 4428]: --> [2:OK]
Jul 01 12:51:27.59: [ 4428]: MODEM set XON/XOFF/FLUSH: input interpreted,
output disabled
Jul 01 12:51:27.59: [ 4428]: <-- [10:AT+FTM=24\r]
Jul 01 12:51:27.61: [ 4428]: --> [7:CONNECT]
Jul 01 12:51:27.61: [ 4428]: DELAY 400 ms
Jul 01 12:51:28.01: [ 4428]: <-- data [450]
Jul 01 12:51:28.01: [ 4428]: <-- data [2]
Jul 01 12:51:30.13: [ 4428]: --> [2:OK]
Jul 01 12:51:30.13: [ 4428]: MODEM set XON/XOFF/DRAIN: input ignored,
output disabled
Jul 01 12:51:30.13: [ 4428]: <-- [9:AT+FRH=3\r]
Jul 01 12:51:30.95: [ 4428]: --> [7:CONNECT]
Jul 01 12:51:31.83: [ 4428]: --> HDLC<5:FF C8 22 67 DD>
Jul 01 12:51:31.93: [ 4428]: --> [2:OK]
Jul 01 12:51:31.93: [ 4428]: TRAINING failed
Jul 01 12:51:31.93: [ 4428]: SEND: Failure to train remote modem at 2400
Jul 01 12:51:31.93: [ 4428]: <-- [9:AT+FRS=7\r]
Jul 01 12:51:31.99: [ 4428]: --> [2:OK]
Jul 01 12:51:31.99: [ 4428]: <-- [9:AT+FTH=3\r]
Jul 01 12:51:32.01: [ 4428]: --> [7:CONNECT]
Jul 01 12:51:32.01: [ 4428]: <-- HDLC<3:FF C8 DF>
Jul 01 12:51:32.01: [ 4428]: <-- data [3]
Jul 01 12:51:32.01: [ 4428]: <-- data [2]
Jul 01 12:51:33.09: [ 4428]: --> [2:OK]
Jul 01 12:51:33.09: [ 4428]: MODEM input buffering enabled
Jul 01 12:51:34.09: [ 4428]: <-- [5:ATH0\r]
Jul 01 12:51:34.21: [ 4428]: --> [2:OK]
Jul 01 12:51:34.21: [ 4428]: MODEM set DTR OFF
Jul 01 12:51:34.21: [ 4428]: MODEM set baud rate: 0 baud (flow control
unchanged)
Jul 01 12:51:34.21: [ 4428]: STATE CHANGE: SENDING -> MODEMWAIT (timeout 5)
Jul 01 12:51:34.21: [ 4428]: SESSION END
On Wed, Jul 1, 2015 at 12:51 PM, Lee Howard <fax...@howardsilvan.com> wrote:
> On 07/01/2015 09:32 AM, Rehman wrote:
>
>> That should be the only cause for this error ?
>>
>
> Given the log file that you provided, yes. The receiver is rejecting the
> training.
>
> Any setting within IAXmodem config that can enhance training ?
>>
>
> No.
>
> Thanks,
>
> Lee.
>
>
Jul 01 12:50:28.09: [ 4428]: SESSION BEGIN 000021471 3607347409
Jul 01 12:50:28.09: [ 4428]: HylaFAX (tm) Version 5.5.5
Jul 01 12:50:28.09: [ 4428]: SEND FAX: JOB 10293 DEST 3607347409 COMMID
000021471 DEVICE '/dev/ttyIAX6050' FROM 'Asterisk User <asterisk@localhost>'
USER asterisk
Jul 01 12:50:28.09: [ 4428]: STATE CHANGE: RUNNING -> SENDING
Jul 01 12:50:28.09: [ 4428]: <-- [12:AT+FCLASS=1\r]
Jul 01 12:50:28.09: [ 4428]: --> [2:OK]
Jul 01 12:50:28.09: [ 4428]: MODEM set XON/XOFF/FLUSH: input ignored,
output disabled
Jul 01 12:50:28.17: [ 4428]: 1-D encoding outperforms 2-D by 18%. Prefer MH
format.
Jul 01 12:50:28.17: [ 4428]: DIAL 3607347409
Jul 01 12:50:28.17: [ 4428]: <-- [15:ATDT3607347409\r]
Jul 01 12:50:46.29: [ 4428]: --> [7:CONNECT]
Jul 01 12:50:47.71: [ 4428]: --> HDLC<25:FF C0 02 04 04 04 04 04 04 04 04
9C 0C 2C EC B4 2C CC EC B4 0C 6C CC D3 E8>
Jul 01 12:50:47.71: [ 4428]: --> [2:OK]
Jul 01 12:50:47.71: [ 4428]: REMOTE CSI "360-734-7409"
Jul 01 12:50:47.71: [ 4428]: <-- [9:AT+FRH=3\r]
Jul 01 12:50:48.05: [ 4428]: --> [7:CONNECT]
Jul 01 12:50:48.05: [ 4428]: --> HDLC<8:FF C8 01 00 77 1E 80 40>
Jul 01 12:50:48.13: [ 4428]: --> [2:OK]
Jul 01 12:50:48.13: [ 4428]: REMOTE best rate 14400 bit/s
Jul 01 12:50:48.13: [ 4428]: REMOTE max A4 page width (215 mm)
Jul 01 12:50:48.13: [ 4428]: REMOTE max unlimited page length
Jul 01 12:50:48.13: [ 4428]: REMOTE best vres 7.7 line/mm
Jul 01 12:50:48.13: [ 4428]: REMOTE format support: MH, MR
Jul 01 12:50:48.13: [ 4428]: REMOTE best 0 ms/scanline
Jul 01 12:50:48.13: [ 4428]: USE 14400 bit/s
Jul 01 12:50:48.13: [ 4428]: SEND file "docq/doc16622.pdf;1d81"
Jul 01 12:50:48.13: [ 4428]: USE A4 page width (215 mm)
Jul 01 12:50:48.13: [ 4428]: USE unlimited page length
Jul 01 12:50:48.13: [ 4428]: USE 7.7 line/mm
Jul 01 12:50:48.13: [ 4428]: USE 1-D MH
Jul 01 12:50:48.13: [ 4428]: USE 0 ms/scanline
Jul 01 12:50:48.13: [ 4428]: SEND training at v.17 14400 bit/s
Jul 01 12:50:48.13: [ 4428]: <-- [9:AT+FRS=7\r]
Jul 01 12:50:48.19: [ 4428]: --> [2:OK]
Jul 01 12:50:48.19: [ 4428]: <-- [9:AT+FTH=3\r]
Jul 01 12:50:48.21: [ 4428]: --> [7:CONNECT]
Jul 01 12:50:48.21: [ 4428]: <-- HDLC<23:FF C0 C2 F6 36 F6 2E 86 B6 AE A6
16 4A 04 4E A6 D6 86 42 04 74 2A B2>
Jul 01 12:50:48.21: [ 4428]: <-- data [23]
Jul 01 12:50:48.21: [ 4428]: <-- data [2]
Jul 01 12:50:49.75: [ 4428]: --> [7:CONNECT]
Jul 01 12:50:49.75: [ 4428]: <-- HDLC<6:FF C8 C1 00 46 1E>
Jul 01 12:50:49.75: [ 4428]: <-- data [6]
Jul 01 12:50:49.75: [ 4428]: <-- data [2]
Jul 01 12:50:50.11: [ 4428]: --> [2:OK]
Jul 01 12:50:50.11: [ 4428]: <-- [9:AT+FTS=7\r]
Jul 01 12:50:50.21: [ 4428]: --> [2:OK]
Jul 01 12:50:50.21: [ 4428]: MODEM set XON/XOFF/FLUSH: input interpreted,
output disabled
Jul 01 12:50:50.21: [ 4428]: <-- [11:AT+FTM=145\r]
Jul 01 12:50:50.23: [ 4428]: --> [7:CONNECT]
Jul 01 12:50:50.23: [ 4428]: DELAY 400 ms
Jul 01 12:50:50.63: [ 4428]: <-- data [1024]
Jul 01 12:50:50.63: [ 4428]: <-- data [1024]
Jul 01 12:50:50.63: [ 4428]: <-- data [652]
Jul 01 12:50:50.63: [ 4428]: <-- data [2]
Jul 01 12:50:53.19: [ 4428]: --> [2:OK]
Jul 01 12:50:53.19: [ 4428]: MODEM set XON/XOFF/DRAIN: input ignored,
output disabled
Jul 01 12:50:53.19: [ 4428]: <-- [9:AT+FRH=3\r]
Jul 01 12:50:56.29: [ 4428]: --> [0:]
Jul 01 12:50:56.29: [ 4428]: MODEM <Empty line>
Jul 01 12:50:56.29: [ 4428]: MODEM TIMEOUT: waiting for v.21 carrier
Jul 01 12:50:56.29: [ 4428]: <-- data [1]
Jul 01 12:50:56.31: [ 4428]: --> [2:OK]
Jul 01 12:50:56.31: [ 4428]: <-- [9:AT+FRS=7\r]
Jul 01 12:50:56.33: [ 4428]: --> [2:OK]
Jul 01 12:50:56.33: [ 4428]: SEND training at v.17 12000 bit/s
Jul 01 12:50:56.33: [ 4428]: <-- [9:AT+FTH=3\r]
Jul 01 12:50:56.35: [ 4428]: --> [7:CONNECT]
Jul 01 12:50:56.35: [ 4428]: <-- HDLC<23:FF C0 C2 F6 36 F6 2E 86 B6 AE A6
16 4A 04 4E A6 D6 86 42 04 74 2A B2>
Jul 01 12:50:56.35: [ 4428]: <-- data [23]
Jul 01 12:50:56.35: [ 4428]: <-- data [2]
Jul 01 12:50:57.89: [ 4428]: --> [7:CONNECT]
Jul 01 12:50:57.89: [ 4428]: <-- HDLC<6:FF C8 C1 00 56 1E>
Jul 01 12:50:57.89: [ 4428]: <-- data [6]
Jul 01 12:50:57.89: [ 4428]: <-- data [2]
Jul 01 12:50:58.25: [ 4428]: --> [2:OK]
Jul 01 12:50:58.25: [ 4428]: <-- [9:AT+FTS=7\r]
Jul 01 12:50:58.33: [ 4428]: --> [2:OK]
Jul 01 12:50:58.33: [ 4428]: MODEM set XON/XOFF/FLUSH: input interpreted,
output disabled
Jul 01 12:50:58.33: [ 4428]: <-- [11:AT+FTM=121\r]
Jul 01 12:50:58.35: [ 4428]: --> [7:CONNECT]
Jul 01 12:50:58.35: [ 4428]: DELAY 400 ms
Jul 01 12:50:58.75: [ 4428]: <-- data [1024]
Jul 01 12:50:58.75: [ 4428]: <-- data [1024]
Jul 01 12:50:58.75: [ 4428]: <-- data [202]
Jul 01 12:50:58.75: [ 4428]: <-- data [2]
Jul 01 12:51:01.31: [ 4428]: --> [2:OK]
Jul 01 12:51:01.31: [ 4428]: MODEM set XON/XOFF/DRAIN: input ignored,
output disabled
Jul 01 12:51:01.31: [ 4428]: <-- [9:AT+FRH=3\r]
Jul 01 12:51:02.59: [ 4428]: --> [7:CONNECT]
Jul 01 12:51:03.49: [ 4428]: --> HDLC<5:FF C8 22 67 DD>
Jul 01 12:51:03.57: [ 4428]: --> [2:OK]
Jul 01 12:51:03.57: [ 4428]: SEND training at v.29 9600 bit/s
Jul 01 12:51:03.57: [ 4428]: <-- [9:AT+FRS=7\r]
Jul 01 12:51:03.65: [ 4428]: --> [2:OK]
Jul 01 12:51:03.65: [ 4428]: <-- [9:AT+FTH=3\r]
Jul 01 12:51:03.67: [ 4428]: --> [7:CONNECT]
Jul 01 12:51:03.67: [ 4428]: <-- HDLC<23:FF C0 C2 F6 36 F6 2E 86 B6 AE A6
16 4A 04 4E A6 D6 86 42 04 74 2A B2>
Jul 01 12:51:03.67: [ 4428]: <-- data [23]
Jul 01 12:51:03.67: [ 4428]: <-- data [2]
Jul 01 12:51:05.21: [ 4428]: --> [7:CONNECT]
Jul 01 12:51:05.21: [ 4428]: <-- HDLC<6:FF C8 C1 00 62 1E>
Jul 01 12:51:05.21: [ 4428]: <-- data [6]
Jul 01 12:51:05.21: [ 4428]: <-- data [2]
Jul 01 12:51:05.57: [ 4428]: --> [2:OK]
Jul 01 12:51:05.57: [ 4428]: <-- [9:AT+FTS=7\r]
Jul 01 12:51:05.67: [ 4428]: --> [2:OK]
Jul 01 12:51:05.67: [ 4428]: MODEM set XON/XOFF/FLUSH: input interpreted,
output disabled
Jul 01 12:51:05.67: [ 4428]: <-- [10:AT+FTM=96\r]
Jul 01 12:51:05.69: [ 4428]: --> [7:CONNECT]
Jul 01 12:51:05.69: [ 4428]: DELAY 400 ms
Jul 01 12:51:06.09: [ 4428]: <-- data [1024]
Jul 01 12:51:06.09: [ 4428]: <-- data [776]
Jul 01 12:51:06.09: [ 4428]: <-- data [2]
Jul 01 12:51:07.65: [ 4428]: --> [2:OK]
Jul 01 12:51:07.65: [ 4428]: MODEM set XON/XOFF/DRAIN: input ignored,
output disabled
Jul 01 12:51:07.65: [ 4428]: <-- [9:AT+FRH=3\r]
Jul 01 12:51:10.75: [ 4428]: --> [0:]
Jul 01 12:51:10.75: [ 4428]: MODEM <Empty line>
Jul 01 12:51:10.75: [ 4428]: MODEM TIMEOUT: waiting for v.21 carrier
Jul 01 12:51:10.75: [ 4428]: <-- data [1]
Jul 01 12:51:10.77: [ 4428]: --> [2:OK]
Jul 01 12:51:10.77: [ 4428]: <-- [9:AT+FRS=7\r]
Jul 01 12:51:10.79: [ 4428]: --> [2:OK]
Jul 01 12:51:10.79: [ 4428]: SEND training at v.29 7200 bit/s
Jul 01 12:51:10.79: [ 4428]: <-- [9:AT+FTH=3\r]
Jul 01 12:51:10.81: [ 4428]: --> [7:CONNECT]
Jul 01 12:51:10.81: [ 4428]: <-- HDLC<23:FF C0 C2 F6 36 F6 2E 86 B6 AE A6
16 4A 04 4E A6 D6 86 42 04 74 2A B2>
Jul 01 12:51:10.81: [ 4428]: <-- data [23]
Jul 01 12:51:10.81: [ 4428]: <-- data [2]
Jul 01 12:51:12.35: [ 4428]: --> [7:CONNECT]
Jul 01 12:51:12.35: [ 4428]: <-- HDLC<6:FF C8 C1 00 72 1E>
Jul 01 12:51:12.36: [ 4428]: <-- data [6]
Jul 01 12:51:12.36: [ 4428]: <-- data [2]
Jul 01 12:51:12.71: [ 4428]: --> [2:OK]
Jul 01 12:51:12.71: [ 4428]: <-- [9:AT+FTS=7\r]
Jul 01 12:51:12.81: [ 4428]: --> [2:OK]
Jul 01 12:51:12.81: [ 4428]: MODEM set XON/XOFF/FLUSH: input interpreted,
output disabled
Jul 01 12:51:12.81: [ 4428]: <-- [10:AT+FTM=72\r]
Jul 01 12:51:12.83: [ 4428]: --> [7:CONNECT]
Jul 01 12:51:12.83: [ 4428]: DELAY 400 ms
Jul 01 12:51:13.23: [ 4428]: <-- data [1024]
Jul 01 12:51:13.23: [ 4428]: <-- data [326]
Jul 01 12:51:13.23: [ 4428]: <-- data [2]
Jul 01 12:51:14.79: [ 4428]: --> [2:OK]
Jul 01 12:51:14.79: [ 4428]: MODEM set XON/XOFF/DRAIN: input ignored,
output disabled
Jul 01 12:51:14.79: [ 4428]: <-- [9:AT+FRH=3\r]
Jul 01 12:51:17.05: [ 4428]: --> [7:CONNECT]
Jul 01 12:51:17.95: [ 4428]: --> HDLC<5:FF C8 22 67 DD>
Jul 01 12:51:18.03: [ 4428]: --> [2:OK]
Jul 01 12:51:18.03: [ 4428]: SEND training at v.27ter 4800 bit/s
Jul 01 12:51:18.03: [ 4428]: <-- [9:AT+FRS=7\r]
Jul 01 12:51:18.11: [ 4428]: --> [2:OK]
Jul 01 12:51:18.11: [ 4428]: <-- [9:AT+FTH=3\r]
Jul 01 12:51:18.13: [ 4428]: --> [7:CONNECT]
Jul 01 12:51:18.13: [ 4428]: <-- HDLC<23:FF C0 C2 F6 36 F6 2E 86 B6 AE A6
16 4A 04 4E A6 D6 86 42 04 74 2A B2>
Jul 01 12:51:18.13: [ 4428]: <-- data [23]
Jul 01 12:51:18.13: [ 4428]: <-- data [2]
Jul 01 12:51:19.67: [ 4428]: --> [7:CONNECT]
Jul 01 12:51:19.67: [ 4428]: <-- HDLC<6:FF C8 C1 00 52 1E>
Jul 01 12:51:19.67: [ 4428]: <-- data [6]
Jul 01 12:51:19.67: [ 4428]: <-- data [2]
Jul 01 12:51:20.03: [ 4428]: --> [2:OK]
Jul 01 12:51:20.03: [ 4428]: <-- [9:AT+FTS=7\r]
Jul 01 12:51:20.13: [ 4428]: --> [2:OK]
Jul 01 12:51:20.13: [ 4428]: MODEM set XON/XOFF/FLUSH: input interpreted,
output disabled
Jul 01 12:51:20.13: [ 4428]: <-- [10:AT+FTM=48\r]
Jul 01 12:51:20.15: [ 4428]: --> [7:CONNECT]
Jul 01 12:51:20.15: [ 4428]: DELAY 400 ms
Jul 01 12:51:20.55: [ 4428]: <-- data [900]
Jul 01 12:51:20.55: [ 4428]: <-- data [2]
Jul 01 12:51:22.43: [ 4428]: --> [2:OK]
Jul 01 12:51:22.43: [ 4428]: MODEM set XON/XOFF/DRAIN: input ignored,
output disabled
Jul 01 12:51:22.43: [ 4428]: <-- [9:AT+FRH=3\r]
Jul 01 12:51:25.53: [ 4428]: --> [0:]
Jul 01 12:51:25.53: [ 4428]: MODEM <Empty line>
Jul 01 12:51:25.53: [ 4428]: MODEM TIMEOUT: waiting for v.21 carrier
Jul 01 12:51:25.53: [ 4428]: <-- data [1]
Jul 01 12:51:25.55: [ 4428]: --> [2:OK]
Jul 01 12:51:25.55: [ 4428]: <-- [9:AT+FRS=7\r]
Jul 01 12:51:25.57: [ 4428]: --> [2:OK]
Jul 01 12:51:25.57: [ 4428]: SEND training at v.27ter fallback mode 2400
bit/s
Jul 01 12:51:25.57: [ 4428]: <-- [9:AT+FTH=3\r]
Jul 01 12:51:25.59: [ 4428]: --> [7:CONNECT]
Jul 01 12:51:25.59: [ 4428]: <-- HDLC<23:FF C0 C2 F6 36 F6 2E 86 B6 AE A6
16 4A 04 4E A6 D6 86 42 04 74 2A B2>
Jul 01 12:51:25.59: [ 4428]: <-- data [23]
Jul 01 12:51:25.59: [ 4428]: <-- data [2]
Jul 01 12:51:27.13: [ 4428]: --> [7:CONNECT]
Jul 01 12:51:27.13: [ 4428]: <-- HDLC<6:FF C8 C1 00 42 1E>
Jul 01 12:51:27.13: [ 4428]: <-- data [6]
Jul 01 12:51:27.13: [ 4428]: <-- data [2]
Jul 01 12:51:27.49: [ 4428]: --> [2:OK]
Jul 01 12:51:27.49: [ 4428]: <-- [9:AT+FTS=7\r]
Jul 01 12:51:27.59: [ 4428]: --> [2:OK]
Jul 01 12:51:27.59: [ 4428]: MODEM set XON/XOFF/FLUSH: input interpreted,
output disabled
Jul 01 12:51:27.59: [ 4428]: <-- [10:AT+FTM=24\r]
Jul 01 12:51:27.61: [ 4428]: --> [7:CONNECT]
Jul 01 12:51:27.61: [ 4428]: DELAY 400 ms
Jul 01 12:51:28.01: [ 4428]: <-- data [450]
Jul 01 12:51:28.01: [ 4428]: <-- data [2]
Jul 01 12:51:30.13: [ 4428]: --> [2:OK]
Jul 01 12:51:30.13: [ 4428]: MODEM set XON/XOFF/DRAIN: input ignored,
output disabled
Jul 01 12:51:30.13: [ 4428]: <-- [9:AT+FRH=3\r]
Jul 01 12:51:30.95: [ 4428]: --> [7:CONNECT]
Jul 01 12:51:31.83: [ 4428]: --> HDLC<5:FF C8 22 67 DD>
Jul 01 12:51:31.93: [ 4428]: --> [2:OK]
Jul 01 12:51:31.93: [ 4428]: TRAINING failed
Jul 01 12:51:31.93: [ 4428]: SEND: Failure to train remote modem at 2400
bps or minimum speed {E137}; Giving up after 3 attempts to send same page
"docq/doc16622.pdf;1d81", dirnum 0
Jul 01 12:51:31.93: [ 4428]: <-- [9:AT+FRS=7\r]
Jul 01 12:51:31.99: [ 4428]: --> [2:OK]
Jul 01 12:51:31.99: [ 4428]: <-- [9:AT+FTH=3\r]
Jul 01 12:51:32.01: [ 4428]: --> [7:CONNECT]
Jul 01 12:51:32.01: [ 4428]: <-- HDLC<3:FF C8 DF>
Jul 01 12:51:32.01: [ 4428]: <-- data [3]
Jul 01 12:51:32.01: [ 4428]: <-- data [2]
Jul 01 12:51:33.09: [ 4428]: --> [2:OK]
Jul 01 12:51:33.09: [ 4428]: MODEM input buffering enabled
Jul 01 12:51:34.09: [ 4428]: <-- [5:ATH0\r]
Jul 01 12:51:34.21: [ 4428]: --> [2:OK]
Jul 01 12:51:34.21: [ 4428]: MODEM set DTR OFF
Jul 01 12:51:34.21: [ 4428]: MODEM set baud rate: 0 baud (flow control
unchanged)
Jul 01 12:51:34.21: [ 4428]: STATE CHANGE: SENDING -> MODEMWAIT (timeout 5)
Jul 01 12:51:34.21: [ 4428]: SESSION END
On Wed, Jul 1, 2015 at 12:51 PM, Lee Howard <fax...@howardsilvan.com> wrote:
> On 07/01/2015 09:32 AM, Rehman wrote:
>
>> That should be the only cause for this error ?
>>
>
> Given the log file that you provided, yes. The receiver is rejecting the
> training.
>
> Any setting within IAXmodem config that can enhance training ?
>>
>
> No.
>
> Thanks,
>
> Lee.
>
>
Lee Howard
Jul 1, 2015, 1:11:55 PM7/1/15
to Rehman, hylafa...@hylafax.org
On 07/01/2015 10:05 AM, Rehman wrote:
> Well here is another log with the same error, is that the same too ?
>
> Well here is another log with the same error, is that the same too ?
>
> Jul 01 12:51:30.13: [ 4428]: <-- [9:AT+FRH=3\r]
> Jul 01 12:51:30.95: [ 4428]: --> [7:CONNECT]
> Jul 01 12:51:31.83: [ 4428]: --> HDLC<5:FF C8 22 67 DD>
> Jul 01 12:51:31.93: [ 4428]: --> [2:OK]
> Jul 01 12:51:31.93: [ 4428]: TRAINING failed
Yes. Here you see the FTT signal coming from the receiver.
> Jul 01 12:51:30.95: [ 4428]: --> [7:CONNECT]
> Jul 01 12:51:31.83: [ 4428]: --> HDLC<5:FF C8 22 67 DD>
> Jul 01 12:51:31.93: [ 4428]: --> [2:OK]
> Jul 01 12:51:31.93: [ 4428]: TRAINING failed
Xtoree
Oct 30, 2023, 2:11:23 AM10/30/23
to
I have this problem too in my issabel server sending over SIP some how hylafax has issue with this part i tested with T38modem FSP app from web it worked through the same SIP connection but from normal issabel with internal hylafax has this error.
0 new messages