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#[email protected]:3] GotoIf("Local/FMGL-7073036327#[email protected];2", "0?dodnd") in new stack
[2017-04-25 10:53:40] VERBOSE[18746][C-00002c18] pbx.c: Executing [FMGL-7073036327#[email protected]:4] Wait("Local/FMGL-7073036327#[email protected];2", "1") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [FMGL-7073036327#[email protected]:5] GotoIf("Local/FMGL-7073036327#[email protected];2", "0?start") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [FMGL-7073036327#[email protected]:6] Set("Local/FMGL-7073036327#[email protected];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#[email protected]:7] Macro("Local/FMGL-7073036327#[email protected];2", "dial,25,Ttr,7073036327#-503") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [[email protected]:1] NoOp("Local/FMGL-7073036327#[email protected];2", "Blind Transfer: , Attended Transfer: , User: , Alert Info: ") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [[email protected]:2] ExecIf("Local/FMGL-7073036327#[email protected];2", "0?Set(ALERT_INFO=)") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [[email protected]:3] ExecIf("Local/FMGL-7073036327#[email protected];2", "0?Set(ALERT_INFO=)") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [[email protected]:4] ExecIf("Local/FMGL-7073036327#[email protected];2", "0?Set(ALERT_INFO=)") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [[email protected]:5] ExecIf("Local/FMGL-7073036327#[email protected];2", "0?Set(CHANNEL(musicclass)=)") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [[email protected]:6] AGI("Local/FMGL-7073036327#[email protected];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#[email protected];2>AGI Script dialparties.agi completed, returning 0
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [[email protected]:9] NoOp("Local/FMGL-7073036327#[email protected];2", "Returned from dialparties with groups to dial") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [[email protected]:10] Set("Local/FMGL-7073036327#[email protected];2", "LOOPCNT=2") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [[email protected]:11] Set("Local/FMGL-7073036327#[email protected];2", "ITER=1") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [[email protected]:12] Set("Local/FMGL-7073036327#[email protected];2", "EXTTOCALL=7073036327") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [[email protected]:13] NoOp("Local/FMGL-7073036327#[email protected];2", "Working with 7073036327") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [[email protected]:14] ExecIf("Local/FMGL-7073036327#[email protected];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 [[email protected]:15] ExecIf("Local/FMGL-7073036327#[email protected];2", "0?Set(HASH(__SIPHEADERS,Alert-Info)=;volume=)") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [[email protected]:16] Set("Local/FMGL-7073036327#[email protected];2", "ITER=2") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [[email protected]:17] GotoIf("Local/FMGL-7073036327#[email protected];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 [[email protected]:12] Set("Local/FMGL-7073036327#[email protected];2", "EXTTOCALL=503") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [[email protected]:13] NoOp("Local/FMGL-7073036327#[email protected];2", "Working with 503") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [[email protected]:14] ExecIf("Local/FMGL-7073036327#[email protected];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 [[email protected]:15] ExecIf("Local/FMGL-7073036327#[email protected];2", "0?Set(HASH(__SIPHEADERS,Alert-Info)=;volume=)") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [[email protected]:16] Set("Local/FMGL-7073036327#[email protected];2", "ITER=3") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [[email protected]:17] GotoIf("Local/FMGL-7073036327#[email protected];2", "0?ndloopbegin") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [[email protected]:18] Macro("Local/FMGL-7073036327#[email protected];2", "dial-ringall-predial-hook,") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [[email protected]:1] MacroExit("Local/FMGL-7073036327#[email protected];2", "") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [[email protected]:19] Dial("Local/FMGL-7073036327#[email protected];2", "Local/[email protected]/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/[email protected];1 Internal Gosub(func-apply-sipheaders,s,1) start
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [[email protected]:1] NoOp("Local/[email protected];1", "Applying SIP Headers to channel") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [[email protected]:2] Set("Local/[email protected];1", "SIPHEADERKEYS=Alert-Info") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [[email protected]:3] While("Local/[email protected];1", "1") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [[email protected]:4] Set("Local/[email protected];1", "sipheader=;volume=0") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [[email protected]:5] SIPAddHeader("Local/[email protected];1", "Alert-Info: ;volume=0") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [[email protected]:6] EndWhile("Local/[email protected];1", "") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [[email protected]:3] While("Local/[email protected];1", "0") in new stack
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] pbx.c: Executing [[email protected]:7] Return("Local/[email protected];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/[email protected];1'
[2017-04-25 10:53:41] VERBOSE[18746][C-00002c18] app_stack.c: Local/[email protected];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 [[email protected]: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 [[email protected]:2] Set("SIP/503-000002fb", "SIPHEADERKEYS=Alert-Info") in new stack
[2017-04-25 10:53:42] VERBOSE[18746][C-00002c18] pbx.c: Executing [[email protected]:3] While("SIP/503-000002fb", "1") in new stack
[2017-04-25 10:53:42] VERBOSE[18746][C-00002c18] pbx.c: Executing [[email protected]:4] Set("SIP/503-000002fb", "sipheader=;volume=0") in new stack
[2017-04-25 10:53:42] VERBOSE[18746][C-00002c18] pbx.c: Executing [[email protected]: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 [[email protected]:6] EndWhile("SIP/503-000002fb", "") in new stack
[2017-04-25 10:53:42] VERBOSE[18746][C-00002c18] pbx.c: Executing [[email protected]:3] While("SIP/503-000002fb", "0") in new stack
[2017-04-25 10:53:42] VERBOSE[18746][C-00002c18] pbx.c: Executing [[email protected]: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/[email protected]/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#[email protected];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#[email protected];2
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:1] Macro("Local/[email protected];2", "user-callerid,LIMIT,EXTERNAL,") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:1] Set("Local/[email protected];2", "TOUCH_MONITOR=1493142821.8047") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:2] Set("Local/[email protected];2", "AMPUSER=17078476288") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:3] GotoIf("Local/[email protected];2", "0?report") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:4] ExecIf("Local/[email protected];2", "1?Set(REALCALLERIDNUM=17078476288)") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:5] Set("Local/[email protected];2", "AMPUSER=") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:6] GotoIf("Local/[email protected];2", "0?limit") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:7] Set("Local/[email protected];2", "AMPUSERCIDNAME=") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:8] GotoIf("Local/[email protected];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 [[email protected]:15] GotoIf("Local/[email protected];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 [[email protected]:28] Set("Local/[email protected];2", "CALLERID(number)=17078476288") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:29] Set("Local/[email protected];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#[email protected];1 is ringing
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:30] GotoIf("Local/[email protected];2", "0?cnum") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:31] Set("Local/[email protected];2", "CDR(cnam)=CAZADERO CA") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:32] Set("Local/[email protected];2", "CDR(cnum)=17078476288") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:33] Set("Local/[email protected];2", "CHANNEL(language)=en") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:2] Gosub("Local/[email protected];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 [[email protected]:1] GotoIf("Local/[email protected];2", "0?initialized") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:2] Set("Local/[email protected];2", "__REC_STATUS=INITIALIZED") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:3] Set("Local/[email protected];2", "NOW=1493142822") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:4] Set("Local/[email protected];2", "__DAY=25") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:5] Set("Local/[email protected];2", "__MONTH=04") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:6] Set("Local/[email protected];2", "__YEAR=2017") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:7] Set("Local/[email protected];2", "__TIMESTR=20170425-105342") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:8] Set("Local/[email protected];2", "__FROMEXTEN=17078476288") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:9] Set("Local/[email protected];2", "__MON_FMT=wav") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:10] NoOp("Local/[email protected];2", "Recordings initialized") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:11] ExecIf("Local/[email protected];2", "0?Set(ARG3=dontcare)") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:12] Set("Local/[email protected];2", "REC_POLICY_MODE_SAVE=") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:13] ExecIf("Local/[email protected];2", "0?Set(REC_STATUS=NO)") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:14] GotoIf("Local/[email protected];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 [[email protected]:17] GotoIf("Local/[email protected];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 [[email protected]:1] NoOp("Local/[email protected];2", "Outbound Recording Check from 17078476288 to 7073036327") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:2] Set("Local/[email protected];2", "RECMODE=") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:3] ExecIf("Local/[email protected];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 [[email protected]:7] Gosub("Local/[email protected];2", "recordcheck,1(dontcare,out,7073036327)") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:1] NoOp("Local/[email protected];2", "Starting recording check against dontcare") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:2] Goto("Local/[email protected];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 [[email protected]:3] Return("Local/[email protected];2", "") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:8] Return("Local/[email protected];2", "") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:3] Set("Local/[email protected];2", "MOHCLASS=default") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:4] Set("Local/[email protected];2", "_NODEST=") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:5] Macro("Local/[email protected];2", "dialout-trunk,2,7073036327,,off") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:1] Set("Local/[email protected];2", "DIAL_TRUNK=2") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:2] GosubIf("Local/[email protected];2", "0?sub-pincheck,s,1()") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:3] GotoIf("Local/[email protected];2", "0?disabletrunk,1") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:4] Set("Local/[email protected];2", "DIAL_NUMBER=7073036327") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:5] Set("Local/[email protected];2", "DIAL_TRUNK_OPTIONS=Ttr") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:6] Set("Local/[email protected];2", "OUTBOUND_GROUP=OUT_2") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:7] GotoIf("Local/[email protected];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 [[email protected]:9] GotoIf("Local/[email protected];2", "0?skipoutcid") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:10] Set("Local/[email protected];2", "DIAL_TRUNK_OPTIONS=Tt") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:11] Macro("Local/[email protected];2", "outbound-callerid,2") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:1] ExecIf("Local/[email protected];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 [[email protected]:2] ExecIf("Local/[email protected];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 [[email protected]:3] ExecIf("Local/[email protected];2", "0?Set(REALCALLERIDNUM=17078476288)") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:4] GotoIf("Local/[email protected];2", "0?normcid") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:5] Set("Local/[email protected];2", "USEROUTCID=17078476288") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:6] GotoIf("Local/[email protected];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 [[email protected]:8] Set("Local/[email protected];2", "EMERGENCYCID=") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:9] Set("Local/[email protected];2", "TRUNKOUTCID=5106382000") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:10] GotoIf("Local/[email protected];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 [[email protected]:15] ExecIf("Local/[email protected];2", "1?Set(CALLERID(all)=5106382000)") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:16] ExecIf("Local/[email protected];2", "1?Set(CALLERID(all)=17078476288)") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:17] ExecIf("Local/[email protected];2", "0?Set(CALLERID(all)=)") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:18] ExecIf("Local/[email protected];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 [[email protected]:19] ExecIf("Local/[email protected];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 [[email protected]:20] Set("Local/[email protected];2", "CDR(outbound_cnum)=17078476288") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:21] Set("Local/[email protected];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 [[email protected]:12] GosubIf("Local/[email protected];2", "0?sub-flp-2,s,1()") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:13] Set("Local/[email protected];2", "OUTNUM=7073036327") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:14] Set("Local/[email protected];2", "custom=SIP/fpbx-1-9e730791") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:15] ExecIf("Local/[email protected];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 [[email protected]:16] ExecIf("Local/[email protected];2", "0?Set(DIAL_TRUNK_OPTIONS=TtM(confirm))") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:17] Macro("Local/[email protected];2", "dialout-trunk-predial-hook,") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:1] MacroExit("Local/[email protected];2", "") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:18] GotoIf("Local/[email protected];2", "0?bypass,1") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:19] ExecIf("Local/[email protected];2", "0?Set(CONNECTEDLINE(num,i)=7073036327)") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:20] ExecIf("Local/[email protected];2", "0?Set(CONNECTEDLINE(name,i)=CID:17078476288)") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:21] ExecIf("Local/[email protected];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 [[email protected]:22] GotoIf("Local/[email protected];2", "0?customtrunk") in new stack
[2017-04-25 10:53:42] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:23] Dial("Local/[email protected];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#[email protected];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/[email protected];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/[email protected];2'
[2017-04-25 10:53:46] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:1] Macro("Local/[email protected];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#[email protected];2'
[2017-04-25 10:53:46] VERBOSE[18746][C-00002c18] pbx.c: Executing [[email protected]:1] Macro("Local/FMGL-7073036327#[email protected];2", "hangupcall") in new stack
[2017-04-25 10:53:46] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:1] GotoIf("Local/[email protected];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 [[email protected]:1] GotoIf("Local/FMGL-7073036327#[email protected];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 [[email protected]:3] ExecIf("Local/[email protected];2", "0?Set(CDR(recordingfile)=)") in new stack
[2017-04-25 10:53:46] VERBOSE[18748][C-00002c18] pbx.c: Executing [[email protected]:4] Hangup("Local/[email protected];2", "") in new stack
[2017-04-25 10:53:46] VERBOSE[18746][C-00002c18] pbx.c: Executing [[email protected]:3] ExecIf("Local/FMGL-7073036327#[email protected];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#[email protected];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 [email protected]:19      Ring    Dial(Local/[email protected]
Local/FMGL-707303632 [email protected]:1  Ringing AppDial((Outgoing Line))
SIP/503-00000007     FMGL-7073036327#-503 Ringing AppDial((Outgoing Line))
Local/[email protected] [email protected] Down    Return()
Local/[email protected] [email protected] Ring    Dial(SIP/fpbx-1-9e730791/70730
SIP/fpbx-1-9e730791- [email protected]:19      Up      Dial(Local/[email protected]
SIP/fpbx-1-9e730791- [email protected] Down    AppDial((Outgoing Line))
7 active channels
3 active calls
12 calls processed