Incoming Faxes Now Failing

Please review -> http://www.freepbx.org/forum/freepbx-distro/distro-discussion-help/fax-destinations-sip-detection

For about a week, I managed to get our PBX to answer and receive faxes flawlessly. However over the weekend, I had to move some equipment and after bringing the PBX back up, it is unable to receive faxes at all.

I will try and provide as many logs as possible… Please let me know what else I need to provide in order to fix the issue.

Log file info after break…

Asterisk - Full Log

[2012-07-16 12:29:34] NOTICE[3166]: chan_sip.c:22581 handle_request_invite: Failed to authenticate device "6025488161" <sip:[email protected]>;tag=14eHc3Hp80p5e
  == Using SIP RTP TOS bits 184
  == Using SIP RTP CoS mark 5
    -- Executing [6234280085@from-trunk-sip-broadvox:1] Set("SIP/broadvox-0000000b", "GROUP()=OUT_2") in new stack
    -- Executing [6234280085@from-trunk-sip-broadvox:2] Goto("SIP/broadvox-0000000b", "from-trunk,6234280085,1") in new stack
    -- Goto (from-trunk,6234280085,1)
    -- Executing [6234280085@from-trunk:1] Set("SIP/broadvox-0000000b", "__FROM_DID=6234280085") in new stack
    -- Executing [6234280085@from-trunk:2] Gosub("SIP/broadvox-0000000b", "app-blacklist-check,s,1()") in new stack
    -- Executing [s@app-blacklist-check:1] GotoIf("SIP/broadvox-0000000b", "0?blacklisted") in new stack
    -- Executing [s@app-blacklist-check:2] Set("SIP/broadvox-0000000b", "CALLED_BLACKLIST=1") in new stack
    -- Executing [s@app-blacklist-check:3] Return("SIP/broadvox-0000000b", "") in new stack
    -- Executing [6234280085@from-trunk:3] Set("SIP/broadvox-0000000b", "CDR(did)=6234280085") in new stack
    -- Executing [6234280085@from-trunk:4] ExecIf("SIP/broadvox-0000000b", "0 ?Set(CALLERID(name)=6025488161)") in new stack
    -- Executing [6234280085@from-trunk:5] Set("SIP/broadvox-0000000b", "__CALLINGPRES_SV=allowed_not_screened") in new stack
    -- Executing [6234280085@from-trunk:6] Set("SIP/broadvox-0000000b", "CALLERPRES()=allowed_not_screened") in new stack
    -- Executing [6234280085@from-trunk:7] Set("SIP/broadvox-0000000b", "FAX_DEST=ext-featurecodes^666^1") in new stack
    -- Executing [6234280085@from-trunk:8] Answer("SIP/broadvox-0000000b", "") in new stack
    -- Executing [6234280085@from-trunk:9] Wait("SIP/broadvox-0000000b", "4") in new stack
  == Redirecting 'SIP/broadvox-0000000b' to fax extension due to CNG detection
  == Spawn extension (from-trunk, fax, 1) exited non-zero on 'SIP/broadvox-0000000b'
    -- Executing [fax@from-trunk:1] Goto("SIP/broadvox-0000000b", "ext-featurecodes,666,1") in new stack
    -- Goto (ext-featurecodes,666,1)
    -- Executing [666@ext-featurecodes:1] Goto("SIP/broadvox-0000000b", "from-internal,666,1") in new stack
    -- Goto (from-internal,666,1)
    -- Executing [666@from-internal:1] Set("SIP/broadvox-0000000b", "[email protected]") in new stack
    -- Executing [666@from-internal:2] Goto("SIP/broadvox-0000000b", "ext-fax,s,1") in new stack
    -- Goto (ext-fax,s,1)
    -- Executing [s@ext-fax:1] Macro("SIP/broadvox-0000000b", "user-callerid,") in new stack
    -- Executing [s@macro-user-callerid:1] Set("SIP/broadvox-0000000b", "AMPUSER=6025488161") in new stack
    -- Executing [s@macro-user-callerid:2] GotoIf("SIP/broadvox-0000000b", "0?report") in new stack
    -- Executing [s@macro-user-callerid:3] ExecIf("SIP/broadvox-0000000b", "1?Set(REALCALLERIDNUM=6025488161)") in new stack
    -- Executing [s@macro-user-callerid:4] Set("SIP/broadvox-0000000b", "AMPUSER=") in new stack
    -- Executing [s@macro-user-callerid:5] Set("SIP/broadvox-0000000b", "AMPUSERCIDNAME=") in new stack
    -- Executing [s@macro-user-callerid:6] GotoIf("SIP/broadvox-0000000b", "1?report") in new stack
    -- Goto (macro-user-callerid,s,13)
    -- Executing [s@macro-user-callerid:13] GotoIf("SIP/broadvox-0000000b", "0?continue") in new stack
    -- Executing [s@macro-user-callerid:14] Set("SIP/broadvox-0000000b", "__TTL=64") in new stack
    -- Executing [s@macro-user-callerid:15] GotoIf("SIP/broadvox-0000000b", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,26)
    -- Executing [s@macro-user-callerid:26] Set("SIP/broadvox-0000000b", "CALLERID(number)=6025488161") in new stack
    -- Executing [s@macro-user-callerid:27] Set("SIP/broadvox-0000000b", "CALLERID(name)=6025488161") in new stack
    -- Executing [s@macro-user-callerid:28] Set("SIP/broadvox-0000000b", "CHANNEL(language)=en") in new stack
    -- Executing [s@ext-fax:2] NoOp("SIP/broadvox-0000000b", "Receiving Fax for: [email protected] , From: "6025488161" <6025488161>") in new stack
    -- Executing [s@ext-fax:3] StopPlayTones("SIP/broadvox-0000000b", "") in new stack
    -- Executing [s@ext-fax:4] ReceiveFAX("SIP/broadvox-0000000b", "/var/spool/asterisk/fax/1342466974.11.tif,f") in new stack
    -- Channel 'SIP/broadvox-0000000b' receiving FAX '/var/spool/asterisk/fax/1342466974.11.tif'
  == Using UDPTL TOS bits 184
  == Using UDPTL CoS mark 5
[2012-07-16 12:29:41] WARNING[5337]: res_rtp_asterisk.c:2140 ast_rtp_read: RTP Read too short
[2012-07-16 12:29:41] WARNING[5337]: res_rtp_asterisk.c:2140 ast_rtp_read: RTP Read too short
[2012-07-16 12:29:41] WARNING[5337]: res_rtp_asterisk.c:2140 ast_rtp_read: RTP Read too short
    -- Executing [s@ext-fax:5] ExecIf("SIP/broadvox-0000000b", "1?Set(FAXSTATUS="FAILED: error: Disconnected after permitted retries statusstr: Disconnected after permitted retries")") in new stack
    -- Executing [s@ext-fax:6] Hangup("SIP/broadvox-0000000b", "") in new stack
  == Spawn extension (ext-fax, s, 6) exited non-zero on 'SIP/broadvox-0000000b'
    -- Executing [h@ext-fax:1] GotoIf("SIP/broadvox-0000000b", "1?failed") in new stack
    -- Goto (ext-fax,h,103)
    -- Executing [h@ext-fax:103] NoOp("SIP/broadvox-0000000b", "FAX "FAILED: error: Disconnected after permitted retries statusstr: Disconnected after permitted retries" for: [email protected] , From: "6025488161" <6025488161>") in new stack
    -- Executing [h@ext-fax:104] Macro("SIP/broadvox-0000000b", "hangupcall,") in new stack
    -- Executing [s@macro-hangupcall:1] GotoIf("SIP/broadvox-0000000b", "1?theend") in new stack
    -- Goto (macro-hangupcall,s,3)
    -- Executing [s@macro-hangupcall:3] ExecIf("SIP/broadvox-0000000b", "0?Set(CDR(recordingfile)=)") in new stack
    -- Executing [s@macro-hangupcall:4] Hangup("SIP/broadvox-0000000b", "") in new stack
  == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/broadvox-0000000b' in macro 'hangupcall'
  == Spawn extension (ext-fax, h, 104) exited non-zero on 'SIP/broadvox-0000000b'

fax show stats

ipbx*CLI> fax show stats

FAX Statistics:
---------------

Current Sessions     : 0
Reserved Sessions    : 0
Transmit Attempts    : 0
Receive Attempts     : 3
Completed FAXes      : 0
Failed FAXes         : 3

Spandsp G.711       
Success              : 0
Switched to T.38     : 0
Call Dropped         : 0
No FAX               : 0
Negotiation Failed   : 0
Train Failure        : 0
Retries Exceeded     : 0
Protocol Error       : 0
TX Protocol Error    : 0
RX Protocol Error    : 0
File Error           : 0
Memory Error         : 0
Unknown Error        : 0

Spandsp T.38        
Success              : 0
Call Dropped         : 0
No FAX               : 0
Negotiation Failed   : 0
Train Failure        : 0
Retries Exceeded     : 3
Protocol Error       : 0
TX Protocol Error    : 0
RX Protocol Error    : 0
File Error           : 0
Memory Error         : 0
Unknown Error        : 0

Any help or direction would be greatly appreciated… My alternative is to freshly wipe the PBX, but it’s a production system, so I’d much rather work through the problem than the latter.

-Jordan W.

Hello,
i have the same exactly problem. Did you solve it?
Thank you