Cannot receive fax "chan_sip.c:8451 sip_read: FAX CNG detected but no fax extension"

Hi,

I am new to asterisk, so sorry if I ask a dummy question, I wasn’t able to find a suitable answer in a the forum so I open a new thread.

This is my environment:

Debian 7.3 64 bits
FreePBX 2.11.0
Asterisk 11.5.1
Free Fax For Asterisk (res_fax_digium v. 11.0_1.3.1)

The Fax software is properly licensed:

CLI> fax show stats

FAX Statistics:

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

Digium G.711
Licensed Channels : 1
Max Concurrent : 0
Success : 0
Switched to T.38 : 0
Canceled : 0
No FAX : 0
Partial : 0
Negotiation Failed : 0
Train Failure : 0
Protocol Error : 0
IO Partial : 0
IO Fail : 0

Digium T.38
Licensed Channels : 1
Max Concurrent : 0
Success : 0
Canceled : 0
No FAX : 0
Partial : 0
Negotiation Failed : 0
Train Failure : 0
Protocol Error : 0
IO Partial : 0
IO Fail : 0

In FreePBX, I have an inbound route that has the “Detect Faxes?:” option set to YES
Fax Detection type?: SIP
Fax Detection Time?: 4
Fax Destination?: Fax recipient --> an extension (313)

This extension has then thw fax option enabled
Fax Email? some email
Attachment Format? pdf

I’ve been trying to send a fax to my inbound route from http://www.hellofax.com/ web page, I get my phone ringing but the message isn’t forwarded to my email address.

In the console from asterisk, I can see the following output:

CLI>
== Using SIP VIDEO TOS bits 136
== Using SIP VIDEO CoS mark 6
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Executing [0275520229@from-trunk-custom:1] Set(“SIP/90528626916-0000011b”, “CALLERID(num)=00016132493231”) in new stack
– Executing [0275520229@from-trunk-custom:2] Goto(“SIP/90528626916-0000011b”, “from-trunk,0275520229,1”) in new stack
– Goto (from-trunk,0275520229,1)
– Executing [0275520229@from-trunk:1] Set(“SIP/90528626916-0000011b”, “__FROM_DID=0275520229”) in new stack
– Executing [0275520229@from-trunk:2] Gosub(“SIP/90528626916-0000011b”, “app-blacklist-check,s,1()”) in new stack
– Executing [s@app-blacklist-check:1] GotoIf(“SIP/90528626916-0000011b”, “0?blacklisted”) in new stack
– Executing [s@app-blacklist-check:2] Set(“SIP/90528626916-0000011b”, “CALLED_BLACKLIST=1”) in new stack
– Executing [s@app-blacklist-check:3] Return(“SIP/90528626916-0000011b”, “”) in new stack
– Executing [0275520229@from-trunk:3] Set(“SIP/90528626916-0000011b”, “CDR(did)=0275520229”) in new stack
– Executing [0275520229@from-trunk:4] ExecIf(“SIP/90528626916-0000011b”, “0 ?Set(CALLERID(name)=00016132493231)”) in new stack
– Executing [0275520229@from-trunk:5] Set(“SIP/90528626916-0000011b”, “__CALLINGPRES_SV=allowed_not_screened”) in new stack
– Executing [0275520229@from-trunk:6] Set(“SIP/90528626916-0000011b”, “CALLERPRES()=allowed_not_screened”) in new stack
– Executing [0275520229@from-trunk:7] Set(“SIP/90528626916-0000011b”, “FAX_DEST=ext-fax^313^1”) in new stack
– Executing [0275520229@from-trunk:8] Answer(“SIP/90528626916-0000011b”, “”) in new stack
– Executing [0275520229@from-trunk:9] Wait(“SIP/90528626916-0000011b”, “4”) in new stack
– Executing [0275520229@from-trunk:10] Goto(“SIP/90528626916-0000011b”, “timeconditions,7,1”) in new stack
– Goto (timeconditions,7,1)
– Executing [7@timeconditions:1] GotoIfTime(“SIP/90528626916-0000011b”, “08:00-12:00,mon-fri,,?truestate”) in new stack
– Goto (timeconditions,7,8)
– Executing [7@timeconditions:8] GotoIf(“SIP/90528626916-0000011b”, “0?falsegoto”) in new stack
– Executing [7@timeconditions:9] ExecIf(“SIP/90528626916-0000011b”, “0?Set(DB(TC/7)=)”) in new stack
– Executing [7@timeconditions:10] GotoIf(“SIP/90528626916-0000011b”, “1?ext-group,113,1”) in new stack
– Goto (ext-group,113,1)
– Executing [113@ext-group:1] Macro(“SIP/90528626916-0000011b”, “user-callerid,”) in new stack
– Executing [s@macro-user-callerid:1] Set(“SIP/90528626916-0000011b”, “TOUCH_MONITOR=1391165990.307”) in new stack
– Executing [s@macro-user-callerid:2] Set(“SIP/90528626916-0000011b”, “AMPUSER=00016132493231”) in new stack
– Executing [s@macro-user-callerid:3] GotoIf(“SIP/90528626916-0000011b”, “0?report”) in new stack
– Executing [s@macro-user-callerid:4] ExecIf(“SIP/90528626916-0000011b”, “1?Set(REALCALLERIDNUM=00016132493231)”) in new stack
– Executing [s@macro-user-callerid:5] Set(“SIP/90528626916-0000011b”, “AMPUSER=”) in new stack
– Executing [s@macro-user-callerid:6] Set(“SIP/90528626916-0000011b”, “AMPUSERCIDNAME=”) in new stack
– Executing [s@macro-user-callerid:7] GotoIf(“SIP/90528626916-0000011b”, “1?report”) in new stack
– Goto (macro-user-callerid,s,15)
– Executing [s@macro-user-callerid:15] GotoIf(“SIP/90528626916-0000011b”, “0?continue”) in new stack
– Executing [s@macro-user-callerid:16] ExecIf(“SIP/90528626916-0000011b”, “1?Set(__CALLEE_ACCOUNCODE=)”) in new stack
– Executing [s@macro-user-callerid:17] Set(“SIP/90528626916-0000011b”, “__TTL=64”) in new stack
– Executing [s@macro-user-callerid:18] GotoIf(“SIP/90528626916-0000011b”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,29)
– Executing [s@macro-user-callerid:29] Set(“SIP/90528626916-0000011b”, “CALLERID(number)=00016132493231”) in new stack
– Executing [s@macro-user-callerid:30] Set(“SIP/90528626916-0000011b”, “CALLERID(name)=0016132493231”) in new stack
– Executing [s@macro-user-callerid:31] Set(“SIP/90528626916-0000011b”, “CDR(cnum)=00016132493231”) in new stack
– Executing [s@macro-user-callerid:32] Set(“SIP/90528626916-0000011b”, “CDR(cnam)=0016132493231”) in new stack
– Executing [s@macro-user-callerid:33] Set(“SIP/90528626916-0000011b”, “CHANNEL(language)=de”) in new stack
– Executing [113@ext-group:2] Macro(“SIP/90528626916-0000011b”, “blkvm-setifempty,”) in new stack
– Executing [s@macro-blkvm-setifempty:1] GotoIf(“SIP/90528626916-0000011b”, “1?init”) in new stack
– Goto (macro-blkvm-setifempty,s,4)
– Executing [s@macro-blkvm-setifempty:4] Set(“SIP/90528626916-0000011b”, “__BLKVM_CHANNEL=SIP/90528626916-0000011b”) in new stack
– Executing [s@macro-blkvm-setifempty:5] Set(“SIP/90528626916-0000011b”, “SHARED(BLKVM,SIP/90528626916-0000011b)=TRUE”) in new stack
– Executing [s@macro-blkvm-setifempty:6] Set(“SIP/90528626916-0000011b”, “GOSUB_RETVAL=TRUE”) in new stack
– Executing [s@macro-blkvm-setifempty:7] MacroExit(“SIP/90528626916-0000011b”, “”) in new stack
– Executing [113@ext-group:3] GotoIf(“SIP/90528626916-0000011b”, “1?skipov”) in new stack
– Goto (ext-group,113,6)
– Executing [113@ext-group:6] Set(“SIP/90528626916-0000011b”, “RRNODEST=”) in new stack
– Executing [113@ext-group:7] Set(“SIP/90528626916-0000011b”, “__NODEST=113”) in new stack
– Executing [113@ext-group:8] GosubIf(“SIP/90528626916-0000011b”, “0?sub-rgsetcid,s,1()”) in new stack
– Executing [113@ext-group:9] Gosub(“SIP/90528626916-0000011b”, “sub-record-check,s,1(rg,113,dontcare)”) in new stack
– Executing [s@sub-record-check:1] Set(“SIP/90528626916-0000011b”, “REC_POLICY_MODE_SAVE=”) in new stack
– Executing [s@sub-record-check:2] GotoIf(“SIP/90528626916-0000011b”, “1?check”) in new stack
– Goto (sub-record-check,s,7)
– Executing [s@sub-record-check:7] Set(“SIP/90528626916-0000011b”, “__MON_FMT=wav”) in new stack
– Executing [s@sub-record-check:8] GotoIf(“SIP/90528626916-0000011b”, “1?next”) in new stack
– Goto (sub-record-check,s,11)
– Executing [s@sub-record-check:11] ExecIf(“SIP/90528626916-0000011b”, “0?Return()”) in new stack
– Executing [s@sub-record-check:12] ExecIf(“SIP/90528626916-0000011b”, “1?Set(__REC_POLICY_MODE=dontcare)”) in new stack
– Executing [s@sub-record-check:13] GotoIf(“SIP/90528626916-0000011b”, “0?rg,1”) in new stack
– Executing [s@sub-record-check:14] Set(“SIP/90528626916-0000011b”, “__REC_STATUS=INITIALIZED”) in new stack
– Executing [s@sub-record-check:15] Set(“SIP/90528626916-0000011b”, “NOW=1391165995”) in new stack
– Executing [s@sub-record-check:16] Set(“SIP/90528626916-0000011b”, “__DAY=31”) in new stack
– Executing [s@sub-record-check:17] Set(“SIP/90528626916-0000011b”, “__MONTH=01”) in new stack
– Executing [s@sub-record-check:18] Set(“SIP/90528626916-0000011b”, “__YEAR=2014”) in new stack
– Executing [s@sub-record-check:19] Set(“SIP/90528626916-0000011b”, “__TIMESTR=20140131-115955”) in new stack
– Executing [s@sub-record-check:20] Set(“SIP/90528626916-0000011b”, “__FROMEXTEN=00016132493231”) in new stack
– Executing [s@sub-record-check:21] Set(“SIP/90528626916-0000011b”, “__CALLFILENAME=rg-113-00016132493231-20140131-115955-1391165990.307”) in new stack
– Executing [s@sub-record-check:22] Goto(“SIP/90528626916-0000011b”, “rg,1”) in new stack
– Goto (sub-record-check,rg,1)
– Executing [rg@sub-record-check:1] GosubIf(“SIP/90528626916-0000011b”, “0?record,1(rg,dontcare,00016132493231)”) in new stack
– Executing [rg@sub-record-check:2] Return(“SIP/90528626916-0000011b”, “”) in new stack
– Executing [113@ext-group:10] Set(“SIP/90528626916-0000011b”, “RingGroupMethod=ringall”) in new stack
– Executing [113@ext-group:11] Macro(“SIP/90528626916-0000011b”, “dial,20,Ttr,213-313”) in new stack
– Executing [s@macro-dial:1] GotoIf(“SIP/90528626916-0000011b”, “1?dial”) in new stack
– Goto (macro-dial,s,3)
– Executing [s@macro-dial:3] AGI(“SIP/90528626916-0000011b”, “dialparties.agi”) in new stack
– Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
dialparties.agi: Starting New Dialparties.agi
dialparties.agi: Caller ID name is ‘0016132493231’ number is '00016132493231’
dialparties.agi: Methodology of ring is ‘ringall’
– dialparties.agi: Added extension 213 to extension map
– dialparties.agi: Added extension 313 to extension map
– dialparties.agi: Extension 213 cf is disabled
– dialparties.agi: Extension 313 cf is disabled
– dialparties.agi: Extension 213 do not disturb is disabled
– dialparties.agi: Extension 313 do not disturb is disabled
– dialparties.agi: dbset CALLTRACE/213 to 00016132493231
– dialparties.agi: dbset CALLTRACE/313 to 00016132493231
– dialparties.agi: Filtered ARG3: 213-313
– <SIP/90528626916-0000011b>AGI Script dialparties.agi completed, returning 0
– Executing [s@macro-dial:7] Dial(“SIP/90528626916-0000011b”, “SIP/213&SIP/313,20,TtrM(auto-blkvm)”) in new stack
== Using SIP VIDEO TOS bits 136
== Using SIP VIDEO CoS mark 6
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
== Using SIP VIDEO TOS bits 136
== Using SIP VIDEO CoS mark 6
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Called SIP/213
– Called SIP/313
– SIP/313-0000011d connected line has changed. Saving it until answer for SIP/90528626916-0000011b
– SIP/213-0000011c connected line has changed. Saving it until answer for SIP/90528626916-0000011b
– SIP/313-0000011d is ringing
– SIP/213-0000011c is ringing
[2014-01-31 12:00:00] NOTICE[22103][C-0000008f]: chan_sip.c:8451 sip_read: FAX CNG detected but no fax extension
– Nobody picked up in 20000 ms
– Executing [s@macro-dial:8] Set(“SIP/90528626916-0000011b”, “DIALSTATUS=NOANSWER”) in new stack
– Executing [s@macro-dial:9] GosubIf(“SIP/90528626916-0000011b”, “0?NOANSWER,1”) in new stack
– Executing [113@ext-group:12] Gosub(“SIP/90528626916-0000011b”, “sub-record-cancel,s,1()”) in new stack
– Executing [s@sub-record-cancel:1] Set(“SIP/90528626916-0000011b”, “__REC_POLICY_MODE=”) in new stack
– Executing [s@sub-record-cancel:2] ExecIf(“SIP/90528626916-0000011b”, “1?Return()”) in new stack
– Executing [113@ext-group:13] Set(“SIP/90528626916-0000011b”, “RingGroupMethod=”) in new stack
– Executing [113@ext-group:14] GotoIf(“SIP/90528626916-0000011b”, “0?nodest”) in new stack
– Executing [113@ext-group:15] Set(“SIP/90528626916-0000011b”, “__NODEST=”) in new stack
– Executing [113@ext-group:16] Macro(“SIP/90528626916-0000011b”, “blkvm-clr,”) in new stack
– Executing [s@macro-blkvm-clr:1] Set(“SIP/90528626916-0000011b”, “SHARED(BLKVM,SIP/90528626916-0000011b)=”) in new stack
– Executing [s@macro-blkvm-clr:2] Set(“SIP/90528626916-0000011b”, “GOSUB_RETVAL=”) in new stack
– Executing [s@macro-blkvm-clr:3] MacroExit(“SIP/90528626916-0000011b”, “”) in new stack
– Executing [113@ext-group:17] Goto(“SIP/90528626916-0000011b”, “ext-local,vmu213,1”) in new stack
– Goto (ext-local,vmu213,1)
– Executing [vmu213@ext-local:1] Macro(“SIP/90528626916-0000011b”, “vm,213,NOANSWER,”) in new stack
– Executing [s@macro-vm:1] Macro(“SIP/90528626916-0000011b”, “user-callerid,SKIPTTL”) in new stack
– Executing [s@macro-user-callerid:1] Set(“SIP/90528626916-0000011b”, “TOUCH_MONITOR=1391165990.307”) in new stack
– Executing [s@macro-user-callerid:2] Set(“SIP/90528626916-0000011b”, “AMPUSER=00016132493231”) in new stack
– Executing [s@macro-user-callerid:3] GotoIf(“SIP/90528626916-0000011b”, “0?report”) in new stack
– Executing [s@macro-user-callerid:4] ExecIf(“SIP/90528626916-0000011b”, “0?Set(REALCALLERIDNUM=00016132493231)”) in new stack
– Executing [s@macro-user-callerid:5] Set(“SIP/90528626916-0000011b”, “AMPUSER=”) in new stack
– Executing [s@macro-user-callerid:6] Set(“SIP/90528626916-0000011b”, “AMPUSERCIDNAME=”) in new stack
– Executing [s@macro-user-callerid:7] GotoIf(“SIP/90528626916-0000011b”, “1?report”) in new stack
– Goto (macro-user-callerid,s,15)
– Executing [s@macro-user-callerid:15] GotoIf(“SIP/90528626916-0000011b”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,29)
– Executing [s@macro-user-callerid:29] Set(“SIP/90528626916-0000011b”, “CALLERID(number)=00016132493231”) in new stack
– Executing [s@macro-user-callerid:30] Set(“SIP/90528626916-0000011b”, “CALLERID(name)=0016132493231”) in new stack
– Executing [s@macro-user-callerid:31] Set(“SIP/90528626916-0000011b”, “CDR(cnum)=00016132493231”) in new stack
– Executing [s@macro-user-callerid:32] Set(“SIP/90528626916-0000011b”, “CDR(cnam)=0016132493231”) in new stack
– Executing [s@macro-user-callerid:33] Set(“SIP/90528626916-0000011b”, “CHANNEL(language)=de”) in new stack
– Executing [s@macro-vm:2] Set(“SIP/90528626916-0000011b”, “VMGAIN=”) in new stack
– Executing [s@macro-vm:3] Macro(“SIP/90528626916-0000011b”, “blkvm-check,”) in new stack
– Executing [s@macro-blkvm-check:1] Set(“SIP/90528626916-0000011b”, “GOSUB_RETVAL=”) in new stack
– Executing [s@macro-blkvm-check:2] ExecIf(“SIP/90528626916-0000011b”, “0?Set(GOSUB_RETVAL=TRUE)”) in new stack
– Executing [s@macro-blkvm-check:3] MacroExit(“SIP/90528626916-0000011b”, “”) in new stack
– Executing [s@macro-vm:4] GotoIf(“SIP/90528626916-0000011b”, “1?vmx,1”) in new stack
– Goto (macro-vm,vmx,1)
– Executing [vmx@macro-vm:1] Set(“SIP/90528626916-0000011b”, “MEXTEN=213”) in new stack
– Executing [vmx@macro-vm:2] Set(“SIP/90528626916-0000011b”, “MMODE=NOANSWER”) in new stack
– Executing [vmx@macro-vm:3] Set(“SIP/90528626916-0000011b”, “RETVM=”) in new stack
– Executing [vmx@macro-vm:4] Set(“SIP/90528626916-0000011b”, “MODE=unavail”) in new stack
– Executing [vmx@macro-vm:5] GotoIf(“SIP/90528626916-0000011b”, “1?chknomsg”) in new stack
– Goto (macro-vm,vmx,7)
– Executing [vmx@macro-vm:7] GotoIf(“SIP/90528626916-0000011b”, “0?s-NOANSWER,1”) in new stack
– Executing [vmx@macro-vm:8] GotoIf(“SIP/90528626916-0000011b”, “1?notdirect”) in new stack
– Goto (macro-vm,vmx,10)
– Executing [vmx@macro-vm:10] NoOp(“SIP/90528626916-0000011b”, "Checking if ext 213 is enabled: ") in new stack
– Executing [vmx@macro-vm:11] GotoIf(“SIP/90528626916-0000011b”, “1?s-NOANSWER,1”) in new stack
– Goto (macro-vm,s-NOANSWER,1)
– Executing [s-NOANSWER@macro-vm:1] Macro(“SIP/90528626916-0000011b”, “get-vmcontext,213”) in new stack
– Executing [s@macro-get-vmcontext:1] Set(“SIP/90528626916-0000011b”, “VMCONTEXT=default”) in new stack
– Executing [s@macro-get-vmcontext:2] GotoIf(“SIP/90528626916-0000011b”, “0?200:300”) in new stack
– Goto (macro-get-vmcontext,s,300)
– Executing [s@macro-get-vmcontext:300] NoOp(“SIP/90528626916-0000011b”, “”) in new stack
– Executing [s-NOANSWER@macro-vm:2] VoiceMail(“SIP/90528626916-0000011b”, “213@default,u”) in new stack
– <SIP/90528626916-0000011b> Playing ‘vm-theperson.gsm’ (language ‘de’)
– <SIP/90528626916-0000011b> Playing ‘digits/2.gsm’ (language ‘de’)
– <SIP/90528626916-0000011b> Playing ‘digits/1.gsm’ (language ‘de’)
– <SIP/90528626916-0000011b> Playing ‘digits/3.gsm’ (language ‘de’)
– <SIP/90528626916-0000011b> Playing ‘vm-isunavail.gsm’ (language ‘de’)
– <SIP/90528626916-0000011b> Playing ‘vm-intro.gsm’ (language ‘de’)
– <SIP/90528626916-0000011b> Playing ‘beep.gsm’ (language ‘de’)
– Recording the message
– x=0, open writing: /var/spool/asterisk/voicemail/default/213/tmp/qEB72w format: wav49, 0x22d5b68
– x=1, open writing: /var/spool/asterisk/voicemail/default/213/tmp/qEB72w format: gsm, 0x1fd02b8
– x=2, open writing: /var/spool/asterisk/voicemail/default/213/tmp/qEB72w format: wav, 0x2330aa8
bt-br-asterisk*CLI>

After the “NOTICE[22103][C-0000008f]: chan_sip.c:8451 sip_read: FAX CNG detected but no fax extension” , the voicemail option starts.

I don’t know what I am missing.
Any help is greatly apreciated

Thanks
Regards

I am able to get faxing to work, but not fax detection.

When I turn fax detect on and set a fax valid fax recipient, incoming faxes go into the regular route destination instead of into fax recipient.

Here is the asterisk CLI output of an attempted fax detection

freepbx02*CLI>
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
– Executing [14256361914@from-sip-external:1] NoOp(“SIP/199.173.94.152-00000028”, “Received incoming SIP connection from unknown peer to 14256361914”) in new stack
– Executing [14256361914@from-sip-external:2] Set(“SIP/199.173.94.152-00000028”, “DID=14256361914”) in new stack
– Executing [14256361914@from-sip-external:3] Goto(“SIP/199.173.94.152-00000028”, “s,1”) in new stack
– Goto (from-sip-external,s,1)
– Executing [s@from-sip-external:1] GotoIf(“SIP/199.173.94.152-00000028”, “1?from-trunk,14256361914,1”) in new stack
– Goto (from-trunk,14256361914,1)
– Executing [14256361914@from-trunk:1] Set(“SIP/199.173.94.152-00000028”, “__FROM_DID=14256361914”) in new stack
– Executing [14256361914@from-trunk:2] Gosub(“SIP/199.173.94.152-00000028”, “app-blacklist-check,s,1()”) in new stack
– Executing [s@app-blacklist-check:1] GotoIf(“SIP/199.173.94.152-00000028”, “0?blacklisted”) in new stack
– Executing [s@app-blacklist-check:2] Set(“SIP/199.173.94.152-00000028”, “CALLED_BLACKLIST=1”) in new stack
– Executing [s@app-blacklist-check:3] Return(“SIP/199.173.94.152-00000028”, “”) in new stack
– Executing [14256361914@from-trunk:3] Set(“SIP/199.173.94.152-00000028”, “CDR(did)=14256361914”) in new stack
– Executing [14256361914@from-trunk:4] ExecIf(“SIP/199.173.94.152-00000028”, “1 ?Set(CALLERID(name)=+15184172260)”) in new stack
– Executing [14256361914@from-trunk:5] Set(“SIP/199.173.94.152-00000028”, “__CALLINGPRES_SV=allowed_not_screened”) in new stack
– Executing [14256361914@from-trunk:6] Set(“SIP/199.173.94.152-00000028”, “CALLERPRES()=allowed_not_screened”) in new stack
– Executing [14256361914@from-trunk:7] Set(“SIP/199.173.94.152-00000028”, “FAX_DEST=ext-fax^201^1”) in new stack
– Executing [14256361914@from-trunk:8] Answer(“SIP/199.173.94.152-00000028”, “”) in new stack
– Executing [14256361914@from-trunk:9] Wait(“SIP/199.173.94.152-00000028”, “4”) in new stack
> 0x7f9e7c0266b0 – Probation passed - setting RTP source address to 199.173.77.106:50290
– Executing [14256361914@from-trunk:10] Goto(“SIP/199.173.94.152-00000028”, “ivr-1,s,1”) in new stack
– Goto (ivr-1,s,1)
– Executing [s@ivr-1:1] Set(“SIP/199.173.94.152-00000028”, “TIMEOUT_LOOPCOUNT=0”) in new stack
– Executing [s@ivr-1:2] Set(“SIP/199.173.94.152-00000028”, “INVALID_LOOPCOUNT=0”) in new stack
– Executing [s@ivr-1:3] Set(“SIP/199.173.94.152-00000028”, “_IVR_CONTEXT_ivr-1=”) in new stack
– Executing [s@ivr-1:4] Set(“SIP/199.173.94.152-00000028”, “_IVR_CONTEXT=ivr-1”) in new stack
– Executing [s@ivr-1:5] Set(“SIP/199.173.94.152-00000028”, “__IVR_RETVM=”) in new stack
– Executing [s@ivr-1:6] GotoIf(“SIP/199.173.94.152-00000028”, “1?skip”) in new stack
– Goto (ivr-1,s,9)
– Executing [s@ivr-1:9] Set(“SIP/199.173.94.152-00000028”, “IVR_MSG=custom/welcome2”) in new stack
– Executing [s@ivr-1:10] Set(“SIP/199.173.94.152-00000028”, “TIMEOUT(digit)=3”) in new stack
– Digit timeout set to 3.000
– Executing [s@ivr-1:11] ExecIf(“SIP/199.173.94.152-00000028”, “1?Background(custom/welcome2)”) in new stack
– <SIP/199.173.94.152-00000028> Playing ‘custom/welcome2.slin’ (language ‘en’)
[2014-02-23 04:23:42] NOTICE[6653][C-00000026]: chan_sip.c:8457 sip_read: FAX CNG detected but no fax extension
– Executing [s@ivr-1:12] WaitExten(“SIP/199.173.94.152-00000028”, “10,”) in new stack
– Timeout on SIP/199.173.94.152-00000028, going to ‘t’
– Executing [t@ivr-1:1] Set(“SIP/199.173.94.152-00000028”, “TIMEOUT_LOOPCOUNT=1”) in new stack
– Executing [t@ivr-1:2] GotoIf(“SIP/199.173.94.152-00000028”, “0?final”) in new stack
– Executing [t@ivr-1:3] Set(“SIP/199.173.94.152-00000028”, “IVR_MSG=no-valid-responce-pls-try-again”) in new stack
– Executing [t@ivr-1:4] Goto(“SIP/199.173.94.152-00000028”, “s,start”) in new stack
– Goto (ivr-1,s,10)
– Executing [s@ivr-1:10] Set(“SIP/199.173.94.152-00000028”, “TIMEOUT(digit)=3”) in new stack
– Digit timeout set to 3.000
– Executing [s@ivr-1:11] ExecIf(“SIP/199.173.94.152-00000028”, “1?Background(no-valid-responce-pls-try-again)”) in new stack
– <SIP/199.173.94.152-00000028> Playing ‘no-valid-responce-pls-try-again.slin’ (language ‘en’)
– Executing [s@ivr-1:12] WaitExten(“SIP/199.173.94.152-00000028”, “10,”) in new stack

If I change the route destination to the same fax recipient, the fax comes in as expected and gets processed into an email as desired. So faxing works fine, it is just fax detection that is not working.

I believe this part of the output is describing the issue: FAX CNG detected but no fax extension

It seems the fax is detected, but asterisk thinks there is no valid fax destination set. Not sure why…

Any suggestions?