[hylafax-users] Lost

[Johan Mulder <johan-hylafax@localhost.nl>]

Hi,

I am a bit lost in my hylafax setup. I just set up a new system
which is going to be used as a mail to fax gw.
Everything seems to be set up properly. 
Now I have this situation:
* When I send a fax, the fax just gets sent to the remote fax;
* The remote side receives the fax, but just doesn't print it.

I have tried several different fax machines, but no fax machine
appears to recognise the received data.
The logfile tells me it has been sent correctly, but it just
went wrong somewhere, and I haven't been able to figure out
where.

Besides that, faxstat tells me that it's waiting for the modem
to become ready:
HylaFAX scheduler on host.name: Running
Modem cuaa0 (+xx xx xxxxxxx): Waiting for modem to come ready

This never changes, even when it's sending a fax at that same
moment. Faxgetty logs nothing (even with ServerTracing set to 0xFFF).
The only thing it does log is that it quits when I kill it..

Attached to this message are the modem config file and one of the
logs generated by faxsend.
Can someone please give me a pointer in the right direction?
Any help is appreciated.

-- 
Johan Mulder
Localhost System Administration
Nov 19 17:00:02.27: [33210]: SESSION BEGIN 00000011 31xxxxxxxxx
Nov 19 17:00:02.28: [33210]: HylaFAX (tm) Version 4.1.5
Nov 19 17:00:02.28: [33210]: SEND FAX: JOB 16 DEST xxxxxxxxxx COMMID 00000011 DEVICE '/dev/cuaa0'
Nov 19 17:00:02.28: [33210]: DELAY 75 ms
Nov 19 17:00:02.36: [33210]: DELAY 2600 ms
Nov 19 17:00:04.97: [33210]: DELAY 10 ms
Nov 19 17:00:04.99: [33210]: <-- [4:ATZ\r]
Nov 19 17:00:05.10: [33210]: --> [2:OK]
Nov 19 17:00:05.10: [33210]: DELAY 3000 ms
Nov 19 17:00:08.11: [33210]: <-- [5:ATE0\r]
Nov 19 17:00:08.12: [33210]: --> [4:ATE0]
Nov 19 17:00:08.12: [33210]: --> [2:OK]
Nov 19 17:00:08.12: [33210]: <-- [5:ATV1\r]
Nov 19 17:00:08.13: [33210]: --> [2:OK]
Nov 19 17:00:08.13: [33210]: <-- [5:ATQ0\r]
Nov 19 17:00:08.14: [33210]: --> [2:OK]
Nov 19 17:00:08.14: [33210]: <-- [7:ATS0=0\r]
Nov 19 17:00:08.15: [33210]: --> [2:OK]
Nov 19 17:00:08.15: [33210]: <-- [7:ATS8=2\r]
Nov 19 17:00:08.16: [33210]: --> [2:OK]
Nov 19 17:00:08.16: [33210]: <-- [8:ATS7=60\r]
Nov 19 17:00:08.17: [33210]: --> [2:OK]
Nov 19 17:00:08.17: [33210]: <-- [6:AT&K3\r]
Nov 19 17:00:08.18: [33210]: --> [2:OK]
Nov 19 17:00:08.18: [33210]: <-- [6:AT&D2\r]
Nov 19 17:00:08.19: [33210]: --> [2:OK]
Nov 19 17:00:08.19: [33210]: <-- [6:AT&C1\r]
Nov 19 17:00:08.20: [33210]: --> [2:OK]
Nov 19 17:00:08.20: [33210]: <-- [12:AT+FCLASS=1\r]
Nov 19 17:00:08.22: [33210]: --> [2:OK]
Nov 19 17:00:08.22: [33210]: <-- [5:ATM0\r]
Nov 19 17:00:08.23: [33210]: --> [2:OK]
Nov 19 17:00:08.23: [33210]: <-- [12:AT+FCLASS=1\r]
Nov 19 17:00:08.35: [33210]: --> [2:OK]
Nov 19 17:00:08.35: [33210]: DIAL xxxxxxxxxx
Nov 19 17:00:08.35: [33210]: <-- [15:ATDTxxxxxxxxxx\r]
Nov 19 17:00:27.06: [33210]: --> [7:CONNECT]
Nov 19 17:00:28.07: [33210]: --> [2:OK]
Nov 19 17:00:28.07: [33210]: REMOTE NSF "AD 00 36 20 00 00 00 00 6D"
Nov 19 17:00:28.07: [33210]: NSF remote fax equipment: HP 
Nov 19 17:00:28.07: [33210]: <-- [9:AT+FRH=3\r]
Nov 19 17:00:28.09: [33210]: --> [7:CONNECT]
Nov 19 17:00:28.76: [33210]: --> [2:OK]
Nov 19 17:00:28.76: [33210]: REMOTE CSI "0"
Nov 19 17:00:28.76: [33210]: <-- [9:AT+FRH=3\r]
Nov 19 17:00:28.78: [33210]: --> [7:CONNECT]
Nov 19 17:00:29.19: [33210]: --> [2:OK]
Nov 19 17:00:29.19: [33210]: REMOTE best rate 14400 bit/s
Nov 19 17:00:29.19: [33210]: REMOTE max page width 1728 pixels in 215 mm
Nov 19 17:00:29.19: [33210]: REMOTE max unlimited page length 
Nov 19 17:00:29.19: [33210]: REMOTE best vres 7.7 line/mm
Nov 19 17:00:29.19: [33210]: REMOTE best format 2-D MMR
Nov 19 17:00:29.19: [33210]: REMOTE supports T.30 Annex A, ECM
Nov 19 17:00:29.19: [33210]: REMOTE best 0 ms/scanline
Nov 19 17:00:29.19: [33210]: USE 14400 bit/s
Nov 19 17:00:29.19: [33210]: USE 0 ms/scanline
Nov 19 17:00:29.19: [33210]: SEND file "docq/doc31.ps;00"
Nov 19 17:00:29.19: [33210]: USE page width 1728 pixels in 215 mm
Nov 19 17:00:29.19: [33210]: USE unlimited page length 
Nov 19 17:00:29.19: [33210]: USE 3.85 line/mm
Nov 19 17:00:29.19: [33210]: USE 1-D MR
Nov 19 17:00:29.19: [33210]: SEND training at v.17 14400 bit/s
Nov 19 17:00:29.19: [33210]: <-- [9:AT+FTH=3\r]
Nov 19 17:00:29.23: [33210]: --> [7:CONNECT]
Nov 19 17:00:29.23: [33210]: <-- data [23]
Nov 19 17:00:29.23: [33210]: <-- data [2]
Nov 19 17:00:31.02: [33210]: --> [7:CONNECT]
Nov 19 17:00:31.02: [33210]: <-- data [6]
Nov 19 17:00:31.02: [33210]: <-- data [2]
Nov 19 17:00:31.45: [33210]: --> [2:OK]
Nov 19 17:00:31.45: [33210]: <-- [9:AT+FTS=7\r]
Nov 19 17:00:31.54: [33210]: --> [2:OK]
Nov 19 17:00:31.54: [33210]: <-- [11:AT+FTM=145\r]
Nov 19 17:00:31.58: [33210]: --> [7:CONNECT]
Nov 19 17:00:31.58: [33210]: <-- data [1024]
Nov 19 17:00:31.58: [33210]: <-- data [1024]
Nov 19 17:00:31.58: [33210]: <-- data [652]
Nov 19 17:00:33.78: [33210]: <-- data [2]
Nov 19 17:00:34.69: [33210]: --> [2:OK]
Nov 19 17:00:34.69: [33210]: <-- [9:AT+FRH=3\r]
Nov 19 17:00:35.13: [33210]: --> [7:CONNECT]
Nov 19 17:00:36.02: [33210]: --> [2:OK]
Nov 19 17:00:36.02: [33210]: TRAINING succeeded
Nov 19 17:00:36.02: [33210]: DELAY 75 ms
Nov 19 17:00:36.11: [33210]: <-- [11:AT+FTM=146\r]
Nov 19 17:00:36.15: [33210]: --> [7:CONNECT]
Nov 19 17:00:36.15: [33210]: SEND begin page
Nov 19 17:00:36.15: [33210]: <-- data [1028]
Nov 19 17:00:36.15: [33210]: <-- data [1024]
Nov 19 17:00:36.15: [33210]: <-- data [382]
Nov 19 17:00:36.16: [33210]: SENT 2430 bytes of data
Nov 19 17:00:36.16: [33210]: SEND 1D RTC
Nov 19 17:00:36.16: [33210]: <-- data [29]
Nov 19 17:00:36.16: [33210]: <-- data [2]
Nov 19 17:00:36.16: [33210]: SEND end page
Nov 19 17:00:37.88: [33210]: --> [2:OK]
Nov 19 17:00:37.88: [33210]: <-- [9:AT+FTS=9\r]
Nov 19 17:00:37.99: [33210]: --> [2:OK]
Nov 19 17:00:37.99: [33210]: SEND send EOP (no more pages or documents)
Nov 19 17:00:37.99: [33210]: <-- [9:AT+FTH=3\r]
Nov 19 17:00:38.03: [33210]: --> [7:CONNECT]
Nov 19 17:00:38.03: [33210]: <-- data [3]
Nov 19 17:00:38.03: [33210]: <-- data [2]
Nov 19 17:00:39.35: [33210]: --> [2:OK]
Nov 19 17:00:39.35: [33210]: <-- [9:AT+FRH=3\r]
Nov 19 17:00:39.74: [33210]: --> [7:CONNECT]
Nov 19 17:00:40.66: [33210]: --> [2:OK]
Nov 19 17:00:40.66: [33210]: SEND recv MCF (message confirmation)
Nov 19 17:00:40.66: [33210]: SEND FAX (00000011): FROM root@box TO xxxxxxxxxx (page 1 of 1 sent in 0:11)
Nov 19 17:00:40.66: [33210]: SEND FAX (00000011): FROM root@box TO xxxxxxxxxx (docq/doc31.ps;00 sent in 0:11)
Nov 19 17:00:40.67: [33210]: <-- [9:AT+FTH=3\r]
Nov 19 17:00:40.71: [33210]: --> [7:CONNECT]
Nov 19 17:00:40.71: [33210]: <-- data [3]
Nov 19 17:00:40.71: [33210]: <-- data [2]
Nov 19 17:00:42.02: [33210]: --> [2:OK]
Nov 19 17:00:42.02: [33210]: <-- [5:ATH0\r]
Nov 19 17:00:42.77: [33210]: --> [2:OK]
Nov 19 17:00:42.77: [33210]: SESSION END
CountryCode:		31
AreaCode:		23
FAXNumber:		"+31 xx xxxxxxx"
LongDistancePrefix:	0
InternationalPrefix:	00
DialStringRules:	etc/dialrules
ServerTracing:		0xFFF
SessionTracing:		1
RecvFileMode:		0600
LogFileMode:		0600
DeviceMode:		0600
RingsBeforeAnswer:	1
SpeakerVolume:		off
GettyArgs:		"-h %l dx_%s"
LocalIdentifier:	"Unconfigged"
TagLineFont:		etc/lutRS18.pcf
TagLineFormat:		"From %%l|%c|Page %%P of %%T"
MaxRecvPages:		25
ModemType:		Class1
ModemRate:		19200
ModemFlowControl:	rtscts
ModemNoFlowCmd:		AT&K0
ModemHardFlowCmd:	AT&K3
ModemSoftFlowCmd:	AT&K4
ModemSetupDTRCmd:	AT&D2
ModemSetupDCDCmd:	AT&C1
ModemMfrQueryCmd:	!Rockwell
ModemModelQueryCmd:	!RC288DPi
ModemRevQueryCmd:	ATI3
ModemDialCmd:		ATDT%s