Re: [hylafax-users] Rockwell modem cards in Lucent TNT

[Tracy R Reed <treed@xxxxxxxxxxxxxxxxxxxxx>]

On Tue, Aug 24, 2004 at 04:20:48PM -0700, Lee Howard spake thusly:
> Basically HylaFAX sends training at all of the various speeds and never 
> gets the CFR signal from the receiver.  It's hard to explain why this 
> would be the case unless the line connection between endpoints is just 
> horribly poor.  Is there anything that the receiver is giving out as 
> clues as to what happened?  Does this happen to all destinations?

The destination fax receives just fine from everyone else and the TNT is
plugged into a DS-3 so it's all digital on that end so the line has got to
be decent. The receiver is not saying anything. It gets the connect, says
receiving fax, then drops it and goes back to displaying the time/date. I
just reconfigured my modem with faxaddmodem (saving off the other config
file) as a class 2 modem and it was able to negotiate a speed properly and
send the fax. However, it was not able to terminate the fax properly. The
attached c00011658 has a log of a fax transmission in class 2. If nothing
else this might show that the line between them is good and they should be
able to negotiate a connection. This does not happen to all destinations,
but it does happen to around 25% of the machines we try to fax to. Another
office has a Windows based fax to email system and we fax to them just
fine using this setup but lots of locations are unable to negotiate a
connection.

A separate issue: I just restarted faxgetty with a kill with a job in the
queue and I saw this in the log:

Aug 24 16:20:59 lnx FaxSend[11006]: /dev/t/dev/ttya0: Can not open modem
(No such file or directory)

I have been noticing this occasionally for weeks. I'm sure I have the
device named correctly everywhere in my configs, I don't know where this
mangled device path is coming from.

-- 
Tracy Reed                     The attachment is a digital signature.
http://copilotconsulting.com   More info: http://copilotconsulting.com/sig

Aug 24 16:32:15.63: [11977]: SESSION BEGIN 00011658 16196717023
Aug 24 16:32:15.63: [11977]: HylaFAX (tm) Version 4.1.8
Aug 24 16:32:15.63: [11977]: SEND FAX: JOB 17923 DEST 16196717023 COMMID 00011658 DEVICE '/dev/ttya0'
Aug 24 16:32:15.63: [11977]: MODEM set DTR OFF
Aug 24 16:32:15.63: [11977]: MODEM set baud rate: 0 baud (flow control unchanged)
Aug 24 16:32:15.63: [11977]: DELAY 75 ms
Aug 24 16:32:15.71: [11977]: MODEM set DTR ON
Aug 24 16:32:15.71: [11977]: DELAY 2600 ms
Aug 24 16:32:19.31: [11977]: MODEM set baud rate: 19200 baud, input flow RTS/CTS, output flow RTS/CTS
Aug 24 16:32:19.31: [11977]: DELAY 10 ms
Aug 24 16:32:19.32: [11977]: MODEM flush i/o
Aug 24 16:32:19.32: [11977]: <-- [4:ATZ\r]
Aug 24 16:32:21.64: [11977]: --> [2:OK]
Aug 24 16:32:21.64: [11977]: DELAY 3000 ms
Aug 24 16:32:24.64: [11977]: <-- [5:ATE0\r]
Aug 24 16:32:24.94: [11977]: --> [4:ATE0]
Aug 24 16:32:24.94: [11977]: --> [2:OK]
Aug 24 16:32:24.94: [11977]: <-- [5:ATV1\r]
Aug 24 16:32:25.24: [11977]: --> [2:OK]
Aug 24 16:32:25.24: [11977]: <-- [5:ATQ0\r]
Aug 24 16:32:25.54: [11977]: --> [2:OK]
Aug 24 16:32:25.54: [11977]: <-- [7:ATS0=0\r]
Aug 24 16:32:25.84: [11977]: --> [2:OK]
Aug 24 16:32:25.84: [11977]: <-- [7:ATS8=2\r]
Aug 24 16:32:26.14: [11977]: --> [2:OK]
Aug 24 16:32:26.14: [11977]: <-- [8:ATS7=60\r]
Aug 24 16:32:26.44: [11977]: --> [2:OK]
Aug 24 16:32:26.44: [11977]: <-- [6:AT&K3\r]
Aug 24 16:32:26.74: [11977]: --> [2:OK]
Aug 24 16:32:26.74: [11977]: <-- [6:AT&D3\r]
Aug 24 16:32:27.04: [11977]: --> [2:OK]
Aug 24 16:32:27.04: [11977]: <-- [6:AT&C1\r]
Aug 24 16:32:27.34: [11977]: --> [2:OK]
Aug 24 16:32:27.34: [11977]: <-- [12:AT+FCLASS=2\r]
Aug 24 16:32:27.64: [11977]: --> [2:OK]
Aug 24 16:32:27.64: [11977]: <-- [10:AT+FTBC=0\r]
Aug 24 16:32:27.94: [11977]: --> [5:ERROR]
Aug 24 16:32:27.94: [11977]: MODEM Command error
Aug 24 16:32:27.94: [11977]: <-- [10:AT+FBOR=0\r]
Aug 24 16:32:28.24: [11977]: --> [2:OK]
Aug 24 16:32:28.24: [11977]: <-- [13:AT+FPHCTO=30\r]
Aug 24 16:32:28.54: [11977]: --> [2:OK]
Aug 24 16:32:28.54: [11977]: <-- [10:AT+FBUG=1\r]
Aug 24 16:32:28.84: [11977]: --> [2:OK]
Aug 24 16:32:28.84: [11977]: <-- [24:AT+FDCC=1,5,2,2,2,0,0,0\r]
Aug 24 16:32:29.14: [11977]: --> [2:OK]
Aug 24 16:32:29.14: [11977]: <-- [7:ATL0M1\r]
Aug 24 16:32:29.44: [11977]: --> [2:OK]
Aug 24 16:32:29.44: [11977]: STATE CHANGE: RUNNING -> SENDING
Aug 24 16:32:29.44: [11977]: MODEM input buffering enabled
Aug 24 16:32:29.44: [11977]: <-- [12:AT+FCLASS=2\r]
Aug 24 16:32:29.84: [11977]: --> [2:OK]
Aug 24 16:32:29.84: [11977]: <-- [10:AT+FTBC=0\r]
Aug 24 16:32:30.24: [11977]: --> [5:ERROR]
Aug 24 16:32:30.24: [11977]: MODEM Command error
Aug 24 16:32:30.24: [11977]: <-- [10:AT+FBOR=0\r]
Aug 24 16:32:30.64: [11977]: --> [2:OK]
Aug 24 16:32:30.64: [11977]: <-- [13:AT+FPHCTO=30\r]
Aug 24 16:32:31.04: [11977]: --> [2:OK]
Aug 24 16:32:31.04: [11977]: <-- [10:AT+FBUG=1\r]
Aug 24 16:32:31.44: [11977]: --> [2:OK]
Aug 24 16:32:31.44: [11977]: <-- [24:AT+FDCC=1,5,2,2,2,0,0,0\r]
Aug 24 16:32:31.84: [11977]: --> [2:OK]
Aug 24 16:32:31.84: [11977]: <-- [21:AT+FLID="8667277732"\r]
Aug 24 16:32:32.24: [11977]: --> [2:OK]
Aug 24 16:32:32.24: [11977]: DIAL 16196717023
Aug 24 16:32:32.24: [11977]: <-- [16:ATDT16196717023\r]
Aug 24 16:32:46.35: [11977]: --> [5:+FCON]
Aug 24 16:32:48.35: [11977]: --> [29:+FCSI: "         16196717023"]
Aug 24 16:32:48.35: [11977]: REMOTE CSI "16196717023"
Aug 24 16:32:48.65: [11977]: --> [22:+FDIS: 1,3,0,2,0,0,0,4]
Aug 24 16:32:48.65: [11977]: --> [2:OK]
Aug 24 16:32:48.65: [11977]: REMOTE best rate 9600 bit/s
Aug 24 16:32:48.65: [11977]: REMOTE max page width 1728 pixels in 215 mm
Aug 24 16:32:48.65: [11977]: REMOTE max unlimited page length
Aug 24 16:32:48.65: [11977]: REMOTE best vres 7.7 line/mm
Aug 24 16:32:48.65: [11977]: REMOTE best format 1-D MR
Aug 24 16:32:48.65: [11977]: REMOTE best 20 ms, 10 ms/scanline
Aug 24 16:32:48.65: [11977]: USE 2400 bit/s
Aug 24 16:32:48.65: [11977]: USE 20 ms, 10 ms/scanline
Aug 24 16:32:48.65: [11977]: SEND file "docq/doc45.tif;30"
Aug 24 16:32:48.65: [11977]: USE page width 1728 pixels in 215 mm
Aug 24 16:32:48.65: [11977]: USE unlimited page length
Aug 24 16:32:48.65: [11977]: USE 3.85 line/mm
Aug 24 16:32:48.65: [11977]: USE 1-D MR
Aug 24 16:32:48.65: [11977]: <-- [24:AT+FDIS=0,0,0,2,0,0,0,4\r]
Aug 24 16:32:48.95: [11977]: --> [2:OK]
Aug 24 16:32:48.95: [11977]: <-- [7:AT+FDT\r]
Aug 24 16:32:57.45: [11977]: --> [22:+FDCS: 0,0,0,2,0,0,0,5]
Aug 24 16:32:57.45: [11977]: --> [7:CONNECT]
Aug 24 16:32:57.45: [11977]: SEND wait for XON
Aug 24 16:32:57.45: [11977]: --> [1:]
Aug 24 16:32:57.45: [11977]: SEND begin page
Aug 24 16:32:57.45: [11977]: <-- data [1026]
Aug 24 16:32:57.45: [11977]: <-- data [1035]
Aug 24 16:32:57.45: [11977]: <-- data [1031]
Aug 24 16:32:57.45: [11977]: <-- data [1029]
Aug 24 16:32:57.55: [11977]: <-- data [1024]
Aug 24 16:32:57.55: [11977]: <-- data [1025]
Aug 24 16:32:57.55: [11977]: <-- data [1024]
Aug 24 16:32:57.55: [11977]: <-- data [1024]
Aug 24 16:32:57.65: [11977]: <-- data [1024]
Aug 24 16:32:57.65: [11977]: <-- data [1024]
Aug 24 16:32:57.65: [11977]: <-- data [1025]
Aug 24 16:32:57.65: [11977]: <-- data [1025]
Aug 24 16:32:57.75: [11977]: <-- data [1024]
Aug 24 16:32:57.75: [11977]: <-- data [1024]
Aug 24 16:32:57.75: [11977]: <-- data [1025]
Aug 24 16:32:57.75: [11977]: <-- data [1026]
Aug 24 16:32:57.85: [11977]: <-- data [1026]
Aug 24 16:32:57.85: [11977]: <-- data [1033]
Aug 24 16:32:57.85: [11977]: <-- data [1044]
Aug 24 16:32:57.85: [11977]: <-- data [1034]
Aug 24 16:32:57.95: [11977]: <-- data [1036]
Aug 24 16:32:57.95: [11977]: <-- data [1029]
Aug 24 16:32:57.95: [11977]: <-- data [1035]
Aug 24 16:32:57.95: [11977]: <-- data [1039]
Aug 24 16:32:58.05: [11977]: <-- data [1030]
Aug 24 16:32:58.05: [11977]: <-- data [1030]
Aug 24 16:32:58.06: [11977]: <-- data [1035]
Aug 24 16:32:58.06: [11977]: <-- data [1032]
Aug 24 16:32:58.16: [11977]: <-- data [1027]
Aug 24 16:32:58.16: [11977]: <-- data [1029]
Aug 24 16:32:58.16: [11977]: <-- data [1024]
Aug 24 16:32:58.16: [11977]: <-- data [1026]
Aug 24 16:32:58.26: [11977]: <-- data [1025]
Aug 24 16:32:58.26: [11977]: <-- data [1024]
Aug 24 16:32:58.26: [11977]: <-- data [1026]
Aug 24 16:32:58.26: [11977]: <-- data [1026]
Aug 24 16:32:58.36: [11977]: <-- data [1024]
Aug 24 16:32:58.36: [11977]: <-- data [1027]
Aug 24 16:32:58.36: [11977]: <-- data [1025]
Aug 24 16:32:58.36: [11977]: <-- data [1024]
Aug 24 16:32:58.46: [11977]: <-- data [1026]
Aug 24 16:32:58.46: [11977]: <-- data [1024]
Aug 24 16:32:58.46: [11977]: <-- data [1025]
Aug 24 16:32:58.46: [11977]: <-- data [1026]
Aug 24 16:32:58.56: [11977]: <-- data [1026]
Aug 24 16:32:58.56: [11977]: <-- data [1026]
Aug 24 16:32:58.56: [11977]: <-- data [1024]
Aug 24 16:32:58.56: [11977]: <-- data [1029]
Aug 24 16:32:58.66: [11977]: <-- data [1026]
Aug 24 16:32:58.66: [11977]: <-- data [1029]
Aug 24 16:32:58.66: [11977]: <-- data [1025]
Aug 24 16:32:58.66: [11977]: <-- data [1029]
Aug 24 16:32:58.76: [11977]: <-- data [1037]
Aug 24 16:32:58.76: [11977]: <-- data [1024]
Aug 24 16:32:58.76: [11977]: <-- data [1025]
Aug 24 16:32:58.76: [11977]: <-- data [1027]
Aug 24 16:32:58.86: [11977]: <-- data [1027]
Aug 24 16:32:58.86: [11977]: <-- data [1026]
Aug 24 16:32:58.86: [11977]: <-- data [1028]
Aug 24 16:32:58.86: [11977]: <-- data [1026]
Aug 24 16:33:11.75: [11977]: <-- data [1026]
Aug 24 16:33:11.75: [11977]: <-- data [1027]
Aug 24 16:33:11.75: [11977]: <-- data [1027]
Aug 24 16:33:11.75: [11977]: <-- data [1029]
Aug 24 16:33:11.85: [11977]: <-- data [1029]
Aug 24 16:33:11.85: [11977]: <-- data [1026]
Aug 24 16:33:11.85: [11977]: <-- data [1024]
Aug 24 16:33:11.85: [11977]: <-- data [184]
Aug 24 16:33:11.85: [11977]: SENT 68792 bytes of data
Aug 24 16:33:11.86: [11977]: <-- data [2]
Aug 24 16:33:11.86: [11977]: SEND end page
Aug 24 16:36:11.86: [11977]: MODEM TIMEOUT: reading line from modem
Aug 24 16:36:11.86: [11977]: MODEM <Timeout>
Aug 24 16:36:11.86: [11977]: <-- [6:AT+FK\r]
Aug 24 16:36:41.86: [11977]: MODEM TIMEOUT: reading line from modem
Aug 24 16:36:41.86: [11977]: MODEM <Timeout>
Aug 24 16:36:41.86: [11977]: SEND FAILED: JOB 17923 DEST 16196717023 ERR Communication failure during Phase B/C; too many attempts to dial
Aug 24 16:36:41.86: [11977]: <-- [5:ATH0\r]
Aug 24 16:36:46.86: [11977]: MODEM TIMEOUT: reading line from modem
Aug 24 16:36:46.86: [11977]: MODEM <Timeout>
Aug 24 16:36:46.86: [11977]: MODEM set DTR OFF
Aug 24 16:36:46.86: [11977]: MODEM set baud rate: 0 baud (flow control unchanged)
Aug 24 16:36:46.86: [11977]: STATE CHANGE: SENDING -> MODEMWAIT (timeout 5)
Aug 24 16:36:46.86: [11977]: SESSION END

Attachment: pgp00017.pgp
Description: PGP signature