[hylafax-users] T.38 hylafax/asterisk query

[Michael <michael@xxxxxxxxxxxxxxxxxx>]

I am getting the following error, faxing using T.38 and SIP, across localhost.

Hylafax attempts to resend the fax.

And yet both times that received PDF fax was attached and I could not see 
anything visually wrong with it!

The setup is Asterisk 1.4.22 + Hylafax 5.2.7 + the software/directions on this 
link-
http://www.voip-info.org/wiki/view/T38modem+configuration+with+Asterisk
------------------------------------------------------
         Sender: 
          Pages: 1
        Quality: Fine
           Size: ISO A4
       Received: 2008:11:23 01:23:05
Time To Receive: 0:01:42
    Signal Rate: 9600 bit/s
    Data Format: JBIG
  Error Correct: Yes
    Received On: ttyT38-1

The full document was not received because:

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

    ---- Transcript of session follows ----

Nov 23 01:21:38.37: [29454]: SESSION BEGIN 000054319 
Nov 23 01:21:38.37: [29454]: HylaFAX (tm) Version 5.2.7
Nov 23 01:21:38.37: [29454]: <-- [4:ATA\r]
Nov 23 01:21:57.20: [29454]: --> [7:CONNECT]
Nov 23 01:21:57.20: [29454]: ANSWER: FAX CONNECTION  DEVICE '/dev/ttyT38-1'
Nov 23 01:21:57.20: [29454]: STATE CHANGE: ANSWERING -> RECEIVING
Nov 23 01:21:57.20: [29454]: RECV FAX: begin
Nov 23 01:21:57.20: [29454]: <-- 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 EC>
Nov 23 01:22:04.24: [29454]: --> [7:CONNECT]
Nov 23 01:22:04.24: [29454]: <-- HDLC<23:FF C0 02 04 04 04 04 04 04 04 04 04 
04 04 04 04 04 04 04 04 04 04 04>
Nov 23 01:22:04.85: [29454]: --> [7:CONNECT]
Nov 23 01:22:04.85: [29454]: <-- HDLC<13:FF C8 01 00 77 5F 23 01 FB C1 01 01 
1E>
Nov 23 01:22:05.70: [29454]: --> [2:OK]
Nov 23 01:22:05.70: [29454]: <-- [9:AT+FRH=3\r]
Nov 23 01:22:06.70: [29454]: --> [7:CONNECT]
Nov 23 01:22:12.38: [29454]: --> HDLC<25:FF C0 C2 04 04 04 04 04 04 04 04 04 
04 04 04 04 04 04 04 04 04 04 04 89 69>
Nov 23 01:22:12.38: [29454]: --> [2:OK]
Nov 23 01:22:12.38: [29454]: RECV recv TSI (sender id)
Nov 23 01:22:12.38: [29454]: REMOTE TSI ""
Nov 23 01:22:12.38: [29454]: <-- [9:AT+FRH=3\r]
Nov 23 01:22:12.38: [29454]: --> [7:CONNECT]
Nov 23 01:22:12.80: [29454]: --> HDLC<15:FF C8 C1 00 46 1F 21 01 01 01 01 01 
04 51 02>
Nov 23 01:22:12.80: [29454]: --> [2:OK]
Nov 23 01:22:12.80: [29454]: RECV recv DCS (command signal)
Nov 23 01:22:12.80: [29454]: REMOTE wants 14400 bit/s
Nov 23 01:22:12.80: [29454]: REMOTE wants A4 page width (215 mm)
Nov 23 01:22:12.80: [29454]: REMOTE wants unlimited page length
Nov 23 01:22:12.80: [29454]: REMOTE wants 7.7 line/mm
Nov 23 01:22:12.80: [29454]: REMOTE wants JBIG
Nov 23 01:22:12.80: [29454]: REMOTE wants T.30 Annex A, 256-byte ECM
Nov 23 01:22:12.80: [29454]: RECV training at v.17 14400 bit/s
Nov 23 01:22:12.80: [29454]: <-- [11:AT+FRM=145\r]
Nov 23 01:22:17.70: [29454]: --> [0:]
Nov 23 01:22:17.70: [29454]: MODEM <Empty line>
Nov 23 01:22:17.70: [29454]: MODEM TIMEOUT: receiving TCF
Nov 23 01:22:17.70: [29454]: --> [2:OK]
Nov 23 01:22:17.70: [29454]: <-- [9:AT+FRS=7\r]
Nov 23 01:22:18.20: [29454]: --> [2:OK]
Nov 23 01:22:18.20: [29454]: <-- [9:AT+FTH=3\r]
Nov 23 01:22:18.20: [29454]: --> [7:CONNECT]
Nov 23 01:22:18.20: [29454]: <-- HDLC<3:FF C8 22>
Nov 23 01:22:25.70: [29454]: --> [2:OK]
Nov 23 01:22:25.70: [29454]: TRAINING failed
Nov 23 01:22:25.70: [29454]: <-- [9:AT+FRH=3\r]
Nov 23 01:22:26.70: [29454]: --> [7:CONNECT]
Nov 23 01:22:32.38: [29454]: --> HDLC<25:FF C0 C2 04 04 04 04 04 04 04 04 04 
04 04 04 04 04 04 04 04 04 04 04 89 69>
Nov 23 01:22:32.38: [29454]: --> [2:OK]
Nov 23 01:22:32.38: [29454]: RECV recv TSI (sender id)
Nov 23 01:22:32.38: [29454]: REMOTE TSI ""
Nov 23 01:22:32.38: [29454]: <-- [9:AT+FRH=3\r]
Nov 23 01:22:32.38: [29454]: --> [7:CONNECT]
Nov 23 01:22:32.81: [29454]: --> HDLC<15:FF C8 C1 00 56 1F 21 01 01 01 01 01 
04 02 FC>
Nov 23 01:22:32.81: [29454]: --> [2:OK]
Nov 23 01:22:32.81: [29454]: RECV recv DCS (command signal)
Nov 23 01:22:32.81: [29454]: REMOTE wants 12000 bit/s
Nov 23 01:22:32.81: [29454]: REMOTE wants A4 page width (215 mm)
Nov 23 01:22:32.81: [29454]: REMOTE wants unlimited page length
Nov 23 01:22:32.81: [29454]: REMOTE wants 7.7 line/mm
Nov 23 01:22:32.81: [29454]: REMOTE wants JBIG
Nov 23 01:22:32.81: [29454]: REMOTE wants T.30 Annex A, 256-byte ECM
Nov 23 01:22:32.81: [29454]: RECV training at v.17 12000 bit/s
Nov 23 01:22:32.81: [29454]: <-- [11:AT+FRM=121\r]
Nov 23 01:22:37.70: [29454]: --> [0:]
Nov 23 01:22:37.70: [29454]: MODEM <Empty line>
Nov 23 01:22:37.70: [29454]: MODEM TIMEOUT: receiving TCF
Nov 23 01:22:37.70: [29454]: --> [2:OK]
Nov 23 01:22:37.70: [29454]: <-- [9:AT+FRS=7\r]
Nov 23 01:22:38.20: [29454]: --> [2:OK]
Nov 23 01:22:38.20: [29454]: <-- [9:AT+FTH=3\r]
Nov 23 01:22:38.20: [29454]: --> [7:CONNECT]
Nov 23 01:22:38.20: [29454]: <-- HDLC<3:FF C8 22>
Nov 23 01:22:45.70: [29454]: --> [2:OK]
Nov 23 01:22:45.70: [29454]: TRAINING failed
Nov 23 01:22:45.70: [29454]: <-- [9:AT+FRH=3\r]
Nov 23 01:22:46.70: [29454]: --> [7:CONNECT]
Nov 23 01:22:52.38: [29454]: --> HDLC<25:FF C0 C2 04 04 04 04 04 04 04 04 04 
04 04 04 04 04 04 04 04 04 04 04 89 69>
Nov 23 01:22:52.38: [29454]: --> [2:OK]
Nov 23 01:22:52.38: [29454]: RECV recv TSI (sender id)
Nov 23 01:22:52.38: [29454]: REMOTE TSI ""
Nov 23 01:22:52.38: [29454]: <-- [9:AT+FRH=3\r]
Nov 23 01:22:52.38: [29454]: --> [7:CONNECT]
Nov 23 01:22:52.81: [29454]: --> HDLC<15:FF C8 C1 00 62 1F 21 01 01 01 01 01 
04 6A 11>
Nov 23 01:22:52.81: [29454]: --> [2:OK]
Nov 23 01:22:52.81: [29454]: RECV recv DCS (command signal)
Nov 23 01:22:52.81: [29454]: REMOTE wants 9600 bit/s
Nov 23 01:22:52.81: [29454]: REMOTE wants A4 page width (215 mm)
Nov 23 01:22:52.81: [29454]: REMOTE wants unlimited page length
Nov 23 01:22:52.81: [29454]: REMOTE wants 7.7 line/mm
Nov 23 01:22:52.81: [29454]: REMOTE wants JBIG
Nov 23 01:22:52.81: [29454]: REMOTE wants T.30 Annex A, 256-byte ECM
Nov 23 01:22:52.81: [29454]: RECV training at v.29 9600 bit/s
Nov 23 01:22:52.81: [29454]: <-- [10:AT+FRM=96\r]
Nov 23 01:22:57.20: [29454]: --> [7:CONNECT]
Nov 23 01:22:58.70: [29454]: RECV: TCF 1800 bytes, 0% non-zero, 1800 zero-run
Nov 23 01:22:58.70: [29454]: --> [10:NO CARRIER]
Nov 23 01:22:58.70: [29454]: <-- [9:AT+FRS=7\r]
Nov 23 01:22:59.20: [29454]: --> [2:OK]
Nov 23 01:22:59.20: [29454]: TRAINING succeeded
Nov 23 01:22:59.20: [29454]: <-- [9:AT+FTH=3\r]
Nov 23 01:22:59.20: [29454]: --> [7:CONNECT]
Nov 23 01:22:59.20: [29454]: <-- HDLC<3:FF C8 21>
Nov 23 01:23:05.20: [29454]: --> [2:OK]
Nov 23 01:23:05.20: [29454]: <-- [10:AT+FRM=96\r]
Nov 23 01:23:06.20: [29454]: --> [7:CONNECT]
Nov 23 01:23:06.20: [29454]: MODEM input buffering enabled
Nov 23 01:23:08.77: [29454]: RECV received frame number 0
Nov 23 01:23:09.24: [29454]: RECV received frame number 1
Nov 23 01:23:09.32: [29454]: RECV received frame number 2
Nov 23 01:23:09.37: [29454]: RECV received frame number 3
Nov 23 01:23:09.78: [29454]: RECV received frame number 4
Nov 23 01:23:09.83: [29454]: RECV received frame number 5
Nov 23 01:23:10.27: [29454]: RECV received frame number 6
Nov 23 01:23:10.32: [29454]: RECV received frame number 7
Nov 23 01:23:10.74: [29454]: RECV received frame number 8
Nov 23 01:23:10.82: [29454]: RECV received frame number 9
Nov 23 01:23:11.24: [29454]: RECV received frame number 10
Nov 23 01:23:11.29: [29454]: RECV received frame number 11
Nov 23 01:23:11.37: [29454]: RECV received frame number 12
Nov 23 01:23:11.77: [29454]: RECV received frame number 13
Nov 23 01:23:11.95: [29454]: RECV received frame number 14
Nov 23 01:23:12.27: [29454]: RECV received frame number 15
Nov 23 01:23:12.32: [29454]: RECV received frame number 16
Nov 23 01:23:12.74: [29454]: RECV received frame number 17
Nov 23 01:23:12.82: [29454]: RECV received frame number 18
Nov 23 01:23:13.24: [29454]: RECV received frame number 19
Nov 23 01:23:13.29: [29454]: RECV received frame number 20
Nov 23 01:23:13.37: [29454]: RECV received frame number 21
Nov 23 01:23:13.77: [29454]: RECV received frame number 22
Nov 23 01:23:13.94: [29454]: RECV received frame number 23
Nov 23 01:23:14.27: [29454]: RECV received frame number 24
Nov 23 01:23:14.32: [29454]: RECV received frame number 25
Nov 23 01:23:14.74: [29454]: RECV received frame number 26
Nov 23 01:23:14.82: [29454]: RECV received frame number 27
Nov 23 01:23:15.24: [29454]: RECV received frame number 28
Nov 23 01:23:15.32: [29454]: RECV received frame number 29
Nov 23 01:23:15.37: [29454]: RECV received frame number 30
Nov 23 01:23:15.77: [29454]: RECV received frame number 31
Nov 23 01:23:15.94: [29454]: RECV received frame number 32
Nov 23 01:23:16.27: [29454]: RECV received frame number 33
Nov 23 01:23:16.35: [29454]: RECV received frame number 34
Nov 23 01:23:16.75: [29454]: RECV received frame number 35
Nov 23 01:23:16.83: [29454]: RECV received frame number 36
Nov 23 01:23:17.24: [29454]: RECV received frame number 37
Nov 23 01:23:17.32: [29454]: RECV received frame number 38
Nov 23 01:23:17.37: [29454]: RECV received frame number 39
Nov 23 01:23:17.77: [29454]: RECV received frame number 40
Nov 23 01:23:17.94: [29454]: RECV received frame number 41
Nov 23 01:23:18.27: [29454]: RECV received frame number 42
Nov 23 01:23:18.35: [29454]: RECV received frame number 43
Nov 23 01:23:18.75: [29454]: RECV received frame number 44
Nov 23 01:23:18.83: [29454]: RECV received frame number 45
Nov 23 01:23:19.24: [29454]: RECV received frame number 46
Nov 23 01:23:19.32: [29454]: RECV received frame number 47
Nov 23 01:23:19.37: [29454]: RECV received frame number 48
Nov 23 01:23:19.77: [29454]: RECV received frame number 49
Nov 23 01:23:19.95: [29454]: RECV received frame number 50
Nov 23 01:23:20.27: [29454]: RECV received frame number 51
Nov 23 01:23:20.35: [29454]: RECV received frame number 52
Nov 23 01:23:20.74: [29454]: RECV received frame number 53
Nov 23 01:23:20.82: [29454]: RECV received frame number 54
Nov 23 01:23:21.24: [29454]: RECV received frame number 55
Nov 23 01:23:21.32: [29454]: RECV received frame number 56
Nov 23 01:23:21.37: [29454]: RECV received frame number 57
Nov 23 01:23:21.78: [29454]: RECV received frame number 58
Nov 23 01:23:21.95: [29454]: RECV received frame number 59
Nov 23 01:23:22.27: [29454]: RECV received frame number 60
Nov 23 01:23:22.35: [29454]: RECV received frame number 61
Nov 23 01:23:22.74: [29454]: RECV received frame number 62
Nov 23 01:23:22.82: [29454]: RECV received frame number 63
Nov 23 01:23:23.24: [29454]: RECV received frame number 64
Nov 23 01:23:23.32: [29454]: RECV received frame number 65
Nov 23 01:23:23.37: [29454]: RECV received frame number 66
Nov 23 01:23:23.77: [29454]: RECV received frame number 67
Nov 23 01:23:23.95: [29454]: RECV received frame number 68
Nov 23 01:23:24.27: [29454]: RECV received frame number 69
Nov 23 01:23:24.35: [29454]: RECV received frame number 70
Nov 23 01:23:24.74: [29454]: RECV received frame number 71
Nov 23 01:23:24.82: [29454]: RECV received frame number 72
Nov 23 01:23:25.24: [29454]: RECV received frame number 73
Nov 23 01:23:25.32: [29454]: RECV received frame number 74
Nov 23 01:23:25.74: [29454]: RECV received frame number 75
Nov 23 01:23:25.79: [29454]: RECV received frame number 76
Nov 23 01:23:25.87: [29454]: RECV received frame number 77
Nov 23 01:23:26.27: [29454]: RECV received frame number 78
Nov 23 01:23:26.32: [29454]: RECV received frame number 79
Nov 23 01:23:26.74: [29454]: RECV received frame number 80
Nov 23 01:23:26.82: [29454]: RECV received frame number 81
Nov 23 01:23:27.24: [29454]: RECV received frame number 82
Nov 23 01:23:27.32: [29454]: RECV received frame number 83
Nov 23 01:23:27.74: [29454]: RECV received frame number 84
Nov 23 01:23:27.79: [29454]: RECV received frame number 85
Nov 23 01:23:27.87: [29454]: RECV received frame number 86
Nov 23 01:23:28.27: [29454]: RECV received frame number 87
Nov 23 01:23:28.32: [29454]: RECV received frame number 88
Nov 23 01:23:28.77: [29454]: RECV received frame number 89
Nov 23 01:23:28.82: [29454]: RECV received frame number 90
Nov 23 01:23:29.25: [29454]: RECV received frame number 91
Nov 23 01:23:29.33: [29454]: RECV received frame number 92
Nov 23 01:23:29.74: [29454]: RECV received frame number 93
Nov 23 01:23:29.79: [29454]: RECV received frame number 94
Nov 23 01:23:29.87: [29454]: RECV received frame number 95
Nov 23 01:23:30.27: [29454]: RECV received frame number 96
Nov 23 01:23:30.45: [29454]: RECV received frame number 97
Nov 23 01:23:30.77: [29454]: RECV received frame number 98
Nov 23 01:23:30.82: [29454]: RECV received frame number 99
Nov 23 01:23:31.25: [29454]: RECV received frame number 100
Nov 23 01:23:31.33: [29454]: RECV received frame number 101
Nov 23 01:23:31.74: [29454]: RECV received frame number 102
Nov 23 01:23:31.79: [29454]: RECV received frame number 103
Nov 23 01:23:31.87: [29454]: RECV received frame number 104
Nov 23 01:23:32.40: [29454]: RECV received frame number 105
Nov 23 01:23:32.40: [29454]: RECV received RCP frame
Nov 23 01:23:32.40: [29454]: MODEM input buffering disabled
Nov 23 01:23:32.70: [29454]: --> [10:NO CARRIER]
Nov 23 01:23:32.70: [29454]: <-- [9:AT+FRH=3\r]
Nov 23 01:23:34.20: [29454]: --> [7:CONNECT]
Nov 23 01:23:39.76: [29454]: --> [2:OK]
Nov 23 01:23:39.76: [29454]: HDLC frame too short (0 bytes)
Nov 23 01:23:39.76: [29454]: <-- [10:AT+FRS=21\r]
Nov 23 01:23:39.76: [29454]: --> [5:ERROR]
Nov 23 01:23:39.76: [29454]: MODEM Command error
Nov 23 01:23:39.76: [29454]: Failure to receive silence (synchronization 
failure). {E100}
Nov 23 01:23:39.76: [29454]: <-- [9:AT+FRH=3\r]
Nov 23 01:23:39.86: [29454]: --> [5:ERROR]
Nov 23 01:23:39.86: [29454]: MODEM Command error
Nov 23 01:23:39.86: [29454]: RECV/CQ: BIH: Dl 0, D 0, P 1, fill 0
Nov 23 01:23:39.86: [29454]: RECV/CQ: BIH: Xd 1728, Yd 2292, L0 128, Mx 0, My 
0
Nov 23 01:23:39.86: [29454]: RECV/CQ: BIH: fill 0, HITOLO 0, SEQ 0, ILEAVE 0, 
SMID 0
Nov 23 01:23:39.86: [29454]: RECV/CQ: BIH: fill 0, LRLTWO 0, VLENGTH 0, TPDON 
0, TPBON 0, DPON 0, DPPRIV 0, DPLAST 0
Nov 23 01:23:39.86: [29454]: RECV/CQ: Found 18 SDNORM Marker Segments in BID
Nov 23 01:23:39.86: [29454]: RECV: 27136 bytes of data, 2292 total lines
Nov 23 01:23:39.86: [29454]: RECV keeping unconfirmed page
Nov 23 01:23:39.89: [29454]: RECV FAX (000054319): from , page 1 in 0:01:42, 
INF, 7.7 line/mm, JBIG, 9600 bit/s
Nov 23 01:23:41.20: [29454]: RECV FAX (000054319): recvq/fax000000400.tif 
from , subaddress <unspecified>, 1 pages in 0:01:42
Nov 23 01:23:41.20: [29454]: RECV FAX: T.30 T2 timeout, expected signal not 
received {E118}
Nov 23 01:23:41.20: [29454]: MODEM input buffering enabled
Nov 23 01:23:41.20: [29454]: RECV FAX (000054319): session with  terminated 
abnormally: T.30 T2 timeout, expected signal not received {E118}
Nov 23 01:23:41.20: [29454]: RECV FAX: 
bin/faxrcvd 'recvq/fax000000400.tif' 'ttyT38-1' '000054319' 'T.30 T2 timeout, 
expected signal not received {E118}'
Nov 23 01:23:41.21: [29454]: RECV FAX: end
Nov 23 01:23:41.21: [29454]: 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*