Help With Debugging Timeout(?) Error?

Community-based Support for users of Free Fax For Asterisk

Help With Debugging Timeout(?) Error?

Postby root52 » Sun Jul 26, 2009 6:43 pm

Hi all,

Looking for some direction here. I have no clue what most of this output means and I am having trouble why my fax is not being received. Is there any guide to troubleshooting with the fax debug option? It looks like something is timing out but not sure what is going on here. Any help is super.

NOTE : I am using the example found in the admin manual to test.

Thanks!!

Code: Select all
Fax Debug Enabled

    -- Registered SIP '4000' at 192.168.10.10 port 5060 expires 3600
    -- Saved useragent "Zoiper rev.4334" for peer 4000

    -- ast_get_srv: SRV lookup for '_sip._udp.sip.flowroute.com' mapped to host sip.flowroute.com, port 5060
    -- Executing [2000@phones:1] Goto("SIP/4000-0866e4c0", "fax-rx|receive|1") in new stack
    -- Goto (fax-rx,receive,1)
    -- Executing [receive@fax-rx:1] NoOp("SIP/4000-0866e4c0", "**** FAX RECEIVE ****") in new stack
    -- Executing [receive@fax-rx:2] Set("SIP/4000-0866e4c0", "GLOBAL(FAXCOUNT)=") in new stack
  == Setting global variable 'FAXCOUNT' to ''
    -- Executing [receive@fax-rx:3] Set("SIP/4000-0866e4c0", "FAXCOUNT=") in new stack
    -- Executing [receive@fax-rx:4] Set("SIP/4000-0866e4c0", "FAXFILE=fax--rx.tif") in new stack
    -- Executing [receive@fax-rx:5] Set("SIP/4000-0866e4c0", "GLOBAL(LASTFAXCALLERNUM)=4000") in new stack
  == Setting global variable 'LASTFAXCALLERNUM' to '4000'
    -- Executing [receive@fax-rx:6] Set("SIP/4000-0866e4c0", "GLOBAL(LASTFAXCALLERNAME)=") in new stack
  == Setting global variable 'LASTFAXCALLERNAME' to ''
    -- Executing [receive@fax-rx:7] NoOp("SIP/4000-0866e4c0", "**** SETTING FAXOPT ****") in new stack
    -- Executing [receive@fax-rx:8] Set("SIP/4000-0866e4c0", "FAXOPT(ecm)=yes") in new stack
    -- Executing [receive@fax-rx:9] Set("SIP/4000-0866e4c0", "FAXOPT(headerinfo)=MY FAXBACK RX") in new stack
    -- Executing [receive@fax-rx:10] Set("SIP/4000-0866e4c0", "FAXOPT(localstationid)=1234567890") in new stack
    -- Executing [receive@fax-rx:11] Set("SIP/4000-0866e4c0", "FAXOPT(maxrate)=14400") in new stack
    -- Executing [receive@fax-rx:12] Set("SIP/4000-0866e4c0", "FAXOPT(minrate)=2400") in new stack
    -- Executing [receive@fax-rx:13] NoOp("SIP/4000-0866e4c0", "FAXOPT(ecm) : yes") in new stack
    -- Executing [receive@fax-rx:14] NoOp("SIP/4000-0866e4c0", "FAXOPT(headerinfo) : MY FAXBACK RX") in new stack
    -- Executing [receive@fax-rx:15] NoOp("SIP/4000-0866e4c0", "FAXOPT(localstationid) : 1234567890") in new stack
    -- Executing [receive@fax-rx:16] NoOp("SIP/4000-0866e4c0", "FAXOPT(maxrate) : 14400") in new stack
    -- Executing [receive@fax-rx:17] NoOp("SIP/4000-0866e4c0", "FAXOPT(minrate) : 2400") in new stack
    -- Executing [receive@fax-rx:18] NoOp("SIP/4000-0866e4c0", "**** RECEIVING FAX : fax--rx.tif ****") in new stack
    -- Executing [receive@fax-rx:19] ReceiveFAX("SIP/4000-0866e4c0", "/home/root52/fax--rx.tif") in new stack
    -- Channel 'SIP/4000-0866e4c0' is receiving fax '/home/root52/fax--rx.tif'
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 000.004675 ], STAT_EVT_STRT_RX       st: IDLE         rt: IDLENSRX
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 000.005338 ], STAT_EVT_RX_HW_RDY     st: WT_RX_HW_RDY rt: RRDYNHRY
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 000.005731 ], STAT_INFO_CSI
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 000.006226 ], STAT_INFO_DIS
    -- Channel 'SIP/4000-0866e4c0' fax session '2' started
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 000.205053 ],  <<<<<< (to chan)   completed sending '   0' frames (     0 ms) of 'energy ', now sending 'energy '.
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 005.252096 ], STAT_EVT_TX_V21_DONE   st: WT_DIS_RSP   rt: WDSRNT21
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 008.504370 ], STAT_EVT_T4_EXP        st: WT_DIS_RSP   rt: XXXXNT4X
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 008.504886 ], STAT_EVT_FSC_ERR       st: WT_DIS_RSP   rt: RXXXNFRX
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 008.505215 ], STAT_INFO_CSI
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 008.505659 ], STAT_INFO_DIS
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 010.670986 ], STAT_EVT_TX_V21_DONE   st: WT_DIS_RSP   rt: WDSRNT21
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 014.004064 ], STAT_EVT_T4_EXP        st: WT_DIS_RSP   rt: XXXXNT4X
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 014.004569 ], STAT_EVT_FSC_ERR       st: WT_DIS_RSP   rt: RXXXNFRX
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 014.004931 ], STAT_INFO_CSI
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 014.005322 ], STAT_INFO_DIS
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 016.172739 ], STAT_EVT_TX_V21_DONE   st: WT_DIS_RSP   rt: WDSRNT21
    -- ast_get_srv: SRV lookup for '_sip._udp.sip.flowroute.com' mapped to host sip.flowroute.com, port 5060
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 019.503978 ], STAT_EVT_T4_EXP        st: WT_DIS_RSP   rt: XXXXNT4X
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 019.504700 ], STAT_EVT_FSC_ERR       st: WT_DIS_RSP   rt: RXXXNFRX
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 019.505412 ], STAT_INFO_CSI
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 019.506584 ], STAT_INFO_DIS
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 021.671048 ], STAT_EVT_TX_V21_DONE   st: WT_DIS_RSP   rt: WDSRNT21
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 025.003983 ], STAT_EVT_T4_EXP        st: WT_DIS_RSP   rt: XXXXNT4X
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 025.004647 ], STAT_EVT_FSC_ERR       st: WT_DIS_RSP   rt: RXXXNFRX
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 025.005397 ], STAT_INFO_CSI
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 025.005930 ], STAT_INFO_DIS
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 027.172174 ], STAT_EVT_TX_V21_DONE   st: WT_DIS_RSP   rt: WDSRNT21
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 030.503988 ], STAT_EVT_T4_EXP        st: WT_DIS_RSP   rt: XXXXNT4X
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 030.504478 ], STAT_EVT_FSC_ERR       st: WT_DIS_RSP   rt: RXXXNFRX
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 030.504836 ], STAT_INFO_CSI
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 030.505257 ], STAT_INFO_DIS
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 032.671541 ], STAT_EVT_TX_V21_DONE   st: WT_DIS_RSP   rt: WDSRNT21
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 036.004502 ], STAT_EVT_T4_EXP        st: WT_DIS_RSP   rt: XXXXNT4X
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 036.005191 ], STAT_EVT_FSC_ERR       st: WT_DIS_RSP   rt: RXXXNFRX
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 036.005919 ], STAT_INFO_CSI
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 036.006579 ], STAT_INFO_DIS
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 038.172218 ], STAT_EVT_TX_V21_DONE   st: WT_DIS_RSP   rt: WDSRNT21
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 040.004128 ], STAT_EVT_T1_EXP        st: WT_DIS_RSP   rt: WDSRNT1X
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 040.004663 ], STAT_FRM_DCN
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 041.197801 ], STAT_EVT_TX_V21_DONE   st: SEND_DCN     rt: XDCNNT21
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 043.698038 ], STAT_EVT_HW_CLOSE      st: WT_HW_CLS    rt: WCLSNCLS
    -- Channel 'SIP/4000-0866e4c0' fax session '2', [ 043.698738 ], STAT_SES_COMPLETE
    -- Channel 'SIP/4000-0866e4c0' fax session '2' is complete, result: 'FAILED' (FAX_NO_FAX), error: 'T1_TIMEOUT', pages: 0, resolution: 'unknown', transfer rate: '2400', remoteSID: ''
  == Auto fallthrough, channel 'SIP/4000-0866e4c0' status is 'UNKNOWN'
    -- Executing [h@fax-rx:1] NoOp("SIP/4000-0866e4c0", "FAXOPT(ecm) : no") in new stack
    -- Executing [h@fax-rx:2] NoOp("SIP/4000-0866e4c0", "FAXOPT(filename) : /home/root52/fax--rx.tif") in new stack
    -- Executing [h@fax-rx:3] NoOp("SIP/4000-0866e4c0", "FAXOPT(headerinfo) : MY FAXBACK RX") in new stack
    -- Executing [h@fax-rx:4] NoOp("SIP/4000-0866e4c0", "FAXOPT(localstationid) : 1234567890") in new stack
    -- Executing [h@fax-rx:5] NoOp("SIP/4000-0866e4c0", "FAXOPT(maxrate) : 14400") in new stack
    -- Executing [h@fax-rx:6] NoOp("SIP/4000-0866e4c0", "FAXOPT(minrate) : 2400") in new stack
    -- Executing [h@fax-rx:7] NoOp("SIP/4000-0866e4c0", "FAXOPT(pages) : 0") in new stack
    -- Executing [h@fax-rx:8] NoOp("SIP/4000-0866e4c0", "FAXOPT(rate) : 2400") in new stack
    -- Executing [h@fax-rx:9] NoOp("SIP/4000-0866e4c0", "FAXOPT(remotestationid) : ") in new stack
    -- Executing [h@fax-rx:10] NoOp("SIP/4000-0866e4c0", "FAXOPT(resolution) : unknown") in new stack
    -- Executing [h@fax-rx:11] NoOp("SIP/4000-0866e4c0", "FAXOPT(status) : FAILED") in new stack
    -- Executing [h@fax-rx:12] NoOp("SIP/4000-0866e4c0", "FAXOPT(statusstr) : FAX_NO_FAX") in new stack
    -- Executing [h@fax-rx:13] NoOp("SIP/4000-0866e4c0", "FAXOPT(error) : T1_TIMEOUT") in new stack
fax set debug off
think*CLI>

Fax Debug Disabled
root52
Oldsterisk
 
Posts: 144
Joined: Sat Feb 09, 2008 6:40 pm
Location: Lakewood, Ohio

Postby root52 » Mon Jul 27, 2009 3:07 pm

Update!

It should be noted that the above problem was with the fax function in the Zoiper soft phone. I got to work this morning and got a hold of a proper fax machine and all worked flawlessly.

That said any thought on why zoiper would crap out like that. I will keep plugging away as I don't want to be tied to a fax / pots line while developing at the local coffee shop. ;-)
root52
Oldsterisk
 
Posts: 144
Joined: Sat Feb 09, 2008 6:40 pm
Location: Lakewood, Ohio


Return to Fax For Asterisk

Who is online

Users browsing this forum: No registered users and 1 guest