Re: [hylafax-users] Some users don't get all scan lines

[Michael Evans <michael@togawasmith.com>]

Lee Howard wrote:

>>>
>>>
>>> Post the new session log.  The one from before was at unreliable 
>>> bitrates, which was why Class1TMConnectDelay was required.
>>
>
>> Aug 26 15:22:15.60: [ 1094]: <-- [11:AT+FTM=145\r]
>> Aug 26 15:22:15.61: [ 1094]: --> [7:CONNECT]
>> Aug 26 15:22:15.61: [ 1094]: <-- data [1024]
>> Aug 26 15:22:15.61: [ 1094]: <-- data [1024]
>> Aug 26 15:22:15.61: [ 1094]: <-- data [652]
>> Aug 26 15:22:15.61: [ 1094]: <-- data [2]
>> Aug 26 15:22:18.74: [ 1094]: --> [2:OK]
>
>
> If Class1TMConnectDelay were working there would be a delay after this 
> CONNECT.  So, it would seem that you didn't apply the patch properly 
> or something.  Please double-check your efforts.
>
> Lee.

Lee,

Thanks for the tip. I checked the dates on the HylaFAX-related files in 
/usr/sbin, and sure enough they were from the SuSE binaries (dated 
2001). I believe when I compiled the sources for 4.1.7 and ran 'make 
install,' I may have had the HylaFAX server and faxgetty processes still 
running from the old installation. In particular, if there's a faxgetty 
process still going, you can't overwrite the old executable with a new copy.

So, I stopped the HylaFAX server, commented out the faxgetty line in 
/etc/inittab, and ran ps -aux to make sure there were no instances of 
these processes running. Then I ran 'make install' in the 4.1.7 
directory again, as well as faxsetup. Finally, I uncommented the 
faxgetty line in /etc/inittab and ran 'init q.'

Now the logs are showing the 400 ms delay after CONNECT, but this hasn't 
solved the problem. Faxes sent to the HP still are missing scan lines, 
while faxes sent to the Brother fax are perfect. In addition, there is 
still a tendency for the HP to respond to the EOP with an RTN complaint, 
which causes HylaFAX to send the page again. (But faxes sent from the 
Brother machine to the HP come out correctly.) Even in cases where there 
is no RTN and the log looks OK, the fax that comes out of the HP is 
blank after about the first inch or so.

I've posted two new logs. Is there anything else I can try?

Thanks,

Michael Evans
Aug 27 18:40:13.87: [ 7518]: SESSION BEGIN 00000010 16267961336
Aug 27 18:40:13.87: [ 7518]: HylaFAX (tm) Version 4.1.7
Aug 27 18:40:13.87: [ 7518]: SEND FAX: JOB 60 DEST 7961336 COMMID 00000010 DEVICE '/dev/ttyS4'
Aug 27 18:40:13.87: [ 7518]: MODEM set DTR OFF
Aug 27 18:40:13.87: [ 7518]: DELAY 75 ms
Aug 27 18:40:13.95: [ 7518]: MODEM set DTR ON
Aug 27 18:40:13.95: [ 7518]: DELAY 2600 ms
Aug 27 18:40:17.56: [ 7518]: MODEM set baud rate: 19200 baud, input flow XON/XOFF, output flow XON/XOF
F
Aug 27 18:40:17.56: [ 7518]: DELAY 10 ms
Aug 27 18:40:17.57: [ 7518]: MODEM flush i/o
Aug 27 18:40:17.57: [ 7518]: <-- [4:ATZ\r]
Aug 27 18:40:17.73: [ 7518]: --> [2:OK]
Aug 27 18:40:17.73: [ 7518]: DELAY 3000 ms
Aug 27 18:40:20.73: [ 7518]: <-- [5:ATE0\r]
Aug 27 18:40:20.74: [ 7518]: --> [4:ATE0]
Aug 27 18:40:20.74: [ 7518]: --> [2:OK]
Aug 27 18:40:20.74: [ 7518]: <-- [5:ATV1\r]
Aug 27 18:40:20.75: [ 7518]: --> [2:OK]
Aug 27 18:40:20.75: [ 7518]: <-- [5:ATQ0\r]
Aug 27 18:40:20.76: [ 7518]: --> [2:OK]
Aug 27 18:40:20.76: [ 7518]: <-- [7:ATS0=0\r]
Aug 27 18:40:20.77: [ 7518]: --> [2:OK]
Aug 27 18:40:20.77: [ 7518]: <-- [7:ATS8=2\r]
Aug 27 18:40:20.78: [ 7518]: --> [2:OK]
Aug 27 18:40:20.78: [ 7518]: <-- [8:ATS7=60\r]
Aug 27 18:40:20.79: [ 7518]: --> [2:OK]
Aug 27 18:40:20.79: [ 7518]: <-- [9:AT+FLO=1\r]
Aug 27 18:40:20.80: [ 7518]: --> [2:OK]
Aug 27 18:40:20.80: [ 7518]: <-- [12:AT+FCLASS=1\r]
Aug 27 18:40:20.81: [ 7518]: --> [2:OK]
Aug 27 18:40:20.81: [ 7518]: <-- [5:ATM0\r]
Aug 27 18:40:20.82: [ 7518]: --> [2:OK]
Aug 27 18:40:20.82: [ 7518]: STATE CHANGE: RUNNING -> SENDING
Aug 27 18:40:20.82: [ 7518]: MODEM input buffering enabled
Aug 27 18:40:20.82: [ 7518]: <-- [12:AT+FCLASS=1\r]
Aug 27 18:40:20.93: [ 7518]: --> [2:OK]
Aug 27 18:40:20.93: [ 7518]: MODEM set XON/XOFF/FLUSH: input ignored, output disabled
Aug 27 18:40:20.93: [ 7518]: DIAL 7961336
Aug 27 18:40:20.93: [ 7518]: <-- [12:ATDT7961336\r]
Aug 27 18:40:45.77: [ 7518]: --> [7:CONNECT]
Aug 27 18:40:45.77: [ 7518]: MODEM input buffering disabled
Aug 27 18:40:47.14: [ 7518]: --> HDLC<13:FF C0 04 B5 00 6C 05 00 00 00 00 1C 75>
Aug 27 18:40:47.14: [ 7518]: --> [2:OK]
Aug 27 18:40:47.14: [ 7518]: REMOTE NSF "AD 00 36 A0 00 00 00 00 38"
Aug 27 18:40:47.14: [ 7518]: NSF remote fax equipment: HP
Aug 27 18:40:47.14: [ 7518]: <-- [9:AT+FRH=3\r]
Aug 27 18:40:47.15: [ 7518]: --> [7:CONNECT]
Aug 27 18:40:47.84: [ 7518]: --> HDLC<25:FF C0 02 6C CC CC 8C 04 6C 9C EC 04 6C 4C 6C 04 04 04 04 04 0
4 04 04 AD A7>
Aug 27 18:40:47.85: [ 7518]: --> [2:OK]
Aug 27 18:40:47.85: [ 7518]: REMOTE CSI "626 796 1336"
Aug 27 18:40:47.85: [ 7518]: <-- [9:AT+FRH=3\r]
Aug 27 18:40:47.86: [ 7518]: --> [7:CONNECT]
Aug 27 18:40:48.28: [ 7518]: --> HDLC<15:FF C8 01 00 77 1F 23 01 49 01 01 19 00 3A A4>
Aug 27 18:40:48.28: [ 7518]: --> [2:OK]
Aug 27 18:40:48.28: [ 7518]: REMOTE best rate 14400 bit/s
Aug 27 18:40:48.28: [ 7518]: REMOTE max page width 1728 pixels in 215 mm
Aug 27 18:40:48.28: [ 7518]: REMOTE max unlimited page length
Aug 27 18:40:48.28: [ 7518]: REMOTE best vres 7.7 line/mm
Aug 27 18:40:48.28: [ 7518]: REMOTE best format 2-D MMR
Aug 27 18:40:48.28: [ 7518]: REMOTE supports T.30 Annex A, ECM
Aug 27 18:40:48.28: [ 7518]: REMOTE best 0 ms/scanline
Aug 27 18:40:48.28: [ 7518]: USE 14400 bit/s
Aug 27 18:40:48.28: [ 7518]: USE 0 ms/scanline
Aug 27 18:40:48.28: [ 7518]: SEND file "docq/doc60.ps;71"
Aug 27 18:40:48.28: [ 7518]: USE page width 1728 pixels in 215 mm
Aug 27 18:40:48.28: [ 7518]: USE unlimited page length
Aug 27 18:40:48.28: [ 7518]: USE 7.7 line/mm
Aug 27 18:40:48.28: [ 7518]: USE 2-D MR
Aug 27 18:40:48.28: [ 7518]: SEND training at v.17 14400 bit/s
Aug 27 18:40:48.28: [ 7518]: <-- [9:AT+FTH=3\r]
Aug 27 18:40:49.14: [ 7518]: --> [7:CONNECT]
Aug 27 18:40:49.14: [ 7518]: <-- HDLC<23:FF C0 C2 74 C6 76 92 04 34 16 2E 96 B6 CA 04 64 04 86 EE 86 E
6 F6 2A>
Aug 27 18:40:49.14: [ 7518]: <-- data [23]
Aug 27 18:40:49.14: [ 7518]: <-- data [2]
Aug 27 18:40:49.15: [ 7518]: --> [7:CONNECT]
Aug 27 18:40:49.15: [ 7518]: <-- HDLC<6:FF C8 C1 00 47 1E>
Aug 27 18:40:49.15: [ 7518]: <-- data [6]
Aug 27 18:40:49.15: [ 7518]: <-- data [2]
Aug 27 18:40:50.24: [ 7518]: --> [2:OK]
Aug 27 18:40:50.24: [ 7518]: <-- [9:AT+FTS=7\r]
Aug 27 18:40:50.30: [ 7518]: --> [2:OK]
Aug 27 18:40:50.30: [ 7518]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled
Aug 27 18:40:50.30: [ 7518]: <-- [11:AT+FTM=145\r]
Aug 27 18:40:50.31: [ 7518]: --> [7:CONNECT]
Aug 27 18:40:50.31: [ 7518]: DELAY 400 ms
Aug 27 18:40:50.71: [ 7518]: <-- data [1024]
Aug 27 18:40:50.71: [ 7518]: <-- data [1024]
Aug 27 18:40:50.71: [ 7518]: <-- data [652]
Aug 27 18:40:50.71: [ 7518]: <-- data [2]
Aug 27 18:40:53.44: [ 7518]: --> [2:OK]
Aug 27 18:40:53.44: [ 7518]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled
Aug 27 18:40:53.44: [ 7518]: <-- [9:AT+FRH=3\r]
Aug 27 18:40:53.56: [ 7518]: --> [7:CONNECT]
Aug 27 18:40:54.83: [ 7518]: --> HDLC<5:FF C8 22 67 DD>
Aug 27 18:40:54.83: [ 7518]: --> [2:OK]
Aug 27 18:40:54.83: [ 7518]: SEND training at v.17 12000 bit/s
Aug 27 18:40:54.83: [ 7518]: <-- [9:AT+FTH=3\r]
Aug 27 18:40:55.69: [ 7518]: --> [7:CONNECT]
Aug 27 18:40:55.69: [ 7518]: <-- HDLC<23:FF C0 C2 74 C6 76 92 04 34 16 2E 96 B6 CA 04 64 04 86 EE 86 E
6 F6 2A>
Aug 27 18:40:55.69: [ 7518]: <-- data [23]
Aug 27 18:40:55.69: [ 7518]: <-- data [2]
Aug 27 18:40:55.70: [ 7518]: --> [7:CONNECT]
Aug 27 18:40:55.70: [ 7518]: <-- HDLC<6:FF C8 C1 00 57 1E>
Aug 27 18:40:55.70: [ 7518]: <-- data [6]
Aug 27 18:40:55.70: [ 7518]: <-- data [2]
Aug 27 18:40:56.79: [ 7518]: --> [2:OK]
Aug 27 18:40:56.79: [ 7518]: <-- [9:AT+FTS=7\r]
Aug 27 18:40:56.85: [ 7518]: --> [2:OK]
Aug 27 18:40:56.85: [ 7518]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled
Aug 27 18:40:56.85: [ 7518]: <-- [11:AT+FTM=121\r]
Aug 27 18:40:56.86: [ 7518]: --> [7:CONNECT]
Aug 27 18:40:56.86: [ 7518]: DELAY 400 ms
Aug 27 18:40:57.26: [ 7518]: <-- data [1024]
Aug 27 18:40:57.26: [ 7518]: <-- data [1024]
Aug 27 18:40:57.26: [ 7518]: <-- data [202]
Aug 27 18:40:57.26: [ 7518]: <-- data [2]
Aug 27 18:41:00.00: [ 7518]: --> [2:OK]
Aug 27 18:41:00.00: [ 7518]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled
Aug 27 18:41:00.00: [ 7518]: <-- [9:AT+FRH=3\r]
Aug 27 18:41:00.12: [ 7518]: --> [7:CONNECT]
Aug 27 18:41:01.39: [ 7518]: --> HDLC<5:FF C8 22 67 DD>
Aug 27 18:41:01.39: [ 7518]: --> [2:OK]
Aug 27 18:41:01.39: [ 7518]: SEND training at v.17 9600 bit/s
Aug 27 18:41:01.39: [ 7518]: <-- [9:AT+FTH=3\r]
Aug 27 18:41:02.25: [ 7518]: --> [7:CONNECT]
Aug 27 18:41:02.25: [ 7518]: <-- HDLC<23:FF C0 C2 74 C6 76 92 04 34 16 2E 96 B6 CA 04 64 04 86 EE 86 E
6 F6 2A>
Aug 27 18:41:02.25: [ 7518]: <-- data [23]
Aug 27 18:41:02.25: [ 7518]: <-- data [2]
Aug 27 18:41:02.26: [ 7518]: --> [7:CONNECT]
Aug 27 18:41:02.26: [ 7518]: <-- HDLC<6:FF C8 C1 00 67 1E>
Aug 27 18:41:02.26: [ 7518]: <-- data [6]
Aug 27 18:41:02.26: [ 7518]: <-- data [2]
Aug 27 18:41:03.35: [ 7518]: --> [2:OK]
Aug 27 18:41:03.35: [ 7518]: <-- [9:AT+FTS=7\r]
Aug 27 18:41:03.41: [ 7518]: --> [2:OK]
Aug 27 18:41:03.41: [ 7518]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled
Aug 27 18:41:03.41: [ 7518]: <-- [10:AT+FTM=97\r]
Aug 27 18:41:03.42: [ 7518]: --> [7:CONNECT]
Aug 27 18:41:03.42: [ 7518]: DELAY 400 ms
Aug 27 18:41:03.82: [ 7518]: <-- data [1024]
Aug 27 18:41:03.82: [ 7518]: <-- data [776]
Aug 27 18:41:03.82: [ 7518]: <-- data [2]
Aug 27 18:41:06.56: [ 7518]: --> [2:OK]
Aug 27 18:41:06.56: [ 7518]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled
Aug 27 18:41:06.56: [ 7518]: <-- [9:AT+FRH=3\r]
Aug 27 18:41:06.68: [ 7518]: --> [7:CONNECT]
Aug 27 18:41:07.95: [ 7518]: --> HDLC<5:FF C8 22 67 DD>
Aug 27 18:41:07.95: [ 7518]: --> [2:OK]
Aug 27 18:41:07.95: [ 7518]: SEND training at v.17 7200 bit/s
Aug 27 18:41:07.95: [ 7518]: <-- [9:AT+FTH=3\r]
Aug 27 18:41:08.81: [ 7518]: --> [7:CONNECT]
Aug 27 18:41:08.81: [ 7518]: <-- HDLC<23:FF C0 C2 74 C6 76 92 04 34 16 2E 96 B6 CA 04 64 04 86 EE 86 E
6 F6 2A>
Aug 27 18:41:08.81: [ 7518]: <-- data [23]
Aug 27 18:41:08.81: [ 7518]: <-- data [2]
Aug 27 18:41:08.82: [ 7518]: --> [7:CONNECT]
Aug 27 18:41:08.82: [ 7518]: <-- HDLC<6:FF C8 C1 00 77 1E>
Aug 27 18:41:08.82: [ 7518]: <-- data [6]
Aug 27 18:41:08.82: [ 7518]: <-- data [2]
Aug 27 18:41:09.91: [ 7518]: --> [2:OK]
Aug 27 18:41:09.91: [ 7518]: <-- [9:AT+FTS=7\r]
Aug 27 18:41:09.97: [ 7518]: --> [2:OK]
Aug 27 18:41:09.97: [ 7518]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled
Aug 27 18:41:09.97: [ 7518]: <-- [10:AT+FTM=73\r]
Aug 27 18:41:09.98: [ 7518]: --> [7:CONNECT]
Aug 27 18:41:09.98: [ 7518]: DELAY 400 ms
Aug 27 18:41:10.38: [ 7518]: <-- data [1024]
Aug 27 18:41:10.38: [ 7518]: <-- data [326]
Aug 27 18:41:10.38: [ 7518]: <-- data [2]
Aug 27 18:41:13.13: [ 7518]: --> [2:OK]
Aug 27 18:41:13.13: [ 7518]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled
Aug 27 18:41:13.13: [ 7518]: <-- [9:AT+FRH=3\r]
Aug 27 18:41:13.25: [ 7518]: --> [7:CONNECT]
Aug 27 18:41:14.52: [ 7518]: --> HDLC<5:FF C8 21 57 BE>
Aug 27 18:41:14.52: [ 7518]: --> [2:OK]
Aug 27 18:41:14.52: [ 7518]: TRAINING succeeded
Aug 27 18:41:14.52: [ 7518]: DELAY 75 ms
Aug 27 18:41:14.60: [ 7518]: <-- [10:AT+FTM=74\r]
Aug 27 18:41:14.61: [ 7518]: --> [7:CONNECT]
Aug 27 18:41:14.61: [ 7518]: DELAY 400 ms
Aug 27 18:41:15.01: [ 7518]: SEND begin page
Aug 27 18:41:15.01: [ 7518]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled
Aug 27 18:41:15.01: [ 7518]: <-- data [1028]
Aug 27 18:41:15.01: [ 7518]: <-- data [1026]
Aug 27 18:41:15.01: [ 7518]: <-- data [1033]
Aug 27 18:41:15.01: [ 7518]: <-- data [1037]
Aug 27 18:41:15.01: [ 7518]: <-- data [1041]
Aug 27 18:41:15.22: [ 7518]: <-- data [1028]
Aug 27 18:41:15.22: [ 7518]: <-- data [1033]
Aug 27 18:41:15.70: [ 7518]: <-- data [1045]
Aug 27 18:41:16.82: [ 7518]: <-- data [1047]
Aug 27 18:41:18.05: [ 7518]: <-- data [1032]
Aug 27 18:41:19.17: [ 7518]: <-- data [1035]
Aug 27 18:41:20.27: [ 7518]: <-- data [1030]
Aug 27 18:41:21.48: [ 7518]: <-- data [1033]
Aug 27 18:41:22.60: [ 7518]: <-- data [1032]
Aug 27 18:41:23.67: [ 7518]: <-- data [1033]
Aug 27 18:41:24.87: [ 7518]: <-- data [1033]
Aug 27 18:41:25.99: [ 7518]: <-- data [1026]
Aug 27 18:41:27.10: [ 7518]: <-- data [1031]
Aug 27 18:41:28.18: [ 7518]: <-- data [1034]
Aug 27 18:41:29.28: [ 7518]: <-- data [1038]
Aug 27 18:41:30.53: [ 7518]: <-- data [1033]
Aug 27 18:41:31.65: [ 7518]: <-- data [1032]
Aug 27 18:41:32.77: [ 7518]: <-- data [831]
Aug 27 18:41:33.73: [ 7518]: SENT 23344 bytes of data
Aug 27 18:41:33.73: [ 7518]: SEND 2D RTC
Aug 27 18:41:33.73: [ 7518]: <-- data [30]
Aug 27 18:41:33.73: [ 7518]: <-- data [2]
Aug 27 18:41:33.73: [ 7518]: SEND end page
Aug 27 18:41:41.03: [ 7518]: --> [2:OK]
Aug 27 18:41:41.03: [ 7518]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled
Aug 27 18:41:41.03: [ 7518]: <-- [9:AT+FTS=9\r]
Aug 27 18:41:41.11: [ 7518]: --> [2:OK]
Aug 27 18:41:41.11: [ 7518]: SEND send EOP (no more pages or documents)
Aug 27 18:41:41.11: [ 7518]: <-- [9:AT+FTH=3\r]
Aug 27 18:41:41.97: [ 7518]: --> [7:CONNECT]
Aug 27 18:41:41.97: [ 7518]: <-- HDLC<3:FF C8 F4>
Aug 27 18:41:41.97: [ 7518]: <-- data [3]Aug 27 18:41:45.91: [ 7518]: 
Aug 27 18:41:41.97: [ 7518]: <-- data [2]
Aug 27 18:41:42.29: [ 7518]: --> [2:OK]
Aug 27 18:41:42.29: [ 7518]: <-- [9:AT+FRH=3\r]
Aug 27 18:41:42.39: [ 7518]: --> [7:CONNECT]
Aug 27 18:41:43.65: [ 7518]: --> HDLC<5:FF C8 31 45 8F>
Aug 27 18:41:43.65: [ 7518]: --> [2:OK]
Aug 27 18:41:43.65: [ 7518]: SEND recv MCF (message confirmation)
Aug 27 18:41:43.65: [ 7518]: SEND FAX (00000010): FROM michael@togawasmith.com TO 7961336 (page 1 of 1
 sent in 0:55)
Aug 27 18:41:43.65: [ 7518]: SEND FAX (00000010): FROM michael@togawasmith.com TO 7961336 (docq/doc60.
ps;71 sent in 0:55)
Aug 27 18:41:43.65: [ 7518]: <-- [9:AT+FTH=3\r]
Aug 27 18:41:44.51: [ 7518]: --> [7:CONNECT]
Aug 27 18:41:44.51: [ 7518]: <-- HDLC<3:FF C8 DF>
Aug 27 18:41:44.51: [ 7518]: <-- data [3]
Aug 27 18:41:44.51: [ 7518]: <-- data [2]
Aug 27 18:41:44.84: [ 7518]: --> [2:OK]
Aug 27 18:41:44.84: [ 7518]: MODEM input buffering enabled
Aug 27 18:41:44.84: [ 7518]: <-- [5:ATH0\r]
Aug 27 18:41:45.91: [ 7518]: --> [2:OK]
Aug 27 18:41:45.91: [ 7518]: MODEM set DTR OFF
Aug 27 18:41:45.91: [ 7518]: STATE CHANGE: SENDING -> MODEMWAIT (timeout 5)
Aug 27 18:41:45.91: [ 7518]: SESSION END
Aug 27 18:35:18.37: [ 7516]: SESSION BEGIN 00000009 16267961336
Aug 27 18:35:18.37: [ 7516]: HylaFAX (tm) Version 4.1.7
Aug 27 18:35:18.37: [ 7516]: SEND FAX: JOB 60 DEST 7961336 COMMID 00000009 DEVICE '/dev/ttyS4'
Aug 27 18:35:18.37: [ 7516]: MODEM set DTR OFF
Aug 27 18:35:18.37: [ 7516]: DELAY 75 ms
Aug 27 18:35:18.44: [ 7516]: MODEM set DTR ON
Aug 27 18:35:18.44: [ 7516]: DELAY 2600 ms
Aug 27 18:35:22.05: [ 7516]: MODEM set baud rate: 19200 baud, input flow XON/XOFF, output flow XON/XOF
F
Aug 27 18:35:22.05: [ 7516]: DELAY 10 ms
Aug 27 18:35:22.06: [ 7516]: MODEM flush i/o
Aug 27 18:35:22.06: [ 7516]: <-- [4:ATZ\r]
Aug 27 18:35:22.22: [ 7516]: --> [2:OK]
Aug 27 18:35:22.22: [ 7516]: DELAY 3000 ms
Aug 27 18:35:25.22: [ 7516]: <-- [5:ATE0\r]
Aug 27 18:35:25.23: [ 7516]: --> [4:ATE0]
Aug 27 18:35:25.23: [ 7516]: --> [2:OK]
Aug 27 18:35:25.23: [ 7516]: <-- [5:ATV1\r]
Aug 27 18:35:25.24: [ 7516]: --> [2:OK]
Aug 27 18:35:25.24: [ 7516]: <-- [5:ATQ0\r]
Aug 27 18:35:25.25: [ 7516]: --> [2:OK]
Aug 27 18:35:25.25: [ 7516]: <-- [7:ATS0=0\r]
Aug 27 18:35:25.26: [ 7516]: --> [2:OK]
Aug 27 18:35:25.26: [ 7516]: <-- [7:ATS8=2\r]
Aug 27 18:35:25.27: [ 7516]: --> [2:OK]
Aug 27 18:35:25.27: [ 7516]: <-- [8:ATS7=60\r]
Aug 27 18:35:25.28: [ 7516]: --> [2:OK]
Aug 27 18:35:25.28: [ 7516]: <-- [9:AT+FLO=1\r]
Aug 27 18:35:25.29: [ 7516]: --> [2:OK]
Aug 27 18:35:25.29: [ 7516]: <-- [12:AT+FCLASS=1\r]
Aug 27 18:35:25.30: [ 7516]: --> [2:OK]
Aug 27 18:35:25.30: [ 7516]: <-- [5:ATM0\r]
Aug 27 18:35:25.31: [ 7516]: --> [2:OK]
Aug 27 18:35:25.31: [ 7516]: STATE CHANGE: RUNNING -> SENDING
Aug 27 18:35:25.31: [ 7516]: MODEM input buffering enabled
Aug 27 18:35:25.31: [ 7516]: <-- [12:AT+FCLASS=1\r]
Aug 27 18:35:25.42: [ 7516]: --> [2:OK]
Aug 27 18:35:25.42: [ 7516]: MODEM set XON/XOFF/FLUSH: input ignored, output disabled
Aug 27 18:35:25.42: [ 7516]: DIAL 7961336
Aug 27 18:35:25.42: [ 7516]: <-- [12:ATDT7961336\r]
Aug 27 18:35:50.16: [ 7516]: --> [7:CONNECT]
Aug 27 18:35:50.16: [ 7516]: MODEM input buffering disabled
Aug 27 18:35:51.53: [ 7516]: --> HDLC<13:FF C0 04 B5 00 6C 05 00 00 00 00 1C 75>
Aug 27 18:35:51.53: [ 7516]: --> [2:OK]
Aug 27 18:35:51.53: [ 7516]: REMOTE NSF "AD 00 36 A0 00 00 00 00 38"
Aug 27 18:35:51.53: [ 7516]: NSF remote fax equipment: HP
Aug 27 18:35:51.53: [ 7516]: <-- [9:AT+FRH=3\r]
Aug 27 18:35:51.54: [ 7516]: --> [7:CONNECT]
Aug 27 18:35:52.23: [ 7516]: --> HDLC<25:FF C0 02 6C CC CC 8C 04 6C 9C EC 04 6C 4C 6C 04 04 04 04 04 0
4 04 04 AD A7>
Aug 27 18:35:52.24: [ 7516]: --> [2:OK]
Aug 27 18:35:52.24: [ 7516]: REMOTE CSI "626 796 1336"
Aug 27 18:35:52.24: [ 7516]: <-- [9:AT+FRH=3\r]
Aug 27 18:35:52.25: [ 7516]: --> [7:CONNECT]
Aug 27 18:35:52.67: [ 7516]: --> HDLC<15:FF C8 01 00 77 1F 23 01 49 01 01 19 00 3A A4>
Aug 27 18:35:52.67: [ 7516]: --> [2:OK]
Aug 27 18:35:52.67: [ 7516]: REMOTE best rate 14400 bit/s
Aug 27 18:35:52.67: [ 7516]: REMOTE max page width 1728 pixels in 215 mm
Aug 27 18:35:52.67: [ 7516]: REMOTE max unlimited page length
Aug 27 18:35:52.67: [ 7516]: REMOTE best vres 7.7 line/mm
Aug 27 18:35:52.67: [ 7516]: REMOTE best format 2-D MMR
Aug 27 18:35:52.67: [ 7516]: REMOTE supports T.30 Annex A, ECM
Aug 27 18:35:52.67: [ 7516]: REMOTE best 0 ms/scanline
Aug 27 18:35:52.67: [ 7516]: USE 14400 bit/s
Aug 27 18:35:52.67: [ 7516]: USE 0 ms/scanline
Aug 27 18:35:52.67: [ 7516]: SEND file "docq/doc60.ps;71"
Aug 27 18:35:52.67: [ 7516]: USE page width 1728 pixels in 215 mm
Aug 27 18:35:52.67: [ 7516]: USE unlimited page length
Aug 27 18:35:52.67: [ 7516]: USE 7.7 line/mm
Aug 27 18:35:52.67: [ 7516]: USE 2-D MR
Aug 27 18:35:52.67: [ 7516]: SEND training at v.17 14400 bit/s
Aug 27 18:35:52.67: [ 7516]: <-- [9:AT+FTH=3\r]
Aug 27 18:35:53.53: [ 7516]: --> [7:CONNECT]
Aug 27 18:35:53.53: [ 7516]: <-- HDLC<23:FF C0 C2 74 C6 76 92 04 34 16 2E 96 B6 CA 04 64 04 86 EE 86 E
6 F6 2A>
Aug 27 18:35:53.53: [ 7516]: <-- data [23]
Aug 27 18:35:53.53: [ 7516]: <-- data [2]
Aug 27 18:35:53.54: [ 7516]: --> [7:CONNECT]
Aug 27 18:35:53.54: [ 7516]: <-- HDLC<6:FF C8 C1 00 47 1E>
Aug 27 18:35:53.54: [ 7516]: <-- data [6]
Aug 27 18:35:53.54: [ 7516]: <-- data [2]
Aug 27 18:35:54.63: [ 7516]: --> [2:OK]
Aug 27 18:35:54.63: [ 7516]: <-- [9:AT+FTS=7\r]
Aug 27 18:35:54.69: [ 7516]: --> [2:OK]
Aug 27 18:35:54.69: [ 7516]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled
Aug 27 18:35:54.69: [ 7516]: <-- [11:AT+FTM=145\r]
Aug 27 18:35:54.70: [ 7516]: --> [7:CONNECT]
Aug 27 18:35:54.70: [ 7516]: DELAY 400 ms
Aug 27 18:35:55.10: [ 7516]: <-- data [1024]
Aug 27 18:35:55.10: [ 7516]: <-- data [1024]
Aug 27 18:35:55.10: [ 7516]: <-- data [652]
Aug 27 18:35:55.10: [ 7516]: <-- data [2]
Aug 27 18:35:57.83: [ 7516]: --> [2:OK]
Aug 27 18:35:57.83: [ 7516]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled
Aug 27 18:35:57.83: [ 7516]: <-- [9:AT+FRH=3\r]
Aug 27 18:35:57.95: [ 7516]: --> [7:CONNECT]
Aug 27 18:35:59.22: [ 7516]: --> HDLC<5:FF C8 22 67 DD>
Aug 27 18:35:59.22: [ 7516]: --> [2:OK]
Aug 27 18:35:59.22: [ 7516]: SEND training at v.17 12000 bit/s
Aug 27 18:35:59.22: [ 7516]: <-- [9:AT+FTH=3\r]
Aug 27 18:36:00.08: [ 7516]: --> [7:CONNECT]
Aug 27 18:36:00.08: [ 7516]: <-- HDLC<23:FF C0 C2 74 C6 76 92 04 34 16 2E 96 B6 CA 04 64 04 86 EE 86 E
6 F6 2A>
Aug 27 18:36:00.08: [ 7516]: <-- data [23]
Aug 27 18:36:00.08: [ 7516]: <-- data [2]
Aug 27 18:36:00.09: [ 7516]: --> [7:CONNECT]
Aug 27 18:36:00.09: [ 7516]: <-- HDLC<6:FF C8 C1 00 57 1E>
Aug 27 18:36:00.09: [ 7516]: <-- data [6]
Aug 27 18:36:00.09: [ 7516]: <-- data [2]
Aug 27 18:36:01.18: [ 7516]: --> [2:OK]
Aug 27 18:36:01.18: [ 7516]: <-- [9:AT+FTS=7\r]
Aug 27 18:36:01.24: [ 7516]: --> [2:OK]
Aug 27 18:36:01.24: [ 7516]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled
Aug 27 18:36:01.24: [ 7516]: <-- [11:AT+FTM=121\r]
Aug 27 18:36:01.25: [ 7516]: --> [7:CONNECT]
Aug 27 18:36:01.25: [ 7516]: DELAY 400 ms
Aug 27 18:36:01.65: [ 7516]: <-- data [1024]
Aug 27 18:36:01.65: [ 7516]: <-- data [1024]
Aug 27 18:36:01.65: [ 7516]: <-- data [202]
Aug 27 18:36:01.65: [ 7516]: <-- data [2]
Aug 27 18:36:04.39: [ 7516]: --> [2:OK]
Aug 27 18:36:04.39: [ 7516]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled
Aug 27 18:36:04.39: [ 7516]: <-- [9:AT+FRH=3\r]
Aug 27 18:36:04.51: [ 7516]: --> [7:CONNECT]
Aug 27 18:36:05.77: [ 7516]: --> HDLC<5:FF C8 22 67 DD>
Aug 27 18:36:05.77: [ 7516]: --> [2:OK]
Aug 27 18:36:05.77: [ 7516]: SEND training at v.17 9600 bit/s
Aug 27 18:36:05.77: [ 7516]: <-- [9:AT+FTH=3\r]
Aug 27 18:36:06.63: [ 7516]: --> [7:CONNECT]
Aug 27 18:36:06.63: [ 7516]: <-- HDLC<23:FF C0 C2 74 C6 76 92 04 34 16 2E 96 B6 CA 04 64 04 86 EE 86 E
6 F6 2A>
Aug 27 18:36:06.63: [ 7516]: <-- data [23]
Aug 27 18:36:06.63: [ 7516]: <-- data [2]
Aug 27 18:36:06.64: [ 7516]: --> [7:CONNECT]
Aug 27 18:36:06.64: [ 7516]: <-- HDLC<6:FF C8 C1 00 67 1E>
Aug 27 18:36:06.64: [ 7516]: <-- data [6]
Aug 27 18:36:06.64: [ 7516]: <-- data [2]
Aug 27 18:36:07.73: [ 7516]: --> [2:OK]
Aug 27 18:36:07.73: [ 7516]: <-- [9:AT+FTS=7\r]
Aug 27 18:36:07.79: [ 7516]: --> [2:OK]
Aug 27 18:36:07.79: [ 7516]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled
Aug 27 18:36:07.79: [ 7516]: <-- [10:AT+FTM=97\r]
Aug 27 18:36:07.80: [ 7516]: --> [7:CONNECT]
Aug 27 18:36:07.80: [ 7516]: DELAY 400 ms
Aug 27 18:36:08.20: [ 7516]: <-- data [1024]
Aug 27 18:36:08.20: [ 7516]: <-- data [776]
Aug 27 18:36:08.20: [ 7516]: <-- data [2]
Aug 27 18:36:10.94: [ 7516]: --> [2:OK]
Aug 27 18:36:10.94: [ 7516]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled
Aug 27 18:36:10.94: [ 7516]: <-- [9:AT+FRH=3\r]
Aug 27 18:36:11.06: [ 7516]: --> [7:CONNECT]
Aug 27 18:36:12.33: [ 7516]: --> HDLC<5:FF C8 22 67 DD>
Aug 27 18:36:12.33: [ 7516]: --> [2:OK]
Aug 27 18:36:12.33: [ 7516]: SEND training at v.17 7200 bit/s
Aug 27 18:36:12.33: [ 7516]: <-- [9:AT+FTH=3\r]
Aug 27 18:36:13.19: [ 7516]: --> [7:CONNECT]
Aug 27 18:36:13.19: [ 7516]: <-- HDLC<23:FF C0 C2 74 C6 76 92 04 34 16 2E 96 B6 CA 04 64 04 86 EE 86 E
6 F6 2A>
Aug 27 18:36:13.19: [ 7516]: <-- data [23]
Aug 27 18:36:13.19: [ 7516]: <-- data [2]
Aug 27 18:36:13.20: [ 7516]: --> [7:CONNECT]
Aug 27 18:36:13.20: [ 7516]: <-- HDLC<6:FF C8 C1 00 77 1E>
Aug 27 18:36:13.20: [ 7516]: <-- data [6]
Aug 27 18:36:13.20: [ 7516]: <-- data [2]
Aug 27 18:36:14.29: [ 7516]: --> [2:OK]
Aug 27 18:36:14.29: [ 7516]: <-- [9:AT+FTS=7\r]
Aug 27 18:36:14.35: [ 7516]: --> [2:OK]
Aug 27 18:36:14.35: [ 7516]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled
Aug 27 18:36:14.35: [ 7516]: <-- [10:AT+FTM=73\r]
Aug 27 18:36:14.36: [ 7516]: --> [7:CONNECT]
Aug 27 18:36:14.36: [ 7516]: DELAY 400 ms
Aug 27 18:36:14.76: [ 7516]: <-- data [1024]
Aug 27 18:36:14.76: [ 7516]: <-- data [326]
Aug 27 18:36:14.76: [ 7516]: <-- data [2]
Aug 27 18:36:17.51: [ 7516]: --> [2:OK]
Aug 27 18:36:17.51: [ 7516]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled
Aug 27 18:36:17.51: [ 7516]: <-- [9:AT+FRH=3\r]
Aug 27 18:36:17.63: [ 7516]: --> [7:CONNECT]
Aug 27 18:36:18.90: [ 7516]: --> HDLC<5:FF C8 21 57 BE>
Aug 27 18:36:18.90: [ 7516]: --> [2:OK]
Aug 27 18:36:18.90: [ 7516]: TRAINING succeeded
Aug 27 18:36:18.90: [ 7516]: DELAY 75 ms
Aug 27 18:36:18.98: [ 7516]: <-- [10:AT+FTM=74\r]
Aug 27 18:36:18.99: [ 7516]: --> [7:CONNECT]
Aug 27 18:36:18.99: [ 7516]: DELAY 400 ms
Aug 27 18:36:19.39: [ 7516]: SEND begin page
Aug 27 18:36:19.39: [ 7516]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled
Aug 27 18:36:19.39: [ 7516]: <-- data [1027]
Aug 27 18:36:19.39: [ 7516]: <-- data [1026]
Aug 27 18:36:19.39: [ 7516]: <-- data [1034]
Aug 27 18:36:19.39: [ 7516]: <-- data [1036]
Aug 27 18:36:19.39: [ 7516]: <-- data [1041]
Aug 27 18:36:19.60: [ 7516]: <-- data [1028]
Aug 27 18:36:19.60: [ 7516]: <-- data [1033]
Aug 27 18:36:20.08: [ 7516]: <-- data [1045]
Aug 27 18:36:21.20: [ 7516]: <-- data [1047]
Aug 27 18:36:22.48: [ 7516]: <-- data [1032]
Aug 27 18:36:23.58: [ 7516]: <-- data [1035]
Aug 27 18:36:24.69: [ 7516]: <-- data [1031]
Aug 27 18:36:25.81: [ 7516]: <-- data [1032]
Aug 27 18:36:26.91: [ 7516]: <-- data [1032]
Aug 27 18:36:28.17: [ 7516]: <-- data [1033]
Aug 27 18:36:29.25: [ 7516]: <-- data [1033]
Aug 27 18:36:30.37: [ 7516]: <-- data [1026]
Aug 27 18:36:31.46: [ 7516]: <-- data [1031]
Aug 27 18:36:32.58: [ 7516]: <-- data [1034]
Aug 27 18:36:33.84: [ 7516]: <-- data [1038]
Aug 27 18:36:34.96: [ 7516]: <-- data [1033]
Aug 27 18:36:36.04: [ 7516]: <-- data [1032]
Aug 27 18:36:37.15: [ 7516]: <-- data [815]
Aug 27 18:36:38.12: [ 7516]: SENT 23328 bytes of data
Aug 27 18:36:38.12: [ 7516]: SEND 2D RTC
Aug 27 18:36:38.12: [ 7516]: <-- data [30]
Aug 27 18:36:38.12: [ 7516]: <-- data [2]
Aug 27 18:36:38.12: [ 7516]: SEND end page
Aug 27 18:36:45.39: [ 7516]: --> [2:OK]
Aug 27 18:36:45.39: [ 7516]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled
Aug 27 18:36:45.39: [ 7516]: <-- [9:AT+FTS=9\r]
Aug 27 18:36:45.47: [ 7516]: --> [2:OK]
Aug 27 18:36:45.47: [ 7516]: SEND send EOP (no more pages or documents)
Aug 27 18:36:45.47: [ 7516]: <-- [9:AT+FTH=3\r]
Aug 27 18:36:46.33: [ 7516]: --> [7:CONNECT]
Aug 27 18:36:46.33: [ 7516]: <-- HDLC<3:FF C8 F4>
Aug 27 18:36:46.33: [ 7516]: <-- data [3]
Aug 27 18:36:46.33: [ 7516]: <-- data [2]
Aug 27 18:36:46.65: [ 7516]: --> [2:OK]
Aug 27 18:36:46.65: [ 7516]: <-- [9:AT+FRH=3\r]
Aug 27 18:36:46.75: [ 7516]: --> [7:CONNECT]
Aug 27 18:36:48.01: [ 7516]: --> HDLC<5:FF C8 32 75 EC>
Aug 27 18:36:48.01: [ 7516]: --> [2:OK]
Aug 27 18:36:48.01: [ 7516]: SEND recv RTN (retrain negative)
Aug 27 18:36:48.01: [ 7516]: SEND training at v.27ter 4800 bit/s
Aug 27 18:36:48.01: [ 7516]: <-- [9:AT+FTH=3\r]
Aug 27 18:36:48.87: [ 7516]: --> [7:CONNECT]
Aug 27 18:36:48.87: [ 7516]: <-- HDLC<23:FF C0 C2 74 C6 76 92 04 34 16 2E 96 B6 CA 04 64 04 86 EE 86 E
6 F6 2A>
Aug 27 18:36:48.87: [ 7516]: <-- data [23]
Aug 27 18:36:48.87: [ 7516]: <-- data [2]
Aug 27 18:36:48.88: [ 7516]: --> [7:CONNECT]
Aug 27 18:36:48.88: [ 7516]: <-- HDLC<6:FF C8 C1 00 53 1E>
Aug 27 18:36:48.88: [ 7516]: <-- data [6]
Aug 27 18:36:48.88: [ 7516]: <-- data [2]
Aug 27 18:36:49.97: [ 7516]: --> [2:OK]
Aug 27 18:36:49.97: [ 7516]: <-- [9:AT+FTS=7\r]
Aug 27 18:36:50.03: [ 7516]: --> [2:OK]
Aug 27 18:36:50.03: [ 7516]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled
Aug 27 18:36:50.03: [ 7516]: <-- [10:AT+FTM=48\r]
Aug 27 18:36:50.31: [ 7516]: --> [7:CONNECT]
Aug 27 18:36:50.31: [ 7516]: DELAY 400 ms
Aug 27 18:36:50.71: [ 7516]: <-- data [900]
Aug 27 18:36:50.71: [ 7516]: <-- data [2]
Aug 27 18:36:52.81: [ 7516]: --> [2:OK]
Aug 27 18:36:52.81: [ 7516]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled
Aug 27 18:36:52.81: [ 7516]: <-- [9:AT+FRH=3\r]
Aug 27 18:36:52.94: [ 7516]: --> [7:CONNECT]
Aug 27 18:36:54.21: [ 7516]: --> HDLC<5:FF C8 21 57 BE>
Aug 27 18:36:54.21: [ 7516]: --> [2:OK]
Aug 27 18:36:54.21: [ 7516]: TRAINING succeeded
Aug 27 18:36:54.21: [ 7516]: DELAY 75 ms
Aug 27 18:36:54.29: [ 7516]: <-- [10:AT+FTM=48\r]
Aug 27 18:36:54.30: [ 7516]: --> [7:CONNECT]
Aug 27 18:36:54.30: [ 7516]: DELAY 400 ms
Aug 27 18:36:54.70: [ 7516]: SEND begin page
Aug 27 18:36:54.70: [ 7516]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled
Aug 27 18:36:54.70: [ 7516]: <-- data [1027]
Aug 27 18:36:54.70: [ 7516]: <-- data [1026]
Aug 27 18:36:54.70: [ 7516]: <-- data [1034]
Aug 27 18:36:54.70: [ 7516]: <-- data [1036]
Aug 27 18:36:54.70: [ 7516]: <-- data [1041]
Aug 27 18:36:55.38: [ 7516]: <-- data [1028]
Aug 27 18:36:55.38: [ 7516]: <-- data [1033]
Aug 27 18:36:56.34: [ 7516]: <-- data [1045]
Aug 27 18:36:58.02: [ 7516]: <-- data [1047]
Aug 27 18:36:59.70: [ 7516]: <-- data [1032]
Aug 27 18:37:01.54: [ 7516]: <-- data [1035]
Aug 27 18:37:03.19: [ 7516]: <-- data [1031]
Aug 27 18:37:04.82: [ 7516]: <-- data [1032]
Aug 27 18:37:06.50: [ 7516]: <-- data [1032]
Aug 27 18:37:08.39: [ 7516]: <-- data [1033]
Aug 27 18:37:09.99: [ 7516]: <-- data [1033]
Aug 27 18:37:11.67: [ 7516]: <-- data [1026]
Aug 27 18:37:13.32: [ 7516]: <-- data [1031]
Aug 27 18:37:15.00: [ 7516]: <-- data [1034]
Aug 27 18:37:16.90: [ 7516]: <-- data [1038]
Aug 27 18:37:18.44: [ 7516]: <-- data [1033]
Aug 27 18:37:20.31: [ 7516]: <-- data [1032]
Aug 27 18:37:21.96: [ 7516]: <-- data [815]
Aug 27 18:37:23.16: [ 7516]: SENT 23328 bytes of data
Aug 27 18:37:23.16: [ 7516]: SEND 2D RTC
Aug 27 18:37:23.16: [ 7516]: <-- data [30]
Aug 27 18:37:23.40: [ 7516]: <-- data [2]
Aug 27 18:37:23.40: [ 7516]: SEND end page
Aug 27 18:37:34.22: [ 7516]: --> [2:OK]
Aug 27 18:37:34.22: [ 7516]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled
Aug 27 18:37:34.22: [ 7516]: <-- [9:AT+FTS=9\r]
Aug 27 18:37:34.30: [ 7516]: --> [2:OK]
Aug 27 18:37:34.30: [ 7516]: SEND send EOP (no more pages or documents)
Aug 27 18:37:34.30: [ 7516]: <-- [9:AT+FTH=3\r]
Aug 27 18:37:35.16: [ 7516]: --> [7:CONNECT]
Aug 27 18:37:35.16: [ 7516]: <-- HDLC<3:FF C8 F4>
Aug 27 18:37:35.16: [ 7516]: <-- data [3]
Aug 27 18:37:35.16: [ 7516]: <-- data [2]
Aug 27 18:37:35.48: [ 7516]: --> [2:OK]
Aug 27 18:37:35.48: [ 7516]: <-- [9:AT+FRH=3\r]
Aug 27 18:37:35.58: [ 7516]: --> [7:CONNECT]
Aug 27 18:37:36.85: [ 7516]: --> HDLC<5:FF C8 32 75 EC>
Aug 27 18:37:36.85: [ 7516]: --> [2:OK]
Aug 27 18:37:36.85: [ 7516]: SEND recv RTN (retrain negative)
Aug 27 18:37:36.85: [ 7516]: SEND training at v.27ter fallback mode 2400 bit/s
Aug 27 18:37:36.85: [ 7516]: <-- [9:AT+FTH=3\r]
Aug 27 18:37:37.71: [ 7516]: --> [7:CONNECT]
Aug 27 18:37:37.71: [ 7516]: <-- HDLC<23:FF C0 C2 74 C6 76 92 04 34 16 2E 96 B6 CA 04 64 04 86 EE 86 E
6 F6 2A>
Aug 27 18:37:37.71: [ 7516]: <-- data [23]
Aug 27 18:37:37.71: [ 7516]: <-- data [2]
Aug 27 18:37:37.72: [ 7516]: --> [7:CONNECT]
Aug 27 18:37:37.72: [ 7516]: <-- HDLC<6:FF C8 C1 00 43 1E>
Aug 27 18:37:37.72: [ 7516]: <-- data [6]
Aug 27 18:37:37.72: [ 7516]: <-- data [2]
Aug 27 18:37:38.81: [ 7516]: --> [2:OK]
Aug 27 18:37:38.81: [ 7516]: <-- [9:AT+FTS=7\r]
Aug 27 18:37:38.87: [ 7516]: --> [2:OK]
Aug 27 18:37:38.87: [ 7516]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled
Aug 27 18:37:38.87: [ 7516]: <-- [10:AT+FTM=24\r]
Aug 27 18:37:38.88: [ 7516]: --> [7:CONNECT]
Aug 27 18:37:38.88: [ 7516]: DELAY 400 ms
Aug 27 18:37:39.28: [ 7516]: <-- data [450]
Aug 27 18:37:39.28: [ 7516]: <-- data [2]
Aug 27 18:37:41.68: [ 7516]: --> [2:OK]
Aug 27 18:37:41.68: [ 7516]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled
Aug 27 18:37:41.68: [ 7516]: <-- [9:AT+FRH=3\r]
Aug 27 18:37:41.82: [ 7516]: --> [7:CONNECT]
Aug 27 18:37:43.09: [ 7516]: --> HDLC<5:FF C8 21 57 BE>
Aug 27 18:37:43.09: [ 7516]: --> [2:OK]
Aug 27 18:37:43.09: [ 7516]: TRAINING succeeded
Aug 27 18:37:43.09: [ 7516]: DELAY 75 ms
Aug 27 18:37:43.17: [ 7516]: <-- [10:AT+FTM=24\r]
Aug 27 18:37:43.18: [ 7516]: --> [7:CONNECT]
Aug 27 18:37:43.18: [ 7516]: DELAY 400 ms
Aug 27 18:37:43.58: [ 7516]: SEND begin page
Aug 27 18:37:43.58: [ 7516]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled
Aug 27 18:37:43.58: [ 7516]: <-- data [1027]
Aug 27 18:37:43.58: [ 7516]: <-- data [1026]
Aug 27 18:37:43.58: [ 7516]: <-- data [1034]
Aug 27 18:37:43.58: [ 7516]: <-- data [1036]
Aug 27 18:37:43.58: [ 7516]: <-- data [1041]
Aug 27 18:37:44.65: [ 7516]: <-- data [1028]
Aug 27 18:37:44.65: [ 7516]: <-- data [1033]
Aug 27 18:37:46.57: [ 7516]: <-- data [1045]
Aug 27 18:37:49.93: [ 7516]: <-- data [1047]
Aug 27 18:37:53.29: [ 7516]: <-- data [1032]
Aug 27 18:37:56.96: [ 7516]: <-- data [1035]
Aug 27 18:38:00.27: [ 7516]: <-- data [1031]
Aug 27 18:38:03.52: [ 7516]: <-- data [1032]
Aug 27 18:38:06.88: [ 7516]: <-- data [1032]
Aug 27 18:38:10.67: [ 7516]: <-- data [1033]
Aug 27 18:38:13.87: [ 7516]: <-- data [1033]
Aug 27 18:38:17.23: [ 7516]: <-- data [1026]
Aug 27 18:38:20.53: [ 7516]: <-- data [1031]
Aug 27 18:38:23.89: [ 7516]: <-- data [1034]
Aug 27 18:38:27.68: [ 7516]: <-- data [1038]
Aug 27 18:38:30.77: [ 7516]: <-- data [1033]
Aug 27 18:38:34.50: [ 7516]: <-- data [1032]
Aug 27 18:38:37.81: [ 7516]: <-- data [815]
Aug 27 18:38:40.21: [ 7516]: SENT 23328 bytes of data
Aug 27 18:38:40.21: [ 7516]: SEND 2D RTC
Aug 27 18:38:40.21: [ 7516]: <-- data [30]
Aug 27 18:38:40.69: [ 7516]: <-- data [2]
Aug 27 18:38:40.69: [ 7516]: SEND end page
Aug 27 18:39:02.32: [ 7516]: --> [2:OK]
Aug 27 18:39:02.32: [ 7516]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled
Aug 27 18:39:02.32: [ 7516]: <-- [9:AT+FTS=9\r]
Aug 27 18:39:02.40: [ 7516]: --> [2:OK]
Aug 27 18:39:02.40: [ 7516]: SEND send EOP (no more pages or documents)
Aug 27 18:39:02.40: [ 7516]: <-- [9:AT+FTH=3\r]
Aug 27 18:39:03.26: [ 7516]: --> [7:CONNECT]
Aug 27 18:39:03.26: [ 7516]: <-- HDLC<3:FF C8 F4>
Aug 27 18:39:03.26: [ 7516]: <-- data [3]
Aug 27 18:39:03.26: [ 7516]: <-- data [2]
Aug 27 18:39:03.58: [ 7516]: --> [2:OK]
Aug 27 18:39:03.58: [ 7516]: <-- [9:AT+FRH=3\r]
Aug 27 18:39:03.69: [ 7516]: --> [7:CONNECT]
Aug 27 18:39:04.95: [ 7516]: --> HDLC<5:FF C8 32 75 EC>
Aug 27 18:39:04.95: [ 7516]: --> [2:OK]
Aug 27 18:39:04.95: [ 7516]: SEND recv RTN (retrain negative)
Aug 27 18:39:04.95: [ 7516]: <-- [9:AT+FTH=3\r]
Aug 27 18:39:05.81: [ 7516]: --> [7:CONNECT]
Aug 27 18:39:05.81: [ 7516]: <-- HDLC<3:FF C8 DF>
Aug 27 18:39:05.81: [ 7516]: <-- data [3]
Aug 27 18:39:05.81: [ 7516]: <-- data [2]
Aug 27 18:39:06.13: [ 7516]: --> [2:OK]
Aug 27 18:39:06.13: [ 7516]: MODEM input buffering enabled
Aug 27 18:39:06.13: [ 7516]: <-- [5:ATH0\r]
Aug 27 18:39:07.20: [ 7516]: --> [2:OK]
Aug 27 18:39:07.20: [ 7516]: MODEM set DTR OFF
Aug 27 18:39:07.20: [ 7516]: STATE CHANGE: SENDING -> MODEMWAIT (timeout 5)
Aug 27 18:39:07.20: [ 7516]: SESSION END