Can't receive calls after upgrade

Nucleus · May 25, 2021, 5:34am

Hello,

I just upgraded from FreePBX 13 (FreePBX 13.0.197.28) to FreePBX 15 (FreePBX 15.0.17.34)

I also had to let go of Digium Phones Config module and set up EndPoint.

Everything is working except I can’t receive calls!

Here’s the log when there’s an incoming call and I don’t understand why it doesn’t work.

The extension 990777 is sitting beside me, is online (I can make calls) and it’s not in DND.

-- Executing [1PhoneNumber@from-sip-external:1] NoOp("SIP/sip.provider.ca-000000d5", "Received incoming SIP connection from unknown peer to 1PhoneNumber") in new stack
-- Executing [1PhoneNumber@from-sip-external:2] Set("SIP/sip.provider.ca-000000d5", "DID=1PhoneNumber") in new stack
-- Executing [1PhoneNumber@from-sip-external:3] Goto("SIP/sip.provider.ca-000000d5", "s,1") in new stack
-- Goto (from-sip-external,s,1)
-- Executing [s@from-sip-external:1] GotoIf("SIP/sip.provider.ca-000000d5", "1?setlanguage:checkanon") in new stack
-- Goto (from-sip-external,s,2)
-- Executing [s@from-sip-external:2] Set("SIP/sip.provider.ca-000000d5", "CHANNEL(language)=fr") in new stack
-- Executing [s@from-sip-external:3] GotoIf("SIP/sip.provider.ca-000000d5", "0?noanonymous") in new stack
-- Executing [s@from-sip-external:4] Goto("SIP/sip.provider.ca-000000d5", "from-trunk,1PhoneNumber,1") in new stack
-- Goto (from-trunk,1PhoneNumber,1)
-- Executing [1PhoneNumber@from-trunk:1] Set("SIP/sip.provider.ca-000000d5", "__DIRECTION=INBOUND") in new stack
-- Executing [1PhoneNumber@from-trunk:2] Set("SIP/sip.provider.ca-000000d5", "CHANNEL(language)=fr") in new stack
-- Executing [1PhoneNumber@from-trunk:3] Set("SIP/sip.provider.ca-000000d5", "CHANNEL(tonezone)=us") in new stack
-- Executing [1PhoneNumber@from-trunk:4] Set("SIP/sip.provider.ca-000000d5", "__FROM_DID=1PhoneNumber") in new stack
-- Executing [1PhoneNumber@from-trunk:5] Set("SIP/sip.provider.ca-000000d5", "returnhere=1") in new stack
-- Executing [1PhoneNumber@from-trunk:6] Gosub("SIP/sip.provider.ca-000000d5", "app-blacklist-check,s,1()") in new stack
-- Executing [s@app-blacklist-check:1] GotoIf("SIP/sip.provider.ca-000000d5", "0?blacklisted") in new stack
-- Executing [s@app-blacklist-check:2] Set("SIP/sip.provider.ca-000000d5", "CALLED_BLACKLIST=1") in new stack
-- Executing [s@app-blacklist-check:3] Return("SIP/sip.provider.ca-000000d5", "") in new stack
-- Executing [1PhoneNumber@from-trunk:7] Set("SIP/sip.provider.ca-000000d5", "CDR(did)=1PhoneNumber") in new stack
-- Executing [1PhoneNumber@from-trunk:8] GotoIf("SIP/sip.provider.ca-000000d5", "0?") in new stack
-- Executing [1PhoneNumber@from-trunk:9] ExecIf("SIP/sip.provider.ca-000000d5", "0 ?Set(CALLERID(name)=1PhoneNumber)") in new stack
-- Executing [1PhoneNumber@from-trunk:10] Set("SIP/sip.provider.ca-000000d5", "CHANNEL(musicclass)=Company") in new stack
-- Executing [1PhoneNumber@from-trunk:11] Set("SIP/sip.provider.ca-000000d5", "__MOHCLASS=Company") in new stack
-- Executing [1PhoneNumber@from-trunk:12] Set("SIP/sip.provider.ca-000000d5", "__REVERSAL_REJECT=FALSE") in new stack
-- Executing [1PhoneNumber@from-trunk:13] GotoIf("SIP/sip.provider.ca-000000d5", "1?post-reverse-charge") in new stack
-- Goto (from-trunk,1PhoneNumber,15)
-- Executing [1PhoneNumber@from-trunk:15] NoOp("SIP/sip.provider.ca-000000d5", "") in new stack
-- Executing [1PhoneNumber@from-trunk:16] Set("SIP/sip.provider.ca-000000d5", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
-- Executing [1PhoneNumber@from-trunk:17] Set("SIP/sip.provider.ca-000000d5", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
-- Executing [1PhoneNumber@from-trunk:18] Set("SIP/sip.provider.ca-000000d5", "CALLERID(name-pres)=allowed_not_screened") in new stack
-- Executing [1PhoneNumber@from-trunk:19] Set("SIP/sip.provider.ca-000000d5", "CALLERID(num-pres)=allowed_not_screened") in new stack
-- Executing [1PhoneNumber@from-trunk:20] NoOp("SIP/sip.provider.ca-000000d5", "CallerID Entry Point") in new stack
-- Executing [1PhoneNumber@from-trunk:21] Set("SIP/sip.provider.ca-000000d5", "FAX_DEST=ext-fax^1^1") in new stack
-- Executing [1PhoneNumber@from-trunk:22] Set("SIP/sip.provider.ca-000000d5", "FAXOPT(faxdetect)=yes") in new stack
-- Executing [1PhoneNumber@from-trunk:23] Answer("SIP/sip.provider.ca-000000d5", "") in new stack
-- Executing [1PhoneNumber@from-trunk:24] Wait("SIP/sip.provider.ca-000000d5", "4") in new stack
   > 0x7f922419d950 -- Strict RTP switching to RTP target address 198.38.7.34:18966 as source
-- Executing [1PhoneNumber@from-trunk:25] Set("SIP/sip.provider.ca-000000d5", "__CRM_DIRECTION=INBOUND") in new stack
-- Executing [1PhoneNumber@from-trunk:26] Set("SIP/sip.provider.ca-000000d5", "__CRM_SOURCE=1PhoneNumber") in new stack
-- Executing [1PhoneNumber@from-trunk:27] Set("SIP/sip.provider.ca-000000d5", "__CRM_LINKEDID=1621920346.221") in new stack
-- Executing [1PhoneNumber@from-trunk:28] AGI("SIP/sip.provider.ca-000000d5", "agi://127.0.0.1/sangomacrm.agi,true") in new stack
-- <SIP/sip.provider.ca-000000d5>AGI Script agi://127.0.0.1/sangomacrm.agi completed, returning 0
-- Executing [1PhoneNumber@from-trunk:29] ExecIf("SIP/sip.provider.ca-000000d5", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
-- Executing [1PhoneNumber@from-trunk:30] Goto("SIP/sip.provider.ca-000000d5", "ivr-1,s,1") in new stack
-- Goto (ivr-1,s,1)
-- Executing [s@ivr-1:1] Set("SIP/sip.provider.ca-000000d5", "TIMEOUT_LOOPCOUNT=0") in new stack
-- Executing [s@ivr-1:2] Set("SIP/sip.provider.ca-000000d5", "INVALID_LOOPCOUNT=0") in new stack
-- Executing [s@ivr-1:3] Set("SIP/sip.provider.ca-000000d5", "_IVR_CONTEXT_ivr-1=") in new stack
-- Executing [s@ivr-1:4] Set("SIP/sip.provider.ca-000000d5", "_IVR_CONTEXT=ivr-1") in new stack
-- Executing [s@ivr-1:5] Set("SIP/sip.provider.ca-000000d5", "__IVR_RETVM=") in new stack
-- Executing [s@ivr-1:6] GotoIf("SIP/sip.provider.ca-000000d5", "1?skip") in new stack
-- Goto (ivr-1,s,8)
-- Executing [s@ivr-1:8] Set("SIP/sip.provider.ca-000000d5", "IVR_MSG=custom/Company-par-MP") in new stack
-- Executing [s@ivr-1:9] Set("SIP/sip.provider.ca-000000d5", "TIMEOUT(digit)=3") in new stack
-- Digit timeout set to 3.000
-- Executing [s@ivr-1:10] ExecIf("SIP/sip.provider.ca-000000d5", "1?Background(custom/Company-par-MP)") in new stack
-- <SIP/sip.provider.ca-000000d5> Playing 'custom/Company-par-MP.slin' (language 'fr')
   > 0x7f922419d950 -- Strict RTP learning complete - Locking on source address 198.38.7.34:18966
-- Executing [777@ivr-1:1] Set("SIP/sip.provider.ca-000000d5", "__ivrreturn=0") in new stack
-- Executing [777@ivr-1:2] Goto("SIP/sip.provider.ca-000000d5", "ext-group,7770,1") in new stack
-- Goto (ext-group,7770,1)
-- Executing [7770@ext-group:1] GotoIf("SIP/sip.provider.ca-000000d5", "0?cid") in new stack
-- Executing [7770@ext-group:2] PlayTones("SIP/sip.provider.ca-000000d5", "ring") in new stack
-- Executing [7770@ext-group:3] Progress("SIP/sip.provider.ca-000000d5", "") in new stack
-- Executing [7770@ext-group:4] Macro("SIP/sip.provider.ca-000000d5", "user-callerid,") in new stack
-- Executing [s@macro-user-callerid:1] Set("SIP/sip.provider.ca-000000d5", "TOUCH_MONITOR=1621920346.221") in new stack
-- Executing [s@macro-user-callerid:2] Set("SIP/sip.provider.ca-000000d5", "AMPUSER=1PhoneNumber") in new stack
-- Executing [s@macro-user-callerid:3] Set("SIP/sip.provider.ca-000000d5", "HOTDESCKCHAN=sip.provider.ca-000000d5") in new stack
-- Executing [s@macro-user-callerid:4] Set("SIP/sip.provider.ca-000000d5", "HOTDESKEXTEN=sip.provider.ca") in new stack
-- Executing [s@macro-user-callerid:5] Set("SIP/sip.provider.ca-000000d5", "HOTDESKCALL=0") in new stack
-- Executing [s@macro-user-callerid:6] ExecIf("SIP/sip.provider.ca-000000d5", "0?Set(HOTDESKCALL=1)") in new stack
-- Executing [s@macro-user-callerid:7] ExecIf("SIP/sip.provider.ca-000000d5", "0?Set(CALLERID(name)=)") in new stack
-- Executing [s@macro-user-callerid:8] GotoIf("SIP/sip.provider.ca-000000d5", "0?report") in new stack
-- Executing [s@macro-user-callerid:9] ExecIf("SIP/sip.provider.ca-000000d5", "1?Set(REALCALLERIDNUM=1PhoneNumber)") in new stack
-- Executing [s@macro-user-callerid:10] Set("SIP/sip.provider.ca-000000d5", "AMPUSER=") in new stack
-- Executing [s@macro-user-callerid:11] GotoIf("SIP/sip.provider.ca-000000d5", "0?limit") in new stack
-- Executing [s@macro-user-callerid:12] Set("SIP/sip.provider.ca-000000d5", "AMPUSERCIDNAME=") in new stack
-- Executing [s@macro-user-callerid:13] ExecIf("SIP/sip.provider.ca-000000d5", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
-- Executing [s@macro-user-callerid:14] GotoIf("SIP/sip.provider.ca-000000d5", "1?report") in new stack
-- Goto (macro-user-callerid,s,23)
-- Executing [s@macro-user-callerid:23] NoOp("SIP/sip.provider.ca-000000d5", "Macro Depth is 1") in new stack
-- Executing [s@macro-user-callerid:24] GotoIf("SIP/sip.provider.ca-000000d5", "1?report2:macroerror") in new stack
-- Goto (macro-user-callerid,s,25)
-- Executing [s@macro-user-callerid:25] GotoIf("SIP/sip.provider.ca-000000d5", "0?continue") in new stack
-- Executing [s@macro-user-callerid:26] ExecIf("SIP/sip.provider.ca-000000d5", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
-- Executing [s@macro-user-callerid:27] Set("SIP/sip.provider.ca-000000d5", "__TTL=64") in new stack
-- Executing [s@macro-user-callerid:28] GotoIf("SIP/sip.provider.ca-000000d5", "1?continue") in new stack
-- Goto (macro-user-callerid,s,44)
-- Executing [s@macro-user-callerid:44] Set("SIP/sip.provider.ca-000000d5", "CALLERID(number)=1PhoneNumber") in new stack
-- Executing [s@macro-user-callerid:45] Set("SIP/sip.provider.ca-000000d5", "CALLERID(name)=Name") in new stack
-- Executing [s@macro-user-callerid:46] GotoIf("SIP/sip.provider.ca-000000d5", "0?cnum") in new stack
-- Executing [s@macro-user-callerid:47] Set("SIP/sip.provider.ca-000000d5", "CDR(cnam)=Name") in new stack
-- Executing [s@macro-user-callerid:48] Set("SIP/sip.provider.ca-000000d5", "CDR(cnum)=1PhoneNumber") in new stack
-- Executing [s@macro-user-callerid:49] Set("SIP/sip.provider.ca-000000d5", "CHANNEL(language)=fr") in new stack
-- Executing [7770@ext-group:5] Macro("SIP/sip.provider.ca-000000d5", "blkvm-setifempty,") in new stack
-- Executing [s@macro-blkvm-setifempty:1] GotoIf("SIP/sip.provider.ca-000000d5", "1?init") in new stack
-- Goto (macro-blkvm-setifempty,s,4)
-- Executing [s@macro-blkvm-setifempty:4] Set("SIP/sip.provider.ca-000000d5", "__BLKVM_CHANNEL=SIP/sip.provider.ca-000000d5") in new stack
-- Executing [s@macro-blkvm-setifempty:5] Set("SIP/sip.provider.ca-000000d5", "SHARED(BLKVM,SIP/sip.provider.ca-000000d5)=TRUE") in new stack
-- Executing [s@macro-blkvm-setifempty:6] Set("SIP/sip.provider.ca-000000d5", "GOSUB_RETVAL=TRUE") in new stack
-- Executing [s@macro-blkvm-setifempty:7] MacroExit("SIP/sip.provider.ca-000000d5", "") in new stack
-- Executing [7770@ext-group:6] GotoIf("SIP/sip.provider.ca-000000d5", "1?skipov") in new stack
-- Goto (ext-group,7770,9)
-- Executing [7770@ext-group:9] Set("SIP/sip.provider.ca-000000d5", "RRNODEST=") in new stack
-- Executing [7770@ext-group:10] Set("SIP/sip.provider.ca-000000d5", "__NODEST=7770") in new stack
-- Executing [7770@ext-group:11] GosubIf("SIP/sip.provider.ca-000000d5", "0?sub-rgsetcid,s,1()") in new stack
-- Executing [7770@ext-group:12] Gosub("SIP/sip.provider.ca-000000d5", "sub-record-check,s,1(rg,7770,dontcare)") in new stack
-- Executing [s@sub-record-check:1] GotoIf("SIP/sip.provider.ca-000000d5", "0?initialized") in new stack
-- Executing [s@sub-record-check:2] Set("SIP/sip.provider.ca-000000d5", "__REC_STATUS=INITIALIZED") in new stack
-- Executing [s@sub-record-check:3] Set("SIP/sip.provider.ca-000000d5", "NOW=1621920353") in new stack
-- Executing [s@sub-record-check:4] Set("SIP/sip.provider.ca-000000d5", "__DAY=25") in new stack
-- Executing [s@sub-record-check:5] Set("SIP/sip.provider.ca-000000d5", "__MONTH=05") in new stack
-- Executing [s@sub-record-check:6] Set("SIP/sip.provider.ca-000000d5", "__YEAR=2021") in new stack
-- Executing [s@sub-record-check:7] Set("SIP/sip.provider.ca-000000d5", "__TIMESTR=20210525-052553") in new stack
-- Executing [s@sub-record-check:8] Set("SIP/sip.provider.ca-000000d5", "__FROMEXTEN=1PhoneNumber") in new stack
-- Executing [s@sub-record-check:9] Set("SIP/sip.provider.ca-000000d5", "__MON_FMT=wav") in new stack
-- Executing [s@sub-record-check:10] NoOp("SIP/sip.provider.ca-000000d5", "Recordings initialized") in new stack
-- Executing [s@sub-record-check:11] ExecIf("SIP/sip.provider.ca-000000d5", "0?Set(ARG3=dontcare)") in new stack
-- Executing [s@sub-record-check:12] Set("SIP/sip.provider.ca-000000d5", "REC_POLICY_MODE_SAVE=") in new stack
-- Executing [s@sub-record-check:13] ExecIf("SIP/sip.provider.ca-000000d5", "0?Set(REC_STATUS=NO)") in new stack
-- Executing [s@sub-record-check:14] GotoIf("SIP/sip.provider.ca-000000d5", "2?checkaction") in new stack
-- Goto (sub-record-check,s,17)
-- Executing [s@sub-record-check:17] GotoIf("SIP/sip.provider.ca-000000d5", "0?sub-record-check,rg,1") in new stack
-- Executing [s@sub-record-check:18] NoOp("SIP/sip.provider.ca-000000d5", "Generic rg Recording Check - 1PhoneNumber 7770") in new stack
-- Executing [s@sub-record-check:19] Gosub("SIP/sip.provider.ca-000000d5", "recordcheck,1(dontcare,rg,7770)") in new stack
-- Executing [recordcheck@sub-record-check:1] NoOp("SIP/sip.provider.ca-000000d5", "Starting recording check against dontcare") in new stack
-- Executing [recordcheck@sub-record-check:2] Goto("SIP/sip.provider.ca-000000d5", "dontcare") in new stack
-- Goto (sub-record-check,recordcheck,3)
-- Executing [recordcheck@sub-record-check:3] Return("SIP/sip.provider.ca-000000d5", "") in new stack
-- Executing [s@sub-record-check:20] Return("SIP/sip.provider.ca-000000d5", "") in new stack
-- Executing [7770@ext-group:13] Set("SIP/sip.provider.ca-000000d5", "RingGroupMethod=ringall") in new stack
-- Executing [7770@ext-group:14] Set("SIP/sip.provider.ca-000000d5", "__ALT_CONFIRM_MSG=") in new stack
-- Executing [7770@ext-group:15] GotoIf("SIP/sip.provider.ca-000000d5", "0?RGVQANNOUNCE:NORGVQANNOUNCE") in new stack
-- Goto (ext-group,7770,18)
-- Executing [7770@ext-group:18] Macro("SIP/sip.provider.ca-000000d5", "dial,20,TtrQ(NO_ANSWER),9977701-990777-777") in new stack
-- Executing [s@macro-dial:1] NoOp("SIP/sip.provider.ca-000000d5", "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
-- Executing [s@macro-dial:2] Set("SIP/sip.provider.ca-000000d5", "__CRM_SOURCE=1PhoneNumber") in new stack
-- Executing [s@macro-dial:3] ExecIf("SIP/sip.provider.ca-000000d5", "0?Set(ALERT_INFO=)") in new stack
-- Executing [s@macro-dial:4] ExecIf("SIP/sip.provider.ca-000000d5", "0?Set(ALERT_INFO=)") in new stack
-- Executing [s@macro-dial:5] ExecIf("SIP/sip.provider.ca-000000d5", "0?Set(ALERT_INFO=)") in new stack
-- Executing [s@macro-dial:6] ExecIf("SIP/sip.provider.ca-000000d5", "1?Set(CHANNEL(musicclass)=Company)") in new stack
-- Executing [s@macro-dial:7] AGI("SIP/sip.provider.ca-000000d5", "agi://127.0.0.1/dialparties.agi") in new stack
 agi://127.0.0.1/dialparties.agi: Starting New Dialparties.agi
 agi://127.0.0.1/dialparties.agi: Caller ID name is 'Name' number is '1PhoneNumber'
 agi://127.0.0.1/dialparties.agi: CW Ignore is: 
 agi://127.0.0.1/dialparties.agi: CF Ignore is: 
 agi://127.0.0.1/dialparties.agi: CW IN_USE/BUSY is: 1
   > agi://127.0.0.1/dialparties.agi: USE_CONFIRMATION:  'FALSE'
   > agi://127.0.0.1/dialparties.agi: RINGGROUP_INDEX:   ''
 agi://127.0.0.1/dialparties.agi: Methodology of ring is  'ringall'
-- agi://127.0.0.1/dialparties.agi: Added extension 9977701 to extension map
-- agi://127.0.0.1/dialparties.agi: Added extension 990777 to extension map
-- agi://127.0.0.1/dialparties.agi: Added extension 777 to extension map
-- agi://127.0.0.1/dialparties.agi: Extension 9977701 cf is disabled
-- agi://127.0.0.1/dialparties.agi: Extension 990777 cf is disabled
-- agi://127.0.0.1/dialparties.agi: Extension 777 cf is disabled
-- agi://127.0.0.1/dialparties.agi: Extension 9977701 do not disturb is disabled
  == agi://127.0.0.1/dialparties.agi: Extension 990777 has do not disturb enabled, or followme pre-ring returned busy
  == agi://127.0.0.1/dialparties.agi: Extension 777 has do not disturb enabled, or followme pre-ring returned busy
   > agi://127.0.0.1/dialparties.agi: extnum 9977701 has:  cw: 1; hascfb: 0 [] hascfu: 0 []
-- agi://127.0.0.1/dialparties.agi: dbset CALLTRACE/9977701 to 1PhoneNumber
-- agi://127.0.0.1/dialparties.agi: Filtered ARG3: 9977701
   > agi://127.0.0.1/dialparties.agi: NODEST: 7770 adding M(auto-blkvm) to dialopts: TtrQ(NO_ANSWER)M(auto-blkvm)
   > agi://127.0.0.1/dialparties.agi: NODEST: 7770 blkvm enabled macro already in dialopts: TtrQ(NO_ANSWER)M(auto-blkvm)
 agi://127.0.0.1/dialparties.agi: RVOL_MODE '' 
 agi://127.0.0.1/dialparties.agi: RVOL is: 
 agi://127.0.0.1/dialparties.agi: RVOLPARENT is: 
-- <SIP/sip.provider.ca-000000d5>AGI Script agi://127.0.0.1/dialparties.agi completed, returning 0
-- Executing [s@macro-dial:8] GotoIf("SIP/sip.provider.ca-000000d5", "1?normdial") in new stack
-- Goto (macro-dial,s,11)
-- Executing [s@macro-dial:11] NoOp("SIP/sip.provider.ca-000000d5", "Returned from dialparties with groups to dial") in new stack
-- Executing [s@macro-dial:12] NoOp("SIP/sip.provider.ca-000000d5", "ringall array  ") in new stack
-- Executing [s@macro-dial:13] Set("SIP/sip.provider.ca-000000d5", "__FMGL_DIAL=") in new stack
-- Executing [s@macro-dial:14] Set("SIP/sip.provider.ca-000000d5", "LOOPCNT=1") in new stack
-- Executing [s@macro-dial:15] Set("SIP/sip.provider.ca-000000d5", "ITER=1") in new stack
-- Executing [s@macro-dial:16] Set("SIP/sip.provider.ca-000000d5", "__EXTTOCALL=9977701") in new stack
-- Executing [s@macro-dial:17] NoOp("SIP/sip.provider.ca-000000d5", "Working with 9977701") in new stack
-- Executing [s@macro-dial:18] ExecIf("SIP/sip.provider.ca-000000d5", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
-- Executing [s@macro-dial:19] ExecIf("SIP/sip.provider.ca-000000d5", "0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)") in new stack
-- Executing [s@macro-dial:20] Set("SIP/sip.provider.ca-000000d5", "ITER=2") in new stack
-- Executing [s@macro-dial:21] GotoIf("SIP/sip.provider.ca-000000d5", "0?ndloopbegin") in new stack
-- Executing [s@macro-dial:22] Macro("SIP/sip.provider.ca-000000d5", "dial-ringall-predial-hook,") in new stack
-- Executing [s@macro-dial-ringall-predial-hook:1] MacroExit("SIP/sip.provider.ca-000000d5", "") in new stack
-- Executing [s@macro-dial:23] ExecIf("SIP/sip.provider.ca-000000d5", "0?Set(CWRING=r(callwaiting)):Set(CWRING=)") in new stack
-- Executing [s@macro-dial:24] ExecIf("SIP/sip.provider.ca-000000d5", "0?Set(ds=SIP/9977701,20,trQ(NO_ANSWER)M(auto-blkvm)g)") in new stack
-- Executing [s@macro-dial:25] Dial("SIP/sip.provider.ca-000000d5", "SIP/9977701,20,trQ(NO_ANSWER)M(auto-blkvm)b(func-apply-sipheaders^s^1),") in new stack
[2021-05-25 05:25:53] WARNING[26865][C-000000e7]: app_dial.c:2578 dial_exec_full: Unable to create channel of type 'SIP' (cause 20 - Subscriber absent)
-- No devices or endpoints to dial (technology/resource)
-- Executing [s@macro-dial:26] Set("SIP/sip.provider.ca-000000d5", "DIALSTATUS=CHANUNAVAIL") in new stack
-- Executing [s@macro-dial:27] GosubIf("SIP/sip.provider.ca-000000d5", "0?CHANUNAVAIL,1()") in new stack
-- Executing [s@macro-dial:28] NoOp("SIP/sip.provider.ca-000000d5", "Returning since nobody answered") in new stack
-- Executing [s@macro-dial:29] MacroExit("SIP/sip.provider.ca-000000d5", "") in new stack
-- Executing [7770@ext-group:19] Gosub("SIP/sip.provider.ca-000000d5", "sub-record-cancel,s,1()") in new stack
-- Executing [s@sub-record-cancel:1] Return("SIP/sip.provider.ca-000000d5", "") in new stack
-- Executing [7770@ext-group:20] Set("SIP/sip.provider.ca-000000d5", "RingGroupMethod=") in new stack
-- Executing [7770@ext-group:21] GotoIf("SIP/sip.provider.ca-000000d5", "0?nodest") in new stack
-- Executing [7770@ext-group:22] Set("SIP/sip.provider.ca-000000d5", "__NODEST=") in new stack
-- Executing [7770@ext-group:23] Macro("SIP/sip.provider.ca-000000d5", "blkvm-clr,") in new stack
-- Executing [s@macro-blkvm-clr:1] Set("SIP/sip.provider.ca-000000d5", "SHARED(BLKVM,SIP/sip.provider.ca-000000d5)=") in new stack
-- Executing [s@macro-blkvm-clr:2] Set("SIP/sip.provider.ca-000000d5", "GOSUB_RETVAL=") in new stack
-- Executing [s@macro-blkvm-clr:3] MacroExit("SIP/sip.provider.ca-000000d5", "") in new stack
-- Executing [7770@ext-group:24] Goto("SIP/sip.provider.ca-000000d5", "ext-local,vmb777,1") in new stack
-- Goto (ext-local,vmb777,1)
-- Executing [vmb777@ext-local:1] Macro("SIP/sip.provider.ca-000000d5", "vm,777,BUSY,") in new stack
-- Executing [s@macro-vm:1] Macro("SIP/sip.provider.ca-000000d5", "user-callerid,SKIPTTL") in new stack
-- Executing [s@macro-user-callerid:1] Set("SIP/sip.provider.ca-000000d5", "TOUCH_MONITOR=1621920346.221") in new stack
-- Executing [s@macro-user-callerid:2] Set("SIP/sip.provider.ca-000000d5", "AMPUSER=1PhoneNumber") in new stack
-- Executing [s@macro-user-callerid:3] Set("SIP/sip.provider.ca-000000d5", "HOTDESCKCHAN=sip.provider.ca-000000d5") in new stack
-- Executing [s@macro-user-callerid:4] Set("SIP/sip.provider.ca-000000d5", "HOTDESKEXTEN=sip.provider.ca") in new stack
-- Executing [s@macro-user-callerid:5] Set("SIP/sip.provider.ca-000000d5", "HOTDESKCALL=0") in new stack
-- Executing [s@macro-user-callerid:6] ExecIf("SIP/sip.provider.ca-000000d5", "0?Set(HOTDESKCALL=1)") in new stack
-- Executing [s@macro-user-callerid:7] ExecIf("SIP/sip.provider.ca-000000d5", "0?Set(CALLERID(name)=)") in new stack
-- Executing [s@macro-user-callerid:8] GotoIf("SIP/sip.provider.ca-000000d5", "0?report") in new stack
-- Executing [s@macro-user-callerid:9] ExecIf("SIP/sip.provider.ca-000000d5", "0?Set(REALCALLERIDNUM=1PhoneNumber)") in new stack
-- Executing [s@macro-user-callerid:10] Set("SIP/sip.provider.ca-000000d5", "AMPUSER=") in new stack
-- Executing [s@macro-user-callerid:11] GotoIf("SIP/sip.provider.ca-000000d5", "0?limit") in new stack
-- Executing [s@macro-user-callerid:12] Set("SIP/sip.provider.ca-000000d5", "AMPUSERCIDNAME=") in new stack
-- Executing [s@macro-user-callerid:13] ExecIf("SIP/sip.provider.ca-000000d5", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
-- Executing [s@macro-user-callerid:14] GotoIf("SIP/sip.provider.ca-000000d5", "1?report") in new stack
-- Goto (macro-user-callerid,s,23)
-- Executing [s@macro-user-callerid:23] NoOp("SIP/sip.provider.ca-000000d5", "Macro Depth is 2") in new stack
-- Executing [s@macro-user-callerid:24] GotoIf("SIP/sip.provider.ca-000000d5", "1?report2:macroerror") in new stack
-- Goto (macro-user-callerid,s,25)
-- Executing [s@macro-user-callerid:25] GotoIf("SIP/sip.provider.ca-000000d5", "1?continue") in new stack
-- Goto (macro-user-callerid,s,44)
-- Executing [s@macro-user-callerid:44] Set("SIP/sip.provider.ca-000000d5", "CALLERID(number)=1PhoneNumber") in new stack
-- Executing [s@macro-user-callerid:45] Set("SIP/sip.provider.ca-000000d5", "CALLERID(name)=Name") in new stack
-- Executing [s@macro-user-callerid:46] GotoIf("SIP/sip.provider.ca-000000d5", "0?cnum") in new stack
-- Executing [s@macro-user-callerid:47] Set("SIP/sip.provider.ca-000000d5", "CDR(cnam)=Name") in new stack
-- Executing [s@macro-user-callerid:48] Set("SIP/sip.provider.ca-000000d5", "CDR(cnum)=1PhoneNumber") in new stack
-- Executing [s@macro-user-callerid:49] Set("SIP/sip.provider.ca-000000d5", "CHANNEL(language)=fr") in new stack
-- Executing [s@macro-vm:2] Set("SIP/sip.provider.ca-000000d5", "VMGAIN=") in new stack
-- Executing [s@macro-vm:3] Macro("SIP/sip.provider.ca-000000d5", "blkvm-check,") in new stack
-- Executing [s@macro-blkvm-check:1] Set("SIP/sip.provider.ca-000000d5", "GOSUB_RETVAL=") in new stack
-- Executing [s@macro-blkvm-check:2] ExecIf("SIP/sip.provider.ca-000000d5", "0?Set(GOSUB_RETVAL=TRUE)") in new stack
-- Executing [s@macro-blkvm-check:3] MacroExit("SIP/sip.provider.ca-000000d5", "") in new stack
-- Executing [s@macro-vm:4] GotoIf("SIP/sip.provider.ca-000000d5", "1?vmx,1") in new stack
-- Goto (macro-vm,vmx,1)
-- Executing [vmx@macro-vm:1] Set("SIP/sip.provider.ca-000000d5", "__EXTTOCALL=777") in new stack
-- Executing [vmx@macro-vm:2] Set("SIP/sip.provider.ca-000000d5", "__CRM_VOICEMAIL=777") in new stack
-- Executing [vmx@macro-vm:3] Set("SIP/sip.provider.ca-000000d5", "MEXTEN=777") in new stack
-- Executing [vmx@macro-vm:4] Set("SIP/sip.provider.ca-000000d5", "MMODE=BUSY") in new stack
-- Executing [vmx@macro-vm:5] Set("SIP/sip.provider.ca-000000d5", "RETVM=") in new stack
-- Executing [vmx@macro-vm:6] Set("SIP/sip.provider.ca-000000d5", "MODE=busy") in new stack
-- Executing [vmx@macro-vm:7] Macro("SIP/sip.provider.ca-000000d5", "get-vmcontext,777") in new stack
-- Executing [s@macro-get-vmcontext:1] Set("SIP/sip.provider.ca-000000d5", "VMCONTEXT=default") in new stack
-- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/sip.provider.ca-000000d5", "0?200:300") in new stack
-- Goto (macro-get-vmcontext,s,300)
-- Executing [s@macro-get-vmcontext:300] NoOp("SIP/sip.provider.ca-000000d5", "") in new stack
-- Executing [vmx@macro-vm:8] Set("SIP/sip.provider.ca-000000d5", "MODE=busy") in new stack
-- Executing [vmx@macro-vm:9] NoOp("SIP/sip.provider.ca-000000d5", "MODE IS: busy") in new stack
-- Executing [vmx@macro-vm:10] GotoIf("SIP/sip.provider.ca-000000d5", "1?chknomsg") in new stack
-- Goto (macro-vm,vmx,12)
-- Executing [vmx@macro-vm:12] GotoIf("SIP/sip.provider.ca-000000d5", "0?s-BUSY,1") in new stack
-- Executing [vmx@macro-vm:13] GotoIf("SIP/sip.provider.ca-000000d5", "1?notdirect") in new stack
-- Goto (macro-vm,vmx,15)
-- Executing [vmx@macro-vm:15] NoOp("SIP/sip.provider.ca-000000d5", "Checking if ext 777 is enabled: blocked") in new stack
-- Executing [vmx@macro-vm:16] GotoIf("SIP/sip.provider.ca-000000d5", "1?s-BUSY,1") in new stack
-- Goto (macro-vm,s-BUSY,1)
-- Executing [s-BUSY@macro-vm:1] NoOp("SIP/sip.provider.ca-000000d5", "BUSY voicemail") in new stack
-- Executing [s-BUSY@macro-vm:2] Macro("SIP/sip.provider.ca-000000d5", "get-vmcontext,777") in new stack
-- Executing [s@macro-get-vmcontext:1] Set("SIP/sip.provider.ca-000000d5", "VMCONTEXT=default") in new stack
-- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/sip.provider.ca-000000d5", "0?200:300") in new stack
-- Goto (macro-get-vmcontext,s,300)
-- Executing [s@macro-get-vmcontext:300] NoOp("SIP/sip.provider.ca-000000d5", "") in new stack
-- Executing [s-BUSY@macro-vm:3] VoiceMail("SIP/sip.provider.ca-000000d5", "777@default,b") in new stack
-- <SIP/sip.provider.ca-000000d5> Playing '/var/spool/asterisk/voicemail/default/777/busy.slin' (language 'fr')

Nucleus · May 25, 2021, 5:43am

Just a thought, I think extension 990777 is stuck in DND.

I don’t see it on the phone.

I don’t know how to change it either.

Is there a way to see which extensions are in DND?

Also, is there a way to remove DND from an extension from the GUI or CLI?

Thank you!

lgaetz · May 25, 2021, 11:01am

Inbound calls arrive at the chan_sip port as anonymous, so either the trunk is misconfigured or using wrong driver for pjsip.

  == agi://127.0.0.1/dialparties.agi: Extension 990777 has do not disturb enabled, or followme pre-ring returned busy

^^ FreePBX thinks 990777 is set to DND, are you able to receive a call on that extension locally from another phone?

Nucleus · May 26, 2021, 3:44am

Well that was my “response” above.

I suspect the extension is stuck in DND

I don’t know how to deal with that.

How can I see the extensions that are in DND from FreePBX GUI or CLI?

And how can I remove the DND status?

Stewart1 · May 26, 2021, 4:04am

In Applications → Extensions, the DND column will be checked if in DND.

At the Asterisk command prompt,
database del DND 990777
(Not a complete removal, but should allow the extension to receive calls.)

Nucleus · May 26, 2021, 10:54pm

Thank you so much @Stewart1!

It worked!

Can you tell me what you mean by “not a complete removal” ?

Stewart1 · May 26, 2021, 11:04pm

Sorry that I don’t know the details, but if you look at what appears in the Asterisk log when you dial *79 (DND deactivate), you’ll see several other variables modified. These may affect how a BLF key for the extension appears on another phone, behavior if the extension is an agent in a queue, what happens if you toggle DND from the extension, etc.

Nucleus · May 27, 2021, 1:30am

Ok then I guess I’ll ask all users to dial the *79 on the extension as well, for good measure.

Thanks @Stewart1!

system · June 3, 2021, 11:14pm

This topic was automatically closed 7 days after the last reply. New replies are no longer allowed.