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

To: Hylafax-Users <hylafax-users@xxxxxxxxxxx>, hylafax-users@xxxxxxxxxxxxxxxxxxxxx
Subject: [hylafax-users] hang-up noise when sending to certain destination
From: marthter <marthter@xxxxxxxx>
Date: Fri, 19 Jan 2007 17:45:21 -0500

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


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

Follow-Ups:
- Re: [hylafax-users] hang-up noise when sending to certain destination
  - From: andrew rinaldi
- Re: [hylafax-users] hang-up noise when sending to certain destination
  - From: Lee Howard

Prev by Date: [hylafax-users] doubt in HylaFAX
Next by Date: Re: [hylafax-users] hang-up noise when sending to certain destination
Previous by thread: Re: [hylafax-users] dialing prefix for outside line ?
Next by thread: Re: [hylafax-users] hang-up noise when sending to certain destination
Index(es):
- Main
- Thread

Project hosted by iFAX Solutions