Re: [hylafax-users] Questions after 4.4.4 upgrade - Was -> Destination user reports receiving incomplete faxes

["Stott Hare" <share@xxxxxxxxxxxx>]

Thanks for looking at these Darren.  I’veincluded the two logs for the same failed job.  The first was with our original config having SessionTracing = 11, the second was the job resent after changing SessionTracing = 0xFFF.

-Stott

 

11:

Nov 05 22:34:36.68: [ 5106]: SESSION BEGIN 000001533 1207724xxxyyyy

Nov 05 22:34:36.68: [ 5106]: HylaFAX (tm) Version 4.4.4

Nov 05 22:34:36.68: [ 5106]: SEND FAX: JOB 1385 DEST 724xxxyyyy COMMID 000001533 DEVICE '/dev/ttyS4' FROM 'BlueTarp Financial, Inc. <faxadmin@xxxxxxxxxxxx>' USER faxuser

Nov 05 22:34:36.68: [ 5106]: <-- [12:AT+FCLASS=1\r]

Nov 05 22:34:36.71: [ 5106]: --> [2:OK]

Nov 05 22:34:36.71: [ 5106]: DIAL 724xxxyyyy

Nov 05 22:34:36.71: [ 5106]: <-- [16:ATDT1724xxxyyyy\r]

Nov 05 22:34:53.60: [ 5106]: --> [7:CONNECT]

Nov 05 22:34:54.61: [ 5106]: --> [5:ERROR]

Nov 05 22:34:54.61: [ 5106]: MODEM Command error

Nov 05 22:34:54.61: [ 5106]: FCS error

Nov 05 22:34:54.61: [ 5106]: <-- [10:AT+FRS=21\r]

Nov 05 22:34:56.34: [ 5106]: --> [2:OK]

Nov 05 22:34:56.34: [ 5106]: <-- [9:AT+FTH=3\r]

Nov 05 22:34:57.22: [ 5106]: --> [7:CONNECT]

Nov 05 22:34:57.22: [ 5106]: <-- data [3]

Nov 05 22:34:57.22: [ 5106]: <-- data [2]

Nov 05 22:34:57.56: [ 5106]: --> [2:OK]

Nov 05 22:34:57.56: [ 5106]: SEND send CRP (command repeat)

Nov 05 22:34:57.56: [ 5106]: <-- [9:AT+FRH=3\r]

Nov 05 22:34:57.78: [ 5106]: --> [7:CONNECT]

Nov 05 22:34:59.28: [ 5106]: --> [2:OK]

Nov 05 22:34:59.28: [ 5106]: REMOTE NSF "00 00 56 55 55 00 88 90 80 5F 00"

Nov 05 22:34:59.28: [ 5106]: NSF remote fax equipment: Brother Fax-560/770

Nov 05 22:34:59.28: [ 5106]: <-- [9:AT+FRH=3\r]

Nov 05 22:34:59.30: [ 5106]: --> [7:CONNECT]

Nov 05 22:34:59.98: [ 5106]: --> [2:OK]

Nov 05 22:34:59.98: [ 5106]: REMOTE CSI "724xxxyyyy"

Nov 05 22:34:59.98: [ 5106]: <-- [9:AT+FRH=3\r]

Nov 05 22:35:00.00: [ 5106]: --> [7:CONNECT]

Nov 05 22:35:00.30: [ 5106]: --> [2:OK]

Nov 05 22:35:00.30: [ 5106]: REMOTE best rate 9600 bit/s

Nov 05 22:35:00.31: [ 5106]: REMOTE max A4 page width (215 mm)

Nov 05 22:35:00.31: [ 5106]: REMOTE max unlimited page length

Nov 05 22:35:00.31: [ 5106]: REMOTE best vres 15.4 line/mm

Nov 05 22:35:00.31: [ 5106]: REMOTE format support: MH

Nov 05 22:35:00.31: [ 5106]: REMOTE supports T.30 Annex A, 256-byte ECM

Nov 05 22:35:00.31: [ 5106]: REMOTE best 20 ms, 10 ms/scanline

Nov 05 22:35:00.31: [ 5106]: USE 9600 bit/s

Nov 05 22:35:00.31: [ 5106]: USE error correction mode

Nov 05 22:35:00.31: [ 5106]: SEND file "docq/doc1919.ps;30"

Nov 05 22:35:00.31: [ 5106]: USE A4 page width (215 mm)

Nov 05 22:35:00.31: [ 5106]: USE unlimited page length

Nov 05 22:35:00.31: [ 5106]: USE 3.85 line/mm

Nov 05 22:35:00.31: [ 5106]: USE 1-D MH

Nov 05 22:35:00.31: [ 5106]: USE 0 ms/scanline

Nov 05 22:35:00.31: [ 5106]: SEND training at v.29 9600 bit/s

Nov 05 22:35:00.31: [ 5106]: <-- [9:AT+FRS=7\r]

Nov 05 22:35:00.41: [ 5106]: --> [2:OK]

Nov 05 22:35:00.41: [ 5106]: <-- [9:AT+FTH=3\r]

Nov 05 22:35:01.29: [ 5106]: --> [7:CONNECT]

Nov 05 22:35:01.29: [ 5106]: <-- data [23]

Nov 05 22:35:01.29: [ 5106]: <-- data [2]

Nov 05 22:35:01.32: [ 5106]: --> [7:CONNECT]

Nov 05 22:35:01.32: [ 5106]: <-- data [7]

Nov 05 22:35:01.32: [ 5106]: <-- data [2]

Nov 05 22:35:02.45: [ 5106]: --> [2:OK]

Nov 05 22:35:02.45: [ 5106]: <-- [9:AT+FTS=7\r]

Nov 05 22:35:02.53: [ 5106]: --> [2:OK]

Nov 05 22:35:02.53: [ 5106]: <-- [10:AT+FTM=96\r]

Nov 05 22:35:02.56: [ 5106]: --> [7:CONNECT]

Nov 05 22:35:02.56: [ 5106]: DELAY 400 ms

Nov 05 22:35:02.96: [ 5106]: <-- data [1024]

Nov 05 22:35:02.96: [ 5106]: <-- data [776]

Nov 05 22:35:02.96: [ 5106]: <-- data [2]

Nov 05 22:35:04.52: [ 5106]: --> [2:OK]

Nov 05 22:35:04.52: [ 5106]: <-- [9:AT+FRH=3\r]

Nov 05 22:35:04.76: [ 5106]: --> [7:CONNECT]

Nov 05 22:35:05.91: [ 5106]: --> [2:OK]

Nov 05 22:35:05.91: [ 5106]: SEND training at v.29 7200 bit/s

Nov 05 22:35:05.91: [ 5106]: <-- [9:AT+FRS=7\r]

Nov 05 22:35:06.01: [ 5106]: --> [2:OK]

Nov 05 22:35:06.01: [ 5106]: <-- [9:AT+FTH=3\r]

Nov 05 22:35:06.88: [ 5106]: --> [7:CONNECT]

Nov 05 22:35:06.88: [ 5106]: <-- data [23]

Nov 05 22:35:06.88: [ 5106]: <-- data [2]

Nov 05 22:35:06.91: [ 5106]: --> [7:CONNECT]

Nov 05 22:35:06.91: [ 5106]: <-- data [7]

Nov 05 22:35:06.91: [ 5106]: <-- data [2]

Nov 05 22:35:08.05: [ 5106]: --> [2:OK]

Nov 05 22:35:08.05: [ 5106]: <-- [9:AT+FTS=7\r]

Nov 05 22:35:08.12: [ 5106]: --> [2:OK]

Nov 05 22:35:08.12: [ 5106]: <-- [10:AT+FTM=72\r]

Nov 05 22:35:08.15: [ 5106]: --> [7:CONNECT]

Nov 05 22:35:08.15: [ 5106]: DELAY 400 ms

Nov 05 22:35:08.55: [ 5106]: <-- data [1024]

Nov 05 22:35:08.55: [ 5106]: <-- data [326]

Nov 05 22:35:08.55: [ 5106]: <-- data [2]

Nov 05 22:35:10.11: [ 5106]: --> [2:OK]

Nov 05 22:35:10.11: [ 5106]: <-- [9:AT+FRH=3\r]

Nov 05 22:35:10.34: [ 5106]: --> [7:CONNECT]

Nov 05 22:35:11.52: [ 5106]: --> [2:OK]

Nov 05 22:35:11.52: [ 5106]: SEND training at v.27ter 4800 bit/s

Nov 05 22:35:11.52: [ 5106]: <-- [9:AT+FRS=7\r]

Nov 05 22:35:11.62: [ 5106]: --> [2:OK]

Nov 05 22:35:11.62: [ 5106]: <-- [9:AT+FTH=3\r]

Nov 05 22:35:12.50: [ 5106]: --> [7:CONNECT]

Nov 05 22:35:12.50: [ 5106]: <-- data [23]

Nov 05 22:35:12.50: [ 5106]: <-- data [2]

Nov 05 22:35:12.53: [ 5106]: --> [7:CONNECT]

Nov 05 22:35:12.53: [ 5106]: <-- data [7]

Nov 05 22:35:12.53: [ 5106]: <-- data [2]

Nov 05 22:35:13.65: [ 5106]: --> [2:OK]

Nov 05 22:35:13.65: [ 5106]: <-- [9:AT+FTS=7\r]

Nov 05 22:35:13.73: [ 5106]: --> [2:OK]

Nov 05 22:35:13.73: [ 5106]: <-- [10:AT+FTM=48\r]

Nov 05 22:35:13.76: [ 5106]: --> [7:CONNECT]

Nov 05 22:35:13.76: [ 5106]: DELAY 400 ms

Nov 05 22:35:14.16: [ 5106]: <-- data [900]

Nov 05 22:35:14.16: [ 5106]: <-- data [2]

Nov 05 22:35:16.25: [ 5106]: --> [2:OK]

Nov 05 22:35:16.25: [ 5106]: <-- [9:AT+FRH=3\r]

Nov 05 22:35:16.63: [ 5106]: --> [7:CONNECT]

Nov 05 22:35:17.77: [ 5106]: --> [2:OK]

Nov 05 22:35:17.77: [ 5106]: TRAINING succeeded

Nov 05 22:35:17.77: [ 5106]: SEND begin page

Nov 05 22:35:17.77: [ 5106]: SEND 1D RTC

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 0

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 1

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 2

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 3

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 4

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 5

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 6

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 7

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 8

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 9

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 10

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 11

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 12

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 13

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 14

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 15

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 16

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 17

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 18

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 19

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 20

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 21

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 22

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 23

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 24

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 25

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 26

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 27

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 28

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 29

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 30

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 31

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 32

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 33

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 34

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 35

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 36

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 37

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 38

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 39

Nov 05 22:35:17.77: [ 5106]: SEND send frame number 40

Nov 05 22:35:17.77: [ 5106]: <-- [9:AT+FRS=7\r]

Nov 05 22:35:17.87: [ 5106]: --> [2:OK]

Nov 05 22:35:17.87: [ 5106]: <-- [10:AT+FTM=48\r]

Nov 05 22:35:17.90: [ 5106]: --> [7:CONNECT]

Nov 05 22:35:17.90: [ 5106]: DELAY 400 ms

Nov 05 22:35:18.30: [ 5106]: <-- data [1028]

Nov 05 22:35:18.30: [ 5106]: <-- data [1029]

Nov 05 22:35:18.30: [ 5106]: <-- data [1025]

Nov 05 22:35:18.30: [ 5106]: <-- data [1026]

Nov 05 22:35:18.30: [ 5106]: <-- data [1027]

Nov 05 22:35:20.29: [ 5106]: <-- data [1027]

Nov 05 22:35:20.29: [ 5106]: <-- data [1028]

Nov 05 22:35:20.29: [ 5106]: <-- data [1026]

Nov 05 22:35:21.57: [ 5106]: <-- data [1025]

Nov 05 22:35:23.17: [ 5106]: <-- data [1024]

Nov 05 22:37:23.19: [ 5106]: MODEM WRITE SHORT: sent 1024, wrote 966

Nov 05 22:37:23.19: [ 5106]: <-- data [2]

Nov 05 22:37:23.19: [ 5106]: --> [5:ERROR]

Nov 05 22:37:23.19: [ 5106]: SEND end page

Nov 05 22:37:23.19: [ 5106]: Unspecified Transmit Phase C error

Nov 05 22:37:23.19: [ 5106]: <-- [9:AT+FRS=7\r]

Nov 05 22:37:53.20: [ 5106]: MODEM <Timeout>

Nov 05 22:37:53.20: [ 5106]: Failure to receive silence (synchronization failure).

Nov 05 22:37:53.20: [ 5106]: <-- data [1]

Nov 05 22:37:53.43: [ 5106]: MODEM <Timeout>

Nov 05 22:37:53.43: [ 5106]: <-- [9:AT+FTH=3\r]

Nov 05 22:38:01.00: [ 5106]: --> [0:]

Nov 05 22:38:01.00: [ 5106]: SEND FAILED: JOB 1385 DEST 724xxxyyyy ERR [334] Unspecified Transmit Phase C error; too many attempts to send

Nov 05 22:38:02.01: [ 5106]: <-- [5:ATH0\r]

Nov 05 22:38:07.02: [ 5106]: MODEM <Timeout>

Nov 05 22:38:37.02: [ 5106]: SESSION END

 

 

0xFFF:

Nov 06 10:01:58.05: [ 2258]: SESSION BEGIN 000001568 1207724xxxyyyy

Nov 06 10:01:58.05: [ 2258]: HylaFAX (tm) Version 4.4.4

Nov 06 10:01:58.05: [ 2258]: SEND FAX: JOB 1418 DEST 724xxxyyyy COMMID 000001568 DEVICE '/dev/ttyS4' FROM 'BlueTarp Financial, Inc. <faxadmin@xxxxxxxxxxxx>' USER faxuser

Nov 06 10:01:58.05: [ 2258]: STATE CHANGE: RUNNING -> SENDING

Nov 06 10:01:58.05: [ 2258]: <-- [12:AT+FCLASS=1\r]

Nov 06 10:01:58.07: [ 2258]: --> [2:OK]

Nov 06 10:01:58.07: [ 2258]: DIAL 724xxxyyyy

Nov 06 10:01:58.07: [ 2258]: <-- [16:ATDT1724xxxyyyy\r]

Nov 06 10:02:13.85: [ 2258]: --> [7:CONNECT]

Nov 06 10:02:15.34: [ 2258]: --> HDLC<16:FF C0 04 00 00 6A AA AA 00 11 09 01 AA 00 A6 35>

Nov 06 10:02:15.34: [ 2258]: --> [2:OK]

Nov 06 10:02:15.34: [ 2258]: REMOTE NSF "00 00 56 55 55 00 88 90 80 55 00"

Nov 06 10:02:15.34: [ 2258]: NSF remote fax equipment: Brother Fax-560/770

Nov 06 10:02:15.34: [ 2258]: <-- [9:AT+FRH=3\r]

Nov 06 10:02:15.36: [ 2258]: --> [7:CONNECT]

Nov 06 10:02:16.05: [ 2258]: --> HDLC<25:FF C0 02 9C 2C 9C 9C 4C 6C 9C 2C 4C EC 04 04 04 04 04 04 04 04 04 04 37 B6>

Nov 06 10:02:16.05: [ 2258]: --> [2:OK]

Nov 06 10:02:16.05: [ 2258]: REMOTE CSI "724xxxyyyy"

Nov 06 10:02:16.05: [ 2258]: <-- [9:AT+FRH=3\r]

Nov 06 10:02:16.07: [ 2258]: --> [7:CONNECT]

Nov 06 10:02:16.37: [ 2258]: --> HDLC<11:FF C8 01 00 72 1D 21 01 88 2F 39>

Nov 06 10:02:16.37: [ 2258]: --> [2:OK]

Nov 06 10:02:16.37: [ 2258]: REMOTE best rate 9600 bit/s

Nov 06 10:02:16.37: [ 2258]: REMOTE max A4 page width (215 mm)

Nov 06 10:02:16.37: [ 2258]: REMOTE max unlimited page length

Nov 06 10:02:16.37: [ 2258]: REMOTE best vres 15.4 line/mm

Nov 06 10:02:16.37: [ 2258]: REMOTE format support: MH

Nov 06 10:02:16.37: [ 2258]: REMOTE supports T.30 Annex A, 256-byte ECM

Nov 06 10:02:16.37: [ 2258]: REMOTE best 20 ms, 10 ms/scanline

Nov 06 10:02:16.37: [ 2258]: USE 9600 bit/s

Nov 06 10:02:16.37: [ 2258]: USE error correction mode

Nov 06 10:02:16.37: [ 2258]: SEND file "docq/doc1958.ps;30"

Nov 06 10:02:16.37: [ 2258]: USE A4 page width (215 mm)

Nov 06 10:02:16.37: [ 2258]: USE unlimited page length

Nov 06 10:02:16.37: [ 2258]: USE 3.85 line/mm

Nov 06 10:02:16.37: [ 2258]: USE 1-D MH

Nov 06 10:02:16.37: [ 2258]: USE 0 ms/scanline

Nov 06 10:02:16.37: [ 2258]: SEND training at v.29 9600 bit/s

Nov 06 10:02:16.37: [ 2258]: <-- [9:AT+FRS=7\r]

Nov 06 10:02:16.47: [ 2258]: --> [2:OK]

Nov 06 10:02:16.47: [ 2258]: <-- [9:AT+FTH=3\r]

Nov 06 10:02:17.34: [ 2258]: --> [7:CONNECT]

Nov 06 10:02:17.34: [ 2258]: <-- HDLC<23:FF C0 C2 0E AE 2E A6 CA E6 76 96 16 2E F6 72 04 04 04 04 04 04 04 04>

Nov 06 10:02:17.34: [ 2258]: <-- data [23]

Nov 06 10:02:17.34: [ 2258]: <-- data [2]

Nov 06 10:02:17.37: [ 2258]: --> [7:CONNECT]

Nov 06 10:02:17.37: [ 2258]: <-- HDLC<7:FF C8 C1 00 60 1F 20>

Nov 06 10:02:17.37: [ 2258]: <-- data [7]

Nov 06 10:02:17.37: [ 2258]: <-- data [2]

Nov 06 10:02:18.50: [ 2258]: --> [2:OK]

Nov 06 10:02:18.50: [ 2258]: <-- [9:AT+FTS=7\r]

Nov 06 10:02:18.57: [ 2258]: --> [2:OK]

Nov 06 10:02:18.57: [ 2258]: <-- [10:AT+FTM=96\r]

Nov 06 10:02:18.59: [ 2258]: --> [7:CONNECT]

Nov 06 10:02:18.59: [ 2258]: DELAY 400 ms

Nov 06 10:02:18.99: [ 2258]: <-- data [1024]

Nov 06 10:02:18.99: [ 2258]: <-- data [776]

Nov 06 10:02:18.99: [ 2258]: <-- data [2]

Nov 06 10:02:20.54: [ 2258]: --> [2:OK]

Nov 06 10:02:20.54: [ 2258]: <-- [9:AT+FRH=3\r]

Nov 06 10:02:20.77: [ 2258]: --> [7:CONNECT]

Nov 06 10:02:21.92: [ 2258]: --> HDLC<5:FF C8 22 67 DD>

Nov 06 10:02:21.92: [ 2258]: --> [2:OK]

Nov 06 10:02:21.92: [ 2258]: SEND training at v.29 7200 bit/s

Nov 06 10:02:21.92: [ 2258]: <-- [9:AT+FRS=7\r]

Nov 06 10:02:22.02: [ 2258]: --> [2:OK]

Nov 06 10:02:22.02: [ 2258]: <-- [9:AT+FTH=3\r]

Nov 06 10:02:22.89: [ 2258]: --> [7:CONNECT]

Nov 06 10:02:22.89: [ 2258]: <-- HDLC<23:FF C0 C2 0E AE 2E A6 CA E6 76 96 16 2E F6 72 04 04 04 04 04 04 04 04>

Nov 06 10:02:22.89: [ 2258]: <-- data [23]

Nov 06 10:02:22.89: [ 2258]: <-- data [2]

Nov 06 10:02:22.92: [ 2258]: --> [7:CONNECT]

Nov 06 10:02:22.92: [ 2258]: <-- HDLC<7:FF C8 C1 00 70 1F 20>

Nov 06 10:02:22.92: [ 2258]: <-- data [7]

Nov 06 10:02:22.92: [ 2258]: <-- data [2]

Nov 06 10:02:24.05: [ 2258]: --> [2:OK]

Nov 06 10:02:24.05: [ 2258]: <-- [9:AT+FTS=7\r]

Nov 06 10:02:24.12: [ 2258]: --> [2:OK]

Nov 06 10:02:24.12: [ 2258]: <-- [10:AT+FTM=72\r]

Nov 06 10:02:24.14: [ 2258]: --> [7:CONNECT]

Nov 06 10:02:24.14: [ 2258]: DELAY 400 ms

Nov 06 10:02:24.54: [ 2258]: <-- data [1024]

Nov 06 10:02:24.54: [ 2258]: <-- data [326]

Nov 06 10:02:24.54: [ 2258]: <-- data [2]

Nov 06 10:02:26.10: [ 2258]: --> [2:OK]

Nov 06 10:02:26.10: [ 2258]: <-- [9:AT+FRH=3\r]

Nov 06 10:02:26.30: [ 2258]: --> [7:CONNECT]

Nov 06 10:02:27.47: [ 2258]: --> HDLC<5:FF C8 22 67 DD>

Nov 06 10:02:27.47: [ 2258]: --> [2:OK]

Nov 06 10:02:27.47: [ 2258]: SEND training at v.27ter 4800 bit/s

Nov 06 10:02:27.47: [ 2258]: <-- [9:AT+FRS=7\r]

Nov 06 10:02:27.57: [ 2258]: --> [2:OK]

Nov 06 10:02:27.57: [ 2258]: <-- [9:AT+FTH=3\r]

Nov 06 10:02:28.44: [ 2258]: --> [7:CONNECT]

Nov 06 10:02:28.44: [ 2258]: <-- HDLC<23:FF C0 C2 0E AE 2E A6 CA E6 76 96 16 2E F6 72 04 04 04 04 04 04 04 04>

Nov 06 10:02:28.44: [ 2258]: <-- data [23]

Nov 06 10:02:28.44: [ 2258]: <-- data [2]

Nov 06 10:02:28.47: [ 2258]: --> [7:CONNECT]

Nov 06 10:02:28.47: [ 2258]: <-- HDLC<7:FF C8 C1 00 50 1F 20>

Nov 06 10:02:28.47: [ 2258]: <-- data [7]

Nov 06 10:02:28.47: [ 2258]: <-- data [2]

Nov 06 10:02:29.60: [ 2258]: --> [2:OK]

Nov 06 10:02:29.60: [ 2258]: <-- [9:AT+FTS=7\r]

Nov 06 10:02:29.67: [ 2258]: --> [2:OK]

Nov 06 10:02:29.67: [ 2258]: <-- [10:AT+FTM=48\r]

Nov 06 10:02:29.69: [ 2258]: --> [7:CONNECT]

Nov 06 10:02:29.69: [ 2258]: DELAY 400 ms

Nov 06 10:02:30.09: [ 2258]: <-- data [900]

Nov 06 10:02:30.09: [ 2258]: <-- data [2]

Nov 06 10:02:32.19: [ 2258]: --> [2:OK]

Nov 06 10:02:32.19: [ 2258]: <-- [9:AT+FRH=3\r]

Nov 06 10:02:32.56: [ 2258]: --> [7:CONNECT]

Nov 06 10:02:33.70: [ 2258]: --> HDLC<5:FF C8 21 57 BE>

Nov 06 10:02:33.70: [ 2258]: --> [2:OK]

Nov 06 10:02:33.70: [ 2258]: TRAINING succeeded

Nov 06 10:02:33.70: [ 2258]: SEND begin page

Nov 06 10:02:33.70: [ 2258]: SEND 1D RTC

Nov 06 10:02:33.70: [ 2258]: SEND send frame number 0

Nov 06 10:02:33.70: [ 2258]: SEND send frame number 1

Nov 06 10:02:33.70: [ 2258]: SEND send frame number 2

Nov 06 10:02:33.70: [ 2258]: SEND send frame number 3

Nov 06 10:02:33.70: [ 2258]: SEND send frame number 4

Nov 06 10:02:33.70: [ 2258]: SEND send frame number 5

Nov 06 10:02:33.70: [ 2258]: SEND send frame number 6

Nov 06 10:02:33.70: [ 2258]: SEND send frame number 7

Nov 06 10:02:33.70: [ 2258]: SEND send frame number 8

Nov 06 10:02:33.70: [ 2258]: SEND send frame number 9

Nov 06 10:02:33.70: [ 2258]: SEND send frame number 10

Nov 06 10:02:33.70: [ 2258]: SEND send frame number 11

Nov 06 10:02:33.70: [ 2258]: SEND send frame number 12

Nov 06 10:02:33.70: [ 2258]: SEND send frame number 13

Nov 06 10:02:33.70: [ 2258]: SEND send frame number 14

Nov 06 10:02:33.70: [ 2258]: SEND send frame number 15

Nov 06 10:02:33.70: [ 2258]: SEND send frame number 16

Nov 06 10:02:33.70: [ 2258]: SEND send frame number 17

Nov 06 10:02:33.70: [ 2258]: SEND send frame number 18

Nov 06 10:02:33.70: [ 2258]: SEND send frame number 19

Nov 06 10:02:33.70: [ 2258]: SEND send frame number 20

Nov 06 10:02:33.70: [ 2258]: SEND send frame number 21

Nov 06 10:02:33.70: [ 2258]: SEND send frame number 22

Nov 06 10:02:33.70: [ 2258]: SEND send frame number 23

Nov 06 10:02:33.70: [ 2258]: SEND send frame number 24

Nov 06 10:02:33.70: [ 2258]: SEND send frame number 25

Nov 06 10:02:33.71: [ 2258]: SEND send frame number 26

Nov 06 10:02:33.71: [ 2258]: SEND send frame number 27

Nov 06 10:02:33.71: [ 2258]: SEND send frame number 28

Nov 06 10:02:33.71: [ 2258]: SEND send frame number 29

Nov 06 10:02:33.71: [ 2258]: SEND send frame number 30

Nov 06 10:02:33.71: [ 2258]: SEND send frame number 31

Nov 06 10:02:33.71: [ 2258]: SEND send frame number 32

Nov 06 10:02:33.71: [ 2258]: SEND send frame number 33

Nov 06 10:02:33.71: [ 2258]: SEND send frame number 34

Nov 06 10:02:33.71: [ 2258]: SEND send frame number 35

Nov 06 10:02:33.71: [ 2258]: SEND send frame number 36

Nov 06 10:02:33.71: [ 2258]: SEND send frame number 37

Nov 06 10:02:33.71: [ 2258]: SEND send frame number 38

Nov 06 10:02:33.71: [ 2258]: SEND send frame number 39

Nov 06 10:02:33.71: [ 2258]: SEND send frame number 40

Nov 06 10:02:33.71: [ 2258]: <-- [9:AT+FRS=7\r]

Nov 06 10:02:33.80: [ 2258]: --> [2:OK]

Nov 06 10:02:33.80: [ 2258]: <-- [10:AT+FTM=48\r]

Nov 06 10:02:33.82: [ 2258]: --> [7:CONNECT]

Nov 06 10:02:33.82: [ 2258]: DELAY 400 ms

Nov 06 10:02:34.22: [ 2258]: <-- data [1027]

Nov 06 10:02:34.22: [ 2258]: <-- data [1029]

Nov 06 10:02:34.22: [ 2258]: <-- data [1031]

Nov 06 10:02:34.22: [ 2258]: <-- data [1025]

Nov 06 10:02:36.20: [ 2258]: <-- data [1024]

Nov 06 10:02:36.20: [ 2258]: <-- data [1027]

Nov 06 10:02:36.20: [ 2258]: <-- data [1026]

Nov 06 10:02:36.20: [ 2258]: <-- data [1026]

Nov 06 10:02:37.48: [ 2258]: <-- data [1025]

Nov 06 10:02:39.37: [ 2258]: <-- data [1024]

Nov 06 10:02:40.97: [ 2258]: <-- data [782]

Nov 06 10:02:42.25: [ 2258]: <-- data [2]

Nov 06 10:02:57.13: [ 2258]: --> [5:ERROR]

Nov 06 10:02:57.13: [ 2258]: SEND end page

Nov 06 10:02:57.13: [ 2258]: Unspecified Transmit Phase C error

Nov 06 10:02:57.13: [ 2258]: <-- [9:AT+FRS=7\r]

Nov 06 10:03:27.14: [ 2258]: MODEM TIMEOUT: reading line from modem

Nov 06 10:03:27.14: [ 2258]: MODEM <Timeout>

Nov 06 10:03:27.14: [ 2258]: Failure to receive silence (synchronization failure).

Nov 06 10:03:27.14: [ 2258]: <-- data [1]

Nov 06 10:03:27.36: [ 2258]: MODEM TIMEOUT: reading line from modem

Nov 06 10:03:27.36: [ 2258]: MODEM <Timeout>

Nov 06 10:03:27.36: [ 2258]: <-- [9:AT+FTH=3\r]

Nov 06 10:03:34.94: [ 2258]: --> [0:]

Nov 06 10:03:34.94: [ 2258]: MODEM TIMEOUT: sending HDLC frame

Nov 06 10:03:34.94: [ 2258]: MODEM input buffering enabled

Nov 06 10:03:34.94: [ 2258]: SEND FAILED: JOB 1418 DEST 724xxxyyyy ERR [334] Unspecified Transmit Phase C error; too many attempts to send

Nov 06 10:03:35.95: [ 2258]: <-- [5:ATH0\r]

Nov 06 10:03:40.96: [ 2258]: MODEM TIMEOUT: reading line from modem

Nov 06 10:03:40.96: [ 2258]: MODEM <Timeout>

Nov 06 10:03:40.96: [ 2258]: MODEM set DTR OFF

Nov 06 10:04:10.95: [ 2258]: STATE CHANGE: SENDING -> MODEMWAIT (timeout 5)

Nov 06 10:04:10.95: [ 2258]: SESSION END

 

---

From: Darren Nickerson [mailto:darren.nickerson@xxxxxxxx]
Sent: Wednesday, November 05, 2008 5:09 PM
To: Stott Hare
Cc: HylaFAX Users Mailing List
Subject: Re: [hylafax-users] Questions after 4.4.4 upgrade - Was -> Destination user reports receiving incomplete faxes

 

Well that's definitely not the desired outcome.

 

Please include a session log file for one (or more) of these failures, ideally with SessionTracing elevated to 0xFFF or higher.

 

-Darren

 

 

On Nov 5, 2008, at 4:18 PM, Stott Hare wrote:

So we upgraded to 4.4.4 in an effort to resolve a small subset of our recipients getting incomplete faxes.  Post upgrade, I’ve been able to get most of the settings tweaked back towards what our processes were expecting output wise, but still have some more work to be done there, notably the following two finesse items:

• failed faxes notifications used to have an attached pdf of the failed fax as <destfaxnumber>.pdf, now it is <docNNN>.pdf)
• files created in /var/spool/hylafax/info have the fax number file name pre-pended with 1207  (207 being our area code)

 

However, since the upgrade we have had significantly more failures than before.  Used to be <10 failed faxes a week, now we’re seeing 20+ a night.  My assumption is these new errors will be tracked back to either a configuration setting, or possibly modem firmware etc that isn’t as happy with Hylafax 4.4.4.  For reference, we are running Ubuntu Server 6.06 (1gig ram, 2 xeon 3.4GHz) and a Multi-Tech MT5656ZDX-V.  We purchased that modem specifically as it was one of the ones on the recommended hardware list.

 

The most common failure messages are:

• Unspecified Transmit Phase C error; too many attempts to dial
• DIS/DTC received 3 times; DCS not recognized; too many attempts to dial
• Failure to train remote modem at 2400 bps or minimum speed; too many attempts to send
• RSPREC error/got DCN (sender abort); too many attempts to send
• COMREC error in transmit Phase B/got DCN; too many attempts to send

 

I’ve been reading the archives for past mention of these reasons, but haven’t made much headway and am open to suggestions on things to check or change.  If it would help for me to attach any config files, let me know which and I will follow up with that information.

 

• Unspecified Transmit Phase C error; too many attempts to dial

I examined the /var/spool/hylafax/info file for one of the numbers that had this error but found “hasV17Trouble:no”

supportsVRes:3

supports2DEncoding:no

supportsMMR:no

hasV34Trouble:no

hasV17Trouble:no

supportsPostScript:no

supportsBatching:yes

calledBefore:yes

maxPageWidth:1728

maxPageLength:65535

maxSignallingRate:"9600"

minScanlineTime:"20ms/10ms"

remoteCSI:"724xxxyyyy"

remoteNSF:"00 00 56 55 55 00 88 90 80 5F 00"

remoteDIS:"00 72 1D 21 01 88"

sendFailures:2

lastSendFailure:"Unspecified Transmit Phase C error"

dialFailures:0

lastDialFailure:"No carrier detected"

pagingProtocol:"ixo"

 

• DIS/DTC received 3 times; DCS not recognized; too many attempts to dial

Previous mention of this seems to point to bad phone lines (our hadn’t exhibited issue pre-upgrade and have been tested fine within past year), or possibly a bug requiring upgrade (which we just did).  Hoping there is a config setting we can tweak here.

 

Thanks in advance!

-Stott Hare

BlueTarp Financial

 

---

From: Darren Nickerson [mailto:darren.nickerson@xxxxxxxx] 
Sent: Tuesday, August 12, 2008 2:26 PM
To: Stott Hare
Cc: HylaFAX Users Mailing List
Subject: Re: [hylafax-users] Destination user reports receiving incomplete faxes

 

There are 4.4.4 packages available for most OSes these days, but there's nothing wrong with a sysadmin who fears change ;-)

 

To disable ECM, try adding '-E' to your sendfax commandline. Here's the relevant excerpt from the sendfax UNIX manual page:

 

       -E          Disable use of the Error Correction Mode (ECM) when  trans-

                   mitting page data. Setting ECM to false will limit the max-

                   imum speed to 14400 bps.  By default HylaFAX  will  attempt

                   to  use  ECM  when both the trasmitting modem and receiving

                   device support it.  Note that international calls are some-

                   times  faster  when ECM is disabled.  Beware also that some

                   modems and/or facsimile devices do not  properly  implement

                   ECM causing transmission failures.

 

-Darren

 

 

On Aug 12, 2008, at 2:19 PM, Stott Hare wrote:

Thanks Darren.  I’ll see if I can nudge the systems admin to upgrade our Hylafax server to 4.4.4.  I know last time I mentioned it he grumbled about having to do a special build or some such.

 

How would I configure Hylafax to disable Error Correction for a specific destination fax only?  This isn’t something I’m familiar with, and initial web searches didn’t reveal the answer.

 

Thanks again!

-Stott

 

---

From: Darren Nickerson [mailto:darren.nickerson@xxxxxxxx] 
Sent: Tuesday, August 12, 2008 1:38 PM
To: Stott Hare
Cc: HylaFAX Users Mailing List
Subject: Re: [hylafax-users] Destination user reports receiving incomplete faxes

 

Stott,

 

Your logs do indicate that  ECM is coming into play here to retransmit frame number 13, so you do have a little signal degradation going on, but this is not uncommon and should in itself be causing a problem. It's possible with such an old version of HylaFAX that you're hitting a bug that's fixed in the current 4.4.4 release, so I recommend upgrading if that's an option for you. If the problem persists in 4.4.4 there's a lot more elaborate test procedure you could do to help pin this down.

 

If the problem is on their side, you might be able to work around it by disabling ECM when sending to this destination.

 

-Darren

 

On Aug 12, 2008, at 1:29 PM, Stott Hare wrote:

Hello folks,

 

I’m hoping someone can point me in the right direction in my search for a solution.  I have a particular fax destination that indicates the faxes they are receiving (which were sent from our Hylafax server) are incomplete or cut off mid page.  I’m not receiving errors indicating there was a failure, but the logs indicate we’ve been getting partial page signals.  Is this indicative of a noisy line, a bad modem configuration or a problematic destination fax machine?  Any good methods for narrowing this down and resolving?

 

Thanks in advance!

-Stott

 

From xferfaxlog:

08/11/08 21:46       SEND   000025660     ttyS4  25995  ""     faxadmin@xxxxxxxxxxxx       "207784****"       "207784****"  2220072       1      0:41   0:25   ""     ""     ""     ""     "faxuser"     "00 44 1F 22"

 

From the specific jobs log file:

Aug 11 21:46:10.57: [ 8233]: SESSION BEGIN 000025660 1207207784****

Aug 11 21:46:10.57: [ 8233]: HylaFAX (tm) Version 4.2.5

Aug 11 21:46:10.57: [ 8233]: SEND FAX: JOB 25995 DEST 207784**** COMMID 000025660 DEVICE '/dev/ttyS4' FROM 'BlueTarp Financial, Inc. <faxadmin@xxxxxxxxxxxx>' USER faxuser

Aug 11 21:46:10.57: [ 8233]: <-- [12:AT+FCLASS=1\r]

Aug 11 21:46:10.60: [ 8233]: --> [2:OK]

Aug 11 21:46:10.60: [ 8233]: DIAL 207784****

Aug 11 21:46:10.60: [ 8233]: <-- [16:ATDT1207784****\r]

Aug 11 21:46:26.49: [ 8233]: --> [7:CONNECT]

Aug 11 21:46:27.95: [ 8233]: --> [2:OK]

Aug 11 21:46:27.95: [ 8233]: REMOTE NSF "00 00 56 55 55 00 8C 90 80 EF 00"

Aug 11 21:46:27.95: [ 8233]: NSF remote fax equipment: Brother MFC-3100C/MFC-8600

Aug 11 21:46:27.95: [ 8233]: <-- [9:AT+FRH=3\r]

Aug 11 21:46:27.98: [ 8233]: --> [7:CONNECT]

Aug 11 21:46:28.66: [ 8233]: --> [2:OK]

Aug 11 21:46:28.66: [ 8233]: REMOTE CSI "207784****"

Aug 11 21:46:28.66: [ 8233]: <-- [9:AT+FRH=3\r]

Aug 11 21:46:28.69: [ 8233]: --> [7:CONNECT]

Aug 11 21:46:28.98: [ 8233]: --> [2:OK]

Aug 11 21:46:28.98: [ 8233]: REMOTE best rate 14400 bit/s

Aug 11 21:46:28.98: [ 8233]: REMOTE max A4 page width (215 mm)

Aug 11 21:46:28.98: [ 8233]: REMOTE max unlimited page length

Aug 11 21:46:28.98: [ 8233]: REMOTE best vres 15.4 line/mm

Aug 11 21:46:28.98: [ 8233]: REMOTE format support: MH, MR, MMR

Aug 11 21:46:28.98: [ 8233]: REMOTE supports T.30 Annex A, 256-byte ECM

Aug 11 21:46:28.98: [ 8233]: REMOTE best 10 ms/scanline

Aug 11 21:46:28.98: [ 8233]: USE 14400 bit/s

Aug 11 21:46:28.98: [ 8233]: USE error correction mode

Aug 11 21:46:28.98: [ 8233]: SEND file "docq/doc34110.ps;f0"

Aug 11 21:46:28.98: [ 8233]: USE A4 page width (215 mm)

Aug 11 21:46:28.98: [ 8233]: USE unlimited page length

Aug 11 21:46:28.98: [ 8233]: USE 3.85 line/mm

Aug 11 21:46:28.98: [ 8233]: USE 2-D MMR

Aug 11 21:46:28.98: [ 8233]: USE 0 ms/scanline

Aug 11 21:46:28.98: [ 8233]: SEND training at v.17 14400 bit/s

Aug 11 21:46:28.98: [ 8233]: <-- [9:AT+FRS=7\r]

Aug 11 21:46:29.13: [ 8233]: --> [2:OK]

Aug 11 21:46:29.13: [ 8233]: <-- [9:AT+FTH=3\r]

Aug 11 21:46:30.01: [ 8233]: --> [7:CONNECT]

Aug 11 21:46:30.01: [ 8233]: <-- data [23]

Aug 11 21:46:30.01: [ 8233]: <-- data [2]

Aug 11 21:46:30.04: [ 8233]: --> [7:CONNECT]

Aug 11 21:46:30.04: [ 8233]: <-- data [7]

Aug 11 21:46:30.04: [ 8233]: <-- data [2]

Aug 11 21:46:31.17: [ 8233]: --> [2:OK]

Aug 11 21:46:31.17: [ 8233]: <-- [9:AT+FTS=7\r]

Aug 11 21:46:31.25: [ 8233]: --> [2:OK]

Aug 11 21:46:31.25: [ 8233]: <-- [11:AT+FTM=145\r]

Aug 11 21:46:31.28: [ 8233]: --> [7:CONNECT]

Aug 11 21:46:31.28: [ 8233]: DELAY 400 ms

Aug 11 21:46:31.68: [ 8233]: <-- data [1024]

Aug 11 21:46:31.68: [ 8233]: <-- data [1024]

Aug 11 21:46:31.68: [ 8233]: <-- data [652]

Aug 11 21:46:31.68: [ 8233]: <-- data [2]

Aug 11 21:46:34.41: [ 8233]: --> [2:OK]

Aug 11 21:46:34.41: [ 8233]: <-- [9:AT+FRH=3\r]

Aug 11 21:46:34.70: [ 8233]: --> [7:CONNECT]

Aug 11 21:46:35.87: [ 8233]: --> [2:OK]

Aug 11 21:46:35.87: [ 8233]: TRAINING succeeded

Aug 11 21:46:35.87: [ 8233]: SEND begin page

Aug 11 21:46:35.88: [ 8233]: SEND EOFB

Aug 11 21:46:35.88: [ 8233]: SEND send frame number 0

Aug 11 21:46:35.88: [ 8233]: SEND send frame number 1

Aug 11 21:46:35.88: [ 8233]: SEND send frame number 2

Aug 11 21:46:35.88: [ 8233]: SEND send frame number 3

Aug 11 21:46:35.88: [ 8233]: SEND send frame number 4

Aug 11 21:46:35.88: [ 8233]: SEND send frame number 5

Aug 11 21:46:35.88: [ 8233]: SEND send frame number 6

Aug 11 21:46:35.88: [ 8233]: SEND send frame number 7

Aug 11 21:46:35.88: [ 8233]: SEND send frame number 8

Aug 11 21:46:35.88: [ 8233]: SEND send frame number 9

Aug 11 21:46:35.88: [ 8233]: SEND send frame number 10

Aug 11 21:46:35.88: [ 8233]: SEND send frame number 11

Aug 11 21:46:35.88: [ 8233]: SEND send frame number 12

Aug 11 21:46:35.88: [ 8233]: SEND send frame number 13

Aug 11 21:46:35.88: [ 8233]: SEND send frame number 14

Aug 11 21:46:35.88: [ 8233]: SEND send frame number 15

Aug 11 21:46:35.88: [ 8233]: SEND send frame number 16

Aug 11 21:46:35.88: [ 8233]: SEND send frame number 17

Aug 11 21:46:35.88: [ 8233]: SEND send frame number 18

Aug 11 21:46:35.88: [ 8233]: SEND send frame number 19

Aug 11 21:46:35.88: [ 8233]: SEND send frame number 20

Aug 11 21:46:35.88: [ 8233]: SEND send frame number 21

Aug 11 21:46:35.88: [ 8233]: SEND send frame number 22

Aug 11 21:46:35.88: [ 8233]: SEND send frame number 23

Aug 11 21:46:35.88: [ 8233]: DELAY 200 ms

Aug 11 21:46:36.08: [ 8233]: <-- [11:AT+FTM=146\r]

Aug 11 21:46:36.11: [ 8233]: --> [7:CONNECT]

Aug 11 21:46:36.11: [ 8233]: DELAY 400 ms

Aug 11 21:46:36.51: [ 8233]: <-- data [1038]

Aug 11 21:46:36.51: [ 8233]: <-- data [1045]

Aug 11 21:46:36.51: [ 8233]: <-- data [1043]

Aug 11 21:46:36.51: [ 8233]: <-- data [1038]

Aug 11 21:46:38.51: [ 8233]: <-- data [1043]

Aug 11 21:46:38.51: [ 8233]: <-- data [1039]

Aug 11 21:46:38.51: [ 8233]: <-- data [667]

Aug 11 21:46:38.51: [ 8233]: <-- data [2]

Aug 11 21:46:40.34: [ 8233]: --> [2:OK]

Aug 11 21:46:40.34: [ 8233]: <-- [9:AT+FTS=9\r]

Aug 11 21:46:40.44: [ 8233]: --> [2:OK]

Aug 11 21:46:40.44: [ 8233]: <-- [9:AT+FTH=3\r]

Aug 11 21:46:41.32: [ 8233]: --> [7:CONNECT]

Aug 11 21:46:41.32: [ 8233]: <-- data [7]

Aug 11 21:46:41.32: [ 8233]: <-- data [2]

Aug 11 21:46:41.78: [ 8233]: --> [2:OK]

Aug 11 21:46:41.78: [ 8233]: SEND send PPS (partial page signal)

Aug 11 21:46:41.78: [ 8233]: SEND send EOP (no more pages or documents)

Aug 11 21:46:41.78: [ 8233]: <-- [9:AT+FRH=3\r]

Aug 11 21:46:41.94: [ 8233]: --> [7:CONNECT]

Aug 11 21:46:44.13: [ 8233]: --> [2:OK]

Aug 11 21:46:44.13: [ 8233]: SEND recv PPR (partial page request)

Aug 11 21:46:44.13: [ 8233]: <-- [9:AT+FRS=7\r]

Aug 11 21:46:44.26: [ 8233]: --> [2:OK]

Aug 11 21:46:44.26: [ 8233]: SEND send frame number 13

Aug 11 21:46:44.26: [ 8233]: SEND send frame number 13

Aug 11 21:46:44.26: [ 8233]: DELAY 200 ms

Aug 11 21:46:44.47: [ 8233]: <-- [11:AT+FTM=146\r]

Aug 11 21:46:44.50: [ 8233]: --> [7:CONNECT]

Aug 11 21:46:44.50: [ 8233]: DELAY 400 ms

Aug 11 21:46:44.90: [ 8233]: <-- data [921]

Aug 11 21:46:44.90: [ 8233]: <-- data [2]

Aug 11 21:46:45.45: [ 8233]: --> [2:OK]

Aug 11 21:46:45.45: [ 8233]: <-- [9:AT+FTS=9\r]

Aug 11 21:46:45.55: [ 8233]: --> [2:OK]

Aug 11 21:46:45.55: [ 8233]: <-- [9:AT+FTH=3\r]

Aug 11 21:46:46.42: [ 8233]: --> [7:CONNECT]

Aug 11 21:46:46.42: [ 8233]: <-- data [7]

Aug 11 21:46:46.42: [ 8233]: <-- data [2]

Aug 11 21:46:46.85: [ 8233]: --> [2:OK]

Aug 11 21:46:46.85: [ 8233]: SEND send PPS (partial page signal)

Aug 11 21:46:46.85: [ 8233]: SEND send EOP (no more pages or documents)

Aug 11 21:46:46.85: [ 8233]: <-- [9:AT+FRH=3\r]

Aug 11 21:46:47.03: [ 8233]: --> [7:CONNECT]

Aug 11 21:46:48.20: [ 8233]: --> [2:OK]

Aug 11 21:46:48.20: [ 8233]: SEND recv MCF (message confirmation)

Aug 11 21:46:48.20: [ 8233]: <-- [9:AT+FRS=7\r]

Aug 11 21:46:48.35: [ 8233]: --> [2:OK]

Aug 11 21:46:48.35: [ 8233]: SEND end page

Aug 11 21:46:48.35: [ 8233]: SEND FAX (000025660): FROM faxadmin@xxxxxxxxxxxx TO 207784**** (page 1 of 1 sent in 0:20)

Aug 11 21:46:48.36: [ 8233]: SEND FAX (000025660): FROM faxadmin@xxxxxxxxxxxx TO 207784**** (docq/doc34110.ps;f0 sent in 0:20)

Aug 11 21:46:49.37: [ 8233]: <-- [9:AT+FTH=3\r]

Aug 11 21:46:50.23: [ 8233]: --> [7:CONNECT]

Aug 11 21:46:50.23: [ 8233]: <-- data [3]

Aug 11 21:46:50.23: [ 8233]: <-- data [2]

Aug 11 21:46:50.55: [ 8233]: --> [2:OK]

Aug 11 21:46:50.55: [ 8233]: <-- [5:ATH0\r]

Aug 11 21:46:51.73: [ 8233]: --> [2:OK]

Aug 11 21:46:51.73: [ 8233]: SESSION END