Re: [hylafax-users] Modem Command Errors

[Horst Simon <hsimon@xxxxxxxxxxxxxxx>]

On Aug 25, 2004 01:12 PM, Lee Howard <faxguy@xxxxxxxxxxxxxxxx> wrote:

> On 2004.08.24 18:39 Horst Simon wrote:
> > Hi Lee,
> > 
> > The patch made it worse it took now 10 minutes for the timeout, with
> > the
> > error:
> > Failure to train remote modem.
> 
> Can you send me the log, please?

Log is attached c000000001

> 
> > I reverted back to HylaFax 4.1.8 on
> > both
> > installations and it sents OK at 9600 between the two modems and to
> > fax
> > machines.
> 
> Can you send me the log of one of these faxes?

Log is attached  c00000018

> 
> > I set the DesiredSpeed to 9600, but it still starts at 14400
> > and trains down to 9600. Is it possible to force it to communicate
> > with
> > 9600 only?
> 
> Are you saying that V.17 is broken on your modem?
> 
> Yes it (V.17) can be disabled, but the feature was added for 4.2.0.
> 
My mistake is started at 9600

Aug 24 13:58:41.81: [17956]: SESSION BEGIN 000000001 61395531624
Aug 24 13:58:41.81: [17956]: HylaFAX (tm) Version 4.2.0
Aug 24 13:58:41.81: [17956]: SEND FAX: JOB 1 DEST 9553-1624 COMMID 000000001 DEVICE '/dev/ttyS0'
Aug 24 13:58:41.81: [17956]: <-- [12:AT+FCLASS=1\r]
Aug 24 13:58:41.95: [17956]: --> [2:OK]
Aug 24 13:58:41.98: [17956]: DIAL 95531624
Aug 24 13:58:41.98: [17956]: <-- [13:ATDT95531624\r]
Aug 24 13:59:15.22: [17956]: --> [7:CONNECT]
Aug 24 13:59:16.94: [17956]: --> [2:OK]
Aug 24 13:59:16.94: [17956]: REMOTE NSF "B5 00 AA 48 79 6C 61 46 41 58 20 28 74 6D 29 20 56 65 72 73 69 6F 6E 20 34 2E 32 2E 30"
Aug 24 13:59:16.94: [17956]: NSF remote fax equipment: HylaFAX 
Aug 24 13:59:16.94: [17956]: NSF remote station ID: "HylaFAX (tm) Version 4.2.0"
Aug 24 13:59:16.94: [17956]: <-- [9:AT+FRH=3\r]
Aug 24 13:59:16.98: [17956]: --> [7:CONNECT]
Aug 24 13:59:17.63: [17956]: --> [2:OK]
Aug 24 13:59:17.63: [17956]: REMOTE CSI "HSC +61(0)3 95531624"
Aug 24 13:59:17.63: [17956]: <-- [9:AT+FRH=3\r]
Aug 24 13:59:17.67: [17956]: --> [7:CONNECT]
Aug 24 13:59:17.97: [17956]: --> [2:OK]
Aug 24 13:59:17.97: [17956]: REMOTE best rate 14400 bit/s
Aug 24 13:59:17.97: [17956]: REMOTE max A3 page width (303 mm)
Aug 24 13:59:17.97: [17956]: REMOTE max unlimited page length
Aug 24 13:59:17.97: [17956]: REMOTE best vres R16 x 15.4 line/mm
Aug 24 13:59:17.97: [17956]: REMOTE best format 2-D MMR
Aug 24 13:59:17.97: [17956]: REMOTE supports T.30 Annex A, 256-byte ECM
Aug 24 13:59:17.97: [17956]: REMOTE best 0 ms/scanline
Aug 24 13:59:17.97: [17956]: USE 14400 bit/s
Aug 24 13:59:17.97: [17956]: USE error correction mode
Aug 24 13:59:17.97: [17956]: USE 0 ms/scanline
Aug 24 13:59:17.97: [17956]: SEND file "docq/doc1.ps;00"
Aug 24 13:59:18.05: [17956]: USE A4 page width (215 mm)
Aug 24 13:59:18.05: [17956]: USE unlimited page length
Aug 24 13:59:18.05: [17956]: USE 3.85 line/mm
Aug 24 13:59:18.05: [17956]: USE 2-D MMR
Aug 24 13:59:18.05: [17956]: SEND training at v.17 14400 bit/s
Aug 24 13:59:18.05: [17956]: <-- [9:AT+FTH=3\r]
Aug 24 13:59:18.15: [17956]: --> [7:CONNECT]
Aug 24 13:59:18.15: [17956]: <-- data [23]
Aug 24 13:59:18.15: [17956]: <-- data [2]
Aug 24 13:59:19.15: [17956]: --> [7:CONNECT]
Aug 24 13:59:19.15: [17956]: <-- data [7]
Aug 24 13:59:19.15: [17956]: <-- data [2]
Aug 24 13:59:20.18: [17956]: --> [2:OK]
Aug 24 13:59:20.18: [17956]: <-- [9:AT+FTS=7\r]
Aug 24 13:59:20.29: [17956]: --> [2:OK]
Aug 24 13:59:20.29: [17956]: <-- [11:AT+FTM=145\r]
Aug 24 13:59:20.35: [17956]: --> [7:CONNECT]
Aug 24 13:59:20.35: [17956]: <-- data [1024]
Aug 24 13:59:21.96: [17956]: <-- data [1024]
Aug 24 13:59:22.35: [17956]: <-- data [652]
Aug 24 13:59:22.75: [17956]: <-- data [2]
Aug 24 13:59:23.27: [17956]: --> [2:]
Aug 24 13:59:23.27: [17956]: --> [2:OK]
Aug 24 14:00:23.27: [17956]: MODEM <Timeout>
Aug 24 14:00:23.27: [17956]: Problem sending TCF data
Aug 24 14:00:23.27: [17956]: <-- [9:AT+FRH=3\r]
Aug 24 14:00:26.37: [17956]: --> [0:]
Aug 24 14:00:26.37: [17956]: MODEM <Empty line>
Aug 24 14:00:26.37: [17956]: <-- data [1]
Aug 24 14:00:26.38: [17956]: --> [2:OK]
Aug 24 14:00:26.38: [17956]: DELAY 1500 ms
Aug 24 14:00:27.88: [17956]: SEND training at v.17 12000 bit/s
Aug 24 14:00:27.88: [17956]: <-- [9:AT+FTH=3\r]
Aug 24 14:00:27.97: [17956]: --> [7:CONNECT]
Aug 24 14:00:27.97: [17956]: <-- data [23]
Aug 24 14:00:27.97: [17956]: <-- data [2]
Aug 24 14:00:28.96: [17956]: --> [7:CONNECT]
Aug 24 14:00:28.96: [17956]: <-- data [7]
Aug 24 14:00:28.96: [17956]: <-- data [2]
Aug 24 14:00:29.99: [17956]: --> [2:OK]
Aug 24 14:00:29.99: [17956]: <-- [9:AT+FTS=7\r]
Aug 24 14:00:30.10: [17956]: --> [2:OK]
Aug 24 14:00:30.10: [17956]: <-- [11:AT+FTM=121\r]
Aug 24 14:00:30.17: [17956]: --> [7:CONNECT]
Aug 24 14:00:30.17: [17956]: <-- data [1024]
Aug 24 14:00:31.81: [17956]: <-- data [1024]
Aug 24 14:00:32.21: [17956]: <-- data [202]
Aug 24 14:00:32.21: [17956]: <-- data [2]
Aug 24 14:00:33.08: [17956]: --> [2:]
Aug 24 14:00:33.08: [17956]: --> [2:OK]
Aug 24 14:01:33.08: [17956]: MODEM <Timeout>
Aug 24 14:01:33.08: [17956]: Problem sending TCF data
Aug 24 14:01:33.08: [17956]: <-- [9:AT+FRH=3\r]
Aug 24 14:01:36.18: [17956]: --> [0:]
Aug 24 14:01:36.18: [17956]: MODEM <Empty line>
Aug 24 14:01:36.18: [17956]: <-- data [1]
Aug 24 14:01:36.19: [17956]: --> [2:OK]
Aug 24 14:01:36.19: [17956]: DELAY 1500 ms
Aug 24 14:01:37.69: [17956]: SEND training at v.17 9600 bit/s
Aug 24 14:01:37.69: [17956]: <-- [9:AT+FTH=3\r]
Aug 24 14:01:37.77: [17956]: --> [7:CONNECT]
Aug 24 14:01:37.77: [17956]: <-- data [23]
Aug 24 14:01:37.77: [17956]: <-- data [2]
Aug 24 14:01:38.77: [17956]: --> [7:CONNECT]
Aug 24 14:01:38.77: [17956]: <-- data [7]
Aug 24 14:01:38.77: [17956]: <-- data [2]
Aug 24 14:01:39.79: [17956]: --> [2:OK]
Aug 24 14:01:39.79: [17956]: <-- [9:AT+FTS=7\r]
Aug 24 14:01:39.90: [17956]: --> [2:OK]
Aug 24 14:01:39.90: [17956]: <-- [10:AT+FTM=97\r]
Aug 24 14:01:39.97: [17956]: --> [7:CONNECT]
Aug 24 14:01:39.97: [17956]: <-- data [1024]
Aug 24 14:01:41.68: [17956]: <-- data [776]
Aug 24 14:01:42.08: [17956]: <-- data [2]
Aug 24 14:01:42.88: [17956]: --> [4:]
Aug 24 14:01:42.88: [17956]: --> [2:OK]
Aug 24 14:02:42.88: [17956]: MODEM <Timeout>
Aug 24 14:02:42.88: [17956]: Problem sending TCF data
Aug 24 14:02:42.88: [17956]: <-- [9:AT+FRH=3\r]
Aug 24 14:02:45.98: [17956]: --> [0:]
Aug 24 14:02:45.98: [17956]: MODEM <Empty line>
Aug 24 14:02:45.98: [17956]: <-- data [1]
Aug 24 14:02:45.99: [17956]: --> [2:OK]
Aug 24 14:02:45.99: [17956]: DELAY 1500 ms
Aug 24 14:02:47.49: [17956]: SEND training at v.29 9600 bit/s
Aug 24 14:02:47.49: [17956]: <-- [9:AT+FTH=3\r]
Aug 24 14:02:47.58: [17956]: --> [7:CONNECT]
Aug 24 14:02:47.58: [17956]: <-- data [23]
Aug 24 14:02:47.58: [17956]: <-- data [2]
Aug 24 14:02:48.58: [17956]: --> [7:CONNECT]
Aug 24 14:02:48.58: [17956]: <-- data [7]
Aug 24 14:02:48.58: [17956]: <-- data [2]
Aug 24 14:02:49.60: [17956]: --> [2:OK]
Aug 24 14:02:49.60: [17956]: <-- [9:AT+FTS=7\r]
Aug 24 14:02:49.71: [17956]: --> [2:OK]
Aug 24 14:02:49.71: [17956]: <-- [10:AT+FTM=96\r]
Aug 24 14:02:49.78: [17956]: --> [7:CONNECT]
Aug 24 14:02:49.78: [17956]: <-- data [1024]
Aug 24 14:02:50.17: [17956]: <-- data [776]
Aug 24 14:02:50.68: [17956]: <-- data [2]
Aug 24 14:02:51.56: [17956]: --> [2:]
Aug 24 14:02:51.56: [17956]: --> [2:OK]
Aug 24 14:03:51.56: [17956]: MODEM <Timeout>
Aug 24 14:03:51.56: [17956]: Problem sending TCF data
Aug 24 14:03:51.56: [17956]: <-- [9:AT+FRH=3\r]
Aug 24 14:03:54.66: [17956]: --> [0:]
Aug 24 14:03:54.66: [17956]: MODEM <Empty line>
Aug 24 14:03:54.66: [17956]: <-- data [1]
Aug 24 14:03:54.67: [17956]: --> [2:OK]
Aug 24 14:03:54.67: [17956]: DELAY 1500 ms
Aug 24 14:03:56.17: [17956]: SEND training at v.29 7200 bit/s
Aug 24 14:03:56.17: [17956]: <-- [9:AT+FTH=3\r]
Aug 24 14:03:56.26: [17956]: --> [7:CONNECT]
Aug 24 14:03:56.26: [17956]: <-- data [23]
Aug 24 14:03:56.26: [17956]: <-- data [2]
Aug 24 14:03:57.26: [17956]: --> [7:CONNECT]
Aug 24 14:03:57.26: [17956]: <-- data [7]
Aug 24 14:03:57.26: [17956]: <-- data [2]
Aug 24 14:03:58.28: [17956]: --> [2:OK]
Aug 24 14:03:58.28: [17956]: <-- [9:AT+FTS=7\r]
Aug 24 14:03:58.40: [17956]: --> [2:OK]
Aug 24 14:03:58.40: [17956]: <-- [10:AT+FTM=72\r]
Aug 24 14:03:58.46: [17956]: --> [7:CONNECT]
Aug 24 14:03:58.46: [17956]: <-- data [1024]
Aug 24 14:03:58.85: [17956]: <-- data [326]
Aug 24 14:03:58.85: [17956]: <-- data [2]
Aug 24 14:04:00.24: [17956]: --> [2:]
Aug 24 14:04:00.24: [17956]: --> [2:OK]
Aug 24 14:05:00.24: [17956]: MODEM <Timeout>
Aug 24 14:05:00.24: [17956]: Problem sending TCF data
Aug 24 14:05:00.24: [17956]: <-- [9:AT+FRH=3\r]
Aug 24 14:05:03.34: [17956]: --> [0:]
Aug 24 14:05:03.34: [17956]: MODEM <Empty line>
Aug 24 14:05:03.34: [17956]: <-- data [1]
Aug 24 14:05:03.35: [17956]: --> [2:OK]
Aug 24 14:05:03.35: [17956]: DELAY 1500 ms
Aug 24 14:05:04.85: [17956]: SEND training at v.27ter 4800 bit/s
Aug 24 14:05:04.85: [17956]: <-- [9:AT+FTH=3\r]
Aug 24 14:05:04.94: [17956]: --> [7:CONNECT]
Aug 24 14:05:04.94: [17956]: <-- data [23]
Aug 24 14:05:04.94: [17956]: <-- data [2]
Aug 24 14:05:05.94: [17956]: --> [7:CONNECT]
Aug 24 14:05:05.94: [17956]: <-- data [7]
Aug 24 14:05:05.94: [17956]: <-- data [2]
Aug 24 14:05:06.97: [17956]: --> [2:OK]
Aug 24 14:05:06.97: [17956]: <-- [9:AT+FTS=7\r]
Aug 24 14:05:07.08: [17956]: --> [2:OK]
Aug 24 14:05:07.08: [17956]: <-- [10:AT+FTM=48\r]
Aug 24 14:05:07.17: [17956]: --> [7:CONNECT]
Aug 24 14:05:07.17: [17956]: <-- data [900]
Aug 24 14:05:07.17: [17956]: <-- data [2]
Aug 24 14:05:09.41: [17956]: --> [2:]
Aug 24 14:05:09.41: [17956]: --> [2:OK]
Aug 24 14:06:09.41: [17956]: MODEM <Timeout>
Aug 24 14:06:09.41: [17956]: Problem sending TCF data
Aug 24 14:06:09.41: [17956]: <-- [9:AT+FRH=3\r]
Aug 24 14:06:12.51: [17956]: --> [0:]
Aug 24 14:06:12.51: [17956]: MODEM <Empty line>
Aug 24 14:06:12.51: [17956]: <-- data [1]
Aug 24 14:06:12.52: [17956]: --> [2:OK]
Aug 24 14:06:12.52: [17956]: DELAY 1500 ms
Aug 24 14:06:14.02: [17956]: SEND training at v.27ter fallback mode 2400 bit/s
Aug 24 14:06:14.02: [17956]: <-- [9:AT+FTH=3\r]
Aug 24 14:06:14.11: [17956]: --> [7:CONNECT]
Aug 24 14:06:14.11: [17956]: <-- data [23]
Aug 24 14:06:14.11: [17956]: <-- data [2]
Aug 24 14:06:15.10: [17956]: --> [7:CONNECT]
Aug 24 14:06:15.10: [17956]: <-- data [7]
Aug 24 14:06:15.10: [17956]: <-- data [2]
Aug 24 14:06:16.14: [17956]: --> [2:OK]
Aug 24 14:06:16.14: [17956]: <-- [9:AT+FTS=7\r]
Aug 24 14:06:16.25: [17956]: --> [2:OK]
Aug 24 14:06:16.25: [17956]: <-- [10:AT+FTM=24\r]
Aug 24 14:06:16.36: [17956]: --> [7:CONNECT]
Aug 24 14:06:16.36: [17956]: <-- data [450]
Aug 24 14:06:16.36: [17956]: <-- data [2]
Aug 24 14:06:18.84: [17956]: --> [2:OK]
Aug 24 14:07:18.83: [17956]: MODEM <Timeout>
Aug 24 14:08:18.83: [17956]: MODEM <Empty line>
Aug 24 14:08:18.83: [17956]: Problem sending TCF data
Aug 24 14:08:18.83: [17956]: <-- [9:AT+FRH=3\r]
Aug 24 14:08:21.93: [17956]: --> [0:]
Aug 24 14:08:21.93: [17956]: MODEM <Empty line>
Aug 24 14:08:21.93: [17956]: <-- data [1]
Aug 24 14:08:21.94: [17956]: --> [2:OK]
Aug 24 14:08:21.94: [17956]: DELAY 1500 ms
Aug 24 14:08:23.44: [17956]: TRAINING failed
Aug 24 14:08:23.44: [17956]: <-- [9:AT+FTH=3\r]
Aug 24 14:08:23.53: [17956]: --> [7:CONNECT]
Aug 24 14:08:23.53: [17956]: <-- data [3]
Aug 24 14:08:23.53: [17956]: <-- data [2]
Aug 24 14:08:24.76: [17956]: --> [2:OK]
Aug 24 14:08:24.76: [17956]: <-- [5:ATH0\r]
Aug 24 14:08:25.51: [17956]: --> [2:OK]
Aug 24 14:08:25.51: [17956]: SESSION END

Aug 25 13:37:22.91: [ 5317]: SESSION BEGIN 00000018 61395531624
Aug 25 13:37:22.91: [ 5317]: HylaFAX (tm) Version 4.1.8
Aug 25 13:37:22.91: [ 5317]: SEND FAX: JOB 10 DEST 95531624 COMMID 00000018 DEVICE '/dev/ttyS0'
Aug 25 13:37:22.91: [ 5317]: DELAY 75 ms
Aug 25 13:37:22.98: [ 5317]: DELAY 2600 ms
Aug 25 13:37:26.58: [ 5317]: DELAY 10 ms
Aug 25 13:37:26.59: [ 5317]: <-- [4:ATZ\r]
Aug 25 13:37:28.44: [ 5317]: --> [2:OK]
Aug 25 13:37:28.44: [ 5317]: DELAY 3000 ms
Aug 25 13:37:31.44: [ 5317]: <-- [5:ATE0\r]
Aug 25 13:37:31.47: [ 5317]: --> [2:OK]
Aug 25 13:37:31.47: [ 5317]: <-- [5:ATV1\r]
Aug 25 13:37:31.50: [ 5317]: --> [2:OK]
Aug 25 13:37:31.50: [ 5317]: <-- [5:ATQ0\r]
Aug 25 13:37:31.53: [ 5317]: --> [2:OK]
Aug 25 13:37:31.53: [ 5317]: <-- [7:ATS0=0\r]
Aug 25 13:37:31.58: [ 5317]: --> [2:OK]
Aug 25 13:37:31.58: [ 5317]: <-- [7:ATS8=2\r]
Aug 25 13:37:31.62: [ 5317]: --> [2:OK]
Aug 25 13:37:31.62: [ 5317]: <-- [8:ATS7=60\r]
Aug 25 13:37:31.66: [ 5317]: --> [2:OK]
Aug 25 13:37:31.66: [ 5317]: <-- [6:AT&K3\r]
Aug 25 13:37:31.69: [ 5317]: --> [2:OK]
Aug 25 13:37:31.69: [ 5317]: <-- [6:AT&D2\r]
Aug 25 13:37:31.72: [ 5317]: --> [2:OK]
Aug 25 13:37:31.72: [ 5317]: <-- [6:AT&C1\r]
Aug 25 13:37:31.75: [ 5317]: --> [2:OK]
Aug 25 13:37:31.75: [ 5317]: <-- [12:AT+FCLASS=1\r]
Aug 25 13:37:31.79: [ 5317]: --> [2:OK]
Aug 25 13:37:31.79: [ 5317]: <-- [5:ATM0\r]
Aug 25 13:37:31.82: [ 5317]: --> [2:OK]
Aug 25 13:37:31.82: [ 5317]: <-- [12:AT+FCLASS=1\r]
Aug 25 13:37:31.97: [ 5317]: --> [2:OK]
Aug 25 13:37:31.97: [ 5317]: DIAL 95531624
Aug 25 13:37:31.97: [ 5317]: <-- [13:ATDT95531624\r]
Aug 25 13:38:04.96: [ 5317]: --> [7:CONNECT]
Aug 25 13:38:06.43: [ 5317]: --> [2:OK]
Aug 25 13:38:06.43: [ 5317]: REMOTE CSI "HSC +61(0)3 95531624"
Aug 25 13:38:06.43: [ 5317]: <-- [9:AT+FRH=3\r]
Aug 25 13:38:06.47: [ 5317]: --> [7:CONNECT]
Aug 25 13:38:06.77: [ 5317]: --> [2:OK]
Aug 25 13:38:06.77: [ 5317]: REMOTE best rate 14400 bit/s
Aug 25 13:38:06.77: [ 5317]: REMOTE max page width 2432 pixels in 303 mm
Aug 25 13:38:06.77: [ 5317]: REMOTE max unlimited page length
Aug 25 13:38:06.77: [ 5317]: REMOTE best vres 7.7 line/mm
Aug 25 13:38:06.77: [ 5317]: REMOTE best format 2-D MR
Aug 25 13:38:06.77: [ 5317]: REMOTE best 0 ms/scanline
Aug 25 13:38:06.77: [ 5317]: USE 9600 bit/s
Aug 25 13:38:06.77: [ 5317]: USE 20 ms/scanline
Aug 25 13:38:06.77: [ 5317]: SEND file "docq/doc11.ps;41"
Aug 25 13:38:06.77: [ 5317]: USE page width 1728 pixels in 215 mm
Aug 25 13:38:06.77: [ 5317]: USE unlimited page length
Aug 25 13:38:06.77: [ 5317]: USE 7.7 line/mm
Aug 25 13:38:06.77: [ 5317]: USE 2-D MR
Aug 25 13:38:06.77: [ 5317]: SEND training at v.17 9600 bit/s
Aug 25 13:38:06.77: [ 5317]: <-- [9:AT+FTH=3\r]
Aug 25 13:38:06.91: [ 5317]: --> [7:CONNECT]
Aug 25 13:38:06.91: [ 5317]: <-- data [23]
Aug 25 13:38:06.91: [ 5317]: <-- data [2]
Aug 25 13:38:07.90: [ 5317]: --> [7:CONNECT]
Aug 25 13:38:07.90: [ 5317]: <-- data [6]
Aug 25 13:38:07.90: [ 5317]: <-- data [2]
Aug 25 13:38:08.90: [ 5317]: --> [2:OK]
Aug 25 13:38:08.90: [ 5317]: <-- [9:AT+FTS=7\r]
Aug 25 13:38:09.01: [ 5317]: --> [2:OK]
Aug 25 13:38:09.01: [ 5317]: <-- [10:AT+FTM=97\r]
Aug 25 13:38:09.07: [ 5317]: --> [7:CONNECT]
Aug 25 13:38:09.07: [ 5317]: <-- data [1024]
Aug 25 13:38:10.79: [ 5317]: <-- data [776]
Aug 25 13:38:11.19: [ 5317]: <-- data [2]
Aug 25 13:38:12.00: [ 5317]: --> [4:]
Aug 25 13:38:12.00: [ 5317]: Problem sending TCF data
Aug 25 13:38:12.00: [ 5317]: <-- [9:AT+FRH=3\r]
Aug 25 13:38:12.00: [ 5317]: --> [2:OK]
Aug 25 13:38:12.76: [ 5317]: --> [7:CONNECT]
Aug 25 13:38:13.83: [ 5317]: --> [2:OK]
Aug 25 13:38:13.83: [ 5317]: TRAINING succeeded
Aug 25 13:38:13.83: [ 5317]: DELAY 75 ms
Aug 25 13:38:13.90: [ 5317]: <-- [10:AT+FTM=98\r]
Aug 25 13:38:13.97: [ 5317]: --> [7:CONNECT]
Aug 25 13:38:13.97: [ 5317]: SEND begin page
Aug 25 13:38:13.97: [ 5317]: <-- data [1033]
Aug 25 13:38:14.36: [ 5317]: <-- data [1028]
Aug 25 13:38:15.09: [ 5317]: <-- data [1024]
Aug 25 13:38:15.49: [ 5317]: <-- data [1024]
Aug 25 13:38:16.38: [ 5317]: <-- data [1031]
Aug 25 13:38:17.28: [ 5317]: <-- data [1026]
Aug 25 13:38:18.18: [ 5317]: <-- data [1029]
Aug 25 13:38:19.08: [ 5317]: <-- data [1027]
Aug 25 13:38:19.98: [ 5317]: <-- data [1031]
Aug 25 13:38:20.89: [ 5317]: <-- data [1030]
Aug 25 13:38:21.79: [ 5317]: <-- data [1028]
Aug 25 13:38:22.69: [ 5317]: <-- data [1024]
Aug 25 13:38:23.59: [ 5317]: <-- data [1024]
Aug 25 13:38:24.09: [ 5317]: <-- data [1024]
Aug 25 13:38:24.98: [ 5317]: <-- data [1024]
Aug 25 13:38:25.87: [ 5317]: <-- data [1024]
Aug 25 13:38:26.77: [ 5317]: <-- data [1024]
Aug 25 13:38:27.66: [ 5317]: <-- data [1024]
Aug 25 13:38:28.55: [ 5317]: <-- data [1024]
Aug 25 13:38:28.95: [ 5317]: <-- data [1024]
Aug 25 13:38:29.85: [ 5317]: <-- data [1024]
Aug 25 13:38:30.74: [ 5317]: <-- data [1024]
Aug 25 13:38:31.63: [ 5317]: <-- data [1024]
Aug 25 13:38:32.53: [ 5317]: <-- data [1024]
Aug 25 13:38:33.42: [ 5317]: <-- data [1024]
Aug 25 13:38:34.31: [ 5317]: <-- data [1024]
Aug 25 13:38:35.21: [ 5317]: <-- data [1024]
Aug 25 13:38:36.10: [ 5317]: <-- data [1024]
Aug 25 13:38:36.99: [ 5317]: <-- data [1024]
Aug 25 13:38:37.89: [ 5317]: <-- data [1024]
Aug 25 13:38:38.78: [ 5317]: <-- data [1024]
Aug 25 13:38:39.67: [ 5317]: <-- data [1024]
Aug 25 13:38:40.57: [ 5317]: <-- data [1024]
Aug 25 13:38:41.46: [ 5317]: <-- data [1024]
Aug 25 13:38:42.35: [ 5317]: <-- data [1024]
Aug 25 13:38:42.85: [ 5317]: <-- data [1024]
Aug 25 13:38:43.74: [ 5317]: <-- data [1024]
Aug 25 13:38:44.63: [ 5317]: <-- data [1024]
Aug 25 13:38:45.53: [ 5317]: <-- data [1024]
Aug 25 13:38:46.42: [ 5317]: <-- data [1024]
Aug 25 13:38:47.31: [ 5317]: <-- data [1024]
Aug 25 13:38:48.21: [ 5317]: <-- data [1024]
Aug 25 13:38:48.61: [ 5317]: <-- data [1024]
Aug 25 13:38:49.50: [ 5317]: <-- data [1024]
Aug 25 13:38:50.39: [ 5317]: <-- data [1024]
Aug 25 13:38:51.29: [ 5317]: <-- data [1024]
Aug 25 13:38:52.18: [ 5317]: <-- data [1024]
Aug 25 13:38:53.07: [ 5317]: <-- data [1024]
Aug 25 13:38:53.97: [ 5317]: <-- data [1024]
Aug 25 13:38:54.86: [ 5317]: <-- data [1024]
Aug 25 13:38:55.75: [ 5317]: <-- data [1024]
Aug 25 13:38:56.65: [ 5317]: <-- data [1024]
Aug 25 13:38:57.54: [ 5317]: <-- data [1024]
Aug 25 13:38:58.44: [ 5317]: <-- data [735]
Aug 25 13:38:58.93: [ 5317]: SENT 55007 bytes of data
Aug 25 13:38:58.93: [ 5317]: <-- data [1024]
Aug 25 13:38:59.82: [ 5317]: <-- data [272]
Aug 25 13:39:00.22: [ 5317]: SENT 1296 bytes of data
Aug 25 13:39:00.22: [ 5317]: SEND 2D RTC
Aug 25 13:39:00.22: [ 5317]: <-- data [30]
Aug 25 13:39:00.22: [ 5317]: <-- data [2]
Aug 25 13:39:00.22: [ 5317]: SEND end page
Aug 25 13:39:01.09: [ 5317]: --> [104:]
Aug 25 13:39:01.09: [ 5317]: --> [2:OK]
Aug 25 13:39:01.09: [ 5317]: <-- [9:AT+FTS=9\r]
Aug 25 13:39:01.22: [ 5317]: --> [2:OK]
Aug 25 13:39:01.22: [ 5317]: SEND send EOP (no more pages or documents)
Aug 25 13:39:01.22: [ 5317]: <-- [9:AT+FTH=3\r]
Aug 25 13:39:01.30: [ 5317]: --> [7:CONNECT]
Aug 25 13:39:01.30: [ 5317]: <-- data [3]
Aug 25 13:39:01.30: [ 5317]: <-- data [2]
Aug 25 13:39:02.52: [ 5317]: --> [2:OK]
Aug 25 13:39:02.53: [ 5317]: <-- [9:AT+FRH=3\r]
Aug 25 13:39:02.85: [ 5317]: --> [7:CONNECT]
Aug 25 13:39:03.92: [ 5317]: --> [2:OK]
Aug 25 13:39:03.92: [ 5317]: SEND recv MCF (message confirmation)
Aug 25 13:39:03.92: [ 5317]: SEND FAX (00000018): FROM hbs0@localhost TO 95531624 (page 1 of 1 sent in 0:57)
Aug 25 13:39:03.92: [ 5317]: SEND FAX (00000018): FROM hbs0@localhost TO 95531624 (docq/doc11.ps;41 sent in 0:57)
Aug 25 13:39:03.92: [ 5317]: <-- [9:AT+FTH=3\r]
Aug 25 13:39:04.05: [ 5317]: --> [7:CONNECT]
Aug 25 13:39:04.05: [ 5317]: <-- data [3]
Aug 25 13:39:04.05: [ 5317]: <-- data [2]
Aug 25 13:39:05.27: [ 5317]: --> [2:OK]
Aug 25 13:39:05.27: [ 5317]: <-- [5:ATH0\r]
Aug 25 13:39:06.02: [ 5317]: --> [2:OK]
Aug 25 13:39:06.02: [ 5317]: SESSION END