Re: [hylafax-users] hang-up noise when sending to certain destination

["andrew rinaldi" <andrew.rinaldi@xxxxxxxxxxxx>]

Hi Marthter,

You are using an old version of HylaFAX (4.2.5 was released over a year
ago)... I would strongly suggest that you upgrade to HylaFAX 4.3.1 or
HylaFAX+ 5.0.3 and try again.

If you still have problems, ditch the USR, make sure you have the latest
firmware for the ZPX, and then post another log here.

Regards

ANDREW RINALDI
Mainpine Limited Support
USA +1 503 822 9944 | Asia/Europe +44 1225 869439  
andrew.rinaldi@xxxxxxxxxxxx | www.mainpine.com

-----Original Message-----
From: hylafax-users-bounces@xxxxxxxxxxxxxxxxxxxxx
[mailto:hylafax-users-bounces@xxxxxxxxxxxxxxxxxxxxx] On Behalf Of marthter
Sent: 19 January 2007 22:45
To: Hylafax-Users; hylafax-users@xxxxxxxxxxxxxxxxxxxxx
Subject: [hylafax-users] hang-up noise when sending to certain destination

Hi,

In trying to write this request for help as explicitly as possible, I 
got a lot further and I almost solved my own problem, but not quite.

I had a problem sending to a certain destination which I'm hoping 
someone can help me with.  This is on Hylafax 4.2.5 on a MT5634ZPX-PCI-U 
modem (the box has 2 of these for outgoing plus a USR 5601 for incoming).

I have session tracing and server tracing as 0xffff

When I call the destination with a voice phone, their fax machine
- picks up after one ring
- starts the squealing noise for about a second
- then suddenly makes a click like a hang-up noise and silence for about 
2 seconds
- but then resumes the squealing noises in normal sounding way - on for 
about 3 seconds, off for about 3 seconds, about 7 or 8 repetitions of 
that (so probably to where the total call length hits 60 seconds)
- then their end really does hang up

The wierd thing is that Hylafax is calling this "busy signal" after only 
about 25 seconds, but minicom is calling this NO CARRIER after about 60 
seconds, (which seems closer to being correct).

The following Hylafax test and minicom test shows the difference between 
what the two programs detect, but are identical between the two 5634 
modems (ttyS4 and 5):

Hylafax:

Jan 19 13:33:59.82: [28474]: SESSION BEGIN 000230103 19055551212
Jan 19 13:33:59.82: [28474]: HylaFAX (tm) Version 4.2.5
Jan 19 13:33:59.82: [28474]: SEND FAX: JOB 317236 DEST 905-555-1212 
COMMID 000230103 DEVICE '/dev/ttyS4' FROM 'green <green>' USER green
Jan 19 13:33:59.82: [28474]: STATE CHANGE: RUNNING -> SENDING
Jan 19 13:33:59.83: [28474]: <-- [14:AT+FCLASS=1.0\r]
Jan 19 13:33:59.83: [28474]: --> [2:OK]
Jan 19 13:33:59.83: [28474]: <-- [14:AT+F34=14,1,2\r]
Jan 19 13:33:59.84: [28474]: --> [2:OK]
Jan 19 13:33:59.85: [28474]: DIAL 19055551212
Jan 19 13:33:59.85: [28474]: <-- [18:ATX4DT19055551212\r]
Jan 19 13:34:23.04: [28474]: --> [4:BUSY]
Jan 19 13:34:23.04: [28474]: SEND FAILED: JOB 317236 DEST 905-555-1212 
ERR Busy signal detected
Jan 19 13:34:23.05: [28474]: <-- [5:ATH0\r]
Jan 19 13:34:23.18: [28474]: --> [2:OK]
Jan 19 13:34:23.18: [28474]: MODEM set DTR OFF
Jan 19 13:34:23.18: [28474]: STATE CHANGE: SENDING -> MODEMWAIT (timeout 5)
Jan 19 13:34:23.19: [28474]: SESSION END




Minicom:
 

Welcome to minicom 2.00.0

OPTIONS: History Buffer, F-key Macros, Search History Buffer, I18n
Compiled on Jan 25 2003, 00:15:18.

Press CTRL-A Z for help on special keys

AT S7=45 S0=0 L1 V1 X4 &c1 E1 Q0
OK
at
OK
atdt19055551212
NO CARRIER
atdt19055551212
NO CARRIER




And IN FACT the next Hylafax attempt after the above minicom attempt 
repeats the "no carrier" result instead of the "busy signal" that kept 
happening before that, but takes only about 30 seconds to decide that:



Jan 19 14:28:41.50: [29101]: SESSION BEGIN 000230133 19055551212
Jan 19 14:28:41.50: [29101]: HylaFAX (tm) Version 4.2.5
Jan 19 14:28:41.50: [29101]: SEND FAX: JOB 317254 DEST 905-555-1212 
COMMID 000230133 DEVICE '/dev/ttyS5' FROM 'root <root@socrates>' USER root
Jan 19 14:28:41.50: [29101]: <-- [14:AT+FCLASS=1.0\r]
Jan 19 14:28:41.51: [29101]: --> [2:OK]
Jan 19 14:28:41.51: [29101]: <-- [14:AT+F34=14,1,2\r]
Jan 19 14:28:41.52: [29101]: --> [2:OK]
Jan 19 14:28:41.52: [29101]: DIAL 19055551212
Jan 19 14:28:41.52: [29101]: <-- [18:ATX4DT19055551212\r]
Jan 19 14:28:59.26: [29101]: --> [10:NO CARRIER]
Jan 19 14:28:59.26: [29101]: SEND FAILED: JOB 317254 DEST 905-555-1212 
ERR No carrier detected
Jan 19 14:28:59.26: [29101]: SEND FAILED: JOB 317254 DEST 905-555-1212 
ERR No carrier detected; too many attempts to dial
Jan 19 14:28:59.26: [29101]: <-- [5:ATH0\r]
Jan 19 14:28:59.40: [29101]: --> [2:OK]
Jan 19 14:28:59.40: [29101]: SESSION END




So I was guessing it was modem init string issues and I figured I'm at 
the limit of my troubleshooting skills here, and so I started writing 
this e-mail.  The config file is below.  It has only local differences 
(i.e. phone number, area code, etc) from lucent-mt-10 v1.4, and (latest 
I have in my hylafax/config directory is v1.8) and the only difference 
from 1.4 to 1.8 is the removal of Class1TCFRecvHack:      yes

Soooo.....I tried commenting that line out in both modems' config (and 
restarting hylafax) and it worked on ttyS4, but not ttyS5.  But it only 
worked for 1 test and now both modems are back to "busy signal" so I'm 
not sure what I've learned, maybe that single success was only because 
one of the minicom tests left the modem in some better state.


# $Id: lucent-mt-10,v 1.4 2004/06/01 21:34:39 lhoward Exp $

#
# prototype config for MultiTech 5634-series modems using
# the Lucent/Agere chipset supporting V.34-Fax.
#

#
CountryCode:            1
AreaCode:               416
FAXNumber:              +1-416-555-4433
LongDistancePrefix:     1
InternationalPrefix:    011
DialStringRules:        etc/dialrules
ServerTracing:          0xffff
SessionTracing:         0xffff
RecvFileMode:           0644
LogFileMode:            0600
DeviceMode:             0600
RingsBeforeAnswer:      0
SpeakerVolume:          off
GettyArgs:              "-h %l dx_%s"
LocalIdentifier:        __
TagLineFont:            etc/lutRS18.pcf
TagLineFormat:          "From %%l|%c|Page %%P of %%T"
MaxRecvPages:           100
#

ModemType:              Class1.0        # use this to supply a hint
ModemRate:              57600           # must be at least as fast as 
the DCE-DCE communication
ModemFlowControl:       rtscts          # many firmwares have broken 
software flow control
Class1EnableV34Cmd:     AT+F34=14,1,2   # 33600-2400 primary, 2400-1200 
control
Class1TCFRecvHack:      yes             # avoid +FCERROR before TCF
Class1TMConnectDelay:   400             # counteract quick CONNECT response

# If your line supports Caller-ID, you may want to uncomment this...
# QualifyCID:           etc/cid         # you must create this file
# ModemResetCmds:       AT+VCID=1
# CIDNumber:            "NMBR="
# CIDName:              "NAME="

# Or with the MT5634ZBA-DID you'll probably need something like...
# ModemReadyCmds:       AT*DS1*DD0*DF1*DN4      # enable DTMF-DID, 
wink-start, 4 digits
# CIDNumber:            "DTMF"          # format of AT*DF1
# CIDNumberAnswerLength:        4       # four DTMF-DID digits then ATA

#ModemPriority:         64
ModemPriority:          164
ModemDialCmd:           ATX4DT%s




As I said, for 1 test, right after I removed the Class1TCFRecvHack line, 
a fax went through.  But I haven't been able to get that to happen 
again, neither with multiple attempts nor with trying to start/stop 
minicom to initialize the modem before hylafax takes it.  Anyway, here 
is the log of the one that worked:



Jan 19 14:50:52.39: [29615]: SESSION BEGIN 000230146 19055551212
Jan 19 14:50:52.39: [29615]: HylaFAX (tm) Version 4.2.5
Jan 19 14:50:52.39: [29615]: SEND FAX: JOB 317267 DEST 905-555-1212 
COMMID 000230146 DEVICE '/dev/ttyS4' FROM 'root <root@socrates>' USER root
Jan 19 14:50:52.39: [29615]: STATE CHANGE: RUNNING -> SENDING
Jan 19 14:50:52.39: [29615]: <-- [14:AT+FCLASS=1.0\r]
Jan 19 14:50:52.40: [29615]: --> [2:OK]
Jan 19 14:50:52.40: [29615]: <-- [14:AT+F34=14,1,2\r]
Jan 19 14:50:52.41: [29615]: --> [2:OK]
Jan 19 14:50:52.41: [29615]: DIAL 19055551212
Jan 19 14:50:52.41: [29615]: <-- [18:ATX4DT19055551212\r]
Jan 19 14:51:01.79: [29615]: --> [7:CONNECT]
Jan 19 14:51:03.01: [29615]: --> HDLC<16:FF C0 04 00 00 6A AA AA 00 31 
09 01 B5 60 D1 6A>
Jan 19 14:51:03.01: [29615]: --> [2:OK]
Jan 19 14:51:03.01: [29615]: REMOTE NSF "00 00 56 55 55 00 8C 90 80 AD 06"
Jan 19 14:51:03.01: [29615]: NSF remote fax equipment: Brother 
MFC-3100C/MFC-8600
Jan 19 14:51:03.01: [29615]: <-- [9:AT+FRH=3\r]
Jan 19 14:51:03.02: [29615]: --> [7:CONNECT]
Jan 19 14:51:03.71: [29615]: --> HDLC<25:FF C0 02 1C 1C 2C 0C 04 9C 8C 
CC 04 AC 0C 9C 04 04 04 04 04 04 04 04 3A 9E>
Jan 19 14:51:03.71: [29615]: --> [2:OK]
Jan 19 14:51:03.71: [29615]: REMOTE CSI "905 555 1212"
Jan 19 14:51:03.71: [29615]: <-- [9:AT+FRH=3\r]
Jan 19 14:51:03.72: [29615]: --> [7:CONNECT]
Jan 19 14:51:04.15: [29615]: --> HDLC<15:FF C8 01 04 77 15 23 01 89 01 
01 01 04 FF 0B>
Jan 19 14:51:04.15: [29615]: --> [2:OK]
Jan 19 14:51:04.15: [29615]: REMOTE best rate 33600 bit/s
Jan 19 14:51:04.15: [29615]: REMOTE max A4 page width (215 mm)
Jan 19 14:51:04.15: [29615]: REMOTE max unlimited page length
Jan 19 14:51:04.15: [29615]: REMOTE best vres 15.4 line/mm
Jan 19 14:51:04.15: [29615]: REMOTE format support: MH, MR, MMR, JBIG
Jan 19 14:51:04.15: [29615]: REMOTE supports T.30 Annex A, 256-byte ECM
Jan 19 14:51:04.15: [29615]: REMOTE best 10 ms/scanline
Jan 19 14:51:04.15: [29615]: USE 14400 bit/s
Jan 19 14:51:04.15: [29615]: USE error correction mode
Jan 19 14:51:04.15: [29615]: SEND file "docq/doc341915.ps;f1"
Jan 19 14:51:04.15: [29615]: USE A4 page width (215 mm)
Jan 19 14:51:04.15: [29615]: USE unlimited page length
Jan 19 14:51:04.15: [29615]: USE 7.7 line/mm
Jan 19 14:51:04.15: [29615]: USE 2-D MMR
Jan 19 14:51:04.15: [29615]: USE 0 ms/scanline
Jan 19 14:51:04.15: [29615]: SEND training at v.17 14400 bit/s
Jan 19 14:51:04.15: [29615]: <-- [9:AT+FRS=7\r]
Jan 19 14:51:04.27: [29615]: --> [2:OK]
Jan 19 14:51:04.27: [29615]: <-- [9:AT+FTH=3\r]
Jan 19 14:51:05.23: [29615]: --> [7:CONNECT]
Jan 19 14:51:05.23: [29615]: <-- HDLC<23:FF C0 C2 FA FA 04 04 04 04 04 
04 04 04 04 04 04 04 04 04 04 04 04 04>
Jan 19 14:51:05.23: [29615]: <-- data [23]
Jan 19 14:51:05.23: [29615]: <-- data [2]
Jan 19 14:51:05.24: [29615]: --> [7:CONNECT]
Jan 19 14:51:05.24: [29615]: <-- HDLC<7:FF C8 C1 00 46 1F 22>
Jan 19 14:51:05.24: [29615]: <-- data [7]
Jan 19 14:51:05.24: [29615]: <-- data [2]
Jan 19 14:51:06.44: [29615]: --> [2:OK]
Jan 19 14:51:06.44: [29615]: <-- [9:AT+FTS=7\r]
Jan 19 14:51:06.50: [29615]: --> [2:OK]
Jan 19 14:51:06.50: [29615]: <-- [11:AT+FTM=145\r]
Jan 19 14:51:06.51: [29615]: --> [7:CONNECT]
Jan 19 14:51:06.51: [29615]: DELAY 400 ms
Jan 19 14:51:06.91: [29615]: <-- data [1024]
Jan 19 14:51:06.91: [29615]: <-- data [1024]
Jan 19 14:51:06.91: [29615]: <-- data [652]
Jan 19 14:51:06.91: [29615]: <-- data [2]
Jan 19 14:51:09.68: [29615]: --> [2:OK]
Jan 19 14:51:09.68: [29615]: <-- [9:AT+FRH=3\r]
Jan 19 14:51:09.86: [29615]: --> [7:CONNECT]
Jan 19 14:51:11.06: [29615]: --> HDLC<5:FF C8 21 57 BE>
Jan 19 14:51:11.06: [29615]: --> [2:OK]
Jan 19 14:51:11.06: [29615]: TRAINING succeeded
Jan 19 14:51:11.06: [29615]: SEND begin page
Jan 19 14:51:11.15: [29615]: SEND EOFB
Jan 19 14:51:11.16: [29615]: SEND send frame number 0
[20 lines snipped]
Jan 19 14:51:11.18: [29615]: SEND send frame number 21
Jan 19 14:51:11.18: [29615]: DELAY 200 ms
Jan 19 14:51:11.38: [29615]: <-- [11:AT+FTM=146\r]
Jan 19 14:51:11.39: [29615]: --> [7:CONNECT]
Jan 19 14:51:11.39: [29615]: DELAY 400 ms
Jan 19 14:51:11.79: [29615]: <-- data [1028]
Jan 19 14:51:11.79: [29615]: <-- data [1030]
Jan 19 14:51:11.79: [29615]: <-- data [1031]
Jan 19 14:51:11.79: [29615]: <-- data [1033]
Jan 19 14:51:11.79: [29615]: <-- data [1033]
Jan 19 14:51:11.81: [29615]: <-- data [1033]
Jan 19 14:51:11.84: [29615]: <-- data [150]
Jan 19 14:51:11.84: [29615]: <-- data [2]
Jan 19 14:51:15.36: [29615]: --> [2:OK]
Jan 19 14:51:15.36: [29615]: <-- [9:AT+FTS=7\r]
Jan 19 14:51:15.42: [29615]: --> [2:OK]
Jan 19 14:51:15.42: [29615]: <-- [9:AT+FTH=3\r]
Jan 19 14:51:16.38: [29615]: --> [7:CONNECT]
Jan 19 14:51:16.38: [29615]: <-- HDLC<7:FF C8 FD F2 00 00 A8>
Jan 19 14:51:16.38: [29615]: <-- data [7]
Jan 19 14:51:16.38: [29615]: <-- data [2]
Jan 19 14:51:16.88: [29615]: --> [2:OK]
Jan 19 14:51:16.88: [29615]: SEND send PPS (partial page signal)
Jan 19 14:51:16.88: [29615]: SEND send MPS (more pages, same document)
Jan 19 14:51:16.88: [29615]: <-- [9:AT+FRH=3\r]
Jan 19 14:51:17.01: [29615]: --> [7:CONNECT]
Jan 19 14:51:18.21: [29615]: --> HDLC<5:FF C8 31 45 8F>
Jan 19 14:51:18.21: [29615]: --> [2:OK]
Jan 19 14:51:18.21: [29615]: SEND recv MCF (message confirmation)
Jan 19 14:51:18.21: [29615]: <-- [9:AT+FRS=7\r]
Jan 19 14:51:18.36: [29615]: --> [2:OK]
Jan 19 14:51:18.36: [29615]: SEND end page
Jan 19 14:51:18.37: [29615]: SEND FAX (000230146): FROM root@socrates TO 
905-555-1212 (page 1 of 2 sent in 0:14)
Jan 19 14:51:18.37: [29615]: SEND begin page
Jan 19 14:51:18.43: [29615]: SEND EOFB
Jan 19 14:51:18.43: [29615]: SEND send frame number 0
[13 lines snipped]
Jan 19 14:51:18.45: [29615]: SEND send frame number 14
Jan 19 14:51:18.45: [29615]: DELAY 200 ms
Jan 19 14:51:18.65: [29615]: <-- [11:AT+FTM=146\r]
Jan 19 14:51:18.66: [29615]: --> [7:CONNECT]
Jan 19 14:51:18.66: [29615]: DELAY 400 ms
Jan 19 14:51:19.06: [29615]: <-- data [1028]
Jan 19 14:51:19.06: [29615]: <-- data [1029]
Jan 19 14:51:19.06: [29615]: <-- data [1032]
Jan 19 14:51:19.06: [29615]: <-- data [1036]
Jan 19 14:51:19.06: [29615]: <-- data [316]
Jan 19 14:51:19.08: [29615]: <-- data [2]
Jan 19 14:51:21.60: [29615]: --> [2:OK]
Jan 19 14:51:21.60: [29615]: <-- [9:AT+FTS=9\r]
Jan 19 14:51:21.68: [29615]: --> [2:OK]
Jan 19 14:51:21.68: [29615]: <-- [9:AT+FTH=3\r]
Jan 19 14:51:22.64: [29615]: --> [7:CONNECT]
Jan 19 14:51:22.64: [29615]: <-- HDLC<7:FF C8 FD F4 80 00 70>
Jan 19 14:51:22.64: [29615]: <-- data [7]
Jan 19 14:51:22.64: [29615]: <-- data [2]
Jan 19 14:51:23.14: [29615]: --> [2:OK]
Jan 19 14:51:23.14: [29615]: SEND send PPS (partial page signal)
Jan 19 14:51:23.14: [29615]: SEND send EOP (no more pages or documents)
Jan 19 14:51:23.14: [29615]: <-- [9:AT+FRH=3\r]
Jan 19 14:51:23.30: [29615]: --> [7:CONNECT]
Jan 19 14:51:24.47: [29615]: --> HDLC<5:FF C8 31 45 8F>
Jan 19 14:51:24.47: [29615]: --> [2:OK]
Jan 19 14:51:24.47: [29615]: SEND recv MCF (message confirmation)
Jan 19 14:51:24.47: [29615]: <-- [9:AT+FRS=7\r]
Jan 19 14:51:24.62: [29615]: --> [2:OK]
Jan 19 14:51:24.62: [29615]: SEND end page
Jan 19 14:51:24.63: [29615]: SEND FAX (000230146): FROM root@socrates TO 
905-555-1212 (page 2 of 2 sent in 0:06)
Jan 19 14:51:24.63: [29615]: SEND FAX (000230146): FROM root@socrates TO 
905-555-1212 (docq/doc341915.ps;f1 sent in 0:20)
Jan 19 14:51:25.64: [29615]: <-- [9:AT+FTH=3\r]
Jan 19 14:51:26.60: [29615]: --> [7:CONNECT]
Jan 19 14:51:26.60: [29615]: <-- HDLC<3:FF C8 DF>
Jan 19 14:51:26.60: [29615]: <-- data [3]
Jan 19 14:51:26.60: [29615]: <-- data [2]
Jan 19 14:51:26.99: [29615]: --> [2:OK]
Jan 19 14:51:26.99: [29615]: MODEM input buffering enabled
Jan 19 14:51:26.99: [29615]: <-- [5:ATH0\r]
Jan 19 14:51:27.35: [29615]: --> [2:OK]
Jan 19 14:51:27.35: [29615]: MODEM set DTR OFF
Jan 19 14:51:27.35: [29615]: STATE CHANGE: SENDING -> MODEMWAIT (timeout 5)
Jan 19 14:51:27.35: [29615]: SESSION END


After that it mostly went back to "busy signal" on every attempt, with 
the occasional "no carrier" thrown in:


Jan 19 15:58:38.71: [31308]: SESSION BEGIN 000230185 19055551212
Jan 19 15:58:38.71: [31308]: HylaFAX (tm) Version 4.2.5
Jan 19 15:58:38.71: [31308]: SEND FAX: JOB 317303 DEST 905-555-1212 
COMMID 000230185 DEVICE '/dev/ttyS4' FROM 'green <green>' USER green
Jan 19 15:58:38.71: [31308]: STATE CHANGE: RUNNING -> SENDING
Jan 19 15:58:38.72: [31308]: <-- [14:AT+FCLASS=1.0\r]
Jan 19 15:58:38.72: [31308]: --> [2:OK]
Jan 19 15:58:38.72: [31308]: <-- [14:AT+F34=14,1,2\r]
Jan 19 15:58:38.73: [31308]: --> [2:OK]
Jan 19 15:58:38.74: [31308]: DIAL 19055551212
Jan 19 15:58:38.74: [31308]: <-- [18:ATX4DT19055551212\r]
Jan 19 15:58:56.45: [31308]: --> [10:NO CARRIER]
Jan 19 15:58:56.45: [31308]: SEND FAILED: JOB 317303 DEST 905-555-1212 
ERR No carrier detected
Jan 19 15:58:56.45: [31308]: <-- [5:ATH0\r]
Jan 19 15:58:56.59: [31308]: --> [2:OK]
Jan 19 15:58:56.59: [31308]: MODEM set DTR OFF
Jan 19 15:58:56.59: [31308]: STATE CHANGE: SENDING -> MODEMWAIT (timeout 5)
Jan 19 15:58:56.59: [31308]: SESSION END




for ttyS4:
ati0
LT V.92 1.0 MT5634ZPX-PCI-U Internal Data/Fax/Voice Modem Version 1.32i


for ttyS5:
ati0
LT V.92 1.0 MT5634ZPX-PCI-U Internal Data/Fax/Voice Modem Version 1.32f


So I started to think the firmware was the difference that allowed ttyS4 
to succeed, albeit only once  (plus having removed the ...Hack... line 
from both of their configs, plus some other unknown.).  So question 
number one is does anyone know how to update these modems' firmware in 
Linux?  I googled and looked at the MultiTech website briefly but didn't 
get far.



And !#@$#%$^%&^*&!  I haven't touched it (haven't changed configs or 
used minicom) in over an hour, I just watched more attempts (still being 
generated by our back end processes) fail and fail and just now (4:30 pm 
if you're watching the log file timestamps above) 3 faxes went through! 
(probably batched on a single successful call).

Bloody hell I wish this was at least reproducible!

So here's the one that worked (no minicom activity in the last hour or 
two, and many sendfax retries in that time), and it was on ttyS5 (where 
the only previous success was ttyS4).  Sheesh.

Jan 19 16:33:35.51: [31989]: SESSION BEGIN 000230199 19055551212
Jan 19 16:33:35.51: [31989]: HylaFAX (tm) Version 4.2.5
Jan 19 16:33:35.51: [31989]: SEND FAX: JOB 317317 DEST 905-555-1212 
COMMID 000230199 DEVICE '/dev/ttyS5' FROM 'green <green>' USER green
Jan 19 16:33:35.51: [31989]: <-- [14:AT+FCLASS=1.0\r]
Jan 19 16:33:35.52: [31989]: --> [2:OK]
Jan 19 16:33:35.52: [31989]: <-- [14:AT+F34=14,1,2\r]
Jan 19 16:33:35.53: [31989]: --> [2:OK]
Jan 19 16:33:35.54: [31989]: DIAL 19055551212
Jan 19 16:33:35.54: [31989]: <-- [18:ATX4DT19055551212\r]
Jan 19 16:33:44.96: [31989]: --> [7:CONNECT]
Jan 19 16:33:46.20: [31989]: --> [2:OK]
Jan 19 16:33:46.20: [31989]: REMOTE NSF "00 00 56 55 55 00 8C 90 80 AC 06"
Jan 19 16:33:46.20: [31989]: NSF remote fax equipment: Brother 
MFC-3100C/MFC-8600
Jan 19 16:33:46.20: [31989]: <-- [9:AT+FRH=3\r]
Jan 19 16:33:46.21: [31989]: --> [7:CONNECT]
Jan 19 16:33:46.90: [31989]: --> [2:OK]
Jan 19 16:33:46.90: [31989]: REMOTE CSI "905 555 1212"
Jan 19 16:33:46.90: [31989]: <-- [9:AT+FRH=3\r]
Jan 19 16:33:46.91: [31989]: --> [7:CONNECT]
Jan 19 16:33:47.34: [31989]: --> [2:OK]
Jan 19 16:33:47.34: [31989]: REMOTE best rate 33600 bit/s
Jan 19 16:33:47.34: [31989]: REMOTE max A4 page width (215 mm)
Jan 19 16:33:47.34: [31989]: REMOTE max unlimited page length
Jan 19 16:33:47.34: [31989]: REMOTE best vres 15.4 line/mm
Jan 19 16:33:47.34: [31989]: REMOTE format support: MH, MR, MMR, JBIG
Jan 19 16:33:47.34: [31989]: REMOTE supports T.30 Annex A, 256-byte ECM
Jan 19 16:33:47.34: [31989]: REMOTE best 10 ms/scanline
Jan 19 16:33:47.34: [31989]: USE 14400 bit/s
Jan 19 16:33:47.34: [31989]: USE error correction mode
Jan 19 16:33:47.34: [31989]: SEND file "docq/doc341965.ps;f1"
Jan 19 16:33:47.35: [31989]: USE A4 page width (215 mm)
Jan 19 16:33:47.35: [31989]: USE unlimited page length
Jan 19 16:33:47.35: [31989]: USE 7.7 line/mm
Jan 19 16:33:47.35: [31989]: USE 2-D MMR
Jan 19 16:33:47.35: [31989]: USE 0 ms/scanline
Jan 19 16:33:47.35: [31989]: SEND training at v.17 14400 bit/s
Jan 19 16:33:47.35: [31989]: <-- [9:AT+FRS=7\r]
Jan 19 16:33:47.45: [31989]: --> [2:OK]
Jan 19 16:33:47.45: [31989]: <-- [9:AT+FTH=3\r]
Jan 19 16:33:48.41: [31989]: --> [7:CONNECT]
Jan 19 16:33:48.41: [31989]: <-- data [23]
Jan 19 16:33:48.41: [31989]: <-- data [2]
Jan 19 16:33:48.42: [31989]: --> [7:CONNECT]
Jan 19 16:33:48.42: [31989]: <-- data [7]
Jan 19 16:33:48.42: [31989]: <-- data [2]
Jan 19 16:33:49.62: [31989]: --> [2:OK]
Jan 19 16:33:49.62: [31989]: <-- [9:AT+FTS=7\r]
Jan 19 16:33:49.68: [31989]: --> [2:OK]
Jan 19 16:33:49.68: [31989]: <-- [11:AT+FTM=145\r]
Jan 19 16:33:49.69: [31989]: --> [7:CONNECT]
Jan 19 16:33:49.69: [31989]: DELAY 400 ms
Jan 19 16:33:50.09: [31989]: <-- data [1024]
Jan 19 16:33:50.09: [31989]: <-- data [1024]
Jan 19 16:33:50.09: [31989]: <-- data [652]
Jan 19 16:33:50.09: [31989]: <-- data [2]
Jan 19 16:33:52.87: [31989]: --> [2:OK]
Jan 19 16:33:52.87: [31989]: <-- [9:AT+FRH=3\r]
Jan 19 16:33:53.08: [31989]: --> [7:CONNECT]
Jan 19 16:33:54.25: [31989]: --> [2:OK]
Jan 19 16:33:54.25: [31989]: TRAINING succeeded
Jan 19 16:33:54.25: [31989]: SEND begin page
Jan 19 16:33:54.62: [31989]: SEND EOFB
Jan 19 16:33:54.63: [31989]: SEND send frame number 0
Jan 19 16:33:54.63: [31989]: SEND send frame number 1
Jan 19 16:33:54.63: [31989]: SEND send frame number 2
Jan 19 16:33:54.63: [31989]: SEND send frame number 3
...
Jan 19 16:33:54.78: [31989]: SEND send frame number 123
Jan 19 16:33:54.78: [31989]: DELAY 200 ms
Jan 19 16:33:54.97: [31989]: <-- [11:AT+FTM=146\r]
Jan 19 16:33:54.98: [31989]: --> [7:CONNECT]
Jan 19 16:33:54.98: [31989]: DELAY 400 ms
Jan 19 16:33:55.38: [31989]: <-- data [1028]
Jan 19 16:33:55.38: [31989]: <-- data [1027]
Jan 19 16:33:55.38: [31989]: <-- data [1027]
Jan 19 16:33:55.38: [31989]: <-- data [1029]
Jan 19 16:33:55.38: [31989]: <-- data [1031]
Jan 19 16:33:55.40: [31989]: <-- data [1027]
...
Jan 19 16:34:08.53: [31989]: <-- data [1026]
Jan 19 16:34:09.09: [31989]: <-- data [1024]
Jan 19 16:34:09.65: [31989]: <-- data [1026]
Jan 19 16:34:10.20: [31989]: <-- data [787]
Jan 19 16:34:10.60: [31989]: <-- data [2]
Jan 19 16:34:14.11: [31989]: --> [2:OK]
Jan 19 16:34:14.11: [31989]: <-- [9:AT+FTS=9\r]
Jan 19 16:34:14.19: [31989]: --> [2:OK]
Jan 19 16:34:14.19: [31989]: <-- [9:AT+FTH=3\r]
Jan 19 16:34:15.15: [31989]: --> [7:CONNECT]
Jan 19 16:34:15.15: [31989]: <-- data [7]
Jan 19 16:34:15.15: [31989]: <-- data [2]
Jan 19 16:34:15.65: [31989]: --> [2:OK]
Jan 19 16:34:15.65: [31989]: SEND send PPS (partial page signal)
Jan 19 16:34:15.65: [31989]: SEND send EOM (more documents)
Jan 19 16:34:15.65: [31989]: <-- [9:AT+FRH=3\r]
Jan 19 16:34:15.79: [31989]: --> [7:CONNECT]
Jan 19 16:34:16.98: [31989]: --> [2:OK]
Jan 19 16:34:16.98: [31989]: SEND recv MCF (message confirmation)
Jan 19 16:34:16.98: [31989]: <-- [9:AT+FRS=7\r]
Jan 19 16:34:17.13: [31989]: --> [2:OK]
Jan 19 16:34:17.13: [31989]: SEND end page
Jan 19 16:34:17.14: [31989]: SEND FAX (000230199): FROM green TO 
905-555-1212 (page 1 of 1 sent in 0:30)
Jan 19 16:34:17.14: [31989]: SEND FAX (000230199): FROM green TO 
905-555-1212 (docq/doc341965.ps;f1 sent in 0:30)
Jan 19 16:34:18.16: [31989]: SEND FAX: JOB 317314 DEST 905-555-1212 
COMMID 000230199 DEVICE '/dev/ttyS5' FROM 'green <green>' USER green
Jan 19 16:34:18.16: [31989]: <-- [9:AT+FRH=3\r]
Jan 19 16:34:23.12: [31989]: --> [7:CONNECT]
Jan 19 16:34:24.61: [31989]: --> [2:OK]
Jan 19 16:34:24.61: [31989]: REMOTE NSF "00 00 56 55 55 00 8C 90 80 53 06"
Jan 19 16:34:24.61: [31989]: NSF remote fax equipment: Brother 
MFC-3100C/MFC-8600
Jan 19 16:34:24.61: [31989]: <-- [9:AT+FRH=3\r]
Jan 19 16:34:24.62: [31989]: --> [7:CONNECT]
Jan 19 16:34:25.31: [31989]: --> [2:OK]
Jan 19 16:34:25.31: [31989]: REMOTE CSI "905 555 1212"
Jan 19 16:34:25.31: [31989]: <-- [9:AT+FRH=3\r]
Jan 19 16:34:25.32: [31989]: --> [7:CONNECT]
Jan 19 16:34:25.74: [31989]: --> [2:OK]
Jan 19 16:34:25.74: [31989]: REMOTE best rate 33600 bit/s
Jan 19 16:34:25.74: [31989]: REMOTE max A4 page width (215 mm)
Jan 19 16:34:25.74: [31989]: REMOTE max unlimited page length
Jan 19 16:34:25.74: [31989]: REMOTE best vres 15.4 line/mm
...
Jan 19 16:34:25.74: [31989]: REMOTE format support: MH, MR, MMR, JBIG
Jan 19 16:34:25.74: [31989]: REMOTE supports T.30 Annex A, 256-byte ECM
Jan 19 16:34:25.74: [31989]: REMOTE best 10 ms/scanline
Jan 19 16:34:25.74: [31989]: USE 14400 bit/s
Jan 19 16:34:25.74: [31989]: USE error correction mode
Jan 19 16:34:25.74: [31989]: SEND file "docq/doc341962.ps;f1"
Jan 19 16:34:25.74: [31989]: USE A4 page width (215 mm)
Jan 19 16:34:25.75: [31989]: USE unlimited page length
Jan 19 16:34:25.75: [31989]: USE 7.7 line/mm
Jan 19 16:34:25.75: [31989]: USE 2-D MMR
Jan 19 16:34:25.75: [31989]: USE 0 ms/scanline
Jan 19 16:34:25.75: [31989]: SEND training at v.17 14400 bit/s
Jan 19 16:34:25.75: [31989]: <-- [9:AT+FRS=7\r]
Jan 19 16:34:25.86: [31989]: --> [2:OK]
Jan 19 16:34:25.86: [31989]: <-- [9:AT+FTH=3\r]
Jan 19 16:34:26.82: [31989]: --> [7:CONNECT]
Jan 19 16:34:26.82: [31989]: <-- data [23]
Jan 19 16:34:26.82: [31989]: <-- data [2]
Jan 19 16:34:26.83: [31989]: --> [7:CONNECT]
Jan 19 16:34:26.83: [31989]: <-- data [7]
Jan 19 16:34:26.83: [31989]: <-- data [2]
Jan 19 16:34:28.03: [31989]: --> [2:OK]
Jan 19 16:34:28.03: [31989]: <-- [9:AT+FTS=7\r]
Jan 19 16:34:28.09: [31989]: --> [2:OK]
Jan 19 16:34:28.09: [31989]: <-- [11:AT+FTM=145\r]
Jan 19 16:34:28.10: [31989]: --> [7:CONNECT]
Jan 19 16:34:28.10: [31989]: DELAY 400 ms
Jan 19 16:34:28.50: [31989]: <-- data [1024]
Jan 19 16:34:28.50: [31989]: <-- data [1024]
Jan 19 16:34:28.50: [31989]: <-- data [652]
Jan 19 16:34:28.50: [31989]: <-- data [2]
Jan 19 16:34:31.27: [31989]: --> [2:OK]
Jan 19 16:34:31.27: [31989]: <-- [9:AT+FRH=3\r]
Jan 19 16:34:31.46: [31989]: --> [7:CONNECT]
Jan 19 16:34:32.65: [31989]: --> [2:OK]
Jan 19 16:34:32.65: [31989]: TRAINING succeeded
Jan 19 16:34:32.65: [31989]: SEND begin page
Jan 19 16:34:33.05: [31989]: SEND EOFB
Jan 19 16:34:33.05: [31989]: SEND send frame number 0
Jan 19 16:34:33.05: [31989]: SEND send frame number 1
Jan 19 16:34:33.05: [31989]: SEND send frame number 2
...
Jan 19 16:34:33.21: [31989]: SEND send frame number 129
Jan 19 16:34:33.21: [31989]: SEND send frame number 130
Jan 19 16:34:33.21: [31989]: DELAY 200 ms
Jan 19 16:34:33.40: [31989]: <-- [11:AT+FTM=146\r]
Jan 19 16:34:33.41: [31989]: --> [7:CONNECT]
Jan 19 16:34:33.41: [31989]: DELAY 400 ms
Jan 19 16:34:33.81: [31989]: <-- data [1028]
Jan 19 16:34:33.81: [31989]: <-- data [1028]
Jan 19 16:34:33.81: [31989]: <-- data [1027]
...
Jan 19 16:34:49.23: [31989]: <-- data [1026]
Jan 19 16:34:49.79: [31989]: <-- data [634]
Jan 19 16:34:50.11: [31989]: <-- data [2]
Jan 19 16:34:53.59: [31989]: --> [2:OK]
Jan 19 16:34:53.59: [31989]: <-- [9:AT+FTS=9\r]
Jan 19 16:34:53.67: [31989]: --> [2:OK]
Jan 19 16:34:53.67: [31989]: <-- [9:AT+FTH=3\r]
Jan 19 16:34:54.63: [31989]: --> [7:CONNECT]
Jan 19 16:34:54.63: [31989]: <-- data [7]
Jan 19 16:34:54.63: [31989]: <-- data [2]
Jan 19 16:34:55.13: [31989]: --> [2:OK]
Jan 19 16:34:55.13: [31989]: SEND send PPS (partial page signal)
Jan 19 16:34:55.13: [31989]: SEND send EOM (more documents)
Jan 19 16:34:55.13: [31989]: <-- [9:AT+FRH=3\r]
Jan 19 16:34:55.26: [31989]: --> [7:CONNECT]
Jan 19 16:34:56.46: [31989]: --> [2:OK]
Jan 19 16:34:56.46: [31989]: SEND recv MCF (message confirmation)
Jan 19 16:34:56.46: [31989]: <-- [9:AT+FRS=7\r]
Jan 19 16:34:56.61: [31989]: --> [2:OK]
Jan 19 16:34:56.61: [31989]: SEND end page
Jan 19 16:34:56.62: [31989]: SEND FAX (000230199): FROM green TO 
905-555-1212 (page 1 of 1 sent in 0:31)
Jan 19 16:34:56.62: [31989]: SEND FAX (000230199): FROM green TO 
905-555-1212 (docq/doc341962.ps;f1 sent in 0:31)
Jan 19 16:34:57.64: [31989]: SEND FAX: JOB 317316 DEST 905-555-1212 
COMMID 000230199 DEVICE '/dev/ttyS5' FROM 'green <green>' USER green
Jan 19 16:34:57.64: [31989]: <-- [9:AT+FRH=3\r]
Jan 19 16:35:02.59: [31989]: --> [7:CONNECT]
Jan 19 16:35:04.08: [31989]: --> [2:OK]
Jan 19 16:35:04.08: [31989]: REMOTE NSF "00 00 56 55 55 00 8C 90 80 52 06"
Jan 19 16:35:04.08: [31989]: NSF remote fax equipment: Brother 
MFC-3100C/MFC-8600
Jan 19 16:35:04.08: [31989]: <-- [9:AT+FRH=3\r]
Jan 19 16:35:04.09: [31989]: --> [7:CONNECT]
Jan 19 16:35:04.79: [31989]: --> [2:OK]
Jan 19 16:35:04.79: [31989]: REMOTE CSI "905 555 1212"
Jan 19 16:35:04.79: [31989]: <-- [9:AT+FRH=3\r]
Jan 19 16:35:04.80: [31989]: --> [7:CONNECT]
Jan 19 16:35:05.22: [31989]: --> [2:OK]
Jan 19 16:35:05.22: [31989]: REMOTE best rate 33600 bit/s
Jan 19 16:35:05.22: [31989]: REMOTE max A4 page width (215 mm)
Jan 19 16:35:05.22: [31989]: REMOTE max unlimited page length
Jan 19 16:35:05.22: [31989]: REMOTE best vres 15.4 line/mm
Jan 19 16:35:05.22: [31989]: REMOTE format support: MH, MR, MMR, JBIG
Jan 19 16:35:05.22: [31989]: REMOTE supports T.30 Annex A, 256-byte ECM
Jan 19 16:35:05.22: [31989]: REMOTE best 10 ms/scanline
Jan 19 16:35:05.22: [31989]: USE 14400 bit/s
Jan 19 16:35:05.22: [31989]: USE error correction mode
Jan 19 16:35:05.22: [31989]: SEND file "docq/doc341964.ps;f1"
Jan 19 16:35:05.22: [31989]: USE A4 page width (215 mm)
Jan 19 16:35:05.23: [31989]: USE unlimited page length
Jan 19 16:35:05.23: [31989]: USE 7.7 line/mm
Jan 19 16:35:05.23: [31989]: USE 2-D MMR
Jan 19 16:35:05.23: [31989]: USE 0 ms/scanline
Jan 19 16:35:05.23: [31989]: SEND training at v.17 14400 bit/s
Jan 19 16:35:05.23: [31989]: <-- [9:AT+FRS=7\r]
Jan 19 16:35:05.34: [31989]: --> [2:OK]
Jan 19 16:35:05.34: [31989]: <-- [9:AT+FTH=3\r]
Jan 19 16:35:06.30: [31989]: --> [7:CONNECT]
Jan 19 16:35:06.30: [31989]: <-- data [23]
Jan 19 16:35:06.30: [31989]: <-- data [2]
Jan 19 16:35:06.31: [31989]: --> [7:CONNECT]
Jan 19 16:35:06.31: [31989]: <-- data [7]
Jan 19 16:35:06.31: [31989]: <-- data [2]
Jan 19 16:35:07.51: [31989]: --> [2:OK]
Jan 19 16:35:07.51: [31989]: <-- [9:AT+FTS=7\r]
Jan 19 16:35:07.57: [31989]: --> [2:OK]
Jan 19 16:35:07.57: [31989]: <-- [11:AT+FTM=145\r]
Jan 19 16:35:07.58: [31989]: --> [7:CONNECT]
Jan 19 16:35:07.58: [31989]: DELAY 400 ms
Jan 19 16:35:07.98: [31989]: <-- data [1024]
Jan 19 16:35:07.98: [31989]: <-- data [1024]
Jan 19 16:35:07.98: [31989]: <-- data [652]
Jan 19 16:35:07.98: [31989]: <-- data [2]
Jan 19 16:35:10.75: [31989]: --> [2:OK]
Jan 19 16:35:10.75: [31989]: <-- [9:AT+FRH=3\r]
Jan 19 16:35:10.96: [31989]: --> [7:CONNECT]
Jan 19 16:35:12.13: [31989]: --> [2:OK]
Jan 19 16:35:12.13: [31989]: TRAINING succeeded
Jan 19 16:35:12.13: [31989]: SEND begin page
Jan 19 16:35:12.50: [31989]: SEND EOFB
Jan 19 16:35:12.50: [31989]: SEND send frame number 0
Jan 19 16:35:12.50: [31989]: SEND send frame number 1
Jan 19 16:35:12.50: [31989]: SEND send frame number 2
...
Jan 19 16:35:12.65: [31989]: SEND send frame number 121
Jan 19 16:35:12.65: [31989]: SEND send frame number 122
Jan 19 16:35:12.65: [31989]: DELAY 200 ms
Jan 19 16:35:12.84: [31989]: <-- [11:AT+FTM=146\r]
Jan 19 16:35:12.85: [31989]: --> [7:CONNECT]
Jan 19 16:35:12.85: [31989]: DELAY 400 ms
Jan 19 16:35:13.25: [31989]: <-- data [1027]
Jan 19 16:35:13.25: [31989]: <-- data [1030]
Jan 19 16:35:13.25: [31989]: <-- data [1027]
...
Jan 19 16:35:26.94: [31989]: <-- data [1028]
Jan 19 16:35:27.50: [31989]: <-- data [1027]
Jan 19 16:35:28.06: [31989]: <-- data [510]
Jan 19 16:35:28.30: [31989]: <-- data [2]
Jan 19 16:35:31.81: [31989]: --> [2:OK]
Jan 19 16:35:31.81: [31989]: <-- [9:AT+FTS=9\r]
Jan 19 16:35:31.89: [31989]: --> [2:OK]
Jan 19 16:35:31.89: [31989]: <-- [9:AT+FTH=3\r]
Jan 19 16:35:32.85: [31989]: --> [7:CONNECT]
Jan 19 16:35:32.85: [31989]: <-- data [7]
Jan 19 16:35:32.85: [31989]: <-- data [2]
Jan 19 16:35:33.35: [31989]: --> [2:OK]
Jan 19 16:35:33.35: [31989]: SEND send PPS (partial page signal)
Jan 19 16:35:33.35: [31989]: SEND send EOP (no more pages or documents)
Jan 19 16:35:33.35: [31989]: <-- [9:AT+FRH=3\r]
Jan 19 16:35:33.50: [31989]: --> [7:CONNECT]
Jan 19 16:35:34.68: [31989]: --> [2:OK]
Jan 19 16:35:34.68: [31989]: SEND recv MCF (message confirmation)
Jan 19 16:35:34.68: [31989]: <-- [9:AT+FRS=7\r]
Jan 19 16:35:34.83: [31989]: --> [2:OK]
Jan 19 16:35:34.83: [31989]: SEND end page
Jan 19 16:35:34.84: [31989]: SEND FAX (000230199): FROM green TO 
905-555-1212 (page 1 of 1 sent in 0:29)
Jan 19 16:35:34.84: [31989]: SEND FAX (000230199): FROM green TO 
905-555-1212 (docq/doc341964.ps;f1 sent in 0:29)
Jan 19 16:35:35.85: [31989]: <-- [9:AT+FTH=3\r]
Jan 19 16:35:36.81: [31989]: --> [7:CONNECT]
Jan 19 16:35:36.81: [31989]: <-- data [3]
Jan 19 16:35:36.81: [31989]: <-- data [2]
Jan 19 16:35:37.20: [31989]: --> [2:OK]
Jan 19 16:35:37.20: [31989]: <-- [5:ATH0\r]
Jan 19 16:35:37.56: [31989]: --> [2:OK]
Jan 19 16:35:37.56: [31989]: SESSION END



So question 2 is, uhh,  suggestions?


Thanks in advance for you patience, anyone that's read this far in this 
long message.

Regards.

Martin




-------------------------------------------------------------------------
Take Surveys. Earn Cash. Influence the Future of IT
Join SourceForge.net's Techsay panel and you'll get the chance to share your
opinions on IT & business topics through brief surveys - and earn cash
http://www.techsay.com/default.php?page=join.php&p=sourceforge&CID=DEVDEV
_______________________________________________
hylafax-users mailing list
hylafax-users@xxxxxxxxxxxxxxxxxxxxx
https://lists.sourceforge.net/lists/listinfo/hylafax-users



____________________ 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*