HylaFAX The world's most advanced open source fax server

[Date Prev][Date Next][Thread Prev][Thread Next] [Date Index] [Thread Index]

[hylafax-users] Modem is Wedged?



Good day,

I work in the IT department of a small firm, and we're looking to convert to 
a 4 line HylaFAX solution, using external modems run off a Digiboard. 
Currently, however, we're doing a test project using an older Pentium with 
an internal linmodem (a Conexant HCF) and HylaFAX 4.1.6.

We're exceedingly close to a working solution, but there are a couple show 
stopping bugs. In short, the HylaFAX box will dial the remote fax machine, 
send a load of data... but nothing will come out of the fax machine. After 
that, the modem gets "wedged". The modem is supposed to be supported.

Pardon the load of a text attached - I figured it would be better to provide 
too much information that to little:

First, a getty is manually started:

[root@localhost root]# faxgetty modem

Sending the message like this seems to work:

[root@localhost root]# sendfax -n -d ####### mtest

(mtest is a short text file)

It injects into the queue, dials the remote fax machine, and produces this 
log:

[root@localhost log]# cat *
Jun 19 22:30:39.07: [ 3452]: SESSION BEGIN 00000001 ###########
Jun 19 22:30:39.07: [ 3452]: HylaFAX (tm) Version 4.1.6
Jun 19 22:30:39.07: [ 3452]: SEND FAX: JOB 1 DEST ####### COMMID 00000001 
DEVICE '/dev/modem'
Jun 19 22:30:39.07: [ 3452]: DELAY 75 ms
Jun 19 22:30:39.14: [ 3452]: DELAY 2600 ms
Jun 19 22:30:42.75: [ 3452]: DELAY 10 ms
Jun 19 22:30:42.76: [ 3452]: <-- [4:ATZ\r]
Jun 19 22:30:42.80: [ 3452]: --> [2:OK]
Jun 19 22:30:42.80: [ 3452]: DELAY 3000 ms
Jun 19 22:30:45.80: [ 3452]: <-- [5:ATE0\r]
Jun 19 22:30:45.83: [ 3452]: --> [4:ATE0]
Jun 19 22:30:45.83: [ 3452]: --> [2:OK]
Jun 19 22:30:45.83: [ 3452]: <-- [5:ATV1\r]
Jun 19 22:30:45.86: [ 3452]: --> [2:OK]
Jun 19 22:30:45.86: [ 3452]: <-- [5:ATQ0\r]
Jun 19 22:30:45.89: [ 3452]: --> [2:OK]
Jun 19 22:30:45.89: [ 3452]: <-- [7:ATS0=0\r]
Jun 19 22:30:45.92: [ 3452]: --> [2:OK]
Jun 19 22:30:45.92: [ 3452]: <-- [7:ATS8=2\r]
Jun 19 22:30:45.95: [ 3452]: --> [2:OK]
Jun 19 22:30:45.95: [ 3452]: <-- [8:ATS7=60\r]
Jun 19 22:30:45.98: [ 3452]: --> [2:OK]
Jun 19 22:30:45.98: [ 3452]: <-- [6:AT&K3\r]
Jun 19 22:30:46.01: [ 3452]: --> [2:OK]
Jun 19 22:30:46.01: [ 3452]: <-- [6:AT&D2\r]
Jun 19 22:30:46.04: [ 3452]: --> [2:OK]
Jun 19 22:30:46.04: [ 3452]: <-- [6:AT&C1\r]
Jun 19 22:30:46.07: [ 3452]: --> [2:OK]
Jun 19 22:30:46.07: [ 3452]: <-- [12:AT+FCLASS=1\r]
Jun 19 22:30:46.09: [ 3452]: --> [2:OK]
Jun 19 22:30:46.09: [ 3452]: <-- [5:ATM0\r]
Jun 19 22:30:46.12: [ 3452]: --> [2:OK]
Jun 19 22:30:46.12: [ 3452]: <-- [12:AT+FCLASS=1\r]
Jun 19 22:30:46.25: [ 3452]: --> [2:OK]
Jun 19 22:30:46.27: [ 3452]: DIAL 4571599
Jun 19 22:30:46.27: [ 3452]: <-- [12:ATDT4571599\r]
Jun 19 22:31:08.17: [ 3452]: --> [7:CONNECT]
Jun 19 22:31:09.29: [ 3452]: --> [2:OK]
Jun 19 22:31:09.29: [ 3452]: REMOTE NSF "00 00 11 80 00 8A 48 00 47 00 86 04 
30 30 30 30 91 D8 8D 02 01 00 01 02 04 E6"
Jun 19 22:31:09.29: [ 3452]: NSF remote fax equipment: Canon
Jun 19 22:31:09.29: [ 3452]: NSF remote station ID: "0000"
Jun 19 22:31:09.29: [ 3452]: <-- [9:AT+FRH=3\r]
Jun 19 22:31:09.33: [ 3452]: --> [7:CONNECT]
Jun 19 22:31:09.63: [ 3452]: --> [2:OK]
Jun 19 22:31:09.63: [ 3452]: REMOTE best rate 14400 bit/s
Jun 19 22:31:09.63: [ 3452]: REMOTE max page width 1728 pixels in 215 mm
Jun 19 22:31:09.63: [ 3452]: REMOTE max unlimited page length
Jun 19 22:31:09.63: [ 3452]: REMOTE best vres 7.7 line/mm
Jun 19 22:31:09.63: [ 3452]: REMOTE best format 2-D MMR
Jun 19 22:31:09.63: [ 3452]: REMOTE supports T.30 Annex A, ECM
Jun 19 22:31:09.63: [ 3452]: REMOTE best 10 ms/scanline
Jun 19 22:31:09.63: [ 3452]: USE 14400 bit/s
Jun 19 22:31:09.63: [ 3452]: USE 10 ms/scanline
Jun 19 22:31:09.63: [ 3452]: SEND file "docq/doc1.ps;30"
Jun 19 22:31:09.63: [ 3452]: USE page width 1728 pixels in 215 mm
Jun 19 22:31:09.63: [ 3452]: USE unlimited page length
Jun 19 22:31:09.63: [ 3452]: USE 3.85 line/mm
Jun 19 22:31:09.63: [ 3452]: USE 1-D MR
Jun 19 22:31:09.63: [ 3452]: SEND training at v.17 14400 bit/s
Jun 19 22:31:09.63: [ 3452]: <-- [9:AT+FTH=3\r]
Jun 19 22:31:09.98: [ 3452]: --> [7:CONNECT]
Jun 19 22:31:09.98: [ 3452]: <-- data [23]
Jun 19 22:31:09.98: [ 3452]: <-- data [2]
Jun 19 22:31:11.68: [ 3452]: --> [7:CONNECT]
Jun 19 22:31:11.68: [ 3452]: <-- data [6]
Jun 19 22:31:11.68: [ 3452]: <-- data [2]
Jun 19 22:31:12.24: [ 3452]: --> [2:OK]
Jun 19 22:31:12.24: [ 3452]: <-- [9:AT+FTS=7\r]
Jun 19 22:31:12.40: [ 3452]: --> [2:OK]
Jun 19 22:31:12.40: [ 3452]: <-- [11:AT+FTM=145\r]
Jun 19 22:31:12.43: [ 3452]: --> [7:CONNECT]
Jun 19 22:31:12.43: [ 3452]: <-- data [1024]
Jun 19 22:31:12.43: [ 3452]: <-- data [1024]
Jun 19 22:31:12.43: [ 3452]: <-- data [652]
Jun 19 22:31:12.43: [ 3452]: <-- data [2]
Jun 19 22:31:42.43: [ 3452]: MODEM <Timeout>
Jun 19 22:31:42.43: [ 3452]: Problem sending TCF data
Jun 19 22:31:42.43: [ 3452]: <-- [9:AT+FRH=3\r]
Jun 19 22:31:45.53: [ 3452]: --> [0:]
Jun 19 22:31:45.53: [ 3452]: MODEM <Empty line>
Jun 19 22:31:45.53: [ 3452]: <-- data [1]
Jun 19 22:31:45.73: [ 3452]: MODEM <Timeout>
Jun 19 22:31:45.73: [ 3452]: DELAY 1500 ms
Jun 19 22:31:47.23: [ 3452]: SEND training at v.17 12000 bit/s
Jun 19 22:31:47.23: [ 3452]: <-- [9:AT+FTH=3\r]
Jun 19 22:31:49.78: [ 3452]: Error sending T.30 prologue frames
Jun 19 22:31:49.78: [ 3452]: SEND training at v.17 9600 bit/s
Jun 19 22:31:49.78: [ 3452]: <-- [9:AT+FTH=3\r]
Jun 19 22:31:52.33: [ 3452]: Error sending T.30 prologue frames
Jun 19 22:31:52.33: [ 3452]: SEND training at v.17 7200 bit/s
Jun 19 22:31:52.33: [ 3452]: <-- [9:AT+FTH=3\r]
Jun 19 22:31:54.88: [ 3452]: Error sending T.30 prologue frames
Jun 19 22:31:54.88: [ 3452]: SEND training at v.27ter 4800 bit/s
Jun 19 22:31:54.88: [ 3452]: <-- [9:AT+FTH=3\r]
Jun 19 22:31:57.43: [ 3452]: Error sending T.30 prologue frames
Jun 19 22:31:57.43: [ 3452]: SEND training at v.27ter fallback mode 2400 
bit/s
Jun 19 22:31:57.43: [ 3452]: <-- [9:AT+FTH=3\r]
Jun 19 22:31:59.98: [ 3452]: Error sending T.30 prologue frames
Jun 19 22:31:59.98: [ 3452]: TRAINING failed
Jun 19 22:31:59.98: [ 3452]: <-- [9:AT+FTH=3\r]
Jun 19 22:32:02.53: [ 3452]: --> [0:]
Jun 19 22:32:02.53: [ 3452]: <-- [5:ATH0\r]
Jun 19 22:32:07.53: [ 3452]: MODEM <Timeout>
Jun 19 22:32:07.64: [ 3452]: SESSION END

By this point:

[root@localhost root]# faxstat
HylaFAX scheduler on localhost.localdomain: Running
Modem modem (+1.###.###.####): Waiting for modem to come ready

Here's what the logs are saying:

[root@localhost etc]# cat xferfaxlog
06/19/03 22:30  SEND    00000001        modem   1       ""
root@localhost.localdomain      "#######"       "<UNSPECIFIED>" 2891776 0
1:28    0:59   "Failure to train remote modem at 2400 bps or minimum speed"

Here's what we find in root's mail box:

To: FaxMaster@localhost.localdomain
From: The HylaFAX Receive Agent <fax@localhost.localdomain>
Subject: modem on /dev/modem appears wedged

The HylaFAX software thinks that there is a problem with the modem on device 
/dev/modem that needs attention; repeated attempts to initialize the modem 
have failed.

Consult the server trace logs for more information on what is happening.



And finally, here are a few relevant config files:

[root@localhost etc]# cat config
LogFacility:            daemon
CountryCode:            1
AreaCode:               ###
LongDistancePrefix:     1
InternationalPrefix:    011
DialStringRules:        etc/dialrules
ServerTracing:          1

[root@localhost etc]# cat config.modem
# Configuration for a Rockwell RC288DPi-based Class 1 modem.
#    Hayes Optima 28800         firmware rev ???
#    Microcom DeskPorte 28.8S   firmware rev V1.100K-V34_DS
#    PPI PM288FXMT              firmware rev 1.57
#
# Note that the modem does not lock the line rate for fax
# receive (at least not the PM288FXMT); but not sure whether
# current flow control settings are maintained or if it
# switches to XON/XOFF (testing host was too fast to see).
#
#
CountryCode:            1
AreaCode:               ###
FAXNumber:              +1.###.###.####
LongDistancePrefix:     1
InternationalPrefix:    011
DialStringRules:        etc/dialrules
ServerTracing:          1
SessionTracing:         11
RecvFileMode:           0666
LogFileMode:            0666
DeviceMode:             0666
RingsBeforeAnswer:      1
SpeakerVolume:          off
GettyArgs:              "-h %l dx_%s"
LocalIdentifier:        ModemTest
TagLineFont:            etc/lutRS18.pcf
TagLineFormat:          "From %%l|%c|Page %%P of %%T"
MaxRecvPages:           25
#
#
# Modem-related stuff: should reflect modem command interface
# and hardware connection/cabling (e.g. flow control).
#
ModemType:              Class1          # use this to supply a hint
ModemRate:              19200           # rate for DCE-DTE communication
ModemFlowControl:       rtscts          # default
#
ModemNoFlowCmd:         AT&K0           # setup no flow control
ModemHardFlowCmd:       AT&K3           # setup hardware flow control
ModemSoftFlowCmd:       AT&K4           # setup software flow control
ModemSetupDTRCmd:       AT&D2           # setup so DTR drop resets modem
ModemSetupDCDCmd:       AT&C1           # setup so DCD reflects carrier (or
not)#
ModemMfrQueryCmd:       !Rockwell
ModemModelQueryCmd:     !RC288DPi
ModemRevQueryCmd:       ATI3            # product information
#
ModemDialCmd:           ATDT%s          # T for tone dialing


[root@localhost etc]# cat setup.modem
# Warning, this file was automatically generated by faxsetup
# on Thu Jun 19 22:17:25 MDT 2003 for root
prompt()
{
   echo -n "$* "
}
            ttyPort()
            {
                expr $1 : 'tty\(.*\)'
            }
            ttyLocks()
            {
                echo $UUCP_LOCKDIR/LCK..$1
            }
            ttyAliases()
            {
                echo /dev/$1
            }
            ttyDev()
            {
                echo /dev/$1
            }
            checkPort()
            {
                 return
            }
            ttyStty()
            {
                echo /bin/stty
            }
            ttySpeeds()
            {
                speeds=
                if [ -z "$SPEED" ]; then
                    for s in 38400 19200 9600 4800 2400 1200; do
                        onDev /bin/stty $s </dev/null >/dev/null 2>&1 &&
speeds="$speeds $s"
                    done
                fi
                echo $speeds
            }


Does anyone have any advice for us? We're getting desperate.

Thanks for your time,
Edward Aronyk

_________________________________________________________________
Protect your PC - get McAfee.com VirusScan Online  
http://clinic.mcafee.com/clinic/ibuy/campaign.asp?cid=3963


____________________ 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@hylafax.org < /dev/null
  *To learn about commercial HylaFAX(tm) support, mail sales@hylafax.org.*




Project hosted by iFAX Solutions