1 extension out of 12 not able to call extension

Rogue · December 27, 2023, 10:45pm

Hello,

FreePBX 14.0.16.13
Current Asterisk Version: 13.38.3

I have a client who has 12 extensions and everything has worked great for years. It was brought to my attention today that 1 extension (5005) is unable to call to extension 5002. The call will go directly to voicemail every time.

I preformed a fwconsole restart. I power cycled both phones 5005, 5002.
5005 is not in DND.
Not likely, but I used *31 from extension 5005 to remove 5002 from the blacklist.

In the CDR it is showing that 5005 does call to 5002, but it is “busy” every time.

Not sure what is going on as every other extension on the system has no issue calling to extension 5002.

Thinking about blowing 5005 and 5002 out of endpoint manager and rebuilding?

jcolp · December 27, 2023, 11:55pm

An Asterisk log[1] will show exactly what is happening during the call. Could be that they’re on a block list on the endpoint itself and it is rejecting the call attempt from that.

[1] https://sangomakb.atlassian.net/wiki/spaces/SS/pages/31162494/Providing+Great+Debug

Rogue · December 28, 2023, 8:07pm

Thank very much for your reply. And, you are absolutly correct in kindly suggesting to post something log related. I am on site today and have pulled an asterisk call log:

Executing [s@macro-exten-vm:12] ExecIf(“PJSIP/5005-00000038”, “0?Macro(vm,5002,DIRECTDIAL,)”) in new stack
– Executing [s@macro-exten-vm:13] ExecIf(“PJSIP/5005-00000038”, “0?MacroExit()”) in new stack
– Executing [s@macro-exten-vm:14] ExecIf(“PJSIP/5005-00000038”, “0?Gosub(ext-intercom,*805002,1())”) in new stack
– Executing [s@macro-exten-vm:15] ExecIf(“PJSIP/5005-00000038”, “0?MacroExit()”) in new stack
– Executing [s@macro-exten-vm:16] ExecIf(“PJSIP/5005-00000038”, “0?ChanSpy(PJSIP/5002,q)”) in new stack
– Executing [s@macro-exten-vm:17] ExecIf(“PJSIP/5005-00000038”, “0?MacroExit()”) in new stack
– Executing [s@macro-exten-vm:18] Gosub(“PJSIP/5005-00000038”, “sub-record-check,s,1(exten,5002,dontcare)”) in new stack
– Executing [s@sub-record-check:1] GotoIf(“PJSIP/5005-00000038”, “0?initialized”) in new stack
– Executing [s@sub-record-check:2] Set(“PJSIP/5005-00000038”, “__REC_STATUS=INITIALIZED”) in new stack
– Executing [s@sub-record-check:3] Set(“PJSIP/5005-00000038”, “NOW=1703792793”) in new stack
– Executing [s@sub-record-check:4] Set(“PJSIP/5005-00000038”, “__DAY=28”) in new stack
– Executing [s@sub-record-check:5] Set(“PJSIP/5005-00000038”, “__MONTH=12”) in new stack
– Executing [s@sub-record-check:6] Set(“PJSIP/5005-00000038”, “__YEAR=2023”) in new stack
– Executing [s@sub-record-check:7] Set(“PJSIP/5005-00000038”, “__TIMESTR=20231228-124633”) in new stack
– Executing [s@sub-record-check:8] Set(“PJSIP/5005-00000038”, “__FROMEXTEN=5005”) in new stack
– Executing [s@sub-record-check:9] Set(“PJSIP/5005-00000038”, “__MON_FMT=wav”) in new stack
– Executing [s@sub-record-check:10] NoOp(“PJSIP/5005-00000038”, “Recordings initialized”) in new stack
– Executing [s@sub-record-check:11] ExecIf(“PJSIP/5005-00000038”, “0?Set(ARG3=dontcare)”) in new stack
– Executing [s@sub-record-check:12] Set(“PJSIP/5005-00000038”, “REC_POLICY_MODE_SAVE=”) in new stack
– Executing [s@sub-record-check:13] ExecIf(“PJSIP/5005-00000038”, “0?Set(REC_STATUS=NO)”) in new stack
– Executing [s@sub-record-check:14] GotoIf(“PJSIP/5005-00000038”, “5?checkaction”) in new stack
– Goto (sub-record-check,s,17)
– Executing [s@sub-record-check:17] GotoIf(“PJSIP/5005-00000038”, “1?sub-record-check,exten,1”) in new stack
– Goto (sub-record-check,exten,1)
– Executing [exten@sub-record-check:1] NoOp(“PJSIP/5005-00000038”, “Exten Recording Check between 5005 and 5002”) in new stack
– Executing [exten@sub-record-check:2] Set(“PJSIP/5005-00000038”, “CALLTYPE=internal”) in new stack
– Executing [exten@sub-record-check:3] ExecIf(“PJSIP/5005-00000038”, “0?Set(CALLTYPE=)”) in new stack
– Executing [exten@sub-record-check:4] Set(“PJSIP/5005-00000038”, “CALLEE=force”) in new stack
– Executing [exten@sub-record-check:5] ExecIf(“PJSIP/5005-00000038”, “0?Set(CALLEE=dontcare)”) in new stack
– Executing [exten@sub-record-check:6] GotoIf(“PJSIP/5005-00000038”, “0?callee”) in new stack
– Executing [exten@sub-record-check:7] GotoIf(“PJSIP/5005-00000038”, “0?caller”) in new stack
– Executing [exten@sub-record-check:8] ExecIf(“PJSIP/5005-00000038”, “2?Set(CALLER_PRI=10):Set(CALLER_PRI=0)”) in new stack
– Executing [exten@sub-record-check:9] ExecIf(“PJSIP/5005-00000038”, “2?Set(CALLEE_PRI=10):Set(CALLEE_PRI=0)”) in new stack
– Executing [exten@sub-record-check:10] GotoIf(“PJSIP/5005-00000038”, “1?caller:callee”) in new stack
– Goto (sub-record-check,exten,13)
– Executing [exten@sub-record-check:13] Set(“PJSIP/5005-00000038”, “RECMODE=force”) in new stack
– Executing [exten@sub-record-check:14] Set(“PJSIP/5005-00000038”, “CALLERRECMODE=force”) in new stack
– Executing [exten@sub-record-check:15] Set(“PJSIP/5005-00000038”, “CALEERECMODE=force”) in new stack
– Executing [exten@sub-record-check:16] GotoIf(“PJSIP/5005-00000038”, “0?processnormal”) in new stack
– Executing [exten@sub-record-check:17] ExecIf(“PJSIP/5005-00000038”, “0?Set(RECMODE=force)”) in new stack
– Executing [exten@sub-record-check:18] ExecIf(“PJSIP/5005-00000038”, “0?Set(RECMODE=force)”) in new stack
– Executing [exten@sub-record-check:19] ExecIf(“PJSIP/5005-00000038”, “0?Set(RECMODE=force)”) in new stack
– Executing [exten@sub-record-check:20] ExecIf(“PJSIP/5005-00000038”, “0?Set(RECMODE=force)”) in new stack
– Executing [exten@sub-record-check:21] ExecIf(“PJSIP/5005-00000038”, “0?Set(RECMODE=dontcare)”) in new stack
– Executing [exten@sub-record-check:22] ExecIf(“PJSIP/5005-00000038”, “0?Set(RECMODE=force)”) in new stack
– Executing [exten@sub-record-check:23] Gosub(“PJSIP/5005-00000038”, “recordcheck,1(force,internal,5002)”) in new stack
– Executing [recordcheck@sub-record-check:1] NoOp(“PJSIP/5005-00000038”, “Starting recording check against force”) in new stack
– Executing [recordcheck@sub-record-check:2] Goto(“PJSIP/5005-00000038”, “force”) in new stack
– Goto (sub-record-check,recordcheck,5)
– Executing [recordcheck@sub-record-check:5] Set(“PJSIP/5005-00000038”, “__REC_POLICY_MODE=FORCE”) in new stack
– Executing [recordcheck@sub-record-check:6] GotoIf(“PJSIP/5005-00000038”, “1?startrec”) in new stack
– Goto (sub-record-check,recordcheck,16)
– Executing [recordcheck@sub-record-check:16] NoOp(“PJSIP/5005-00000038”, “Starting recording: internal, 5002”) in new stack
– Executing [recordcheck@sub-record-check:17] Set(“PJSIP/5005-00000038”, “__CALLFILENAME=internal-5002-5005-20231228-124633-1703792793.62”) in new stack
– Executing [recordcheck@sub-record-check:18] MixMonitor(“PJSIP/5005-00000038”, “2023/12/28/internal-5002-5005-20231228-124633-1703792793.62.wav,abi(LOCAL_MIXMON_ID),”) in new stack
– Executing [recordcheck@sub-record-check:19] Set(“PJSIP/5005-00000038”, “__MIXMON_ID=0x7f75fc0c5020”) in new stack
– Executing [recordcheck@sub-record-check:20] Set(“PJSIP/5005-00000038”, “__RECORD_ID=PJSIP/5005-00000038”) in new stack
– Executing [recordcheck@sub-record-check:21] Set(“PJSIP/5005-00000038”, “__REC_STATUS=RECORDING”) in new stack
– Executing [recordcheck@sub-record-check:22] Set(“PJSIP/5005-00000038”, “CDR(recordingfile)=internal-5002-5005-20231228-124633-1703792793.62.wav”) in new stack
– Executing [recordcheck@sub-record-check:23] Return(“PJSIP/5005-00000038”, “”) in new stack
– Executing [exten@sub-record-check:24] Return(“PJSIP/5005-00000038”, “”) in new stack
– Executing [s@macro-exten-vm:19] GotoIf(“PJSIP/5005-00000038”, “1?macrodial”) in new stack
– Goto (macro-exten-vm,s,25)
– Executing [s@macro-exten-vm:25] GosubIf(“PJSIP/5005-00000038”, “0?clrheader,1()”) in new stack
– Executing [s@macro-exten-vm:26] Macro(“PJSIP/5005-00000038”, “dial-one,20,HhTtr,5002”) in new stack
– Executing [s@macro-dial-one:1] Set(“PJSIP/5005-00000038”, “DEXTEN=5002”) in new stack
– Executing [s@macro-dial-one:2] Set(“PJSIP/5005-00000038”, “__CRM_SOURCE=5005”) in new stack
– Executing [s@macro-dial-one:3] ExecIf(“PJSIP/5005-00000038”, “0?Set(__EXTTOCALL=5002)”) in new stack
– Executing [s@macro-dial-one:4] Set(“PJSIP/5005-00000038”, “DIALSTATUS_CW=”) in new stack
– Executing [s@macro-dial-one:5] GosubIf(“PJSIP/5005-00000038”, “0?screen,1()”) in new stack
– Executing [s@macro-dial-one:6] GosubIf(“PJSIP/5005-00000038”, “0?cf,1()”) in new stack
– Executing [s@macro-dial-one:7] GotoIf(“PJSIP/5005-00000038”, “1?skip1”) in new stack
– Goto (macro-dial-one,s,10)
– Executing [s@macro-dial-one:10] GotoIf(“PJSIP/5005-00000038”, “0?nodial”) in new stack
– Executing [s@macro-dial-one:11] GotoIf(“PJSIP/5005-00000038”, “0?continue”) in new stack
– Executing [s@macro-dial-one:12] Set(“PJSIP/5005-00000038”, “EXTHASCW=ENABLED”) in new stack
– Executing [s@macro-dial-one:13] GotoIf(“PJSIP/5005-00000038”, “0?next1:cwinusebusy”) in new stack
– Goto (macro-dial-one,s,25)
– Executing [s@macro-dial-one:25] GotoIf(“PJSIP/5005-00000038”, “0?next3:continue”) in new stack
– Goto (macro-dial-one,s,27)
– Executing [s@macro-dial-one:27] GotoIf(“PJSIP/5005-00000038”, “0?nodial”) in new stack
– Executing [s@macro-dial-one:28] GosubIf(“PJSIP/5005-00000038”, “1?dstring,1():dlocal,1()”) in new stack
– Executing [dstring@macro-dial-one:1] Set(“PJSIP/5005-00000038”, “DSTRING=”) in new stack
– Executing [dstring@macro-dial-one:2] Set(“PJSIP/5005-00000038”, “DEVICES=5002”) in new stack
– Executing [dstring@macro-dial-one:3] ExecIf(“PJSIP/5005-00000038”, “0?Return()”) in new stack
– Executing [dstring@macro-dial-one:4] ExecIf(“PJSIP/5005-00000038”, “0?Set(DEVICES=002)”) in new stack
– Executing [dstring@macro-dial-one:5] Set(“PJSIP/5005-00000038”, “LOOPCNT=1”) in new stack
– Executing [dstring@macro-dial-one:6] Set(“PJSIP/5005-00000038”, “ITER=1”) in new stack
– Executing [dstring@macro-dial-one:7] Set(“PJSIP/5005-00000038”, “THISDIAL=PJSIP/5002”) in new stack
– Executing [dstring@macro-dial-one:8] GosubIf(“PJSIP/5005-00000038”, “1?zap2dahdi,1()”) in new stack
– Executing [zap2dahdi@macro-dial-one:1] ExecIf(“PJSIP/5005-00000038”, “0?Return()”) in new stack
– Executing [zap2dahdi@macro-dial-one:2] Set(“PJSIP/5005-00000038”, “NEWDIAL=”) in new stack
– Executing [zap2dahdi@macro-dial-one:3] Set(“PJSIP/5005-00000038”, “LOOPCNT2=1”) in new stack
– Executing [zap2dahdi@macro-dial-one:4] Set(“PJSIP/5005-00000038”, “ITER2=1”) in new stack
– Executing [zap2dahdi@macro-dial-one:5] Set(“PJSIP/5005-00000038”, “THISPART2=PJSIP/5002”) in new stack
– Executing [zap2dahdi@macro-dial-one:6] ExecIf(“PJSIP/5005-00000038”, “0?Set(THISPART2=DAHDIIP/5002)”) in new stack
– Executing [zap2dahdi@macro-dial-one:7] Set(“PJSIP/5005-00000038”, “NEWDIAL=PJSIP/5002&”) in new stack
– Executing [zap2dahdi@macro-dial-one:8] Set(“PJSIP/5005-00000038”, “ITER2=2”) in new stack
– Executing [zap2dahdi@macro-dial-one:9] GotoIf(“PJSIP/5005-00000038”, “0?begin2”) in new stack
– Executing [zap2dahdi@macro-dial-one:10] Set(“PJSIP/5005-00000038”, “THISDIAL=PJSIP/5002”) in new stack
– Executing [zap2dahdi@macro-dial-one:11] Return(“PJSIP/5005-00000038”, “”) in new stack
– Executing [dstring@macro-dial-one:9] GotoIf(“PJSIP/5005-00000038”, “0?docheck”) in new stack
– Executing [dstring@macro-dial-one:10] NoOp(“PJSIP/5005-00000038”, “Debug: Found PJSIP Destination PJSIP/5002”) in new stack
– Executing [dstring@macro-dial-one:11] GotoIf(“PJSIP/5005-00000038”, “0?doset”) in new stack
– Executing [dstring@macro-dial-one:12] NoOp(“PJSIP/5005-00000038”, “Debug: Updating PJSIP Destination with PJSIP_DIAL_CONTACTS”) in new stack
– Executing [dstring@macro-dial-one:13] Set(“PJSIP/5005-00000038”, “THISDIAL=PJSIP/5002/sip:[email protected]:1024;x-ast-orig-host=192.168.1.58:5060”) in new stack
– Executing [dstring@macro-dial-one:14] ExecIf(“PJSIP/5005-00000038”, “0?Set(DIALSTATUS=CHANUNAVAIL)”) in new stack
– Executing [dstring@macro-dial-one:15] GotoIf(“PJSIP/5005-00000038”, “0?skipset”) in new stack
– Executing [dstring@macro-dial-one:16] Set(“PJSIP/5005-00000038”, “DSTRING=PJSIP/5002/sip:[email protected]:1024;x-ast-orig-host=192.168.1.58:5060&”) in new stack
– Executing [dstring@macro-dial-one:17] Set(“PJSIP/5005-00000038”, “ITER=2”) in new stack
– Executing [dstring@macro-dial-one:18] GotoIf(“PJSIP/5005-00000038”, “0?begin”) in new stack
– Executing [dstring@macro-dial-one:19] ExecIf(“PJSIP/5005-00000038”, “0?Return()”) in new stack
– Executing [dstring@macro-dial-one:20] Set(“PJSIP/5005-00000038”, “DSTRING=PJSIP/5002/sip:[email protected]:1024;x-ast-orig-host=192.168.1.58:5060”) in new stack
– Executing [dstring@macro-dial-one:21] Return(“PJSIP/5005-00000038”, “”) in new stack
– Executing [s@macro-dial-one:29] GotoIf(“PJSIP/5005-00000038”, “0?nodial”) in new stack
– Executing [s@macro-dial-one:30] GotoIf(“PJSIP/5005-00000038”, “0?skiptrace”) in new stack
– Executing [s@macro-dial-one:31] GosubIf(“PJSIP/5005-00000038”, “1?ctset,1():ctclear,1()”) in new stack
– Executing [ctset@macro-dial-one:1] Set(“PJSIP/5005-00000038”, “DB(CALLTRACE/5002)=5005”) in new stack
– Executing [ctset@macro-dial-one:2] Return(“PJSIP/5005-00000038”, “”) in new stack
– Executing [s@macro-dial-one:32] Set(“PJSIP/5005-00000038”, “D_OPTIONS=HhTtr”) in new stack
– Executing [s@macro-dial-one:33] GosubIf(“PJSIP/5005-00000038”, “0?func-set-sipheader,s,1(Alert-Info,)”) in new stack
– Executing [s@macro-dial-one:34] NoOp(“PJSIP/5005-00000038”, "Blind Transfer: , Attended Transfer: , User: 5005, Alert Info: ") in new stack
– Executing [s@macro-dial-one:35] ExecIf(“PJSIP/5005-00000038”, “1?Set(ALERT_INFO=)”) in new stack
– Executing [s@macro-dial-one:36] ExecIf(“PJSIP/5005-00000038”, “0?Set(ALERT_INFO=)”) in new stack
– Executing [s@macro-dial-one:37] ExecIf(“PJSIP/5005-00000038”, “0?Set(ALERT_INFO=)”) in new stack
– Executing [s@macro-dial-one:38] ExecIf(“PJSIP/5005-00000038”, “0?Set(ALERT_INFO=Normal;volume=)”) in new stack
– Executing [s@macro-dial-one:39] ExecIf(“PJSIP/5005-00000038”, “0?Set(ALERT_INFO=Normal;volume=)”) in new stack
– Executing [s@macro-dial-one:40] GosubIf(“PJSIP/5005-00000038”, “0?func-set-sipheader,s,1(Alert-Info,)”) in new stack
– Executing [s@macro-dial-one:41] ExecIf(“PJSIP/5005-00000038”, “0?Set(CHANNEL(musicclass)=)”) in new stack
– Executing [s@macro-dial-one:42] GosubIf(“PJSIP/5005-00000038”, “0?qwait,1()”) in new stack
– Executing [s@macro-dial-one:43] Set(“PJSIP/5005-00000038”, “__CWIGNORE=”) in new stack
– Executing [s@macro-dial-one:44] Set(“PJSIP/5005-00000038”, “__KEEPCID=TRUE”) in new stack
– Executing [s@macro-dial-one:45] GotoIf(“PJSIP/5005-00000038”, “0?usegoto,1”) in new stack
– Executing [s@macro-dial-one:46] GotoIf(“PJSIP/5005-00000038”, “0?godial”) in new stack
– Executing [s@macro-dial-one:47] Gosub(“PJSIP/5005-00000038”, “sub-presencestate-display,s,1(5002)”) in new stack
– Executing [s@sub-presencestate-display:1] Goto(“PJSIP/5005-00000038”, “state-available,1”) in new stack
– Goto (sub-presencestate-display,state-available,1)
– Executing [state-available@sub-presencestate-display:1] Set(“PJSIP/5005-00000038”, “PRESENCESTATE_DISPLAY=(Available)”) in new stack
– Executing [state-available@sub-presencestate-display:2] Return(“PJSIP/5005-00000038”, “”) in new stack
– Executing [s@macro-dial-one:48] Set(“PJSIP/5005-00000038”, “CONNECTEDLINE(name,i)=Allen(Available)”) in new stack
– Executing [s@macro-dial-one:49] Set(“PJSIP/5005-00000038”, “CONNECTEDLINE(num)=5002”) in new stack
– Executing [s@macro-dial-one:50] Set(“PJSIP/5005-00000038”, “D_OPTIONS=HhTtr”) in new stack
– Executing [s@macro-dial-one:51] Macro(“PJSIP/5005-00000038”, “dialout-one-predial-hook,”) in new stack
– Executing [s@macro-dialout-one-predial-hook:1] MacroExit(“PJSIP/5005-00000038”, “”) in new stack
– Executing [s@macro-dial-one:52] ExecIf(“PJSIP/5005-00000038”, “0?Set(D_OPTIONS=HhtrI)”) in new stack
– Executing [s@macro-dial-one:53] NoOp(“PJSIP/5005-00000038”, “”) in new stack
– Executing [s@macro-dial-one:54] ExecIf(“PJSIP/5005-00000038”, “0?Set(D_OPTIONS=HhTtrg)”) in new stack
– Executing [s@macro-dial-one:55] Dial(“PJSIP/5005-00000038”, “PJSIP/5002/sip:[email protected]:1024;x-ast-orig-host=192.168.1.58:5060,20,HhTtrb(func-apply-sipheaders^s^1)”) in new stack
– PJSIP/5002-00000039 Internal Gosub(func-apply-sipheaders,s,1) start
– Executing [s@func-apply-sipheaders:1] ExecIf(“PJSIP/5002-00000039”, “0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)”) in new stack
– Executing [s@func-apply-sipheaders:2] NoOp(“PJSIP/5002-00000039”, “Applying SIP Headers to channel PJSIP/5002-00000039”) in new stack
– Executing [s@func-apply-sipheaders:3] Set(“PJSIP/5002-00000039”, “TECH=PJSIP”) in new stack
– Executing [s@func-apply-sipheaders:4] Set(“PJSIP/5002-00000039”, “SIPHEADERKEYS=”) in new stack
– Executing [s@func-apply-sipheaders:5] While(“PJSIP/5002-00000039”, “0”) in new stack
– Jumping to priority 11
– Executing [s@func-apply-sipheaders:12] Return(“PJSIP/5002-00000039”, “”) in new stack
== Spawn extension (from-internal, 5002, 1) exited non-zero on ‘PJSIP/5002-00000039’
– PJSIP/5002-00000039 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
== Begin MixMonitor Recording PJSIP/5005-00000038
– Called PJSIP/5002/sip:[email protected]:1024;x-ast-orig-host=192.168.1.58:5060
== Using SIP RTP Audio TOS bits 184
== Using SIP RTP Audio TOS bits 184 in TCLASS field.
== Using SIP RTP Audio CoS mark 5
== Everyone is busy/congested at this time (1:1/0/0)
– Executing [s@macro-dial-one:56] ExecIf(“PJSIP/5005-00000038”, “0?MacroExit()”) in new stack
– Executing [s@macro-dial-one:57] ExecIf(“PJSIP/5005-00000038”, “0?Set(DIALSTATUS=)”) in new stack
– Executing [s@macro-dial-one:58] GosubIf(“PJSIP/5005-00000038”, “0?s-BUSY,1()”) in new stack
– Executing [s@macro-dial-one:59] MacroExit(“PJSIP/5005-00000038”, “”) in new stack
– Executing [s@macro-exten-vm:27] Set(“PJSIP/5005-00000038”, “SV_DIALSTATUS=BUSY”) in new stack
– Executing [s@macro-exten-vm:28] GosubIf(“PJSIP/5005-00000038”, “0?docfu,1()”) in new stack
– Executing [s@macro-exten-vm:29] GosubIf(“PJSIP/5005-00000038”, “0?docfb,1()”) in new stack
– Executing [s@macro-exten-vm:30] Set(“PJSIP/5005-00000038”, “DIALSTATUS=BUSY”) in new stack
– Executing [s@macro-exten-vm:31] ExecIf(“PJSIP/5005-00000038”, “0?MacroExit()”) in new stack
– Executing [s@macro-exten-vm:32] GotoIf(“PJSIP/5005-00000038”, “0?s-BUSY,1”) in new stack
– Executing [s@macro-exten-vm:33] Macro(“PJSIP/5005-00000038”, “vm,5002,BUSY,”) in new stack
– Executing [s@macro-vm:1] Macro(“PJSIP/5005-00000038”, “user-callerid,SKIPTTL”) in new stack
– Executing [s@macro-user-callerid:1] Set(“PJSIP/5005-00000038”, “TOUCH_MONITOR=1703792793.62”) in new stack
– Executing [s@macro-user-callerid:2] Set(“PJSIP/5005-00000038”, “AMPUSER=5005”) in new stack
– Executing [s@macro-user-callerid:3] Set(“PJSIP/5005-00000038”, “HOTDESCKCHAN=5005-00000038”) in new stack
– Executing [s@macro-user-callerid:4] Set(“PJSIP/5005-00000038”, “HOTDESKEXTEN=5005”) in new stack
– Executing [s@macro-user-callerid:5] Set(“PJSIP/5005-00000038”, “HOTDESKCALL=0”) in new stack
– Executing [s@macro-user-callerid:6] ExecIf(“PJSIP/5005-00000038”, “0?Set(HOTDESKCALL=1)”) in new stack
– Executing [s@macro-user-callerid:7] ExecIf(“PJSIP/5005-00000038”, “0?Set(CALLERID(name)=)”) in new stack
– Executing [s@macro-user-callerid:8] GotoIf(“PJSIP/5005-00000038”, “7?report”) in new stack
– Goto (macro-user-callerid,s,29)
– Executing [s@macro-user-callerid:29] NoOp(“PJSIP/5005-00000038”, “Macro Depth is 3”) in new stack
– Executing [s@macro-user-callerid:30] GotoIf(“PJSIP/5005-00000038”, “1?report2:macroerror”) in new stack
– Goto (macro-user-callerid,s,31)
– Executing [s@macro-user-callerid:31] GotoIf(“PJSIP/5005-00000038”, “1?continue”) in new stack
– Goto (macro-user-callerid,s,50)
– Executing [s@macro-user-callerid:50] Set(“PJSIP/5005-00000038”, “CALLERID(number)=5005”) in new stack
– Executing [s@macro-user-callerid:51] Set(“PJSIP/5005-00000038”, “CALLERID(name)=Lindita”) in new stack
– Executing [s@macro-user-callerid:52] GotoIf(“PJSIP/5005-00000038”, “0?cnum”) in new stack
– Executing [s@macro-user-callerid:53] Set(“PJSIP/5005-00000038”, “CDR(cnam)=Lindita”) in new stack
– Executing [s@macro-user-callerid:54] Set(“PJSIP/5005-00000038”, “CDR(cnum)=5005”) in new stack
– Executing [s@macro-user-callerid:55] Set(“PJSIP/5005-00000038”, “CHANNEL(language)=en”) in new stack
– Executing [s@macro-vm:2] Set(“PJSIP/5005-00000038”, “VMGAIN=”) in new stack
– Executing [s@macro-vm:3] Macro(“PJSIP/5005-00000038”, “blkvm-check,”) in new stack
– Executing [s@macro-blkvm-check:1] Set(“PJSIP/5005-00000038”, “GOSUB_RETVAL=”) in new stack
– Executing [s@macro-blkvm-check:2] ExecIf(“PJSIP/5005-00000038”, “0?Set(GOSUB_RETVAL=TRUE)”) in new stack
– Executing [s@macro-blkvm-check:3] MacroExit(“PJSIP/5005-00000038”, “”) in new stack
– Executing [s@macro-vm:4] GotoIf(“PJSIP/5005-00000038”, “1?vmx,1”) in new stack
– Goto (macro-vm,vmx,1)
– Executing [vmx@macro-vm:1] Set(“PJSIP/5005-00000038”, “__EXTTOCALL=5002”) in new stack
– Executing [vmx@macro-vm:2] Set(“PJSIP/5005-00000038”, “__CRM_VOICEMAIL=5002”) in new stack
– Executing [vmx@macro-vm:3] Set(“PJSIP/5005-00000038”, “MEXTEN=5002”) in new stack
– Executing [vmx@macro-vm:4] Set(“PJSIP/5005-00000038”, “MMODE=BUSY”) in new stack
– Executing [vmx@macro-vm:5] Set(“PJSIP/5005-00000038”, “RETVM=”) in new stack
– Executing [vmx@macro-vm:6] Set(“PJSIP/5005-00000038”, “MODE=busy”) in new stack
– Executing [vmx@macro-vm:7] Macro(“PJSIP/5005-00000038”, “get-vmcontext,5002”) in new stack
– Executing [s@macro-get-vmcontext:1] Set(“PJSIP/5005-00000038”, “VMCONTEXT=default”) in new stack
– Executing [s@macro-get-vmcontext:2] GotoIf(“PJSIP/5005-00000038”, “0?200:300”) in new stack
– Goto (macro-get-vmcontext,s,300)
– Executing [s@macro-get-vmcontext:300] NoOp(“PJSIP/5005-00000038”, “”) in new stack
– Executing [vmx@macro-vm:8] Set(“PJSIP/5005-00000038”, “MODE=busy”) in new stack
– Executing [vmx@macro-vm:9] NoOp(“PJSIP/5005-00000038”, “MODE IS: busy”) in new stack
– Executing [vmx@macro-vm:10] GotoIf(“PJSIP/5005-00000038”, “1?chknomsg”) in new stack
– Goto (macro-vm,vmx,12)
– Executing [vmx@macro-vm:12] GotoIf(“PJSIP/5005-00000038”, “0?s-BUSY,1”) in new stack
– Executing [vmx@macro-vm:13] GotoIf(“PJSIP/5005-00000038”, “1?notdirect”) in new stack
– Goto (macro-vm,vmx,15)
– Executing [vmx@macro-vm:15] NoOp(“PJSIP/5005-00000038”, "Checking if ext 5002 is enabled: ") in new stack
– Executing [vmx@macro-vm:16] GotoIf(“PJSIP/5005-00000038”, “1?s-BUSY,1”) in new stack
– Goto (macro-vm,s-BUSY,1)
– Executing [s-BUSY@macro-vm:1] NoOp(“PJSIP/5005-00000038”, “BUSY voicemail”) in new stack
– Executing [s-BUSY@macro-vm:2] Macro(“PJSIP/5005-00000038”, “get-vmcontext,5002”) in new stack
– Executing [s@macro-get-vmcontext:1] Set(“PJSIP/5005-00000038”, “VMCONTEXT=default”) in new stack
– Executing [s@macro-get-vmcontext:2] GotoIf(“PJSIP/5005-00000038”, “0?200:300”) in new stack
– Goto (macro-get-vmcontext,s,300)
– Executing [s@macro-get-vmcontext:300] NoOp(“PJSIP/5005-00000038”, “”) in new stack
– Executing [s-BUSY@macro-vm:3] VoiceMail(“PJSIP/5005-00000038”, “5002@default,b”) in new stack
> 0x7f75f802c4b0 – Strict RTP learning after remote address set to: 192.168.1.49:12758

Rogue · December 28, 2023, 8:09pm

The above is an attempt to call extension 5002 from 5005

jcolp · December 28, 2023, 9:50pm

A call attempt was made to 5002, and it immediately failed. The output of an attempt with “pjsip set logger on” done on the Asterisk console would confirm the response from 5002, but even without that since the attempt was made I’d say check 5002 itself.

Rogue · December 28, 2023, 10:28pm

Thank you!

Interesting. I don’t specifically know what to check on 5002 as every other phone in the building can call 5002 except 5005. My instinct is to blow ext 5002 away and rebuild it.

ashcortech · December 28, 2023, 11:07pm

Is it possible the user of 5002 set up something on the local phone settings to block 5005? Did you try factory defaulting 5002 and then letting it re-configure from the PBX?

Rogue · December 29, 2023, 12:11am

Hello and thank you,

I dont know how the user could block another extension. I did #31 from 5002 and removed 5005 in case it was blacklisted. I dont think rejecting a call would create this behavior. The user is 0% tech savy.

I removed the extension from extension mapping in EPM and factory reset the phone. Its fixed now.

I appreciate all of your input greatly.

Seems like this one goes down as mystery.

ashcortech · December 29, 2023, 12:26pm

If factory resetting it and then re-configuring it fixed it, the users did something on the local phone settings. 0% tech savy just means they didn’t do it on purpose or know they did it. I can’t tell you how many times users turned off call waiting by pressing *71 instead of 71 Dial to get a call off of parking.

Quite often if a user comes from a different job they will put in codes from the old phone system they used out of force of habit. That can cause no end of weird issues.

dghundt · December 29, 2023, 2:23pm

I’ve had users dial code to for DND. It showed DND in the FreePbx GUI and had to be fixed by dialing the DND off code from extension. Nothing in the GUI was able to fix it.

Rogue · December 29, 2023, 3:14pm

You guys are 100% correct, as users are the proving ground for anything.

I know it was not a DND issue, nor a blacklist issue. Or, if it was; not attempt to reverse those issues worked. Without further log data, I am chalking it up to some sort of corruption in config for the ext.

Thank you all for the input.

system · January 5, 2024, 3:15pm

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