[hylafax-users] bad Class 1 fax log

[Lee Howard <faxguy@deanox.com>]

After many attempts, I can only send one page of this 2-page fax to this
number.  The intended recipient is using a Winmodem in Windows 2000 and is
using the default Windows 2000 fax software.

Oct 10 11:56:02.64: [17744]: SESSION BEGIN 00000981 14352584403
Oct 10 11:56:02.64: [17744]: SEND FAX: JOB 204 DEST 2584403 COMMID 00000981
Oct 10 11:56:02.64: [17744]: MODEM set DTR OFF
Oct 10 11:56:02.64: [17744]: DELAY 2600 ms
Oct 10 11:56:05.24: [17744]: MODEM set DTR ON
Oct 10 11:56:05.24: [17744]: MODEM set baud rate: 38400 baud, input flow
RTS/CTS, output flow RTS/CTS
Oct 10 11:56:05.24: [17744]: MODEM flush i/o
Oct 10 11:56:05.24: [17744]: <-- [4:ATZ\r]
Oct 10 11:56:05.37: [17744]: --> [2:OK]
Oct 10 11:56:05.37: [17744]: <-- [17:ATE0V1Q0X4S0=0H0\r]
Oct 10 11:56:05.51: [17744]: --> [16:ATE0V1Q0X4S0=0H0]
Oct 10 11:56:05.51: [17744]: --> [2:OK]
Oct 10 11:56:05.51: [17744]: <-- [32:ATS8=2S7=60&H1&I0&R2S13=1&D2&C1\r]
Oct 10 11:56:05.65: [17744]: --> [2:OK]
Oct 10 11:56:05.65: [17744]: <-- [12:AT+FCLASS=1\r]
Oct 10 11:56:05.78: [17744]: --> [2:OK]
Oct 10 11:56:05.78: [17744]: <-- [12:AT&H1&I0&R2\r]
Oct 10 11:56:05.91: [17744]: --> [2:OK]
Oct 10 11:56:05.91: [17744]: <-- [21:AT+FCLASS=0&H1&I0&R2\r]
Oct 10 11:56:06.05: [17744]: --> [2:OK]
Oct 10 11:56:06.05: [17744]: <-- [5:ATM0\r]
Oct 10 11:56:06.18: [17744]: --> [2:OK]
Oct 10 11:56:06.18: [17744]: STATE CHANGE: RUNNING -> SENDING
Oct 10 11:56:06.18: [17744]: MODEM input buffering enabled
Oct 10 11:56:06.18: [17744]: <-- [12:AT+FCLASS=1\r]
Oct 10 11:56:06.42: [17744]: --> [2:OK]
Oct 10 11:56:06.42: [17744]: <-- [12:AT&H1&I0&R2\r]
Oct 10 11:56:06.65: [17744]: --> [2:OK]
Oct 10 11:56:06.65: [17744]: DIAL 2584403
Oct 10 11:56:06.65: [17744]: <-- [12:ATDT2584403\r]
Oct 10 11:56:26.88: [17744]: --> [7:CONNECT]
Oct 10 11:56:26.88: [17744]: MODEM input buffering disabled
Oct 10 11:56:28.47: [17744]: --> HDLC<25:FF C0 02 04 04 04 04 04 04 04 04
04 04 04 04 04 04 04 04 04 1E 86 62 51 CB>
Oct 10 11:56:28.48: [17744]: --> [2:OK]
Oct 10 11:56:28.48: [17744]: REMOTE CSI "Fax"
Oct 10 11:56:28.48: [17744]: <-- [9:AT+FRH=3\r]
Oct 10 11:56:28.49: [17744]: --> [7:CONNECT]
Oct 10 11:56:28.93: [17744]: --> HDLC<8:FF C8 01 00 77 1E 80 40>
Oct 10 11:56:28.93: [17744]: --> [2:OK]
Oct 10 11:56:28.93: [17744]: REMOTE best rate 14400 bit/s
Oct 10 11:56:28.93: [17744]: REMOTE max page width 1728 pixels in 215 mm
Oct 10 11:56:28.93: [17744]: REMOTE max unlimited page length 
Oct 10 11:56:28.93: [17744]: REMOTE best vres 7.7 line/mm
Oct 10 11:56:28.93: [17744]: REMOTE best format 2-D MR
Oct 10 11:56:28.93: [17744]: REMOTE best 0 ms/scanline
Oct 10 11:56:28.93: [17744]: USE 14400 bit/s
Oct 10 11:56:28.93: [17744]: USE 0 ms/scanline
Oct 10 11:56:28.93: [17744]: SEND file "docq/doc316.tif;31"
Oct 10 11:56:28.93: [17744]: USE page width 1728 pixels in 215 mm
Oct 10 11:56:28.93: [17744]: USE unlimited page length 
Oct 10 11:56:28.93: [17744]: USE 7.7 line/mm
Oct 10 11:56:28.93: [17744]: USE 1-D MR
Oct 10 11:56:28.93: [17744]: SEND training at v.17 14400 bit/s
Oct 10 11:56:28.93: [17744]: <-- [9:AT+FTH=3\r]
Oct 10 11:56:29.13: [17744]: --> [7:CONNECT]
Oct 10 11:56:29.13: [17744]: <-- HDLC<23:FF C0 C2 74 C6 76 92 04 34 1E F6
76 86 A6 22 04 04 04 04 04 04 04 04>
Oct 10 11:56:29.15: [17744]: --> [7:CONNECT]
Oct 10 11:56:29.15: [17744]: <-- HDLC<6:FF C8 C1 00 46 1E>
Oct 10 11:56:31.10: [17744]: --> [2:OK]
Oct 10 11:56:31.10: [17744]: DELAY 75 ms
Oct 10 11:56:31.18: [17744]: <-- [11:AT+FTM=145\r]
Oct 10 11:56:32.66: [17744]: --> [7:CONNECT]
Oct 10 11:56:34.19: [17744]: --> [2:OK]
Oct 10 11:56:34.19: [17744]: <-- [9:AT+FRH=3\r]
Oct 10 11:56:34.78: [17744]: --> [7:CONNECT]
Oct 10 11:56:35.73: [17744]: --> HDLC<5:FF C8 21 57 BE>
Oct 10 11:56:35.74: [17744]: --> [2:OK]
Oct 10 11:56:35.74: [17744]: TRAINING succeeded
Oct 10 11:56:35.74: [17744]: <-- [11:AT+FTM=146\r]
Oct 10 11:56:35.99: [17744]: --> [7:CONNECT]
Oct 10 11:56:35.99: [17744]: SEND begin page
Oct 10 11:56:58.05: [17744]: SENT 45292 bytes of data
Oct 10 11:56:58.05: [17744]: SEND 1D RTC
Oct 10 11:56:58.06: [17744]: SEND end page
Oct 10 11:57:01.20: [17744]: --> [2:OK]
Oct 10 11:57:01.20: [17744]: DELAY 95 ms
Oct 10 11:57:01.30: [17744]: SEND send EOP (no more pages or documents)
Oct 10 11:57:01.30: [17744]: <-- [9:AT+FTH=3\r]
Oct 10 11:57:01.45: [17744]: --> [7:CONNECT]
Oct 10 11:57:01.45: [17744]: <-- HDLC<3:FF C8 F4>
Oct 10 11:57:02.63: [17744]: --> [2:OK]
Oct 10 11:57:02.63: [17744]: <-- [9:AT+FRH=3\r]
Oct 10 11:57:03.18: [17744]: --> [7:CONNECT]
Oct 10 11:57:04.23: [17744]: --> HDLC<5:FF C8 32 75 EC>
Oct 10 11:57:04.23: [17744]: --> [2:OK]
Oct 10 11:57:04.23: [17744]: SEND recv RTN (retrain negative)
Oct 10 11:57:04.23: [17744]: SEND training at v.17 12000 bit/s
Oct 10 11:57:04.23: [17744]: <-- [9:AT+FTH=3\r]
Oct 10 11:57:04.42: [17744]: --> [7:CONNECT]
Oct 10 11:57:04.42: [17744]: <-- HDLC<23:FF C0 C2 74 C6 76 92 04 34 1E F6
76 86 A6 22 04 04 04 04 04 04 04 04>
Oct 10 11:57:04.44: [17744]: --> [7:CONNECT]
Oct 10 11:57:04.44: [17744]: <-- HDLC<6:FF C8 C1 00 56 1E>
Oct 10 11:57:06.40: [17744]: --> [2:OK]
Oct 10 11:57:06.40: [17744]: DELAY 75 ms
Oct 10 11:57:06.48: [17744]: <-- [11:AT+FTM=121\r]
Oct 10 11:57:07.96: [17744]: --> [7:CONNECT]
Oct 10 11:57:09.49: [17744]: --> [2:OK]
Oct 10 11:57:09.49: [17744]: <-- [9:AT+FRH=3\r]
Oct 10 11:57:09.98: [17744]: --> [7:CONNECT]
Oct 10 11:57:11.05: [17744]: --> HDLC<5:FF C8 21 57 BE>
Oct 10 11:57:11.05: [17744]: --> [2:OK]
Oct 10 11:57:11.05: [17744]: TRAINING succeeded
Oct 10 11:57:11.05: [17744]: <-- [11:AT+FTM=122\r]
Oct 10 11:57:11.30: [17744]: --> [7:CONNECT]
Oct 10 11:57:11.30: [17744]: SEND begin page
Oct 10 11:57:37.61: [17744]: SENT 45292 bytes of data
Oct 10 11:57:37.61: [17744]: SEND 1D RTC
Oct 10 11:57:37.61: [17744]: SEND end page
Oct 10 11:57:41.55: [17744]: --> [2:OK]
Oct 10 11:57:41.55: [17744]: DELAY 95 ms
Oct 10 11:57:41.65: [17744]: SEND send EOP (no more pages or documents)
Oct 10 11:57:41.65: [17744]: <-- [9:AT+FTH=3\r]
Oct 10 11:57:41.80: [17744]: --> [7:CONNECT]
Oct 10 11:57:41.80: [17744]: <-- HDLC<3:FF C8 F4>
Oct 10 11:57:42.98: [17744]: --> [2:OK]
Oct 10 11:57:42.98: [17744]: <-- [9:AT+FRH=3\r]
Oct 10 11:57:46.08: [17744]: --> [0:]
Oct 10 11:57:46.08: [17744]: MODEM <Empty line>
Oct 10 11:57:46.08: [17744]: MODEM TIMEOUT: waiting for v.21 carrier
Oct 10 11:57:46.09: [17744]: --> [2:OK]
Oct 10 11:57:46.09: [17744]: SEND send EOP (no more pages or documents)
Oct 10 11:57:46.09: [17744]: <-- [9:AT+FTH=3\r]
Oct 10 11:57:46.12: [17744]: --> [7:CONNECT]
Oct 10 11:57:46.12: [17744]: <-- HDLC<3:FF C8 F4>
Oct 10 11:57:47.30: [17744]: --> [2:OK]
Oct 10 11:57:47.30: [17744]: <-- [9:AT+FRH=3\r]
Oct 10 11:57:47.78: [17744]: --> [7:CONNECT]
Oct 10 11:57:48.81: [17744]: --> HDLC<5:FF C8 32 75 EC>
Oct 10 11:57:48.81: [17744]: --> [2:OK]
Oct 10 11:57:48.81: [17744]: SEND recv RTN (retrain negative)
Oct 10 11:57:48.81: [17744]: SEND training at v.17 9600 bit/s
Oct 10 11:57:48.81: [17744]: <-- [9:AT+FTH=3\r]
Oct 10 11:57:49.00: [17744]: --> [7:CONNECT]
Oct 10 11:57:49.00: [17744]: <-- HDLC<23:FF C0 C2 74 C6 76 92 04 34 1E F6
76 86 A6 22 04 04 04 04 04 04 04 04>
Oct 10 11:57:49.02: [17744]: --> [7:CONNECT]
Oct 10 11:57:49.02: [17744]: <-- HDLC<6:FF C8 C1 00 66 1E>
Oct 10 11:57:50.98: [17744]: --> [2:OK]
Oct 10 11:57:50.98: [17744]: DELAY 75 ms
Oct 10 11:57:51.06: [17744]: <-- [10:AT+FTM=97\r]
Oct 10 11:57:52.54: [17744]: --> [7:CONNECT]
Oct 10 11:57:54.07: [17744]: --> [2:OK]
Oct 10 11:57:54.07: [17744]: <-- [9:AT+FRH=3\r]
Oct 10 11:57:54.18: [17744]: --> [10:NO CARRIER]
Oct 10 11:57:54.18: [17744]: MODEM No carrier
Oct 10 11:57:54.18: [17744]: DELAY 1500 ms
Oct 10 11:57:55.68: [17744]: SEND training at v.17 7200 bit/s
Oct 10 11:57:55.68: [17744]: <-- [9:AT+FTH=3\r]
Oct 10 11:57:55.87: [17744]: --> [7:CONNECT]
Oct 10 11:57:55.87: [17744]: <-- HDLC<23:FF C0 C2 74 C6 76 92 04 34 1E F6
76 86 A6 22 04 04 04 04 04 04 04 04>
Oct 10 11:57:55.89: [17744]: --> [7:CONNECT]
Oct 10 11:57:55.89: [17744]: <-- HDLC<6:FF C8 C1 00 76 1E>
Oct 10 11:57:57.85: [17744]: --> [2:OK]
Oct 10 11:57:57.85: [17744]: DELAY 75 ms
Oct 10 11:57:57.93: [17744]: <-- [10:AT+FTM=73\r]
Oct 10 11:57:59.41: [17744]: --> [7:CONNECT]
Oct 10 11:58:00.94: [17744]: --> [2:OK]
Oct 10 11:58:00.94: [17744]: <-- [9:AT+FRH=3\r]
Oct 10 11:58:04.04: [17744]: --> [0:]
Oct 10 11:58:04.04: [17744]: MODEM <Empty line>
Oct 10 11:58:04.04: [17744]: MODEM TIMEOUT: waiting for v.21 carrier
Oct 10 11:58:04.05: [17744]: --> [2:OK]
Oct 10 11:58:04.05: [17744]: DELAY 1500 ms
Oct 10 11:58:05.55: [17744]: SEND training at v.27ter 4800 bit/s
Oct 10 11:58:05.55: [17744]: <-- [9:AT+FTH=3\r]
Oct 10 11:58:05.58: [17744]: --> [7:CONNECT]
Oct 10 11:58:05.58: [17744]: <-- HDLC<23:FF C0 C2 74 C6 76 92 04 34 1E F6
76 86 A6 22 04 04 04 04 04 04 04 04>
Oct 10 11:58:05.60: [17744]: --> [7:CONNECT]
Oct 10 11:58:05.60: [17744]: <-- HDLC<6:FF C8 C1 00 52 1E>
Oct 10 11:58:07.56: [17744]: --> [2:OK]
Oct 10 11:58:07.56: [17744]: DELAY 75 ms
Oct 10 11:58:07.64: [17744]: <-- [10:AT+FTM=48\r]
Oct 10 11:58:08.65: [17744]: --> [7:CONNECT]
Oct 10 11:58:10.19: [17744]: --> [2:OK]
Oct 10 11:58:10.19: [17744]: <-- [9:AT+FRH=3\r]
Oct 10 11:58:10.78: [17744]: --> [7:CONNECT]
Oct 10 11:58:11.73: [17744]: --> HDLC<5:FF C8 21 57 BE>
Oct 10 11:58:11.74: [17744]: --> [2:OK]
Oct 10 11:58:11.74: [17744]: TRAINING succeeded
Oct 10 11:58:11.74: [17744]: <-- [10:AT+FTM=48\r]
Oct 10 11:58:12.78: [17744]: --> [7:CONNECT]
Oct 10 11:58:12.78: [17744]: SEND begin page
Oct 10 11:59:17.90: [17744]: SENT 45292 bytes of data
Oct 10 11:59:17.90: [17744]: SEND 1D RTC
Oct 10 11:59:17.90: [17744]: SEND end page
Oct 10 11:59:28.36: [17744]: --> [2:OK]
Oct 10 11:59:28.36: [17744]: DELAY 95 ms
Oct 10 11:59:28.46: [17744]: SEND send EOP (no more pages or documents)
Oct 10 11:59:28.46: [17744]: <-- [9:AT+FTH=3\r]
Oct 10 11:59:28.61: [17744]: --> [7:CONNECT]
Oct 10 11:59:28.61: [17744]: <-- HDLC<3:FF C8 F4>
Oct 10 11:59:29.79: [17744]: --> [2:OK]
Oct 10 11:59:29.79: [17744]: <-- [9:AT+FRH=3\r]
Oct 10 11:59:30.38: [17744]: --> [10:NO CARRIER]
Oct 10 11:59:30.38: [17744]: MODEM No carrier
Oct 10 11:59:30.38: [17744]: SEND send EOP (no more pages or documents)
Oct 10 11:59:30.38: [17744]: <-- [9:AT+FTH=3\r]
Oct 10 11:59:30.57: [17744]: --> [7:CONNECT]
Oct 10 11:59:30.57: [17744]: <-- HDLC<3:FF C8 F4>
Oct 10 11:59:31.75: [17744]: --> [2:OK]
Oct 10 11:59:31.75: [17744]: <-- [9:AT+FRH=3\r]
Oct 10 11:59:34.85: [17744]: --> [0:]
Oct 10 11:59:34.85: [17744]: MODEM <Empty line>
Oct 10 11:59:34.85: [17744]: MODEM TIMEOUT: waiting for v.21 carrier
Oct 10 11:59:34.86: [17744]: --> [2:OK]
Oct 10 11:59:34.86: [17744]: SEND send EOP (no more pages or documents)
Oct 10 11:59:34.86: [17744]: <-- [9:AT+FTH=3\r]
Oct 10 11:59:34.89: [17744]: --> [7:CONNECT]
Oct 10 11:59:34.89: [17744]: <-- HDLC<3:FF C8 F4>
Oct 10 11:59:36.07: [17744]: --> [2:OK]
Oct 10 11:59:36.07: [17744]: <-- [9:AT+FRH=3\r]
Oct 10 11:59:37.18: [17744]: --> [10:NO CARRIER]
Oct 10 11:59:37.18: [17744]: MODEM No carrier
Oct 10 11:59:37.18: [17744]: No response to MPS or EOP repeated 3 tries
Oct 10 11:59:37.18: [17744]: SEND: No response to MPS or EOP repeated 3
tries; Giving up after 3 attempts to send same page "docq/doc316.tif;31",
dirnum 0
Oct 10 11:59:37.18: [17744]: <-- [9:AT+FTH=3\r]
Oct 10 11:59:37.37: [17744]: --> [7:CONNECT]
Oct 10 11:59:37.37: [17744]: <-- HDLC<3:FF C8 DF>
Oct 10 11:59:38.55: [17744]: --> [2:OK]
Oct 10 11:59:38.55: [17744]: MODEM input buffering enabled
Oct 10 11:59:38.55: [17744]: <-- [5:ATH0\r]
Oct 10 11:59:38.78: [17744]: --> [2:OK]
Oct 10 11:59:38.78: [17744]: MODEM set DTR OFF
Oct 10 11:59:38.78: [17744]: STATE CHANGE: SENDING -> MODEMWAIT (timeout 5)
Oct 10 11:59:38.78: [17744]: SESSION END




____________________ HylaFAX(tm) Users Mailing List _______________________
 To unsub: mail -s unsubscribe hylafax-users-request@hylafax.org < /dev/null