[hylafax-users] Modem gets wedged after receiving a fax

["Nacef L" <nacef.l@xxxxxxxxx>]

Hi all,

I have recently installed hylafax on a server at my office. It is
running well for sending and receiving. Although sometimes during
receiving a fax the transmission is stopped and the modem can't
recover from it. So my hylafax server keeps waiting for modm to get
ready and faxgetty after three failing retries reports that the modem
is wedged.
I am forced then to turn off/on my modem and it continues working nomally.

I have attached the log script of the failing fax. Please help,
because it is very annoying to me and I am so habituated to using this
solution that I can't think to work with my old fax machine again.

Thks

Jan 08 11:51:17.77: [ 3743]: SESSION BEGIN 000000120 +216xxxxxxxx
Jan 08 11:51:17.77: [ 3743]: HylaFAX (tm) Version 5.1.11
Jan 08 11:51:17.77: [ 3743]: <-- [4:ATA\r]
Jan 08 11:51:23.84: [ 3743]: --> [7:CONNECT]
Jan 08 11:51:23.84: [ 3743]: ANSWER: FAX CONNECTION  DEVICE '/dev/ttyS0'
Jan 08 11:51:23.84: [ 3743]: STATE CHANGE: ANSWERING -> RECEIVING
Jan 08 11:51:23.84: [ 3743]: RECV FAX: begin
Jan 08 11:51:23.84: [ 3743]: <-- HDLC<33:FF C0 04 B5 00 AA 12 9E 36 86 62 82 1A 04 14 2E B6 94 04 6A A6 4E CE 96 F6 76 04 AC 74 8C 74 8C 8C>
Jan 08 11:51:23.84: [ 3743]: <-- data [33]
Jan 08 11:51:23.84: [ 3743]: <-- data [2]
Jan 08 11:51:25.89: [ 3743]: --> [7:CONNECT]
Jan 08 11:51:25.89: [ 3743]: <-- HDLC<23:FF C0 02 04 CE 96 76 82 04 92 32 12 A2 CA B2 04 2E A6 76 96 46 86 C2>
Jan 08 11:51:25.89: [ 3743]: <-- data [23]
Jan 08 11:51:25.89: [ 3743]: <-- data [2]
Jan 08 11:51:26.71: [ 3743]: --> [7:CONNECT]
Jan 08 11:51:26.71: [ 3743]: <-- HDLC<13:FF C8 01 00 77 5F 23 01 FB C1 01 01 18>
Jan 08 11:51:26.71: [ 3743]: <-- data [13]
Jan 08 11:51:26.71: [ 3743]: <-- data [2]
Jan 08 11:51:27.35: [ 3743]: --> [2:OK]
Jan 08 11:51:27.35: [ 3743]: <-- [9:AT+FRH=3\r]
Jan 08 11:51:27.78: [ 3743]: --> [7:CONNECT]
Jan 08 11:51:29.20: [ 3743]: --> HDLC<25:FF C0 C2 9C 9C 4C 0C 9C CC 0C EC 6C 8C 4C 0C 0C 04 04 04 04 04 04 04 47 A3>
Jan 08 11:51:29.21: [ 3743]: --> [2:OK]
Jan 08 11:51:29.21: [ 3743]: RECV recv TSI (sender id)
Jan 08 11:51:29.21: [ 3743]: REMOTE TSI "00216xxxxxxxx"
Jan 08 11:51:29.21: [ 3743]: <-- [9:AT+FRH=3\r]
Jan 08 11:51:29.26: [ 3743]: --> [7:CONNECT]
Jan 08 11:51:29.50: [ 3743]: --> HDLC<9:FF C8 C1 00 46 1F 22 39 BB>
Jan 08 11:51:29.50: [ 3743]: --> [2:OK]
Jan 08 11:51:29.50: [ 3743]: RECV recv DCS (command signal)
Jan 08 11:51:29.50: [ 3743]: REMOTE wants 14400 bit/s
Jan 08 11:51:29.50: [ 3743]: REMOTE wants A4 page width (215 mm)
Jan 08 11:51:29.50: [ 3743]: REMOTE wants unlimited page length
Jan 08 11:51:29.50: [ 3743]: REMOTE wants 7.7 line/mm
Jan 08 11:51:29.50: [ 3743]: REMOTE wants 2-D MMR
Jan 08 11:51:29.50: [ 3743]: REMOTE wants T.30 Annex A, 256-byte ECM
Jan 08 11:51:29.50: [ 3743]: RECV training at v.17 14400 bit/s
Jan 08 11:51:29.50: [ 3743]: <-- [11:AT+FRM=145\r]
Jan 08 11:51:31.19: [ 3743]: --> [7:CONNECT]
Jan 08 11:51:32.74: [ 3743]: RECV: TCF 2758 bytes, 1% non-zero, 2714 zero-run
Jan 08 11:51:32.75: [ 3743]: --> [10:NO CARRIER]
Jan 08 11:51:32.75: [ 3743]: <-- [9:AT+FRS=7\r]
Jan 08 11:51:32.90: [ 3743]: --> [2:OK]
Jan 08 11:51:32.90: [ 3743]: TRAINING succeeded
Jan 08 11:51:32.90: [ 3743]: <-- [9:AT+FTH=3\r]
Jan 08 11:51:32.97: [ 3743]: --> [7:CONNECT]
Jan 08 11:51:32.97: [ 3743]: <-- HDLC<3:FF C8 21>
Jan 08 11:51:32.97: [ 3743]: <-- data [3]
Jan 08 11:51:32.97: [ 3743]: <-- data [2]
Jan 08 11:51:34.27: [ 3743]: --> [2:OK]
Jan 08 11:51:34.27: [ 3743]: <-- [11:AT+FRM=146\r]
Jan 08 11:51:34.91: [ 3743]: --> [7:CONNECT]
Jan 08 11:51:34.91: [ 3743]: MODEM input buffering enabled
Jan 08 11:51:36.61: [ 3743]: RECV received frame number 0
Jan 08 11:51:36.75: [ 3743]: RECV received frame number 1
Jan 08 11:51:36.89: [ 3743]: RECV received frame number 2
Jan 08 11:51:37.96: [ 3743]: RECV received frame number 3
Jan 08 11:51:38.24: [ 3743]: RECV received frame number 4
Jan 08 11:51:38.45: [ 3743]: RECV received frame number 5
Jan 08 11:51:38.59: [ 3743]: RECV received frame number 6
Jan 08 11:51:38.73: [ 3743]: RECV received frame number 7
Jan 08 11:51:38.87: [ 3743]: RECV received frame number 8
Jan 08 11:51:39.01: [ 3743]: RECV received frame number 9
Jan 08 11:51:39.22: [ 3743]: RECV received frame number 10
Jan 08 11:51:39.43: [ 3743]: RECV received frame number 11
Jan 08 11:51:39.64: [ 3743]: RECV received frame number 12
Jan 08 11:51:39.83: [ 3743]: Bad HDLC terminating flag received.
Jan 08 11:52:39.83: [ 3743]: Timeout awaiting synchronization sequence
Jan 08 11:52:39.83: [ 3743]: Bad HDLC terminating flag received.
Jan 08 11:53:39.00: [ 3743]: Timeout awaiting synchronization sequence
Jan 08 11:53:39.00: [ 3743]: Bad HDLC terminating flag received.
Jan 08 11:54:39.00: [ 3743]: Timeout awaiting synchronization sequence
Jan 08 11:54:39.00: [ 3743]: Bad HDLC terminating flag received.
Jan 08 11:55:39.00: [ 3743]: Timeout awaiting synchronization sequence
Jan 08 11:55:39.00: [ 3743]: Bad HDLC terminating flag received.
Jan 08 11:56:39.00: [ 3743]: Timeout awaiting synchronization sequence
Jan 08 11:56:39.00: [ 3743]: MODEM input buffering disabled
Jan 08 11:57:09.00: [ 3743]: MODEM TIMEOUT: reading from modem
Jan 08 11:57:09.00: [ 3743]: <-- data [1]
Jan 08 11:57:09.20: [ 3743]: MODEM TIMEOUT: reading line from modem
Jan 08 11:57:09.20: [ 3743]: MODEM <Timeout>
Jan 08 11:57:09.20: [ 3743]: Timeout waiting for Phase C carrier drop. {E154}
Jan 08 11:57:09.20: [ 3743]: RECV: 3328 bytes of data, 354 total lines
Jan 08 11:57:09.20: [ 3743]: RECV keeping unconfirmed page
Jan 08 11:57:09.20: [ 3743]: RECV FAX (000000120): from 00216xxxxxxxx, page 1 in 5:46, INF, 7.7 line/mm, 2-D MMR, 14400 bit/s
Jan 08 11:57:10.21: [ 3743]: RECV FAX (000000120): recvq/fax000000037.tif from 00216xxxxxxxx, subaddress <unspecified>, 1 pages in 5:46
Jan 08 11:57:10.21: [ 3743]: RECV FAX: Timeout waiting for Phase C carrier drop. {E154}
Jan 08 11:57:10.21: [ 3743]: <-- [9:AT+FRS=7\r]
Jan 08 11:57:40.21: [ 3743]: MODEM TIMEOUT: reading line from modem
Jan 08 11:57:40.21: [ 3743]: MODEM <Timeout>
Jan 08 11:57:40.21: [ 3743]: Failure to receive silence (synchronization failure). {E100}
Jan 08 11:57:40.21: [ 3743]: <-- data [1]
Jan 08 11:57:40.41: [ 3743]: MODEM TIMEOUT: reading line from modem
Jan 08 11:57:40.41: [ 3743]: MODEM <Timeout>
Jan 08 11:57:40.41: [ 3743]: <-- [9:AT+FTH=3\r]
Jan 08 11:57:47.96: [ 3743]: --> [0:]
Jan 08 11:57:47.96: [ 3743]: MODEM TIMEOUT: sending HDLC frame
Jan 08 11:57:47.96: [ 3743]: MODEM input buffering enabled
Jan 08 11:57:47.96: [ 3743]: RECV FAX (000000120): session with 00216xxxxxxxx terminated abnormally: Timeout waiting for Phase C carrier drop. {E154}
Jan 08 11:57:47.96: [ 3743]: RECV FAX: bin/faxrcvd 'recvq/fax000000037.tif' 'ttyS0' '000000120' 'Timeout waiting for Phase C carrier drop. {E154}'
Jan 08 11:57:47.97: [ 3743]: RECV FAX: end
Jan 08 11:57:47.97: [ 3743]: SESSION END