[hylafax-users] Timeouts depending on disk drive!

["Uwe Dippel" <udippel@xxxxxxxxx>]

After a few tests forth and back, I can confirm from here that I have zero
T.30 T2 timeout, expected signal not received
when running from hard disk, while plenty when running from Compact
Flash (Kingston, 133X).
The install is identically the same, because the CF was created by
'dd'. Everything else runs smoothly.

I consider this an interesting find, since it seems that a lower speed
(CF) interferes with the operation of hylafax in an unexpected manner.
I have done two full rounds of tests, with another 'dd', and
immediately when booting back from CF the errors start. We are faxing
batches of hundreds of pages. I have as well switched serial and USB
modem, to no avail: no change of the errors happening; be it on CF or
hard drive.

One of the many logs, from a single page transfer, is attached.


Thanks for any hint,

Uwe


Oct 08 14:18:11.79: [14774]: SESSION BEGIN 000000061 60389212388
Oct 08 14:18:11.79: [14774]: HylaFAX (tm) Version 4.4.3
Oct 08 14:18:11.79: [14774]: <-- [4:ATA\r]
Oct 08 14:18:20.92: [14774]: --> [7:CONNECT]
Oct 08 14:18:20.92: [14774]: ANSWER: FAX CONNECTION  DEVICE '/dev/ttyU0'
Oct 08 14:18:20.93: [14774]: RECV FAX: begin
Oct 08 14:18:21.04: [14774]: <-- data [32]
Oct 08 14:18:21.04: [14774]: <-- data [2]
Oct 08 14:18:21.06: [14774]: --> [7:CONNECT]
Oct 08 14:18:21.06: [14774]: <-- data [23]
Oct 08 14:18:21.06: [14774]: <-- data [2]
Oct 08 14:18:21.08: [14774]: --> [7:CONNECT]
Oct 08 14:18:21.08: [14774]: <-- data [13]
Oct 08 14:18:21.08: [14774]: <-- data [2]
Oct 08 14:18:23.29: [14774]: --> [2:OK]
Oct 08 14:18:23.29: [14774]: <-- [9:AT+FRH=3\r]
Oct 08 14:18:23.44: [14774]: --> [7:CONNECT]
Oct 08 14:18:25.15: [14774]: --> [2:OK]
Oct 08 14:18:25.15: [14774]: REMOTE TSI "0389263661"
Oct 08 14:18:25.15: [14774]: RECV recv TSI (sender id)
Oct 08 14:18:25.15: [14774]: <-- [9:AT+FRH=3\r]
Oct 08 14:18:25.17: [14774]: --> [7:CONNECT]
Oct 08 14:18:25.53: [14774]: --> [2:OK]
Oct 08 14:18:25.53: [14774]: REMOTE wants 14400 bit/s
Oct 08 14:18:25.53: [14774]: REMOTE wants A4 page width (215 mm)
Oct 08 14:18:25.53: [14774]: REMOTE wants unlimited page length
Oct 08 14:18:25.53: [14774]: REMOTE wants R16 x 15.4 line/mm
Oct 08 14:18:25.53: [14774]: REMOTE wants 2-D MMR
Oct 08 14:18:25.53: [14774]: REMOTE wants T.30 Annex A, 256-byte ECM
Oct 08 14:18:25.53: [14774]: RECV recv DCS (command signal)
Oct 08 14:18:25.53: [14774]: RECV training at v.17 14400 bit/s
Oct 08 14:18:25.53: [14774]: <-- [11:AT+FRM=145\r]
Oct 08 14:18:27.34: [14774]: --> [7:CONNECT]
Oct 08 14:18:28.86: [14774]: RECV: TCF 2720 bytes, 0% non-zero, 2697 zero-run
Oct 08 14:18:28.86: [14774]: --> [10:NO CARRIER]
Oct 08 14:18:28.86: [14774]: <-- [9:AT+FRS=7\r]
Oct 08 14:18:28.94: [14774]: --> [2:OK]
Oct 08 14:18:28.94: [14774]: TRAINING succeeded
Oct 08 14:18:28.94: [14774]: <-- [9:AT+FTH=3\r]
Oct 08 14:18:29.81: [14774]: --> [7:CONNECT]
Oct 08 14:18:29.81: [14774]: <-- data [3]
Oct 08 14:18:29.81: [14774]: <-- data [2]
Oct 08 14:18:30.13: [14774]: --> [2:OK]
Oct 08 14:18:30.13: [14774]: <-- [11:AT+FRM=146\r]
Oct 08 14:18:31.01: [14774]: --> [7:CONNECT]
Oct 08 14:18:31.44: [14774]: RECV received frame number 0
Oct 08 14:18:31.58: [14774]: RECV received frame number 1
Oct 08 14:18:31.72: [14774]: RECV received frame number 2
Oct 08 14:18:31.86: [14774]: RECV received frame number 3
Oct 08 14:18:32.00: [14774]: RECV received frame number 4
Oct 08 14:18:32.14: [14774]: RECV received frame number 5
Oct 08 14:18:32.34: [14774]: RECV received frame number 6
Oct 08 14:18:32.48: [14774]: RECV received frame number 7
Oct 08 14:18:32.62: [14774]: RECV received frame number 8
Oct 08 14:18:32.76: [14774]: RECV received frame number 9
Oct 08 14:18:32.90: [14774]: RECV received frame number 10
Oct 08 14:18:33.04: [14774]: RECV received frame number 11
Oct 08 14:18:33.18: [14774]: RECV received frame number 12
Oct 08 14:18:33.39: [14774]: RECV received frame number 13
Oct 08 14:18:33.53: [14774]: RECV received frame number 14
Oct 08 14:18:33.67: [14774]: RECV received frame number 15
Oct 08 14:18:33.81: [14774]: RECV received frame number 16
Oct 08 14:18:33.95: [14774]: RECV received frame number 17
Oct 08 14:18:34.09: [14774]: RECV received frame number 18
Oct 08 14:18:34.23: [14774]: RECV received frame number 19
Oct 08 14:18:34.37: [14774]: RECV received frame number 20
Oct 08 14:18:34.58: [14774]: RECV received frame number 21
Oct 08 14:18:34.72: [14774]: RECV received frame number 22
Oct 08 14:18:34.86: [14774]: RECV received frame number 23
Oct 08 14:18:35.00: [14774]: RECV received frame number 24
Oct 08 14:18:35.14: [14774]: RECV received frame number 25
Oct 08 14:18:35.28: [14774]: RECV received frame number 26
Oct 08 14:18:35.42: [14774]: RECV received frame number 27
Oct 08 14:18:35.62: [14774]: RECV received frame number 28
Oct 08 14:18:35.76: [14774]: RECV received frame number 29
Oct 08 14:18:35.90: [14774]: RECV received frame number 30
Oct 08 14:18:36.04: [14774]: RECV received frame number 31
Oct 08 14:18:36.18: [14774]: RECV received frame number 32
Oct 08 14:18:36.32: [14774]: RECV received frame number 33
Oct 08 14:18:36.46: [14774]: RECV received frame number 34
Oct 08 14:18:36.60: [14774]: RECV received frame number 35
Oct 08 14:18:36.81: [14774]: RECV received frame number 36
Oct 08 14:18:36.95: [14774]: RECV received frame number 37
Oct 08 14:18:37.09: [14774]: RECV received frame number 38
Oct 08 14:18:37.23: [14774]: RECV received frame number 39
Oct 08 14:18:37.37: [14774]: RECV received frame number 40
Oct 08 14:18:37.51: [14774]: RECV received frame number 41
Oct 08 14:18:37.65: [14774]: RECV received frame number 42
Oct 08 14:18:37.79: [14774]: RECV received frame number 43
Oct 08 14:18:37.93: [14774]: RECV received frame number 44
Oct 08 14:18:38.14: [14774]: RECV received frame number 45
Oct 08 14:18:38.28: [14774]: RECV received frame number 46
Oct 08 14:18:38.42: [14774]: RECV received frame number 47
Oct 08 14:18:38.56: [14774]: RECV received frame number 48
Oct 08 14:18:38.70: [14774]: RECV received frame number 49
Oct 08 14:18:38.84: [14774]: RECV received frame number 50
Oct 08 14:18:38.98: [14774]: RECV received frame number 51
Oct 08 14:18:39.12: [14774]: RECV received frame number 52
Oct 08 14:18:39.33: [14774]: RECV received frame number 53
Oct 08 14:18:39.47: [14774]: RECV received frame number 54
Oct 08 14:18:39.61: [14774]: RECV received frame number 55
Oct 08 14:18:39.75: [14774]: RECV received frame number 56
Oct 08 14:18:39.89: [14774]: RECV received frame number 57
Oct 08 14:18:40.03: [14774]: RECV received frame number 58
Oct 08 14:18:40.17: [14774]: RECV received frame number 59
Oct 08 14:18:40.37: [14774]: RECV received frame number 60
Oct 08 14:18:40.52: [14774]: RECV received frame number 61
Oct 08 14:18:40.65: [14774]: RECV received frame number 62
Oct 08 14:18:40.79: [14774]: RECV received frame number 63
Oct 08 14:18:40.94: [14774]: RECV received frame number 64
Oct 08 14:18:41.08: [14774]: RECV received frame number 65
Oct 08 14:18:41.22: [14774]: RECV received frame number 66
Oct 08 14:18:41.36: [14774]: RECV received frame number 67
Oct 08 14:18:41.56: [14774]: RECV received frame number 68
Oct 08 14:18:41.70: [14774]: RECV received frame number 69
Oct 08 14:18:41.84: [14774]: RECV received frame number 70
Oct 08 14:18:41.98: [14774]: RECV received frame number 71
Oct 08 14:18:42.12: [14774]: RECV received frame number 72
Oct 08 14:18:42.26: [14774]: RECV received frame number 73
Oct 08 14:18:42.76: [14774]: RECV received frame number 74
Oct 08 14:18:42.77: [14774]: RECV received frame number 75
Oct 08 14:18:42.77: [14774]: RECV assumed RCP frame with block end
Oct 08 14:18:42.77: [14774]: --> [2:OK]
Oct 08 14:18:44.77: [14774]: MODEM <Timeout>
Oct 08 14:18:46.78: [14774]: MODEM <Empty line>
Oct 08 14:18:48.79: [14774]: MODEM <Empty line>
Oct 08 14:18:48.79: [14774]: <-- [9:AT+FRH=3\r]
Oct 08 14:18:49.18: [14774]: --> [7:CONNECT]
Oct 08 14:18:59.18: [14774]: <-- data [1]
Oct 08 14:18:59.19: [14774]: --> [2:]
Oct 08 14:18:59.19: [14774]: --> [2:OK]
Oct 08 14:18:59.42: [14774]: RECV: 19456 bytes of data, 972 total lines
Oct 08 14:18:59.42: [14774]: RECV keeping unconfirmed page
Oct 08 14:18:59.42: [14774]: RECV FAX: T.30 T2 timeout, expected
signal not received
Oct 08 14:18:59.42: [14774]: <-- [9:AT+FTH=3\r]
Oct 08 14:18:59.42: [14774]: RECV FAX (000000061): from 0389263661,
page 1 in 0:38, INF, R16 x 15.4 line/mm, 2-D MMR, 14400 bit/s
Oct 08 14:18:59.42: [14774]: RECV FAX (000000061):
recvq/fax000000083.tif from 0389263661, route to <unspecified>, 1
pages in 0:38
Oct 08 14:19:00.28: [14774]: --> [7:CONNECT]
Oct 08 14:19:00.28: [14774]: <-- data [3]
Oct 08 14:19:00.28: [14774]: <-- data [2]
Oct 08 14:19:00.60: [14774]: --> [2:OK]
Oct 08 14:19:00.61: [14774]: RECV FAX (000000061): session with
0389263661 terminated abnormally: T.30 T2 timeout, expected signal not
received
Oct 08 14:19:00.61: [14774]: RECV FAX: bin/faxrcvd
"recvq/fax000000083.tif" "ttyU0" "000000061" "T.30 T2 timeout,
expected signal not received"
Oct 08 14:19:00.61: [14774]: RECV FAX: end
Oct 08 14:19:00.61: [14774]: T.30 T2 timeout, expected signal not received
Oct 08 14:19:00.61: [14774]: SESSION END


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