[hylafax-users] abundance of Send errors

["George H" <george.dma@xxxxxxxxx>]

Hi again,

I'm getting unusually high error rates on my other faxserver set to
send only. I've checked the xferfaxstats and xferfaxlog and came up
with some really unhappy statistics.

Faxserver: HylaFAX+ v5.1.12 to 5.2.1
Modems: USR 5631
Line Type: Goes through a Mitel gateway

Reciever: Brother MFC-3100C/MFC-8600
Modulation Support: 14400/12000/9600/7200/4800/2400 bps; Automatic Fallback
Compression: MH/ MR/ MMR/ JPEG
ECM: Yes
Note: Same site as FaxServer, behind mitel gateway

Before anything I know about the USR modem and that it's crappy yes,
but I ran out of multitech modems to replace them with. Either way did
a test with another faxserver using the USR sending it to a Richo
fax/photocopier machine and all faxes succeeded except for 1 error
that was related to the modem (bad support for batch faxing).

The Fax Server stats (using xferfaxstats) are
532 pages, 646 calls, 364 errors

Looks very bad to me.

For the above faxmachine I tracked all the errors and successes from xferfaxlog.
Success is when REASON column is empty
Error is when REASON column has a comment in it with an Exxx code
Record counted where the ENTRYTYPE clolumn is = to SEND

Total: 136
Errors: 88
Success: 48

40  No receiver protocol (T.30 T1 timeout) {E126}
48  Failure to train modems are minimum speed 2400 {E137}
48  Success but see E126 errors inside session logs

Attached are 3 session logs depicting a E126, E136 error and 1
success. All 136 session logs are the same as one of those 3. Log
c000001038 is the "successful" one btw.

Aside from the modem being a USR doesn't it seem that the errors are
too much ? Could this be a problem with the Mitel gateway ?
Thanks for any help I can get.
-- 
"Nothing is impossible for the person that doesn't have to do it"
"The probability of anything happening is in inverse ratio to its desirability"
--
George H
george.dma@xxxxxxxxx

Jan 17 10:27:28.14: [14408]: SESSION BEGIN 000001038 +1231231231
Jan 17 10:27:28.14: [14408]: HylaFAX (tm) Version 5.2.1
Jan 17 10:27:28.14: [14408]: SEND FAX: JOB 392 DEST 01/444444 COMMID 000001038 DEVICE '/dev/ttyS1' FROM 'User <user@xxxxxxxxxxxxxxxxxxxxx>' USER uucp
Jan 17 10:27:28.14: [14408]: STATE CHANGE: RUNNING -> SENDING
Jan 17 10:27:28.14: [14408]: <-- [12:AT+FCLASS=1\r]
Jan 17 10:27:28.19: [14408]: --> [2:OK]
Jan 17 10:27:28.19: [14408]: MODEM set XON/XOFF/FLUSH: input ignored, output disabled
Jan 17 10:27:28.19: [14408]: DIAL 01/444444
Jan 17 10:27:28.19: [14408]: <-- [14:ATDT01/444444\r]
Jan 17 10:27:57.14: [14408]: --> [7:CONNECT]
Jan 17 10:27:58.37: [14408]: --> HDLC<16:FF C0 04 00 00 6A AA AA 00 31 09 01 65 20 8C 89>
Jan 17 10:27:58.38: [14408]: --> [2:OK]
Jan 17 10:27:58.38: [14408]: REMOTE NSF "00 00 56 55 55 00 8C 90 80 A6 04"
Jan 17 10:27:58.38: [14408]: NSF remote fax equipment: Brother MFC-3100C/MFC-8600
Jan 17 10:27:58.38: [14408]: <-- [9:AT+FRH=3\r]
Jan 17 10:27:58.43: [14408]: --> [7:CONNECT]
Jan 17 10:27:59.09: [14408]: --> HDLC<25:FF C0 02 0C 2C 6C 4C 04 04 04 04 04 04 04 04 04 04 04 04 04 04 04 04 E9 F5>
Jan 17 10:27:59.10: [14408]: --> [2:OK]
Jan 17 10:27:59.10: [14408]: REMOTE CSI "2640"
Jan 17 10:27:59.10: [14408]: <-- [9:AT+FRH=3\r]
Jan 17 10:27:59.15: [14408]: --> [7:CONNECT]
Jan 17 10:27:59.41: [14408]: --> HDLC<11:FF C8 01 00 77 15 23 01 88 E7 CD>
Jan 17 10:27:59.42: [14408]: --> [2:OK]
Jan 17 10:27:59.42: [14408]: REMOTE best rate 14400 bit/s
Jan 17 10:27:59.42: [14408]: REMOTE max A4 page width (215 mm)
Jan 17 10:27:59.42: [14408]: REMOTE max unlimited page length
Jan 17 10:27:59.42: [14408]: REMOTE best vres 15.4 line/mm
Jan 17 10:27:59.42: [14408]: REMOTE format support: MH, MR, MMR
Jan 17 10:27:59.42: [14408]: REMOTE supports T.30 Annex A, 256-byte ECM
Jan 17 10:27:59.42: [14408]: REMOTE best 10 ms/scanline
Jan 17 10:27:59.42: [14408]: USE 14400 bit/s
Jan 17 10:27:59.42: [14408]: USE error correction mode
Jan 17 10:27:59.42: [14408]: SEND file "docq/doc392.ps;f0"
Jan 17 10:27:59.42: [14408]: USE A4 page width (215 mm)
Jan 17 10:27:59.42: [14408]: USE unlimited page length
Jan 17 10:27:59.42: [14408]: USE 3.85 line/mm
Jan 17 10:27:59.42: [14408]: USE 2-D MMR
Jan 17 10:27:59.42: [14408]: USE 0 ms/scanline
Jan 17 10:27:59.42: [14408]: SEND training at v.17 14400 bit/s
Jan 17 10:27:59.42: [14408]: DELAY 70 ms
Jan 17 10:27:59.50: [14408]: <-- [9:AT+FTH=3\r]
Jan 17 10:27:59.57: [14408]: --> [7:CONNECT]
Jan 17 10:27:59.57: [14408]: <-- HDLC<23:FF C0 C2 4E A6 6E 4E A6 CA 04 1E 86 62 04 B2 F2 32 82 42 04 04 04 04>
Jan 17 10:27:59.57: [14408]: <-- data [23]
Jan 17 10:27:59.57: [14408]: <-- data [2]
Jan 17 10:28:01.35: [14408]: --> [7:CONNECT]
Jan 17 10:28:01.35: [14408]: <-- HDLC<7:FF C8 C1 00 44 1F 22>
Jan 17 10:28:01.35: [14408]: <-- data [7]
Jan 17 10:28:01.35: [14408]: <-- data [2]
Jan 17 10:28:01.80: [14408]: --> [2:OK]
Jan 17 10:28:01.80: [14408]: <-- [9:AT+FTS=7\r]
Jan 17 10:28:01.93: [14408]: --> [2:OK]
Jan 17 10:28:01.93: [14408]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled
Jan 17 10:28:01.93: [14408]: <-- [11:AT+FTM=145\r]
Jan 17 10:28:02.01: [14408]: --> [7:CONNECT]
Jan 17 10:28:02.01: [14408]: <-- data [1024]
Jan 17 10:28:02.01: [14408]: <-- data [1024]
Jan 17 10:28:02.01: [14408]: <-- data [652]
Jan 17 10:28:02.01: [14408]: <-- data [2]
Jan 17 10:28:05.12: [14408]: --> [2:OK]
Jan 17 10:28:05.12: [14408]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled
Jan 17 10:28:05.12: [14408]: DELAY 10 ms
Jan 17 10:28:05.13: [14408]: <-- [9:AT+FRH=3\r]
Jan 17 10:28:05.59: [14408]: --> [7:CONNECT]
Jan 17 10:28:06.55: [14408]: --> HDLC<5:FF C8 21 57 BE>
Jan 17 10:28:06.56: [14408]: --> [2:OK]
Jan 17 10:28:06.56: [14408]: TRAINING succeeded
Jan 17 10:28:06.56: [14408]: SEND begin page
Jan 17 10:28:06.56: [14408]: SEND send frame number 0
Jan 17 10:28:06.56: [14408]: SEND send frame number 1
Jan 17 10:28:06.56: [14408]: SEND send frame number 2
Jan 17 10:28:06.56: [14408]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled
Jan 17 10:28:06.56: [14408]: DELAY 70 ms
Jan 17 10:28:06.64: [14408]: <-- [11:AT+FTM=146\r]
Jan 17 10:28:06.71: [14408]: --> [7:CONNECT]
Jan 17 10:28:06.71: [14408]: <-- data [1036]
Jan 17 10:28:06.71: [14408]: <-- data [181]
Jan 17 10:28:06.71: [14408]: <-- data [2]
Jan 17 10:28:07.74: [14408]: --> [2:OK]
Jan 17 10:28:07.74: [14408]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled
Jan 17 10:28:07.74: [14408]: <-- [9:AT+FTS=7\r]
Jan 17 10:28:07.87: [14408]: --> [2:OK]
Jan 17 10:28:07.87: [14408]: <-- [9:AT+FTH=3\r]
Jan 17 10:28:07.95: [14408]: --> [7:CONNECT]
Jan 17 10:28:07.95: [14408]: <-- HDLC<7:FF C8 FD F2 00 00 40>
Jan 17 10:28:07.95: [14408]: <-- data [7]
Jan 17 10:28:07.95: [14408]: <-- data [2]
Jan 17 10:28:09.35: [14408]: --> [2:OK]
Jan 17 10:28:09.35: [14408]: SEND send PPS (partial page signal)
Jan 17 10:28:09.35: [14408]: SEND send MPS (more pages, same document)
Jan 17 10:28:09.35: [14408]: <-- [9:AT+FRH=3\r]
Jan 17 10:28:09.71: [14408]: --> [7:CONNECT]
Jan 17 10:28:10.65: [14408]: --> HDLC<5:FF C8 31 45 8F>
Jan 17 10:28:10.66: [14408]: --> [2:OK]
Jan 17 10:28:10.66: [14408]: SEND recv MCF (message confirmation)
Jan 17 10:28:10.66: [14408]: DELAY 70 ms
Jan 17 10:28:10.73: [14408]: SEND end page
Jan 17 10:28:10.73: [14408]: SEND FAX (000001038): FROM user@xxxxxxxxxxxxxxxxxxxxx TO 01/444444 (page 1 of 2 sent in 0:00:11)
Jan 17 10:28:10.73: [14408]: SEND begin page
Jan 17 10:28:10.74: [14408]: SEND send frame number 0
Jan 17 10:28:10.74: [14408]: SEND send frame number 1
Jan 17 10:28:10.74: [14408]: SEND send frame number 2
Jan 17 10:28:10.74: [14408]: SEND send frame number 3
Jan 17 10:28:10.74: [14408]: SEND send frame number 4
Jan 17 10:28:10.74: [14408]: SEND send frame number 5
Jan 17 10:28:10.74: [14408]: SEND send frame number 6
Jan 17 10:28:10.74: [14408]: SEND send frame number 7
Jan 17 10:28:10.74: [14408]: SEND send frame number 8
Jan 17 10:28:10.74: [14408]: SEND send frame number 9
Jan 17 10:28:10.74: [14408]: SEND send frame number 10
Jan 17 10:28:10.74: [14408]: SEND send frame number 11
Jan 17 10:28:10.74: [14408]: SEND send frame number 12
Jan 17 10:28:10.74: [14408]: SEND send frame number 13
Jan 17 10:28:10.74: [14408]: SEND send frame number 14
Jan 17 10:28:10.74: [14408]: SEND send frame number 15
Jan 17 10:28:10.74: [14408]: SEND send frame number 16
Jan 17 10:28:10.74: [14408]: SEND send frame number 17
Jan 17 10:28:10.74: [14408]: SEND send frame number 18
Jan 17 10:28:10.74: [14408]: SEND send frame number 19
Jan 17 10:28:10.74: [14408]: SEND send frame number 20
Jan 17 10:28:10.74: [14408]: SEND send frame number 21
Jan 17 10:28:10.74: [14408]: SEND send frame number 22
Jan 17 10:28:10.74: [14408]: SEND send frame number 23
Jan 17 10:28:10.74: [14408]: SEND send frame number 24
Jan 17 10:28:10.74: [14408]: SEND send frame number 25
Jan 17 10:28:10.74: [14408]: SEND send frame number 26
Jan 17 10:28:10.74: [14408]: SEND send frame number 27
Jan 17 10:28:10.74: [14408]: SEND send frame number 28
Jan 17 10:28:10.74: [14408]: SEND send frame number 29
Jan 17 10:28:10.74: [14408]: SEND send frame number 30
Jan 17 10:28:10.74: [14408]: SEND send frame number 31
Jan 17 10:28:10.74: [14408]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled
Jan 17 10:28:10.74: [14408]: <-- [11:AT+FTM=146\r]
Jan 17 10:28:10.81: [14408]: --> [7:CONNECT]
Jan 17 10:28:10.81: [14408]: <-- data [1030]
Jan 17 10:28:10.81: [14408]: <-- data [1033]
Jan 17 10:28:10.81: [14408]: <-- data [1039]
Jan 17 10:28:10.81: [14408]: <-- data [1040]
Jan 17 10:28:12.81: [14408]: <-- data [1032]
Jan 17 10:28:12.81: [14408]: <-- data [1035]
Jan 17 10:28:12.81: [14408]: <-- data [1031]
Jan 17 10:28:12.81: [14408]: <-- data [1035]
Jan 17 10:28:14.80: [14408]: <-- data [817]
Jan 17 10:28:14.80: [14408]: <-- data [2]
Jan 17 10:28:16.17: [14408]: --> [2:OK]
Jan 17 10:28:16.17: [14408]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled
Jan 17 10:28:16.17: [14408]: <-- [9:AT+FTS=9\r]
Jan 17 10:28:16.32: [14408]: --> [2:OK]
Jan 17 10:28:16.32: [14408]: <-- [9:AT+FTH=3\r]
Jan 17 10:28:16.39: [14408]: --> [7:CONNECT]
Jan 17 10:28:16.39: [14408]: <-- HDLC<7:FF C8 FD F1 80 00 F8>
Jan 17 10:28:16.39: [14408]: <-- data [7]
Jan 17 10:28:16.39: [14408]: <-- data [2]
Jan 17 10:28:17.80: [14408]: --> [2:OK]
Jan 17 10:28:17.80: [14408]: SEND send PPS (partial page signal)
Jan 17 10:28:17.80: [14408]: SEND send EOM (more documents)
Jan 17 10:28:17.80: [14408]: <-- [9:AT+FRH=3\r]
Jan 17 10:28:18.19: [14408]: --> [7:CONNECT]
Jan 17 10:28:19.08: [14408]: --> HDLC<5:FF C8 31 45 8F>
Jan 17 10:28:19.08: [14408]: --> [2:OK]
Jan 17 10:28:19.08: [14408]: SEND recv MCF (message confirmation)
Jan 17 10:28:19.08: [14408]: DELAY 70 ms
Jan 17 10:28:19.15: [14408]: SEND end page
Jan 17 10:28:19.15: [14408]: SEND FAX (000001038): FROM user@xxxxxxxxxxxxxxxxxxxxx TO 01/444444 (page 2 of 2 sent in 0:00:09)
Jan 17 10:28:19.15: [14408]: SEND FAX (000001038): FROM user@xxxxxxxxxxxxxxxxxxxxx TO 01/444444 (docq/doc392.ps;f0 sent in 0:00:20)
Jan 17 10:28:20.17: [14408]: SEND FAX: JOB 389 DEST 01/444444 COMMID 000001038 DEVICE '/dev/ttyS1' FROM 'User <user@xxxxxxxxxxxxxxxxxxxxx>' USER uucp
Jan 17 10:28:20.17: [14408]: MODEM set XON/XOFF/FLUSH: input ignored, output disabled
Jan 17 10:28:20.17: [14408]: <-- [9:AT+FRH=3\r]
Jan 17 10:28:20.23: [14408]: --> [7:CONNECT]
Jan 17 10:28:20.24: [14408]: --> [2:10 03]
Jan 17 10:28:20.24: [14408]: --> [5:ERROR]
Jan 17 10:28:20.24: [14408]: MODEM Command error
Jan 17 10:28:20.24: [14408]: FCS error
Jan 17 10:28:20.24: [14408]: DELAY 70 ms
Jan 17 10:28:20.31: [14408]: <-- [9:AT+FTH=3\r]
Jan 17 10:28:20.38: [14408]: --> [7:CONNECT]
Jan 17 10:28:20.38: [14408]: <-- HDLC<3:FF C8 D8>
Jan 17 10:28:20.38: [14408]: <-- data [3]
Jan 17 10:28:20.38: [14408]: <-- data [2]
Jan 17 10:28:21.68: [14408]: --> [2:OK]
Jan 17 10:28:21.68: [14408]: SEND send CRP (command repeat)
Jan 17 10:28:21.68: [14408]: <-- [9:AT+FRH=3\r]
Jan 17 10:28:35.69: [14408]: --> [0:]
Jan 17 10:28:35.69: [14408]: MODEM <Empty line>
Jan 17 10:28:35.69: [14408]: MODEM TIMEOUT: waiting for v.21 carrier
Jan 17 10:28:35.69: [14408]: <-- data [1]
Jan 17 10:28:35.75: [14408]: --> [2:OK]
Jan 17 10:28:35.75: [14408]: DELAY 70 ms
Jan 17 10:28:35.82: [14408]: <-- [9:AT+FRH=3\r]
Jan 17 10:28:50.83: [14408]: --> [0:]
Jan 17 10:28:50.83: [14408]: MODEM <Empty line>
Jan 17 10:28:50.83: [14408]: MODEM TIMEOUT: waiting for v.21 carrier
Jan 17 10:28:50.83: [14408]: <-- data [1]
Jan 17 10:28:50.89: [14408]: --> [2:OK]
Jan 17 10:28:50.89: [14408]: DELAY 70 ms
Jan 17 10:28:50.96: [14408]: <-- [9:AT+FRH=3\r]
Jan 17 10:29:20.97: [14408]: --> [0:]
Jan 17 10:29:20.97: [14408]: MODEM <Empty line>
Jan 17 10:29:20.97: [14408]: MODEM TIMEOUT: waiting for v.21 carrier
Jan 17 10:29:20.97: [14408]: <-- data [1]
Jan 17 10:29:21.03: [14408]: --> [2:OK]
Jan 17 10:29:21.03: [14408]: No receiver protocol (T.30 T1 timeout) {E126}
Jan 17 10:29:21.03: [14408]: SEND FAILED: JOB 389 DEST 01/444444 ERR No receiver protocol (T.30 T1 timeout) {E126}
Jan 17 10:29:21.03: [14408]: DELAY 70 ms
Jan 17 10:29:21.10: [14408]: <-- [9:AT+FTH=3\r]
Jan 17 10:29:21.17: [14408]: --> [7:CONNECT]
Jan 17 10:29:21.17: [14408]: <-- HDLC<3:FF C8 DF>
Jan 17 10:29:21.17: [14408]: <-- data [3]
Jan 17 10:29:21.17: [14408]: <-- data [2]
Jan 17 10:29:22.47: [14408]: --> [2:OK]
Jan 17 10:29:22.47: [14408]: MODEM input buffering enabled
Jan 17 10:29:23.48: [14408]: <-- [5:ATH0\r]
Jan 17 10:29:24.43: [14408]: --> [2:OK]
Jan 17 10:29:24.43: [14408]: MODEM set DTR OFF
Jan 17 10:29:24.43: [14408]: STATE CHANGE: SENDING -> MODEMWAIT (timeout 5)
Jan 17 10:29:24.43: [14408]: SESSION END

Jan 17 10:30:32.56: [14605]: SESSION BEGIN 000001039 +1231231232
Jan 17 10:30:32.56: [14605]: HylaFAX (tm) Version 5.2.1
Jan 17 10:30:32.56: [14605]: SEND FAX: JOB 389 DEST 01/444444 COMMID 000001039 DEVICE '/dev/ttyS4' FROM 'User <user@xxxxxxxxxxxxxxxxxxxxx>' USER uucp
Jan 17 10:30:32.56: [14605]: STATE CHANGE: RUNNING -> SENDING
Jan 17 10:30:32.56: [14605]: <-- [12:AT+FCLASS=1\r]
Jan 17 10:30:32.62: [14605]: --> [2:OK]
Jan 17 10:30:32.62: [14605]: MODEM set XON/XOFF/FLUSH: input ignored, output disabled
Jan 17 10:30:32.62: [14605]: DIAL 01/444444
Jan 17 10:30:32.62: [14605]: <-- [14:ATDT01/444444\r]
Jan 17 10:31:11.66: [14605]: --> [7:CONNECT]
Jan 17 10:31:12.88: [14605]: --> HDLC<16:FF C0 04 00 00 6A AA AA 00 31 09 01 65 20 8C 89>
Jan 17 10:31:12.90: [14605]: --> [2:OK]
Jan 17 10:31:12.90: [14605]: REMOTE NSF "00 00 56 55 55 00 8C 90 80 A6 04"
Jan 17 10:31:12.90: [14605]: NSF remote fax equipment: Brother MFC-3100C/MFC-8600
Jan 17 10:31:12.90: [14605]: <-- [9:AT+FRH=3\r]
Jan 17 10:31:12.95: [14605]: --> [7:CONNECT]
Jan 17 10:31:13.60: [14605]: --> HDLC<25:FF C0 02 0C 2C 6C 4C 04 04 04 04 04 04 04 04 04 04 04 04 04 04 04 04 E9 F5>
Jan 17 10:31:13.61: [14605]: --> [2:OK]
Jan 17 10:31:13.61: [14605]: REMOTE CSI "2640"
Jan 17 10:31:13.61: [14605]: <-- [9:AT+FRH=3\r]
Jan 17 10:31:13.67: [14605]: --> [7:CONNECT]
Jan 17 10:31:13.92: [14605]: --> HDLC<11:FF C8 01 00 77 15 23 01 88 E7 CD>
Jan 17 10:31:13.94: [14605]: --> [2:OK]
Jan 17 10:31:13.94: [14605]: REMOTE best rate 14400 bit/s
Jan 17 10:31:13.94: [14605]: REMOTE max A4 page width (215 mm)
Jan 17 10:31:13.94: [14605]: REMOTE max unlimited page length
Jan 17 10:31:13.94: [14605]: REMOTE best vres 15.4 line/mm
Jan 17 10:31:13.94: [14605]: REMOTE format support: MH, MR, MMR
Jan 17 10:31:13.94: [14605]: REMOTE supports T.30 Annex A, 256-byte ECM
Jan 17 10:31:13.94: [14605]: REMOTE best 10 ms/scanline
Jan 17 10:31:13.94: [14605]: USE 14400 bit/s
Jan 17 10:31:13.94: [14605]: USE error correction mode
Jan 17 10:31:13.94: [14605]: SEND file "docq/doc389.ps;f0"
Jan 17 10:31:13.94: [14605]: USE A4 page width (215 mm)
Jan 17 10:31:13.94: [14605]: USE unlimited page length
Jan 17 10:31:13.94: [14605]: USE 3.85 line/mm
Jan 17 10:31:13.94: [14605]: USE 2-D MMR
Jan 17 10:31:13.94: [14605]: USE 0 ms/scanline
Jan 17 10:31:13.94: [14605]: SEND training at v.17 14400 bit/s
Jan 17 10:31:13.94: [14605]: DELAY 70 ms
Jan 17 10:31:14.01: [14605]: <-- [9:AT+FTH=3\r]
Jan 17 10:31:14.09: [14605]: --> [7:CONNECT]
Jan 17 10:31:14.09: [14605]: <-- HDLC<23:FF C0 C2 4E A6 6E 4E A6 CA 04 1E 86 62 04 B2 F2 32 82 42 04 04 04 04>
Jan 17 10:31:14.09: [14605]: <-- data [23]
Jan 17 10:31:14.09: [14605]: <-- data [2]
Jan 17 10:31:19.76: [14605]: --> [2:OK]
Jan 17 10:31:19.76: [14605]: Error sending T.30 prologue frames
Jan 17 10:31:19.76: [14605]: SEND training at v.17 12000 bit/s
Jan 17 10:31:19.76: [14605]: DELAY 70 ms
Jan 17 10:31:19.84: [14605]: <-- [9:AT+FTH=3\r]
Jan 17 10:31:19.92: [14605]: --> [7:CONNECT]
Jan 17 10:31:19.92: [14605]: <-- HDLC<23:FF C0 C2 4E A6 6E 4E A6 CA 04 1E 86 62 04 B2 F2 32 82 42 04 04 04 04>
Jan 17 10:31:19.92: [14605]: <-- data [23]
Jan 17 10:31:19.92: [14605]: <-- data [2]
Jan 17 10:31:25.76: [14605]: --> [2:OK]
Jan 17 10:31:25.76: [14605]: Error sending T.30 prologue frames
Jan 17 10:31:25.76: [14605]: SEND training at v.29 9600 bit/s
Jan 17 10:31:25.76: [14605]: DELAY 70 ms
Jan 17 10:31:25.84: [14605]: <-- [9:AT+FTH=3\r]
Jan 17 10:31:25.92: [14605]: --> [7:CONNECT]
Jan 17 10:31:25.92: [14605]: <-- HDLC<23:FF C0 C2 4E A6 6E 4E A6 CA 04 1E 86 62 04 B2 F2 32 82 42 04 04 04 04>
Jan 17 10:31:25.92: [14605]: <-- data [23]
Jan 17 10:31:25.92: [14605]: <-- data [2]
Jan 17 10:31:31.76: [14605]: --> [2:OK]
Jan 17 10:31:31.76: [14605]: Error sending T.30 prologue frames
Jan 17 10:31:31.76: [14605]: SEND training at v.29 7200 bit/s
Jan 17 10:31:31.76: [14605]: DELAY 70 ms
Jan 17 10:31:31.84: [14605]: <-- [9:AT+FTH=3\r]
Jan 17 10:31:31.92: [14605]: --> [7:CONNECT]
Jan 17 10:31:31.92: [14605]: <-- HDLC<23:FF C0 C2 4E A6 6E 4E A6 CA 04 1E 86 62 04 B2 F2 32 82 42 04 04 04 04>
Jan 17 10:31:31.92: [14605]: <-- data [23]
Jan 17 10:31:31.92: [14605]: <-- data [2]
Jan 17 10:31:37.76: [14605]: --> [2:OK]
Jan 17 10:31:37.76: [14605]: Error sending T.30 prologue frames
Jan 17 10:31:37.76: [14605]: SEND training at v.27ter 4800 bit/s
Jan 17 10:31:37.76: [14605]: DELAY 70 ms
Jan 17 10:31:37.84: [14605]: <-- [9:AT+FTH=3\r]
Jan 17 10:31:37.92: [14605]: --> [7:CONNECT]
Jan 17 10:31:37.92: [14605]: <-- HDLC<23:FF C0 C2 4E A6 6E 4E A6 CA 04 1E 86 62 04 B2 F2 32 82 42 04 04 04 04>
Jan 17 10:31:37.92: [14605]: <-- data [23]
Jan 17 10:31:37.92: [14605]: <-- data [2]
Jan 17 10:31:43.76: [14605]: --> [2:OK]
Jan 17 10:31:43.76: [14605]: Error sending T.30 prologue frames
Jan 17 10:31:43.76: [14605]: SEND training at v.27ter fallback mode 2400 bit/s
Jan 17 10:31:43.76: [14605]: DELAY 70 ms
Jan 17 10:31:43.84: [14605]: <-- [9:AT+FTH=3\r]
Jan 17 10:31:43.92: [14605]: --> [7:CONNECT]
Jan 17 10:31:43.92: [14605]: <-- HDLC<23:FF C0 C2 4E A6 6E 4E A6 CA 04 1E 86 62 04 B2 F2 32 82 42 04 04 04 04>
Jan 17 10:31:43.92: [14605]: <-- data [23]
Jan 17 10:31:43.92: [14605]: <-- data [2]
Jan 17 10:31:49.76: [14605]: --> [2:OK]
Jan 17 10:31:49.76: [14605]: Error sending T.30 prologue frames
Jan 17 10:31:49.76: [14605]: TRAINING failed
Jan 17 10:31:49.76: [14605]: DELAY 70 ms
Jan 17 10:31:49.84: [14605]: <-- [9:AT+FTH=3\r]
Jan 17 10:31:49.92: [14605]: --> [7:CONNECT]
Jan 17 10:31:49.92: [14605]: <-- HDLC<3:FF C8 DF>
Jan 17 10:31:49.92: [14605]: <-- data [3]
Jan 17 10:31:49.92: [14605]: <-- data [2]
Jan 17 10:31:55.76: [14605]: --> [2:OK]
Jan 17 10:31:55.76: [14605]: MODEM input buffering enabled
Jan 17 10:31:56.77: [14605]: <-- [5:ATH0\r]
Jan 17 10:31:57.73: [14605]: --> [2:OK]
Jan 17 10:31:57.73: [14605]: MODEM set DTR OFF
Jan 17 10:31:57.73: [14605]: STATE CHANGE: SENDING -> MODEMWAIT (timeout 5)
Jan 17 10:31:57.73: [14605]: SESSION END

Jan 17 10:42:36.46: [14639]: SESSION BEGIN 000001042 +1231231231
Jan 17 10:42:36.46: [14639]: HylaFAX (tm) Version 5.2.1
Jan 17 10:42:36.46: [14639]: SEND FAX: JOB 388 DEST 01/444444 COMMID 000001042 DEVICE '/dev/ttyS4' FROM 'User <user@xxxxxxxxxxxxxxxxx>' USER uucp
Jan 17 10:42:36.46: [14639]: STATE CHANGE: RUNNING -> SENDING
Jan 17 10:42:36.46: [14639]: <-- [12:AT+FCLASS=1\r]
Jan 17 10:42:36.52: [14639]: --> [2:OK]
Jan 17 10:42:36.52: [14639]: MODEM set XON/XOFF/FLUSH: input ignored, output disabled
Jan 17 10:42:36.52: [14639]: DIAL 01/444444
Jan 17 10:42:36.52: [14639]: <-- [14:ATDT01/444444\r]
Jan 17 10:43:15.07: [14639]: --> [7:CONNECT]
Jan 17 10:43:16.30: [14639]: --> HDLC<16:FF C0 04 00 00 6A AA AA 00 31 09 01 65 20 8C 89>
Jan 17 10:43:16.31: [14639]: --> [2:OK]
Jan 17 10:43:16.31: [14639]: REMOTE NSF "00 00 56 55 55 00 8C 90 80 A6 04"
Jan 17 10:43:16.31: [14639]: NSF remote fax equipment: Brother MFC-3100C/MFC-8600
Jan 17 10:43:16.31: [14639]: <-- [9:AT+FRH=3\r]
Jan 17 10:43:16.37: [14639]: --> [7:CONNECT]
Jan 17 10:43:17.02: [14639]: --> HDLC<25:FF C0 02 0C 2C 6C 4C 04 04 04 04 04 04 04 04 04 04 04 04 04 04 04 04 E9 F5>
Jan 17 10:43:17.03: [14639]: --> [2:OK]
Jan 17 10:43:17.03: [14639]: REMOTE CSI "2640"
Jan 17 10:43:17.03: [14639]: <-- [9:AT+FRH=3\r]
Jan 17 10:43:17.09: [14639]: --> [7:CONNECT]
Jan 17 10:43:17.34: [14639]: --> HDLC<11:FF C8 01 00 77 15 23 01 88 E7 CD>
Jan 17 10:43:17.35: [14639]: --> [2:OK]
Jan 17 10:43:17.35: [14639]: REMOTE best rate 14400 bit/s
Jan 17 10:43:17.35: [14639]: REMOTE max A4 page width (215 mm)
Jan 17 10:43:17.35: [14639]: REMOTE max unlimited page length
Jan 17 10:43:17.35: [14639]: REMOTE best vres 15.4 line/mm
Jan 17 10:43:17.35: [14639]: REMOTE format support: MH, MR, MMR
Jan 17 10:43:17.35: [14639]: REMOTE supports T.30 Annex A, 256-byte ECM
Jan 17 10:43:17.35: [14639]: REMOTE best 10 ms/scanline
Jan 17 10:43:17.35: [14639]: USE 14400 bit/s
Jan 17 10:43:17.35: [14639]: USE error correction mode
Jan 17 10:43:17.35: [14639]: SEND file "docq/doc388.ps;f0"
Jan 17 10:43:17.35: [14639]: USE A4 page width (215 mm)
Jan 17 10:43:17.35: [14639]: USE unlimited page length
Jan 17 10:43:17.35: [14639]: USE 3.85 line/mm
Jan 17 10:43:17.35: [14639]: USE 2-D MMR
Jan 17 10:43:17.35: [14639]: USE 0 ms/scanline
Jan 17 10:43:17.35: [14639]: SEND training at v.17 14400 bit/s
Jan 17 10:43:17.35: [14639]: DELAY 70 ms
Jan 17 10:43:17.42: [14639]: <-- [9:AT+FTH=3\r]
Jan 17 10:43:17.50: [14639]: --> [7:CONNECT]
Jan 17 10:43:17.50: [14639]: <-- HDLC<23:FF C0 C2 4E A6 6E 4E A6 CA 04 1E 86 62 04 B2 F2 32 82 42 04 04 04 04>
Jan 17 10:43:17.50: [14639]: <-- data [23]
Jan 17 10:43:17.50: [14639]: <-- data [2]
Jan 17 10:43:23.25: [14639]: --> [2:OK]
Jan 17 10:43:23.25: [14639]: Error sending T.30 prologue frames
Jan 17 10:43:23.25: [14639]: SEND training at v.17 12000 bit/s
Jan 17 10:43:23.25: [14639]: DELAY 70 ms
Jan 17 10:43:23.32: [14639]: <-- [9:AT+FTH=3\r]
Jan 17 10:43:23.40: [14639]: --> [7:CONNECT]
Jan 17 10:43:23.40: [14639]: <-- HDLC<23:FF C0 C2 4E A6 6E 4E A6 CA 04 1E 86 62 04 B2 F2 32 82 42 04 04 04 04>
Jan 17 10:43:23.40: [14639]: <-- data [23]
Jan 17 10:43:23.40: [14639]: <-- data [2]
Jan 17 10:43:29.25: [14639]: --> [2:OK]
Jan 17 10:43:29.25: [14639]: Error sending T.30 prologue frames
Jan 17 10:43:29.25: [14639]: SEND training at v.29 9600 bit/s
Jan 17 10:43:29.25: [14639]: DELAY 70 ms
Jan 17 10:43:29.32: [14639]: <-- [9:AT+FTH=3\r]
Jan 17 10:43:29.40: [14639]: --> [7:CONNECT]
Jan 17 10:43:29.40: [14639]: <-- HDLC<23:FF C0 C2 4E A6 6E 4E A6 CA 04 1E 86 62 04 B2 F2 32 82 42 04 04 04 04>
Jan 17 10:43:29.40: [14639]: <-- data [23]
Jan 17 10:43:29.40: [14639]: <-- data [2]
Jan 17 10:43:35.25: [14639]: --> [2:OK]
Jan 17 10:43:35.25: [14639]: Error sending T.30 prologue frames
Jan 17 10:43:35.25: [14639]: SEND training at v.29 7200 bit/s
Jan 17 10:43:35.25: [14639]: DELAY 70 ms
Jan 17 10:43:35.32: [14639]: <-- [9:AT+FTH=3\r]
Jan 17 10:43:35.40: [14639]: --> [7:CONNECT]
Jan 17 10:43:35.40: [14639]: <-- HDLC<23:FF C0 C2 4E A6 6E 4E A6 CA 04 1E 86 62 04 B2 F2 32 82 42 04 04 04 04>
Jan 17 10:43:35.40: [14639]: <-- data [23]
Jan 17 10:43:35.40: [14639]: <-- data [2]
Jan 17 10:43:41.25: [14639]: --> [2:OK]
Jan 17 10:43:41.25: [14639]: Error sending T.30 prologue frames
Jan 17 10:43:41.25: [14639]: SEND training at v.27ter 4800 bit/s
Jan 17 10:43:41.25: [14639]: DELAY 70 ms
Jan 17 10:43:41.32: [14639]: <-- [9:AT+FTH=3\r]
Jan 17 10:43:41.40: [14639]: --> [7:CONNECT]
Jan 17 10:43:41.40: [14639]: <-- HDLC<23:FF C0 C2 4E A6 6E 4E A6 CA 04 1E 86 62 04 B2 F2 32 82 42 04 04 04 04>
Jan 17 10:43:41.40: [14639]: <-- data [23]
Jan 17 10:43:41.41: [14639]: <-- data [2]
Jan 17 10:43:47.25: [14639]: --> [2:OK]
Jan 17 10:43:47.25: [14639]: Error sending T.30 prologue frames
Jan 17 10:43:47.25: [14639]: SEND training at v.27ter fallback mode 2400 bit/s
Jan 17 10:43:47.25: [14639]: DELAY 70 ms
Jan 17 10:43:47.32: [14639]: <-- [9:AT+FTH=3\r]
Jan 17 10:43:47.40: [14639]: --> [7:CONNECT]
Jan 17 10:43:47.40: [14639]: <-- HDLC<23:FF C0 C2 4E A6 6E 4E A6 CA 04 1E 86 62 04 B2 F2 32 82 42 04 04 04 04>
Jan 17 10:43:47.40: [14639]: <-- data [23]
Jan 17 10:43:47.40: [14639]: <-- data [2]
Jan 17 10:43:53.24: [14639]: --> [2:OK]
Jan 17 10:43:53.24: [14639]: Error sending T.30 prologue frames
Jan 17 10:43:53.24: [14639]: TRAINING failed
Jan 17 10:43:53.24: [14639]: DELAY 70 ms
Jan 17 10:43:53.32: [14639]: <-- [9:AT+FTH=3\r]
Jan 17 10:43:53.40: [14639]: --> [7:CONNECT]
Jan 17 10:43:53.40: [14639]: <-- HDLC<3:FF C8 DF>
Jan 17 10:43:53.40: [14639]: <-- data [3]
Jan 17 10:43:53.40: [14639]: <-- data [2]
Jan 17 10:43:59.24: [14639]: --> [2:OK]
Jan 17 10:43:59.24: [14639]: MODEM input buffering enabled
Jan 17 10:44:00.25: [14639]: <-- [5:ATH0\r]
Jan 17 10:44:01.21: [14639]: --> [2:OK]
Jan 17 10:44:01.21: [14639]: MODEM set DTR OFF
Jan 17 10:44:01.21: [14639]: STATE CHANGE: SENDING -> MODEMWAIT (timeout 5)
Jan 17 10:44:01.21: [14639]: SESSION END