Re: [hylafax-users] bad Class 1 fax log

[Dmitry Bely <dbely@mail.ru>]

Lee Howard <faxguy@deanox.com> writes:

> 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
                                             ^^^^^^^^ too bad :-(
> using the default Windows 2000 fax software.

I'm afraid it's the remote that causes the problem. Maybe it's not properly 
configured (flow control etc.), maybe the drivers are buggy. BTW, what does
it report after the session below? Is the received page is readable? Does
it work with other fax machines?

But you can work around this. Man config for "RTNHandlingMethod" then add

RTNHandlingMethod:      ignore
or even
RTNHandlingMethod:      giveup

to your config.<device>

> 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

Hope to hear from you soon,
Dmitry


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