Hello all,
new freepbx 13 install. Everything else is working as expected.
Having an issue with inbound faxing. Outbound fax works fine from UCP
I have a DID and inbound route assigned to fax recipient 999 (fax enabled user) email address is set to my company email. Email server setup is correct and all other email related items work (voicemail, new user creation etc…)
PBX is behind a nat. All required ports forwarded. 4000-4999. 1:1 nat
Here is the output from the console when I try to send a test fax from faxzero.
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
> 0x7f18dc106520 – Strict RTP learning after remote address set to: 8.38.41.138:37750
– Executing [2677932925@from-trunk-sip-BBD_1:1] Set(“SIP/BBD_1-0000002f”, “GROUP()=OUT_1”) in new stack
– Executing [2677932925@from-trunk-sip-BBD_1:2] Goto(“SIP/BBD_1-0000002f”, “from-trunk,2677932925,1”) in new stack
– Goto (from-trunk,2677932925,1)
– Executing [2677932925@from-trunk:1] Set(“SIP/BBD_1-0000002f”, “__DIRECTION=INBOUND”) in new stack
– Executing [2677932925@from-trunk:2] Gosub(“SIP/BBD_1-0000002f”, “sub-record-check,s,1(in,2677932925,no)”) in new stack
– Executing [s@sub-record-check:1] GotoIf(“SIP/BBD_1-0000002f”, “0?initialized”) in new stack
– Executing [s@sub-record-check:2] Set(“SIP/BBD_1-0000002f”, “__REC_STATUS=INITIALIZED”) in new stack
– Executing [s@sub-record-check:3] Set(“SIP/BBD_1-0000002f”, “NOW=1515039207”) in new stack
– Executing [s@sub-record-check:4] Set(“SIP/BBD_1-0000002f”, “__DAY=04”) in new stack
– Executing [s@sub-record-check:5] Set(“SIP/BBD_1-0000002f”, “__MONTH=01”) in new stack
– Executing [s@sub-record-check:6] Set(“SIP/BBD_1-0000002f”, “__YEAR=2018”) in new stack
– Executing [s@sub-record-check:7] Set(“SIP/BBD_1-0000002f”, “__TIMESTR=20180104-041327”) in new stack
– Executing [s@sub-record-check:8] Set(“SIP/BBD_1-0000002f”, “__FROMEXTEN=unknown”) in new stack
– Executing [s@sub-record-check:9] Set(“SIP/BBD_1-0000002f”, “__MON_FMT=wav”) in new stack
– Executing [s@sub-record-check:10] NoOp(“SIP/BBD_1-0000002f”, “Recordings initialized”) in new stack
– Executing [s@sub-record-check:11] ExecIf(“SIP/BBD_1-0000002f”, “0?Set(ARG3=dontcare)”) in new stack
– Executing [s@sub-record-check:12] Set(“SIP/BBD_1-0000002f”, “REC_POLICY_MODE_SAVE=”) in new stack
– Executing [s@sub-record-check:13] ExecIf(“SIP/BBD_1-0000002f”, “0?Set(REC_STATUS=NO)”) in new stack
– Executing [s@sub-record-check:14] GotoIf(“SIP/BBD_1-0000002f”, “2?checkaction”) in new stack
– Goto (sub-record-check,s,17)
– Executing [s@sub-record-check:17] GotoIf(“SIP/BBD_1-0000002f”, “1?sub-record-check,in,1”) in new stack
– Goto (sub-record-check,in,1)
– Executing [in@sub-record-check:1] NoOp(“SIP/BBD_1-0000002f”, “Inbound Recording Check to 2677932925”) in new stack
– Executing [in@sub-record-check:2] Set(“SIP/BBD_1-0000002f”, “FROMEXTEN=unknown”) in new stack
– Executing [in@sub-record-check:3] ExecIf(“SIP/BBD_1-0000002f”, “10?Set(FROMEXTEN=7708240759)”) in new stack
– Executing [in@sub-record-check:4] Gosub(“SIP/BBD_1-0000002f”, “recordcheck,1(no,in,2677932925)”) in new stack
– Executing [recordcheck@sub-record-check:1] NoOp(“SIP/BBD_1-0000002f”, “Starting recording check against no”) in new stack
– Executing [recordcheck@sub-record-check:2] Goto(“SIP/BBD_1-0000002f”, “no”) in new stack
– Goto (sub-record-check,recordcheck,12)
– Executing [recordcheck@sub-record-check:12] Set(“SIP/BBD_1-0000002f”, “__REC_POLICY_MODE=NO”) in new stack
– Executing [recordcheck@sub-record-check:13] Return(“SIP/BBD_1-0000002f”, “”) in new stack
– Executing [in@sub-record-check:5] Return(“SIP/BBD_1-0000002f”, “”) in new stack
– Executing [2677932925@from-trunk:3] Gosub(“SIP/BBD_1-0000002f”, “app-blacklist-check,s,1()”) in new stack
– Executing [s@app-blacklist-check:1] GotoIf(“SIP/BBD_1-0000002f”, “0?blacklisted”) in new stack
– Executing [s@app-blacklist-check:2] Set(“SIP/BBD_1-0000002f”, “CALLED_BLACKLIST=1”) in new stack
– Executing [s@app-blacklist-check:3] Return(“SIP/BBD_1-0000002f”, “”) in new stack
– Executing [2677932925@from-trunk:4] Set(“SIP/BBD_1-0000002f”, “__FROM_DID=2677932925”) in new stack
– Executing [2677932925@from-trunk:5] Set(“SIP/BBD_1-0000002f”, “CDR(did)=2677932925”) in new stack
– Executing [2677932925@from-trunk:6] ExecIf(“SIP/BBD_1-0000002f”, “1 ?Set(CALLERID(name)=7708240759)”) in new stack
– Executing [2677932925@from-trunk:7] Set(“SIP/BBD_1-0000002f”, “__MOHCLASS=”) in new stack
– Executing [2677932925@from-trunk:8] Set(“SIP/BBD_1-0000002f”, “__REVERSAL_REJECT=FALSE”) in new stack
– Executing [2677932925@from-trunk:9] GotoIf(“SIP/BBD_1-0000002f”, “1?post-reverse-charge”) in new stack
– Goto (from-trunk,2677932925,11)
– Executing [2677932925@from-trunk:11] NoOp(“SIP/BBD_1-0000002f”, “”) in new stack
– Executing [2677932925@from-trunk:12] Set(“SIP/BBD_1-0000002f”, “__CALLINGNAMEPRES_SV=allowed_not_screened”) in new stack
– Executing [2677932925@from-trunk:13] Set(“SIP/BBD_1-0000002f”, “__CALLINGNUMPRES_SV=allowed_not_screened”) in new stack
– Executing [2677932925@from-trunk:14] Set(“SIP/BBD_1-0000002f”, “CALLERID(name-pres)=allowed_not_screened”) in new stack
– Executing [2677932925@from-trunk:15] Set(“SIP/BBD_1-0000002f”, “CALLERID(num-pres)=allowed_not_screened”) in new stack
– Executing [2677932925@from-trunk:16] NoOp(“SIP/BBD_1-0000002f”, “CallerID Entry Point”) in new stack
– Executing [2677932925@from-trunk:17] Set(“SIP/BBD_1-0000002f”, “FAX_DEST=ext-fax^102^1”) in new stack
– Executing [2677932925@from-trunk:18] Set(“SIP/BBD_1-0000002f”, “FAXOPT(faxdetect)=yes”) in new stack
– Executing [2677932925@from-trunk:19] Answer(“SIP/BBD_1-0000002f”, “”) in new stack
> 0x7f18dc106520 – Strict RTP learning after remote address set to: 8.38.41.138:37750
> 0x7f18dc106520 – Strict RTP switching to RTP target address 8.38.41.138:37750 as source
– Executing [2677932925@from-trunk:20] PlayTones(“SIP/BBD_1-0000002f”, “ring”) in new stack
– Executing [2677932925@from-trunk:21] Wait(“SIP/BBD_1-0000002f”, “4”) in new stack
> 0x7f18dc106520 – Strict RTP learning complete - Locking on source address 8.38.41.138:37750
– Executing [2677932925@from-trunk:22] Goto(“SIP/BBD_1-0000002f”, “ext-fax,102,1”) in new stack
– Goto (ext-fax,102,1)
– Executing [102@ext-fax:1] Set(“SIP/BBD_1-0000002f”, “FAX_FOR=Fax (102)”) in new stack
– Executing [102@ext-fax:2] NoOp(“SIP/BBD_1-0000002f”, “Receiving Fax for: Fax (102), From: “7708240759” <7708240759>”) in new stack
– Executing [102@ext-fax:3] Set(“SIP/BBD_1-0000002f”, “FAX_RX_USER=102”) in new stack
– Executing [102@ext-fax:4] Set(“SIP/BBD_1-0000002f”, “FAX_RX_EMAIL_LEN=27”) in new stack
– Executing [102@ext-fax:5] ExecIf(“SIP/BBD_1-0000002f”, “1?Set(ARIUSER=102)”) in new stack
– Executing [102@ext-fax:6] ExecIf(“SIP/BBD_1-0000002f”, “1?AGI(fax.agi)”) in new stack
– Launched AGI Script /var/lib/asterisk/agi-bin/fax.agi
– <SIP/BBD_1-0000002f>AGI Script fax.agi completed, returning 0
– Executing [102@ext-fax:7] Goto(“SIP/BBD_1-0000002f”, “s,receivefax”) in new stack
– Goto (ext-fax,s,3)
– Executing [s@ext-fax:3] StopPlayTones(“SIP/BBD_1-0000002f”, “”) in new stack
– Executing [s@ext-fax:4] ReceiveFAX(“SIP/BBD_1-0000002f”, “/var/spool/asterisk/fax/1515039207.89.tif,f”) in new stack
– Channel ‘SIP/BBD_1-0000002f’ receiving FAX ‘/var/spool/asterisk/fax/1515039207.89.tif’
[2018-01-04 04:13:37] WARNING[9847][C-00000044]: chan_sip.c:10649 process_sdp: Failed to initialize UDPTL, declining image stream
[2018-01-04 04:13:37] WARNING[9847][C-00000044]: chan_sip.c:10828 process_sdp: Failing due to no acceptable offer found
== Spawn extension (ext-fax, s, 4) exited non-zero on ‘SIP/BBD_1-0000002f’
– Executing [h@ext-fax:1] GotoIf(“SIP/BBD_1-0000002f”, “1?failed”) in new stack
– Goto (ext-fax,h,104)
– Executing [h@ext-fax:104] NoOp(“SIP/BBD_1-0000002f”, “FAX FAILED for: Fax (102) , From: “7708240759” <7708240759>”) in new stack
– Executing [h@ext-fax:105] Macro(“SIP/BBD_1-0000002f”, “hangupcall,”) in new stack
– Executing [s@macro-hangupcall:1] GotoIf(“SIP/BBD_1-0000002f”, “1?theend”) in new stack
– Goto (macro-hangupcall,s,3)
– Executing [s@macro-hangupcall:3] ExecIf(“SIP/BBD_1-0000002f”, “0?Set(CDR(recordingfile)=)”) in new stack
– Executing [s@macro-hangupcall:4] NoOp(“SIP/BBD_1-0000002f”, " monior file= ") in new stack
– Executing [s@macro-hangupcall:5] AGI(“SIP/BBD_1-0000002f”, “attendedtransfer-rec-restart.php,”) in new stack
– Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
– <SIP/BBD_1-0000002f>AGI Script attendedtransfer-rec-restart.php completed, returning 0
– Executing [s@macro-hangupcall:6] Hangup(“SIP/BBD_1-0000002f”, “”) in new stack
== Spawn extension (macro-hangupcall, s, 6) exited non-zero on ‘SIP/BBD_1-0000002f’ in macro ‘hangupcall’
== Spawn extension (ext-fax, h, 105) exited non-zero on ‘SIP/BBD_1-0000002f’
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
> 0x7f18dc106520 – Strict RTP learning after remote address set to: 8.38.41.138:41742
– Executing [2677932925@from-trunk-sip-BBD_1:1] Set(“SIP/BBD_1-00000030”, “GROUP()=OUT_1”) in new stack
– Executing [2677932925@from-trunk-sip-BBD_1:2] Goto(“SIP/BBD_1-00000030”, “from-trunk,2677932925,1”) in new stack
– Goto (from-trunk,2677932925,1)
– Executing [2677932925@from-trunk:1] Set(“SIP/BBD_1-00000030”, “__DIRECTION=INBOUND”) in new stack
– Executing [2677932925@from-trunk:2] Gosub(“SIP/BBD_1-00000030”, “sub-record-check,s,1(in,2677932925,no)”) in new stack
– Executing [s@sub-record-check:1] GotoIf(“SIP/BBD_1-00000030”, “0?initialized”) in new stack
– Executing [s@sub-record-check:2] Set(“SIP/BBD_1-00000030”, “__REC_STATUS=INITIALIZED”) in new stack
– Executing [s@sub-record-check:3] Set(“SIP/BBD_1-00000030”, “NOW=1515039441”) in new stack
– Executing [s@sub-record-check:4] Set(“SIP/BBD_1-00000030”, “__DAY=04”) in new stack
– Executing [s@sub-record-check:5] Set(“SIP/BBD_1-00000030”, “__MONTH=01”) in new stack
– Executing [s@sub-record-check:6] Set(“SIP/BBD_1-00000030”, “__YEAR=2018”) in new stack
– Executing [s@sub-record-check:7] Set(“SIP/BBD_1-00000030”, “__TIMESTR=20180104-041721”) in new stack
– Executing [s@sub-record-check:8] Set(“SIP/BBD_1-00000030”, “__FROMEXTEN=unknown”) in new stack
– Executing [s@sub-record-check:9] Set(“SIP/BBD_1-00000030”, “__MON_FMT=wav”) in new stack
– Executing [s@sub-record-check:10] NoOp(“SIP/BBD_1-00000030”, “Recordings initialized”) in new stack
– Executing [s@sub-record-check:11] ExecIf(“SIP/BBD_1-00000030”, “0?Set(ARG3=dontcare)”) in new stack
– Executing [s@sub-record-check:12] Set(“SIP/BBD_1-00000030”, “REC_POLICY_MODE_SAVE=”) in new stack
– Executing [s@sub-record-check:13] ExecIf(“SIP/BBD_1-00000030”, “0?Set(REC_STATUS=NO)”) in new stack
– Executing [s@sub-record-check:14] GotoIf(“SIP/BBD_1-00000030”, “2?checkaction”) in new stack
– Goto (sub-record-check,s,17)
– Executing [s@sub-record-check:17] GotoIf(“SIP/BBD_1-00000030”, “1?sub-record-check,in,1”) in new stack
– Goto (sub-record-check,in,1)
– Executing [in@sub-record-check:1] NoOp(“SIP/BBD_1-00000030”, “Inbound Recording Check to 2677932925”) in new stack
– Executing [in@sub-record-check:2] Set(“SIP/BBD_1-00000030”, “FROMEXTEN=unknown”) in new stack
– Executing [in@sub-record-check:3] ExecIf(“SIP/BBD_1-00000030”, “10?Set(FROMEXTEN=9252484037)”) in new stack
– Executing [in@sub-record-check:4] Gosub(“SIP/BBD_1-00000030”, “recordcheck,1(no,in,2677932925)”) in new stack
– Executing [recordcheck@sub-record-check:1] NoOp(“SIP/BBD_1-00000030”, “Starting recording check against no”) in new stack
– Executing [recordcheck@sub-record-check:2] Goto(“SIP/BBD_1-00000030”, “no”) in new stack
– Goto (sub-record-check,recordcheck,12)
– Executing [recordcheck@sub-record-check:12] Set(“SIP/BBD_1-00000030”, “__REC_POLICY_MODE=NO”) in new stack
– Executing [recordcheck@sub-record-check:13] Return(“SIP/BBD_1-00000030”, “”) in new stack
– Executing [in@sub-record-check:5] Return(“SIP/BBD_1-00000030”, “”) in new stack
– Executing [2677932925@from-trunk:3] Gosub(“SIP/BBD_1-00000030”, “app-blacklist-check,s,1()”) in new stack
– Executing [s@app-blacklist-check:1] GotoIf(“SIP/BBD_1-00000030”, “0?blacklisted”) in new stack
– Executing [s@app-blacklist-check:2] Set(“SIP/BBD_1-00000030”, “CALLED_BLACKLIST=1”) in new stack
– Executing [s@app-blacklist-check:3] Return(“SIP/BBD_1-00000030”, “”) in new stack
– Executing [2677932925@from-trunk:4] Set(“SIP/BBD_1-00000030”, “__FROM_DID=2677932925”) in new stack
– Executing [2677932925@from-trunk:5] Set(“SIP/BBD_1-00000030”, “CDR(did)=2677932925”) in new stack
– Executing [2677932925@from-trunk:6] ExecIf(“SIP/BBD_1-00000030”, “1 ?Set(CALLERID(name)=9252484037)”) in new stack
– Executing [2677932925@from-trunk:7] Set(“SIP/BBD_1-00000030”, “__MOHCLASS=”) in new stack
– Executing [2677932925@from-trunk:8] Set(“SIP/BBD_1-00000030”, “__REVERSAL_REJECT=FALSE”) in new stack
– Executing [2677932925@from-trunk:9] GotoIf(“SIP/BBD_1-00000030”, “1?post-reverse-charge”) in new stack
– Goto (from-trunk,2677932925,11)
– Executing [2677932925@from-trunk:11] NoOp(“SIP/BBD_1-00000030”, “”) in new stack
– Executing [2677932925@from-trunk:12] Set(“SIP/BBD_1-00000030”, “__CALLINGNAMEPRES_SV=allowed_not_screened”) in new stack
– Executing [2677932925@from-trunk:13] Set(“SIP/BBD_1-00000030”, “__CALLINGNUMPRES_SV=allowed_not_screened”) in new stack
– Executing [2677932925@from-trunk:14] Set(“SIP/BBD_1-00000030”, “CALLERID(name-pres)=allowed_not_screened”) in new stack
– Executing [2677932925@from-trunk:15] Set(“SIP/BBD_1-00000030”, “CALLERID(num-pres)=allowed_not_screened”) in new stack
– Executing [2677932925@from-trunk:16] NoOp(“SIP/BBD_1-00000030”, “CallerID Entry Point”) in new stack
– Executing [2677932925@from-trunk:17] Set(“SIP/BBD_1-00000030”, “FAX_DEST=ext-fax^102^1”) in new stack
– Executing [2677932925@from-trunk:18] Set(“SIP/BBD_1-00000030”, “FAXOPT(faxdetect)=yes”) in new stack
– Executing [2677932925@from-trunk:19] Answer(“SIP/BBD_1-00000030”, “”) in new stack
> 0x7f18dc106520 – Strict RTP switching to RTP target address 8.38.41.138:41742 as source
– Executing [2677932925@from-trunk:20] PlayTones(“SIP/BBD_1-00000030”, “ring”) in new stack
– Executing [2677932925@from-trunk:21] Wait(“SIP/BBD_1-00000030”, “4”) in new stack
> 0x7f18dc106520 – Strict RTP learning complete - Locking on source address 8.38.41.138:41742
– Executing [2677932925@from-trunk:22] Goto(“SIP/BBD_1-00000030”, “ext-fax,102,1”) in new stack
– Goto (ext-fax,102,1)
– Executing [102@ext-fax:1] Set(“SIP/BBD_1-00000030”, “FAX_FOR=Fax (102)”) in new stack
– Executing [102@ext-fax:2] NoOp(“SIP/BBD_1-00000030”, “Receiving Fax for: Fax (102), From: “9252484037” <9252484037>”) in new stack
– Executing [102@ext-fax:3] Set(“SIP/BBD_1-00000030”, “FAX_RX_USER=102”) in new stack
– Executing [102@ext-fax:4] Set(“SIP/BBD_1-00000030”, “FAX_RX_EMAIL_LEN=27”) in new stack
– Executing [102@ext-fax:5] ExecIf(“SIP/BBD_1-00000030”, “1?Set(ARIUSER=102)”) in new stack
– Executing [102@ext-fax:6] ExecIf(“SIP/BBD_1-00000030”, “1?AGI(fax.agi)”) in new stack
– Launched AGI Script /var/lib/asterisk/agi-bin/fax.agi
– <SIP/BBD_1-00000030>AGI Script fax.agi completed, returning 0
– Executing [102@ext-fax:7] Goto(“SIP/BBD_1-00000030”, “s,receivefax”) in new stack
– Goto (ext-fax,s,3)
– Executing [s@ext-fax:3] StopPlayTones(“SIP/BBD_1-00000030”, “”) in new stack
– Executing [s@ext-fax:4] ReceiveFAX(“SIP/BBD_1-00000030”, “/var/spool/asterisk/fax/1515039441.90.tif,f”) in new stack
– Channel ‘SIP/BBD_1-00000030’ receiving FAX ‘/var/spool/asterisk/fax/1515039441.90.tif’
[2018-01-04 04:17:31] WARNING[9847][C-00000045]: chan_sip.c:10649 process_sdp: Failed to initialize UDPTL, declining image stream
[2018-01-04 04:17:31] WARNING[9847][C-00000045]: chan_sip.c:10828 process_sdp: Failing due to no acceptable offer found
[2018-01-04 04:17:31] WARNING[9847][C-00000045]: chan_sip.c:10649 process_sdp: Failed to initialize UDPTL, declining image stream
[2018-01-04 04:17:31] WARNING[9847][C-00000045]: chan_sip.c:10828 process_sdp: Failing due to no acceptable offer found
[2018-01-04 04:17:31] WARNING[9847][C-00000045]: chan_sip.c:10649 process_sdp: Failed to initialize UDPTL, declining image stream
[2018-01-04 04:17:31] WARNING[9847][C-00000045]: chan_sip.c:10828 process_sdp: Failing due to no acceptable offer found
– Executing [s@ext-fax:5] ExecIf(“SIP/BBD_1-00000030”, “1?Set(FAXSTATUS=“FAILED: error: Unexpected message received statusstr: Unexpected message received”)”) in new stack
– Executing [s@ext-fax:6] Hangup(“SIP/BBD_1-00000030”, “”) in new stack
== Spawn extension (ext-fax, s, 6) exited non-zero on ‘SIP/BBD_1-00000030’
– Executing [h@ext-fax:1] GotoIf(“SIP/BBD_1-00000030”, “1?failed”) in new stack
– Goto (ext-fax,h,104)
– Executing [h@ext-fax:104] NoOp(“SIP/BBD_1-00000030”, “FAX “FAILED: error: Unexpected message received statusstr: Unexpected message received” for: Fax (102) , From: “9252484037” <9252484037>”) in new stack
– Executing [h@ext-fax:105] Macro(“SIP/BBD_1-00000030”, “hangupcall,”) in new stack
– Executing [s@macro-hangupcall:1] GotoIf(“SIP/BBD_1-00000030”, “1?theend”) in new stack
– Goto (macro-hangupcall,s,3)
– Executing [s@macro-hangupcall:3] ExecIf(“SIP/BBD_1-00000030”, “0?Set(CDR(recordingfile)=)”) in new stack
– Executing [s@macro-hangupcall:4] NoOp(“SIP/BBD_1-00000030”, " monior file= ") in new stack
– Executing [s@macro-hangupcall:5] AGI(“SIP/BBD_1-00000030”, “attendedtransfer-rec-restart.php,”) in new stack
– Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
– <SIP/BBD_1-00000030>AGI Script attendedtransfer-rec-restart.php completed, returning 0
– Executing [s@macro-hangupcall:6] Hangup(“SIP/BBD_1-00000030”, “”) in new stack
== Spawn extension (macro-hangupcall, s, 6) exited non-zero on ‘SIP/BBD_1-00000030’ in macro ‘hangupcall’
== Spawn extension (ext-fax, h, 105) exited non-zero on ‘SIP/BBD_1-00000030’
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
> 0x7f18dc106520 – Strict RTP learning after remote address set to: 8.38.41.138:12638
– Executing [2677932925@from-trunk-sip-BBD_1:1] Set(“SIP/BBD_1-00000031”, “GROUP()=OUT_1”) in new stack
– Executing [2677932925@from-trunk-sip-BBD_1:2] Goto(“SIP/BBD_1-00000031”, “from-trunk,2677932925,1”) in new stack
– Goto (from-trunk,2677932925,1)
– Executing [2677932925@from-trunk:1] Set(“SIP/BBD_1-00000031”, “__DIRECTION=INBOUND”) in new stack
– Executing [2677932925@from-trunk:2] Gosub(“SIP/BBD_1-00000031”, “sub-record-check,s,1(in,2677932925,no)”) in new stack
– Executing [s@sub-record-check:1] GotoIf(“SIP/BBD_1-00000031”, “0?initialized”) in new stack
– Executing [s@sub-record-check:2] Set(“SIP/BBD_1-00000031”, “__REC_STATUS=INITIALIZED”) in new stack
– Executing [s@sub-record-check:3] Set(“SIP/BBD_1-00000031”, “NOW=1515039675”) in new stack
– Executing [s@sub-record-check:4] Set(“SIP/BBD_1-00000031”, “__DAY=04”) in new stack
– Executing [s@sub-record-check:5] Set(“SIP/BBD_1-00000031”, “__MONTH=01”) in new stack
– Executing [s@sub-record-check:6] Set(“SIP/BBD_1-00000031”, “__YEAR=2018”) in new stack
– Executing [s@sub-record-check:7] Set(“SIP/BBD_1-00000031”, “__TIMESTR=20180104-042115”) in new stack
– Executing [s@sub-record-check:8] Set(“SIP/BBD_1-00000031”, “__FROMEXTEN=unknown”) in new stack
– Executing [s@sub-record-check:9] Set(“SIP/BBD_1-00000031”, “__MON_FMT=wav”) in new stack
– Executing [s@sub-record-check:10] NoOp(“SIP/BBD_1-00000031”, “Recordings initialized”) in new stack
– Executing [s@sub-record-check:11] ExecIf(“SIP/BBD_1-00000031”, “0?Set(ARG3=dontcare)”) in new stack
– Executing [s@sub-record-check:12] Set(“SIP/BBD_1-00000031”, “REC_POLICY_MODE_SAVE=”) in new stack
– Executing [s@sub-record-check:13] ExecIf(“SIP/BBD_1-00000031”, “0?Set(REC_STATUS=NO)”) in new stack
– Executing [s@sub-record-check:14] GotoIf(“SIP/BBD_1-00000031”, “2?checkaction”) in new stack
– Goto (sub-record-check,s,17)
– Executing [s@sub-record-check:17] GotoIf(“SIP/BBD_1-00000031”, “1?sub-record-check,in,1”) in new stack
– Goto (sub-record-check,in,1)
– Executing [in@sub-record-check:1] NoOp(“SIP/BBD_1-00000031”, “Inbound Recording Check to 2677932925”) in new stack
– Executing [in@sub-record-check:2] Set(“SIP/BBD_1-00000031”, “FROMEXTEN=unknown”) in new stack
– Executing [in@sub-record-check:3] ExecIf(“SIP/BBD_1-00000031”, “10?Set(FROMEXTEN=7867895398)”) in new stack
– Executing [in@sub-record-check:4] Gosub(“SIP/BBD_1-00000031”, “recordcheck,1(no,in,2677932925)”) in new stack
– Executing [recordcheck@sub-record-check:1] NoOp(“SIP/BBD_1-00000031”, “Starting recording check against no”) in new stack
– Executing [recordcheck@sub-record-check:2] Goto(“SIP/BBD_1-00000031”, “no”) in new stack
– Goto (sub-record-check,recordcheck,12)
– Executing [recordcheck@sub-record-check:12] Set(“SIP/BBD_1-00000031”, “__REC_POLICY_MODE=NO”) in new stack
– Executing [recordcheck@sub-record-check:13] Return(“SIP/BBD_1-00000031”, “”) in new stack
– Executing [in@sub-record-check:5] Return(“SIP/BBD_1-00000031”, “”) in new stack
– Executing [2677932925@from-trunk:3] Gosub(“SIP/BBD_1-00000031”, “app-blacklist-check,s,1()”) in new stack
– Executing [s@app-blacklist-check:1] GotoIf(“SIP/BBD_1-00000031”, “0?blacklisted”) in new stack
– Executing [s@app-blacklist-check:2] Set(“SIP/BBD_1-00000031”, “CALLED_BLACKLIST=1”) in new stack
– Executing [s@app-blacklist-check:3] Return(“SIP/BBD_1-00000031”, “”) in new stack
– Executing [2677932925@from-trunk:4] Set(“SIP/BBD_1-00000031”, “__FROM_DID=2677932925”) in new stack
– Executing [2677932925@from-trunk:5] Set(“SIP/BBD_1-00000031”, “CDR(did)=2677932925”) in new stack
– Executing [2677932925@from-trunk:6] ExecIf(“SIP/BBD_1-00000031”, “1 ?Set(CALLERID(name)=7867895398)”) in new stack
– Executing [2677932925@from-trunk:7] Set(“SIP/BBD_1-00000031”, “__MOHCLASS=”) in new stack
– Executing [2677932925@from-trunk:8] Set(“SIP/BBD_1-00000031”, “__REVERSAL_REJECT=FALSE”) in new stack
– Executing [2677932925@from-trunk:9] GotoIf(“SIP/BBD_1-00000031”, “1?post-reverse-charge”) in new stack
– Goto (from-trunk,2677932925,11)
– Executing [2677932925@from-trunk:11] NoOp(“SIP/BBD_1-00000031”, “”) in new stack
– Executing [2677932925@from-trunk:12] Set(“SIP/BBD_1-00000031”, “__CALLINGNAMEPRES_SV=allowed_not_screened”) in new stack
– Executing [2677932925@from-trunk:13] Set(“SIP/BBD_1-00000031”, “__CALLINGNUMPRES_SV=allowed_not_screened”) in new stack
– Executing [2677932925@from-trunk:14] Set(“SIP/BBD_1-00000031”, “CALLERID(name-pres)=allowed_not_screened”) in new stack
– Executing [2677932925@from-trunk:15] Set(“SIP/BBD_1-00000031”, “CALLERID(num-pres)=allowed_not_screened”) in new stack
– Executing [2677932925@from-trunk:16] NoOp(“SIP/BBD_1-00000031”, “CallerID Entry Point”) in new stack
– Executing [2677932925@from-trunk:17] Set(“SIP/BBD_1-00000031”, “FAX_DEST=ext-fax^102^1”) in new stack
– Executing [2677932925@from-trunk:18] Set(“SIP/BBD_1-00000031”, “FAXOPT(faxdetect)=yes”) in new stack
– Executing [2677932925@from-trunk:19] Answer(“SIP/BBD_1-00000031”, “”) in new stack
> 0x7f18dc106520 – Strict RTP switching to RTP target address 8.38.41.138:12638 as source
– Executing [2677932925@from-trunk:20] PlayTones(“SIP/BBD_1-00000031”, “ring”) in new stack
– Executing [2677932925@from-trunk:21] Wait(“SIP/BBD_1-00000031”, “4”) in new stack
> 0x7f18dc106520 – Strict RTP learning complete - Locking on source address 8.38.41.138:12638
– Executing [2677932925@from-trunk:22] Goto(“SIP/BBD_1-00000031”, “ext-fax,102,1”) in new stack
– Goto (ext-fax,102,1)
– Executing [102@ext-fax:1] Set(“SIP/BBD_1-00000031”, “FAX_FOR=Fax (102)”) in new stack
– Executing [102@ext-fax:2] NoOp(“SIP/BBD_1-00000031”, “Receiving Fax for: Fax (102), From: “7867895398” <7867895398>”) in new stack
– Executing [102@ext-fax:3] Set(“SIP/BBD_1-00000031”, “FAX_RX_USER=102”) in new stack
– Executing [102@ext-fax:4] Set(“SIP/BBD_1-00000031”, “FAX_RX_EMAIL_LEN=27”) in new stack
– Executing [102@ext-fax:5] ExecIf(“SIP/BBD_1-00000031”, “1?Set(ARIUSER=102)”) in new stack
– Executing [102@ext-fax:6] ExecIf(“SIP/BBD_1-00000031”, “1?AGI(fax.agi)”) in new stack
– Launched AGI Script /var/lib/asterisk/agi-bin/fax.agi
– <SIP/BBD_1-00000031>AGI Script fax.agi completed, returning 0
– Executing [102@ext-fax:7] Goto(“SIP/BBD_1-00000031”, “s,receivefax”) in new stack
– Goto (ext-fax,s,3)
– Executing [s@ext-fax:3] StopPlayTones(“SIP/BBD_1-00000031”, “”) in new stack
– Executing [s@ext-fax:4] ReceiveFAX(“SIP/BBD_1-00000031”, “/var/spool/asterisk/fax/1515039675.91.tif,f”) in new stack
– Channel ‘SIP/BBD_1-00000031’ receiving FAX ‘/var/spool/asterisk/fax/1515039675.91.tif’
[2018-01-04 04:21:24] WARNING[9847][C-00000046]: chan_sip.c:10649 process_sdp: Failed to initialize UDPTL, declining image stream
[2018-01-04 04:21:24] WARNING[9847][C-00000046]: chan_sip.c:10828 process_sdp: Failing due to no acceptable offer found
== Spawn extension (ext-fax, s, 4) exited non-zero on ‘SIP/BBD_1-00000031’
– Executing [h@ext-fax:1] GotoIf(“SIP/BBD_1-00000031”, “1?failed”) in new stack
– Goto (ext-fax,h,104)
– Executing [h@ext-fax:104] NoOp(“SIP/BBD_1-00000031”, “FAX FAILED for: Fax (102) , From: “7867895398” <7867895398>”) in new stack
– Executing [h@ext-fax:105] Macro(“SIP/BBD_1-00000031”, “hangupcall,”) in new stack
– Executing [s@macro-hangupcall:1] GotoIf(“SIP/BBD_1-00000031”, “1?theend”) in new stack
– Goto (macro-hangupcall,s,3)
– Executing [s@macro-hangupcall:3] ExecIf(“SIP/BBD_1-00000031”, “0?Set(CDR(recordingfile)=)”) in new stack
– Executing [s@macro-hangupcall:4] NoOp(“SIP/BBD_1-00000031”, " monior file= ") in new stack
– Executing [s@macro-hangupcall:5] AGI(“SIP/BBD_1-00000031”, “attendedtransfer-rec-restart.php,”) in new stack
– Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
– <SIP/BBD_1-00000031>AGI Script attendedtransfer-rec-restart.php completed, returning 0
– Executing [s@macro-hangupcall:6] Hangup(“SIP/BBD_1-00000031”, “”) in new stack
== Spawn extension (macro-hangupcall, s, 6) exited non-zero on ‘SIP/BBD_1-00000031’ in macro ‘hangupcall’
== Spawn extension (ext-fax, h, 105) exited non-zero on ‘SIP/BBD_1-00000031’
Please advise 