[hylafax-users] SendJob gets hung

This is 4.2.2 version Hylafax running under SuSe 9.0. The modems are the dreaded USR. I am having Sendfax jobs hanging. I have to use kill –HUP to get the process stopped.

Here is the log of the hanging job.

Jul 21 07:27:01.07: [ 9329]: SESSION BEGIN 000000228 1xxxxxxxxxx

Jul 21 07:27:01.07: [ 9329]: HylaFAX (tm) Version 4.2.2beta1

Jul 21 07:27:01.08: [ 9329]: SEND FAX: JOB 9875 DEST xxxxxxxxxx COMMID 000000228 DEVICE '/dev/ttyS0'

Jul 21 07:27:01.08: [ 9329]: <-- [12:AT+FCLASS=1\r]

Jul 21 07:27:01.21: [ 9329]: --> [2:OK]

Jul 21 07:27:01.22: [ 9329]: DIAL xxxxxxxxxx

Jul 21 07:27:01.22: [ 9329]: <-- [15:ATDTxxxxxxxxxx\r]

Jul 21 07:27:21.00: [ 9329]: --> [7:CONNECT]

Jul 21 07:27:22.52: [ 9329]: --> [2:OK]

Jul 21 07:27:22.52: [ 9329]: REMOTE NSF "00 00 11 80 FF 8A 48 00 5E 00 80 80 80 0E 01 01 01 03"

Jul 21 07:27:22.52: [ 9329]: NSF remote fax equipment: Canon

Jul 21 07:27:22.52: [ 9329]: <-- [9:AT+FRH=3\r]

Jul 21 07:27:22.54: [ 9329]: --> [7:CONNECT]

Jul 21 07:27:22.95: [ 9329]: --> [2:OK]

Jul 21 07:27:22.95: [ 9329]: REMOTE best rate 33600 bit/s

Jul 21 07:27:22.95: [ 9329]: REMOTE max A4 page width (215 mm)

Jul 21 07:27:22.95: [ 9329]: REMOTE max unlimited page length

Jul 21 07:27:22.95: [ 9329]: REMOTE best vres R16 x 15.4 line/mm

Jul 21 07:27:22.95: [ 9329]: REMOTE best format 2-D MMR

Jul 21 07:27:22.95: [ 9329]: REMOTE supports T.30 Annex A, 256-byte ECM

Jul 21 07:27:22.95: [ 9329]: REMOTE best 0 ms/scanline

Jul 21 07:27:22.95: [ 9329]: USE 14400 bit/s

Jul 21 07:27:22.95: [ 9329]: USE error correction mode

Jul 21 07:27:22.95: [ 9329]: SEND file "docq/doc9863.tif;c1"

Jul 21 07:27:22.95: [ 9329]: USE A4 page width (215 mm)

Jul 21 07:27:22.95: [ 9329]: USE unlimited page length

Jul 21 07:27:22.95: [ 9329]: USE 7.7 line/mm

Jul 21 07:27:22.95: [ 9329]: USE 2-D MMR

Jul 21 07:27:22.95: [ 9329]: USE 0 ms/scanline

Jul 21 07:27:22.95: [ 9329]: SEND training at v.17 14400 bit/s

Jul 21 07:27:22.95: [ 9329]: <-- [9:AT+FRS=7\r]

Jul 21 07:27:23.72: [ 9329]: --> [2:OK]

Jul 21 07:27:23.72: [ 9329]: <-- [9:AT+FTH=3\r]

Jul 21 07:27:23.91: [ 9329]: --> [7:CONNECT]

Jul 21 07:27:23.91: [ 9329]: <-- data [23]

Jul 21 07:27:23.91: [ 9329]: <-- data [2]

Jul 21 07:27:23.93: [ 9329]: --> [7:CONNECT]

Jul 21 07:27:23.93: [ 9329]: <-- data [7]

Jul 21 07:27:23.93: [ 9329]: <-- data [2]

Jul 21 07:27:25.91: [ 9329]: --> [2:OK]

Jul 21 07:27:25.91: [ 9329]: <-- [9:AT+FTS=7\r]

Jul 21 07:27:26.00: [ 9329]: --> [2:OK]

Jul 21 07:27:26.00: [ 9329]: <-- [11:AT+FTM=145\r]

Jul 21 07:27:27.48: [ 9329]: --> [7:CONNECT]

Jul 21 07:27:27.48: [ 9329]: <-- data [1024]

Jul 21 07:27:27.48: [ 9329]: <-- data [652]

Jul 21 07:27:27.48: [ 9329]: <-- data [2]

Jul 21 07:27:29.00: [ 9329]: --> [2:OK]

Jul 21 07:27:29.00: [ 9329]: <-- [9:AT+FRS=1\r]

Jul 21 07:27:29.03: [ 9329]: --> [2:OK]

Jul 21 07:27:29.03: [ 9329]: <-- [9:AT+FRH=3\r]

Jul 21 07:27:29.44: [ 9329]: --> [7:CONNECT]

Jul 21 07:27:30.44: [ 9329]: --> [2:OK]

Jul 21 07:27:30.44: [ 9329]: SEND training at v.17 12000 bit/s

Jul 21 07:27:30.44: [ 9329]: <-- [9:AT+FRS=7\r]

Jul 21 07:27:31.21: [ 9329]: --> [2:OK]

Jul 21 07:27:31.21: [ 9329]: <-- [9:AT+FTH=3\r]

Jul 21 07:27:31.40: [ 9329]: --> [7:CONNECT]

Jul 21 07:27:31.40: [ 9329]: <-- data [23]

Jul 21 07:27:31.40: [ 9329]: <-- data [2]

Jul 21 07:27:31.42: [ 9329]: --> [7:CONNECT]

Jul 21 07:27:31.42: [ 9329]: <-- data [7]

Jul 21 07:27:31.42: [ 9329]: <-- data [2]

Jul 21 07:27:33.41: [ 9329]: --> [2:OK]

Jul 21 07:27:33.41: [ 9329]: <-- [9:AT+FTS=7\r]

Jul 21 07:27:33.49: [ 9329]: --> [2:OK]

Jul 21 07:27:33.49: [ 9329]: <-- [11:AT+FTM=121\r]

Jul 21 07:27:34.97: [ 9329]: --> [7:CONNECT]

Jul 21 07:27:34.97: [ 9329]: <-- data [1024]

Jul 21 07:27:34.97: [ 9329]: <-- data [202]

Jul 21 07:27:34.97: [ 9329]: <-- data [2]

Jul 21 07:27:36.50: [ 9329]: --> [2:OK]

Jul 21 07:27:36.50: [ 9329]: <-- [9:AT+FRS=1\r]

Jul 21 07:27:36.52: [ 9329]: --> [2:OK]

Jul 21 07:27:36.52: [ 9329]: <-- [9:AT+FRH=3\r]

Jul 21 07:27:36.73: [ 9329]: --> [7:CONNECT]

Jul 21 07:27:37.90: [ 9329]: --> [2:OK]

Jul 21 07:27:37.90: [ 9329]: SEND training at v.17 9600 bit/s

Jul 21 07:27:37.90: [ 9329]: <-- [9:AT+FRS=7\r]

Jul 21 07:27:38.00: [ 9329]: --> [2:OK]

Jul 21 07:27:38.00: [ 9329]: <-- [9:AT+FTH=3\r]

Jul 21 07:27:38.19: [ 9329]: --> [7:CONNECT]

Jul 21 07:27:38.19: [ 9329]: <-- data [23]

Jul 21 07:27:38.19: [ 9329]: <-- data [2]

Jul 21 07:27:38.22: [ 9329]: --> [7:CONNECT]

Jul 21 07:27:38.22: [ 9329]: <-- data [7]

Jul 21 07:27:38.22: [ 9329]: <-- data [2]

Jul 21 07:27:40.22: [ 9329]: --> [2:OK]

Jul 21 07:27:40.22: [ 9329]: <-- [9:AT+FTS=7\r]

Jul 21 07:27:40.30: [ 9329]: --> [2:OK]

Jul 21 07:27:40.30: [ 9329]: <-- [10:AT+FTM=97\r]

Jul 21 07:27:41.78: [ 9329]: --> [7:CONNECT]

Jul 21 07:27:41.78: [ 9329]: <-- data [1024]

Jul 21 07:27:41.78: [ 9329]: <-- data [776]

Jul 21 07:27:41.78: [ 9329]: <-- data [2]

Jul 21 07:27:43.32: [ 9329]: --> [2:OK]

Jul 21 07:27:43.32: [ 9329]: <-- [9:AT+FRS=1\r]

Jul 21 07:27:43.34: [ 9329]: --> [2:OK]

Jul 21 07:27:43.34: [ 9329]: <-- [9:AT+FRH=3\r]

Jul 21 07:27:43.84: [ 9329]: --> [7:CONNECT]

Jul 21 07:27:44.74: [ 9329]: --> [2:OK]

Jul 21 07:27:44.74: [ 9329]: SEND training at v.29 9600 bit/s

Jul 21 07:27:44.74: [ 9329]: <-- [9:AT+FRS=7\r]

Jul 21 07:27:45.50: [ 9329]: --> [2:OK]

Jul 21 07:27:45.50: [ 9329]: <-- [9:AT+FTH=3\r]

Jul 21 07:27:45.70: [ 9329]: --> [7:CONNECT]

Jul 21 07:27:45.70: [ 9329]: <-- data [23]

Jul 21 07:27:45.70: [ 9329]: <-- data [2]

Jul 21 07:27:45.72: [ 9329]: --> [7:CONNECT]

Jul 21 07:27:45.72: [ 9329]: <-- data [7]

Jul 21 07:27:45.72: [ 9329]: <-- data [2]

Jul 21 07:27:47.70: [ 9329]: --> [2:OK]

Jul 21 07:27:47.70: [ 9329]: <-- [9:AT+FTS=7\r]

Jul 21 07:27:47.78: [ 9329]: --> [2:OK]

Jul 21 07:27:47.78: [ 9329]: <-- [10:AT+FTM=96\r]

Jul 21 07:27:48.10: [ 9329]: --> [7:CONNECT]

Jul 21 07:27:48.10: [ 9329]: <-- data [1024]

Jul 21 07:27:48.10: [ 9329]: <-- data [776]

Jul 21 07:27:48.10: [ 9329]: <-- data [2]

Jul 21 07:27:49.63: [ 9329]: --> [2:OK]

Jul 21 07:27:49.63: [ 9329]: <-- [9:AT+FRS=1\r]

Jul 21 07:27:49.65: [ 9329]: --> [2:OK]

Jul 21 07:27:49.65: [ 9329]: <-- [9:AT+FRH=3\r]

Jul 21 07:27:52.75: [ 9329]: --> [0:]

Jul 21 07:27:52.75: [ 9329]: MODEM <Empty line>

Jul 21 07:27:52.76: [ 9329]: <-- data [1]

Jul 21 07:27:52.76: [ 9329]: --> [2:OK]

Jul 21 07:27:52.76: [ 9329]: DELAY 1500 ms

Jul 21 07:27:54.26: [ 9329]: SEND training at v.29 7200 bit/s

Jul 21 07:27:54.26: [ 9329]: <-- [9:AT+FRS=7\r]

Jul 21 07:27:54.36: [ 9329]: --> [2:OK]

Jul 21 07:27:54.36: [ 9329]: <-- [9:AT+FTH=3\r]

Jul 21 07:27:54.40: [ 9329]: --> [7:CONNECT]

Jul 21 07:27:54.40: [ 9329]: <-- data [23]

Jul 21 07:27:54.40: [ 9329]: <-- data [2]

Jul 21 07:27:54.42: [ 9329]: --> [7:CONNECT]

Jul 21 07:27:54.42: [ 9329]: <-- data [7]

Jul 21 07:27:54.42: [ 9329]: <-- data [2]

Jul 21 07:27:56.40: [ 9329]: --> [2:OK]

Jul 21 07:27:56.40: [ 9329]: <-- [9:AT+FTS=7\r]

Jul 21 07:27:56.48: [ 9329]: --> [2:OK]

Jul 21 07:27:56.48: [ 9329]: <-- [10:AT+FTM=72\r]

Jul 21 07:27:56.80: [ 9329]: --> [7:CONNECT]

Jul 21 07:27:56.80: [ 9329]: <-- data [1024]

Jul 21 07:27:56.80: [ 9329]: <-- data [326]

Jul 21 07:27:56.80: [ 9329]: <-- data [2]

Jul 21 07:27:58.34: [ 9329]: --> [2:OK]

Jul 21 07:27:58.34: [ 9329]: <-- [9:AT+FRS=1\r]

Jul 21 07:27:58.36: [ 9329]: --> [2:OK]

Jul 21 07:27:58.36: [ 9329]: <-- [9:AT+FRH=3\r]

Jul 21 07:28:01.46: [ 9329]: --> [0:]

Jul 21 07:28:01.46: [ 9329]: MODEM <Empty line>

Jul 21 07:28:01.46: [ 9329]: <-- data [1]

Jul 21 07:28:01.47: [ 9329]: --> [2:OK]

Jul 21 07:28:01.47: [ 9329]: DELAY 1500 ms

Jul 21 07:28:02.97: [ 9329]: SEND training at v.27ter 4800 bit/s

Jul 21 07:28:02.97: [ 9329]: <-- [9:AT+FRS=7\r]

Jul 21 07:28:03.07: [ 9329]: --> [2:OK]

Jul 21 07:28:03.07: [ 9329]: <-- [9:AT+FTH=3\r]

Jul 21 07:28:03.11: [ 9329]: --> [7:CONNECT]

Jul 21 07:28:03.11: [ 9329]: <-- data [23]

Jul 21 07:28:03.11: [ 9329]: <-- data [2]

Jul 21 07:28:03.13: [ 9329]: --> [7:CONNECT]

Jul 21 07:28:03.13: [ 9329]: <-- data [7]

Jul 21 07:28:03.13: [ 9329]: <-- data [2]

Jul 21 07:28:05.11: [ 9329]: --> [2:OK]

Jul 21 07:28:05.11: [ 9329]: <-- [9:AT+FTS=7\r]

Jul 21 07:28:05.19: [ 9329]: --> [2:OK]

Jul 21 07:28:05.19: [ 9329]: <-- [10:AT+FTM=48\r]

Jul 21 07:28:06.20: [ 9329]: --> [7:CONNECT]

Jul 21 07:28:06.20: [ 9329]: <-- data [900]

Jul 21 07:28:06.21: [ 9329]: <-- data [2]

Jul 21 07:28:07.75: [ 9329]: --> [2:OK]

Jul 21 07:28:07.75: [ 9329]: <-- [9:AT+FRS=1\r]

Jul 21 07:28:07.77: [ 9329]: --> [2:OK]

Jul 21 07:28:07.77: [ 9329]: <-- [9:AT+FRH=3\r]

Jul 21 07:28:10.87: [ 9329]: --> [0:]

Jul 21 07:28:10.87: [ 9329]: MODEM <Empty line>

Jul 21 07:28:10.87: [ 9329]: <-- data [1]

Jul 21 07:28:10.88: [ 9329]: --> [2:OK]

Jul 21 07:28:10.88: [ 9329]: DELAY 1500 ms

Jul 21 07:28:12.38: [ 9329]: SEND training at v.27ter fallback mode 2400 bit/s

Jul 21 07:28:12.38: [ 9329]: <-- [9:AT+FRS=7\r]

Jul 21 07:28:13.80: [ 9329]: --> [2:OK]

Jul 21 07:28:13.80: [ 9329]: <-- [9:AT+FTH=3\r]

Jul 21 07:28:13.83: [ 9329]: --> [7:CONNECT]

Jul 21 07:28:13.83: [ 9329]: <-- data [23]

Jul 21 07:28:13.83: [ 9329]: <-- data [2]

Jul 21 07:28:13.86: [ 9329]: --> [7:CONNECT]

Jul 21 07:28:13.86: [ 9329]: <-- data [7]

Jul 21 07:28:13.86: [ 9329]: <-- data [2]

Jul 21 07:28:15.84: [ 9329]: --> [2:OK]

Jul 21 07:28:15.84: [ 9329]: <-- [9:AT+FTS=7\r]

Jul 21 07:28:15.92: [ 9329]: --> [2:OK]

Jul 21 07:28:15.92: [ 9329]: <-- [10:AT+FTM=24\r]

Jul 21 07:28:17.17: [ 9329]: --> [7:CONNECT]

Jul 21 07:28:17.17: [ 9329]: <-- data [450]

Jul 21 07:28:17.17: [ 9329]: <-- data [2]

Jul 21 07:28:18.74: [ 9329]: --> [2:OK]

Jul 21 07:28:18.74: [ 9329]: <-- [9:AT+FRS=1\r]

Jul 21 07:28:18.76: [ 9329]: --> [2:OK]

Jul 21 07:28:18.76: [ 9329]: <-- [9:AT+FRH=3\r]

Jul 21 07:28:20.39: [ 9329]: --> [7:CONNECT]

Jul 21 07:28:24.23: [ 9329]: --> [5:ERROR]

Jul 21 07:28:24.23: [ 9329]: MODEM Command error

Jul 21 07:28:24.23: [ 9329]: FCS error

Jul 21 07:28:24.23: [ 9329]: <-- [9:AT+FRS=7\r]

Jul 21 07:28:24.34: [ 9329]: --> [2:OK]

Jul 21 07:28:24.34: [ 9329]: <-- [9:AT+FTH=3\r]

Jul 21 07:28:24.53: [ 9329]: --> [7:CONNECT]

Jul 21 07:28:24.53: [ 9329]: <-- data [3]

Jul 21 07:28:24.53: [ 9329]: <-- data [2]

Jul 21 07:28:25.71: [ 9329]: --> [2:OK]

Jul 21 07:28:25.71: [ 9329]: SEND send CRP (command repeat)

Jul 21 07:28:25.71: [ 9329]: <-- [9:AT+FRH=3\r]

Jul 21 07:30:55.19: [ 9329]: --> [7:CONNECT]

Jul 21 07:30:57.56: [ 9329]: --> [5:ERROR]

Jul 21 07:30:57.56: [ 9329]: MODEM Command error

Jul 21 07:30:57.56: [ 9329]: FCS error

Jul 21 07:30:57.56: [ 9329]: <-- [9:AT+FRS=7\r]

Jul 21 07:30:57.66: [ 9329]: --> [2:OK]

Jul 21 07:30:57.66: [ 9329]: <-- [9:AT+FTH=3\r]

Jul 21 07:30:57.86: [ 9329]: --> [7:CONNECT]

Jul 21 07:30:57.86: [ 9329]: <-- data [3]

Jul 21 07:30:57.86: [ 9329]: <-- data [2]

Jul 21 07:30:59.04: [ 9329]: --> [2:OK]

Jul 21 07:30:59.04: [ 9329]: SEND send CRP (command repeat)

Jul 21 07:30:59.04: [ 9329]: <-- [9:AT+FRH=3\r]

Jul 21 08:54:07.34: [ 9329]: ABORT: job abort requested

Jul 21 08:54:20.55: [ 9329]: ABORT: job abort requested

Jul 21 09:05:27.87: [ 9329]: ABORT: job abort requested

Jul 21 09:11:37.02: [ 9329]: ABORT: job abort requested

Jul 21 09:29:01.42: [ 9329]: ABORT: job abort requested

Any Ideas?