[hylafax-users] Modem Training problems

[Chris McLean <chris@xxxxxxxxxxxxxxxx>]

Hi,

on a new install of HylaFax on Ubuntu, we had everything working for about 3 days, until we started to get training failures - the server can no longer receive faxes, but can still send. I have tried many, many different config options for the specific modem (agere systems venus modem (V90, 56K flex)), but always with the same errors. This is happening from any sender. I have attempted generating default configs (using default params) and hacking various lucent config files (this looks like a std lucent chipset), again with the same errors. I have also tried increasing all timeout values relating to TCF, as "MODEM TIMEOUT: receiving TCF" is prominent in the logs.

I have tested this as a class 2 modem, but get:

COMREC error (code 72)

errors - I gave up on this and focused on resolving the class 1 errors.

I have attached a receive log and modem config in the hope that others have tackled this before - I have trawled the web for 3 days without any luck on this specific problem. Any help would be very much appreciated. If any further log files etc are needed, please let me know.

regards

Chris McLean



---- RECEIVE LOG ----------------------------------------------------
root@minervaserver:/etc/hylafax# cat /var/spool/hylafax/log/c000000058
Feb 08 11:59:16.42: [ 5141]: SESSION BEGIN 000000058 4401132426181
Feb 08 11:59:16.42: [ 5141]: HylaFAX (tm) Version 4.2.5
Feb 08 11:59:16.42: [ 5141]: <-- [4:ATA\r]
Feb 08 11:59:25.01: [ 5141]: --> [7:CONNECT]
Feb 08 11:59:25.01: [ 5141]: ANSWER: FAX CONNECTION  DEVICE '/dev/ttyS4'
Feb 08 11:59:25.01: [ 5141]: RECV FAX: begin
Feb 08 11:59:25.01: [ 5141]: <-- data [32]
Feb 08 11:59:25.01: [ 5141]: <-- data [2]
Feb 08 11:59:25.02: [ 5141]: --> [7:CONNECT]
Feb 08 11:59:25.02: [ 5141]: <-- data [23]
Feb 08 11:59:25.02: [ 5141]: <-- data [2]
Feb 08 11:59:25.02: [ 5141]: --> [7:CONNECT]
Feb 08 11:59:25.02: [ 5141]: <-- data [13]
Feb 08 11:59:25.02: [ 5141]: <-- data [2]
Feb 08 11:59:27.24: [ 5141]: --> [2:OK]
Feb 08 11:59:27.24: [ 5141]: <-- [9:AT+FRH=3\r]
Feb 08 11:59:27.79: [ 5141]: --> [7:CONNECT]
Feb 08 11:59:29.50: [ 5141]: --> [2:OK]
Feb 08 11:59:29.50: [ 5141]: REMOTE TSI "204401"
Feb 08 11:59:29.50: [ 5141]: <-- [9:AT+FRH=3\r]
Feb 08 11:59:29.50: [ 5141]: --> [7:CONNECT]
Feb 08 11:59:29.77: [ 5141]: --> [2:OK]
Feb 08 11:59:29.77: [ 5141]: REMOTE wants 9600 bit/s
Feb 08 11:59:29.77: [ 5141]: REMOTE wants A4 page width (215 mm)
Feb 08 11:59:29.77: [ 5141]: REMOTE wants A4 page length (297 mm)
Feb 08 11:59:29.77: [ 5141]: REMOTE wants 7.7 line/mm
Feb 08 11:59:29.77: [ 5141]: REMOTE wants 2-D MR
Feb 08 11:59:29.77: [ 5141]: RECV training at v.29 9600 bit/s
Feb 08 11:59:29.77: [ 5141]: <-- [10:AT+FRM=96\r]
Feb 08 11:59:30.18: [ 5141]: --> [7:CONNECT]
Feb 08 11:59:38.53: [ 5141]: MODEM TIMEOUT: receiving TCF
Feb 08 11:59:38.53: [ 5141]: <-- data [1]
Feb 08 11:59:38.54: [ 5141]: --> [17:~4�NmC�l�  �V{�]
Feb 08 11:59:38.54: [ 5141]: <-- [9:AT+FRS=7\r]
Feb 08 11:59:38.54: [ 5141]: --> [2:OK]
Feb 08 11:59:38.54: [ 5141]: <-- [9:AT+FTH=3\r]
Feb 08 11:59:39.92: [ 5141]: --> [7:CONNECT]
Feb 08 11:59:39.92: [ 5141]: <-- data [3]
Feb 08 11:59:39.92: [ 5141]: <-- data [2]
Feb 08 11:59:40.24: [ 5141]: --> [2:OK]
Feb 08 11:59:40.24: [ 5141]: TRAINING failed
Feb 08 11:59:40.24: [ 5141]: <-- [9:AT+FRH=3\r]
Feb 08 11:59:40.79: [ 5141]: --> [7:CONNECT]
Feb 08 11:59:42.50: [ 5141]: --> [2:OK]
Feb 08 11:59:42.50: [ 5141]: REMOTE TSI "204401"
Feb 08 11:59:42.50: [ 5141]: <-- [9:AT+FRH=3\r]
Feb 08 11:59:42.50: [ 5141]: --> [7:CONNECT]
Feb 08 11:59:42.77: [ 5141]: --> [2:OK]
Feb 08 11:59:42.77: [ 5141]: REMOTE wants 7200 bit/s
Feb 08 11:59:42.77: [ 5141]: REMOTE wants A4 page width (215 mm)
Feb 08 11:59:42.77: [ 5141]: REMOTE wants A4 page length (297 mm)
Feb 08 11:59:42.77: [ 5141]: REMOTE wants 7.7 line/mm
Feb 08 11:59:42.77: [ 5141]: REMOTE wants 2-D MR
Feb 08 11:59:42.77: [ 5141]: RECV training at v.29 7200 bit/s
Feb 08 11:59:42.77: [ 5141]: <-- [10:AT+FRM=72\r]
Feb 08 11:59:43.18: [ 5141]: --> [7:CONNECT]
Feb 08 11:59:47.70: [ 5141]: MODEM TIMEOUT: receiving TCF
Feb 08 11:59:47.70: [ 5141]: <-- data [1]
Feb 08 11:59:47.71: [ 5141]: --> [2:]
Feb 08 11:59:47.71: [ 5141]: --> [2:OK]
Feb 08 11:59:47.71: [ 5141]: <-- [9:AT+FRS=7\r]
Feb 08 11:59:47.78: [ 5141]: --> [2:OK]
Feb 08 11:59:47.78: [ 5141]: <-- [9:AT+FTH=3\r]
Feb 08 11:59:48.64: [ 5141]: --> [7:CONNECT]
Feb 08 11:59:48.64: [ 5141]: <-- data [3]
Feb 08 11:59:48.64: [ 5141]: <-- data [2]
Feb 08 11:59:48.96: [ 5141]: --> [2:OK]
Feb 08 11:59:48.96: [ 5141]: TRAINING failed
Feb 08 11:59:48.96: [ 5141]: <-- [9:AT+FRH=3\r]
Feb 08 11:59:49.47: [ 5141]: --> [7:CONNECT]
Feb 08 11:59:49.97: [ 5141]: --> [2:OK]
Feb 08 11:59:49.97: [ 5141]: REMOTE wants 7200 bit/s
Feb 08 11:59:49.97: [ 5141]: REMOTE wants A4 page width (215 mm)
Feb 08 11:59:49.97: [ 5141]: REMOTE wants A4 page length (297 mm)
Feb 08 11:59:49.97: [ 5141]: REMOTE wants 7.7 line/mm
Feb 08 11:59:49.97: [ 5141]: REMOTE wants 2-D MR
Feb 08 11:59:49.97: [ 5141]: RECV training at v.29 7200 bit/s
Feb 08 11:59:49.97: [ 5141]: <-- [10:AT+FRM=72\r]
Feb 08 11:59:50.39: [ 5141]: --> [7:CONNECT]
Feb 08 11:59:54.91: [ 5141]: MODEM TIMEOUT: receiving TCF
Feb 08 11:59:54.91: [ 5141]: <-- data [1]
Feb 08 11:59:54.91: [ 5141]: --> [2:]
Feb 08 11:59:54.91: [ 5141]: --> [2:OK]
Feb 08 11:59:54.91: [ 5141]: <-- [9:AT+FRS=7\r]
Feb 08 11:59:54.99: [ 5141]: --> [2:OK]
Feb 08 11:59:54.99: [ 5141]: <-- [9:AT+FTH=3\r]
Feb 08 11:59:55.84: [ 5141]: --> [7:CONNECT]
Feb 08 11:59:55.84: [ 5141]: <-- data [3]
Feb 08 11:59:55.84: [ 5141]: <-- data [2]
Feb 08 11:59:56.17: [ 5141]: --> [2:OK]
Feb 08 11:59:56.17: [ 5141]: TRAINING failed
Feb 08 11:59:56.17: [ 5141]: <-- [9:AT+FRH=3\r]
Feb 08 11:59:56.71: [ 5141]: --> [7:CONNECT]
Feb 08 11:59:58.42: [ 5141]: --> [2:OK]
Feb 08 11:59:58.42: [ 5141]: REMOTE TSI "204401"
Feb 08 11:59:58.42: [ 5141]: <-- [9:AT+FRH=3\r]
Feb 08 11:59:58.43: [ 5141]: --> [7:CONNECT]
Feb 08 11:59:58.69: [ 5141]: --> [2:OK]
Feb 08 11:59:58.69: [ 5141]: REMOTE wants 4800 bit/s
Feb 08 11:59:58.69: [ 5141]: REMOTE wants A4 page width (215 mm)
Feb 08 11:59:58.69: [ 5141]: REMOTE wants A4 page length (297 mm)
Feb 08 11:59:58.69: [ 5141]: REMOTE wants 7.7 line/mm
Feb 08 11:59:58.69: [ 5141]: REMOTE wants 2-D MR
Feb 08 11:59:58.69: [ 5141]: RECV training at v.27ter 4800 bit/s
Feb 08 11:59:58.69: [ 5141]: <-- [10:AT+FRM=48\r]
Feb 08 11:59:59.79: [ 5141]: --> [7:CONNECT]
Feb 08 12:00:04.32: [ 5141]: MODEM TIMEOUT: receiving TCF
Feb 08 12:00:04.32: [ 5141]: <-- data [1]
Feb 08 12:00:04.32: [ 5141]: --> [18:�6���j��#-#]
Feb 08 12:00:04.32: [ 5141]: <-- [9:AT+FRS=7\r]
Feb 08 12:00:04.32: [ 5141]: --> [2:OK]
Feb 08 12:00:04.32: [ 5141]: <-- [9:AT+FTH=3\r]
Feb 08 12:00:05.25: [ 5141]: --> [7:CONNECT]
Feb 08 12:00:05.25: [ 5141]: <-- data [3]
Feb 08 12:00:05.25: [ 5141]: <-- data [2]
Feb 08 12:00:05.57: [ 5141]: --> [2:OK]
Feb 08 12:00:05.57: [ 5141]: TRAINING failed
Feb 08 12:00:05.57: [ 5141]: <-- [9:AT+FRH=3\r]
Feb 08 12:00:06.10: [ 5141]: --> [7:CONNECT]
Feb 08 12:00:06.60: [ 5141]: --> [2:OK]
Feb 08 12:00:06.60: [ 5141]: REMOTE wants 4800 bit/s
Feb 08 12:00:06.60: [ 5141]: REMOTE wants A4 page width (215 mm)
Feb 08 12:00:06.60: [ 5141]: REMOTE wants A4 page length (297 mm)
Feb 08 12:00:06.60: [ 5141]: REMOTE wants 7.7 line/mm
Feb 08 12:00:06.60: [ 5141]: REMOTE wants 2-D MR
Feb 08 12:00:06.60: [ 5141]: RECV training at v.27ter 4800 bit/s
Feb 08 12:00:06.60: [ 5141]: <-- [10:AT+FRM=48\r]
Feb 08 12:00:07.69: [ 5141]: --> [7:CONNECT]
Feb 08 12:00:24.38: [ 5141]: MODEM TIMEOUT: receiving TCF
Feb 08 12:00:24.38: [ 5141]: <-- data [1]
Feb 08 12:00:24.39: [ 5141]: --> [18:[�a҃Κ�!�ˋ]
Feb 08 12:00:24.39: [ 5141]: <-- [9:AT+FRS=7\r]
Feb 08 12:00:24.39: [ 5141]: --> [2:OK]
Feb 08 12:00:24.39: [ 5141]: <-- [9:AT+FTH=3\r]
Feb 08 12:00:25.32: [ 5141]: --> [7:CONNECT]
Feb 08 12:00:25.32: [ 5141]: <-- data [3]
Feb 08 12:00:25.32: [ 5141]: <-- data [2]
Feb 08 12:00:25.64: [ 5141]: --> [2:OK]
Feb 08 12:00:25.64: [ 5141]: TRAINING failed
Feb 08 12:00:25.64: [ 5141]: <-- [9:AT+FRH=3\r]
Feb 08 12:00:32.65: [ 5141]: --> [0:]
Feb 08 12:00:32.65: [ 5141]: MODEM <Empty line>
Feb 08 12:00:32.65: [ 5141]: MODEM TIMEOUT: waiting for v.21 carrier
Feb 08 12:00:32.65: [ 5141]: <-- data [1]
Feb 08 12:00:32.66: [ 5141]: --> [2:]
Feb 08 12:00:32.66: [ 5141]: --> [2:OK]
Feb 08 12:00:32.66: [ 5141]: RECV FAX: Failure to train modems
Feb 08 12:00:32.66: [ 5141]: RECV FAX: end
Feb 08 12:00:32.66: [ 5141]: SESSION END


----- MODEM CONFIG -------------------------------------------------
root@minervaserver:/etc/hylafax# cat /etc/hylafax/config.ttyS4
# $Id: lucent,v 1.22 2005/12/05 15:48:54 darren Exp $
#
# HylaFAX Facsimile Software
#
# Copyright (c) 1990-1996 Sam Leffler
# Copyright (c) 1991-1996 Silicon Graphics, Inc.
# HylaFAX is a trademark of Silicon Graphics, Inc.
#
# Permission to use, copy, modify, distribute, and sell this software and
# its documentation for any purpose is hereby granted without fee, provided
# that (i) the above copyright notices and this permission notice appear in
# all copies of the software and related documentation, and (ii) the names of
# Sam Leffler and Silicon Graphics may not be used in any advertising or
# publicity relating to the software without the specific, prior written
# permission of Sam Leffler and Silicon Graphics.
#
# THE SOFTWARE IS PROVIDED "AS-IS" AND WITHOUT WARRANTY OF ANY KIND,
# EXPRESS, IMPLIED OR OTHERWISE, INCLUDING WITHOUT LIMITATION, ANY
# WARRANTY OF MERCHANTABILITY OR FITNESS FOR A PARTICULAR PURPOSE.
#
# IN NO EVENT SHALL SAM LEFFLER OR SILICON GRAPHICS BE LIABLE FOR
# ANY SPECIAL, INCIDENTAL, INDIRECT OR CONSEQUENTIAL DAMAGES OF ANY KIND,
# OR ANY DAMAGES WHATSOEVER RESULTING FROM LOSS OF USE, DATA OR PROFITS,
# WHETHER OR NOT ADVISED OF THE POSSIBILITY OF DAMAGE, AND ON ANY THEORY OF
# LIABILITY, ARISING OUT OF OR IN CONNECTION WITH THE USE OR PERFORMANCE
# OF THIS SOFTWARE.
#

#
# prototype config for Lucent LT Winmodem using ltmodem.o driver,
# as well as many other Lucent chipset modems.
# (They work well with all default Class 1 configs.)
#

#
CountryCode:            44
AreaCode:               0113
FAXNumber:              +44.0113.2426181
LongDistancePrefix:     1
InternationalPrefix:    00
DialStringRules:        etc/dialrules
ServerTracing:          1
# session tracing was 11
SessionTracing:         63
RecvFileMode:           0644
LogFileMode:            0600
DeviceMode:             0600
RingsBeforeAnswer:      1
SpeakerVolume:          off
GettyArgs:              "-h %l dx_%s"
LocalIdentifier:        "Minerva Appointments"
TagLineFont:            etc/lutRS18.pcf
TagLineFormat:          "From %%l|%c|Page %%P of %%T"
MaxRecvPages:           50
#

ModemType:              Class1          # use this to supply a hint
ModemFlowControl:       rtscts          # many firmwares have broken software flow control
Class1TMConnectDelay:   400             # counteract quick CONNECT response

# If your line supports Caller-ID, you may want to uncomment this...
 ModemResetCmds:        AT+VCID=1
 CallIDPattern: "NMBR="
 CallIDPattern: "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
# CallIDPattern:        "DTMF"          # format of AT*DF1
# CallIDAnswerLength:   4               # four DTMF-DID digits then ATA

begin:vcard
fn:Chris McLean
n:McLean;Chris
org:eleventeenth ltd
adr:;;19 king st;Wakefield;West Yorkshire;WF1 2SQ;UK
email;internet:chris@xxxxxxxxxxxxxxxx
title:Technical Manager
tel;work:08456 441 603
tel;cell:07974 075454
x-mozilla-html:TRUE
url:http://www.eleventeenth.com
version:2.1
end:vcard