[hylafax-users] 2 faxes sent in one session

This is a SuSe 9.0 box with Hylafax 4.2.1 installed on it. I am going to attach a log that shows 2 jobs being sent during one connection. The interesting part is it does not mark the job as being done so it then sends it again. It was 2 fax jobs to the same number and the que list the 2^nd as blocked by concurrent job. The reason I discovered this is a customer ask why we were faxing the same letter multiple times.

Sorry for the length of the post but this is a long log file. Notice the job numbers. I deleted the HDLC data trying to reduce the size of the posting. If it is needed I will repost will the complete log. I also have the log where job 8178 is sent by itself.

I have searched the archives looking for something similar, but came up empty. I understood hylafax not to be able to combine multiple jobs to same destination and send at once.

Thanks for the help.

Jul 07 07:10:48.91: [31728]: SESSION BEGIN 000000100 xxxxxxxxxxx

Jul 07 07:10:48.91: [31728]: HylaFAX (tm) Version 4.2.1

Jul 07 07:10:48.91: [31728]: SEND FAX: JOB 8156 DEST xxxxxxxxxx COMMID 000000100 DEVICE '/dev/ttyS7'

Jul 07 07:10:48.91: [31728]: STATE CHANGE: RUNNING -> SENDING

Jul 07 07:10:48.91: [31728]: MODEM input buffering enabled

Jul 07 07:10:48.91: [31728]: <-- [12:AT+FCLASS=1\r]

Jul 07 07:10:49.15: [31728]: --> [2:OK]

Jul 07 07:10:49.15: [31728]: MODEM set XON/XOFF/FLUSH: input ignored, output disabled

Jul 07 07:10:49.15: [31728]: DIAL xxxxxxxxxx

Jul 07 07:10:49.15: [31728]: <-- [15:ATDTxxxxxxxxxx\r]

Jul 07 07:11:09.93: [31728]: --> [7:CONNECT]

Jul 07 07:11:09.93: [31728]: MODEM input buffering disabled

Jul 07 07:11:11.06: [31728]: --> HDLC<19:FF C0 04 00 00 88 01 00 51 12 00 1E 00 01 01 01 00 AC 8D>

Jul 07 07:11:11.06: [31728]: --> [2:OK]

Jul 07 07:11:11.06: [31728]: REMOTE NSF "00 00 11 80 00 8A 48 00 78 00 80 80 80 00"

Jul 07 07:11:11.06: [31728]: NSF remote fax equipment: Canon

Jul 07 07:11:11.06: [31728]: <-- [9:AT+FRH=3\r]

Jul 07 07:11:11.08: [31728]: --> [7:CONNECT]

Jul 07 07:11:11.76: [31728]: --> HDLC<25:FF C0 02 6C 6C 0C 6C 04 6C EC EC 04 CC 0C CC 04 04 04 04 04 04 04 04 CE 59>

Jul 07 07:11:11.76: [31728]: --> [2:OK]

Jul 07 07:11:11.76: [31728]: REMOTE CSI "xxx xxx xxxx"

Jul 07 07:11:11.76: [31728]: <-- [9:AT+FRH=3\r]

Jul 07 07:11:11.77: [31728]: --> [7:CONNECT]

Jul 07 07:11:12.18: [31728]: --> HDLC<15:FF C8 01 04 77 1F 23 01 A9 01 01 01 04 66 C3>

Jul 07 07:11:12.18: [31728]: --> [2:OK]

Jul 07 07:11:12.18: [31728]: REMOTE best rate 33600 bit/s

Jul 07 07:11:12.18: [31728]: REMOTE max A4 page width (215 mm)

Jul 07 07:11:12.18: [31728]: REMOTE max unlimited page length

Jul 07 07:11:12.18: [31728]: REMOTE best vres R16 x 15.4 line/mm

Jul 07 07:11:12.18: [31728]: REMOTE best format 2-D MMR

Jul 07 07:11:12.18: [31728]: REMOTE supports T.30 Annex A, 256-byte ECM

Jul 07 07:11:12.18: [31728]: REMOTE best 0 ms/scanline

Jul 07 07:11:12.18: [31728]: USE 14400 bit/s

Jul 07 07:11:12.18: [31728]: USE error correction mode

Jul 07 07:11:12.18: [31728]: USE 0 ms/scanline

Jul 07 07:11:12.18: [31728]: SEND file "docq/doc8151.tif;c1"

Jul 07 07:11:12.18: [31728]: USE A4 page width (215 mm)

Jul 07 07:11:12.18: [31728]: USE unlimited page length

Jul 07 07:11:12.18: [31728]: USE 7.7 line/mm

Jul 07 07:11:12.19: [31728]: USE 2-D MMR

Jul 07 07:11:12.19: [31728]: SEND training at v.17 14400 bit/s

Jul 07 07:11:12.19: [31728]: <-- [9:AT+FTH=3\r]

Jul 07 07:11:12.38: [31728]: --> [7:CONNECT]

Jul 07 07:11:12.38: [31728]: <-- HDLC<23:FF C0 C2 0C B4 76 A6 26 36 F6 E2 B4 A6 66 96 36 96 26 A6 B2 04 04 04>

Jul 07 07:11:12.38: [31728]: <-- data [23]

Jul 07 07:11:12.38: [31728]: <-- data [2]

Jul 07 07:11:12.40: [31728]: --> [7:CONNECT]

Jul 07 07:11:12.40: [31728]: <-- HDLC<7:FF C8 C1 00 46 1F 22>

Jul 07 07:11:12.40: [31728]: <-- data [7]

Jul 07 07:11:12.40: [31728]: <-- data [2]

Jul 07 07:11:14.38: [31728]: --> [2:OK]

Jul 07 07:11:14.38: [31728]: <-- [9:AT+FTS=7\r]

Jul 07 07:11:14.46: [31728]: --> [2:OK]

Jul 07 07:11:14.46: [31728]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled

Jul 07 07:11:14.46: [31728]: <-- [11:AT+FTM=145\r]

Jul 07 07:11:15.94: [31728]: --> [7:CONNECT]

Jul 07 07:11:15.94: [31728]: <-- data [1024]

Jul 07 07:11:15.94: [31728]: <-- data [652]

Jul 07 07:11:15.94: [31728]: <-- data [2]

Jul 07 07:11:17.47: [31728]: --> [2:OK]

Jul 07 07:11:17.47: [31728]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled

Jul 07 07:11:17.47: [31728]: <-- [9:AT+FRS=1\r]

Jul 07 07:11:17.49: [31728]: --> [2:OK]

Jul 07 07:11:17.49: [31728]: <-- [9:AT+FRH=3\r]

Jul 07 07:11:17.83: [31728]: --> [7:CONNECT]

Jul 07 07:11:18.84: [31728]: --> HDLC<5:FF C8 21 57 BE>

Jul 07 07:11:18.84: [31728]: --> [2:OK]

Jul 07 07:11:18.84: [31728]: TRAINING succeeded

Jul 07 07:11:18.84: [31728]: SEND begin page

Jul 07 07:11:19.07: [31728]: SEND EOFB

Jul 07 07:11:19.20: [31728]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled

Jul 07 07:11:19.20: [31728]: DELAY 200 ms

Jul 07 07:11:19.41: [31728]: <-- [11:AT+FTM=146\r]

Jul 07 07:11:19.67: [31728]: --> [7:CONNECT]

Jul 07 07:11:19.67: [31728]: <-- data [1029]

Jul 07 07:11:19.67: [31728]: <-- data [1028]

Jul 07 07:11:19.67: [31728]: <-- data [1029]

Jul 07 07:11:19.67: [31728]: <-- data [1028]

Jul 07 07:11:21.67: [31728]: <-- data [1032]

Jul 07 07:11:21.67: [31728]: <-- data [1031]

Jul 07 07:11:21.67: [31728]: <-- data [1030]

Jul 07 07:11:21.67: [31728]: <-- data [1028]

Jul 07 07:11:23.67: [31728]: <-- data [1043]

Jul 07 07:11:23.67: [31728]: <-- data [1035]

Jul 07 07:11:23.67: [31728]: <-- data [1030]

Jul 07 07:11:23.67: [31728]: <-- data [1031]

Jul 07 07:11:25.67: [31728]: <-- data [1035]

Jul 07 07:11:25.67: [31728]: <-- data [1031]

Jul 07 07:11:25.67: [31728]: <-- data [1035]

Jul 07 07:11:25.67: [31728]: <-- data [1033]

Jul 07 07:11:27.67: [31728]: <-- data [1036]

Jul 07 07:11:27.67: [31728]: <-- data [1033]

Jul 07 07:11:27.67: [31728]: <-- data [1035]

Jul 07 07:11:29.67: [31728]: <-- data [1036]

Jul 07 07:11:29.67: [31728]: <-- data [1035]

Jul 07 07:11:29.67: [31728]: <-- data [139]

Jul 07 07:11:29.67: [31728]: <-- data [2]

Jul 07 07:11:31.73: [31728]: --> [2:OK]

Jul 07 07:11:31.73: [31728]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled

Jul 07 07:11:31.73: [31728]: <-- [9:AT+FTS=9\r]

Jul 07 07:11:31.83: [31728]: --> [2:OK]

Jul 07 07:11:31.83: [31728]: <-- [9:AT+FTH=3\r]

Jul 07 07:11:31.98: [31728]: --> [7:CONNECT]

Jul 07 07:11:31.98: [31728]: <-- HDLC<7:FF C8 FD F1 00 00 B2>

Jul 07 07:11:31.98: [31728]: <-- data [7]

Jul 07 07:11:31.98: [31728]: <-- data [2]

Jul 07 07:11:33.26: [31728]: --> [2:OK]

Jul 07 07:11:33.26: [31728]: SEND send PPS (partial page signal)

Jul 07 07:11:33.26: [31728]: SEND send EOM (more documents)

Jul 07 07:11:33.26: [31728]: <-- [9:AT+FRH=3\r]

Jul 07 07:11:33.83: [31728]: --> [7:CONNECT]

Jul 07 07:11:34.72: [31728]: --> HDLC<5:FF C8 31 45 8F>

Jul 07 07:11:34.72: [31728]: --> [2:OK]

Jul 07 07:11:34.72: [31728]: SEND recv MCF (message confirmation)

Jul 07 07:11:34.72: [31728]: <-- [9:AT+FRS=7\r]

Jul 07 07:11:35.48: [31728]: --> [2:OK]

Jul 07 07:11:35.48: [31728]: SEND end page

Jul 07 07:11:35.49: [31728]: SEND FAX (000000100): FROM faxuser@xxxxxxxxxxxxxx TO xxxxxxxxxx (docq/doc8151.tif;c1 sent in 0:23)

Jul 07 07:11:35.49: [31728]: SEND FAX (000000100): FROM faxuser@xxxxxxxxxxxxxx TO xxxxxxxxxx (page 1 of 1 sent in 0:23)

Jul 07 07:11:36.50: [31728]: SEND FAX: JOB 8178 DEST xxxxxxxxxx COMMID 000000100 DEVICE '/dev/ttyS7'

Jul 07 07:11:36.50: [31728]: MODEM set XON/XOFF/FLUSH: input ignored, output disabled

Jul 07 07:11:36.50: [31728]: MODEM input buffering disabled

Jul 07 07:11:36.50: [31728]: <-- [9:AT+FRH=3\r]

Jul 07 07:11:36.52: [31728]: --> [7:CONNECT]

Jul 07 07:11:41.52: [31728]: MODEM TIMEOUT: receiving HDLC frame data

Jul 07 07:11:41.52: [31728]: <-- data [1]

Jul 07 07:11:41.53: [31728]: --> [2:OK]

Jul 07 07:11:41.53: [31728]: DELAY 200 ms

Jul 07 07:11:41.73: [31728]: <-- [9:AT+FRH=3\r]

Jul 07 07:11:41.83: [31728]: --> [7:CONNECT]

Jul 07 07:11:42.54: [31728]: --> HDLC<19:FF C0 04 00 00 88 01 00 51 12 00 1E 00 01 01 01 00 AC 8D>

Jul 07 07:11:42.54: [31728]: --> [2:OK]

Jul 07 07:11:42.54: [31728]: REMOTE NSF "00 00 11 80 00 8A 48 00 78 00 80 80 80 00"

Jul 07 07:11:42.54: [31728]: NSF remote fax equipment: Canon

Jul 07 07:11:42.54: [31728]: <-- [9:AT+FRH=3\r]

Jul 07 07:11:42.55: [31728]: --> [7:CONNECT]

Jul 07 07:11:43.23: [31728]: --> HDLC<25:FF C0 02 6C 6C 0C 6C 04 6C EC EC 04 CC 0C CC 04 04 04 04 04 04 04 04 CE 59>

Jul 07 07:11:43.23: [31728]: --> [2:OK]

Jul 07 07:11:43.23: [31728]: REMOTE CSI "xxx xxx xxxx"

Jul 07 07:11:43.23: [31728]: <-- [9:AT+FRH=3\r]

Jul 07 07:11:43.24: [31728]: --> [7:CONNECT]

Jul 07 07:11:43.68: [31728]: --> HDLC<15:FF C8 01 00 77 1F 23 01 A9 01 01 01 04 CB F6>

Jul 07 07:11:43.68: [31728]: --> [2:OK]

Jul 07 07:11:43.68: [31728]: REMOTE best rate 14400 bit/s

Jul 07 07:11:43.68: [31728]: REMOTE max A4 page width (215 mm)

Jul 07 07:11:43.68: [31728]: REMOTE max unlimited page length

Jul 07 07:11:43.68: [31728]: REMOTE best vres R16 x 15.4 line/mm

Jul 07 07:11:43.68: [31728]: REMOTE best format 2-D MMR

Jul 07 07:11:43.68: [31728]: REMOTE supports T.30 Annex A, 256-byte ECM

Jul 07 07:11:43.68: [31728]: REMOTE best 0 ms/scanline

Jul 07 07:11:43.68: [31728]: USE 14400 bit/s

Jul 07 07:11:43.68: [31728]: USE error correction mode

Jul 07 07:11:43.68: [31728]: USE 0 ms/scanline

Jul 07 07:11:43.68: [31728]: SEND file "docq/doc8173.tif;c1"

Jul 07 07:11:43.69: [31728]: USE A4 page width (215 mm)

Jul 07 07:11:43.69: [31728]: USE unlimited page length

Jul 07 07:11:43.69: [31728]: USE 7.7 line/mm

Jul 07 07:11:43.69: [31728]: USE 2-D MMR

Jul 07 07:11:43.69: [31728]: SEND training at v.17 14400 bit/s

Jul 07 07:11:43.69: [31728]: <-- [9:AT+FTH=3\r]

Jul 07 07:11:43.88: [31728]: --> [7:CONNECT]

Jul 07 07:11:43.88: [31728]: <-- HDLC<23:FF C0 C2 0C B4 76 A6 26 36 F6 E2 B4 A6 66 96 36 96 26 A6 B2 04 04 04>

Jul 07 07:11:43.88: [31728]: <-- data [23]

Jul 07 07:11:43.88: [31728]: <-- data [2]

Jul 07 07:11:43.90: [31728]: --> [7:CONNECT]

Jul 07 07:11:43.90: [31728]: <-- HDLC<7:FF C8 C1 00 46 1F 22>

Jul 07 07:11:43.90: [31728]: <-- data [7]

Jul 07 07:11:43.90: [31728]: <-- data [2]

Jul 07 07:11:45.89: [31728]: --> [2:OK]

Jul 07 07:11:45.89: [31728]: <-- [9:AT+FTS=7\r]

Jul 07 07:11:45.97: [31728]: --> [2:OK]

Jul 07 07:11:45.97: [31728]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled

Jul 07 07:11:45.97: [31728]: <-- [11:AT+FTM=145\r]

Jul 07 07:11:47.45: [31728]: --> [7:CONNECT]

Jul 07 07:11:47.45: [31728]: <-- data [1024]

Jul 07 07:11:47.45: [31728]: <-- data [652]

Jul 07 07:11:47.45: [31728]: <-- data [2]

Jul 07 07:11:48.97: [31728]: --> [2:OK]

Jul 07 07:11:48.97: [31728]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled

Jul 07 07:11:48.97: [31728]: <-- [9:AT+FRS=1\r]

Jul 07 07:11:49.00: [31728]: --> [2:OK]

Jul 07 07:11:49.00: [31728]: <-- [9:AT+FRH=3\r]

Jul 07 07:11:49.39: [31728]: --> [7:CONNECT]

Jul 07 07:11:50.33: [31728]: --> HDLC<5:FF C8 21 57 BE>

Jul 07 07:11:50.33: [31728]: --> [2:OK]

Jul 07 07:11:50.33: [31728]: TRAINING succeeded

Jul 07 07:11:50.33: [31728]: SEND begin page

Jul 07 07:11:50.54: [31728]: SEND EOFB

Jul 07 07:11:50.61: [31728]: MODEM set XON/XOFF/FLUSH: input interpreted, output disabled

Jul 07 07:11:50.62: [31728]: DELAY 200 ms

Jul 07 07:11:50.82: [31728]: <-- [11:AT+FTM=146\r]

Jul 07 07:11:51.07: [31728]: --> [7:CONNECT]

Jul 07 07:11:51.07: [31728]: <-- data [1027]

Jul 07 07:11:51.07: [31728]: <-- data [1031]

Jul 07 07:11:51.07: [31728]: <-- data [1033]

Jul 07 07:11:51.07: [31728]: <-- data [1029]

Jul 07 07:11:53.07: [31728]: <-- data [1035]

Jul 07 07:11:53.07: [31728]: <-- data [1034]

Jul 07 07:11:53.07: [31728]: <-- data [1030]

Jul 07 07:11:53.07: [31728]: <-- data [1025]

Jul 07 07:11:55.07: [31728]: <-- data [1035]

Jul 07 07:11:55.07: [31728]: <-- data [1031]

Jul 07 07:11:55.07: [31728]: <-- data [1029]

Jul 07 07:11:55.07: [31728]: <-- data [1032]

Jul 07 07:11:57.07: [31728]: <-- data [1032]

Jul 07 07:11:57.07: [31728]: <-- data [1030]

Jul 07 07:11:57.08: [31728]: <-- data [1028]

Jul 07 07:11:59.07: [31728]: <-- data [1029]

Jul 07 07:11:59.07: [31728]: <-- data [1038]

Jul 07 07:11:59.08: [31728]: <-- data [1028]

Jul 07 07:12:01.07: [31728]: <-- data [1037]

Jul 07 07:12:01.08: [31728]: <-- data [902]

Jul 07 07:12:01.08: [31728]: <-- data [2]

Jul 07 07:12:02.98: [31728]: --> [2:OK]

Jul 07 07:12:02.98: [31728]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled

Jul 07 07:12:02.98: [31728]: <-- [9:AT+FTS=9\r]

Jul 07 07:12:03.08: [31728]: --> [2:OK]

Jul 07 07:12:03.08: [31728]: <-- [9:AT+FTH=3\r]

Jul 07 07:12:03.23: [31728]: --> [7:CONNECT]

Jul 07 07:12:03.23: [31728]: <-- HDLC<7:FF C8 FD F4 00 00 32>

Jul 07 07:12:03.23: [31728]: <-- data [7]

Jul 07 07:12:03.23: [31728]: <-- data [2]

Jul 07 07:12:04.52: [31728]: --> [2:OK]

Jul 07 07:12:04.52: [31728]: SEND send PPS (partial page signal)

Jul 07 07:12:04.52: [31728]: SEND send EOP (no more pages or documents)

Jul 07 07:12:04.52: [31728]: <-- [9:AT+FRH=3\r]

Jul 07 07:12:05.04: [31728]: --> [7:CONNECT]

Jul 07 07:12:05.84: [31728]: --> HDLC<5:FF C8 5F C8 E7>

Jul 07 07:12:05.84: [31728]: --> [2:OK]

Jul 07 07:12:05.84: [31728]: SEND recv DCN (disconnect)

Jul 07 07:12:05.84: [31728]: <-- [9:AT+FRS=7\r]

Jul 07 07:12:06.60: [31728]: --> [2:OK]

Jul 07 07:12:06.60: [31728]: COMREC invalid response received to PPS.

Jul 07 07:12:06.60: [31728]: SEND end page

Jul 07 07:12:06.60: [31728]: <-- [9:AT+FTH=3\r]

Jul 07 07:12:06.80: [31728]: --> [7:CONNECT]

Jul 07 07:12:06.80: [31728]: <-- HDLC<3:FF C8 DF>

Jul 07 07:12:06.80: [31728]: <-- data [3]

Jul 07 07:12:06.80: [31728]: <-- data [2]

Jul 07 07:12:07.98: [31728]: --> [2:OK]

Jul 07 07:12:07.98: [31728]: MODEM input buffering enabled

Jul 07 07:12:07.98: [31728]: <-- [5:ATH0\r]

Jul 07 07:12:08.21: [31728]: --> [2:OK]

Jul 07 07:12:08.21: [31728]: MODEM set DTR OFF

Jul 07 07:12:08.21: [31728]: STATE CHANGE: SENDING -> MODEMWAIT (timeout 5)

Jul 07 07:12:08.21: [31728]: SESSION END