[hylafax-users] Can send, but can't receive

[Michael Evans <michael@xxxxxxxxxxxxxxx>]

I've been experimenting with hylaFAX on and off for a while, trying to 
get it to work with the following:
Pentium II, 450 MHz, 128Mb RAM
SuSE 7.3
Zoom 2920 PCI modem (Lucent chipset)

On Nov. 28, I got the latest hylafax sources from CVS and built and 
installed them. There's a feature that lets you limit the Class 1 
sending and receiving speeds by substituting your own string for the 
available speeds reported by the modem in response to the AT+FTM=? and 
AT+FRM=? commands. Once I've limited the modem to 4800 bps, sending 
works great. However, when the modem's phone number is called, it 
doesn't seem to get to the speed negotiation part:

Nov 29 17:23:39.88: [  938]: SESSION BEGIN 00000108 16264494440
Nov 29 17:23:39.88: [  938]: HylaFAX (tm) Version 4.1.6
Nov 29 17:23:39.88: [  938]: <-- [4:ATA\r]
Nov 29 17:23:46.01: [  938]: --> [7:CONNECT]
Nov 29 17:23:46.01: [  938]: ANSWER: FAX CONNECTION  DEVICE '/dev/ttyS4'
Nov 29 17:23:46.01: [  938]: MODEM input buffering enabled
Nov 29 17:23:46.01: [  938]: RECV FAX: begin
Nov 29 17:23:46.01: [  938]: MODEM input buffering disabled
Nov 29 17:23:46.01: [  938]: <-- HDLC<32:FF C0 04 AD 00 55 12 9E 36 86 
62 82 1A 04 14 2E B6 94 04 6A A6 4E CE 96 F6 76 04 2C 74 8C 74 6C>
Nov 29 17:23:46.01: [  938]: <-- data [32]
Nov 29 17:23:46.01: [  938]: <-- data [2]
Nov 29 17:23:46.02: [  938]: --> [7:CONNECT]
Nov 29 17:23:46.02: [  938]: <-- HDLC<23:FF C0 02 74 C6 76 92 04 34 16 
2E 96 B6 CA 04 64 04 86 EE 86 E6 F6 2A>
Nov 29 17:23:46.02: [  938]: <-- data [23]
Nov 29 17:23:46.02: [  938]: <-- data [2]
Nov 29 17:23:46.02: [  938]: --> [7:CONNECT]
Nov 29 17:23:46.03: [  938]: <-- HDLC<10:FF C8 01 00 77 5F 01 79 FB C0>
Nov 29 17:23:46.03: [  938]: <-- data [10]
Nov 29 17:23:46.03: [  938]: <-- data [2]
Nov 29 17:23:48.17: [  938]: --> [2:OK]
Nov 29 17:23:48.17: [  938]: <-- [9:AT+FRH=3\r]
Nov 29 17:23:48.27: [  938]: --> [7:CONNECT]
Nov 29 17:23:49.97: [  938]: --> HDLC<25:FF C0 C2 0C 2C 2C 2C 9C 2C 2C 
6C 4C 6C 04 04 04 04 04 04 04 04 04 04 7B D6>
Nov 29 17:23:49.97: [  938]: --> [2:OK]
Nov 29 17:23:49.98: [  938]: REMOTE TSI "6264494440"
Nov 29 17:23:49.98: [  938]: <-- [9:AT+FRH=3\r]
Nov 29 17:23:49.99: [  938]: --> [7:CONNECT]
Nov 29 17:23:50.33: [  938]: --> HDLC<11:FF C1 00 45 1F 01 01 01 00 CC D3>
Nov 29 17:23:50.33: [  938]: --> [2:OK]
Nov 29 17:23:50.33: [  938]: HDLC frame with bad control field 0xc1
Nov 29 17:23:50.33: [  938]: DELAY 1500 ms
Nov 29 17:23:51.83: [  938]: <-- [9:AT+FTH=3\r]
Nov 29 17:23:54.83: [  938]: --> [0:]
Nov 29 17:23:54.83: [  938]: MODEM TIMEOUT: sending HDLC frame
Nov 29 17:23:54.83: [  938]: DELAY 1500 ms
Nov 29 17:23:56.33: [  938]: <-- [9:AT+FTH=3\r]
Nov 29 17:23:59.33: [  938]: --> [0:]
Nov 29 17:23:59.33: [  938]: MODEM TIMEOUT: sending HDLC frame
Nov 29 17:23:59.33: [  938]: DELAY 1500 ms
Nov 29 17:24:00.83: [  938]: <-- [9:AT+FTH=3\r]
Nov 29 17:24:03.83: [  938]: --> [0:]
Nov 29 17:24:03.83: [  938]: MODEM TIMEOUT: sending HDLC frame
Nov 29 17:24:03.83: [  938]: DELAY 1500 ms
Nov 29 17:24:05.33: [  938]: <-- [9:AT+FTH=3\r]
Nov 29 17:24:08.33: [  938]: --> [0:]
Nov 29 17:24:08.33: [  938]: MODEM TIMEOUT: sending HDLC frame
Nov 29 17:24:08.33: [  938]: DELAY 1500 ms
Nov 29 17:24:09.83: [  938]: <-- [9:AT+FTH=3\r]
Nov 29 17:24:12.83: [  938]: --> [0:]
Nov 29 17:24:12.83: [  938]: MODEM TIMEOUT: sending HDLC frame
Nov 29 17:24:12.83: [  938]: DELAY 1500 ms
Nov 29 17:24:14.33: [  938]: <-- [9:AT+FTH=3\r]
Nov 29 17:24:17.33: [  938]: --> [0:]
Nov 29 17:24:17.33: [  938]: MODEM TIMEOUT: sending HDLC frame
Nov 29 17:24:17.33: [  938]: DELAY 1500 ms
Nov 29 17:24:18.83: [  938]: <-- [9:AT+FTH=3\r]
Nov 29 17:24:21.83: [  938]: --> [0:]
Nov 29 17:24:21.83: [  938]: MODEM TIMEOUT: sending HDLC frame
Nov 29 17:24:21.83: [  938]: DELAY 1500 ms
Nov 29 17:24:23.33: [  938]: <-- [9:AT+FTH=3\r]
Nov 29 17:24:26.33: [  938]: --> [0:]
Nov 29 17:24:26.33: [  938]: MODEM TIMEOUT: sending HDLC frame
Nov 29 17:24:26.33: [  938]: RECV FAX: RSPREC invalid response received
Nov 29 17:24:26.33: [  938]: RECV FAX: end
Nov 29 17:24:26.33: [  938]: SESSION END

It always chokes on that one HDLC frame with the "bad control field 
0xc1".  It does this regardless of which machine is placing the call 
(machines calling from different phone numbers).  After "SESSION END" , 
when the log file is completed, the modem is no longer able to answer 
any calls.  Any subsequent callers just hear the phone ringing 
endlessly, with no answer (and no busy signal). If I query the hylafax 
server with WHFC, it reports "receiving facsimile" instead of the usual 
"running and idle." The only way to make the modem usable again is to 
stop hylafax, issue the "faxquit" command, kill the faxgetty process, 
and then restart hylafax. Meanwhile, depending on his machine, the 
sender gets a "NG - Poor Line Condition" error message or something 
similar, and his machine hangs up.

In searching through the message logs, I got a hint that this might be 
due to an outdated libtiff (I had v. 3.4), so I went to libtiff.org and 
got the latest version, v 3.6. But building and installing the latest 
version over the old one didn't help. Attempting to receive a fax still 
results in the very same log as above.

In addition to the session log, the server log has these 4 lines:

Nov 29 17:23:39 faxer FaxGetty[938]: --> [4:RING]
Nov 29 17:23:46 faxer FaxGetty[938]: ANSWER: FAX CONNECTION  DEVICE 
'/dev/ttyS4'
Nov 29 17:24:26 faxer FaxGetty[938]: <-- [5:ATH0\r]
Nov 29 17:24:31 faxer FaxGetty[938]: MODEM <Timeout>

It stays this way until hylafax is stopped and restarted as noted above.

Does this give anyone any clues as to what might be happening? TIA for 
any help you might be able to give.

Michael Evans



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