Re: [hylafax-users] Received faxes resen

[bill.allison@xxxxxxxxx]

Lee

Logs as requested - first two for a fax received twice. Third, in case   
connected to this problem, for a fax with severe errors that nevertheless   
got put in recvq.

Regards
Bill A.

fileserver-car:/var/spool/hylafax/log # cat c000000281
Nov 04 11:56:04.35: [ 1247]: SESSION BEGIN 000000281 4401228674722
Nov 04 11:56:04.35: [ 1247]: HylaFAX (tm) Version 4.2.1
Nov 04 11:56:04.35: [ 1247]: <-- [4:ATA\r]
Nov 04 11:56:09.64: [ 1247]: --> [7:CONNECT]
Nov 04 11:56:09.64: [ 1247]: ANSWER: FAX CONNECTION  DEVICE '/dev/ttyS0'
Nov 04 11:56:09.64: [ 1247]: RECV FAX: begin
Nov 04 11:56:09.64: [ 1247]: <-- data [32]
Nov 04 11:56:09.64: [ 1247]: <-- data [2]
Nov 04 11:56:09.70: [ 1247]: --> [7:CONNECT]
Nov 04 11:56:09.70: [ 1247]: <-- data [23]
Nov 04 11:56:09.70: [ 1247]: <-- data [2]
Nov 04 11:56:10.42: [ 1247]: --> [7:CONNECT]
Nov 04 11:56:10.42: [ 1247]: <-- data [10]
Nov 04 11:56:10.42: [ 1247]: <-- data [2]
Nov 04 11:56:12.61: [ 1247]: --> [2:OK]
Nov 04 11:56:12.61: [ 1247]: <-- [9:AT+FRH=3\r]
Nov 04 11:56:13.34: [ 1247]: --> [7:CONNECT]
Nov 04 11:56:14.90: [ 1247]: --> [2:OK]
Nov 04 11:56:14.90: [ 1247]: REMOTE TSI "01142326611"
Nov 04 11:56:14.90: [ 1247]: <-- [9:AT+FRH=3\r]
Nov 04 11:56:14.92: [ 1247]: --> [7:CONNECT]
Nov 04 11:56:15.16: [ 1247]: --> [2:OK]
Nov 04 11:56:15.16: [ 1247]: REMOTE wants 14400 bit/s
Nov 04 11:56:15.16: [ 1247]: REMOTE wants A4 page width (215 mm)
Nov 04 11:56:15.16: [ 1247]: REMOTE wants unlimited page length
Nov 04 11:56:15.16: [ 1247]: REMOTE wants 3.85 line/mm
Nov 04 11:56:15.16: [ 1247]: REMOTE wants 2-D MMR
Nov 04 11:56:15.16: [ 1247]: REMOTE wants T.30 Annex A, 256-byte ECM
Nov 04 11:56:15.16: [ 1247]: RECV training at v.17 14400 bit/s
Nov 04 11:56:15.16: [ 1247]: <-- [11:AT+FRM=145\r]
Nov 04 11:56:16.91: [ 1247]: --> [7:CONNECT]
Nov 04 11:56:18.47: [ 1247]: RECV: TCF 2813 bytes, 3% non-zero, 2710   
zero-run
Nov 04 11:56:18.47: [ 1247]: --> [10:NO CARRIER]
Nov 04 11:56:18.47: [ 1247]: DELAY 75 ms
Nov 04 11:56:18.55: [ 1247]: TRAINING succeeded
Nov 04 11:56:18.55: [ 1247]: <-- [9:AT+FTH=3\r]
Nov 04 11:56:18.75: [ 1247]: --> [7:CONNECT]
Nov 04 11:56:18.75: [ 1247]: <-- data [3]
Nov 04 11:56:18.75: [ 1247]: <-- data [2]
Nov 04 11:56:19.93: [ 1247]: --> [2:OK]
Nov 04 11:56:19.93: [ 1247]: <-- [11:AT+FRM=146\r]
Nov 04 11:56:20.99: [ 1247]: --> [7:CONNECT]
Nov 04 11:56:21.20: [ 1247]: RECV received frame number 0
Nov 04 11:56:21.41: [ 1247]: RECV received frame number 1
Nov 04 11:56:21.56: [ 1247]: RECV received frame number 2
Nov 04 11:56:21.70: [ 1247]: RECV received frame number 3
Nov 04 11:56:21.86: [ 1247]: RECV received frame number 4
Nov 04 11:56:22.01: [ 1247]: RECV received frame number 5
Nov 04 11:56:22.17: [ 1247]: RECV received frame number 6
Nov 04 11:56:22.32: [ 1247]: RECV received frame number 7
Nov 04 11:56:22.47: [ 1247]: RECV received frame number 8
Nov 04 11:56:22.61: [ 1247]: RECV received frame number 9
Nov 04 11:56:22.75: [ 1247]: RECV received frame number 10
Nov 04 11:56:22.91: [ 1247]: RECV received frame number 11
Nov 04 11:56:23.05: [ 1247]: RECV received frame number 12
Nov 04 11:56:23.19: [ 1247]: RECV received frame number 13
Nov 04 11:56:23.33: [ 1247]: RECV received frame number 14
Nov 04 11:56:23.54: [ 1247]: RECV received frame number 15
Nov 04 11:56:23.69: [ 1247]: RECV received frame number 16
Nov 04 11:56:23.84: [ 1247]: RECV received frame number 17
Nov 04 11:56:24.00: [ 1247]: RECV received frame number 18
Nov 04 11:56:24.15: [ 1247]: RECV received frame number 19
Nov 04 11:56:24.30: [ 1247]: RECV received frame number 20
Nov 04 11:56:24.46: [ 1247]: RECV received frame number 21
Nov 04 11:56:24.61: [ 1247]: RECV received frame number 22
Nov 04 11:56:24.77: [ 1247]: RECV received frame number 23
Nov 04 11:56:24.93: [ 1247]: RECV received frame number 24
Nov 04 11:56:25.08: [ 1247]: RECV received frame number 25
Nov 04 11:56:25.23: [ 1247]: RECV received frame number 26
Nov 04 11:56:25.38: [ 1247]: RECV received frame number 27
Nov 04 11:56:25.53: [ 1247]: RECV received frame number 28
Nov 04 11:56:25.69: [ 1247]: RECV received frame number 29
Nov 04 11:56:25.85: [ 1247]: RECV received frame number 30
Nov 04 11:56:25.99: [ 1247]: RECV received frame number 31
Nov 04 11:56:26.15: [ 1247]: RECV received frame number 32
Nov 04 11:56:26.30: [ 1247]: RECV received frame number 33
Nov 04 11:56:26.46: [ 1247]: RECV received frame number 34
Nov 04 11:56:26.61: [ 1247]: RECV received frame number 35
Nov 04 11:56:26.77: [ 1247]: RECV received frame number 36
Nov 04 11:56:26.92: [ 1247]: RECV received frame number 37
Nov 04 11:56:27.08: [ 1247]: RECV received frame number 38
Nov 04 11:56:27.24: [ 1247]: RECV received frame number 39
Nov 04 11:56:27.39: [ 1247]: RECV received frame number 40
Nov 04 11:56:27.55: [ 1247]: RECV received frame number 41
Nov 04 11:56:27.70: [ 1247]: RECV received frame number 42
Nov 04 11:56:27.86: [ 1247]: RECV received frame number 43
Nov 04 11:56:28.00: [ 1247]: RECV received frame number 44
Nov 04 11:56:28.21: [ 1247]: RECV received frame number 45
Nov 04 11:56:28.35: [ 1247]: RECV received frame number 46
Nov 04 11:56:28.50: [ 1247]: RECV received frame number 47
Nov 04 11:56:28.64: [ 1247]: RECV received frame number 48
Nov 04 11:56:28.79: [ 1247]: RECV received frame number 49
Nov 04 11:56:28.93: [ 1247]: RECV received frame number 50
Nov 04 11:56:29.08: [ 1247]: RECV received frame number 51
Nov 04 11:56:29.23: [ 1247]: RECV received frame number 52
Nov 04 11:56:29.37: [ 1247]: RECV received frame number 53
Nov 04 11:56:29.58: [ 1247]: RECV received frame number 54
Nov 04 11:56:29.72: [ 1247]: RECV received frame number 55
Nov 04 11:56:29.87: [ 1247]: RECV received frame number 56
Nov 04 11:56:30.02: [ 1247]: RECV received frame number 57
Nov 04 11:56:30.17: [ 1247]: RECV received frame number 58
Nov 04 11:56:30.33: [ 1247]: RECV received frame number 59
Nov 04 11:56:30.48: [ 1247]: RECV received frame number 60
Nov 04 11:56:30.62: [ 1247]: RECV received frame number 61
Nov 04 11:56:30.78: [ 1247]: RECV received frame number 62
Nov 04 11:56:30.93: [ 1247]: RECV received frame number 63
Nov 04 11:56:31.07: [ 1247]: RECV received frame number 64
Nov 04 11:56:31.22: [ 1247]: RECV received frame number 65
Nov 04 11:56:31.36: [ 1247]: RECV received frame number 66
Nov 04 11:56:31.59: [ 1247]: RECV received frame number 67
Nov 04 11:56:31.74: [ 1247]: RECV received frame number 68
Nov 04 11:56:31.88: [ 1247]: RECV received frame number 69
Nov 04 11:56:32.03: [ 1247]: RECV received frame number 70
Nov 04 11:56:32.17: [ 1247]: RECV received frame number 71
Nov 04 11:56:32.32: [ 1247]: RECV received frame number 72
Nov 04 11:56:32.48: [ 1247]: RECV received frame number 73
Nov 04 11:56:32.63: [ 1247]: RECV received frame number 74
Nov 04 11:56:32.78: [ 1247]: RECV received frame number 75
Nov 04 11:56:32.93: [ 1247]: RECV received frame number 76
Nov 04 11:56:33.08: [ 1247]: RECV received frame number 77
Nov 04 11:56:33.23: [ 1247]: RECV received frame number 78
Nov 04 11:56:33.23: [ 1247]: RECV received RCP frame
Nov 04 11:56:33.35: [ 1247]: --> [10:NO CARRIER]
Nov 04 11:56:33.35: [ 1247]: <-- [9:AT+FRH=3\r]
Nov 04 11:56:33.69: [ 1247]: --> [7:CONNECT]
Nov 04 11:56:34.57: [ 1247]: --> [2:OK]
Nov 04 11:56:34.57: [ 1247]: RECV recv PPS (partial page signal)
Nov 04 11:56:34.57: [ 1247]: RECV recv EOP (no more pages or documents)
Nov 04 11:56:34.57: [ 1247]: RECV received 79 frames of block 1 of page 1
Nov 04 11:56:34.62: [ 1247]: RECV: 20102 bytes of data, 1030 total lines
Nov 04 11:56:34.63: [ 1247]: <-- [9:AT+FRS=7\r]
Nov 04 11:56:34.73: [ 1247]: --> [2:OK]
Nov 04 11:56:34.73: [ 1247]: <-- [9:AT+FTH=3\r]
Nov 04 11:56:42.28: [ 1247]: --> [0:]
Nov 04 11:56:42.28: [ 1247]: RECV send MCF (message confirmation)
Nov 04 11:56:42.28: [ 1247]: RECV FAX (000000281): from 01142326611, page   
1 in 0:24, INF, 3.85 line/mm, 2-D MMR, 14400 bit/s
Nov 04 11:56:42.28: [ 1247]: RECV FAX (000000281): recvq/fax000000215.tif   
from 01142326611, route to <unspecified>, 1 pages in 0:33
Nov 04 11:56:42.28: [ 1247]: <-- [9:AT+FRH=3\r]
Nov 04 11:56:42.43: [ 1247]: --> [8:AT+FRH=3]
Nov 04 11:56:42.43: [ 1247]: RECV FAX: bin/faxrcvd   
"recvq/fax000000215.tif" "ttyS0" "000000281" "" "01228674221" ""
Nov 04 11:56:42.44: [ 1247]: RECV FAX: end
Nov 04 11:56:42.44: [ 1247]: SESSION END
fileserver-car:/var/spool/hylafax/log #

========================================================================



 -----Original Message-----
From: faxguy@xxxxxxxxxxxxxxxx fileserver-car:/var/spool/hylafax/log # cat   
c000000282
Nov 04 12:01:21.21: [ 1247]: SESSION BEGIN 000000282 4401228674722
Nov 04 12:01:21.21: [ 1247]: HylaFAX (tm) Version 4.2.1
Nov 04 12:01:21.21: [ 1247]: <-- [4:ATA\r]
Nov 04 12:01:26.49: [ 1247]: --> [7:CONNECT]
Nov 04 12:01:26.49: [ 1247]: ANSWER: FAX CONNECTION  DEVICE '/dev/ttyS0'
Nov 04 12:01:26.49: [ 1247]: RECV FAX: begin
Nov 04 12:01:26.49: [ 1247]: <-- data [32]
Nov 04 12:01:26.49: [ 1247]: <-- data [2]
Nov 04 12:01:26.56: [ 1247]: --> [7:CONNECT]
Nov 04 12:01:26.56: [ 1247]: <-- data [23]
Nov 04 12:01:26.56: [ 1247]: <-- data [2]
Nov 04 12:01:27.28: [ 1247]: --> [7:CONNECT]
Nov 04 12:01:27.28: [ 1247]: <-- data [10]
Nov 04 12:01:27.28: [ 1247]: <-- data [2]
Nov 04 12:01:29.47: [ 1247]: --> [2:OK]
Nov 04 12:01:29.47: [ 1247]: <-- [9:AT+FRH=3\r]
Nov 04 12:01:30.44: [ 1247]: --> [7:CONNECT]
Nov 04 12:01:31.75: [ 1247]: --> [2:OK]
Nov 04 12:01:31.75: [ 1247]: REMOTE TSI "01142326611"
Nov 04 12:01:31.75: [ 1247]: <-- [9:AT+FRH=3\r]
Nov 04 12:01:31.77: [ 1247]: --> [7:CONNECT]
Nov 04 12:01:32.04: [ 1247]: --> [2:OK]
Nov 04 12:01:32.04: [ 1247]: REMOTE wants 14400 bit/s
Nov 04 12:01:32.04: [ 1247]: REMOTE wants A4 page width (215 mm)
Nov 04 12:01:32.04: [ 1247]: REMOTE wants unlimited page length
Nov 04 12:01:32.04: [ 1247]: REMOTE wants 3.85 line/mm
Nov 04 12:01:32.04: [ 1247]: REMOTE wants 2-D MMR
Nov 04 12:01:32.04: [ 1247]: REMOTE wants T.30 Annex A, 256-byte ECM
Nov 04 12:01:32.04: [ 1247]: RECV training at v.17 14400 bit/s
Nov 04 12:01:32.04: [ 1247]: <-- [11:AT+FRM=145\r]
Nov 04 12:01:33.77: [ 1247]: --> [7:CONNECT]
Nov 04 12:01:35.33: [ 1247]: RECV: TCF 2811 bytes, 2% non-zero, 2727   
zero-run
Nov 04 12:01:35.33: [ 1247]: --> [10:NO CARRIER]
Nov 04 12:01:35.33: [ 1247]: DELAY 75 ms
Nov 04 12:01:35.41: [ 1247]: TRAINING succeeded
Nov 04 12:01:35.42: [ 1247]: <-- [9:AT+FTH=3\r]
Nov 04 12:01:35.61: [ 1247]: --> [7:CONNECT]
Nov 04 12:01:35.61: [ 1247]: <-- data [3]
Nov 04 12:01:35.61: [ 1247]: <-- data [2]
Nov 04 12:01:36.79: [ 1247]: --> [2:OK]
Nov 04 12:01:36.79: [ 1247]: <-- [11:AT+FRM=146\r]
Nov 04 12:01:37.84: [ 1247]: --> [7:CONNECT]
Nov 04 12:01:38.06: [ 1247]: RECV received frame number 0
Nov 04 12:01:38.21: [ 1247]: RECV received frame number 1
Nov 04 12:01:38.37: [ 1247]: RECV received frame number 2
Nov 04 12:01:38.51: [ 1247]: RECV received frame number 3
Nov 04 12:01:38.66: [ 1247]: RECV received frame number 4
Nov 04 12:01:38.82: [ 1247]: RECV received frame number 5
Nov 04 12:01:38.98: [ 1247]: RECV received frame number 6
Nov 04 12:01:39.13: [ 1247]: RECV received frame number 7
Nov 04 12:01:39.28: [ 1247]: RECV received frame number 8
Nov 04 12:01:39.42: [ 1247]: RECV received frame number 9
Nov 04 12:01:39.57: [ 1247]: RECV received frame number 10
Nov 04 12:01:39.73: [ 1247]: RECV received frame number 11
Nov 04 12:01:39.95: [ 1247]: RECV received frame number 12
Nov 04 12:01:40.10: [ 1247]: RECV received frame number 13
Nov 04 12:01:40.25: [ 1247]: RECV received frame number 14
Nov 04 12:01:40.39: [ 1247]: RECV received frame number 15
Nov 04 12:01:40.55: [ 1247]: RECV received frame number 16
Nov 04 12:01:40.70: [ 1247]: RECV received frame number 17
Nov 04 12:01:40.86: [ 1247]: RECV received frame number 18
Nov 04 12:01:41.01: [ 1247]: RECV received frame number 19
Nov 04 12:01:41.17: [ 1247]: RECV received frame number 20
Nov 04 12:01:41.33: [ 1247]: RECV received frame number 21
Nov 04 12:01:41.48: [ 1247]: RECV received frame number 22
Nov 04 12:01:41.62: [ 1247]: RECV received frame number 23
Nov 04 12:01:41.76: [ 1247]: RECV received frame number 24
Nov 04 12:01:41.91: [ 1247]: RECV received frame number 25
Nov 04 12:01:42.06: [ 1247]: RECV received frame number 26
Nov 04 12:01:42.22: [ 1247]: RECV received frame number 27
Nov 04 12:01:42.38: [ 1247]: RECV received frame number 28
Nov 04 12:01:42.54: [ 1247]: RECV received frame number 29
Nov 04 12:01:42.69: [ 1247]: RECV received frame number 30
Nov 04 12:01:42.84: [ 1247]: RECV received frame number 31
Nov 04 12:01:42.98: [ 1247]: RECV received frame number 32
Nov 04 12:01:43.13: [ 1247]: HDLC frame not byte-oriented.  Trailing   
byte: 0xc0
Nov 04 12:01:43.44: [ 1247]: RECV received frame number 41
Nov 04 12:01:43.60: [ 1247]: RECV received frame number 42
Nov 04 12:01:43.75: [ 1247]: RECV received frame number 43
Nov 04 12:01:43.90: [ 1247]: RECV received frame number 44
Nov 04 12:01:44.05: [ 1247]: RECV received frame number 45
Nov 04 12:01:44.26: [ 1247]: RECV received frame number 46
Nov 04 12:01:44.40: [ 1247]: RECV received frame number 47
Nov 04 12:01:44.54: [ 1247]: RECV received frame number 48
Nov 04 12:01:44.68: [ 1247]: RECV received frame number 49
Nov 04 12:01:44.82: [ 1247]: RECV received frame number 50
Nov 04 12:01:44.97: [ 1247]: RECV received frame number 51
Nov 04 12:01:45.11: [ 1247]: RECV received frame number 52
Nov 04 12:01:45.33: [ 1247]: RECV received frame number 53
Nov 04 12:01:45.47: [ 1247]: RECV received frame number 54
Nov 04 12:01:45.61: [ 1247]: RECV received frame number 55
Nov 04 12:01:45.75: [ 1247]: RECV received frame number 56
Nov 04 12:01:45.90: [ 1247]: RECV received frame number 57
Nov 04 12:01:46.05: [ 1247]: RECV received frame number 58
Nov 04 12:01:46.21: [ 1247]: RECV received frame number 59
Nov 04 12:01:46.36: [ 1247]: RECV received frame number 60
Nov 04 12:01:46.51: [ 1247]: RECV received frame number 61
Nov 04 12:01:46.66: [ 1247]: RECV received frame number 62
Nov 04 12:01:46.81: [ 1247]: RECV received frame number 63
Nov 04 12:01:46.96: [ 1247]: RECV received frame number 64
Nov 04 12:01:47.11: [ 1247]: RECV received frame number 65
Nov 04 12:01:47.26: [ 1247]: RECV received frame number 66
Nov 04 12:01:47.48: [ 1247]: RECV received frame number 67
Nov 04 12:01:47.62: [ 1247]: RECV received frame number 68
Nov 04 12:01:47.76: [ 1247]: RECV received frame number 69
Nov 04 12:01:47.91: [ 1247]: RECV received frame number 70
Nov 04 12:01:48.06: [ 1247]: RECV received frame number 71
Nov 04 12:01:48.22: [ 1247]: RECV received frame number 72
Nov 04 12:01:48.38: [ 1247]: RECV received frame number 73
Nov 04 12:01:48.54: [ 1247]: RECV received frame number 74
Nov 04 12:01:48.69: [ 1247]: RECV received frame number 75
Nov 04 12:01:48.85: [ 1247]: RECV received frame number 76
Nov 04 12:01:49.00: [ 1247]: RECV received frame number 77
Nov 04 12:01:49.07: [ 1247]: RECV received frame number 78
Nov 04 12:01:49.07: [ 1247]: RECV received RCP frame
Nov 04 12:01:49.25: [ 1247]: --> [10:NO CARRIER]
Nov 04 12:01:49.25: [ 1247]: <-- [9:AT+FRH=3\r]
Nov 04 12:01:49.49: [ 1247]: --> [7:CONNECT]
Nov 04 12:01:50.47: [ 1247]: --> [2:OK]
Nov 04 12:01:50.47: [ 1247]: RECV recv PPS (partial page signal)
Nov 04 12:01:50.47: [ 1247]: RECV recv EOP (no more pages or documents)
Nov 04 12:01:50.47: [ 1247]: RECV received 79 frames of block 1 of page 1
Nov 04 12:01:50.47: [ 1247]: <-- [9:AT+FRS=7\r]
Nov 04 12:01:51.24: [ 1247]: --> [2:OK]
Nov 04 12:01:51.24: [ 1247]: <-- [9:AT+FTH=3\r]
Nov 04 12:01:51.44: [ 1247]: --> [7:CONNECT]
Nov 04 12:01:51.44: [ 1247]: <-- data [35]
Nov 04 12:01:51.44: [ 1247]: <-- data [2]
Nov 04 12:01:53.58: [ 1247]: --> [2:OK]
Nov 04 12:01:53.58: [ 1247]: RECV send PPR (partial page request)
Nov 04 12:01:53.58: [ 1247]: <-- [11:AT+FRM=146\r]
Nov 04 12:01:54.66: [ 1247]: --> [7:CONNECT]
Nov 04 12:01:54.88: [ 1247]: RECV received frame number 33
Nov 04 12:01:55.03: [ 1247]: RECV received frame number 34
Nov 04 12:01:55.19: [ 1247]: RECV received frame number 35
Nov 04 12:01:55.35: [ 1247]: RECV received frame number 36
Nov 04 12:01:55.50: [ 1247]: RECV received frame number 37
Nov 04 12:01:55.65: [ 1247]: RECV received frame number 38
Nov 04 12:01:55.80: [ 1247]: RECV received frame number 39
Nov 04 12:01:55.95: [ 1247]: RECV received frame number 40
Nov 04 12:01:56.03: [ 1247]: RECV received RCP frame
Nov 04 12:01:56.16: [ 1247]: --> [10:NO CARRIER]
Nov 04 12:01:56.16: [ 1247]: <-- [9:AT+FRH=3\r]
Nov 04 12:01:56.69: [ 1247]: --> [7:CONNECT]
Nov 04 12:01:57.35: [ 1247]: --> [2:OK]
Nov 04 12:01:57.35: [ 1247]: RECV recv PPS (partial page signal)
Nov 04 12:01:57.35: [ 1247]: RECV recv EOP (no more pages or documents)
Nov 04 12:01:57.35: [ 1247]: RECV received 8 frames of block 1 of page 1
Nov 04 12:01:57.40: [ 1247]: RECV: 20100 bytes of data, 1030 total lines
Nov 04 12:01:57.40: [ 1247]: <-- [9:AT+FRS=7\r]
Nov 04 12:01:57.51: [ 1247]: --> [2:OK]
Nov 04 12:01:57.51: [ 1247]: <-- [9:AT+FTH=3\r]
Nov 04 12:01:57.71: [ 1247]: --> [7:CONNECT]
Nov 04 12:01:57.71: [ 1247]: <-- data [3]
Nov 04 12:01:57.71: [ 1247]: <-- data [2]
Nov 04 12:01:58.89: [ 1247]: --> [2:OK]
Nov 04 12:01:58.89: [ 1247]: RECV send MCF (message confirmation)
Nov 04 12:01:58.89: [ 1247]: RECV FAX (000000282): from 01142326611, page   
1 in 0:23, INF, 3.85 line/mm, 2-D MMR, 14400 bit/s
Nov 04 12:01:58.89: [ 1247]: RECV FAX (000000282): recvq/fax000000216.tif   
from 01142326611, route to <unspecified>, 1 pages in 0:32
Nov 04 12:01:58.89: [ 1247]: <-- [9:AT+FRH=3\r]
Nov 04 12:01:59.89: [ 1247]: --> [7:CONNECT]
Nov 04 12:02:00.68: [ 1247]: --> [2:OK]
Nov 04 12:02:00.68: [ 1247]: RECV FAX: bin/faxrcvd   
"recvq/fax000000216.tif" "ttyS0" "000000282" "" "01228674221" ""
Nov 04 12:02:00.69: [ 1247]: RECV FAX: end
Nov 04 12:02:00.69: [ 1247]: SESSION END
fileserver-car:/var/spool/hylafax/log #

==============================================================

fileserver-car:/var/spool/hylafax/log # cat c000000269
Nov 04 10:19:53.92: [ 1247]: SESSION BEGIN 000000269 4401228674722
Nov 04 10:19:53.92: [ 1247]: HylaFAX (tm) Version 4.2.1
Nov 04 10:19:53.92: [ 1247]: <-- [4:ATA\r]
Nov 04 10:19:59.21: [ 1247]: --> [7:CONNECT]
Nov 04 10:19:59.21: [ 1247]: ANSWER: FAX CONNECTION  DEVICE '/dev/ttyS0'
Nov 04 10:19:59.21: [ 1247]: RECV FAX: begin
Nov 04 10:19:59.21: [ 1247]: <-- data [32]
Nov 04 10:19:59.21: [ 1247]: <-- data [2]
Nov 04 10:19:59.27: [ 1247]: --> [7:CONNECT]
Nov 04 10:19:59.27: [ 1247]: <-- data [23]
Nov 04 10:19:59.27: [ 1247]: <-- data [2]
Nov 04 10:19:59.99: [ 1247]: --> [7:CONNECT]
Nov 04 10:19:59.99: [ 1247]: <-- data [10]
Nov 04 10:19:59.99: [ 1247]: <-- data [2]
Nov 04 10:20:02.18: [ 1247]: --> [2:OK]
Nov 04 10:20:02.18: [ 1247]: <-- [9:AT+FRH=3\r]
Nov 04 10:20:02.72: [ 1247]: --> [7:CONNECT]
Nov 04 10:20:04.11: [ 1247]: --> [2:OK]
Nov 04 10:20:04.11: [ 1247]: REMOTE TSI "02083413330"
Nov 04 10:20:04.11: [ 1247]: <-- [9:AT+FRH=3\r]
Nov 04 10:20:04.13: [ 1247]: --> [7:CONNECT]
Nov 04 10:20:04.49: [ 1247]: --> [2:OK]
Nov 04 10:20:04.49: [ 1247]: REMOTE wants 14400 bit/s
Nov 04 10:20:04.49: [ 1247]: REMOTE wants A4 page width (215 mm)
Nov 04 10:20:04.49: [ 1247]: REMOTE wants unlimited page length
Nov 04 10:20:04.49: [ 1247]: REMOTE wants 3.85 line/mm
Nov 04 10:20:04.49: [ 1247]: REMOTE wants 2-D MMR
Nov 04 10:20:04.49: [ 1247]: REMOTE wants T.30 Annex A, 256-byte ECM
Nov 04 10:20:04.49: [ 1247]: RECV training at v.17 14400 bit/s
Nov 04 10:20:04.49: [ 1247]: <-- [11:AT+FRM=145\r]
Nov 04 10:20:06.20: [ 1247]: --> [7:CONNECT]
Nov 04 10:20:07.76: [ 1247]: RECV: TCF 2812 bytes, 3% non-zero, 1433   
zero-run
Nov 04 10:20:07.76: [ 1247]: RECV: reject TCF (zero run too short, min   
1800)
Nov 04 10:20:07.76: [ 1247]: --> [10:NO CARRIER]
Nov 04 10:20:07.76: [ 1247]: DELAY 75 ms
Nov 04 10:20:07.84: [ 1247]: <-- [9:AT+FTH=3\r]
Nov 04 10:20:08.04: [ 1247]: --> [7:CONNECT]
Nov 04 10:20:08.04: [ 1247]: <-- data [3]
Nov 04 10:20:08.04: [ 1247]: <-- data [2]
Nov 04 10:20:09.22: [ 1247]: --> [2:OK]
Nov 04 10:20:09.22: [ 1247]: TRAINING failed
Nov 04 10:20:09.22: [ 1247]: <-- [9:AT+FRH=3\r]
Nov 04 10:20:09.92: [ 1247]: --> [7:CONNECT]
Nov 04 10:20:11.17: [ 1247]: --> [2:OK]
Nov 04 10:20:11.17: [ 1247]: REMOTE TSI "02083413330"
Nov 04 10:20:11.17: [ 1247]: <-- [9:AT+FRH=3\r]
Nov 04 10:20:11.19: [ 1247]: --> [7:CONNECT]
Nov 04 10:20:11.54: [ 1247]: --> [2:OK]
Nov 04 10:20:11.54: [ 1247]: REMOTE wants 14400 bit/s
Nov 04 10:20:11.54: [ 1247]: REMOTE wants A4 page width (215 mm)
Nov 04 10:20:11.54: [ 1247]: REMOTE wants unlimited page length
Nov 04 10:20:11.54: [ 1247]: REMOTE wants 3.85 line/mm
Nov 04 10:20:11.54: [ 1247]: REMOTE wants 2-D MMR
Nov 04 10:20:11.54: [ 1247]: REMOTE wants T.30 Annex A, 256-byte ECM
Nov 04 10:20:11.54: [ 1247]: RECV training at v.17 14400 bit/s
Nov 04 10:20:11.54: [ 1247]: <-- [11:AT+FRM=145\r]
Nov 04 10:20:13.24: [ 1247]: --> [7:CONNECT]
Nov 04 10:20:14.60: [ 1247]: RECV: TCF 2452 bytes, 7% non-zero, 443   
zero-run
Nov 04 10:20:14.60: [ 1247]: RECV: reject TCF (zero run too short, min   
1800)
Nov 04 10:20:14.60: [ 1247]: --> [10:NO CARRIER]
Nov 04 10:20:14.60: [ 1247]: DELAY 75 ms
Nov 04 10:20:14.68: [ 1247]: <-- [9:AT+FTH=3\r]
Nov 04 10:20:14.88: [ 1247]: --> [7:CONNECT]
Nov 04 10:20:14.88: [ 1247]: <-- data [3]
Nov 04 10:20:14.88: [ 1247]: <-- data [2]
Nov 04 10:20:16.06: [ 1247]: --> [2:OK]
Nov 04 10:20:16.06: [ 1247]: TRAINING failed
Nov 04 10:20:16.06: [ 1247]: <-- [9:AT+FRH=3\r]
Nov 04 10:20:16.67: [ 1247]: --> [7:CONNECT]
Nov 04 10:20:18.00: [ 1247]: --> [2:OK]
Nov 04 10:20:18.00: [ 1247]: REMOTE TSI "02083413330"
Nov 04 10:20:18.00: [ 1247]: <-- [9:AT+FRH=3\r]
Nov 04 10:20:18.02: [ 1247]: --> [7:CONNECT]
Nov 04 10:20:18.38: [ 1247]: --> [2:OK]
Nov 04 10:20:18.38: [ 1247]: REMOTE wants 12000 bit/s
Nov 04 10:20:18.38: [ 1247]: REMOTE wants A4 page width (215 mm)
Nov 04 10:20:18.38: [ 1247]: REMOTE wants unlimited page length
Nov 04 10:20:18.38: [ 1247]: REMOTE wants 3.85 line/mm
Nov 04 10:20:18.38: [ 1247]: REMOTE wants 2-D MMR
Nov 04 10:20:18.38: [ 1247]: REMOTE wants T.30 Annex A, 256-byte ECM
Nov 04 10:20:18.38: [ 1247]: RECV training at v.17 12000 bit/s
Nov 04 10:20:18.38: [ 1247]: <-- [11:AT+FRM=121\r]
Nov 04 10:20:20.08: [ 1247]: --> [7:CONNECT]
Nov 04 10:20:21.64: [ 1247]: RECV: TCF 2343 bytes, 2% non-zero, 2266   
zero-run
Nov 04 10:20:21.65: [ 1247]: --> [10:NO CARRIER]
Nov 04 10:20:21.65: [ 1247]: DELAY 75 ms
Nov 04 10:20:21.73: [ 1247]: TRAINING succeeded
Nov 04 10:20:21.73: [ 1247]: <-- [9:AT+FTH=3\r]
Nov 04 10:20:21.93: [ 1247]: --> [7:CONNECT]
Nov 04 10:20:21.93: [ 1247]: <-- data [3]
Nov 04 10:20:21.93: [ 1247]: <-- data [2]
Nov 04 10:20:23.11: [ 1247]: --> [2:OK]
Nov 04 10:20:23.11: [ 1247]: <-- [11:AT+FRM=122\r]
Nov 04 10:20:24.10: [ 1247]: --> [7:CONNECT]
Nov 04 10:20:24.45: [ 1247]: RECV received frame number 0
Nov 04 10:20:24.54: [ 1247]: Bad HDLC terminating flag received.
Nov 04 10:20:24.80: [ 1247]: RECV received frame number 2
Nov 04 10:20:24.97: [ 1247]: RECV received frame number 3
Nov 04 10:20:25.15: [ 1247]: RECV received frame number 4
Nov 04 10:20:25.33: [ 1247]: RECV received frame number 5
Nov 04 10:20:25.51: [ 1247]: RECV received frame number 6
Nov 04 10:20:25.69: [ 1247]: HDLC frame not byte-oriented.  Trailing   
byte: 0
Nov 04 10:20:25.78: [ 1247]: HDLC frame not byte-oriented.  Trailing   
byte: 0xf8
Nov 04 10:20:26.04: [ 1247]: HDLC frame not byte-oriented.  Trailing   
byte: 0xfc
Nov 04 10:20:26.13: [ 1247]: Bad HDLC terminating flag received.
Nov 04 10:20:26.31: [ 1247]: Bad HDLC terminating flag received.
Nov 04 10:20:26.57: [ 1247]: RECV received frame number 12
Nov 04 10:20:26.75: [ 1247]: HDLC frame not byte-oriented.  Trailing   
byte: 0x80
Nov 04 10:20:26.92: [ 1247]: RECV received frame number 14
Nov 04 10:20:27.10: [ 1247]: RECV received frame number 15
Nov 04 10:20:27.10: [ 1247]: RECV frame FCS check failed
Nov 04 10:20:27.28: [ 1247]: RECV received frame number 16
Nov 04 10:20:27.28: [ 1247]: Bad HDLC terminating flag received.
Nov 04 10:20:27.64: [ 1247]: HDLC frame not byte-oriented.  Trailing   
byte: 0
Nov 04 10:20:27.82: [ 1247]: RECV received frame number 19
Nov 04 10:20:28.00: [ 1247]: RECV received frame number 20
Nov 04 10:20:28.18: [ 1247]: RECV received frame number 21
Nov 04 10:20:28.36: [ 1247]: RECV received frame number 22
Nov 04 10:20:28.54: [ 1247]: RECV received frame number 23
Nov 04 10:20:28.72: [ 1247]: RECV received frame number 24
Nov 04 10:20:28.90: [ 1247]: HDLC frame not byte-oriented.  Trailing   
byte: 0xfc
Nov 04 10:20:29.08: [ 1247]: RECV received frame number 26
Nov 04 10:20:29.08: [ 1247]: RECV frame FCS check failed
Nov 04 10:20:29.29: [ 1247]: RECV assumed RCP frame with block end
Nov 04 10:20:29.29: [ 1247]: --> [10:NO CARRIER]
Nov 04 10:20:29.29: [ 1247]: <-- [9:AT+FRH=3\r]
Nov 04 10:20:30.85: [ 1247]: --> [7:CONNECT]
Nov 04 10:20:30.99: [ 1247]: --> [5:ERROR]
Nov 04 10:20:30.99: [ 1247]: MODEM Command error
Nov 04 10:20:30.99: [ 1247]: FCS error
Nov 04 10:20:30.99: [ 1247]: <-- [9:AT+FRH=3\r]
Nov 04 10:20:31.01: [ 1247]: --> [7:CONNECT]
Nov 04 10:20:34.04: [ 1247]: --> [5:ERROR]
Nov 04 10:20:34.04: [ 1247]: MODEM Command error
Nov 04 10:20:34.04: [ 1247]: FCS error
Nov 04 10:20:34.04: [ 1247]: <-- [9:AT+FRH=3\r]
Nov 04 10:20:34.06: [ 1247]: --> [7:CONNECT]
Nov 04 10:20:34.49: [ 1247]: --> [5:ERROR]
Nov 04 10:20:34.49: [ 1247]: MODEM Command error
Nov 04 10:20:34.49: [ 1247]: FCS error
Nov 04 10:20:34.49: [ 1247]: <-- [9:AT+FRH=3\r]
Nov 04 10:20:34.51: [ 1247]: --> [7:CONNECT]
Nov 04 10:20:36.55: [ 1247]: --> [5:ERROR]
Nov 04 10:20:36.55: [ 1247]: MODEM Command error
Nov 04 10:20:36.55: [ 1247]: FCS error
Nov 04 10:20:36.55: [ 1247]: <-- [9:AT+FRH=3\r]
Nov 04 10:20:36.57: [ 1247]: --> [7:CONNECT]
Nov 04 10:20:39.10: [ 1247]: --> [5:ERROR]
Nov 04 10:20:39.10: [ 1247]: MODEM Command error
Nov 04 10:20:39.10: [ 1247]: FCS error
Nov 04 10:20:39.10: [ 1247]: <-- [9:AT+FRH=3\r]
Nov 04 10:20:39.12: [ 1247]: --> [7:CONNECT]
Nov 04 10:20:44.12: [ 1247]: <-- data [1]
Nov 04 10:20:44.13: [ 1247]: --> [2:OK]
Nov 04 10:20:44.13: [ 1247]: RECV keeping unconfirmed page
Nov 04 10:20:44.14: [ 1247]: RECV: 6912 bytes of data, 5 total lines
Nov 04 10:20:44.14: [ 1247]: <-- [9:AT+FRS=7\r]
Nov 04 10:20:45.46: [ 1247]: --> [2:OK]
Nov 04 10:20:45.47: [ 1247]: RECV FAX (000000269): from 02083413330, page   
1 in 0:24, INF, 3.85 line/mm, 2-D MMR, 12000 bit/s
Nov 04 10:20:45.47: [ 1247]: RECV FAX (000000269): recvq/fax000000204.tif   
from 02083413330, route to <unspecified>, 1 pages in 0:46
Nov 04 10:20:45.47: [ 1247]: RECV FAX: T.30 T2 timeout, expected signal   
not received
Nov 04 10:20:45.47: [ 1247]: <-- [9:AT+FTH=3\r]
Nov 04 10:20:45.50: [ 1247]: --> [7:CONNECT]
Nov 04 10:20:45.50: [ 1247]: <-- data [3]
Nov 04 10:20:45.50: [ 1247]: <-- data [2]
Nov 04 10:20:46.68: [ 1247]: --> [2:OK]
Nov 04 10:20:46.68: [ 1247]: RECV FAX (000000269): session with   
02083413330 terminated abnormally: T.30 T2 timeout, expected signal not   
received
Nov 04 10:20:46.68: [ 1247]: RECV FAX: bin/faxrcvd   
"recvq/fax000000204.tif" "ttyS0" "000000269" "T.30 T2 timeout, expected   
signal not received" "01228674221" ""
Nov 04 10:20:46.69: [ 1247]: RECV FAX: end
Nov 04 10:20:46.69: [ 1247]: SESSION END
fileserver-car:/var/spool/hylafax/log #

=============================================================

Sent: 07 November 2005 17:55
To: Bill Allison
Cc: hylafax-users@xxxxxxxxxxx
Subject: Re: [hylafax-users] Received faxes resen


   

 --------------------------------------------------------------------------  
 --
bill.allison@xxxxxxxxx wrote:

>modem USR 5630B (64-245-630-04)
>OS SLE 8.1 (2.4.19-64GB-SMP)
>hylafax 4.2.1 compiled from source
>
>

First off, please send a log, so that I can see what may be going on.

Secondly, it's probably the USR modem that's the problem catalyst here.

Some time ago (and I don't recall when it was) I "fixed" an issue in
HylaFAX where faxgetty could potentially end the call without the sender
initiating that disconnection.  It went something like this:

 --> get page data
 --> get post-page message indicating no more pages (EOP)
<-- send page confirmation (MCF)
<-- disconnect (DCN)

The problem with this approach is that the receiver didn't ensure that
the sender got the MCF signal.  So what we should do now is this:

 --> get page data
 --> get post-page message indicating no more pages (EOP)
<-- send page confirmation (MCF)
 --> get DCN
<-- hang up

I don't recall when it was that I did this fix, and I would have thought
that it was in 4.2.1, but maybe not.  Your session log will help tell.

Lee.

____________________ 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@xxxxxxxxx*




____________________ 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@xxxxxxxxx*