fax receiving, protocol problem

Community-based Support for users of Free Fax For Asterisk

fax receiving, protocol problem

Postby goraht » Mon Nov 25, 2013 12:56 pm

Hello. I have a problem with fax receiving. Every time when inbound fax is being transmitted i get 3RD_T2_TIMEOUT or T2_TIMEOUT. Interestingly, the errors occur when I have already got P30EVN_PAGE_END, P30EVN_DOC_END, STAT_SES_COMPLETE and P30EVN_COMPLETE - after 60s of transmission. Does anyone have a clue what could be wrong?

PBX is running on Asterisk 11.6.0 and Digium FAX Driver: 11.0_1.3.1

Code: Select all
-- Executing [XXXXXXX@pstn-in:1] Goto("DAHDI/i4/ZXXXXXXXX-aee", "fax-rx,receive,1") in new stack
    -- Goto (fax-rx,receive,1)
    -- Executing [receive@fax-rx:1] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "**** FAX RECEIVE ****") in new stack
    -- Executing [receive@fax-rx:2] Set("DAHDI/i4/ZXXXXXXXX-aee", "FAXFILE=faxrx-ZXXXXXXXX_XXXXXXX_20131125_165456.tif") in new stack
    -- Executing [receive@fax-rx:3] Set("DAHDI/i4/ZXXXXXXXX-aee", "GLOBAL(LASTFAXCALLERNUM)=ZXXXXXXXX") in new stack
  == Setting global variable 'LASTFAXCALLERNUM' to 'ZXXXXXXXX'
    -- Executing [receive@fax-rx:4] Set("DAHDI/i4/ZXXXXXXXX-aee", "GLOBAL(LASTFAXCALLERNAME)=") in new stack
  == Setting global variable 'LASTFAXCALLERNAME' to ''
    -- Executing [receive@fax-rx:5] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "**** SETTING FAXOPT ****") in new stack
    -- Executing [receive@fax-rx:6] Set("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(ecm)=yes") in new stack
    -- Executing [receive@fax-rx:7] Set("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(headerinfo)=MY FAXBACK RX") in new stack
    -- Executing [receive@fax-rx:8] Set("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(localstationid)=713747390") in new stack
    -- Executing [receive@fax-rx:9] Set("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(maxrate)=9600") in new stack
    -- Executing [receive@fax-rx:10] Set("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(minrate)=2400") in new stack
    -- Executing [receive@fax-rx:11] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(ecm) : yes") in new stack
    -- Executing [receive@fax-rx:12] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(headerinfo) : MY FAXBACK RX") in new stack
    -- Executing [receive@fax-rx:13] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(localstationid) : 713747390") in new stack
    -- Executing [receive@fax-rx:14] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(maxrate) : 9600") in new stack
    -- Executing [receive@fax-rx:15] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(minrate) : 2400") in new stack
    -- Executing [receive@fax-rx:16] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "**** RECEIVING FAX : faxrx-ZXXXXXXXX_XXXXXXX_20131125_165456.tif ****") in new stack
    -- Executing [receive@fax-rx:17] ReceiveFAX("DAHDI/i4/ZXXXXXXXX-aee", "/var/spool/asterisk/faxes/inbound/faxrx-ZXXXXXXXX_XXXXXXX_20131125_165456.tif") in new stack
    -- Channel 'DAHDI/i4/ZXXXXXXXX-aee' receiving FAX '/var/spool/asterisk/faxes/inbound/faxrx-ZXXXXXXXX_XXXXXXX_20131125_165456.tif'
    -- Channel 'DAHDI/i4/ZXXXXXXXX-aee' FAX session '80' started
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 000.000721 ], channel sent 3 frames (60 ms) of energy.
    -- FAX handle 0: [ 000.000094 ], STAT_EVT_STRT_RX       st: IDLE         rt: IDLENSRX
    -- FAX handle 0: [ 000.000191 ], STAT_EVT_RX_HW_RDY     st: WT_RX_HW_RDY rt: RRDYNHRY
    -- FAX handle 0: [ 000.000218 ], P30EVN_RECEIVE_STARTED
    -- FAX handle 0: [ 000.000228 ], STAT_INFO_CSI
    -- FAX handle 0: [ 000.000243 ], STAT_INFO_DIS
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 000.099025 ], stack sent 5 frames (100 ms) of energy.
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 000.159119 ], stack sent 3 frames (60 ms) of silence.
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 003.157218 ], stack sent 150 frames (3000 ms) of energy.
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 003.219149 ], stack sent 3 frames (60 ms) of silence.
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 003.258974 ], channel sent 163 frames (3260 ms) of silence.
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 003.299039 ], channel sent 2 frames (40 ms) of energy.
    -- FAX handle 0: [ 005.238358 ], STAT_EVT_TX_V21_DONE   st: WT_DIS_RSP   rt: WDSRNT21
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 005.578391 ], stack sent 118 frames (2360 ms) of energy.
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 006.098987 ], channel sent 140 frames (2800 ms) of silence.
    -- FAX handle 0: [ 008.018560 ], STAT_INFO_TSI
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 008.379050 ], channel sent 114 frames (2280 ms) of energy.
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 008.439062 ], channel sent 3 frames (60 ms) of silence.
    -- FAX handle 0: [ 008.458464 ], STAT_INFO_DCS
    -- FAX handle 0: [ 008.458488 ], STAT_EVT_DCS           st: WT_DIS_RSP   rt: WDSRNDCS
    -- FAX handle 0: [ 008.458498 ], STAT_NEG_V29_9600
    -- FAX handle 0: [ 008.458504 ], STAT_NEG_MMR
    -- FAX handle 0: [ 008.458510 ], STAT_NEG_A4
    -- FAX handle 0: [ 008.458515 ], STAT_NEG_RES_204x196
    -- FAX handle 0: [ 008.458520 ], STAT_NEG_ECM
    -- FAX handle 0: [ 008.458526 ], STAT_EVT_SW_ECM        st: WT_DIS_RSP   rt: WDSRNSWE
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 010.179083 ], channel sent 87 frames (1740 ms) of energy.
    -- FAX handle 0: [ 011.671546 ], STAT_EVT_RX_TRN_END    st: RCV_ECM_TRN  rt: RTCFNERT
    -- FAX handle 0: [ 011.671570 ], STAT_FRM_CFR
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 011.811183 ], stack sent 312 frames (6240 ms) of silence.
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 012.619153 ], channel sent 122 frames (2440 ms) of silence.
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 012.639131 ], channel sent 1 frames (20 ms) of energy.
    -- FAX handle 0: [ 012.858565 ], STAT_EVT_TX_V21_DONE   st: RCV_ECM_STRT rt: RECMNT21
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 013.198250 ], stack sent 69 frames (1380 ms) of energy.
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 013.799076 ], channel sent 58 frames (1160 ms) of silence.
    -- FAX handle 0: [ 013.956743 ], STAT_EVT_RX_IMG_STRT   st: RCV_ECM_STRT rt: RECMNSRI
    -- FAX handle 0: [ 013.956764 ], P30EVN_PHASE_C
    -- FAX handle 0: [ 013.956772 ], P30EVN_DOC_START
    -- FAX handle 0: [ 013.957276 ], P30EVN_PAGE_START
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 023.099316 ], channel sent 465 frames (9300 ms) of energy.
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 023.139319 ], channel sent 2 frames (40 ms) of silence.
    -- FAX handle 0: [ 023.199184 ], STAT_EVT_RX_IMG_END    st: RCV_ECM      rt: RECMNERI
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 024.559363 ], channel sent 71 frames (1420 ms) of energy.
    -- FAX handle 0: [ 024.638788 ], STAT_INFO_PPS_EOP
    -- FAX handle 0: [ 024.639046 ], STAT_EVT_PPS_EOP       st: F_END_ECM    rt: FEEMNP_P
    -- FAX handle 0: [ 024.639060 ], STAT_EVT_ECM_ERR       st: F_END_ECM    rt: XXXXNEBE
    -- FAX handle 0: [ 024.639070 ], STAT_INFO_PPR
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 024.799439 ], stack sent 580 frames (11600 ms) of silence.
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 024.839340 ], channel sent 14 frames (280 ms) of silence.
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 024.859362 ], channel sent 1 frames (20 ms) of energy.
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 026.439426 ], channel sent 79 frames (1580 ms) of silence.
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 026.459386 ], channel sent 1 frames (20 ms) of energy.
    -- FAX handle 0: [ 026.854895 ], STAT_EVT_TX_V21_DONE   st: RCV_ECM_STRT rt: RECMNT21
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 027.099402 ], channel sent 32 frames (640 ms) of silence.
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 027.119425 ], channel sent 1 frames (20 ms) of energy.
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 027.177559 ], stack sent 119 frames (2380 ms) of energy.
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 027.779409 ], channel sent 33 frames (660 ms) of silence.
    -- FAX handle 0: [ 027.936005 ], STAT_EVT_RX_IMG_STRT   st: RCV_ECM_STRT rt: RECMNSRI
    -- FAX handle 0: [ 027.936034 ], P30EVN_PHASE_C
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 037.059604 ], channel sent 464 frames (9280 ms) of energy.
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 037.119547 ], channel sent 3 frames (60 ms) of silence.
    -- FAX handle 0: [ 037.179611 ], STAT_EVT_RX_IMG_END    st: RCV_ECM      rt: RECMNERI
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 038.519551 ], channel sent 70 frames (1400 ms) of energy.
    -- FAX handle 0: [ 038.599027 ], STAT_INFO_PPS_EOP
    -- FAX handle 0: [ 038.599511 ], STAT_EVT_PPS_EOP       st: F_END_ECM    rt: FEEMNP_P
    -- FAX handle 0: [ 038.599584 ], STAT_EVT_ECM_ERR       st: F_END_ECM    rt: XXXXNEBE
    -- FAX handle 0: [ 038.599597 ], STAT_INFO_PPR
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 038.750821 ], stack sent 579 frames (11580 ms) of silence.
    -- FAX handle 0: [ 040.815144 ], STAT_EVT_TX_V21_DONE   st: RCV_ECM_STRT rt: RECMNT21
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 040.919605 ], channel sent 120 frames (2400 ms) of silence.
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 040.939586 ], channel sent 1 frames (20 ms) of energy.
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 041.137841 ], stack sent 119 frames (2380 ms) of energy.
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 041.739721 ], channel sent 40 frames (800 ms) of silence.
    -- FAX handle 0: [ 041.895318 ], STAT_EVT_RX_IMG_STRT   st: RCV_ECM_STRT rt: RECMNSRI
    -- FAX handle 0: [ 041.895341 ], P30EVN_PHASE_C
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 051.019770 ], channel sent 464 frames (9280 ms) of energy.
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 051.079836 ], channel sent 3 frames (60 ms) of silence.
    -- FAX handle 0: [ 051.139334 ], STAT_EVT_RX_IMG_END    st: RCV_ECM      rt: RECMNERI
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 052.479923 ], channel sent 70 frames (1400 ms) of energy.
    -- FAX handle 0: [ 052.559306 ], STAT_INFO_PPS_EOP
    -- FAX handle 0: [ 052.559327 ], STAT_EVT_PPS_EOP       st: F_END_ECM    rt: FEEMNP_P
    -- FAX handle 0: [ 052.559337 ], STAT_EVT_ECM_ERR       st: F_END_ECM    rt: XXXXNEBE
    -- FAX handle 0: [ 052.559347 ], STAT_INFO_PPR
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 052.720049 ], stack sent 579 frames (11580 ms) of silence.
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 054.099889 ], channel sent 81 frames (1620 ms) of silence.
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 054.119933 ], channel sent 1 frames (20 ms) of energy.
    -- FAX handle 0: [ 054.774505 ], STAT_EVT_TX_V21_DONE   st: RCV_ECM_STRT rt: RECMNT21
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 055.097148 ], stack sent 119 frames (2380 ms) of energy.
       > Channel 'DAHDI/i4/ZXXXXXXXX-aee' fax session '80', [ 055.700005 ], channel sent 79 frames (1580 ms) of silence.
    -- FAX handle 0: [ 061.499399 ], STAT_EVT_T2_EXP        st: RCV_ECM_STRT rt: RESTNT2X
    -- FAX handle 0: [ 061.499427 ], STAT_EVT_GO_TO_B       st: RCV_ECM_STRT rt: XXXXNGOB
    -- FAX handle 0: [ 063.999938 ], STAT_EVT_HW_CLOSE      st: WT_HW_CLS    rt: WCLSNCLS
    -- FAX handle 0: [ 064.000621 ], P30EVN_PAGE_END
    -- FAX handle 0: [ 064.000641 ], P30EVN_DOC_END
    -- FAX handle 0: [ 064.000648 ], STAT_SES_COMPLETE
    -- FAX handle 0: [ 064.000664 ], P30EVN_COMPLETE
    -- Channel 'DAHDI/i4/ZXXXXXXXX-aee' FAX session '80' is complete, result: 'FAILED' (FAX_FAILURE_PROTOCOL_ERROR), error: '3RD_T2_TIMEOUT', pages: 0, resolution: '204x196', transfer rate: '9600', remoteSID: '0048ZXXXXXXXX'
    -- Executing [h@fax-rx:1] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(ecm) : yes") in new stack
    -- Executing [h@fax-rx:2] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(filename) : /var/spool/asterisk/faxes/inbound/faxrx-ZXXXXXXXX_XXXXXXX_20131125_165456.tif") in new stack
    -- Executing [h@fax-rx:3] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(headerinfo) : MY FAXBACK RX") in new stack
    -- Executing [h@fax-rx:4] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(localstationid) : 713747390") in new stack
    -- Executing [h@fax-rx:5] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(maxrate) : 9600") in new stack
    -- Executing [h@fax-rx:6] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(minrate) : 2400") in new stack
    -- Executing [h@fax-rx:7] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(pages) : 0") in new stack
    -- Executing [h@fax-rx:8] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(rate) : 9600") in new stack
    -- Executing [h@fax-rx:9] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(remotestationid) : 0048ZXXXXXXXX") in new stack
    -- Executing [h@fax-rx:10] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(resolution) : 204x196") in new stack
    -- Executing [h@fax-rx:11] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(status) : FAILED") in new stack
    -- Executing [h@fax-rx:12] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(statusstr) : FAX_FAILURE_PROTOCOL_ERROR") in new stack
    -- Executing [h@fax-rx:13] NoOp("DAHDI/i4/ZXXXXXXXX-aee", "FAXOPT(error) : 3RD_T2_TIMEOUT") in new stack
    -- Executing [h@fax-rx:14] System("DAHDI/i4/ZXXXXXXXX-aee", "/var/lib/asterisk/agi-bin/fax2email.pl /var/spool/asterisk/faxes/inbound/faxrx-ZXXXXXXXX_XXXXXXX_20131125_165456.tif XXXXXXX ZXXXXXXXX") in new stack
goraht
Newsterisk
 
Posts: 5
Joined: Tue Oct 02, 2012 8:41 am

Return to Fax For Asterisk

Who is online

Users browsing this forum: No registered users and 3 guests