Re: [hylafax-users] FW: Hylafax sending issue

["Chris Duncan" <hylafax@xxxxxxxxx>]

I have switched applications to hylafaxFSP and now get. I have also added
below a working fax from the command line, anyone have any ideas I'm really
stumped. Thanks


(root)>cat c000000036
Jul 06 16:07:57.48: [26063]: SESSION BEGIN 000000036 16148714848
Jul 06 16:07:57.48: [26063]: HylaFAX (tm) Version 4.3.0
Jul 06 16:07:57.48: [26063]: SEND FAX: JOB 14 DEST 16148714848 COMMID
000000036 DEVICE '/dev/ttyIAX2' FROM 'Andy Mentges <andy@xxxxxxxxxxx>' USER
andy
Jul 06 16:07:57.48: [26063]: STATE CHANGE: RUNNING -> SENDING
Jul 06 16:07:57.48: [26063]: <-- [12:AT+FCLASS=1\r]
Jul 06 16:07:57.48: [26063]: --> [2:OK]
Jul 06 16:07:57.48: [26063]: MODEM set XON/XOFF/FLUSH: input ignored, output
disabled
Jul 06 16:07:57.48: [26063]: DIAL 16148714848
Jul 06 16:07:57.48: [26063]: <-- [16:ATDT16148714848\r]
Jul 06 16:08:09.01: [26063]: --> [7:CONNECT]
Jul 06 16:08:09.63: [26063]: --> [10:NO CARRIER]
Jul 06 16:08:09.63: [26063]: MODEM No carrier
Jul 06 16:08:09.63: [26063]: <-- [9:AT+FRS=7\r]
Jul 06 16:08:09.64: [26063]: --> [2:OK]
Jul 06 16:08:09.64: [26063]: <-- [9:AT+FTH=3\r]
Jul 06 16:08:09.66: [26063]: --> [7:CONNECT]
Jul 06 16:08:09.66: [26063]: <-- HDLC<3:FF C8 D8>
Jul 06 16:08:09.66: [26063]: <-- data [3]
Jul 06 16:08:09.66: [26063]: <-- data [2]
Jul 06 16:08:11.14: [26063]: --> [2:OK]
Jul 06 16:08:11.14: [26063]: SEND send CRP (command repeat)
Jul 06 16:08:11.14: [26063]: <-- [9:AT+FRH=3\r]
Jul 06 16:08:15.01: [26063]: --> [7:CONNECT]
Jul 06 16:08:16.27: [26063]: --> HDLC<13:FF C0 04 B5 00 6C 05 00 00 00 00 1C
75>
Jul 06 16:08:16.27: [26063]: --> [2:OK]
Jul 06 16:08:16.27: [26063]: REMOTE NSF "AD 00 36 A0 00 00 00 00"
Jul 06 16:08:16.27: [26063]: NSF remote fax equipment: HP 
Jul 06 16:08:16.27: [26063]: <-- [9:AT+FRH=3\r]
Jul 06 16:08:16.97: [26063]: --> [7:CONNECT]
Jul 06 16:08:16.97: [26063]: --> HDLC<25:FF C0 02 1C 2C 1C 2C 04 8C EC 1C 04
2C 8C 6C 04 04 04 04 04 04 04 04 29 92>
Jul 06 16:08:16.97: [26063]: --> [2:OK]
Jul 06 16:08:16.97: [26063]: REMOTE CSI "614 871 4848"
Jul 06 16:08:16.97: [26063]: <-- [9:AT+FRH=3\r]
Jul 06 16:08:17.39: [26063]: --> [7:CONNECT]
Jul 06 16:08:17.39: [26063]: --> HDLC<15:FF C8 01 04 77 1F 23 01 49 01 01 19
00 97 91>
Jul 06 16:08:17.51: [26063]: --> [2:OK]
Jul 06 16:08:17.51: [26063]: REMOTE best rate 33600 bit/s
Jul 06 16:08:17.51: [26063]: REMOTE max A4 page width (215 mm)
Jul 06 16:08:17.51: [26063]: REMOTE max unlimited page length
Jul 06 16:08:17.51: [26063]: REMOTE best vres 300 x 300 dpi
Jul 06 16:08:17.51: [26063]: REMOTE format support: MH, MR, MMR, JPEG
Full-Color
Jul 06 16:08:17.51: [26063]: REMOTE supports T.30 Annex A, 256-byte ECM
Jul 06 16:08:17.51: [26063]: REMOTE best 0 ms/scanline
Jul 06 16:08:17.51: [26063]: USE 14400 bit/s
Jul 06 16:08:17.51: [26063]: USE error correction mode
Jul 06 16:08:17.51: [26063]: SEND file "docq/doc19.tif;c1"
Jul 06 16:08:17.51: [26063]: USE A4 page width (215 mm)
Jul 06 16:08:17.51: [26063]: USE unlimited page length
Jul 06 16:08:17.51: [26063]: USE 7.7 line/mm
Jul 06 16:08:17.51: [26063]: USE 2-D MMR
Jul 06 16:08:17.51: [26063]: USE 0 ms/scanline
Jul 06 16:08:17.51: [26063]: SEND training at v.17 14400 bit/s
Jul 06 16:08:17.51: [26063]: <-- [9:AT+FRS=7\r]
Jul 06 16:08:17.57: [26063]: --> [2:OK]
Jul 06 16:08:17.57: [26063]: <-- [9:AT+FTH=3\r]
Jul 06 16:08:17.58: [26063]: --> [7:CONNECT]
Jul 06 16:08:17.58: [26063]: <-- HDLC<23:FF C0 C2 B6 A6 26 F6 B6 1A 82 92 04
04 04 04 04 04 04 04 04 04 04 04>
Jul 06 16:08:17.58: [26063]: <-- data [23]
Jul 06 16:08:17.58: [26063]: <-- data [2]
Jul 06 16:08:19.60: [26063]: --> [7:CONNECT]
Jul 06 16:08:19.60: [26063]: <-- HDLC<7:FF C8 C1 00 46 1F 22>
Jul 06 16:08:19.60: [26063]: <-- data [7]
Jul 06 16:08:19.60: [26063]: <-- data [2]
Jul 06 16:08:20.00: [26063]: --> [2:OK]
Jul 06 16:08:20.00: [26063]: <-- [9:AT+FTS=7\r]
Jul 06 16:08:20.08: [26063]: --> [2:OK]
Jul 06 16:08:20.08: [26063]: MODEM set XON/XOFF/FLUSH: input interpreted,
output disabled
Jul 06 16:08:20.08: [26063]: <-- [11:AT+FTM=145\r]
Jul 06 16:08:20.09: [26063]: --> [7:CONNECT]
Jul 06 16:08:20.09: [26063]: DELAY 400 ms
Jul 06 16:08:20.49: [26063]: <-- data [1024]
Jul 06 16:08:20.49: [26063]: <-- data [1024]
Jul 06 16:08:20.49: [26063]: <-- data [652]
Jul 06 16:08:20.49: [26063]: <-- data [2]
Jul 06 16:08:23.04: [26063]: --> [2:OK]
Jul 06 16:08:23.04: [26063]: MODEM set XON/XOFF/DRAIN: input ignored, output
disabled
Jul 06 16:08:23.04: [26063]: <-- [9:AT+FRH=3\r]
Jul 06 16:08:26.15: [26063]: --> [0:]
Jul 06 16:08:26.15: [26063]: MODEM <Empty line>
Jul 06 16:08:26.15: [26063]: MODEM TIMEOUT: waiting for v.21 carrier
Jul 06 16:08:26.15: [26063]: <-- data [1]
Jul 06 16:08:26.16: [26063]: --> [2:OK]
Jul 06 16:08:26.16: [26063]: DELAY 1500 ms
Jul 06 16:08:27.66: [26063]: SEND training at v.17 12000 bit/s
Jul 06 16:08:27.66: [26063]: <-- [9:AT+FRS=7\r]
Jul 06 16:08:27.67: [26063]: --> [2:OK]
Jul 06 16:08:27.67: [26063]: <-- [9:AT+FTH=3\r]
Jul 06 16:08:27.68: [26063]: --> [7:CONNECT]
Jul 06 16:08:27.68: [26063]: <-- HDLC<23:FF C0 C2 B6 A6 26 F6 B6 1A 82 92 04
04 04 04 04 04 04 04 04 04 04 04>
Jul 06 16:08:27.68: [26063]: <-- data [23]
Jul 06 16:08:27.68: [26063]: <-- data [2]
Jul 06 16:08:29.68: [26063]: --> [7:CONNECT]
Jul 06 16:08:29.68: [26063]: <-- HDLC<7:FF C8 C1 00 56 1F 22>
Jul 06 16:08:29.68: [26063]: <-- data [7]
Jul 06 16:08:29.68: [26063]: <-- data [2]
Jul 06 16:08:30.08: [26063]: --> [2:OK]
Jul 06 16:08:30.08: [26063]: <-- [9:AT+FTS=7\r]
Jul 06 16:08:30.16: [26063]: --> [2:OK]
Jul 06 16:08:30.16: [26063]: MODEM set XON/XOFF/FLUSH: input interpreted,
output disabled
Jul 06 16:08:30.16: [26063]: <-- [11:AT+FTM=121\r]
Jul 06 16:08:30.17: [26063]: --> [7:CONNECT]
Jul 06 16:08:30.17: [26063]: DELAY 400 ms
Jul 06 16:08:30.57: [26063]: <-- data [1024]
Jul 06 16:08:30.57: [26063]: <-- data [1024]
Jul 06 16:08:30.57: [26063]: <-- data [202]
Jul 06 16:08:30.57: [26063]: <-- data [2]
Jul 06 16:08:33.12: [26063]: --> [2:OK]
Jul 06 16:08:33.12: [26063]: MODEM set XON/XOFF/DRAIN: input ignored, output
disabled
Jul 06 16:08:33.12: [26063]: <-- [9:AT+FRH=3\r]
Jul 06 16:08:36.23: [26063]: --> [0:]
Jul 06 16:08:36.23: [26063]: MODEM <Empty line>
Jul 06 16:08:36.23: [26063]: MODEM TIMEOUT: waiting for v.21 carrier
Jul 06 16:08:36.23: [26063]: <-- data [1]
Jul 06 16:08:36.24: [26063]: --> [2:OK]
Jul 06 16:08:36.24: [26063]: DELAY 1500 ms
Jul 06 16:08:37.74: [26063]: SEND training at v.17 9600 bit/s
Jul 06 16:08:37.74: [26063]: <-- [9:AT+FRS=7\r]
Jul 06 16:08:37.75: [26063]: --> [2:OK]
Jul 06 16:08:37.75: [26063]: <-- [9:AT+FTH=3\r]
Jul 06 16:08:37.76: [26063]: --> [7:CONNECT]
Jul 06 16:08:37.76: [26063]: <-- HDLC<23:FF C0 C2 B6 A6 26 F6 B6 1A 82 92 04
04 04 04 04 04 04 04 04 04 04 04>
Jul 06 16:08:37.76: [26063]: <-- data [23]
Jul 06 16:08:37.76: [26063]: <-- data [2]
Jul 06 16:08:39.78: [26063]: --> [7:CONNECT]
Jul 06 16:08:39.78: [26063]: <-- HDLC<7:FF C8 C1 00 66 1F 22>
Jul 06 16:08:39.78: [26063]: <-- data [7]
Jul 06 16:08:39.78: [26063]: <-- data [2]
Jul 06 16:08:40.20: [26063]: --> [2:OK]
Jul 06 16:08:40.20: [26063]: <-- [9:AT+FTS=7\r]
Jul 06 16:08:40.28: [26063]: --> [2:OK]
Jul 06 16:08:40.28: [26063]: MODEM set XON/XOFF/FLUSH: input interpreted,
output disabled
Jul 06 16:08:40.28: [26063]: <-- [10:AT+FTM=97\r]
Jul 06 16:08:40.29: [26063]: --> [7:CONNECT]
Jul 06 16:08:40.29: [26063]: DELAY 400 ms
Jul 06 16:08:40.69: [26063]: <-- data [1024]
Jul 06 16:08:40.69: [26063]: <-- data [776]
Jul 06 16:08:40.69: [26063]: <-- data [2]
Jul 06 16:08:43.24: [26063]: --> [2:OK]
Jul 06 16:08:43.24: [26063]: MODEM set XON/XOFF/DRAIN: input ignored, output
disabled
Jul 06 16:08:43.24: [26063]: <-- [9:AT+FRH=3\r]
Jul 06 16:08:43.37: [26063]: --> [7:CONNECT]
Jul 06 16:08:43.87: [26063]: --> HDLC<5:FF C8 5F C8 E7>
Jul 06 16:08:43.99: [26063]: --> [2:OK]
Jul 06 16:08:43.99: [26063]: TRAINING failed
Jul 06 16:08:43.99: [26063]: SEND: RSRPEC error/got DCN; Giving up after 3
attempts to send same page "docq/doc19.tif;c1", dirnum 0
Jul 06 16:08:43.99: [26063]: <-- [9:AT+FTH=3\r]
Jul 06 16:08:44.00: [26063]: --> [7:CONNECT]
Jul 06 16:08:44.00: [26063]: <-- HDLC<3:FF C8 DF>
Jul 06 16:08:44.00: [26063]: <-- data [3]
Jul 06 16:08:44.00: [26063]: <-- data [2]
Jul 06 16:08:45.48: [26063]: --> [2:OK]
Jul 06 16:08:45.48: [26063]: MODEM input buffering enabled
Jul 06 16:08:45.48: [26063]: <-- [5:ATH0\r]
Jul 06 16:08:45.59: [26063]: --> [2:OK]
Jul 06 16:08:45.59: [26063]: MODEM set DTR OFF
Jul 06 16:08:45.59: [26063]: MODEM set baud rate: 0 baud (flow control
unchanged)
Jul 06 16:08:45.59: [26063]: STATE CHANGE: SENDING -> MODEMWAIT (timeout 5)
Jul 06 16:08:45.59: [26063]: SESSION END



WORKING FAX FROM COMMAND LINE
sendfax -f "myname@xxxxxxxxxxxx" -R -r "faxsubject" -c "coverpage comments"
-x "Recipient's company" -d "Recipient@16148714848" 'test-big5.ps'

(root)>cat c000000026
Jul 06 11:55:06.16: [24122]: SESSION BEGIN 000000026 16148714848
Jul 06 11:55:06.16: [24122]: HylaFAX (tm) Version 4.3.0
Jul 06 11:55:06.16: [24122]: SEND FAX: JOB 8 DEST 16148714848 COMMID
000000026 DEVICE '/dev/ttyIAX' FROM 'myname <myname@xxxxxxxxxxxx>' USER root
Jul 06 11:55:06.16: [24122]: STATE CHANGE: RUNNING -> SENDING
Jul 06 11:55:06.16: [24122]: <-- [12:AT+FCLASS=1\r]
Jul 06 11:55:06.16: [24122]: --> [2:OK]
Jul 06 11:55:06.16: [24122]: MODEM set XON/XOFF/FLUSH: input ignored, output
disabled
Jul 06 11:55:06.16: [24122]: DIAL 16148714848
Jul 06 11:55:06.16: [24122]: <-- [16:ATDT16148714848\r]
Jul 06 11:55:17.61: [24122]: --> [7:CONNECT]
Jul 06 11:55:18.23: [24122]: --> [10:NO CARRIER]
Jul 06 11:55:18.23: [24122]: MODEM No carrier
Jul 06 11:55:18.23: [24122]: <-- [9:AT+FRS=7\r]
Jul 06 11:55:18.24: [24122]: --> [2:OK]
Jul 06 11:55:18.24: [24122]: <-- [9:AT+FTH=3\r]
Jul 06 11:55:18.25: [24122]: --> [7:CONNECT]
Jul 06 11:55:18.25: [24122]: <-- HDLC<3:FF C8 D8>
Jul 06 11:55:18.25: [24122]: <-- data [3]
Jul 06 11:55:18.25: [24122]: <-- data [2]
Jul 06 11:55:19.72: [24122]: --> [2:OK]
Jul 06 11:55:19.72: [24122]: SEND send CRP (command repeat)
Jul 06 11:55:19.72: [24122]: <-- [9:AT+FRH=3\r]
Jul 06 11:55:23.61: [24122]: --> [7:CONNECT]
Jul 06 11:55:24.87: [24122]: --> HDLC<13:FF C0 04 B5 00 6C 05 00 00 00 00 1C
75>
Jul 06 11:55:24.87: [24122]: --> [2:OK]
Jul 06 11:55:24.87: [24122]: REMOTE NSF "AD 00 36 A0 00 00 00 00"
Jul 06 11:55:24.87: [24122]: NSF remote fax equipment: HP 
Jul 06 11:55:24.87: [24122]: <-- [9:AT+FRH=3\r]
Jul 06 11:55:25.57: [24122]: --> [7:CONNECT]
Jul 06 11:55:25.57: [24122]: --> HDLC<25:FF C0 02 1C 2C 1C 2C 04 8C EC 1C 04
2C 8C 6C 04 04 04 04 04 04 04 04 29 92>
Jul 06 11:55:25.57: [24122]: --> [2:OK]
Jul 06 11:55:25.57: [24122]: REMOTE CSI "614 871 4848"
Jul 06 11:55:25.57: [24122]: <-- [9:AT+FRH=3\r]
Jul 06 11:55:26.01: [24122]: --> [7:CONNECT]
Jul 06 11:55:26.01: [24122]: --> HDLC<15:FF C8 01 04 77 1F 23 01 49 01 01 19
00 97 91>
Jul 06 11:55:26.11: [24122]: --> [2:OK]
Jul 06 11:55:26.11: [24122]: REMOTE best rate 33600 bit/s
Jul 06 11:55:26.11: [24122]: REMOTE max A4 page width (215 mm)
Jul 06 11:55:26.11: [24122]: REMOTE max unlimited page length
Jul 06 11:55:26.11: [24122]: REMOTE best vres 300 x 300 dpi
Jul 06 11:55:26.11: [24122]: REMOTE format support: MH, MR, MMR, JPEG
Full-Color
Jul 06 11:55:26.11: [24122]: REMOTE supports T.30 Annex A, 256-byte ECM
Jul 06 11:55:26.11: [24122]: REMOTE best 0 ms/scanline
Jul 06 11:55:26.11: [24122]: USE 14400 bit/s
Jul 06 11:55:26.11: [24122]: USE error correction mode
Jul 06 11:55:26.11: [24122]: SEND file "docq/cover8;c1"
Jul 06 11:55:26.11: [24122]: USE A4 page width (215 mm)
Jul 06 11:55:26.11: [24122]: USE unlimited page length
Jul 06 11:55:26.11: [24122]: USE 7.7 line/mm
Jul 06 11:55:26.11: [24122]: USE 2-D MMR
Jul 06 11:55:26.11: [24122]: USE 0 ms/scanline
Jul 06 11:55:26.11: [24122]: SEND training at v.17 14400 bit/s
Jul 06 11:55:26.11: [24122]: <-- [9:AT+FRS=7\r]
Jul 06 11:55:26.17: [24122]: --> [2:OK]
Jul 06 11:55:26.17: [24122]: <-- [9:AT+FTH=3\r]
Jul 06 11:55:26.18: [24122]: --> [7:CONNECT]
Jul 06 11:55:26.18: [24122]: <-- HDLC<23:FF C0 C2 B6 A6 26 F6 B6 1A 82 92 04
04 04 04 04 04 04 04 04 04 04 04>
Jul 06 11:55:26.18: [24122]: <-- data [23]
Jul 06 11:55:26.18: [24122]: <-- data [2]
Jul 06 11:55:28.20: [24122]: --> [7:CONNECT]
Jul 06 11:55:28.20: [24122]: <-- HDLC<7:FF C8 C1 00 46 1F 22>
Jul 06 11:55:28.20: [24122]: <-- data [7]
Jul 06 11:55:28.20: [24122]: <-- data [2]
Jul 06 11:55:28.60: [24122]: --> [2:OK]
Jul 06 11:55:28.60: [24122]: <-- [9:AT+FTS=7\r]
Jul 06 11:55:28.68: [24122]: --> [2:OK]
Jul 06 11:55:28.68: [24122]: MODEM set XON/XOFF/FLUSH: input interpreted,
output disabled
Jul 06 11:55:28.68: [24122]: <-- [11:AT+FTM=145\r]
Jul 06 11:55:28.69: [24122]: --> [7:CONNECT]
Jul 06 11:55:28.69: [24122]: DELAY 400 ms
Jul 06 11:55:29.09: [24122]: <-- data [1024]
Jul 06 11:55:29.09: [24122]: <-- data [1024]
Jul 06 11:55:29.09: [24122]: <-- data [652]
Jul 06 11:55:29.09: [24122]: <-- data [2]
Jul 06 11:55:31.64: [24122]: --> [2:OK]
Jul 06 11:55:31.64: [24122]: MODEM set XON/XOFF/DRAIN: input ignored, output
disabled
Jul 06 11:55:31.64: [24122]: <-- [9:AT+FRH=3\r]
Jul 06 11:55:32.79: [24122]: --> [7:CONNECT]
Jul 06 11:55:34.05: [24122]: --> HDLC<13:FF C0 04 B5 00 6C 05 00 00 00 00 1C
75>
Jul 06 11:55:34.05: [24122]: --> [2:OK]
Jul 06 11:55:34.05: [24122]: REMOTE NSF "AD 00 36 A0 00 00 00 00"
Jul 06 11:55:34.05: [24122]: NSF remote fax equipment: HP 
Jul 06 11:55:34.05: [24122]: <-- [9:AT+FRH=3\r]
Jul 06 11:55:34.75: [24122]: --> [7:CONNECT]
Jul 06 11:55:34.75: [24122]: --> HDLC<25:FF C0 02 1C 2C 1C 2C 04 8C EC 1C 04
2C 8C 6C 04 04 04 04 04 04 04 04 29 92>
Jul 06 11:55:34.75: [24122]: --> [2:OK]
Jul 06 11:55:34.75: [24122]: REMOTE CSI "614 871 4848"
Jul 06 11:55:34.75: [24122]: <-- [9:AT+FRH=3\r]
Jul 06 11:55:35.19: [24122]: --> [7:CONNECT]
Jul 06 11:55:35.19: [24122]: --> HDLC<15:FF C8 01 04 77 1F 23 01 49 01 01 19
00 97 91>
Jul 06 11:55:35.29: [24122]: --> [2:OK]
Jul 06 11:55:35.29: [24122]: SEND training at v.17 14400 bit/s
Jul 06 11:55:35.29: [24122]: <-- [9:AT+FRS=7\r]
Jul 06 11:55:35.35: [24122]: --> [2:OK]
Jul 06 11:55:35.35: [24122]: <-- [9:AT+FTH=3\r]
Jul 06 11:55:35.36: [24122]: --> [7:CONNECT]
Jul 06 11:55:35.36: [24122]: <-- HDLC<23:FF C0 C2 B6 A6 26 F6 B6 1A 82 92 04
04 04 04 04 04 04 04 04 04 04 04>
Jul 06 11:55:35.36: [24122]: <-- data [23]
Jul 06 11:55:35.36: [24122]: <-- data [2]
Jul 06 11:55:37.38: [24122]: --> [7:CONNECT]
Jul 06 11:55:37.38: [24122]: <-- HDLC<7:FF C8 C1 00 46 1F 22>
Jul 06 11:55:37.38: [24122]: <-- data [7]
Jul 06 11:55:37.38: [24122]: <-- data [2]
Jul 06 11:55:37.78: [24122]: --> [2:OK]
Jul 06 11:55:37.78: [24122]: <-- [9:AT+FTS=7\r]
Jul 06 11:55:37.86: [24122]: --> [2:OK]
Jul 06 11:55:37.86: [24122]: MODEM set XON/XOFF/FLUSH: input interpreted,
output disabled
Jul 06 11:55:37.86: [24122]: <-- [11:AT+FTM=145\r]
Jul 06 11:55:37.87: [24122]: --> [7:CONNECT]
Jul 06 11:55:37.87: [24122]: DELAY 400 ms
Jul 06 11:55:38.27: [24122]: <-- data [1024]
Jul 06 11:55:38.27: [24122]: <-- data [1024]
Jul 06 11:55:38.27: [24122]: <-- data [652]
Jul 06 11:55:38.27: [24122]: <-- data [2]
Jul 06 11:55:40.82: [24122]: --> [2:OK]
Jul 06 11:55:40.82: [24122]: MODEM set XON/XOFF/DRAIN: input ignored, output
disabled
Jul 06 11:55:40.82: [24122]: <-- [9:AT+FRH=3\r]
Jul 06 11:55:41.19: [24122]: --> [7:CONNECT]
Jul 06 11:55:42.25: [24122]: --> HDLC<5:FF C8 21 57 BE>
Jul 06 11:55:42.35: [24122]: --> [2:OK]
Jul 06 11:55:42.35: [24122]: TRAINING succeeded
Jul 06 11:55:42.38: [24122]: SEND begin page
Jul 06 11:55:42.39: [24122]: SEND EOFB
Jul 06 11:55:42.39: [24122]: SEND send frame number 0
Jul 06 11:55:42.39: [24122]: SEND send frame number 1
Jul 06 11:55:42.39: [24122]: SEND send frame number 2
Jul 06 11:55:42.39: [24122]: SEND send frame number 3
Jul 06 11:55:42.39: [24122]: SEND send frame number 4
Jul 06 11:55:42.39: [24122]: SEND send frame number 5
Jul 06 11:55:42.39: [24122]: SEND send frame number 6
Jul 06 11:55:42.39: [24122]: SEND send frame number 7
Jul 06 11:55:42.39: [24122]: SEND send frame number 8
Jul 06 11:55:42.39: [24122]: SEND send frame number 9
Jul 06 11:55:42.40: [24122]: SEND send frame number 10
Jul 06 11:55:42.40: [24122]: SEND send frame number 11
Jul 06 11:55:42.40: [24122]: SEND send frame number 12
Jul 06 11:55:42.40: [24122]: SEND send frame number 13
Jul 06 11:55:42.40: [24122]: SEND send frame number 14
Jul 06 11:55:42.40: [24122]: SEND send frame number 15
Jul 06 11:55:42.40: [24122]: SEND send frame number 16
Jul 06 11:55:42.40: [24122]: SEND send frame number 17
Jul 06 11:55:42.40: [24122]: SEND send frame number 18
Jul 06 11:55:42.40: [24122]: SEND send frame number 19
Jul 06 11:55:42.40: [24122]: SEND send frame number 20
Jul 06 11:55:42.40: [24122]: SEND send frame number 21
Jul 06 11:55:42.40: [24122]: SEND send frame number 22
Jul 06 11:55:42.40: [24122]: SEND send frame number 23
Jul 06 11:55:42.40: [24122]: SEND send frame number 24
Jul 06 11:55:42.40: [24122]: SEND send frame number 25
Jul 06 11:55:42.40: [24122]: SEND send frame number 26
Jul 06 11:55:42.40: [24122]: SEND send frame number 27
Jul 06 11:55:42.40: [24122]: MODEM set XON/XOFF/FLUSH: input interpreted,
output disabled
Jul 06 11:55:42.40: [24122]: DELAY 200 ms
Jul 06 11:55:42.60: [24122]: <-- [11:AT+FTM=146\r]
Jul 06 11:55:42.60: [24122]: --> [7:CONNECT]
Jul 06 11:55:42.60: [24122]: DELAY 400 ms
Jul 06 11:55:43.00: [24122]: <-- data [1030]
Jul 06 11:55:43.00: [24122]: <-- data [1040]
Jul 06 11:55:43.00: [24122]: <-- data [1037]
Jul 06 11:55:43.00: [24122]: <-- data [1033]
Jul 06 11:55:43.58: [24122]: <-- data [1027]
Jul 06 11:55:43.58: [24122]: <-- data [1037]
Jul 06 11:55:43.58: [24122]: <-- data [1029]
Jul 06 11:55:43.58: [24122]: <-- data [913]
Jul 06 11:55:43.58: [24122]: <-- data [2]
Jul 06 11:55:47.56: [24122]: --> [2:OK]
Jul 06 11:55:47.56: [24122]: MODEM set XON/XOFF/DRAIN: input ignored, output
disabled
Jul 06 11:55:47.56: [24122]: <-- [9:AT+FTS=7\r]
Jul 06 11:55:47.64: [24122]: --> [2:OK]
Jul 06 11:55:47.64: [24122]: <-- [9:AT+FTH=3\r]
Jul 06 11:55:47.65: [24122]: --> [7:CONNECT]
Jul 06 11:55:47.65: [24122]: <-- HDLC<7:FF C8 FD F2 00 00 D8>
Jul 06 11:55:47.65: [24122]: <-- data [7]
Jul 06 11:55:47.65: [24122]: <-- data [2]
Jul 06 11:55:49.22: [24122]: --> [2:OK]
Jul 06 11:55:49.22: [24122]: SEND send PPS (partial page signal)
Jul 06 11:55:49.22: [24122]: SEND send MPS (more pages, same document)
Jul 06 11:55:49.22: [24122]: <-- [9:AT+FRH=3\r]
Jul 06 11:55:49.71: [24122]: --> [7:CONNECT]
Jul 06 11:55:51.75: [24122]: --> HDLC<37:FF C8 3D 61 00 00 0F FF FF FF FF FF
FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF 06 3E>
Jul 06 11:55:51.89: [24122]: --> [2:OK]
Jul 06 11:55:51.89: [24122]: SEND recv PPR (partial page request)
Jul 06 11:55:51.89: [24122]: <-- [9:AT+FRS=7\r]
Jul 06 11:55:51.95: [24122]: --> [2:OK]
Jul 06 11:55:51.95: [24122]: SEND send frame number 1
Jul 06 11:55:51.95: [24122]: SEND send frame number 2
Jul 06 11:55:51.95: [24122]: SEND send frame number 7
Jul 06 11:55:51.95: [24122]: MODEM set XON/XOFF/FLUSH: input interpreted,
output disabled
Jul 06 11:55:51.95: [24122]: DELAY 200 ms
Jul 06 11:55:52.15: [24122]: <-- [11:AT+FTM=146\r]
Jul 06 11:55:52.15: [24122]: --> [7:CONNECT]
Jul 06 11:55:52.15: [24122]: DELAY 400 ms
Jul 06 11:55:52.55: [24122]: <-- data [1028]
Jul 06 11:55:52.55: [24122]: <-- data [191]
Jul 06 11:55:52.55: [24122]: <-- data [2]
Jul 06 11:55:53.28: [24122]: --> [2:OK]
Jul 06 11:55:53.28: [24122]: MODEM set XON/XOFF/DRAIN: input ignored, output
disabled
Jul 06 11:55:53.28: [24122]: <-- [9:AT+FTS=7\r]
Jul 06 11:55:53.36: [24122]: --> [2:OK]
Jul 06 11:55:53.36: [24122]: <-- [9:AT+FTH=3\r]
Jul 06 11:55:53.37: [24122]: --> [7:CONNECT]
Jul 06 11:55:53.37: [24122]: <-- HDLC<7:FF C8 FD F2 00 00 40>
Jul 06 11:55:53.37: [24122]: <-- data [7]
Jul 06 11:55:53.37: [24122]: <-- data [2]
Jul 06 11:55:54.94: [24122]: --> [2:OK]
Jul 06 11:55:54.94: [24122]: SEND send PPS (partial page signal)
Jul 06 11:55:54.94: [24122]: SEND send MPS (more pages, same document)
Jul 06 11:55:54.94: [24122]: <-- [9:AT+FRH=3\r]
Jul 06 11:55:55.39: [24122]: --> [7:CONNECT]
Jul 06 11:55:56.43: [24122]: --> HDLC<5:FF C8 31 45 8F>
Jul 06 11:55:56.53: [24122]: --> [2:OK]
Jul 06 11:55:56.53: [24122]: SEND recv MCF (message confirmation)
Jul 06 11:55:56.53: [24122]: <-- [9:AT+FRS=7\r]
Jul 06 11:55:56.65: [24122]: --> [2:OK]
Jul 06 11:55:56.65: [24122]: SEND end page
Jul 06 11:55:56.65: [24122]: SEND FAX (000000026): FROM myname@xxxxxxxxxxxx
TO 16148714848 (page 1 of 2 sent in 0:30)
Jul 06 11:55:56.65: [24122]: SEND FAX (000000026): FROM myname@xxxxxxxxxxxx
TO 16148714848 (docq/cover8;c1 sent in 0:30)
Jul 06 11:55:57.65: [24122]: SEND file "docq/doc12.ps;c1"
Jul 06 11:55:57.65: [24122]: SEND begin page
Jul 06 11:55:57.66: [24122]: SEND EOFB
Jul 06 11:55:57.66: [24122]: SEND send frame number 0
Jul 06 11:55:57.66: [24122]: SEND send frame number 1
Jul 06 11:55:57.66: [24122]: SEND send frame number 2
Jul 06 11:55:57.66: [24122]: SEND send frame number 3
Jul 06 11:55:57.66: [24122]: SEND send frame number 4
Jul 06 11:55:57.66: [24122]: SEND send frame number 5
Jul 06 11:55:57.66: [24122]: SEND send frame number 6
Jul 06 11:55:57.66: [24122]: SEND send frame number 7
Jul 06 11:55:57.66: [24122]: SEND send frame number 8
Jul 06 11:55:57.66: [24122]: SEND send frame number 9
Jul 06 11:55:57.66: [24122]: SEND send frame number 10
Jul 06 11:55:57.66: [24122]: SEND send frame number 11
Jul 06 11:55:57.66: [24122]: SEND send frame number 12
Jul 06 11:55:57.66: [24122]: SEND send frame number 13
Jul 06 11:55:57.66: [24122]: SEND send frame number 14
Jul 06 11:55:57.66: [24122]: SEND send frame number 15
Jul 06 11:55:57.66: [24122]: SEND send frame number 16
Jul 06 11:55:57.66: [24122]: SEND send frame number 17
Jul 06 11:55:57.66: [24122]: SEND send frame number 18
Jul 06 11:55:57.66: [24122]: SEND send frame number 19
Jul 06 11:55:57.66: [24122]: SEND send frame number 20
Jul 06 11:55:57.67: [24122]: SEND send frame number 21
Jul 06 11:55:57.67: [24122]: SEND send frame number 22
Jul 06 11:55:57.67: [24122]: SEND send frame number 23
Jul 06 11:55:57.67: [24122]: SEND send frame number 24
Jul 06 11:55:57.67: [24122]: SEND send frame number 25
Jul 06 11:55:57.67: [24122]: SEND send frame number 26
Jul 06 11:55:57.67: [24122]: MODEM set XON/XOFF/FLUSH: input interpreted,
output disabled
Jul 06 11:55:57.67: [24122]: DELAY 200 ms
Jul 06 11:55:57.86: [24122]: <-- [11:AT+FTM=146\r]
Jul 06 11:55:57.86: [24122]: --> [7:CONNECT]
Jul 06 11:55:57.86: [24122]: DELAY 400 ms
Jul 06 11:55:58.26: [24122]: <-- data [1028]
Jul 06 11:55:58.26: [24122]: <-- data [1033]
Jul 06 11:55:58.26: [24122]: <-- data [1032]
Jul 06 11:55:58.26: [24122]: <-- data [1037]
Jul 06 11:55:58.84: [24122]: <-- data [1030]
Jul 06 11:55:58.84: [24122]: <-- data [1032]
Jul 06 11:55:58.84: [24122]: <-- data [1035]
Jul 06 11:55:58.85: [24122]: <-- data [596]
Jul 06 11:55:58.85: [24122]: <-- data [2]
Jul 06 11:56:02.64: [24122]: --> [2:OK]
Jul 06 11:56:02.64: [24122]: MODEM set XON/XOFF/DRAIN: input ignored, output
disabled
Jul 06 11:56:02.64: [24122]: <-- [9:AT+FTS=9\r]
Jul 06 11:56:02.74: [24122]: --> [2:OK]
Jul 06 11:56:02.74: [24122]: <-- [9:AT+FTH=3\r]
Jul 06 11:56:02.74: [24122]: --> [7:CONNECT]
Jul 06 11:56:02.74: [24122]: <-- HDLC<7:FF C8 FD F4 80 00 58>
Jul 06 11:56:02.74: [24122]: <-- data [7]
Jul 06 11:56:02.75: [24122]: <-- data [2]
Jul 06 11:56:04.32: [24122]: --> [2:OK]
Jul 06 11:56:04.32: [24122]: SEND send PPS (partial page signal)
Jul 06 11:56:04.32: [24122]: SEND send EOP (no more pages or documents)
Jul 06 11:56:04.32: [24122]: <-- [9:AT+FRH=3\r]
Jul 06 11:56:04.74: [24122]: --> [7:CONNECT]
Jul 06 11:56:06.78: [24122]: --> HDLC<37:FF C8 3D 01 00 30 3F FF FF FF FF FF
FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF 0E 63>
Jul 06 11:56:06.92: [24122]: --> [2:OK]
Jul 06 11:56:06.93: [24122]: SEND recv PPR (partial page request)
Jul 06 11:56:06.93: [24122]: <-- [9:AT+FRS=7\r]
Jul 06 11:56:07.00: [24122]: --> [2:OK]
Jul 06 11:56:07.01: [24122]: SEND send frame number 7
Jul 06 11:56:07.01: [24122]: SEND send frame number 18
Jul 06 11:56:07.01: [24122]: SEND send frame number 19
Jul 06 11:56:07.01: [24122]: SEND send frame number 26
Jul 06 11:56:07.01: [24122]: MODEM set XON/XOFF/FLUSH: input interpreted,
output disabled
Jul 06 11:56:07.01: [24122]: DELAY 200 ms
Jul 06 11:56:07.20: [24122]: <-- [11:AT+FTM=146\r]
Jul 06 11:56:07.21: [24122]: --> [7:CONNECT]
Jul 06 11:56:07.21: [24122]: DELAY 400 ms
Jul 06 11:56:07.60: [24122]: <-- data [1028]
Jul 06 11:56:07.61: [24122]: <-- data [459]
Jul 06 11:56:07.61: [24122]: <-- data [2]
Jul 06 11:56:08.48: [24122]: --> [2:OK]
Jul 06 11:56:08.48: [24122]: MODEM set XON/XOFF/DRAIN: input ignored, output
disabled
Jul 06 11:56:08.48: [24122]: <-- [9:AT+FTS=9\r]
Jul 06 11:56:08.58: [24122]: --> [2:OK]
Jul 06 11:56:08.58: [24122]: <-- [9:AT+FTH=3\r]
Jul 06 11:56:08.59: [24122]: --> [7:CONNECT]
Jul 06 11:56:08.59: [24122]: <-- HDLC<7:FF C8 FD F4 80 00 C0>
Jul 06 11:56:08.59: [24122]: <-- data [7]
Jul 06 11:56:08.59: [24122]: <-- data [2]
Jul 06 11:56:10.16: [24122]: --> [2:OK]
Jul 06 11:56:10.16: [24122]: SEND send PPS (partial page signal)
Jul 06 11:56:10.16: [24122]: SEND send EOP (no more pages or documents)
Jul 06 11:56:10.16: [24122]: <-- [9:AT+FRH=3\r]
Jul 06 11:56:10.59: [24122]: --> [7:CONNECT]
Jul 06 11:56:11.63: [24122]: --> HDLC<5:FF C8 31 45 8F>
Jul 06 11:56:11.75: [24122]: --> [2:OK]
Jul 06 11:56:11.75: [24122]: SEND recv MCF (message confirmation)
Jul 06 11:56:11.75: [24122]: <-- [9:AT+FRS=7\r]
Jul 06 11:56:11.83: [24122]: --> [2:OK]
Jul 06 11:56:11.83: [24122]: SEND end page
Jul 06 11:56:11.83: [24122]: SEND FAX (000000026): FROM myname@xxxxxxxxxxxx
TO 16148714848 (page 2 of 2 sent in 0:14)
Jul 06 11:56:11.83: [24122]: SEND FAX (000000026): FROM myname@xxxxxxxxxxxx
TO 16148714848 (docq/doc12.ps;c1 sent in 0:14)
Jul 06 11:56:12.83: [24122]: <-- [9:AT+FTH=3\r]
Jul 06 11:56:12.84: [24122]: --> [7:CONNECT]
Jul 06 11:56:12.84: [24122]: <-- HDLC<3:FF C8 DF>
Jul 06 11:56:12.84: [24122]: <-- data [3]
Jul 06 11:56:12.84: [24122]: <-- data [2]
Jul 06 11:56:14.32: [24122]: --> [2:OK]
Jul 06 11:56:14.32: [24122]: MODEM input buffering enabled
Jul 06 11:56:14.32: [24122]: <-- [5:ATH0\r]
Jul 06 11:56:14.43: [24122]: --> [2:OK]
Jul 06 11:56:14.43: [24122]: MODEM set DTR OFF
Jul 06 11:56:14.43: [24122]: MODEM set baud rate: 0 baud (flow control
unchanged)
Jul 06 11:56:14.43: [24122]: STATE CHANGE: SENDING -> MODEMWAIT (timeout 5)
Jul 06 11:56:14.43: [24122]: SESSION END









-----Original Message-----
From: Lee Howard [mailto:faxguy@xxxxxxxxxxxxxxxx] 
Sent: Thursday, July 06, 2006 3:33 PM
To: Chris Duncan
Cc: hylafax-users@xxxxxxxxxxx
Subject: Re: [hylafax-users] FW: Hylafax sending issue

Chris Duncan wrote:

> Jul 06 11:37:15.29: [23736]: USE 14400 bit/s
>
> Jul 06 11:37:15.29: [23736]: USE error correction mode
>
> Jul 06 11:37:15.29: [23736]: <-- [9:AT+FTH=3\r]
>
> Jul 06 11:37:15.30: [23736]: --> [7:CONNECT]
>
> Jul 06 11:37:15.30: [23736]: <-- HDLC<3:FF C8 DF>
>
> Jul 06 11:37:15.30: [23736]: <-- data [3]
>
> Jul 06 11:37:15.30: [23736]: <-- data [2]
>
> Jul 06 11:37:16.78: [23736]: --> [2:OK]
>

For some reason faxq is invoking faxsend for a job that does not have 
any faxable documents on it.  faxq does make some effort to prevent this 
from happening, but it's apparently not perfect.

Unfortunately, the log doesn't tell us enough about what went wrong to 
know how to improve it more.  We'd probably need to see verbose 
FaxQueuer logging as well as have copies of the original documents, 
prepared documents, and the sendq file.

Lee.


____________________ HylaFAX(tm) Users Mailing List _______________________
  To subscribe/unsubscribe, click http://lists.hylafax.org/cgi-bin/lsg2.cgi
 On UNIX: mail -s unsubscribe hylafax-users-request@xxxxxxxxxxx < /dev/null
  *To learn about commercial HylaFAX(tm) support, mail sales@xxxxxxxxx*