[hylafax-users] Duxbury (conexant-based) modem problems

["David Wilson" <dave@xxxxxxxxxxxx>]

Hi guys,

 

I'm having all types of problems with the blue Duxbury (conexant-based) analogue modems we get here in South Africa.

We've used them in the past and they've worked pretty well with HylaFAX in class 1 mode but for some reason something has changed with the latest ones and we are picking problems with them - perhaps a modem firmware issue ?

From what I can see, the errors in my HylaFAX (version 4.3.0) logs are intermittent, from "No response to EOP" command when used for sending, and training errors ending in "(x) bad lines" etc when receiving. These errors are causing outbound FAXes to sometimes not be sent, or be sent with squashed/squished pages and missing lines.

 

I've looked all over the web but can't find any work around. Please have a look at my logs below to see if you can pick up anything and perhaps suggest a fix for me.

 

Your kind assistance would be greatly appreciated.

 

LOG-1

------------------------------------------------------------------------------

Jun 26 11:24:19.82: [27519]: SESSION BEGIN 000000266 27312666041
Jun 26 11:24:19.82: [27519]: HylaFAX (tm) Version 4.3.0
Jun 26 11:24:19.82: [27519]: SEND FAX: JOB 54 DEST 2666041 COMMID 000000266 DEVICE '/dev/ttyS46' FROM 'Neil <neiln@xxxxxxxxxxxx>' USER neiln
Jun 26 11:24:19.82: [27519]: <-- [12:AT+FCLASS=1\r]
Jun 26 11:24:19.93: [27519]: --> [2:OK]
Jun 26 11:24:19.95: [27519]: DIAL 2666041
Jun 26 11:24:19.95: [27519]: <-- [12:ATDT2666041\r]
Jun 26 11:24:34.23: [27519]: --> [7:CONNECT]
Jun 26 11:24:34.96: [27519]: --> [2:OK]
Jun 26 11:24:34.96: [27519]: REMOTE CSI "StarRate I.T."
Jun 26 11:24:34.96: [27519]: <-- [9:AT+FRH=3\r]
Jun 26 11:24:34.97: [27519]: --> [7:CONNECT]
Jun 26 11:24:42.80: [27519]: --> [2:OK]
Jun 26 11:24:42.80: [27519]: HDLC frame with bad control field 0x80
Jun 26 11:24:42.80: [27519]: <-- [9:AT+FRS=7\r]
Jun 26 11:24:42.81: [27519]: --> [2:OK]
Jun 26 11:24:42.81: [27519]: <-- [9:AT+FTH=3\r]
Jun 26 11:24:42.86: [27519]: --> [7:CONNECT]
Jun 26 11:24:42.86: [27519]: <-- data [3]
Jun 26 11:24:42.86: [27519]: <-- data [2]
Jun 26 11:24:44.18: [27519]: --> [2:OK]
Jun 26 11:24:44.18: [27519]: SEND send CRP (command repeat)
Jun 26 11:24:44.18: [27519]: <-- [9:AT+FRH=3\r]
Jun 26 11:24:44.56: [27519]: --> [7:CONNECT]
Jun 26 11:24:46.53: [27519]: --> [2:OK]
Jun 26 11:24:46.53: [27519]: REMOTE NSF "00 00 31 00 EE B8 00 84 0D DD 00 00 DD
D 21 90 00 00 10"
Jun 26 11:24:46.53: [27519]: NSF remote fax equipment: Sharp/Olivetti
Jun 26 11:24:46.53: [27519]: NSF remote station ID: "»»»»»``d"
Jun 26 11:24:46.53: [27519]: <-- [9:AT+FRH=3\r]
Jun 26 11:24:46.54: [27519]: --> [7:CONNECT]
Jun 26 11:24:47.23: [27519]: --> [2:OK]
Jun 26 11:24:47.23: [27519]: REMOTE CSI "031 5636119"
Jun 26 11:24:47.23: [27519]: <-- [9:AT+FRH=3\r]
Jun 26 11:24:47.24: [27519]: --> [7:CONNECT]
Jun 26 11:24:47.57: [27519]: --> [2:OK]
Jun 26 11:24:47.58: [27519]: REMOTE best rate 14400 bit/s
Jun 26 11:24:47.58: [27519]: REMOTE max A4 page width (215 mm)
Jun 26 11:24:47.58: [27519]: REMOTE max unlimited page length
Jun 26 11:24:47.58: [27519]: REMOTE best vres 7.7 line/mm
Jun 26 11:24:47.58: [27519]: REMOTE format support: MH, MR
Jun 26 11:24:47.58: [27519]: REMOTE best 20 ms, 10 ms/scanline
Jun 26 11:24:47.58: [27519]: USE 14400 bit/s
Jun 26 11:24:47.58: [27519]: SEND file "docq/doc53.ps;00"
Jun 26 11:24:47.58: [27519]: USE A4 page width (215 mm)
Jun 26 11:24:47.58: [27519]: USE unlimited page length
Jun 26 11:24:47.58: [27519]: USE 3.85 line/mm
Jun 26 11:24:47.58: [27519]: USE 2-D MR
Jun 26 11:24:47.58: [27519]: USE 20 ms/scanline
Jun 26 11:24:47.58: [27519]: SEND training at v.17 14400 bit/s
Jun 26 11:24:47.58: [27519]: <-- [9:AT+FRS=7\r]
Jun 26 11:24:47.59: [27519]: --> [2:OK]
Jun 26 11:24:47.59: [27519]: <-- [9:AT+FTH=3\r]
Jun 26 11:24:47.64: [27519]: --> [7:CONNECT]
Jun 26 11:24:47.64: [27519]: <-- data [23]
Jun 26 11:24:47.64: [27519]: <-- data [2]
Jun 26 11:24:47.66: [27519]: --> [7:CONNECT]
Jun 26 11:24:47.66: [27519]: <-- data [6]
Jun 26 11:24:47.66: [27519]: <-- data [2]
Jun 26 11:24:49.83: [27519]: --> [2:OK]
Jun 26 11:24:49.83: [27519]: <-- [9:AT+FTS=7\r]
Jun 26 11:24:49.84: [27519]: --> [2:OK]
Jun 26 11:24:49.85: [27519]: <-- [11:AT+FTM=145\r]
Jun 26 11:24:51.52: [27519]: --> [7:CONNECT]
Jun 26 11:24:51.52: [27519]: <-- data [1024]
Jun 26 11:24:51.52: [27519]: <-- data [1024]
Jun 26 11:24:51.52: [27519]: <-- data [652]
Jun 26 11:24:51.52: [27519]: <-- data [2]
Jun 26 11:24:53.07: [27519]: --> [2:OK]
Jun 26 11:24:53.07: [27519]: <-- [9:AT+FRH=3\r]
Jun 26 11:24:53.28: [27519]: --> [7:CONNECT]
Jun 26 11:24:54.38: [27519]: --> [2:OK]
Jun 26 11:24:54.38: [27519]: TRAINING succeeded
Jun 26 11:24:54.38: [27519]: DELAY 200 ms
Jun 26 11:24:54.58: [27519]: <-- [11:AT+FTM=146\r]
Jun 26 11:24:55.07: [27519]: --> [7:CONNECT]
Jun 26 11:24:55.07: [27519]: SEND begin page
Jun 26 11:24:55.07: [27519]: Reading MH-compressed image file
Jun 26 11:24:55.08: [27519]: <-- data [190]
Jun 26 11:24:55.08: [27519]: SENT 189 bytes of data
.....

....
Jun 26 11:25:19.20: [27519]: <-- data [144]
Jun 26 11:25:19.20: [27519]: SENT 144 bytes of data
Jun 26 11:25:19.20: [27519]: SEND 2D RTC
Jun 26 11:25:19.20: [27519]: <-- data [30]
Jun 26 11:25:19.20: [27519]: <-- data [2]
Jun 26 11:25:19.20: [27519]: SEND end page
Jun 26 11:25:21.49: [27519]: --> [2:OK]
Jun 26 11:25:21.49: [27519]: <-- [9:AT+FTS=9\r]
Jun 26 11:25:21.50: [27519]: --> [2:OK]
Jun 26 11:25:21.50: [27519]: SEND send EOP (no more pages or documents)
Jun 26 11:25:21.50: [27519]: <-- [9:AT+FTH=3\r]
Jun 26 11:25:21.55: [27519]: --> [7:CONNECT]
Jun 26 11:25:21.55: [27519]: <-- data [3]
Jun 26 11:25:21.55: [27519]: <-- data [2]
Jun 26 11:25:22.87: [27519]: --> [2:OK]
Jun 26 11:25:22.87: [27519]: <-- [9:AT+FRH=3\r]
Jun 26 11:25:23.11: [27519]: --> [7:CONNECT]
Jun 26 11:25:24.24: [27519]: --> [2:OK]
Jun 26 11:25:24.24: [27519]: SEND recv RTN (retrain negative)
Jun 26 11:25:24.24: [27519]: SEND training at v.17 12000 bit/s
Jun 26 11:25:24.24: [27519]: <-- [9:AT+FRS=7\r]
Jun 26 11:25:24.25: [27519]: --> [2:OK]
Jun 26 11:25:24.25: [27519]: <-- [9:AT+FTH=3\r]
Jun 26 11:25:24.30: [27519]: --> [7:CONNECT]
Jun 26 11:25:24.30: [27519]: <-- data [23]
Jun 26 11:25:24.30: [27519]: <-- data [2]
Jun 26 11:25:24.32: [27519]: --> [7:CONNECT]
Jun 26 11:25:24.32: [27519]: <-- data [6]
Jun 26 11:25:24.32: [27519]: <-- data [2]
Jun 26 11:25:26.49: [27519]: --> [2:OK]
Jun 26 11:25:26.49: [27519]: <-- [9:AT+FTS=7\r]
Jun 26 11:25:26.50: [27519]: --> [2:OK]
Jun 26 11:25:26.50: [27519]: <-- [11:AT+FTM=121\r]
Jun 26 11:25:28.18: [27519]: --> [7:CONNECT]
Jun 26 11:25:28.18: [27519]: <-- data [1024]
Jun 26 11:25:28.18: [27519]: <-- data [1024]
Jun 26 11:25:28.18: [27519]: <-- data [202]
Jun 26 11:25:28.18: [27519]: <-- data [2]
Jun 26 11:25:29.73: [27519]: --> [2:OK]
Jun 26 11:25:29.73: [27519]: <-- [9:AT+FRH=3\r]
Jun 26 11:25:32.83: [27519]: --> [0:]
Jun 26 11:25:32.83: [27519]: MODEM <Empty line>
Jun 26 11:25:32.83: [27519]: <-- data [1]
Jun 26 11:25:33.03: [27519]: MODEM <Timeout>
Jun 26 11:25:33.03: [27519]: DELAY 1500 ms
Jun 26 11:25:34.53: [27519]: SEND training at v.17 9600 bit/s
Jun 26 11:25:34.53: [27519]: <-- [9:AT+FRS=7\r]
Jun 26 11:25:34.54: [27519]: --> [2:OK]
Jun 26 11:25:34.54: [27519]: <-- [9:AT+FTH=3\r]
Jun 26 11:25:34.59: [27519]: --> [7:CONNECT]
Jun 26 11:25:34.59: [27519]: <-- data [23]
Jun 26 11:25:34.59: [27519]: <-- data [2]
Jun 26 11:25:34.61: [27519]: --> [7:CONNECT]
Jun 26 11:25:34.61: [27519]: <-- data [6]
Jun 26 11:25:34.61: [27519]: <-- data [2]
Jun 26 11:25:36.78: [27519]: --> [2:OK]
Jun 26 11:25:36.78: [27519]: <-- [9:AT+FTS=7\r]
Jun 26 11:25:36.79: [27519]: --> [2:OK]
Jun 26 11:25:36.79: [27519]: <-- [10:AT+FTM=97\r]
Jun 26 11:25:38.47: [27519]: --> [7:CONNECT]
Jun 26 11:25:38.47: [27519]: <-- data [1024]
Jun 26 11:25:38.47: [27519]: <-- data [776]
Jun 26 11:25:38.47: [27519]: <-- data [2]
Jun 26 11:25:40.02: [27519]: --> [2:OK]
Jun 26 11:25:40.02: [27519]: <-- [9:AT+FRH=3\r]
Jun 26 11:25:43.12: [27519]: --> [0:]
Jun 26 11:25:43.12: [27519]: MODEM <Empty line>
Jun 26 11:25:43.12: [27519]: <-- data [1]
Jun 26 11:25:43.32: [27519]: MODEM <Timeout>
Jun 26 11:25:43.32: [27519]: DELAY 1500 ms
Jun 26 11:25:44.82: [27519]: SEND training at v.29 9600 bit/s
Jun 26 11:25:44.82: [27519]: <-- [9:AT+FRS=7\r]
Jun 26 11:25:44.83: [27519]: --> [2:OK]
Jun 26 11:25:44.83: [27519]: <-- [9:AT+FTH=3\r]
Jun 26 11:25:44.88: [27519]: --> [7:CONNECT]
Jun 26 11:25:44.88: [27519]: <-- data [23]
Jun 26 11:25:44.88: [27519]: <-- data [2]
Jun 26 11:25:44.90: [27519]: --> [7:CONNECT]
Jun 26 11:25:44.90: [27519]: <-- data [6]
Jun 26 11:25:44.90: [27519]: <-- data [2]
Jun 26 11:25:47.07: [27519]: --> [2:OK]
Jun 26 11:25:47.72: [27519]: <-- [9:AT+FTS=7\r]
Jun 26 11:25:47.73: [27519]: --> [2:OK]
Jun 26 11:25:47.73: [27519]: <-- [10:AT+FTM=96\r]
Jun 26 11:25:48.25: [27519]: --> [7:CONNECT]
Jun 26 11:25:48.25: [27519]: <-- data [1024]
Jun 26 11:25:48.25: [27519]: <-- data [776]
Jun 26 11:25:48.25: [27519]: <-- data [2]
Jun 26 11:25:49.80: [27519]: --> [2:OK]
Jun 26 11:25:49.80: [27519]: <-- [9:AT+FRH=3\r]
Jun 26 11:25:52.90: [27519]: --> [0:]
Jun 26 11:25:52.90: [27519]: MODEM <Empty line>
Jun 26 11:25:52.90: [27519]: <-- data [1]
Jun 26 11:25:53.10: [27519]: MODEM <Timeout>
Jun 26 11:25:53.10: [27519]: DELAY 1500 ms
Jun 26 11:25:54.60: [27519]: SEND training at v.29 7200 bit/s
Jun 26 11:25:54.60: [27519]: <-- [9:AT+FRS=7\r]
Jun 26 11:25:54.61: [27519]: --> [2:OK]
Jun 26 11:25:54.61: [27519]: <-- [9:AT+FTH=3\r]
Jun 26 11:25:54.66: [27519]: --> [7:CONNECT]
Jun 26 11:25:54.66: [27519]: <-- data [23]
Jun 26 11:25:54.66: [27519]: <-- data [2]
Jun 26 11:25:54.68: [27519]: --> [7:CONNECT]
Jun 26 11:25:54.68: [27519]: <-- data [6]
Jun 26 11:25:54.68: [27519]: <-- data [2]
Jun 26 11:25:56.86: [27519]: --> [2:OK]
Jun 26 11:25:56.86: [27519]: <-- [9:AT+FTS=7\r]
Jun 26 11:25:56.87: [27519]: --> [2:OK]
Jun 26 11:25:56.87: [27519]: <-- [10:AT+FTM=72\r]
Jun 26 11:25:57.38: [27519]: --> [7:CONNECT]
Jun 26 11:25:57.64: [27519]: <-- data [1024]
Jun 26 11:25:57.64: [27519]: <-- data [326]
Jun 26 11:25:57.64: [27519]: <-- data [2]
Jun 26 11:25:59.19: [27519]: --> [2:OK]
Jun 26 11:25:59.19: [27519]: <-- [9:AT+FRH=3\r]
Jun 26 11:26:02.29: [27519]: --> [0:]
Jun 26 11:26:02.29: [27519]: MODEM <Empty line>
Jun 26 11:26:02.29: [27519]: <-- data [1]
Jun 26 11:26:02.49: [27519]: MODEM <Timeout>
Jun 26 11:26:02.82: [27519]: DELAY 1500 ms
Jun 26 11:26:04.32: [27519]: SEND training at v.27ter 4800 bit/s
Jun 26 11:26:04.32: [27519]: <-- [9:AT+FRS=7\r]
Jun 26 11:26:04.32: [27519]: --> [2:OK]
Jun 26 11:26:04.32: [27519]: <-- [9:AT+FTH=3\r]
Jun 26 11:26:04.33: [27519]: --> [2:OK]
Jun 26 11:26:04.33: [27519]: Error sending T.30 prologue frames
Jun 26 11:26:04.33: [27519]: SEND training at v.27ter fallback mode 2400 bit/s
Jun 26 11:26:04.33: [27519]: <-- [9:AT+FRS=7\r]
Jun 26 11:26:04.34: [27519]: --> [2:OK]
Jun 26 11:26:04.34: [27519]: <-- [9:AT+FTH=3\r]
Jun 26 11:26:04.39: [27519]: --> [7:CONNECT]
Jun 26 11:26:04.39: [27519]: <-- data [23]
Jun 26 11:26:04.39: [27519]: <-- data [2]
Jun 26 11:26:04.41: [27519]: --> [7:CONNECT]
Jun 26 11:26:04.41: [27519]: <-- data [6]
Jun 26 11:26:04.41: [27519]: <-- data [2]
Jun 26 11:26:06.59: [27519]: --> [2:OK]
Jun 26 11:26:06.59: [27519]: <-- [9:AT+FTS=7\r]
Jun 26 11:26:06.60: [27519]: --> [2:OK]
Jun 26 11:26:06.60: [27519]: <-- [10:AT+FTM=24\r]
Jun 26 11:26:07.82: [27519]: --> [7:CONNECT]
Jun 26 11:26:07.82: [27519]: <-- data [450]
Jun 26 11:26:07.82: [27519]: <-- data [2]
Jun 26 11:26:09.37: [27519]: --> [2:OK]
Jun 26 11:26:09.37: [27519]: <-- [9:AT+FRH=3\r]
Jun 26 11:26:12.47: [27519]: --> [0:]
Jun 26 11:26:12.47: [27519]: MODEM <Empty line>
Jun 26 11:26:12.47: [27519]: <-- data [1]
Jun 26 11:26:12.67: [27519]: MODEM <Timeout>
Jun 26 11:26:12.67: [27519]: DELAY 1500 ms
Jun 26 11:26:14.17: [27519]: TRAINING failed
Jun 26 11:26:14.17: [27519]: <-- [9:AT+FTH=3\r]
Jun 26 11:26:14.22: [27519]: --> [7:CONNECT]
Jun 26 11:26:14.22: [27519]: <-- data [3]
Jun 26 11:26:14.22: [27519]: <-- data [2]
Jun 26 11:26:15.53: [27519]: --> [2:OK]
Jun 26 11:26:15.53: [27519]: <-- [5:ATH0\r]
Jun 26 11:26:15.96: [27519]: --> [10:NO CARRIER]
Jun 26 11:26:15.96: [27519]: MODEM No carrier
Jun 26 11:26:15.96: [27519]: SESSION END

LOG-2

---------------------------------------------------------------------

Jun 26 13:38:54.25: [17481]: SESSION BEGIN 000000315 27315636119
Jun 26 13:38:54.25: [17481]: HylaFAX (tm) Version 4.3.0
Jun 26 13:38:54.25: [17481]: <-- [4:ATA\r]
Jun 26 13:39:04.00: [17481]: --> [7:CONNECT]
Jun 26 13:39:04.00: [17481]: ANSWER: FAX CONNECTION  DEVICE '/dev/ttyS47'
Jun 26 13:39:04.00: [17481]: RECV FAX: begin
Jun 26 13:39:04.00: [17481]: <-- data [32]
Jun 26 13:39:04.00: [17481]: <-- data [2]
Jun 26 13:39:04.03: [17481]: --> [7:CONNECT]
Jun 26 13:39:04.03: [17481]: <-- data [23]
Jun 26 13:39:04.03: [17481]: <-- data [2]
Jun 26 13:39:04.05: [17481]: --> [7:CONNECT]
Jun 26 13:39:04.05: [17481]: <-- data [13]
Jun 26 13:39:04.05: [17481]: <-- data [2]
Jun 26 13:39:07.26: [17481]: --> [2:OK]
Jun 26 13:39:07.26: [17481]: <-- [9:AT+FRH=3\r]
Jun 26 13:39:12.25: [17481]: --> [2:OK]
Jun 26 13:39:12.25: [17481]: DELAY 1500 ms
Jun 26 13:39:13.75: [17481]: <-- [9:AT+FTH=3\r]
Jun 26 13:39:13.80: [17481]: --> [7:CONNECT]
Jun 26 13:39:13.80: [17481]: <-- data [32]
Jun 26 13:39:13.80: [17481]: <-- data [2]
Jun 26 13:39:13.82: [17481]: --> [7:CONNECT]
Jun 26 13:39:13.82: [17481]: <-- data [23]
Jun 26 13:39:13.82: [17481]: <-- data [2]
Jun 26 13:39:13.85: [17481]: --> [7:CONNECT]
Jun 26 13:39:13.85: [17481]: <-- data [13]
Jun 26 13:39:13.85: [17481]: <-- data [2]
Jun 26 13:39:17.06: [17481]: --> [2:OK]
Jun 26 13:39:17.06: [17481]: <-- [9:AT+FRH=3\r]
Jun 26 13:39:17.22: [17481]: --> [7:CONNECT]
Jun 26 13:39:18.41: [17481]: --> [2:OK]
Jun 26 13:39:18.41: [17481]: <-- [9:AT+FRH=3\r]
Jun 26 13:39:18.42: [17481]: --> [7:CONNECT]
Jun 26 13:39:19.11: [17481]: --> [2:OK]
Jun 26 13:39:19.11: [17481]: <-- [9:AT+FRH=3\r]
Jun 26 13:39:19.13: [17481]: --> [7:CONNECT]
Jun 26 13:39:19.56: [17481]: --> [2:OK]
Jun 26 13:39:19.56: [17481]: DELAY 1500 ms
Jun 26 13:39:21.07: [17481]: <-- [9:AT+FTH=3\r]
Jun 26 13:39:21.11: [17481]: --> [7:CONNECT]
Jun 26 13:39:21.11: [17481]: <-- data [32]
Jun 26 13:39:21.11: [17481]: <-- data [2]
Jun 26 13:39:21.14: [17481]: --> [7:CONNECT]
Jun 26 13:39:21.14: [17481]: <-- data [23]
Jun 26 13:39:21.14: [17481]: <-- data [2]
Jun 26 13:39:21.16: [17481]: --> [7:CONNECT]
Jun 26 13:39:21.16: [17481]: <-- data [13]
Jun 26 13:39:21.16: [17481]: <-- data [2]
Jun 26 13:39:24.37: [17481]: --> [2:OK]
Jun 26 13:39:24.37: [17481]: <-- [9:AT+FRH=3\r]
Jun 26 13:39:27.40: [17481]: --> [7:CONNECT]
Jun 26 13:39:28.72: [17481]: --> [2:OK]
Jun 26 13:39:28.72: [17481]: <-- [9:AT+FRH=3\r]
Jun 26 13:39:28.73: [17481]: --> [7:CONNECT]
Jun 26 13:39:29.43: [17481]: --> [2:OK]
Jun 26 13:39:29.43: [17481]: <-- [9:AT+FRH=3\r]
Jun 26 13:39:29.44: [17481]: --> [7:CONNECT]
Jun 26 13:39:29.88: [17481]: --> [2:OK]
Jun 26 13:39:29.88: [17481]: DELAY 1500 ms
Jun 26 13:39:31.38: [17481]: <-- [9:AT+FTH=3\r]
Jun 26 13:39:31.42: [17481]: --> [7:CONNECT]
Jun 26 13:39:31.43: [17481]: <-- data [32]
Jun 26 13:39:31.43: [17481]: <-- data [2]
Jun 26 13:39:31.45: [17481]: --> [7:CONNECT]
Jun 26 13:39:31.45: [17481]: <-- data [23]
Jun 26 13:39:31.45: [17481]: <-- data [2]
Jun 26 13:39:31.47: [17481]: --> [7:CONNECT]
Jun 26 13:39:31.47: [17481]: <-- data [13]
Jun 26 13:39:31.47: [17481]: <-- data [2]
Jun 26 13:39:34.69: [17481]: --> [2:OK]
Jun 26 13:39:34.69: [17481]: <-- [9:AT+FRH=3\r]
Jun 26 13:39:37.72: [17481]: --> [7:CONNECT]
Jun 26 13:39:39.03: [17481]: --> [2:OK]
Jun 26 13:39:39.03: [17481]: <-- [9:AT+FRH=3\r]
Jun 26 13:39:39.05: [17481]: --> [7:CONNECT]
Jun 26 13:39:39.74: [17481]: --> [2:OK]
Jun 26 13:39:39.74: [17481]: <-- [9:AT+FRH=3\r]
Jun 26 13:39:39.75: [17481]: --> [7:CONNECT]
Jun 26 13:39:40.19: [17481]: --> [2:OK]
Jun 26 13:39:40.19: [17481]: DELAY 1500 ms
Jun 26 13:39:41.69: [17481]: <-- [9:AT+FTH=3\r]
Jun 26 13:39:41.74: [17481]: --> [7:CONNECT]
Jun 26 13:39:41.74: [17481]: <-- data [32]
Jun 26 13:39:41.74: [17481]: <-- data [2]
Jun 26 13:39:41.76: [17481]: --> [7:CONNECT]
Jun 26 13:39:41.76: [17481]: <-- data [23]
Jun 26 13:39:41.76: [17481]: <-- data [2]
Jun 26 13:39:41.79: [17481]: --> [7:CONNECT]
Jun 26 13:39:41.79: [17481]: <-- data [13]
Jun 26 13:39:41.79: [17481]: <-- data [2]
Jun 26 13:39:45.00: [17481]: --> [2:OK]
Jun 26 13:39:45.00: [17481]: <-- [9:AT+FRH=3\r]
Jun 26 13:39:48.03: [17481]: --> [7:CONNECT]
Jun 26 13:39:49.16: [17481]: --> [2:OK]
Jun 26 13:39:49.16: [17481]: RECV FAX: RSPREC error/got DCN
Jun 26 13:39:49.16: [17481]: RECV FAX: end
Jun 26 13:39:49.16: [17481]: SESSION END

 

 

LOG-3

---------------------------------------------------------------------------------------------------------------------

Jun 26 11:50:43.87: [ 4589]: SESSION BEGIN 000000281 273161099
Jun 26 11:50:43.87: [ 4589]: HylaFAX (tm) Version 4.3.0
Jun 26 11:50:43.87: [ 4589]: SEND FAX: JOB 59 DEST 2441199 COMMID 000000281 DEVICE '/dev/ttyS44' FROM 'Neil <neiln@xxxxxxxxxxxx>' USER neiln
Jun 26 11:50:43.87: [ 4589]: <-- [12:AT+FCLASS=1\r]
Jun 26 11:50:43.98: [ 4589]: --> [2:OK]
Jun 26 11:50:43.98: [ 4589]: DIAL 2661099
Jun 26 11:50:43.98: [ 4589]: <-- [12:ATDT2661099\r]
Jun 26 11:51:04.21: [ 4589]: --> [7:CONNECT]
Jun 26 11:51:06.21: [ 4589]: --> [2:OK]
Jun 26 11:51:06.21: [ 4589]: REMOTE NSF "00 00 0E 00 00 00 96 0F 01 03 00 10 05 02 85 C8 08 01 49 02 43 48 49 50 54 52 4F 4E 49 58 20 20 20 20 20 20 03"
Jun 26 11:51:06.21: [ 4589]: NSF remote fax equipment: Panasonic KX-F230/KX-FT21
Jun 26 11:51:06.21: [ 4589]: NSF remote station ID: "CHIPTRONIX      "
Jun 26 11:51:06.21: [ 4589]: <-- [9:AT+FRH=3\r]
Jun 26 11:51:06.23: [ 4589]: --> [7:CONNECT]
Jun 26 11:51:06.96: [ 4589]: --> [2:OK]
Jun 26 11:51:06.96: [ 4589]: REMOTE CSI "031+2661094"
Jun 26 11:51:06.96: [ 4589]: <-- [9:AT+FRH=3\r]
Jun 26 11:51:06.97: [ 4589]: --> [7:CONNECT]
Jun 26 11:51:07.30: [ 4589]: --> [2:OK]
Jun 26 11:51:07.30: [ 4589]: REMOTE best rate 9600 bit/s
Jun 26 11:51:07.30: [ 4589]: REMOTE max A4 page width (215 mm)
Jun 26 11:51:07.30: [ 4589]: REMOTE max unlimited page length
Jun 26 11:51:07.30: [ 4589]: REMOTE best vres 7.7 line/mm
Jun 26 11:51:07.30: [ 4589]: REMOTE format support: MH, MR
Jun 26 11:51:07.30: [ 4589]: REMOTE best 20 ms/scanline
Jun 26 11:51:07.30: [ 4589]: USE 9600 bit/s
Jun 26 11:51:07.30: [ 4589]: SEND file "docq/doc58.ps;c0"
Jun 26 11:51:07.30: [ 4589]: USE A4 page width (215 mm)
Jun 26 11:51:07.30: [ 4589]: USE unlimited page length
Jun 26 11:51:07.30: [ 4589]: USE 3.85 line/mm
Jun 26 11:51:07.30: [ 4589]: USE 2-D MR
Jun 26 11:51:07.30: [ 4589]: USE 20 ms/scanline
Jun 26 11:51:07.30: [ 4589]: SEND training at v.29 9600 bit/s
Jun 26 11:51:07.30: [ 4589]: <-- [9:AT+FRS=7\r]
Jun 26 11:51:07.31: [ 4589]: --> [2:OK]
Jun 26 11:51:07.31: [ 4589]: <-- [9:AT+FTH=3\r]
Jun 26 11:51:07.36: [ 4589]: --> [7:CONNECT]
Jun 26 11:51:07.36: [ 4589]: <-- data [23]
Jun 26 11:51:07.36: [ 4589]: <-- data [2]
Jun 26 11:51:07.39: [ 4589]: --> [7:CONNECT]
Jun 26 11:51:07.39: [ 4589]: <-- data [6]
Jun 26 11:51:07.39: [ 4589]: <-- data [2]
Jun 26 11:51:09.56: [ 4589]: --> [2:OK]
Jun 26 11:51:09.56: [ 4589]: <-- [9:AT+FTS=7\r]
Jun 26 11:51:09.57: [ 4589]: --> [2:OK]
Jun 26 11:51:09.57: [ 4589]: <-- [10:AT+FTM=96\r]
Jun 26 11:51:10.08: [ 4589]: --> [7:CONNECT]
Jun 26 11:51:10.08: [ 4589]: <-- data [1024]
Jun 26 11:51:10.08: [ 4589]: <-- data [776]
Jun 26 11:51:10.08: [ 4589]: <-- data [2]
Jun 26 11:51:11.63: [ 4589]: --> [2:OK]
Jun 26 11:51:11.63: [ 4589]: <-- [9:AT+FRH=3\r]
Jun 26 11:51:12.78: [ 4589]: --> [7:CONNECT]
Jun 26 11:51:13.89: [ 4589]: --> [2:OK]
Jun 26 11:51:13.89: [ 4589]: SEND training at v.29 7200 bit/s
Jun 26 11:51:13.89: [ 4589]: <-- [9:AT+FRS=7\r]
Jun 26 11:51:13.90: [ 4589]: --> [2:OK]
Jun 26 11:51:13.90: [ 4589]: <-- [9:AT+FTH=3\r]
Jun 26 11:51:13.94: [ 4589]: --> [7:CONNECT]
Jun 26 11:51:13.94: [ 4589]: <-- data [23]
Jun 26 11:51:13.94: [ 4589]: <-- data [2]
Jun 26 11:51:13.97: [ 4589]: --> [7:CONNECT]
Jun 26 11:51:13.97: [ 4589]: <-- data [6]
Jun 26 11:51:13.97: [ 4589]: <-- data [2]
Jun 26 11:51:16.14: [ 4589]: --> [2:OK]
Jun 26 11:51:16.14: [ 4589]: <-- [9:AT+FTS=7\r]
Jun 26 11:51:16.15: [ 4589]: --> [2:OK]
Jun 26 11:51:16.15: [ 4589]: <-- [10:AT+FTM=72\r]
Jun 26 11:51:16.66: [ 4589]: --> [7:CONNECT]
Jun 26 11:51:16.66: [ 4589]: <-- data [1024]
Jun 26 11:51:16.66: [ 4589]: <-- data [326]
Jun 26 11:51:16.66: [ 4589]: <-- data [2]
Jun 26 11:51:18.21: [ 4589]: --> [2:OK]
Jun 26 11:51:18.21: [ 4589]: <-- [9:AT+FRH=3\r]
Jun 26 11:51:19.03: [ 4589]: --> [7:CONNECT]
Jun 26 11:51:20.13: [ 4589]: --> [2:OK]
Jun 26 11:51:20.13: [ 4589]: TRAINING succeeded
Jun 26 11:51:20.13: [ 4589]: DELAY 200 ms
Jun 26 11:51:20.33: [ 4589]: <-- [10:AT+FTM=72\r]
Jun 26 11:51:20.92: [ 4589]: --> [7:CONNECT]
Jun 26 11:51:20.92: [ 4589]: SEND begin page
Jun 26 11:51:20.93: [ 4589]: Reading MMR-compressed image file
Jun 26 11:51:20.93: [ 4589]: <-- data [74]
Jun 26 11:51:20.93: [ 4589]: SENT 74 bytes of data
Jun 26 11:51:20.93: [ 4589]: <-- data [75]
Jun 26 11:51:20.93: [ 4589]: SENT 75 bytes of data
Jun 26 11:51:20.93: [ 4589]: <-- data [42]
Jun 26 11:51:20.93: [ 4589]: SENT 42 bytes of data
Jun 26 11:51:20.93: [ 4589]: <-- data [75]
    Jun 26 11:51:34.93: [ 4589]: SENT 90 bytes of data
Jun 26 11:51:34.93: [ 4589]: <-- data [90]
Jun 26 11:51:34.93: [ 4589]: SENT 90 bytes of data
Jun 26 11:51:34.93: [ 4589]: <-- data [90]
Jun 26 11:51:34.93: [ 4589]: SENT 90 bytes of data
Jun 26 11:51:34.93: [ 4589]: <-- data [90]
Jun 26 11:51:34.93: [ 4589]: SENT 90 bytes of data
Jun 26 11:51:34.93: [ 4589]: <-- data [36]
Jun 26 11:51:34.93: [ 4589]: SENT 36 bytes of data
Jun 26 11:51:34.93: [ 4589]: SEND 2D RTC
Jun 26 11:51:34.93: [ 4589]: <-- data [30]
Jun 26 11:51:34.93: [ 4589]: <-- data [2]
Jun 26 11:51:34.93: [ 4589]: SEND end page
Jun 26 11:51:54.64: [ 4589]: --> [2:OK]
Jun 26 11:51:54.64: [ 4589]: <-- [9:AT+FTS=9\r]
Jun 26 11:51:54.65: [ 4589]: --> [2:OK]
Jun 26 11:51:54.65: [ 4589]: SEND send EOP (no more pages or documents)
Jun 26 11:51:54.65: [ 4589]: <-- [9:AT+FTH=3\r]
Jun 26 11:51:54.70: [ 4589]: --> [7:CONNECT]
Jun 26 11:51:54.70: [ 4589]: <-- data [3]
Jun 26 11:51:54.70: [ 4589]: <-- data [2]
Jun 26 11:51:56.01: [ 4589]: --> [2:OK]
Jun 26 11:51:56.01: [ 4589]: <-- [9:AT+FRH=3\r]
Jun 26 11:51:59.11: [ 4589]: --> [0:]
Jun 26 11:51:59.11: [ 4589]: MODEM <Empty line>
Jun 26 11:51:59.11: [ 4589]: <-- data [1]
Jun 26 11:51:59.31: [ 4589]: MODEM <Timeout>
Jun 26 11:51:59.31: [ 4589]: SEND send EOP (no more pages or documents)
Jun 26 11:51:59.31: [ 4589]: <-- [9:AT+FTH=3\r]
Jun 26 11:51:59.36: [ 4589]: --> [7:CONNECT]
Jun 26 11:51:59.36: [ 4589]: <-- data [3]
Jun 26 11:51:59.36: [ 4589]: <-- data [2]
Jun 26 11:52:00.67: [ 4589]: --> [2:OK]
Jun 26 11:52:00.67: [ 4589]: <-- [9:AT+FRH=3\r]
Jun 26 11:52:03.78: [ 4589]: --> [0:]
Jun 26 11:52:03.78: [ 4589]: MODEM <Empty line>
Jun 26 11:52:03.78: [ 4589]: <-- data [1]
Jun 26 11:52:03.98: [ 4589]: MODEM <Timeout>
Jun 26 11:52:03.98: [ 4589]: SEND send EOP (no more pages or documents)
Jun 26 11:52:03.98: [ 4589]: <-- [9:AT+FTH=3\r]
Jun 26 11:52:04.03: [ 4589]: --> [7:CONNECT]
Jun 26 11:52:04.03: [ 4589]: <-- data [3]
Jun 26 11:52:04.03: [ 4589]: <-- data [2]
Jun 26 11:52:05.34: [ 4589]: --> [2:OK]
Jun 26 11:52:05.34: [ 4589]: <-- [9:AT+FRH=3\r]
Jun 26 11:52:08.44: [ 4589]: --> [0:]
Jun 26 11:52:08.44: [ 4589]: MODEM <Empty line>
Jun 26 11:52:08.44: [ 4589]: <-- data [1]
Jun 26 11:52:08.64: [ 4589]: MODEM <Timeout>
Jun 26 11:52:08.64: [ 4589]: No response to EOP repeated 3 tries
Jun 26 11:52:08.64: [ 4589]: <-- [9:AT+FTH=3\r]
Jun 26 11:52:08.69: [ 4589]: --> [7:CONNECT]
Jun 26 11:52:08.69: [ 4589]: <-- data [3]
Jun 26 11:52:08.69: [ 4589]: <-- data [2]
Jun 26 11:52:10.00: [ 4589]: --> [2:OK]
Jun 26 11:52:10.00: [ 4589]: <-- [5:ATH0\r]
Jun 26 11:52:10.43: [ 4589]: --> [10:NO CARRIER]
Jun 26 11:52:10.43: [ 4589]: MODEM No carrier
Jun 26 11:52:10.43: [ 4589]: SESSION END

 

 

LOG-4

--------------------------------------------------------------------------------------

Jun 26 12:10:14.90: [  182]: SESSION BEGIN 000002789 27312666041
Jun 26 12:10:14.90: [  182]: HylaFAX (tm) Version 4.2.1
Jun 26 12:10:14.90: [  182]: <-- [4:ATA\r]
Jun 26 12:10:22.79: [  182]: --> [7:CONNECT]
Jun 26 12:10:22.79: [  182]: ANSWER: FAX CONNECTION  DEVICE '/dev/ttyS1'
Jun 26 12:10:22.79: [  182]: RECV FAX: begin
Jun 26 12:10:22.80: [  182]: <-- data [32]
Jun 26 12:10:22.80: [  182]: <-- data [2]
Jun 26 12:10:22.82: [  182]: --> [7:CONNECT]
Jun 26 12:10:22.82: [  182]: <-- data [23]
Jun 26 12:10:22.82: [  182]: <-- data [2]
Jun 26 12:10:22.85: [  182]: --> [7:CONNECT]
Jun 26 12:10:22.85: [  182]: <-- data [10]
Jun 26 12:10:22.85: [  182]: <-- data [2]
Jun 26 12:10:25.95: [  182]: --> [2:OK]
Jun 26 12:10:25.95: [  182]: <-- [9:AT+FRH=3\r]
Jun 26 12:10:26.21: [  182]: --> [7:CONNECT]
Jun 26 12:10:27.87: [  182]: --> [2:OK]
Jun 26 12:10:27.87: [  182]: REMOTE TSI "Liviero"
Jun 26 12:10:27.87: [  182]: <-- [9:AT+FRH=3\r]
Jun 26 12:10:27.89: [  182]: --> [7:CONNECT]
Jun 26 12:10:28.25: [  182]: --> [2:OK]
Jun 26 12:10:28.25: [  182]: REMOTE wants 14400 bit/s
Jun 26 12:10:28.25: [  182]: REMOTE wants A4 page width (215 mm)
Jun 26 12:10:28.25: [  182]: REMOTE wants unlimited page length
Jun 26 12:10:28.25: [  182]: REMOTE wants 3.85 line/mm
Jun 26 12:10:28.25: [  182]: REMOTE wants 1-D MH
Jun 26 12:10:28.25: [  182]: RECV training at v.17 14400 bit/s
Jun 26 12:10:28.25: [  182]: <-- [11:AT+FRM=145\r]
Jun 26 12:10:29.97: [  182]: --> [7:CONNECT]
Jun 26 12:10:32.43: [  182]: RECV: TCF 0 bytes, 0% non-zero, 0 zero-run
Jun 26 12:10:32.43: [  182]: RECV: reject TCF (zero run too short, min 1800)
Jun 26 12:10:32.43: [  182]: --> [10:NO CARRIER]
Jun 26 12:10:32.43: [  182]: DELAY 75 ms
Jun 26 12:10:32.51: [  182]: <-- [9:AT+FTH=3\r]
Jun 26 12:10:32.56: [  182]: --> [7:CONNECT]
Jun 26 12:10:32.56: [  182]: <-- data [3]
Jun 26 12:10:32.56: [  182]: <-- data [2]
Jun 26 12:10:33.88: [  182]: --> [2:OK]
Jun 26 12:10:33.88: [  182]: TRAINING failed
Jun 26 12:10:33.88: [  182]: <-- [9:AT+FRH=3\r]
Jun 26 12:10:34.11: [  182]: --> [7:CONNECT]
Jun 26 12:10:35.78: [  182]: --> [2:OK]
Jun 26 12:10:35.78: [  182]: REMOTE TSI "Liviero"
Jun 26 12:10:35.78: [  182]: <-- [9:AT+FRH=3\r]
Jun 26 12:10:35.80: [  182]: --> [7:CONNECT]
Jun 26 12:10:36.16: [  182]: --> [2:OK]
Jun 26 12:10:36.16: [  182]: REMOTE wants 12000 bit/s
Jun 26 12:10:36.16: [  182]: REMOTE wants A4 page width (215 mm)
Jun 26 12:10:36.16: [  182]: REMOTE wants unlimited page length
Jun 26 12:10:36.16: [  182]: REMOTE wants 3.85 line/mm
Jun 26 12:10:36.16: [  182]: REMOTE wants 1-D MH
Jun 26 12:10:36.16: [  182]: RECV training at v.17 12000 bit/s
Jun 26 12:10:36.16: [  182]: <-- [11:AT+FRM=121\r]
Jun 26 12:10:37.87: [  182]: --> [7:CONNECT]
Jun 26 12:10:39.43: [  182]: RECV: TCF 2100 bytes, 0% non-zero, 2100 zero-run
Jun 26 12:10:39.44: [  182]: --> [10:NO CARRIER]
Jun 26 12:10:39.44: [  182]: DELAY 75 ms
Jun 26 12:10:39.52: [  182]: TRAINING succeeded
Jun 26 12:10:39.52: [  182]: <-- [9:AT+FTH=3\r]
Jun 26 12:10:39.58: [  182]: --> [7:CONNECT]
Jun 26 12:10:39.58: [  182]: <-- data [3]
Jun 26 12:10:39.58: [  182]: <-- data [2]
Jun 26 12:10:40.89: [  182]: --> [2:OK]
Jun 26 12:10:40.89: [  182]: <-- [11:AT+FRM=122\r]
Jun 26 12:10:41.92: [  182]: --> [7:CONNECT]
Jun 26 12:10:41.92: [  182]: RECV: begin page
Jun 26 12:10:52.67: [  182]: RECV: 926 total lines, 4 bad lines, 1 consecutive bad lines
Jun 26 12:10:52.67: [  182]: RECV: end page
Jun 26 12:10:52.67: [  182]: --> [10:NO CARRIER]
Jun 26 12:10:52.67: [  182]: <-- [9:AT+FRH=3\r]
Jun 26 12:10:54.77: [  182]: --> [7:CONNECT]
Jun 26 12:10:55.93: [  182]: --> [2:OK]
Jun 26 12:10:55.93: [  182]: RECV recv EOP (no more pages or documents)
Jun 26 12:10:55.93: [  182]: <-- [9:AT+FRS=7\r]
Jun 26 12:10:56.05: [  182]: --> [2:OK]
Jun 26 12:10:56.06: [  182]: <-- [9:AT+FTH=3\r]
Jun 26 12:10:56.21: [  182]: --> [7:CONNECT]
Jun 26 12:10:56.21: [  182]: <-- data [3]
Jun 26 12:10:56.21: [  182]: <-- data [2]
Jun 26 12:10:57.52: [  182]: --> [2:OK]
Jun 26 12:10:57.52: [  182]: RECV send MCF (message confirmation)
Jun 26 12:10:57.52: [  182]: <-- [9:AT+FRH=3\r]
Jun 26 12:10:57.53: [  182]: RECV FAX (000002789): from Liviero, page 1 in 0:18, INF, 3.85 line/mm, 1-D MH, 12000 bit/s
Jun 26 12:10:57.53: [  182]: RECV FAX (000002789): recvq/fax000001967.tif from Liviero, route to <unspecified>, 1 pages in 0:35
Jun 26 12:10:58.74: [  182]: --> [7:CONNECT]
Jun 26 12:10:59.93: [  182]: --> [2:OK]
Jun 26 12:10:59.93: [  182]: RECV FAX: bin/faxrcvd "recvq/fax000001967.tif" "ttyS1" "000002789" "" "" ""
Jun 26 12:10:59.93: [  182]: RECV FAX: end
Jun 26 12:10:59.96: [  182]: SESSION END

 

Kind regards

 

David Wilson
D c D a t a
CNS, CLS, Linux+
Email/MSN: dave@xxxxxxxxxxxx
Phone: 0860-1-LINUX
Fax: 0866878971
Mobile: 0824147413
Skype: dave-wilson

 

Getting the most out your current PBX ?
Still paying it off ?
Want to hear how Linux & VOIP can save you money ?
Call us on 0860-1-LINUX

--
This email and all contents are subject to the following disclaimer:
http://www.dcdata.co.za/emaildisclaimer.php