Call Forward - The person at extension ### is not available

My System
Asterisk version: 16.9.0 b
Freepbx version: 15.0.16.75

I configured several extensions, with Find me/follow me activated, and put an external phone with #, but when the extension is called, we got the message “The person a extension #### is not available” and goes to the voice mail, any idea?

Guessing without more detail.

https://wiki.freepbx.org/display/SUP/Providing+Great+Debug

Do you have an outbound route that will support the FMFM number you are trying to dial? Are you able to dial the FMFM number from an internal extension?

thanks for the reply, Sorry for not giving more details, but I´m trying to get the information related to the call forwarding in the logs and I cannot find it.

Yes I have trunks and outbound routes configure and I can dial the number from an internal extension, also, after the first install find me was working fine. any ideas?

A common problem is the Caller ID of the “outbound” (now) call doesn’t match any of the CIDs associated with your account. I’d start checking that first.

this is what appears in the log

4928 [2020-09-22 13:32:37] VERBOSE[6979][C-00000769] pbx_builtins.c: Goto (from-internal,FMGL-9#######85#,4)
4929 [2020-09-22 13:32:37] VERBOSE[6979][C-00000769] pbx.c: Executing [FMGL-9#######85#@from-internal:4] GotoIf(“Local/FMGL-9#######85#@from-internal-00000018;2”, “0?dodnd”) in new stack
4930 [2020-09-22 13:32:37] VERBOSE[6979][C-00000769] pbx.c: Executing [FMGL-9#######85#@from-internal:5] Wait(“Local/FMGL-9#######85#@from-internal-00000018;2”, “1”) in new stack
4931 [2020-09-22 13:32:38] VERBOSE[6979][C-00000769] pbx.c: Executing [FMGL-9#######85#@from-internal:6] GotoIf(“Local/FMGL-9#######85#@from-internal-00000018;2”, “1?start”) in new stack
4932 [2020-09-22 13:32:38] VERBOSE[6979][C-00000769] pbx_builtins.c: Goto (from-internal,FMGL-9#######85#,4)
4933 [2020-09-22 13:32:38] VERBOSE[6979][C-00000769] pbx.c: Executing [FMGL-9#######85#@from-internal:4] GotoIf(“Local/FMGL-9#######85#@from-internal-00000018;2”, “0?dodnd”) in new stack
4934 [2020-09-22 13:32:38] VERBOSE[6979][C-00000769] pbx.c: Executing [FMGL-9#######85#@from-internal:5] Wait(“Local/FMGL-9#######85#@from-internal-00000018;2”, “1”) in new stack
4935 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] pbx.c: Executing [FMGL-9#######85#@from-internal:6] GotoIf(“Local/FMGL-9#######85#@from-internal-00000018;2”, “0?start”) in new stack
4936 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] pbx.c: Executing [FMGL-9#######85#@from-internal:7] Set(“Local/FMGL-9#######85#@from-internal-00000018;2”, “SHARED(FM_DND,SIP/122-00000709)=”) in new stack
4937 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] pbx.c: Executing [FMGL-9#######85#@from-internal:8] Macro(“Local/FMGL-9#######85#@from-internal-00000018;2”, “dial,20,Ttr,9#######85#”) in new stack
4938 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] pbx.c: Executing [[email protected]:1] NoOp(“Local/FMGL-9#######85#@from-internal-00000018;2”, "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
4939 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] pbx.c: Executing [[email protected]:2] Set(“Local/FMGL-9#######85#@from-internal-00000018;2”, “__CRM_SOURCE=122”) in new stack
4940 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] pbx.c: Executing [[email protected]:3] ExecIf(“Local/FMGL-9#######85#@from-internal-00000018;2”, “0?Set(ALERT_INFO=)”) in new stack
4941 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] pbx.c: Executing [[email protected]:4] ExecIf(“Local/FMGL-9#######85#@from-internal-00000018;2”, “0?Set(ALERT_INFO=)”) in new stack
4942 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] pbx.c: Executing [[email protected]:5] ExecIf(“Local/FMGL-9#######85#@from-internal-00000018;2”, “0?Set(ALERT_INFO=)”) in new stack
4943 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] pbx.c: Executing [[email protected]:6] ExecIf(“Local/FMGL-9#######85#@from-internal-00000018;2”, “0?Set(CHANNEL(musicclass)=)”) in new stack
4944 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] pbx.c: Executing [[email protected]:7] AGI(“Local/FMGL-9#######85#@from-internal-00000018;2”, “dialparties.agi”) in new stack
4945 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
4946 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] app_dial.c: Nobody picked up in 7000 ms
4947 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] pbx.c: Executing [[email protected]:57] ExecIf(“Local/[email protected];2”, “0?MacroExit()”) in new stack
4948 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] pbx.c: Executing [[email protected]:58] ExecIf(“Local/[email protected];2”, “0?Set(DIALSTATUS=)”) in new stack
4949 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] pbx.c: Executing [[email protected]:59] GosubIf(“Local/[email protected];2”, “0?s-NOANSWER,1()”) in new stack
4950 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] pbx.c: Executing [[email protected]:60] MacroExit(“Local/[email protected];2”, “”) in new stack
4951 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] pbx.c: Executing [[email protected]:7] Set(“Local/[email protected];2”, “__CWIGNORE=”) in new stack
4952 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] pbx.c: Executing [[email protected]:8] Set(“Local/[email protected];2”, “PR_DIALSTATUS=NOANSWER”) in new stack
4953 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] pbx.c: Executing [[email protected]:9] GosubIf(“Local/[email protected];2”, “0?docfu,1()”) in new stack
4954 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] pbx.c: Executing [[email protected]:10] GosubIf(“Local/[email protected];2”, “0?docfb,1()”) in new stack
4955 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] pbx.c: Executing [[email protected]:11] Set(“Local/[email protected];2”, “DIALSTATUS=NOANSWER”) in new stack
4956 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] pbx.c: Executing [[email protected]:12] Goto(“Local/[email protected];2”, “s-NOANSWER,1”) in new stack
4957 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] pbx_builtins.c: Goto (macro-simple-dial,s-NOANSWER,1)
4958 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] pbx.c: Executing [[email protected]:1] NoOp(“Local/[email protected];2”, “Extension is reporting s-NOANSWER”) in new stack
4959 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] pbx.c: Executing [[email protected]:6] ExecIf(“Local/[email protected];2”, “0?Set(SHARED(FM_DND,SIP/122-00000709)=DND)”) in new stack
4960 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] pbx.c: Executing [[email protected]:7] Hangup(“Local/[email protected];2”, “”) in new stack
4961 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] pbx.c: Spawn extension (from-internal, FMPR-117, 7) exited non-zero on ‘Local/[email protected];2’
4962 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] pbx.c: Executing [[email protected]:1] Macro(“Local/[email protected];2”, “hangupcall”) in new stack
4963 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] pbx.c: Executing [[email protected]:1] GotoIf(“Local/[email protected];2”, “1?theend”) in new stack
4964 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] pbx_builtins.c: Goto (macro-hangupcall,s,3)
4965 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] pbx.c: Executing [[email protected]:3] ExecIf(“Local/[email protected];2”, “0?Set(CDR(recordingfile)=)”) in new stack
4966 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] pbx.c: Executing [[email protected]:4] NoOp(“Local/[email protected];2”, " montior file= ") in new stack
4967 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] pbx.c: Executing [[email protected]:5] GotoIf(“Local/[email protected];2”, “1?skipagi”) in new stack
4968 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] pbx_builtins.c: Goto (macro-hangupcall,s,7)
4969 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] pbx.c: Executing [[email protected]:7] Hangup(“Local/[email protected];2”, “”) in new stack
4970 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] app_macro.c: Spawn extension (macro-hangupcall, s, 7) exited non-zero on ‘Local/[email protected];2’ in macro ‘hangupcall’
4971 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on ‘Local/[email protected];2’
4972 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] res_agi.c: dialparties.agi: Starting New Dialparties.agi
4973 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] res_agi.c: dialparties.agi: Caller ID name is ‘Claus SoftPhone’ number is ‘122’
4974 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] res_agi.c: dialparties.agi: CW Ignore is:
4975 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] res_agi.c: dialparties.agi: CF Ignore is:
4976 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] res_agi.c: dialparties.agi: CW IN_USE/BUSY is: 1
4977 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] res_agi.c: dialparties.agi: Methodology of ring is ‘ringall’
4978 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] res_agi.c: dialparties.agi: Added extension 9#######85# to extension map
4979 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] res_agi.c: dialparties.agi: Extension 9#######85# cf is disabled
4980 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] res_agi.c: dialparties.agi: Filtered ARG3: 9#######85
4981 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] res_agi.c: dialparties.agi: RVOL_MODE ‘’
4982 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] res_agi.c: dialparties.agi: RVOL is:
4983 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] res_agi.c: dialparties.agi: RVOLPARENT is:
4984 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] res_agi.c: <Local/FMGL-9#######85#@from-internal-00000018;2>AGI Script dialparties.agi completed, returning 0
4985 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] pbx.c: Executing [[email protected]:8] GotoIf(“Local/FMGL-9#######85#@from-internal-00000018;2”, “1?normdial”) in new stack
4986 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] pbx_builtins.c: Goto (macro-dial,s,11)
4987 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] pbx.c: Executing [[email protected]:11] NoOp(“Local/FMGL-9#######85#@from-internal-00000018;2”, “Returned from dialparties with groups to dial”) in new stack
4988 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] pbx.c: Executing [[email protected]:12] NoOp(“Local/FMGL-9#######85#@from-internal-00000018;2”, "ringall array 9#######85# ") in new stack
4989 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] pbx.c: Executing [[email protected]:13] Set(“Local/FMGL-9#######85#@from-internal-00000018;2”, “__FMGL_DIAL=9#######85#”) in new stack
4990 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] pbx.c: Executing [[email protected]:14] Set(“Local/FMGL-9#######85#@from-internal-00000018;2”, “LOOPCNT=1”) in new stack
4991 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] pbx.c: Executing [[email protected]:15] Set(“Local/FMGL-9#######85#@from-internal-00000018;2”, “ITER=1”) in new stack
4992 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] pbx.c: Executing [[email protected]:16] Set(“Local/FMGL-9#######85#@from-internal-00000018;2”, “__EXTTOCALL=9#######85”) in new stack
4993 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] pbx.c: Executing [[email protected]:17] NoOp(“Local/FMGL-9#######85#@from-internal-00000018;2”, “Working with 9#######85”) in new stack
4994 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] pbx.c: Executing [[email protected]:18] ExecIf(“Local/FMGL-9#######85#@from-internal-00000018;2”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)”) in new stack
4995 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] pbx.c: Executing [[email protected]:19] ExecIf(“Local/FMGL-9#######85#@from-internal-00000018;2”, “0?Set(HASH(__SIPHEADERS,Alert-Info)=Normal;volume=)”) in new stack
4996 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] pbx.c: Executing [[email protected]:20] Set(“Local/FMGL-9#######85#@from-internal-00000018;2”, “ITER=2”) in new stack
4997 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] pbx.c: Executing [[email protected]:21] GotoIf(“Local/FMGL-9#######85#@from-internal-00000018;2”, “0?ndloopbegin”) in new stack
4998 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] pbx.c: Executing [[email protected]:22] Macro(“Local/FMGL-9#######85#@from-internal-00000018;2”, “dial-ringall-predial-hook,”) in new stack
4999 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] pbx.c: Executing [[email protected]:1] MacroExit(“Local/FMGL-9#######85#@from-internal-00000018;2”, “”) in new stack
5000 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] pbx.c: Executing [[email protected]:23] ExecIf(“Local/FMGL-9#######85#@from-internal-00000018;2”, “0?Set(CWRING=r(callwaiting)):Set(CWRING=)”) in new stack
5001 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] pbx.c: Executing [[email protected]:24] ExecIf(“Local/FMGL-9#######85#@from-internal-00000018;2”, “0?Set(ds=Local/9#######85@from-internal/n,20,TtrM(auto-blkvm)g)”) in new stack
5002 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] pbx.c: Executing [[email protected]:25] Dial(“Local/FMGL-9#######85#@from-internal-00000018;2”, “Local/9#######85@from-internal/n,20,TtrM(auto-blkvm)b(func-apply-sipheaders^s^1),”) in new stack
5003 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] app_stack.c: Local/9#######85@from-internal-00000019;1 Internal Gosub(func-apply-sipheaders,s,1) start
5004 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] pbx.c: Executing [[email protected]:1] ExecIf(“Local/9#######85@from-internal-00000019;1”, “0?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)”) in new stack
5005 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] pbx.c: Executing [[email protected]:2] NoOp(“Local/9#######85@from-internal-00000019;1”, “Applying SIP Headers to channel Local/9#######85@from-internal-00000019;1”) in new stack
5006 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] pbx.c: Executing [[email protected]:3] Set(“Local/9#######85@from-internal-00000019;1”, “TECH=Local”) in new stack
5007 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] pbx.c: Executing [[email protected]:4] Set(“Local/9#######85@from-internal-00000019;1”, “SIPHEADERKEYS=”) in new stack
5008 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] pbx.c: Executing [[email protected]:5] While(“Local/9#######85@from-internal-00000019;1”, “0”) in new stack
5009 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] app_while.c: Jumping to priority 13
5010 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] pbx.c: Executing [[email protected]:14] Return(“Local/9#######85@from-internal-00000019;1”, “”) in new stack
5011 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] app_stack.c: Spawn extension (from-internal, FMGL-9#######85#, 1) exited non-zero on ‘Local/9#######85@from-internal-00000019;1’
5012 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] app_stack.c: Local/9#######85@from-internal-00000019;1 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
5013 [2020-09-22 13:32:39] VERBOSE[6979][C-00000769] app_dial.c: Called Local/9#######85@from-internal/n
5014 [2020-09-22 13:32:39] VERBOSE[6976][C-00000769] app_dial.c: Local/FMGL-9#######85#@from-internal-00000018;1 is ringing
5015 [2020-09-22 13:32:39] VERBOSE[6986][C-00000769] pbx.c: Executing [9#######85@from-internal:1] Macro(“Local/9#######85@from-internal-00000019;2”, “user-callerid,LIMIT,EXTERNAL,”) in new stack
5016 [2020-09-22 13:32:39] VERBOSE[6986][C-00000769] pbx.c: Executing [[email protected]:1] Set(“Local/9#######85@from-internal-00000019;2”, “TOUCH_MONITOR=1600795959.1899”) in new stack
5017 [2020-09-22 13:32:39] VERBOSE[6986][C-00000769] pbx.c: Executing [[email protected]:2] Set(“Local/9#######85@from-internal-00000019;2”, “AMPUSER=122”) in new stack

Good, but your log stops a few lines after that. Please paste the complete log for the call at pastebin.freepbx.org and post the link here. If you’re not sure what’s relevant, post everything from 13:32:37 through 13:32:45.

I don’t see anywhere that FMFM is getting instantiated for the extension. Are you sure it’s enabled?

Be sure to start at no later than 13:32:32, since we know the call runs at least that long. You can also use “grep ‘[6979]’ /var/log/asterisk/full” to get the full call.

this is the complete log

https://pastebin.freepbx.org/view/6e5e4933

I´m calling from extension 122 to extension 117 which has follow me to 9#######85

[2020-09-22 13:47:23] VERBOSE[12512][C-00000774] pbx.c: Executing [[email protected]:30] ExecIf("Local/9#######[email protected];2", "1?Set(CALLERID(all)=7329853331)") in new stack   
[2020-09-22 13:47:23] VERBOSE[12512][C-00000774] pbx.c: Executing [[email protected]:31] ExecIf("Local/9#######[email protected];2", "1?Set(CALLERID(all)=Claus SP (122))") in new stack       

OK, Follow-Me by default sends the caller ID of the original caller, but for some reason that I don’t understand, it’s sending your extension number, which is being rejected by SIPStation or their upstream.

To confirm this, in the Follow Me settings, change Change External CID Configuration to Outside Calls Fixed CID Value and enter your main company number in the Fixed CID Value field.

If this allows the call to go through but you want to pass the original caller’s number, we’ll need to do some additional debugging.

If the call still fails, we’ll need a SIP trace to see why.

It’s an internal call. Are you setting extension Caller IDs or are you leaving them blank? Also, do you have the outbound route set up as an Intra-company route?

both yes, I´m setting a number to follow, and yes I have an outbound route for intra-company route

The Outbound Route used by the Follow-Me (that routes to SIPStation trunk) should not have the Intra-Company flag set.

Confirm that for extension 122, you have Outbound CID correctly formatted e.g.
"Claus SP" <7329853331>

The reason is because an Intracompany route uses the extension number in the Caller ID so that people know it’s a “local” call, even if it’s between two distant servers.

Thank you all, its fixed, the problem was that all extensions, had Outbound CID filled with the extension number and that was send and rejected by the Trunk.

1 Like

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