HylaFAX The world's most advanced open source fax server

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

[hylafax-users] FCS error,TRAINING failed with Conexant HSF PCI modem



Hi,

I am using hylafax-4.4.2 on fedora core 6, and my modem is a PCI softmodem that uses Conexant's hsf driver from linuxant. I installed hsfmodem-7.68.00.04full_k2.6.22.14_72.fc6-1fdr and activated it, according to the instructions at http://www.linuxant.com/drivers/hsf/install.php. Here is the output of "hsfconfig --info":

---------------------------
Config for modem unit 0: /dev/ttySHSF0
        Device instance: 0-PCI-14f1:2f30-14f1:205d
        HW revision    : Basic2 2.18 Standard DAA 3VoltsIA
        HW profile name: hsfpcibasic2hsfi
        Registration ID: 30D3-048D-C7A7
        License owner  : XXXXXXXXXXX
        License key    : B4-3D-4C-6C-34-C9
        License status : OK
        Current region : GREECE (T.35 code: 0046)

The /dev/modem alias (symlink) points to ttySHSF0
---------------------------

However, even though I am in general able to send and receive faxes, some faxes fail. My config.ttySHSF0 file is the following:
---------------------------
CountryCode: 30
InternationalPrefix: 00
AreaCode: 210
DialStringRules: etc/dialrules
FAXNumber: +30.210.1111111
ServerTracing: 0xFFF # log server-related operations
SessionTracing: 0xFFF # log server-related operations
RecvFileMode: 0600 # recvd facsimile are not public
LogFileMode: 0600 # private log files
DeviceMode: 0600 # device is accessible only to uucp/fax
SpeakerVolume: off # machine's are already too loud
RingsBeforeAnswer: 1
TagLineFont: etc/lutRS18.pcf # tag line font
TagLineFormat: "From %%l|%c|Page %%P of %%T"
MaxBadCalls: 5 # max 5 consecutive bad calls
PostScriptTimeout: 300 # 5 minute timeout on PostScript jobs
MaxSendPages: 30
MaxRecvPages: 30
MaxConcurrentCalls: 1 # serialize jobs to the same dest
ModemType: Class1 # use this to supply a hint
GettyArgs: "-h %l dx_%s" # modem must auto-detect fax/data
Class1Cmd: AT+FCLASS=1 # command to enter class 1
Class1PPMWaitCmd: AT+FTS=7 # command to stop and wait before PPM
Class1TCFWaitCmd: AT+FTS=7 # command to stop and wait before TCF
Class1EOPWaitCmd: AT+FTS=9 # command to stop and wait before EOP
Class1SwitchingCmd: AT+FRS=7 # silence between HDLC recv and trans
Class1RecvAbortOK: 200 # wait 200ms for abort response
Class1FrameOverhead: 4 # 4 byte overhead in recvd HDLC frames
Class1RecvIdentTimer: 40000 # 35+5secs waiting for ident frames
Class1TCFMaxNonZero: 10 # max 10% of data may be non-zero

Class1TCFMinRun: 1000 # min run is 2/3rds of TCF duration
---------------------------

and here is the end of an example failed log:

---------------------------
.........
.........
Feb 28 15:48:47.28: [21370]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled
Feb 28 15:48:47.28: [21370]: <-- [11:AT+FTM=121\r]
Feb 28 15:48:47.30: [21370]: --> [7:CONNECT]
Feb 28 15:48:47.30: [21370]: <-- data [1024]
Feb 28 15:48:47.30: [21370]: <-- data [1024]
Feb 28 15:48:47.30: [21370]: <-- data [202]
Feb 28 15:48:47.30: [21370]: <-- data [2]
Feb 28 15:48:50.46: [21370]: --> [2:OK]
Feb 28 15:48:50.46: [21370]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled
Feb 28 15:48:50.46: [21370]: <-- [9:AT+FRH=3\r]
Feb 28 15:48:50.60: [21370]: --> [7:CONNECT]
Feb 28 15:48:50.72: [21370]: --> [2:10 03]
Feb 28 15:48:50.72: [21370]: --> [5:ERROR]
Feb 28 15:48:50.72: [21370]: MODEM Command error
Feb 28 15:48:50.72: [21370]: FCS error
Feb 28 15:48:50.72: [21370]: <-- [10:AT+FRS=21\r]
Feb 28 15:48:52.38: [21370]: --> [2:OK]
Feb 28 15:48:52.38: [21370]: <-- [9:AT+FTH=3\r]
Feb 28 15:48:52.70: [21370]: --> [7:CONNECT]
Feb 28 15:48:52.70: [21370]: <-- HDLC<3:FF C8 D8>
Feb 28 15:48:52.70: [21370]: <-- data [3]
Feb 28 15:48:52.70: [21370]: <-- data [2]
Feb 28 15:48:53.99: [21370]: --> [2:OK]
Feb 28 15:48:53.99: [21370]: SEND send CRP (command repeat)
Feb 28 15:48:53.99: [21370]: <-- [9:AT+FRH=3\r]
Feb 28 15:48:54.14: [21370]: --> [7:CONNECT]
Feb 28 15:48:54.44: [21370]: --> HDLC<6:FF C8 5F C8 E7 40>
Feb 28 15:48:54.48: [21370]: --> [5:ERROR]
Feb 28 15:48:54.48: [21370]: MODEM Command error
Feb 28 15:48:54.48: [21370]: FCS error
Feb 28 15:48:54.48: [21370]: <-- [10:AT+FRS=21\r]
Feb 28 15:48:54.75: [21370]: --> [2:OK]
Feb 28 15:48:54.75: [21370]: <-- [9:AT+FTH=3\r]
Feb 28 15:48:55.07: [21370]: --> [7:CONNECT]
Feb 28 15:48:55.07: [21370]: <-- HDLC<3:FF C8 D8>
Feb 28 15:48:55.07: [21370]: <-- data [3]
Feb 28 15:48:55.07: [21370]: <-- data [2]
Feb 28 15:48:56.36: [21370]: --> [2:OK]
Feb 28 15:48:56.36: [21370]: SEND send CRP (command repeat)
Feb 28 15:48:56.36: [21370]: <-- [9:AT+FRH=3\r]
Feb 28 15:48:59.46: [21370]: --> [0:]
Feb 28 15:48:59.46: [21370]: MODEM <Empty line>
Feb 28 15:48:59.46: [21370]: MODEM TIMEOUT: waiting for v.21 carrier
Feb 28 15:48:59.46: [21370]: <-- data [1]
Feb 28 15:48:59.66: [21370]: MODEM TIMEOUT: reading line from modem
Feb 28 15:48:59.66: [21370]: MODEM <Timeout>
Feb 28 15:48:59.66: [21370]: <-- [9:AT+FRS=7\r]
Feb 28 15:48:59.79: [21370]: --> [2:OK]
Feb 28 15:48:59.79: [21370]: SEND training at v.29 9600 bit/s
Feb 28 15:48:59.79: [21370]: <-- [9:AT+FTH=3\r]
Feb 28 15:49:00.11: [21370]: --> [7:CONNECT]
Feb 28 15:49:00.11: [21370]: <-- HDLC<23:FF C0 C2 8C 8C 8C 8C 8C 8C 8C 0C 8C 4C 0C CC D4 04 04 04 04 04 04 04>
Feb 28 15:49:00.11: [21370]: <-- data [23]
Feb 28 15:49:00.11: [21370]: <-- data [2]
Feb 28 15:49:01.68: [21370]: --> [7:CONNECT]
Feb 28 15:49:01.68: [21370]: <-- HDLC<7:FF C8 C1 00 62 1F 22>
Feb 28 15:49:01.68: [21370]: <-- data [7]
Feb 28 15:49:01.68: [21370]: <-- data [2]
Feb 28 15:49:02.20: [21370]: --> [2:OK]
Feb 28 15:49:02.20: [21370]: <-- [9:AT+FTS=7\r]
Feb 28 15:49:02.35: [21370]: --> [2:OK]
Feb 28 15:49:02.35: [21370]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled
Feb 28 15:49:02.35: [21370]: <-- [10:AT+FTM=96\r]
Feb 28 15:49:02.35: [21370]: --> [7:CONNECT]
Feb 28 15:49:02.35: [21370]: <-- data [1024]
Feb 28 15:49:02.35: [21370]: <-- data [776]
Feb 28 15:49:02.35: [21370]: <-- data [2]
Feb 28 15:49:04.37: [21370]: --> [2:OK]
Feb 28 15:49:04.37: [21370]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled
Feb 28 15:49:04.37: [21370]: <-- [9:AT+FRH=3\r]
Feb 28 15:49:07.47: [21370]: --> [0:]
Feb 28 15:49:07.47: [21370]: MODEM <Empty line>
Feb 28 15:49:07.47: [21370]: MODEM TIMEOUT: waiting for v.21 carrier
Feb 28 15:49:07.47: [21370]: <-- data [1]
Feb 28 15:49:07.67: [21370]: MODEM TIMEOUT: reading line from modem
Feb 28 15:49:07.67: [21370]: MODEM <Timeout>
Feb 28 15:49:07.67: [21370]: <-- [9:AT+FRS=7\r]
Feb 28 15:49:07.80: [21370]: --> [2:OK]
Feb 28 15:49:07.80: [21370]: SEND training at v.29 7200 bit/s
Feb 28 15:49:07.80: [21370]: <-- [9:AT+FTH=3\r]
Feb 28 15:49:08.12: [21370]: --> [7:CONNECT]
Feb 28 15:49:08.12: [21370]: <-- HDLC<23:FF C0 C2 8C 8C 8C 8C 8C 8C 8C 0C 8C 4C 0C CC D4 04 04 04 04 04 04 04>
Feb 28 15:49:08.12: [21370]: <-- data [23]
Feb 28 15:49:08.12: [21370]: <-- data [2]
Feb 28 15:49:09.69: [21370]: --> [7:CONNECT]
Feb 28 15:49:09.69: [21370]: <-- HDLC<7:FF C8 C1 00 72 1F 22>
Feb 28 15:49:09.69: [21370]: <-- data [7]
Feb 28 15:49:09.69: [21370]: <-- data [2]
Feb 28 15:49:10.21: [21370]: --> [2:OK]
Feb 28 15:49:10.21: [21370]: <-- [9:AT+FTS=7\r]
Feb 28 15:49:10.36: [21370]: --> [2:OK]
Feb 28 15:49:10.36: [21370]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled
Feb 28 15:49:10.36: [21370]: <-- [10:AT+FTM=72\r]
Feb 28 15:49:10.38: [21370]: --> [7:CONNECT]
Feb 28 15:49:10.38: [21370]: <-- data [1024]
Feb 28 15:49:10.38: [21370]: <-- data [326]
Feb 28 15:49:10.38: [21370]: <-- data [2]
Feb 28 15:49:12.38: [21370]: --> [2:OK]
Feb 28 15:49:12.38: [21370]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled
Feb 28 15:49:12.38: [21370]: <-- [9:AT+FRH=3\r]
Feb 28 15:49:15.48: [21370]: --> [0:]
Feb 28 15:49:15.48: [21370]: MODEM <Empty line>
Feb 28 15:49:15.48: [21370]: MODEM TIMEOUT: waiting for v.21 carrier
Feb 28 15:49:15.48: [21370]: <-- data [1]
Feb 28 15:49:15.68: [21370]: MODEM TIMEOUT: reading line from modem
Feb 28 15:49:15.68: [21370]: MODEM <Timeout>
Feb 28 15:49:15.68: [21370]: <-- [9:AT+FRS=7\r]
Feb 28 15:49:15.81: [21370]: --> [2:OK]
Feb 28 15:49:15.81: [21370]: SEND training at v.27ter 4800 bit/s
Feb 28 15:49:15.81: [21370]: <-- [9:AT+FTH=3\r]
Feb 28 15:49:16.12: [21370]: --> [7:CONNECT]
Feb 28 15:49:16.12: [21370]: <-- HDLC<23:FF C0 C2 8C 8C 8C 8C 8C 8C 8C 0C 8C 4C 0C CC D4 04 04 04 04 04 04 04>
Feb 28 15:49:16.12: [21370]: <-- data [23]
Feb 28 15:49:16.12: [21370]: <-- data [2]
Feb 28 15:49:17.70: [21370]: --> [7:CONNECT]
Feb 28 15:49:17.70: [21370]: <-- HDLC<7:FF C8 C1 00 52 1F 22>
Feb 28 15:49:17.70: [21370]: <-- data [7]
Feb 28 15:49:17.70: [21370]: <-- data [2]
Feb 28 15:49:18.22: [21370]: --> [2:OK]
Feb 28 15:49:18.22: [21370]: <-- [9:AT+FTS=7\r]
Feb 28 15:49:18.37: [21370]: --> [2:OK]
Feb 28 15:49:18.37: [21370]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled
Feb 28 15:49:18.37: [21370]: <-- [10:AT+FTM=48\r]
Feb 28 15:49:18.37: [21370]: --> [7:CONNECT]
Feb 28 15:49:18.37: [21370]: <-- data [900]
Feb 28 15:49:18.37: [21370]: <-- data [2]
Feb 28 15:49:20.87: [21370]: --> [2:OK]
Feb 28 15:49:20.87: [21370]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled
Feb 28 15:49:20.87: [21370]: <-- [9:AT+FRH=3\r]
Feb 28 15:49:23.97: [21370]: --> [0:]
Feb 28 15:49:23.97: [21370]: MODEM <Empty line>
Feb 28 15:49:23.97: [21370]: MODEM TIMEOUT: waiting for v.21 carrier
Feb 28 15:49:23.97: [21370]: <-- data [1]
Feb 28 15:49:24.17: [21370]: MODEM TIMEOUT: reading line from modem
Feb 28 15:49:24.17: [21370]: MODEM <Timeout>
Feb 28 15:49:24.17: [21370]: <-- [9:AT+FRS=7\r]
Feb 28 15:49:24.30: [21370]: --> [2:OK]
Feb 28 15:49:24.30: [21370]: SEND training at v.27ter fallback mode 2400 bit/s
Feb 28 15:49:24.30: [21370]: <-- [9:AT+FTH=3\r]
Feb 28 15:49:24.62: [21370]: --> [7:CONNECT]
Feb 28 15:49:24.62: [21370]: <-- HDLC<23:FF C0 C2 8C 8C 8C 8C 8C 8C 8C 0C 8C 4C 0C CC D4 04 04 04 04 04 04 04>
Feb 28 15:49:24.62: [21370]: <-- data [23]
Feb 28 15:49:24.62: [21370]: <-- data [2]
Feb 28 15:49:26.19: [21370]: --> [7:CONNECT]
Feb 28 15:49:26.19: [21370]: <-- HDLC<7:FF C8 C1 00 42 1F 22>
Feb 28 15:49:26.19: [21370]: <-- data [7]
Feb 28 15:49:26.19: [21370]: <-- data [2]
Feb 28 15:49:26.71: [21370]: --> [2:OK]
Feb 28 15:49:26.71: [21370]: <-- [9:AT+FTS=7\r]
Feb 28 15:49:26.86: [21370]: --> [2:OK]
Feb 28 15:49:26.86: [21370]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled
Feb 28 15:49:26.86: [21370]: <-- [10:AT+FTM=24\r]
Feb 28 15:49:26.88: [21370]: --> [7:CONNECT]
Feb 28 15:49:26.88: [21370]: <-- data [450]
Feb 28 15:49:26.88: [21370]: <-- data [2]
Feb 28 15:49:29.60: [21370]: --> [2:OK]
Feb 28 15:49:29.60: [21370]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled
Feb 28 15:49:29.60: [21370]: <-- [9:AT+FRH=3\r]
Feb 28 15:49:32.70: [21370]: --> [0:]
Feb 28 15:49:32.70: [21370]: MODEM <Empty line>
Feb 28 15:49:32.70: [21370]: MODEM TIMEOUT: waiting for v.21 carrier
Feb 28 15:49:32.70: [21370]: <-- data [1]
Feb 28 15:49:32.90: [21370]: MODEM TIMEOUT: reading line from modem
Feb 28 15:49:32.90: [21370]: MODEM <Timeout>
Feb 28 15:49:32.90: [21370]: <-- [9:AT+FRS=7\r]
Feb 28 15:49:33.03: [21370]: --> [2:OK]
Feb 28 15:49:33.03: [21370]: TRAINING failed
Feb 28 15:49:33.03: [21370]: <-- [9:AT+FTH=3\r]
Feb 28 15:49:33.35: [21370]: --> [7:CONNECT]
Feb 28 15:49:33.35: [21370]: <-- HDLC<3:FF C8 DF>
Feb 28 15:49:33.35: [21370]: <-- data [3]
Feb 28 15:49:33.35: [21370]: <-- data [2]
Feb 28 15:49:34.64: [21370]: --> [2:OK]
Feb 28 15:49:34.64: [21370]: MODEM input buffering enabled
Feb 28 15:49:35.64: [21370]: <-- [5:ATH0\r]
Feb 28 15:49:35.78: [21370]: --> [2:OK]
Feb 28 15:49:35.78: [21370]: MODEM set DTR OFF
Feb 28 15:49:35.78: [21370]: STATE CHANGE: SENDING -> MODEMWAIT (timeout 5)
Feb 28 15:49:35.78: [21370]: SESSION END
---------------------------

Can someone tell why this is happening? Any help much appreciated!

Yannis



Project hosted by iFAX Solutions