[hylafax-users] hylafax hangs periodically

[Bret Hughes <bhughes@elevating.com>]

trying to track down an issue with hylafax 4.5.1 on redhat 8.0 with usr
sportster 33K modem

This time it worked fine for hours and then it simply hung.  faxstat
reports that it is receiving fax.  The log below shows that the process
hung until i did a service hylfax restart and the CLOSE was issued.

this box gets 3 or 4 faxes an hour and it is not good that it hangs with
no indication.

Tips any one?  I snipped some lines from the middle of the 180+line log
to reduce bandwith.


I assume that the line right before the CLOSE is the culprit.
interesting that cat and less show it differently.

at the very end is a hexdump -C of the last couple of lines

Is there a time out parameter I can set to keep this from happening?  I
can send my config.ttyS0 if needed.


Mar 25 11:32:46.41: [ 4012]: SESSION BEGIN 00000062 19186644119
Mar 25 11:32:46.41: [ 4012]: HylaFAX (tm) Version 4.1.5
Mar 25 11:32:46.41: [ 4012]: <-- [13:AT+FCLASS=1A\r]
Mar 25 11:32:51.20: [ 4012]: --> [7:CONNECT]
Mar 25 11:32:51.20: [ 4012]: ANSWER: FAX CONNECTION  DEVICE '/dev/ttyS0'
Mar 25 11:32:51.20: [ 4012]: RECV FAX: begin
Mar 25 11:32:51.20: [ 4012]: <-- data [23]
Mar 25 11:32:51.20: [ 4012]: <-- data [2]
Mar 25 11:32:51.22: [ 4012]: --> [7:CONNECT]
Mar 25 11:32:51.22: [ 4012]: <-- data [10]
Mar 25 11:32:51.22: [ 4012]: <-- data [2]
Mar 25 11:32:53.18: [ 4012]: --> [2:OK]
Mar 25 11:32:53.18: [ 4012]: <-- [9:AT+FRH=3\r]
Mar 25 11:32:53.61: [ 4012]: --> [7:CONNECT]
Mar 25 11:32:54.98: [ 4012]: --> [2:OK]
Mar 25 11:32:54.98: [ 4012]: REMOTE TSI "1 918 272 1164"
Mar 25 11:32:54.98: [ 4012]: <-- [9:AT+FRH=3\r]
Mar 25 11:32:55.00: [ 4012]: --> [7:CONNECT]
Mar 25 11:32:55.28: [ 4012]: --> [2:OK]
Mar 25 11:32:55.28: [ 4012]: REMOTE wants 14400 bit/s
Mar 25 11:32:55.28: [ 4012]: REMOTE wants page width 1728 pixels in 215
mm
Mar 25 11:32:55.28: [ 4012]: REMOTE wants unlimited page length 
Mar 25 11:32:55.28: [ 4012]: REMOTE wants 3.85 line/mm
Mar 25 11:32:55.28: [ 4012]: REMOTE wants 2-D MR
Mar 25 11:32:55.28: [ 4012]: RECV training at v.17 14400 bit/s
Mar 25 11:32:55.28: [ 4012]: <-- [11:AT+FRM=145\r]
Mar 25 11:32:56.97: [ 4012]: --> [7:CONNECT]
Mar 25 11:32:58.53: [ 4012]: RECV: TCF 2812 bytes, 3% non-zero, 2711
zero-run
Mar 25 11:32:58.54: [ 4012]: --> [10:NO CARRIER]
Mar 25 11:32:58.54: [ 4012]: DELAY 75 ms
Mar 25 11:32:58.62: [ 4012]: TRAINING succeeded
Mar 25 11:32:58.62: [ 4012]: <-- [9:AT+FTH=3\r]
Mar 25 11:32:58.81: [ 4012]: --> [7:CONNECT]
Mar 25 11:32:58.81: [ 4012]: <-- data [3]
Mar 25 11:32:58.81: [ 4012]: <-- data [2]
Mar 25 11:32:59.99: [ 4012]: --> [2:OK]
Mar 25 11:32:59.99: [ 4012]: <-- [11:AT+FRM=146\r]
Mar 25 11:33:00.64: [ 4012]: --> [7:CONNECT]
Mar 25 11:33:00.64: [ 4012]: RECV: begin page
Mar 25 11:33:19.40: [ 4012]: RECV: 1072 total lines, 0 bad lines, 0
consecutive bad lines
Mar 25 11:33:19.40: [ 4012]: RECV: end page
Mar 25 11:33:19.40: [ 4012]: --> [10:NO CARRIER]
Mar 25 11:33:19.40: [ 4012]: <-- [9:AT+FRH=3\r]
Mar 25 11:33:19.66: [ 4012]: --> [7:CONNECT]
Mar 25 11:33:20.54: [ 4012]: --> [2:OK]
Mar 25 11:33:20.54: [ 4012]: RECV recv MPS (more pages, same document)
Mar 25 11:33:20.54: [ 4012]: <-- [9:AT+FRS=7\r]
Mar 25 11:33:21.30: [ 4012]: --> [2:OK]
Mar 25 11:33:21.30: [ 4012]: <-- [9:AT+FTH=3\r]
Mar 25 11:33:21.49: [ 4012]: --> [7:CONNECT]
Mar 25 11:33:21.49: [ 4012]: RECV send MCF (message confirmation)
Mar 25 11:33:21.49: [ 4012]: RECV FAX (00000062): from 1 918 272 1164,
page 1 in 0:23, INF, 3.85 line/mm, 2-D MR, 14400 bit/s
Mar 25 11:33:21.49: [ 4012]: <-- data [3]
Mar 25 11:33:21.49: [ 4012]: <-- data [2]
Mar 25 11:33:22.67: [ 4012]: --> [2:OK]
Mar 25 11:33:22.67: [ 4012]: <-- [11:AT+FRM=146\r]
Mar 25 11:33:23.32: [ 4012]: --> [7:CONNECT]
Mar 25 11:33:23.32: [ 4012]: RECV: begin page
Mar 25 11:33:42.09: [ 4012]: RECV: 1077 total lines, 0 bad lines, 0
consecutive bad lines
Mar 25 11:33:42.09: [ 4012]: RECV: end page
Mar 25 11:33:42.09: [ 4012]: --> [10:NO CARRIER]
Mar 25 11:33:42.09: [ 4012]: <-- [9:AT+FRH=3\r]
Mar 25 11:33:42.43: [ 4012]: --> [7:CONNECT]
Mar 25 11:33:43.24: [ 4012]: --> [2:OK]
Mar 25 11:33:43.24: [ 4012]: RECV recv MPS (more pages, same document)
Mar 25 11:33:43.24: [ 4012]: <-- [9:AT+FRS=7\r]
Mar 25 11:33:43.34: [ 4012]: --> [2:OK]
Mar 25 11:33:43.34: [ 4012]: <-- [9:AT+FTH=3\r]
Mar 25 11:33:43.54: [ 4012]: --> [7:CONNECT]
Mar 25 11:33:43.54: [ 4012]: RECV send MCF (message confirmation)
Mar 25 11:33:43.54: [ 4012]: RECV FAX (00000062): from 1 918 272 1164,
page 2 in 0:22, INF, 3.85 line/mm, 2-D MR, 14400

 <snip>

Mar 25 11:35:39.35: [ 4012]: --> [10:NO CARRIER]
Mar 25 11:35:39.35: [ 4012]: <-- [9:AT+FRH=3\r]
Mar 25 11:35:39.68: [ 4012]: --> [7:CONNECT]
Mar 25 11:35:40.51: [ 4012]: --> [2:OK]
Mar 25 11:35:40.51: [ 4012]: RECV recv MPS (more pages, same document)
Mar 25 11:35:40.51: [ 4012]: <-- [9:AT+FRS=7\r]
Mar 25 11:35:41.93: [ 4012]: --> [2:OK]
Mar 25 11:35:41.93: [ 4012]: <-- [9:AT+FTH=3\r]
Mar 25 11:35:42.13: [ 4012]: --> [7:CONNECT]
Mar 25 11:35:42.13: [ 4012]: RECV send MCF (message confirmation)
Mar 25 11:35:42.13: [ 4012]: RECV FAX (00000062): from 1 918 272 1164,
page 7 in 0:27, INF, 3.85 line/mm, 2-D MR, 14400 bit/s
Mar 25 11:35:42.13: [ 4012]: <-- data [3]
Mar 25 11:35:42.13: [ 4012]: <-- data [2]
Mar 25 11:35:43.30: [ 4012]: --> [2:OK]
Mar 25 11:35:43.30: [ 4012]: <-- [11:AT+FRM=146\r]
Mar 25 11:35:43.96: [ 4012]: --> [7:CONNECT]
Mar 25 11:35:43.96: [ 4012]: RECV: begin page
Mar 25 11:36:02.23: [ 4012]: RECV: 1075 total lines, 0 bad lines, 0
consecutive bad lines
Mar 25 11:36:02.23: [ 4012]: RECV: end page
Mar 25 11:36:02.33: [ 4012]: --> [10:NO CARRIER]
Mar 25 11:36:02.33: [ 4012]: <-- [9:AT+FRH=3\r]
Mar 25 11:36:02.45: [ 4012]: --> [7:CONNECT]
Mar 25 11:36:03.46: [ 4012]: --> [2:OK]
Mar 25 11:36:03.46: [ 4012]: RECV recv MPS (more pages, same document)
Mar 25 11:36:03.46: [ 4012]: <-- [9:AT+FRS=7\r]
Mar 25 11:36:03.57: [ 4012]: --> [2:OK]
Mar 25 11:36:03.57: [ 4012]: <-- [9:AT+FTH=3\r]
Mar 25 11:36:33.57: [ 4012]: MODEM <Timeout>
Mar 25 11:36:33.57: [ 4012]: RECV send MCF (message confirmation)
Mar 25 11:36:33.57: [ 4012]: RECV FAX (00000062): from 1 918 272 1164,
page 8 in 0:51, INF, 3.85 line/mm, 2-D MR, 14400 bit/s
Mar 25 11:36:33.57: [ 4012]: <-- data [3]
Mar 25 11:36:33.57: [ 4012]: <-- data [2]
Mar 25 11:36:36.13: [ 4012]: --> [5:ÿ]
Mar 25 12:47:46.17: [ 4012]: CLOSE /dev/ttyS0

000026f0  5b 32 5d 0a 4d 61 72 20  32 35 20 31 31 3a 33 36  |[2].Mar 25 11:36|
00002700  3a 33 36 2e 31 33 3a 20  5b 20 34 30 31 32 5d 3a  |:36.13: [ 4012]:|
00002710  20 2d 2d 3e 20 5b 35 3a  ff 13 8c 10 03 5d 0a 4d  | --> [5:ÿ....].M|
00002720  61 72 20 32 35 20 31 32  3a 34 37 3a 34 36 2e 31  |ar 25 12:47:46.1|
00002730  37 3a 20 5b 20 34 30 31  32 5d 3a 20 43 4c 4f 53  |7: [ 4012]: CLOS|
00002740  45 20 2f 64 65 76 2f 74  74 79 53 30 0a           |E /dev/ttyS0.|




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