Incoming Faxes not making it to email

[AJHogan]

New FreePBX 17 setup. I have setup incoming fax before on our previous 14 distro, and it worked fine. So far as i can tell, the faxes are coming into the server and properly being directed to the correct user, but aren’t emailed. I’ve used message trace on my exchange to check if the emails are being quarantined, there are not. Voicemail to email, as well as the new user welcome emails, are properly received. Using a trial version of a Softphone app with built-in faxing capabilities, i have tested outbound, and they send successfully. I want to get all of our faxing swapped over to FreePBX using the FaxPro Module, but buying that license is pointless if i cannot get inbound faxes working.

Attaching screenshots of my configuration

Log of an incoming Fax

[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [12039028467@from-pstn:1] Set(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “__DIRECTION=INBOUND”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [12039028467@from-pstn:2] Gosub(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “sub-record-check,s,1(in,12039028467,dontcare)”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [s@sub-record-check:1] GotoIf(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “0?initialized”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [s@sub-record-check:2] Set(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “__REC_STATUS=INITIALIZED”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [s@sub-record-check:3] Set(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “NOW=1731024577”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [s@sub-record-check:4] Set(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “__DAY=07”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [s@sub-record-check:5] Set(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “__MONTH=11”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [s@sub-record-check:6] Set(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “__YEAR=2024”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [s@sub-record-check:7] Set(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “__TIMESTR=20241107-180937”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [s@sub-record-check:8] Set(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “__FROMEXTEN=unknown”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [s@sub-record-check:9] Set(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “__MON_FMT=wav”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [s@sub-record-check:10] NoOp(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “Recordings initialized”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [s@sub-record-check:11] ExecIf(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “0?Set(ARG3=dontcare)”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [s@sub-record-check:12] Set(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “REC_POLICY_MODE_SAVE=”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [s@sub-record-check:13] ExecIf(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “0?Set(REC_STATUS=NO)”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [s@sub-record-check:14] GotoIf(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “2?checkaction”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx_builtins.c: Goto (sub-record-check,s,17)
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [s@sub-record-check:17] GotoIf(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “1?sub-record-check,in,1”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx_builtins.c: Goto (sub-record-check,in,1)
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [in@sub-record-check:1] NoOp(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “Inbound Recording Check to 12039028467”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [in@sub-record-check:2] Set(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “FROMEXTEN=unknown”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [in@sub-record-check:3] ExecIf(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “12?Set(FROMEXTEN=+13164404596)”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [in@sub-record-check:4] Gosub(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “recordcheck,1(dontcare,in,12039028467)”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “Starting recording check against dontcare”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [recordcheck@sub-record-check:2] Goto(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “dontcare”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [recordcheck@sub-record-check:3] Return(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [in@sub-record-check:5] Return(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [12039028467@from-pstn:3] Set(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “CHANNEL(tonezone)=us”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [12039028467@from-pstn:4] Set(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “__FROM_DID=12039028467”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [12039028467@from-pstn:5] Set(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “returnhere=1”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [12039028467@from-pstn:6] Gosub(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “app-blacklist-check,s,1()”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [s@app-blacklist-check:1] GotoIf(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “0?blacklisted”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [s@app-blacklist-check:2] Set(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “CALLED_BLACKLIST=1”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [s@app-blacklist-check:3] Return(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [12039028467@from-pstn:7] Set(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “CDR(did)=12039028467”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [12039028467@from-pstn:8] GotoIf(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “0?”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [12039028467@from-pstn:9] ExecIf(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “0 ?Set(CALLERID(name)=+13164404596)”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [12039028467@from-pstn:10] Set(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “__MOHCLASS=”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [12039028467@from-pstn:11] Set(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “__REVERSAL_REJECT=FALSE”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [12039028467@from-pstn:12] GotoIf(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “1?post-reverse-charge”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx_builtins.c: Goto (from-pstn,12039028467,14)
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [12039028467@from-pstn:14] NoOp(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [12039028467@from-pstn:15] Set(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “__CALLINGNAMEPRES_SV=allowed_not_screened”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [12039028467@from-pstn:16] Set(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “__CALLINGNUMPRES_SV=allowed_not_screened”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [12039028467@from-pstn:17] Set(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “CALLERID(name-pres)=allowed_not_screened”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [12039028467@from-pstn:18] Set(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “CALLERID(num-pres)=allowed_not_screened”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [12039028467@from-pstn:19] NoOp(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “CallerID Entry Point”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [12039028467@from-pstn:20] Set(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “FAX_DEST=ext-fax^18^1”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [12039028467@from-pstn:21] Set(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “FAXOPT(faxdetect)=yes”) in new stack
[2024-11-07 18:09:37] VERBOSE[619167][C-0000018d] pbx.c: Executing [12039028467@from-pstn:22] Answer(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “”) in new stack
[2024-11-07 18:09:38] VERBOSE[619167][C-0000018d] pbx.c: Executing [12039028467@from-pstn:23] Wait(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “4”) in new stack
[2024-11-07 18:09:40] VERBOSE[619167][C-0000018d] chan_pjsip.c: Redirecting ‘PJSIP/us-west-or.sip.flowroute.com-000003e7’ to fax extension due to CNG detection
[2024-11-07 18:09:40] VERBOSE[619167][C-0000018d] res_fax.c: Redirecting ‘PJSIP/us-west-or.sip.flowroute.com-000003e7’ to fax extension due to CNG detection
[2024-11-07 18:09:40] VERBOSE[619167][C-0000018d] pbx.c: Spawn extension (from-pstn, fax, 1) exited non-zero on ‘PJSIP/us-west-or.sip.flowroute.com-000003e7’
[2024-11-07 18:09:40] VERBOSE[619167][C-0000018d] pbx.c: Executing [fax@from-pstn:1] Set(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “__DIRECTION=INBOUND”) in new stack
[2024-11-07 18:09:40] VERBOSE[619167][C-0000018d] pbx.c: Executing [fax@from-pstn:2] Goto(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “ext-fax,18,1”) in new stack
[2024-11-07 18:09:40] VERBOSE[619167][C-0000018d] pbx_builtins.c: Goto (ext-fax,18,1)
[2024-11-07 18:09:40] VERBOSE[619167][C-0000018d] pbx.c: Executing [18@ext-fax:1] Set(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “FAX_FOR=Faxes (18)”) in new stack
[2024-11-07 18:09:40] VERBOSE[619167][C-0000018d] pbx.c: Executing [18@ext-fax:2] NoOp(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “Receiving Fax for: Faxes (18), From: “[V]UNYMED INC P” <+13164404596>”) in new stack
[2024-11-07 18:09:40] VERBOSE[619167][C-0000018d] pbx.c: Executing [18@ext-fax:3] Set(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “FAX_RX_USER=18”) in new stack
[2024-11-07 18:09:40] VERBOSE[619167][C-0000018d] pbx.c: Executing [18@ext-fax:4] Set(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “FAX_RX_EMAIL_LEN=21”) in new stack
[2024-11-07 18:09:40] VERBOSE[619167][C-0000018d] pbx.c: Executing [18@ext-fax:5] Goto(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “s,receivefax”) in new stack
[2024-11-07 18:09:40] VERBOSE[619167][C-0000018d] pbx_builtins.c: Goto (ext-fax,s,3)
[2024-11-07 18:09:40] VERBOSE[619167][C-0000018d] pbx.c: Executing [s@ext-fax:3] StopPlayTones(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “”) in new stack
[2024-11-07 18:09:40] VERBOSE[619167][C-0000018d] pbx.c: Executing [s@ext-fax:4] ReceiveFAX(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “/var/spool/asterisk/fax/1731024577.1715.tif,f”) in new stack
[2024-11-07 18:09:40] VERBOSE[619167][C-0000018d] res_fax.c: Channel ‘PJSIP/us-west-or.sip.flowroute.com-000003e7’ receiving FAX ‘/var/spool/asterisk/fax/1731024577.1715.tif’
[2024-11-07 18:09:50] WARNING[619167][C-0000018d] res_fax_spandsp.c: WARNING T.30 ECM carrier not found
[2024-11-07 18:09:51] VERBOSE[57320] netsock2.c: Using SIP RTP Audio TOS bits 184
[2024-11-07 18:09:51] VERBOSE[57320] netsock2.c: Using SIP RTP Audio TOS bits 184 in TCLASS field.
[2024-11-07 18:09:51] VERBOSE[57320] netsock2.c: Using SIP RTP Audio CoS mark 5
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [*281@from-internal:1] Gosub(“PJSIP/501-000003e8”, “macro-user-callerid,s,1()”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:1] Set(“PJSIP/501-000003e8”, “TOUCH_MONITOR=1731024591.1716”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:2] Set(“PJSIP/501-000003e8”, “CHANCONTEXT=”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:3] Progress(“PJSIP/501-000003e8”, “”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:4] Set(“PJSIP/501-000003e8”, “CHANCONTEXT=”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:5] Set(“PJSIP/501-000003e8”, “CHANEXTENCONTEXT=501-000003e8”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:6] Set(“PJSIP/501-000003e8”, “CHANEXTEN=501-000003e8”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:7] Set(“PJSIP/501-000003e8”, “CALLERID(number)=501”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:8] Set(“PJSIP/501-000003e8”, “AMPUSER=501”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:9] Set(“PJSIP/501-000003e8”, “HOTDESCKCHAN=501-000003e8”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:10] Set(“PJSIP/501-000003e8”, “HOTDESKEXTEN=501”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:11] Set(“PJSIP/501-000003e8”, “HOTDESKCALL=0”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:12] ExecIf(“PJSIP/501-000003e8”, “0?Set(HOTDESKCALL=1)”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:13] ExecIf(“PJSIP/501-000003e8”, “0?Set(CALLERID(name)=)”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:14] GotoIf(“PJSIP/501-000003e8”, “0?report”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:15] ExecIf(“PJSIP/501-000003e8”, “1?Set(REALCALLERIDNUM=501)”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:16] Set(“PJSIP/501-000003e8”, “AMPUSER=501”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:17] GotoIf(“PJSIP/501-000003e8”, “0?limit”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:18] Set(“PJSIP/501-000003e8”, “AMPUSERCIDNAME=Front Desk, NW”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:19] ExecIf(“PJSIP/501-000003e8”, “0?Set(__CIDMASQUERADING=TRUE)”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:20] GotoIf(“PJSIP/501-000003e8”, “0?report”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:21] Set(“PJSIP/501-000003e8”, “AMPUSERCID=501”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:22] Set(“PJSIP/501-000003e8”, “__DIAL_OPTIONS=HhTtr”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:23] Set(“PJSIP/501-000003e8”, “CALLERID(all)=“Front Desk, NW” <501>”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:24] ExecIf(“PJSIP/501-000003e8”, “0?Set(CUSDIAL=)”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:25] ExecIf(“PJSIP/501-000003e8”, “0?Set(CALLERID(all)=“Front Desk, NW” <501>)”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:26] GotoIf(“PJSIP/501-000003e8”, “0?limit”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:27] ExecIf(“PJSIP/501-000003e8”, “0?Set(GROUP(concurrency_limit)=501)”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:28] ExecIf(“PJSIP/501-000003e8”, “0?Set(CHANNEL(language)=)”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:29] NoOp(“PJSIP/501-000003e8”, “Macro depricated!! To keep the same line numbers”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:30] NoOp(“PJSIP/501-000003e8”, “Macro depricated !! To keep the same line numbers”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:31] GotoIf(“PJSIP/501-000003e8”, “0?continue”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:32] ExecIf(“PJSIP/501-000003e8”, “1?Set(__CALLEE_ACCOUNCODE=)”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:33] Set(“PJSIP/501-000003e8”, “__TTL=64”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:34] GotoIf(“PJSIP/501-000003e8”, “1?continue”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx_builtins.c: Goto (macro-user-callerid,s,50)
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:50] Set(“PJSIP/501-000003e8”, “CALLERID(number)=501”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:51] Set(“PJSIP/501-000003e8”, “CALLERID(name)=Front Desk, NW”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:52] GotoIf(“PJSIP/501-000003e8”, “0?cnum”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:53] Set(“PJSIP/501-000003e8”, “__MCNUM=501”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:54] Set(“PJSIP/501-000003e8”, “__MCNAME=Front Desk, NW”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:55] Set(“PJSIP/501-000003e8”, “__MCEXTEN=501”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:56] Set(“PJSIP/501-000003e8”, “__MCORGCHAN=PJSIP/501-000003e8”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:57] Set(“PJSIP/501-000003e8”, “CDR(cnam)=Front Desk, NW”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:58] Set(“PJSIP/501-000003e8”, “CDR(cnum)=501”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@macro-user-callerid:59] Return(“PJSIP/501-000003e8”, “”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [*281@from-internal:2] Answer(“PJSIP/501-000003e8”, “”) in new stack
[2024-11-07 18:09:51] VERBOSE[619241][C-0000018e] pbx.c: Executing [*281@from-internal:3] Wait(“PJSIP/501-000003e8”, “1”) in new stack
[2024-11-07 18:09:52] WARNING[619167][C-0000018d] res_fax_spandsp.c: WARNING T.30 ECM carrier not found
[2024-11-07 18:09:52] WARNING[619167][C-0000018d] res_fax_spandsp.c: WARNING T.30 ECM carrier not found
[2024-11-07 18:09:52] VERBOSE[619241][C-0000018e] pbx.c: Executing [*281@from-internal:4] Set(“PJSIP/501-000003e8”, “INDEXES=1”) in new stack
[2024-11-07 18:09:52] VERBOSE[619241][C-0000018e] pbx.c: Executing [*281@from-internal:5] Set(“PJSIP/501-000003e8”, “DAYREC=beep&silence/1&featurecode&digits/1&de-activated”) in new stack
[2024-11-07 18:09:52] VERBOSE[619241][C-0000018e] pbx.c: Executing [*281@from-internal:6] Set(“PJSIP/501-000003e8”, “NIGHTREC=beep&silence/1&featurecode&digits/1&activated”) in new stack
[2024-11-07 18:09:52] VERBOSE[619241][C-0000018e] pbx.c: Executing [*281@from-internal:7] Goto(“PJSIP/501-000003e8”, “app-daynight-toggle,s,1”) in new stack
[2024-11-07 18:09:52] VERBOSE[619241][C-0000018e] pbx_builtins.c: Goto (app-daynight-toggle,s,1)
[2024-11-07 18:09:52] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@app-daynight-toggle:1] Set(“PJSIP/501-000003e8”, “LOOPCNT=1”) in new stack
[2024-11-07 18:09:52] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@app-daynight-toggle:2] Set(“PJSIP/501-000003e8”, “ITER=1”) in new stack
[2024-11-07 18:09:52] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@app-daynight-toggle:3] Set(“PJSIP/501-000003e8”, “INDEX=1”) in new stack
[2024-11-07 18:09:52] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@app-daynight-toggle:4] Set(“PJSIP/501-000003e8”, “MODE=DAY”) in new stack
[2024-11-07 18:09:52] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@app-daynight-toggle:5] GotoIf(“PJSIP/501-000003e8”, “1?end1”) in new stack
[2024-11-07 18:09:52] VERBOSE[619241][C-0000018e] pbx_builtins.c: Goto (app-daynight-toggle,s,7)
[2024-11-07 18:09:52] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@app-daynight-toggle:7] Set(“PJSIP/501-000003e8”, “ITER=2”) in new stack
[2024-11-07 18:09:52] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@app-daynight-toggle:8] GotoIf(“PJSIP/501-000003e8”, “0?begin1”) in new stack
[2024-11-07 18:09:52] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@app-daynight-toggle:9] Set(“PJSIP/501-000003e8”, “LOOPCNT=1”) in new stack
[2024-11-07 18:09:52] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@app-daynight-toggle:10] Set(“PJSIP/501-000003e8”, “ITER=1”) in new stack
[2024-11-07 18:09:52] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@app-daynight-toggle:11] Set(“PJSIP/501-000003e8”, “INDEX=1”) in new stack
[2024-11-07 18:09:52] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@app-daynight-toggle:12] GotoIf(“PJSIP/501-000003e8”, “0?day:night”) in new stack
[2024-11-07 18:09:52] VERBOSE[619241][C-0000018e] pbx_builtins.c: Goto (app-daynight-toggle,s,16)
[2024-11-07 18:09:52] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@app-daynight-toggle:16] Set(“PJSIP/501-000003e8”, “DB(DAYNIGHT/C1)=NIGHT”) in new stack
[2024-11-07 18:09:52] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@app-daynight-toggle:17] Set(“PJSIP/501-000003e8”, “DEVICE_STATE(Custom:DAYNIGHT1)=INUSE”) in new stack
[2024-11-07 18:09:52] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@app-daynight-toggle:18] Goto(“PJSIP/501-000003e8”, “end2”) in new stack
[2024-11-07 18:09:52] VERBOSE[619241][C-0000018e] pbx_builtins.c: Goto (app-daynight-toggle,s,19)
[2024-11-07 18:09:52] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@app-daynight-toggle:19] Set(“PJSIP/501-000003e8”, “ITER=2”) in new stack
[2024-11-07 18:09:52] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@app-daynight-toggle:20] GotoIf(“PJSIP/501-000003e8”, “0?begin2”) in new stack
[2024-11-07 18:09:52] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@app-daynight-toggle:21] ExecIf(“PJSIP/501-000003e8”, “0?Playback(beep&silence/1&featurecode&digits/1&de-activated):Playback(beep&silence/1&featurecode&digits/1&activated)”) in new stack
[2024-11-07 18:09:52] VERBOSE[619241][C-0000018e] file.c: <PJSIP/501-000003e8> Playing ‘beep.ulaw’ (language ‘en’)
[2024-11-07 18:09:53] VERBOSE[619241][C-0000018e] file.c: <PJSIP/501-000003e8> Playing ‘silence/1.ulaw’ (language ‘en’)
[2024-11-07 18:09:54] VERBOSE[619241][C-0000018e] file.c: <PJSIP/501-000003e8> Playing ‘featurecode.slin’ (language ‘en’)
[2024-11-07 18:09:55] VERBOSE[619241][C-0000018e] file.c: <PJSIP/501-000003e8> Playing ‘digits/1.ulaw’ (language ‘en’)
[2024-11-07 18:09:56] VERBOSE[619241][C-0000018e] file.c: <PJSIP/501-000003e8> Playing ‘activated.ulaw’ (language ‘en’)
[2024-11-07 18:09:57] VERBOSE[619241][C-0000018e] pbx.c: Executing [s@app-daynight-toggle:22] Hangup(“PJSIP/501-000003e8”, “”) in new stack
[2024-11-07 18:09:57] VERBOSE[619241][C-0000018e] pbx.c: Spawn extension (app-daynight-toggle, s, 22) exited non-zero on ‘PJSIP/501-000003e8’
[2024-11-07 18:09:57] VERBOSE[619167][C-0000018d] pbx.c: Executing [s@ext-fax:5] ExecIf(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “0?Set(FAXSTATUS=“FAILED: error: statusstr: OK”)”) in new stack
[2024-11-07 18:09:57] VERBOSE[619167][C-0000018d] pbx.c: Executing [s@ext-fax:6] Hangup(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “”) in new stack
[2024-11-07 18:09:57] VERBOSE[619167][C-0000018d] pbx.c: Spawn extension (ext-fax, s, 6) exited non-zero on ‘PJSIP/us-west-or.sip.flowroute.com-000003e7’
[2024-11-07 18:09:57] VERBOSE[619167][C-0000018d] pbx.c: Executing [h@ext-fax:1] GotoIf(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “0?failed”) in new stack
[2024-11-07 18:09:57] VERBOSE[619167][C-0000018d] pbx.c: Executing [h@ext-fax:2] Set(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “DELETE_AFTER_SEND=true”) in new stack
[2024-11-07 18:09:57] VERBOSE[619167][C-0000018d] pbx.c: Executing [h@ext-fax:3] GotoIf(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “0?noemail”) in new stack
[2024-11-07 18:09:57] VERBOSE[619167][C-0000018d] pbx.c: Executing [h@ext-fax:4] System(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “/var/lib/asterisk/bin/fax2mail.php --remotestationid “” --user “18” --dest “12039028467” --callerid “IltWXVVOWU1FRCBJTkMgUCIgPCsxMzE2NDQwNDU5Nj4=” --file /var/spool/asterisk/fax/1731024577.1715.tif --delete “true””) in new stack
[2024-11-07 18:09:57] VERBOSE[619167][C-0000018d] pbx.c: Executing [h@ext-fax:5] Gosub(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “macro-hangupcall,s,1()”) in new stack
[2024-11-07 18:09:57] VERBOSE[619167][C-0000018d] pbx.c: Executing [s@macro-hangupcall:1] Set(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “__MCVMSTATUS=”) in new stack
[2024-11-07 18:09:57] VERBOSE[619167][C-0000018d] pbx.c: Executing [s@macro-hangupcall:2] Gosub(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “app-missedcall-hangup,s,1()”) in new stack
[2024-11-07 18:09:57] VERBOSE[619167][C-0000018d] pbx.c: Executing [s@app-missedcall-hangup:1] NoOp(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “Dialed: s”) in new stack
[2024-11-07 18:09:57] VERBOSE[619167][C-0000018d] pbx.c: Executing [s@app-missedcall-hangup:2] NoOp(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, "Caller: ") in new stack
[2024-11-07 18:09:57] VERBOSE[619167][C-0000018d] pbx.c: Executing [s@app-missedcall-hangup:3] GotoIf(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “0?exit”) in new stack
[2024-11-07 18:09:57] VERBOSE[619167][C-0000018d] pbx.c: Executing [s@app-missedcall-hangup:4] Set(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “EXTENNUM=s”) in new stack
[2024-11-07 18:09:57] VERBOSE[619167][C-0000018d] pbx.c: Executing [s@app-missedcall-hangup:5] Set(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “FEXTENNUM=s”) in new stack
[2024-11-07 18:09:57] VERBOSE[619167][C-0000018d] pbx.c: Executing [s@app-missedcall-hangup:6] GotoIf(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “0?exit”) in new stack
[2024-11-07 18:09:57] VERBOSE[619167][C-0000018d] pbx.c: Executing [s@app-missedcall-hangup:7] AGI(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “agi://127.0.0.1/missedcallnotify.php,s,s,0,PJSIP/us-west-or.sip.flowroute.com-000003e7,”) in new stack
[2024-11-07 18:09:57] VERBOSE[619167][C-0000018d] res_agi.c: <PJSIP/us-west-or.sip.flowroute.com-000003e7>AGI Script agi://127.0.0.1/missedcallnotify.php completed, returning 0
[2024-11-07 18:09:57] VERBOSE[619167][C-0000018d] pbx.c: Executing [s@app-missedcall-hangup:8] Return(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “”) in new stack
[2024-11-07 18:09:57] VERBOSE[619167][C-0000018d] pbx.c: Executing [s@macro-hangupcall:3] UserEvent(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “MES,RTPAUDIOQOSMESBRIDGED:,RTPAUDIOQOSMES:”) in new stack
[2024-11-07 18:09:57] VERBOSE[619167][C-0000018d] pbx.c: Executing [s@macro-hangupcall:4] GotoIf(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “1?theend”) in new stack
[2024-11-07 18:09:57] VERBOSE[619167][C-0000018d] pbx_builtins.c: Goto (macro-hangupcall,s,6)
[2024-11-07 18:09:57] VERBOSE[619167][C-0000018d] pbx.c: Executing [s@macro-hangupcall:6] ExecIf(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “0?Set(CDR(recordingfile)=)”) in new stack
[2024-11-07 18:09:57] VERBOSE[619167][C-0000018d] pbx.c: Executing [s@macro-hangupcall:7] Hangup(“PJSIP/us-west-or.sip.flowroute.com-000003e7”, “”) in new stack
[2024-11-07 18:09:57] VERBOSE[619167][C-0000018d] pbx.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on ‘PJSIP/us-west-or.sip.flowroute.com-000003e7’

[shahin (Shahin NAZIR)]

Have you tested sending emails? its working?

[AJHogan]

Voicemail to email are working properly. So are the New User Welcome emails that can be sent out from User Management. What other Email tests should i perform?

[shahin (Shahin NAZIR)]

Check yours FreePBX Modules updates and license specially for FaxPro could be expired.

[AJHogan]

I appreciate that you are trying to help, but it feels like you aren’t actually reading what i have written. My FaxPro license cannot be expired, because i don’t have one, nor have i ever.

Additionally, I am talking specifically about inbound faxes. The free module for “Fax Configuration” specifically says that it is for Inbound Faxing. FaxPro is or outbound faxes via the UCP. So unless inbound faxes now require FaxPro, but the modules descriptions haven’t been updated to reflect this, that is not the problem.

[tmulkey2 (Thomas Mulkey)]

I had this problem when setting it up. Make a empty IVR to route the fax number to under inbound routes General Tab. Then with FAX detection on under the FAX tab, it will wait the 4 seconds then route it to 299. With you routing the number directly to fax extensions under the General tab. It sends it directly to the fax. It does not auto-detect when the destination is a fax anyway, and it does not do the T.38 (SIP) fax so the faxes will error out quite often. They will start, but not complete.

[AJHogan]

Thank you for this suggestion, i will try it later this evening and update with the results.

[AJHogan]

I am sorry to say, that did not resolve the issue. Here are some SS of the configuration, let me know if i misunderstood something. (The IVR has no destinations)

[AJHogan]

The other

[tmulkey2 (Thomas Mulkey)]

Man, Everything Looks right. I had a bunch of timeouts and errors when I tried to do the main inbound route to the fax extension, and when I pointed it to an IVR the fax detection grabbed it and they all started coming through.

Here is my setup on my test system:

[tmulkey2 (Thomas Mulkey)]

If you set them to store locally you could see if they are coming into the UCD and not getting emailed potentially for some reason. But you said you are getting other emails to the exchange server. I setup my e-mail to relay to exchange with this, which were instructions I created years ago for my backup servers that were on CentOS now Rocky 8. Voicemail notifications then worked, and so did the faxes:

[AJHogan]

Thanks for your help on this. With a little help from another, i was able to determine that the faxes were indeed being properly obtained by the server. Even though they were being saved as a TIFF, regardless on the Fax User’s settings. Every test fax i had sent with “store locally” on was sitting in /var/spool/asterisk/fax/

So the problem is solely with the Fax to Mail process. In the end, we didn’t want the faxes in a mailbox anyways. I was going to create a Power Automate to pull them out of there and put them in a Sharepoint Document Library. It’s going to be much more complicated script, but i can create one that will pull the images out of that directory via SFTP and get them where i wanted to go (the complicated part is pulling the needed Metadata from the log file).