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 [s@macro-dial: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 [s@macro-dial: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 [s@macro-dial: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 [s@macro-dial: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 [s@macro-dial: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 [s@macro-dial: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 [s@macro-dial: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 [s@macro-dial-one:57] ExecIf(“Local/FMPR-117@from-internal-00000017;2”, “0?MacroExit()”) in new stack
4948 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] pbx.c: Executing [s@macro-dial-one:58] ExecIf(“Local/FMPR-117@from-internal-00000017;2”, “0?Set(DIALSTATUS=)”) in new stack
4949 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] pbx.c: Executing [s@macro-dial-one:59] GosubIf(“Local/FMPR-117@from-internal-00000017;2”, “0?s-NOANSWER,1()”) in new stack
4950 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] pbx.c: Executing [s@macro-dial-one:60] MacroExit(“Local/FMPR-117@from-internal-00000017;2”, “”) in new stack
4951 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] pbx.c: Executing [s@macro-simple-dial:7] Set(“Local/FMPR-117@from-internal-00000017;2”, “__CWIGNORE=”) in new stack
4952 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] pbx.c: Executing [s@macro-simple-dial:8] Set(“Local/FMPR-117@from-internal-00000017;2”, “PR_DIALSTATUS=NOANSWER”) in new stack
4953 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] pbx.c: Executing [s@macro-simple-dial:9] GosubIf(“Local/FMPR-117@from-internal-00000017;2”, “0?docfu,1()”) in new stack
4954 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] pbx.c: Executing [s@macro-simple-dial:10] GosubIf(“Local/FMPR-117@from-internal-00000017;2”, “0?docfb,1()”) in new stack
4955 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] pbx.c: Executing [s@macro-simple-dial:11] Set(“Local/FMPR-117@from-internal-00000017;2”, “DIALSTATUS=NOANSWER”) in new stack
4956 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] pbx.c: Executing [s@macro-simple-dial:12] Goto(“Local/FMPR-117@from-internal-00000017;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 [s-NOANSWER@macro-simple-dial:1] NoOp(“Local/FMPR-117@from-internal-00000017;2”, “Extension is reporting s-NOANSWER”) in new stack
4959 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] pbx.c: Executing [FMPR-117@from-internal:6] ExecIf(“Local/FMPR-117@from-internal-00000017;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 [FMPR-117@from-internal:7] Hangup(“Local/FMPR-117@from-internal-00000017;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/FMPR-117@from-internal-00000017;2’
4962 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] pbx.c: Executing [h@from-internal:1] Macro(“Local/FMPR-117@from-internal-00000017;2”, “hangupcall”) in new stack
4963 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] pbx.c: Executing [s@macro-hangupcall:1] GotoIf(“Local/FMPR-117@from-internal-00000017;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 [s@macro-hangupcall:3] ExecIf(“Local/FMPR-117@from-internal-00000017;2”, “0?Set(CDR(recordingfile)=)”) in new stack
4966 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] pbx.c: Executing [s@macro-hangupcall:4] NoOp(“Local/FMPR-117@from-internal-00000017;2”, " montior file= ") in new stack
4967 [2020-09-22 13:32:39] VERBOSE[6978][C-00000769] pbx.c: Executing [s@macro-hangupcall:5] GotoIf(“Local/FMPR-117@from-internal-00000017;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 [s@macro-hangupcall:7] Hangup(“Local/FMPR-117@from-internal-00000017;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/FMPR-117@from-internal-00000017;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/FMPR-117@from-internal-00000017;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 [s@macro-dial: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 [s@macro-dial: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 [s@macro-dial: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 [s@macro-dial: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 [s@macro-dial: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 [s@macro-dial: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 [s@macro-dial: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 [s@macro-dial: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 [s@macro-dial: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 [s@macro-dial: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 [s@macro-dial: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 [s@macro-dial: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 [s@macro-dial: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 [s@macro-dial-ringall-predial-hook: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 [s@macro-dial: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 [s@macro-dial: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 [s@macro-dial: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 [s@func-apply-sipheaders: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 [s@func-apply-sipheaders: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 [s@func-apply-sipheaders: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 [s@func-apply-sipheaders: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 [s@func-apply-sipheaders: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 [s@func-apply-sipheaders: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 [s@macro-user-callerid: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 [s@macro-user-callerid: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 [s@macro-outbound-callerid:30] ExecIf("Local/9#######85@from-internal-00000022;2", "1?Set(CALLERID(all)=7329853331)") in new stack
[2020-09-22 13:47:23] VERBOSE[12512][C-00000774] pbx.c: Executing [s@macro-outbound-callerid:31] ExecIf("Local/9#######85@from-internal-00000022;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.
This topic was automatically closed 31 days after the last reply. New replies are no longer allowed.