[hylafax-users] Remote fax disconnect prematurely

[Osvaldo Lucchini <o.lucchini@pid.it>]

Hi all of you.

As requested by Lee, I send the full log of the problem.
Here the description:
After sending a fax, the finale status indicate the error in the subject,
even if the final destination seems to have received the document completely.

Here the log:
Mar 19 12:14:00.82: [19397]: SESSION BEGIN 00000021 39020521873753
Mar 19 12:14:00.82: [19397]: HylaFAX (tm) Version 4.1.1
Mar 19 12:14:00.82: [19397]: SEND FAX: JOB 18 DEST 0521873753 COMMID 
00000021 DEVICE '/dev/ttyS0'
Mar 19 12:14:00.82: [19397]: MODEM set DTR OFF
Mar 19 12:14:00.82: [19397]: DELAY 75 ms
Mar 19 12:14:00.89: [19397]: MODEM set DTR ON
Mar 19 12:14:00.89: [19397]: DELAY 2600 ms
Mar 19 12:14:03.49: [19397]: MODEM set baud rate: 57600 baud, input flow 
RTS/CTS, output flow RTS/CTS
Mar 19 12:14:03.49: [19397]: MODEM flush i/o
Mar 19 12:14:03.49: [19397]: <-- [4:ATZ\r]
Mar 19 12:14:03.66: [19397]: --> [3:ATZ]
Mar 19 12:14:03.66: [19397]: --> [2:OK]
Mar 19 12:14:03.66: [19397]: DELAY 3000 ms
Mar 19 12:14:06.66: [19397]: <-- [5:ATE0\r]
Mar 19 12:14:06.72: [19397]: --> [4:ATE0]
Mar 19 12:14:06.72: [19397]: --> [2:OK]
Mar 19 12:14:06.72: [19397]: <-- [5:ATV1\r]
Mar 19 12:14:06.78: [19397]: --> [2:OK]
Mar 19 12:14:06.78: [19397]: <-- [5:ATQ0\r]
Mar 19 12:14:06.84: [19397]: --> [2:OK]
Mar 19 12:14:06.84: [19397]: <-- [7:ATS0=0\r]
Mar 19 12:14:06.90: [19397]: --> [2:OK]
Mar 19 12:14:06.90: [19397]: <-- [7:ATS8=2\r]
Mar 19 12:14:06.96: [19397]: --> [2:OK]
Mar 19 12:14:06.96: [19397]: <-- [8:ATS7=60\r]
Mar 19 12:14:07.02: [19397]: --> [2:OK]
Mar 19 12:14:07.02: [19397]: <-- [6:AT\Q3\r]
Mar 19 12:14:07.08: [19397]: --> [2:OK]
Mar 19 12:14:07.08: [19397]: <-- [6:AT&D3\r]
Mar 19 12:14:07.14: [19397]: --> [2:OK]
Mar 19 12:14:07.14: [19397]: <-- [6:AT&C1\r]
Mar 19 12:14:07.20: [19397]: --> [2:OK]
Mar 19 12:14:07.20: [19397]: <-- [12:AT+FCLASS=1\r]
Mar 19 12:14:07.26: [19397]: --> [2:OK]
Mar 19 12:14:07.26: [19397]: <-- [7:ATL2M1\r]
Mar 19 12:14:07.32: [19397]: --> [2:OK]
Mar 19 12:14:07.32: [19397]: STATE CHANGE: RUNNING -> SENDING
Mar 19 12:14:07.32: [19397]: MODEM input buffering enabled
Mar 19 12:14:07.32: [19397]: <-- [12:AT+FCLASS=1\r]
Mar 19 12:14:07.48: [19397]: --> [2:OK]
Mar 19 12:14:07.49: [19397]: DIAL 0521873753
Mar 19 12:14:07.49: [19397]: <-- [15:ATDT0521873753\r]
Mar 19 12:14:29.68: [19397]: --> [7:CONNECT]
Mar 19 12:14:29.68: [19397]: MODEM input buffering disabled
Mar 19 12:14:31.46: [19397]: --> HDLC<31:FF C0 04 9A 80 40 82 5A 74 82 E2 
74 C2 82 E4 42 82 CA CA 82 04 04 04 04 04 04 33 00 A0 00 6C>
Mar 19 12:14:31.46: [19397]: --> [2:OK]
Mar 19 12:14:31.46: [19397]: REMOTE NSF "59 01 02 41 5A 2E 41 47 2E 43 41 
27 42 41 53 53 41 20 20 20 20 20 20 CC 00 05 00"
Mar 19 12:14:31.46: [19397]: NSF remote fax equipment: unknown
Mar 19 12:14:31.46: [19397]: NSF possible remote station ID: 
"AZ.AG.CA'BASSA   "
Mar 19 12:14:31.46: [19397]: <-- [9:AT+FRH=3\r]
Mar 19 12:14:31.49: [19397]: --> [7:CONNECT]
Mar 19 12:14:32.16: [19397]: --> HDLC<25:FF C0 02 CC AC EC CC EC 1C 04 8C 
4C AC 04 9C CC D4 04 04 04 04 04 04 69 A0>
Mar 19 12:14:32.16: [19397]: --> [2:OK]
Mar 19 12:14:32.16: [19397]: REMOTE CSI "+39 521 873753"
Mar 19 12:14:32.16: [19397]: <-- [9:AT+FRH=3\r]
Mar 19 12:14:32.19: [19397]: --> [7:CONNECT]
Mar 19 12:14:32.43: [19397]: --> HDLC<9:FF C8 01 00 72 1D 20 55 46>
Mar 19 12:14:32.43: [19397]: --> [2:OK]
Mar 19 12:14:32.43: [19397]: REMOTE best rate 9600 bit/s
Mar 19 12:14:32.43: [19397]: REMOTE max page width 1728 pixels in 215 mm
Mar 19 12:14:32.43: [19397]: REMOTE max unlimited page length
Mar 19 12:14:32.43: [19397]: REMOTE best vres 7.7 line/mm
Mar 19 12:14:32.43: [19397]: REMOTE best format 1-D MR
Mar 19 12:14:32.43: [19397]: REMOTE supports T.30 Annex A, ECM
Mar 19 12:14:32.43: [19397]: REMOTE best 20 ms, 10 ms/scanline
Mar 19 12:14:32.43: [19397]: USE 9600 bit/s
Mar 19 12:14:32.43: [19397]: USE 20 ms, 10 ms/scanline
Mar 19 12:14:32.43: [19397]: SEND file "docq/doc22.ps;00"
Mar 19 12:14:32.44: [19397]: USE page width 1728 pixels in 215 mm
Mar 19 12:14:32.44: [19397]: USE unlimited page length
Mar 19 12:14:32.44: [19397]: USE 3.85 line/mm
Mar 19 12:14:32.44: [19397]: USE 1-D MR
Mar 19 12:14:32.44: [19397]: SEND training at v.29 9600 bit/s
Mar 19 12:14:32.44: [19397]: <-- [9:AT+FTH=3\r]
Mar 19 12:14:32.47: [19397]: --> [7:CONNECT]
Mar 19 12:14:32.47: [19397]: <-- HDLC<23:FF C0 C2 86 0E CA 04 22 92 04 CE 
CE A6 C6 F6 4E 0A 04 04 04 04 04 04>
Mar 19 12:14:32.47: [19397]: <-- data [23]
Mar 19 12:14:32.47: [19397]: <-- data [2]
Mar 19 12:14:33.70: [19397]: --> [7:CONNECT]
Mar 19 12:14:33.70: [19397]: <-- HDLC<6:FF C8 C1 00 60 10>
Mar 19 12:14:33.70: [19397]: <-- data [6]
Mar 19 12:14:33.70: [19397]: <-- data [2]
Mar 19 12:14:34.04: [19397]: --> [2:OK]
Mar 19 12:14:34.04: [19397]: <-- [9:AT+FTS=7\r]
Mar 19 12:14:34.11: [19397]: --> [2:OK]
Mar 19 12:14:34.11: [19397]: <-- [10:AT+FTM=96\r]
Mar 19 12:14:34.14: [19397]: --> [7:CONNECT]
Mar 19 12:14:34.14: [19397]: <-- data [1024]
Mar 19 12:14:34.14: [19397]: <-- data [776]
Mar 19 12:14:34.14: [19397]: <-- data [2]
Mar 19 12:14:35.93: [19397]: --> [2:OK]
Mar 19 12:14:35.93: [19397]: <-- [9:AT+FRH=3\r]
Mar 19 12:14:37.00: [19397]: --> [7:CONNECT]
Mar 19 12:14:38.88: [19397]: --> HDLC<31:FF C0 04 9A 80 40 82 5A 74 82 E2 
74 C2 82 E4 42 82 CA CA 82 04 04 04 04 04 04 33 00 A0 00 6C>
Mar 19 12:14:38.88: [19397]: --> [2:OK]
Mar 19 12:14:38.88: [19397]: <-- [9:AT+FRH=3\r]
Mar 19 12:14:38.91: [19397]: --> [7:CONNECT]
Mar 19 12:14:39.58: [19397]: --> HDLC<25:FF C0 02 CC AC EC CC EC 1C 04 8C 
4C AC 04 9C CC D4 04 04 04 04 04 04 69 A0>
Mar 19 12:14:39.58: [19397]: --> [2:OK]
Mar 19 12:14:39.58: [19397]: REMOTE CSI "+39 521 873753"
Mar 19 12:14:39.58: [19397]: <-- [9:AT+FRH=3\r]
Mar 19 12:14:39.61: [19397]: --> [7:CONNECT]
Mar 19 12:14:39.85: [19397]: --> HDLC<9:FF C8 01 00 72 1D 20 55 46>
Mar 19 12:14:39.85: [19397]: --> [2:OK]
Mar 19 12:14:39.85: [19397]: SEND training at v.29 9600 bit/s
Mar 19 12:14:39.85: [19397]: <-- [9:AT+FTH=3\r]
Mar 19 12:14:39.89: [19397]: --> [7:CONNECT]
Mar 19 12:14:39.89: [19397]: <-- HDLC<23:FF C0 C2 86 0E CA 04 22 92 04 CE 
CE A6 C6 F6 4E 0A 04 04 04 04 04 04>
Mar 19 12:14:39.89: [19397]: <-- data [23]
Mar 19 12:14:39.89: [19397]: <-- data [2]
Mar 19 12:14:41.12: [19397]: --> [7:CONNECT]
Mar 19 12:14:41.12: [19397]: <-- HDLC<6:FF C8 C1 00 60 10>
Mar 19 12:14:41.12: [19397]: <-- data [6]
Mar 19 12:14:41.12: [19397]: <-- data [2]
Mar 19 12:14:41.46: [19397]: --> [2:OK]
Mar 19 12:14:41.46: [19397]: <-- [9:AT+FTS=7\r]
Mar 19 12:14:41.53: [19397]: --> [2:OK]
Mar 19 12:14:41.53: [19397]: <-- [10:AT+FTM=96\r]
Mar 19 12:14:41.56: [19397]: --> [7:CONNECT]
Mar 19 12:14:41.56: [19397]: <-- data [1024]
Mar 19 12:14:41.56: [19397]: <-- data [776]
Mar 19 12:14:41.56: [19397]: <-- data [2]
Mar 19 12:14:43.35: [19397]: --> [2:OK]
Mar 19 12:14:43.35: [19397]: <-- [9:AT+FRH=3\r]
Mar 19 12:14:44.39: [19397]: --> [7:CONNECT]
Mar 19 12:14:46.27: [19397]: --> HDLC<31:FF C0 04 9A 80 40 82 5A 74 82 E2 
74 C2 82 E4 42 82 CA CA 82 04 04 04 04 04 04 33 00 A0 00 6C>
Mar 19 12:14:46.27: [19397]: --> [2:OK]
Mar 19 12:14:46.27: [19397]: <-- [9:AT+FRH=3\r]
Mar 19 12:14:46.30: [19397]: --> [7:CONNECT]
Mar 19 12:14:46.97: [19397]: --> HDLC<25:FF C0 02 CC AC EC CC EC 1C 04 8C 
4C AC 04 9C CC D4 04 04 04 04 04 04 69 A0>
Mar 19 12:14:46.97: [19397]: --> [2:OK]
Mar 19 12:14:46.97: [19397]: REMOTE CSI "+39 521 873753"
Mar 19 12:14:46.97: [19397]: <-- [9:AT+FRH=3\r]
Mar 19 12:14:47.00: [19397]: --> [7:CONNECT]
Mar 19 12:14:47.24: [19397]: --> HDLC<9:FF C8 01 00 72 1D 20 55 46>
Mar 19 12:14:47.24: [19397]: --> [2:OK]
Mar 19 12:14:47.24: [19397]: SEND training at v.29 9600 bit/s
Mar 19 12:14:47.24: [19397]: <-- [9:AT+FTH=3\r]
Mar 19 12:14:47.28: [19397]: --> [7:CONNECT]
Mar 19 12:14:47.28: [19397]: <-- HDLC<23:FF C0 C2 86 0E CA 04 22 92 04 CE 
CE A6 C6 F6 4E 0A 04 04 04 04 04 04>
Mar 19 12:14:47.28: [19397]: <-- data [23]
Mar 19 12:14:47.28: [19397]: <-- data [2]
Mar 19 12:14:48.51: [19397]: --> [7:CONNECT]
Mar 19 12:14:48.51: [19397]: <-- HDLC<6:FF C8 C1 00 60 10>
Mar 19 12:14:48.51: [19397]: <-- data [6]
Mar 19 12:14:48.51: [19397]: <-- data [2]
Mar 19 12:14:48.85: [19397]: --> [2:OK]
Mar 19 12:14:48.85: [19397]: <-- [9:AT+FTS=7\r]
Mar 19 12:14:48.92: [19397]: --> [2:OK]
Mar 19 12:14:48.92: [19397]: <-- [10:AT+FTM=96\r]
Mar 19 12:14:48.95: [19397]: --> [7:CONNECT]
Mar 19 12:14:48.95: [19397]: <-- data [1024]
Mar 19 12:14:48.95: [19397]: <-- data [776]
Mar 19 12:14:48.95: [19397]: <-- data [2]
Mar 19 12:14:50.74: [19397]: --> [2:OK]
Mar 19 12:14:50.74: [19397]: <-- [9:AT+FRH=3\r]
Mar 19 12:14:51.77: [19397]: --> [7:CONNECT]
Mar 19 12:14:53.65: [19397]: --> HDLC<31:FF C0 04 9A 80 40 82 5A 74 82 E2 
74 C2 82 E4 42 82 CA CA 82 04 04 04 04 04 04 33 00 A0 00 6C>
Mar 19 12:14:53.65: [19397]: --> [2:OK]
Mar 19 12:14:53.65: [19397]: <-- [9:AT+FRH=3\r]
Mar 19 12:14:53.68: [19397]: --> [7:CONNECT]
Mar 19 12:14:54.35: [19397]: --> HDLC<25:FF C0 02 CC AC EC CC EC 1C 04 8C 
4C AC 04 9C CC D4 04 04 04 04 04 04 69 A0>
Mar 19 12:14:54.35: [19397]: --> [2:OK]
Mar 19 12:14:54.35: [19397]: REMOTE CSI "+39 521 873753"
Mar 19 12:14:54.35: [19397]: <-- [9:AT+FRH=3\r]
Mar 19 12:14:54.38: [19397]: --> [7:CONNECT]
Mar 19 12:14:54.62: [19397]: --> HDLC<9:FF C8 01 00 72 1D 20 55 46>
Mar 19 12:14:54.62: [19397]: --> [2:OK]
Mar 19 12:14:54.62: [19397]: <-- [9:AT+FTH=3\r]
Mar 19 12:14:54.66: [19397]: --> [7:CONNECT]
Mar 19 12:14:54.66: [19397]: <-- HDLC<3:FF C8 DF>
Mar 19 12:14:54.66: [19397]: <-- data [3]
Mar 19 12:14:54.66: [19397]: <-- data [2]
Mar 19 12:14:55.40: [19397]: --> [2:OK]
Mar 19 12:14:55.40: [19397]: MODEM input buffering enabled
Mar 19 12:14:55.40: [19397]: <-- [5:ATH0\r]
Mar 19 12:14:55.56: [19397]: --> [2:OK]
Mar 19 12:14:55.56: [19397]: MODEM set DTR OFF
Mar 19 12:14:55.56: [19397]: STATE CHANGE: SENDING -> MODEMWAIT (timeout 5)
Mar 19 12:14:55.56: [19397]: SESSION END


Hylafax 4.1.1
RH Linux 7.1 - kernel 2.4.2.-2
Standard Hayes-AT compatible Class 1 modem/fax.
("1.0" is all that I've from ati0, sorry).

Thnx in advance,

Osvaldo


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