HylaFAX The world's most advanced open source fax server

[Date Prev][Date Next][Thread Prev][Thread Next] [Date Index] [Thread Index]

[hylafax-users] T.30 T2 Timeout (redux)

To: <hylafax-users@xxxxxxxxxxx>
Subject: [hylafax-users] T.30 T2 Timeout (redux)
From: "Brad Stevens" <bstevens@xxxxxxxxx>
Date: Thu, 11 Sep 2008 10:25:05 -0400

I've read many an archive on this particular problem and there really doesn't seem to be any concrete solutions to the problem and the last posts I can find are a couple years old. So here I go. Can anyone help me out????????

Fedora 8 Kernel Version 2.6.25.11-60.fc8

Hylafax 5.2.5

Six - USR PCI 5601 Internal Modem (Rev. 5.22.16 on this modem ttyS6 that is the busiest inbound modem)

Inbound Faxes failing with partial pages. Final error is:

T.30 T2 timeout, expected signal not received {E118}

Transcript: (with large repeating section cut from the center)

---------------------------------------------------------------------------------------------------------------------------------------------------------------------

Sep 10 16:03:22.52: [ 8592]: SESSION BEGIN 000002086 1234567890

Sep 10 16:03:22.52: [ 8592]: HylaFAX (tm) Version 5.2.5

Sep 10 16:03:22.52: [ 8592]: MODEM set XON/XOFF/FLUSH: input ignored, output disabled

Sep 10 16:03:22.52: [ 8592]: <-- [13:AT+FCLASS=1A\r]

Sep 10 16:03:28.11: [ 8592]: --> [7:CONNECT]

Sep 10 16:03:28.11: [ 8592]: ANSWER: FAX CONNECTION DEVICE '/dev/ttyS6'

Sep 10 16:03:28.11: [ 8592]: STATE CHANGE: ANSWERING -> RECEIVING

Sep 10 16:03:28.11: [ 8592]: RECV FAX: begin

Sep 10 16:03:28.11: [ 8592]: <-- HDLC<32:FF C0 04 B5 00 AA 12 9E 36 86 62 82 1A 04 14 2E B6 94 04 6A A6 4E CE 96 F6 76 04 AC 74 4C 74 AC>

Sep 10 16:03:28.27: [ 8592]: --> [7:CONNECT]

Sep 10 16:03:28.27: [ 8592]: <-- HDLC<23:FF C0 02 92 B2 4A 04 04 04 04 04 04 04 04 04 04 04 04 04 04 04 04 04>

Sep 10 16:03:28.99: [ 8592]: --> [7:CONNECT]

Sep 10 16:03:28.99: [ 8592]: <-- HDLC<13:FF C8 01 00 77 5F 23 01 FB C1 01 01 18>

Sep 10 16:03:31.26: [ 8592]: --> [2:OK]

Sep 10 16:03:31.26: [ 8592]: <-- [9:AT+FRH=3\r]

Sep 10 16:03:31.53: [ 8592]: --> [7:CONNECT]

Sep 10 16:03:33.07: [ 8592]: --> HDLC<25:FF C0 C2 1C CC 6C 1C CC CC EC 0C 8C 1C 8C 04 04 04 04 04 04 04 04 04 DF 46>

Sep 10 16:03:33.07: [ 8592]: --> [2:OK]

Sep 10 16:03:33.07: [ 8592]: RECV recv TSI (sender id)

Sep 10 16:03:33.07: [ 8592]: REMOTE TSI "5551234"

Sep 10 16:03:33.07: [ 8592]: <-- [9:AT+FRH=3\r]

Sep 10 16:03:33.09: [ 8592]: --> [7:CONNECT]

Sep 10 16:03:33.52: [ 8592]: --> HDLC<15:FF C8 C1 00 44 1F 23 01 01 01 01 01 00 B7 02>

Sep 10 16:03:33.53: [ 8592]: --> [2:OK]

Sep 10 16:03:33.53: [ 8592]: RECV recv DCS (command signal)

Sep 10 16:03:33.53: [ 8592]: REMOTE wants 14400 bit/s

Sep 10 16:03:33.53: [ 8592]: REMOTE wants A4 page width (215 mm)

Sep 10 16:03:33.53: [ 8592]: REMOTE wants unlimited page length

Sep 10 16:03:33.53: [ 8592]: REMOTE wants 3.85 line/mm

Sep 10 16:03:33.53: [ 8592]: REMOTE wants 2-D MMR

Sep 10 16:03:33.53: [ 8592]: REMOTE wants T.30 Annex A, 256-byte ECM

Sep 10 16:03:33.53: [ 8592]: RECV training at v.17 14400 bit/s

Sep 10 16:03:33.53: [ 8592]: MODEM set XON/XOFF/DRAIN: input ignored, output generated

Sep 10 16:03:33.53: [ 8592]: <-- [11:AT+FRM=145\r]

Sep 10 16:03:35.28: [ 8592]: --> [7:CONNECT]

Sep 10 16:03:36.04: [ 8592]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled

Sep 10 16:03:36.04: [ 8592]: RECV: TCF 1372 bytes, 0% non-zero, 1362 zero-run

Sep 10 16:03:36.04: [ 8592]: --> [10:NO CARRIER]

Sep 10 16:03:36.04: [ 8592]: DELAY 70 ms

Sep 10 16:03:36.11: [ 8592]: TRAINING succeeded

Sep 10 16:03:36.12: [ 8592]: <-- [9:AT+FTH=3\r]

Sep 10 16:03:36.31: [ 8592]: --> [7:CONNECT]

Sep 10 16:03:36.31: [ 8592]: <-- HDLC<3:FF C8 21>

Sep 10 16:03:37.48: [ 8592]: --> [2:OK]

Sep 10 16:03:37.48: [ 8592]: <-- [11:AT+FRM=146\r]

Sep 10 16:03:38.33: [ 8592]: --> [7:CONNECT]

Sep 10 16:03:38.33: [ 8592]: MODEM input buffering enabled

Sep 10 16:03:38.33: [ 8592]: MODEM set XON/XOFF/FLUSH: input ignored, output generated

Sep 10 16:03:38.75: [ 8592]: RECV received frame number 0

Sep 10 16:03:38.89: [ 8592]: RECV received frame number 1

Sep 10 16:03:39.03: [ 8592]: RECV received frame number 2

Sep 10 16:03:39.17: [ 8592]: RECV received frame number 3

Sep 10 16:03:39.30: [ 8592]: RECV received frame number 4

Sep 10 16:03:39.45: [ 8592]: RECV assumed RCP frame with block end

Sep 10 16:03:39.45: [ 8592]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled

Sep 10 16:03:39.45: [ 8592]: MODEM input buffering disabled

Sep 10 16:03:39.45: [ 8592]: --> [10:NO CARRIER]

Sep 10 16:03:39.45: [ 8592]: <-- [9:AT+FRH=3\r]

Sep 10 16:03:44.33: [ 8592]: --> [7:CONNECT]

Sep 10 16:03:45.47: [ 8592]: --> HDLC<9:FF C8 FD F4 00 00 24 37 44>

Sep 10 16:03:45.48: [ 8592]: --> [2:OK]

Sep 10 16:03:45.48: [ 8592]: RECV recv PPS (partial page signal)

Sep 10 16:03:45.48: [ 8592]: RECV recv EOP (no more pages or documents)

Sep 10 16:03:45.48: [ 8592]: RECV received 37 frames of block 1 of page 1

Sep 10 16:03:45.48: [ 8592]: DELAY 70 ms

Sep 10 16:03:45.55: [ 8592]: <-- [9:AT+FTH=3\r]

Sep 10 16:03:45.74: [ 8592]: --> [7:CONNECT]

Sep 10 16:03:45.74: [ 8592]: <-- HDLC<35:FF C8 3D 07 FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF>

Sep 10 16:03:47.93: [ 8592]: --> [2:OK]

Sep 10 16:03:47.93: [ 8592]: RECV send PPR (partial page request)

Sep 10 16:03:47.93: [ 8592]: <-- [11:AT+FRM=146\r]

Sep 10 16:03:48.76: [ 8592]: --> [7:CONNECT]

Sep 10 16:03:48.76: [ 8592]: MODEM input buffering enabled

Sep 10 16:03:48.76: [ 8592]: MODEM set XON/XOFF/FLUSH: input ignored, output generated

Sep 10 16:03:49.17: [ 8592]: RECV received frame number 5

Sep 10 16:03:49.31: [ 8592]: RECV received frame number 6

Sep 10 16:03:49.45: [ 8592]: RECV received frame number 7

Sep 10 16:03:49.59: [ 8592]: RECV received frame number 8

Sep 10 16:03:49.73: [ 8592]: RECV received frame number 9

Sep 10 16:03:49.87: [ 8592]: RECV assumed RCP frame with block end

Sep 10 16:03:49.87: [ 8592]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled

Sep 10 16:03:49.87: [ 8592]: MODEM input buffering disabled

Sep 10 16:03:49.87: [ 8592]: --> [10:NO CARRIER]

Sep 10 16:03:49.87: [ 8592]: <-- [9:AT+FRH=3\r]

Sep 10 16:03:51.48: [ 8592]: --> [7:CONNECT]

Sep 10 16:03:51.65: [ 8592]: --> [9:45 96 1C F1 78 6B 46 10 03]

Sep 10 16:03:51.66: [ 8592]: --> [5:ERROR]

Sep 10 16:03:51.66: [ 8592]: MODEM Command error

Sep 10 16:03:51.66: [ 8592]: FCS error

Sep 10 16:03:51.66: [ 8592]: DELAY 70 ms

Sep 10 16:03:51.73: [ 8592]: <-- [9:AT+FTH=3\r]

Sep 10 16:03:51.92: [ 8592]: --> [7:CONNECT]

Sep 10 16:03:51.92: [ 8592]: <-- HDLC<3:FF C8 58>

Sep 10 16:03:53.09: [ 8592]: --> [2:OK]

Sep 10 16:03:53.09: [ 8592]: RECV send CRP (command repeat)

Sep 10 16:03:53.09: [ 8592]: <-- [9:AT+FRH=3\r]

Sep 10 16:03:53.93: [ 8592]: --> [7:CONNECT]

Sep 10 16:03:55.16: [ 8592]: --> HDLC<9:FF C8 FD F4 00 00 F8 3D B5>

Sep 10 16:03:55.16: [ 8592]: --> [2:OK]

Sep 10 16:03:55.16: [ 8592]: RECV recv PPS (partial page signal)

Sep 10 16:03:55.16: [ 8592]: RECV recv EOP (no more pages or documents)

Sep 10 16:03:55.16: [ 8592]: RECV received 32 frames of block 1 of page 1

Sep 10 16:03:55.16: [ 8592]: DELAY 70 ms

Sep 10 16:03:55.23: [ 8592]: <-- [9:AT+FTH=3\r]

Sep 10 16:03:55.42: [ 8592]: --> [7:CONNECT]

Sep 10 16:03:55.42: [ 8592]: <-- HDLC<35:FF C8 3D 00 3F FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF FF>

Sep 10 16:03:57.61: [ 8592]: --> [2:OK]

Sep 10 16:03:57.61: [ 8592]: RECV send PPR (partial page request)

Sep 10 16:03:57.61: [ 8592]: <-- [11:AT+FRM=146\r]

Sep 10 16:03:58.44: [ 8592]: --> [7:CONNECT]

Sep 10 16:03:58.44: [ 8592]: MODEM input buffering enabled

Sep 10 16:03:58.44: [ 8592]: MODEM set XON/XOFF/FLUSH: input ignored, output generated

Sep 10 16:03:58.85: [ 8592]: RECV received frame number 10

Sep 10 16:03:58.99: [ 8592]: RECV received frame number 11

Sep 10 16:03:59.13: [ 8592]: RECV received frame number 12

Sep 10 16:03:59.27: [ 8592]: RECV received frame number 13

Sep 10 16:03:59.40: [ 8592]: RECV received frame number 14

Sep 10 16:03:59.54: [ 8592]: RECV received frame number 15

Sep 10 16:03:59.65: [ 8592]: RECV assumed RCP frame with block end

Sep 10 16:03:59.65: [ 8592]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled

Sep 10 16:03:59.65: [ 8592]: MODEM input buffering disabled

Sep 10 16:03:59.65: [ 8592]: --> [10:NO CARRIER]

Sep 10 16:03:59.65: [ 8592]: <-- [9:AT+FRH=3\r]

Sep 10 16:04:02.73: [ 8592]: --> [10:NO CARRIER]

Sep 10 16:04:02.73: [ 8592]: MODEM No carrier

Sep 10 16:04:02.73: [ 8592]: <-- [9:AT+FRH=3\r]

Sep 10 16:04:02.75: [ 8592]: --> [10:NO CARRIER]

Sep 10 16:04:02.75: [ 8592]: MODEM No carrier

Sep 10 16:04:02.75: [ 8592]: <-- [9:AT+FRH=3\r]

Sep 10 16:04:02.76: [ 8592]: --> [10:NO CARRIER]

Sep 10 16:04:02.76: [ 8592]: MODEM No carrier

.........

Sep 10 16:04:45.15: [ 8592]: MODEM No carrier

Sep 10 16:04:45.15: [ 8592]: <-- [9:AT+FRH=3\r]

Sep 10 16:04:45.16: [ 8592]: --> [10:NO CARRIER]

Sep 10 16:04:45.16: [ 8592]: MODEM No carrier

Sep 10 16:04:45.16: [ 8592]: <-- [9:AT+FRH=3\r]

Sep 10 16:04:45.16: [ 8592]: --> [0:]

Sep 10 16:04:45.16: [ 8592]: MODEM <Empty line>

Sep 10 16:04:45.16: [ 8592]: MODEM TIMEOUT: waiting for v.21 carrier

Sep 10 16:04:45.17: [ 8592]: --> [10:NO CARRIER]

Sep 10 16:04:45.17: [ 8592]: MODEM No carrier

Sep 10 16:04:45.20: [ 8592]: RECV/CQ: Adjusting for EOFB at row 330

Sep 10 16:04:45.21: [ 8592]: RECV: 9472 bytes of data, 330 total lines

Sep 10 16:04:45.21: [ 8592]: RECV keeping unconfirmed page

Sep 10 16:04:45.21: [ 8592]: MODEM set XON/XOFF/DRAIN: input ignored, output disabled

Sep 10 16:04:45.21: [ 8592]: RECV FAX (000002086): from 5551234, page 1 in 0:01:17, INF, 3.85 line/mm, 2-D MMR, 14400 bit/s

Sep 10 16:04:46.21: [ 8592]: RECV FAX (000002086): recvq/fax000001400.tif from 5551234, subaddress <unspecified>, 1 pages in 0:01:17

Sep 10 16:04:46.21: [ 8592]: RECV FAX: T.30 T2 timeout, expected signal not received {E118}

Sep 10 16:04:46.21: [ 8592]: DELAY 70 ms

Sep 10 16:04:46.28: [ 8592]: <-- [9:AT+FTH=3\r]

Sep 10 16:04:46.47: [ 8592]: --> [7:CONNECT]

Sep 10 16:04:46.47: [ 8592]: <-- HDLC<3:FF C8 5F>

Sep 10 16:04:47.65: [ 8592]: --> [2:OK]

Sep 10 16:04:47.65: [ 8592]: MODEM input buffering enabled

Sep 10 16:04:47.65: [ 8592]: RECV FAX (000002086): session with 5551234 terminated abnormally: T.30 T2 timeout, expected signal not received {E118}

Sep 10 16:04:47.65: [ 8592]: RECV FAX: bin/faxrcvd 'recvq/fax000001400.tif' 'ttyS6' '000002086' 'T.30 T2 timeout, expected signal not received {E118}'

Sep 10 16:04:47.66: [ 8592]: RECV FAX: end

Sep 10 16:04:47.66: [ 8592]: SESSION END

---------------------------------------------------------------------------------------------------------------------------------------------------------------------

END of log

---------------------------------------------------------------------------------------------------------------------------------------------------------------------

Follow-Ups:
- Re: [hylafax-users] T.30 T2 Timeout (redux)
  - From: Lee Howard

Prev by Date: [hylafax-users] Problem in Fax receiving
Next by Date: [hylafax-users] How can i use hylafax without phone service?
Previous by thread: Re: [hylafax-users] Problem in Fax receiving
Next by thread: Re: [hylafax-users] T.30 T2 Timeout (redux)
Index(es):
- Main
- Thread

Project hosted by iFAX Solutions