I can dial my outside mobile number but my follow-me can't

fizgig10 · April 25, 2017, 5:59pm

Have a simple follow me assigned to extension 100 to dial my internal SIP extension (503) and also my external mobile number (7073036327). When 100 is dialed, my 503 internal extension rings but not my cell phone. If I dial my mobile number from any internal SIP phone, it rings my mobile line just fine. I do have the # sign at the end of my mobile number. I have a sip station trunk with three channels so the two channels required here shouldn’t be a problem.

Anyone have anything I can try?

Using FreePBX 10.13.66-19.

Full log here: http://www.wepaste.com/followmeproblem/

Partial log follows:

[2017-04-25 10:53:40] VERBOSE[18746][C-00002c18] pbx.c: Executing [FMGL-7073036327#-503@from-internal:3] GotoIf("Local/FMGL-7073036327#-503@from-internal-00000103;2", "0?dodnd") in new stack
[2017-04-25 10:53:40] VERBOSE[18746][C-00002c18] pbx.c: Executing [FMGL-7073036327#-503@from-internal:4] Wait("Local/FMGL-7073036327#-503@from-internal-00000103;2", "1") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [FMGL-7073036327#-503@from-internal:5] GotoIf("Local/FMGL-7073036327#-503@from-internal-00000103;2", "0?start") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [FMGL-7073036327#-503@from-internal:6] Set("Local/FMGL-7073036327#-503@from-internal-00000103;2", "SHARED(FM_DND,SIP/fpbx-1-9e730791-000002fa)=") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [FMGL-7073036327#-503@from-internal:7] Macro("Local/FMGL-7073036327#-503@from-internal-00000103;2", "dial,25,Ttr,7073036327#-503") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@macro-dial:1] NoOp("Local/FMGL-7073036327#-503@from-internal-00000103;2", "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@macro-dial:2] ExecIf("Local/FMGL-7073036327#-503@from-internal-00000103;2", "0?Set(ALERT_INFO=)") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@macro-dial:3] ExecIf("Local/FMGL-7073036327#-503@from-internal-00000103;2", "0?Set(ALERT_INFO=)") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@macro-dial:4] ExecIf("Local/FMGL-7073036327#-503@from-internal-00000103;2", "0?Set(ALERT_INFO=)") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@macro-dial:5] ExecIf("Local/FMGL-7073036327#-503@from-internal-00000103;2", "0?Set(CHANNEL(musicclass)=)") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@macro-dial:6] AGI("Local/FMGL-7073036327#-503@from-internal-00000103;2", "dialparties.agi") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] res_agi.c: dialparties.agi: Starting New Dialparties.agi
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] res_agi.c: dialparties.agi: Caller ID name is 'CAZADERO CA' number is '17078476288'
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] res_agi.c: dialparties.agi: CW Ignore is:
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] res_agi.c: dialparties.agi: CF Ignore is:
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] res_agi.c: dialparties.agi: CW IN_USE/BUSY is: 1
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] res_agi.c: dialparties.agi: Methodology of ring is 'ringall'
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] res_agi.c: dialparties.agi: Added extension 7073036327# to extension map
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] res_agi.c: dialparties.agi: Added extension 503 to extension map
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] res_agi.c: dialparties.agi: Extension 7073036327# cf is disabled
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] res_agi.c: dialparties.agi: Extension 503 cf is disabled
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] res_agi.c: dialparties.agi: Extension 503 do not disturb is disabled
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] res_agi.c: dialparties.agi: dbset CALLTRACE/503 to 17078476288
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] res_agi.c: dialparties.agi: Filtered ARG3: 7073036327-503
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] res_agi.c: <Local/FMGL-7073036327#-503@from-internal-00000103;2>AGI Script dialparties.agi completed, returning 0
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@macro-dial:9] NoOp("Local/FMGL-7073036327#-503@from-internal-00000103;2", "Returned from dialparties with groups to dial") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@macro-dial:10] Set("Local/FMGL-7073036327#-503@from-internal-00000103;2", "LOOPCNT=2") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@macro-dial:11] Set("Local/FMGL-7073036327#-503@from-internal-00000103;2", "ITER=1") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@macro-dial:12] Set("Local/FMGL-7073036327#-503@from-internal-00000103;2", "EXTTOCALL=7073036327") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@macro-dial:13] NoOp("Local/FMGL-7073036327#-503@from-internal-00000103;2", "Working with 7073036327") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@macro-dial:14] ExecIf("Local/FMGL-7073036327#-503@from-internal-00000103;2", "1?Set(HASH(__SIPHEADERS,Alert-Info)=;volume=0)") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@macro-dial:15] ExecIf("Local/FMGL-7073036327#-503@from-internal-00000103;2", "0?Set(HASH(__SIPHEADERS,Alert-Info)=;volume=)") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@macro-dial:16] Set("Local/FMGL-7073036327#-503@from-internal-00000103;2", "ITER=2") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@macro-dial:17] GotoIf("Local/FMGL-7073036327#-503@from-internal-00000103;2", "1?ndloopbegin") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx_builtins.c: Goto (macro-dial,s,12)
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@macro-dial:12] Set("Local/FMGL-7073036327#-503@from-internal-00000103;2", "EXTTOCALL=503") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@macro-dial:13] NoOp("Local/FMGL-7073036327#-503@from-internal-00000103;2", "Working with 503") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@macro-dial:14] ExecIf("Local/FMGL-7073036327#-503@from-internal-00000103;2", "1?Set(HASH(__SIPHEADERS,Alert-Info)=;volume=0)") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@macro-dial:15] ExecIf("Local/FMGL-7073036327#-503@from-internal-00000103;2", "0?Set(HASH(__SIPHEADERS,Alert-Info)=;volume=)") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@macro-dial:16] Set("Local/FMGL-7073036327#-503@from-internal-00000103;2", "ITER=3") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@macro-dial:17] GotoIf("Local/FMGL-7073036327#-503@from-internal-00000103;2", "0?ndloopbegin") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@macro-dial:18] Macro("Local/FMGL-7073036327#-503@from-internal-00000103;2", "dial-ringall-predial-hook,") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@macro-dial-ringall-predial-hook:1] MacroExit("Local/FMGL-7073036327#-503@from-internal-00000103;2", "") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@macro-dial:19] Dial("Local/FMGL-7073036327#-503@from-internal-00000103;2", "Local/7073036327@from-internal/n&SIP/503,25,trM(auto-blkvm)b(func-apply-sipheaders^s^1),") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] netsock2.c: Using SIP RTP TOS bits 184
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] netsock2.c: Using SIP RTP CoS mark 5
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] app_stack.c: Local/7073036327@from-internal-00000104;1 Internal Gosub(func-apply-sipheaders,s,1) start
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("Local/7073036327@from-internal-00000104;1", "Applying SIP Headers to channel") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@func-apply-sipheaders:2] Set("Local/7073036327@from-internal-00000104;1", "SIPHEADERKEYS=Alert-Info") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@func-apply-sipheaders:3] While("Local/7073036327@from-internal-00000104;1", "1") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@func-apply-sipheaders:4] Set("Local/7073036327@from-internal-00000104;1", "sipheader=;volume=0") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@func-apply-sipheaders:5] SIPAddHeader("Local/7073036327@from-internal-00000104;1", "Alert-Info: ;volume=0") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@func-apply-sipheaders:6] EndWhile("Local/7073036327@from-internal-00000104;1", "") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@func-apply-sipheaders:3] While("Local/7073036327@from-internal-00000104;1", "0") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@func-apply-sipheaders:7] Return("Local/7073036327@from-internal-00000104;1", "") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] app_stack.c: Spawn extension (from-internal, FMGL-7073036327#-503, 1) exited non-zero on 'Local/7073036327@from-internal-00000104;1'
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] app_stack.c: Local/7073036327@from-internal-00000104;1 Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] app_stack.c: SIP/503-000002fb Internal Gosub(func-apply-sipheaders,s,1) start
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@func-apply-sipheaders:1] NoOp("SIP/503-000002fb", "Applying SIP Headers to channel") in new stack
[2017-04-25 10:53:42] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@func-apply-sipheaders:2] Set("SIP/503-000002fb", "SIPHEADERKEYS=Alert-Info") in new stack
[2017-04-25 10:53:42] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@func-apply-sipheaders:3] While("SIP/503-000002fb", "1") in new stack
[2017-04-25 10:53:42] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@func-apply-sipheaders:4] Set("SIP/503-000002fb", "sipheader=;volume=0") in new stack
[2017-04-25 10:53:42] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@func-apply-sipheaders:5] SIPAddHeader("SIP/503-000002fb", "Alert-Info: ;volume=0") in new stack
[2017-04-25 10:53:42] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@func-apply-sipheaders:6] EndWhile("SIP/503-000002fb", "") in new stack
[2017-04-25 10:53:42] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@func-apply-sipheaders:3] While("SIP/503-000002fb", "0") in new stack
[2017-04-25 10:53:42] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@func-apply-sipheaders:7] Return("SIP/503-000002fb", "") in new stack
[2017-04-25 10:53:42] VERBOSE[18746][C-00002c18] app_stack.c: Spawn extension (from-internal, FMGL-7073036327#-503, 1) exited non-zero on 'SIP/503-000002fb'
[2017-04-25 10:53:42] VERBOSE[18746][C-00002c18] app_stack.c: SIP/503-000002fb Internal Gosub(func-apply-sipheaders,s,1) complete GOSUB_RETVAL=
[2017-04-25 10:53:42] VERBOSE[18746][C-00002c18] app_dial.c: Called Local/7073036327@from-internal/n
[2017-04-25 10:53:42] VERBOSE[18746][C-00002c18] app_dial.c: Called SIP/503
[2017-04-25 10:53:42] VERBOSE[18741][C-00002c18] app_dial.c: Local/FMGL-7073036327#-503@from-internal-00000103;1 is ringing
[2017-04-25 10:53:42] VERBOSE[18746][C-00002c18] app_dial.c: SIP/503-000002fb connected line has changed. Saving it until answer for Local/FMGL-7073036327#-503@from-internal-00000103;2
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [7073036327@from-internal:1] Macro("Local/7073036327@from-internal-00000104;2", "user-callerid,LIMIT,EXTERNAL,") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-user-callerid:1] Set("Local/7073036327@from-internal-00000104;2", "TOUCH_MONITOR=1493142821.8047") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-user-callerid:2] Set("Local/7073036327@from-internal-00000104;2", "AMPUSER=17078476288") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-user-callerid:3] GotoIf("Local/7073036327@from-internal-00000104;2", "0?report") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-user-callerid:4] ExecIf("Local/7073036327@from-internal-00000104;2", "1?Set(REALCALLERIDNUM=17078476288)") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-user-callerid:5] Set("Local/7073036327@from-internal-00000104;2", "AMPUSER=") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-user-callerid:6] GotoIf("Local/7073036327@from-internal-00000104;2", "0?limit") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-user-callerid:7] Set("Local/7073036327@from-internal-00000104;2", "AMPUSERCIDNAME=") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-user-callerid:8] GotoIf("Local/7073036327@from-internal-00000104;2", "1?report") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx_builtins.c: Goto (macro-user-callerid,s,15)
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-user-callerid:15] GotoIf("Local/7073036327@from-internal-00000104;2", "1?continue") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx_builtins.c: Goto (macro-user-callerid,s,28)
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-user-callerid:28] Set("Local/7073036327@from-internal-00000104;2", "CALLERID(number)=17078476288") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-user-callerid:29] Set("Local/7073036327@from-internal-00000104;2", "CALLERID(name)=CAZADERO CA") in new stack
[2017-04-25 10:53:42] VERBOSE[18746][C-00002c18] app_dial.c: SIP/503-000002fb is ringing
[2017-04-25 10:53:42] VERBOSE[18741][C-00002c18] app_dial.c: Local/FMGL-7073036327#-503@from-internal-00000103;1 is ringing
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-user-callerid:30] GotoIf("Local/7073036327@from-internal-00000104;2", "0?cnum") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-user-callerid:31] Set("Local/7073036327@from-internal-00000104;2", "CDR(cnam)=CAZADERO CA") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-user-callerid:32] Set("Local/7073036327@from-internal-00000104;2", "CDR(cnum)=17078476288") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-user-callerid:33] Set("Local/7073036327@from-internal-00000104;2", "CHANNEL(language)=en") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [7073036327@from-internal:2] Gosub("Local/7073036327@from-internal-00000104;2", "sub-record-check,s,1(out,7073036327,dontcare)") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@sub-record-check:1] GotoIf("Local/7073036327@from-internal-00000104;2", "0?initialized") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@sub-record-check:2] Set("Local/7073036327@from-internal-00000104;2", "__REC_STATUS=INITIALIZED") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@sub-record-check:3] Set("Local/7073036327@from-internal-00000104;2", "NOW=1493142822") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@sub-record-check:4] Set("Local/7073036327@from-internal-00000104;2", "__DAY=25") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@sub-record-check:5] Set("Local/7073036327@from-internal-00000104;2", "__MONTH=04") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@sub-record-check:6] Set("Local/7073036327@from-internal-00000104;2", "__YEAR=2017") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@sub-record-check:7] Set("Local/7073036327@from-internal-00000104;2", "__TIMESTR=20170425-105342") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@sub-record-check:8] Set("Local/7073036327@from-internal-00000104;2", "__FROMEXTEN=17078476288") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@sub-record-check:9] Set("Local/7073036327@from-internal-00000104;2", "__MON_FMT=wav") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@sub-record-check:10] NoOp("Local/7073036327@from-internal-00000104;2", "Recordings initialized") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@sub-record-check:11] ExecIf("Local/7073036327@from-internal-00000104;2", "0?Set(ARG3=dontcare)") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@sub-record-check:12] Set("Local/7073036327@from-internal-00000104;2", "REC_POLICY_MODE_SAVE=") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@sub-record-check:13] ExecIf("Local/7073036327@from-internal-00000104;2", "0?Set(REC_STATUS=NO)") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@sub-record-check:14] GotoIf("Local/7073036327@from-internal-00000104;2", "3?checkaction") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx_builtins.c: Goto (sub-record-check,s,17)
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@sub-record-check:17] GotoIf("Local/7073036327@from-internal-00000104;2", "1?sub-record-check,out,1") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx_builtins.c: Goto (sub-record-check,out,1)
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [out@sub-record-check:1] NoOp("Local/7073036327@from-internal-00000104;2", "Outbound Recording Check from 17078476288 to 7073036327") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [out@sub-record-check:2] Set("Local/7073036327@from-internal-00000104;2", "RECMODE=") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [out@sub-record-check:3] ExecIf("Local/7073036327@from-internal-00000104;2", "1?Goto(routewins)") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx_builtins.c: Goto (sub-record-check,out,7)
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [out@sub-record-check:7] Gosub("Local/7073036327@from-internal-00000104;2", "recordcheck,1(dontcare,out,7073036327)") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("Local/7073036327@from-internal-00000104;2", "Starting recording check against dontcare") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("Local/7073036327@from-internal-00000104;2", "dontcare") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx_builtins.c: Goto (sub-record-check,recordcheck,3)
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [recordcheck@sub-record-check:3] Return("Local/7073036327@from-internal-00000104;2", "") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [out@sub-record-check:8] Return("Local/7073036327@from-internal-00000104;2", "") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [7073036327@from-internal:3] Set("Local/7073036327@from-internal-00000104;2", "MOHCLASS=default") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [7073036327@from-internal:4] Set("Local/7073036327@from-internal-00000104;2", "_NODEST=") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [7073036327@from-internal:5] Macro("Local/7073036327@from-internal-00000104;2", "dialout-trunk,2,7073036327,,off") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-dialout-trunk:1] Set("Local/7073036327@from-internal-00000104;2", "DIAL_TRUNK=2") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-dialout-trunk:2] GosubIf("Local/7073036327@from-internal-00000104;2", "0?sub-pincheck,s,1()") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-dialout-trunk:3] GotoIf("Local/7073036327@from-internal-00000104;2", "0?disabletrunk,1") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-dialout-trunk:4] Set("Local/7073036327@from-internal-00000104;2", "DIAL_NUMBER=7073036327") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-dialout-trunk:5] Set("Local/7073036327@from-internal-00000104;2", "DIAL_TRUNK_OPTIONS=Ttr") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-dialout-trunk:6] Set("Local/7073036327@from-internal-00000104;2", "OUTBOUND_GROUP=OUT_2") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-dialout-trunk:7] GotoIf("Local/7073036327@from-internal-00000104;2", "1?nomax") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx_builtins.c: Goto (macro-dialout-trunk,s,9)
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-dialout-trunk:9] GotoIf("Local/7073036327@from-internal-00000104;2", "0?skipoutcid") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-dialout-trunk:10] Set("Local/7073036327@from-internal-00000104;2", "DIAL_TRUNK_OPTIONS=Tt") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-dialout-trunk:11] Macro("Local/7073036327@from-internal-00000104;2", "outbound-callerid,2") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-outbound-callerid:1] ExecIf("Local/7073036327@from-internal-00000104;2", "1?Set(CALLERPRES(name-pres)=allowed_not_screened)") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-outbound-callerid:2] ExecIf("Local/7073036327@from-internal-00000104;2", "1?Set(CALLERPRES(num-pres)=allowed_not_screened)") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-outbound-callerid:3] ExecIf("Local/7073036327@from-internal-00000104;2", "0?Set(REALCALLERIDNUM=17078476288)") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-outbound-callerid:4] GotoIf("Local/7073036327@from-internal-00000104;2", "0?normcid") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-outbound-callerid:5] Set("Local/7073036327@from-internal-00000104;2", "USEROUTCID=17078476288") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-outbound-callerid:6] GotoIf("Local/7073036327@from-internal-00000104;2", "1?bypass") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx_builtins.c: Goto (macro-outbound-callerid,s,8)
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-outbound-callerid:8] Set("Local/7073036327@from-internal-00000104;2", "EMERGENCYCID=") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-outbound-callerid:9] Set("Local/7073036327@from-internal-00000104;2", "TRUNKOUTCID=5106382000") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-outbound-callerid:10] GotoIf("Local/7073036327@from-internal-00000104;2", "1?trunkcid") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx_builtins.c: Goto (macro-outbound-callerid,s,15)
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-outbound-callerid:15] ExecIf("Local/7073036327@from-internal-00000104;2", "1?Set(CALLERID(all)=5106382000)") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-outbound-callerid:16] ExecIf("Local/7073036327@from-internal-00000104;2", "1?Set(CALLERID(all)=17078476288)") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-outbound-callerid:17] ExecIf("Local/7073036327@from-internal-00000104;2", "0?Set(CALLERID(all)=)") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-outbound-callerid:18] ExecIf("Local/7073036327@from-internal-00000104;2", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-outbound-callerid:19] ExecIf("Local/7073036327@from-internal-00000104;2", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-outbound-callerid:20] Set("Local/7073036327@from-internal-00000104;2", "CDR(outbound_cnum)=17078476288") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-outbound-callerid:21] Set("Local/7073036327@from-internal-00000104;2", "CDR(outbound_cnam)=") in new stack
[2017-04-25 10:53:42] WARNING[3018] func_cdr.c: CDR requires a value (CDR(variable)=value)
)[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-dialout-trunk:12] GosubIf("Local/7073036327@from-internal-00000104;2", "0?sub-flp-2,s,1()") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-dialout-trunk:13] Set("Local/7073036327@from-internal-00000104;2", "OUTNUM=7073036327") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-dialout-trunk:14] Set("Local/7073036327@from-internal-00000104;2", "custom=SIP/fpbx-1-9e730791") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-dialout-trunk:15] ExecIf("Local/7073036327@from-internal-00000104;2", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)Tt)") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-dialout-trunk:16] ExecIf("Local/7073036327@from-internal-00000104;2", "0?Set(DIAL_TRUNK_OPTIONS=TtM(confirm))") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-dialout-trunk:17] Macro("Local/7073036327@from-internal-00000104;2", "dialout-trunk-predial-hook,") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("Local/7073036327@from-internal-00000104;2", "") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-dialout-trunk:18] GotoIf("Local/7073036327@from-internal-00000104;2", "0?bypass,1") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-dialout-trunk:19] ExecIf("Local/7073036327@from-internal-00000104;2", "0?Set(CONNECTEDLINE(num,i)=7073036327)") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-dialout-trunk:20] ExecIf("Local/7073036327@from-internal-00000104;2", "0?Set(CONNECTEDLINE(name,i)=CID:17078476288)") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-dialout-trunk:21] ExecIf("Local/7073036327@from-internal-00000104;2", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)17078476288)") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-dialout-trunk:22] GotoIf("Local/7073036327@from-internal-00000104;2", "0?customtrunk") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-dialout-trunk:23] Dial("Local/7073036327@from-internal-00000104;2", "SIP/fpbx-1-9e730791/7073036327,300,Tt") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] netsock2.c: Using SIP RTP TOS bits 184
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] netsock2.c: Using SIP RTP CoS mark 5
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] app_dial.c: Called SIP/fpbx-1-9e730791/7073036327
[2017-04-25 10:53:46] VERBOSE[18746][C-00002c18] app_macro.c: Spawn extension (macro-dial, s, 19) exited non-zero on 'Local/FMGL-7073036327#-503@from-internal-00000103;2' in macro 'dial'
[2017-04-25 10:53:46] VERBOSE[18748][C-00002c18] app_macro.c: Spawn extension (macro-dialout-trunk, s, 23) exited non-zero on 'Local/7073036327@from-internal-00000104;2' in macro 'dialout-trunk'
[2017-04-25 10:53:46] VERBOSE[18748][C-00002c18] pbx.c: Spawn extension (from-internal, 7073036327, 5) exited non-zero on 'Local/7073036327@from-internal-00000104;2'
[2017-04-25 10:53:46] VERBOSE[18748][C-00002c18] pbx.c: Executing [h@from-internal:1] Macro("Local/7073036327@from-internal-00000104;2", "hangupcall") in new stack
[2017-04-25 10:53:46] VERBOSE[18741][C-00002c18] app_macro.c: Spawn extension (macro-dial, s, 19) exited non-zero on 'SIP/fpbx-1-9e730791-000002fa' in macro 'dial'
[2017-04-25 10:53:46] VERBOSE[18746][C-00002c18] pbx.c: Spawn extension (from-internal, FMGL-7073036327#-503, 7) exited non-zero on 'Local/FMGL-7073036327#-503@from-internal-00000103;2'
[2017-04-25 10:53:46] VERBOSE[18746][C-00002c18] pbx.c: Executing [h@from-internal:1] Macro("Local/FMGL-7073036327#-503@from-internal-00000103;2", "hangupcall") in new stack
[2017-04-25 10:53:46] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("Local/7073036327@from-internal-00000104;2", "1?theend") in new stack
[2017-04-25 10:53:46] VERBOSE[18748][C-00002c18] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2017-04-25 10:53:46] VERBOSE[18741][C-00002c18] pbx.c: Spawn extension (followme-sub, 100, 39) exited non-zero on 'SIP/fpbx-1-9e730791-000002fa'
[2017-04-25 10:53:46] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("Local/FMGL-7073036327#-503@from-internal-00000103;2", "1?theend") in new stack
[2017-04-25 10:53:46] VERBOSE[18746][C-00002c18] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2017-04-25 10:53:46] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("Local/7073036327@from-internal-00000104;2", "0?Set(CDR(recordingfile)=)") in new stack
[2017-04-25 10:53:46] VERBOSE[18748][C-00002c18] pbx.c: Executing [s@macro-hangupcall:4] Hangup("Local/7073036327@from-internal-00000104;2", "") in new stack
[2017-04-25 10:53:46] VERBOSE[18746][C-00002c18] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("Local/FMGL-7073036327#-503@from-internal-00000103;2", "0?Set(CDR(recordingfile)=)") in new stack

GSnover · April 25, 2017, 9:06pm

That looks like a SipStation trunk from the trunk ID - It is certainly capable, but do you have enough channels? Remember that you need a channel for every leg of the call - so if you are calling in from the outside, that is one channel, and then if you are forwarding it to your cel, that is a second channel.

So you need at a minimum two channels to make this work assuming nothing else is going on with the system.

fizgig10 · April 25, 2017, 9:07pm

Thanks. I have three channels on the SIP station trunk. I’m testing this when no other calls are taking place so I should be good.

I’ve also experimented with forcing the CID to my DID number in case Sipstation blocks other CID’s from being used and still no dice.

GSnover · April 25, 2017, 9:09pm

SipStation will allow foreign caller id, and three channels is 1 more than you need.

Does the phone ever ring?

fizgig10 · April 25, 2017, 9:11pm

Can’t get the cellphone to ring at all from the follow-me group. Can dial my cell phone from my internal sip extension just fine like any other number in the outside world I need to call.

Still scratching head. Wish I had the knowledge to debug that log.

GSnover · April 25, 2017, 9:14pm

Well, I noticed here:

[2017-04-25 10:42:00] VERBOSE[17718][C-00002c13] app_dial.c: Called SIP/fpbx-1-9e730791/7073036327
[2017-04-25 10:42:15] VERBOSE[17716][C-00002c13] app_macro.c: Spawn extension (macro-dial, s, 19) exited non-zero on ‘Local/FMGL-7073036327#-503@from-internal-000000fd;2’ in macro ‘dial’

that it seems to dial and the gives up after 15 seconds - that seems like plenty long to wait for the Cel to start ringing.

Can you extend the Follow-Me time out to like 60 seconds and try again?

fizgig10 · April 25, 2017, 9:17pm

I’ll extend it but I’m only letting it ring for 10-15 seconds each test anyhow. When I dial my cell directly from my office sip extension, it rings virtually instantly so I figure 15 seconds is enough to know it isn’t going to happen.

GSnover · April 25, 2017, 9:19pm

Ok - if there is no lag, that might not help - although it may show something different in the logs.

Try this too - from the CLI do a “core show channels” while the call is in progress and post results - it would be good to see what channels are being opened.

fizgig10 · April 25, 2017, 9:21pm

Here’s what I get when I dial my follow-me extension and my sip line is ringing (but my cell is not)

Channel              Location             State   Application(Data)
Local/FMGL-707303632 s@macro-dial:19      Ring    Dial(Local/7073036327@from-int
Local/FMGL-707303632 100@from-internal:1  Ringing AppDial((Outgoing Line))
SIP/503-00000007     FMGL-7073036327#-503 Ringing AppDial((Outgoing Line))
Local/7073036327@fro s@func-apply-siphead Down    Return()
Local/7073036327@fro s@macro-dialout-trun Ring    Dial(SIP/fpbx-1-9e730791/70730
SIP/fpbx-1-9e730791- s@macro-dial:19      Up      Dial(Local/FMPR-100@from-inter
SIP/fpbx-1-9e730791- 7073036327@from-pstn Down    AppDial((Outgoing Line))
7 active channels
3 active calls
12 calls processed