Change External CID Configuration for Follow Me not working

Posted March 7th, 2010 by vbrief1 (tadpole)

I created a fixed value of "17185551212" and regardless of how I dial that extension ie:internal/external the caller id is not overwritten.

__________________

you may want to provide a

On March 8th, 2010 p_lindheimer (Developer) said:

you may want to provide a bit more detail then that or you are not going to be able to get any help.

It does work, but maybe your specific configuration is not working because it is being overridden elsewhere or limitations with your trunk.

Try providing a trace of the call that is not working, as well as a few details such as other callerid's that may be set on the specific trunk in question, outbound route, etc. and then someone may be able to isolate why you are getting the results you are of if there is some failure mode where it is not working as it should.

__________________

Philippe Lindheimer - FreePBX Project Leader
FreePBX Training Opportunities - Click Here
Get Official Paid Support - Click Here

I would have provided it,

On March 8th, 2010 vbrief1 (tadpole) said:

I would have provided it, but nowhere else is there any callerid modifications.

To test it, i directed a DID to an extension, created a follow me, and set the fixed caller ID value. The caller ID showing is the inbound call, not the fixed.

Also, the trunk allows any callerId so its not a trunk issue either.

p_lindheimer wrote: Try

On March 8th, 2010 p_lindheimer (Developer) said:

p_lindheimer wrote:

Try providing a trace of the call that is not working, as well as...

Please ... if you would like input tracking down the issue (whether a bug or yours) provide something that we can see.

Thank You

__________________

Philippe Lindheimer - FreePBX Project Leader
FreePBX Training Opportunities - Click Here
Get Official Paid Support - Click Here

Trace Enclosed

On March 8th, 2010 vbrief1 (tadpole) said:

VERBOSE[28639] logger.c: -- Executing Set("SIP/1168416176-b6f42a70", "__FROM_DID=212XXXXXX") in new stack
VERBOSE[28639] logger.c: -- Executing Gosub("SIP/1168416176-b6f42a70", "app-blacklist-check|s|1") in new stack
VERBOSE[28639] logger.c: -- Executing LookupBlacklist("SIP/1168416176-b6f42a70", "") in new stack
DEBUG[28639] db.c: Unable to find key '1718437XXXX' in family 'blacklist'
DEBUG[28639] db.c: Unable to find key 'John Doe' in family 'blacklist'
DEBUG[28639] pbx.c: Expression result is '0'
VERBOSE[28639] logger.c: -- Executing GotoIf("SIP/1168416176-b6f42a70", "0?blacklisted") in new stack
DEBUG[28639] pbx.c: Not taking any branch
VERBOSE[28639] logger.c: -- Executing Set("SIP/1168416176-b6f42a70", "CALLED_BLACKLIST=1") in new stack
VERBOSE[28639] logger.c: -- Executing Return("SIP/1168416176-b6f42a70", "") in new stack
VERBOSE[28639] logger.c: -- Executing Gosub("SIP/1168416176-b6f42a70", "cidlookup|cidlookup_2|1") in new stack
DEBUG[28639] pbx.c: Function result is '1718437XXXX'
DEBUG[28639] pbx.c: Function result is 'Jane Doe'
VERBOSE[28639] logger.c: -- Executing Set("SIP/1168416176-b6f42a70", "CALLERID(name)=Jane Doe") in new stack
VERBOSE[28639] logger.c: -- Executing Return("SIP/1168416176-b6f42a70", "") in new stack
DEBUG[28639] pbx.c: Function result is 'Jane Doe'
DEBUG[28639] pbx.c: Expression result is '0'
DEBUG[28639] pbx.c: Function result is '1718437XXXX'
VERBOSE[28639] logger.c: -- Executing ExecIf("SIP/1168416176-b6f42a70", "0 |Set|CALLERID(name)=1718437XXXX") in new

stack
VERBOSE[28639] logger.c: -- Executing Set("SIP/1168416176-b6f42a70", "__CALLINGPRES_SV=allowed_not_screened") in

new stack
VERBOSE[28639] logger.c: -- Executing SetCallerPres("SIP/1168416176-b6f42a70", "allowed_not_screened") in new stack
VERBOSE[28639] logger.c: -- Executing Goto("SIP/1168416176-b6f42a70", "from-did-direct|3004|1") in new stack
VERBOSE[28639] logger.c: -- Goto (from-did-direct,3004,1)
DEBUG[28639] pbx.c: Function result is 'DIRECT'
DEBUG[28639] pbx.c: Expression result is '0'
VERBOSE[28639] logger.c: -- Executing GotoIf("SIP/1168416176-b6f42a70", "0?ext-local|3004|1") in new stack
DEBUG[28639] pbx.c: Not taking any branch
VERBOSE[28639] logger.c: -- Executing Macro("SIP/1168416176-b6f42a70", "user-callerid|") in new stack
DEBUG[28639] pbx.c: Expression result is '1'
DEBUG[28639] pbx.c: Function result is '1718437XXXX'
DEBUG[28639] pbx.c: Function result is '1718437XXXX'
VERBOSE[28639] logger.c: -- Executing Set("SIP/1168416176-b6f42a70", "AMPUSER=1718437XXXX") in new stack
DEBUG[28639] app_macro.c: Executed application: Set
DEBUG[28639] pbx.c: Expression result is '0'
VERBOSE[28639] logger.c: -- Executing GotoIf("SIP/1168416176-b6f42a70", "0?report") in new stack
DEBUG[28639] pbx.c: Not taking any branch
DEBUG[28639] app_macro.c: Executed application: GotoIf
DEBUG[28639] pbx.c: Expression result is '1'
DEBUG[28639] pbx.c: Function result is '1718437XXXX'
VERBOSE[28639] logger.c: -- Executing ExecIf("SIP/1168416176-b6f42a70", "1|Set|REALCALLERIDNUM=1718437XXXX") in

new stack
DEBUG[28639] app_macro.c: Executed application: ExecIf
DEBUG[28639] pbx.c: Expression result is '0'
DEBUG[28639] pbx.c: Function result is '1718437XXXX'
DEBUG[28639] db.c: Unable to find key '1718437XXXX/user' in family 'DEVICE'
DEBUG[28639] func_db.c: DB: DEVICE/1718437XXXX/user not found in database.
DEBUG[28639] pbx.c: Function result is ''
VERBOSE[28639] logger.c: -- Executing Set("SIP/1168416176-b6f42a70", "AMPUSER=") in new stack
DEBUG[28639] app_macro.c: Executed application: Set
DEBUG[28639] db.c: Unable to find key '/cidname' in family 'AMPUSER'
DEBUG[28639] func_db.c: DB: AMPUSER//cidname not found in database.
DEBUG[28639] pbx.c: Function result is ''
VERBOSE[28639] logger.c: -- Executing Set("SIP/1168416176-b6f42a70", "AMPUSERCIDNAME=") in new stack
DEBUG[28639] app_macro.c: Executed application: Set
DEBUG[28639] pbx.c: Expression result is '1'
VERBOSE[28639] logger.c: -- Executing GotoIf("SIP/1168416176-b6f42a70", "1?report") in new stack
VERBOSE[28639] logger.c: -- Goto (macro-user-callerid,s,10)
DEBUG[28639] app_macro.c: Executed application: GotoIf
DEBUG[28639] pbx.c: Expression result is '0'
VERBOSE[28639] logger.c: -- Executing GotoIf("SIP/1168416176-b6f42a70", "0?continue") in new stack
DEBUG[28639] pbx.c: Not taking any branch
DEBUG[28639] app_macro.c: Executed application: GotoIf
DEBUG[28639] pbx.c: Expression result is '1'
DEBUG[28639] pbx.c: Expression result is '-1'
DEBUG[28639] pbx.c: Function result is '64'
VERBOSE[28639] logger.c: -- Executing Set("SIP/1168416176-b6f42a70", "__TTL=64") in new stack
DEBUG[28639] app_macro.c: Executed application: Set
DEBUG[28639] pbx.c: Expression result is '1'
VERBOSE[28639] logger.c: -- Executing GotoIf("SIP/1168416176-b6f42a70", "1?continue") in new stack
VERBOSE[28639] logger.c: -- Goto (macro-user-callerid,s,19)
DEBUG[28639] app_macro.c: Executed application: GotoIf
DEBUG[28639] pbx.c: Function result is '"Jane Doe" <1718437XXXX>'
VERBOSE[28639] logger.c: -- Executing NoOp("SIP/1168416176-b6f42a70", "Using CallerID "Jane Doe" <1718437XXXX>") in

new stack
DEBUG[28639] app_macro.c: Executed application: Noop
DEBUG[28639] pbx.c: Expression result is '1'
VERBOSE[28639] logger.c: -- Executing GotoIf("SIP/1168416176-b6f42a70", "1?skipdb") in new stack
VERBOSE[28639] logger.c: -- Goto (from-did-direct,3004,5)
VERBOSE[28639] logger.c: -- Executing Set("SIP/1168416176-b6f42a70", "__NODEST=") in new stack
VERBOSE[28639] logger.c: -- Executing Set("SIP/1168416176-b6f42a70",

"__BLKVM_OVERRIDE=BLKVM/3004/SIP/1168416176-b6f42a70") in new stack
VERBOSE[28639] logger.c: -- Executing Set("SIP/1168416176-b6f42a70", "__BLKVM_BASE=3004") in new stack
VERBOSE[28639] logger.c: -- Executing Set("SIP/1168416176-b6f42a70", "DB(BLKVM/3004/SIP/1168416176-b6f42a70)

=TRUE") in new stack
VERBOSE[28639] logger.c: -- Executing Set("SIP/1168416176-b6f42a70", "RRNODEST=") in new stack
VERBOSE[28639] logger.c: -- Executing Set("SIP/1168416176-b6f42a70", "__NODEST=3004") in new stack
DEBUG[28639] pbx.c: Function result is '1'
DEBUG[28639] pbx.c: Function result is 'fixed'
DEBUG[28639] pbx.c: Function result is 'fixed'
DEBUG[28639] pbx.c: Expression result is '1'
VERBOSE[28639] logger.c: -- Executing GosubIf("SIP/1168416176-b6f42a70", "1?sub-fmsetcid|s|1") in new stack
DEBUG[28639] pbx.c: Function result is 'fixed'
VERBOSE[28639] logger.c: -- Executing Goto("SIP/1168416176-b6f42a70", "s-fixed|1") in new stack
VERBOSE[28639] logger.c: -- Goto (sub-fmsetcid,s-fixed,1)
DEBUG[28639] pbx.c: Function result is '0'
DEBUG[28639] pbx.c: Expression result is '0'
DEBUG[28639] pbx.c: Function result is '718252XXXX'
VERBOSE[28639] logger.c: -- Executing ExecIf("SIP/1168416176-b6f42a70", "0|Set|__TRUNKCIDOVERRIDE=718252XXXX") in

new stack
VERBOSE[28639] logger.c: -- Executing Return("SIP/1168416176-b6f42a70", "") in new stack
VERBOSE[28639] logger.c: -- Executing Set("SIP/1168416176-b6f42a70", "RecordMethod=Group") in new stack
DEBUG[28639] pbx.c: Function result is '3000-1917862XXXX#'
VERBOSE[28639] logger.c: -- Executing Macro("SIP/1168416176-b6f42a70", "record-enable|3000-1917862XXXX#|Group") in

new stack
DEBUG[28639] pbx.c: Expression result is '1'
VERBOSE[28639] logger.c: -- Executing GotoIf("SIP/1168416176-b6f42a70", "1?check") in new stack
VERBOSE[28639] logger.c: -- Goto (macro-record-enable,s,4)
DEBUG[28639] app_macro.c: Executed application: GotoIf
DEBUG[28639] pbx.c: Expression result is '0'
VERBOSE[28639] logger.c: -- Executing ExecIf("SIP/1168416176-b6f42a70", "0|MacroExit|") in new stack
DEBUG[28639] app_macro.c: Executed application: ExecIf
DEBUG[28639] pbx.c: Expression result is '0'
DEBUG[28639] pbx.c: Expression result is '1'
VERBOSE[28639] logger.c: -- Executing GotoIf("SIP/1168416176-b6f42a70", "1?Group:OUT") in new stack
VERBOSE[28639] logger.c: -- Goto (macro-record-enable,s,6)
DEBUG[28639] app_macro.c: Executed application: GotoIf
DEBUG[28639] pbx.c: Function result is '2'
VERBOSE[28639] logger.c: -- Executing Set("SIP/1168416176-b6f42a70", "LOOPCNT=2") in new stack
DEBUG[28639] app_macro.c: Executed application: Set
VERBOSE[28639] logger.c: -- Executing Set("SIP/1168416176-b6f42a70", "ITER=1") in new stack
DEBUG[28639] app_macro.c: Executed application: Set
DEBUG[28639] pbx.c: Function result is '3000'
DEBUG[28639] pbx.c: Function result is 'out=Adhoc|in=Adhoc'
VERBOSE[28639] logger.c: -- Executing Set("SIP/1168416176-b6f42a70", "RECSET="out=Adhoc|in=Adhoc"") in new stack
DEBUG[28639] app_macro.c: Executed application: Set
ERROR[28639] app_cut.c: Usage: Splits a variable's contents using the specified delimiter
DEBUG[28639] pbx.c: Function result is ''
WARNING[28639] ast_expr2.fl: ast_yyerror(): syntax error: syntax error, unexpected TOKEN, expecting $end; Input:
WARNING[28639] ast_expr2.fl: If you have questions, please refer to doc/README.variables in the asterisk source.
DEBUG[28639] pbx.c: Expression result is '""'
VERBOSE[28639] logger.c: -- Executing GotoIf("SIP/1168416176-b6f42a70", """?continue") in new stack
VERBOSE[28639] logger.c: -- Goto (macro-record-enable,s,14)
DEBUG[28639] app_macro.c: Executed application: GotoIf
DEBUG[28639] pbx.c: Expression result is '2'
VERBOSE[28639] logger.c: -- Executing Set("SIP/1168416176-b6f42a70", "ITER=2") in new stack
DEBUG[28639] app_macro.c: Executed application: Set
DEBUG[28639] pbx.c: Expression result is '1'
VERBOSE[28639] logger.c: -- Executing GotoIf("SIP/1168416176-b6f42a70", "1?begin") in new stack
VERBOSE[28639] logger.c: -- Goto (macro-record-enable,s,8)
DEBUG[28639] app_macro.c: Executed application: GotoIf
DEBUG[28639] pbx.c: Function result is '1917862XXXX#'
DEBUG[28639] db.c: Unable to find key '1917862XXXX#/recording' in family 'AMPUSER'
DEBUG[28639] func_db.c: DB: AMPUSER/1917862XXXX#/recording not found in database.
DEBUG[28639] pbx.c: Function result is ''
VERBOSE[28639] logger.c: -- Executing Set("SIP/1168416176-b6f42a70", "RECSET=""") in new stack
DEBUG[28639] app_macro.c: Executed application: Set
ERROR[28639] app_cut.c: Usage: Splits a variable's contents using the specified delimiter
DEBUG[28639] pbx.c: Function result is ''
WARNING[28639] ast_expr2.fl: ast_yyerror(): syntax error: syntax error, unexpected TOKEN, expecting $end; Input:
WARNING[28639] ast_expr2.fl: If you have questions, please refer to doc/README.variables in the asterisk source.
DEBUG[28639] pbx.c: Expression result is '""'
VERBOSE[28639] logger.c: -- Executing GotoIf("SIP/1168416176-b6f42a70", """?continue") in new stack
VERBOSE[28639] logger.c: -- Goto (macro-record-enable,s,14)
DEBUG[28639] app_macro.c: Executed application: GotoIf
DEBUG[28639] pbx.c: Expression result is '3'
VERBOSE[28639] logger.c: -- Executing Set("SIP/1168416176-b6f42a70", "ITER=3") in new stack
DEBUG[28639] app_macro.c: Executed application: Set
DEBUG[28639] pbx.c: Expression result is '0'
VERBOSE[28639] logger.c: -- Executing GotoIf("SIP/1168416176-b6f42a70", "0?begin") in new stack
DEBUG[28639] pbx.c: Not taking any branch
DEBUG[28639] app_macro.c: Executed application: GotoIf
DEBUG[28639] pbx.c: Expression result is '0'
VERBOSE[28639] logger.c: -- Executing GotoIf("SIP/1168416176-b6f42a70", "0?IN") in new stack
DEBUG[28639] pbx.c: Not taking any branch
DEBUG[28639] app_macro.c: Executed application: GotoIf
DEBUG[28639] db.c: Unable to find key '3000-1917862XXXX#/recording' in family 'AMPUSER'
DEBUG[28639] func_db.c: DB: AMPUSER/3000-1917862XXXX#/recording not found in database.
DEBUG[28639] pbx.c: Function result is ''
ERROR[28639] app_cut.c: Usage: Splits a variable's contents using the specified delimiter
DEBUG[28639] pbx.c: Function result is ''
DEBUG[28639] pbx.c: Expression result is '1'
VERBOSE[28639] logger.c: -- Executing ExecIf("SIP/1168416176-b6f42a70", "1|MacroExit|") in new stack
VERBOSE[28639] logger.c: -- Executing Set("SIP/1168416176-b6f42a70", "RingGroupMethod=ringallv2") in new stack
VERBOSE[28639] logger.c: -- Executing Set("SIP/1168416176-b6f42a70", "_FMGRP=3004") in new stack
DEBUG[28639] pbx.c: Function result is 'ENABLED'
DEBUG[28639] pbx.c: Expression result is '1'
VERBOSE[28639] logger.c: -- Executing GotoIf("SIP/1168416176-b6f42a70", "1?doconfirm") in new stack
VERBOSE[28639] logger.c: -- Goto (from-did-direct,3004,19)
DEBUG[28639] pbx.c: Function result is '20'
DEBUG[28639] pbx.c: Function result is '0'
DEBUG[28639] pbx.c: Expression result is '20'
DEBUG[28639] pbx.c: Function result is '3000-1917862XXXX#'
VERBOSE[28639] logger.c: -- Executing Macro("SIP/1168416176-b6f42a70", "dial-confirm|20|trw|3000-

1917862XXXX#|3004") in new stack
VERBOSE[28639] logger.c: -- Executing Set("SIP/1168416176-b6f42a70", "DB(RG/3004/SIP/1168416176-b6f42a70)

=RINGING") in new stack
DEBUG[28639] app_macro.c: Executed application: Set
VERBOSE[28639] logger.c: -- Executing Set("SIP/1168416176-b6f42a70", "__UNIQCHAN=SIP/1168416176-b6f42a70") in new

stack
DEBUG[28639] app_macro.c: Executed application: Set
VERBOSE[28639] logger.c: -- Executing Set("SIP/1168416176-b6f42a70", "USE_CONFIRMATION=TRUE") in new stack
DEBUG[28639] app_macro.c: Executed application: Set
VERBOSE[28639] logger.c: -- Executing Set("SIP/1168416176-b6f42a70", "RINGGROUP_INDEX=3004") in new stack
DEBUG[28639] app_macro.c: Executed application: Set
VERBOSE[28639] logger.c: -- Executing Set("SIP/1168416176-b6f42a70", "ARG4=") in new stack
DEBUG[28639] app_macro.c: Executed application: Set
VERBOSE[28639] logger.c: -- Executing Macro("SIP/1168416176-b6f42a70", "dial|20|trw|3000-1917862XXXX#") in new stack
DEBUG[28639] pbx.c: Expression result is '1'
VERBOSE[28639] logger.c: -- Executing GotoIf("SIP/1168416176-b6f42a70", "1?dial") in new stack
VERBOSE[28639] logger.c: -- Goto (macro-dial,s,3)
DEBUG[28639] app_macro.c: Executed application: GotoIf
VERBOSE[28639] logger.c: -- Executing AGI("SIP/1168416176-b6f42a70", "dialparties.agi") in new stack
VERBOSE[28639] logger.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
VERBOSE[28639] logger.c: dialparties.agi: Starting New Dialparties.agi
VERBOSE[28639] logger.c: dialparties.agi: Caller ID name is 'Jane Doe' number is '1718437XXXX'
VERBOSE[28639] logger.c: dialparties.agi: USE_CONFIRMATION: 'TRUE'
VERBOSE[28639] logger.c: dialparties.agi: RINGGROUP_INDEX: '3004'
VERBOSE[28639] logger.c: dialparties.agi: Methodology of ring is 'ringallv2'
VERBOSE[28639] logger.c: -- dialparties.agi: Added extension 3000 to extension map
VERBOSE[28639] logger.c: -- dialparties.agi: Added extension 1917862XXXX# to extension map
VERBOSE[28639] logger.c: > dialparties.agi: got fmgrp_prering: 2, fmgrp_grptime: 20
VERBOSE[28639] logger.c: > dialparties.agi: fmgrp_totalprering: 22
VERBOSE[28639] logger.c: > dialparties.agi: extension not in group list, ringing only during prering time
VERBOSE[28639] logger.c: > dialparties.agi: ringallv2 ring times: REALPRERING: 2, PRERING: 2
DEBUG[28639] db.c: Unable to find key '3004/screen' in family 'AMPUSER'
VERBOSE[28639] logger.c: > dialparties.agi: Extension 1917862XXXX# has call screening off
DEBUG[28639] db.c: Unable to find key '3004' in family 'CF'
VERBOSE[28639] logger.c: -- dialparties.agi: Extension 3004 cf is disabled
DEBUG[28639] db.c: Unable to find key '3000' in family 'CF'
VERBOSE[28639] logger.c: -- dialparties.agi: Extension 3000 cf is disabled
DEBUG[28639] db.c: Unable to find key '1917862XXXX#' in family 'CF'
VERBOSE[28639] logger.c: -- dialparties.agi: Extension 1917862XXXX# cf is disabled
DEBUG[28639] db.c: Unable to find key '3004' in family 'DND'
VERBOSE[28639] logger.c: -- dialparties.agi: Extension 3004 do not disturb is disabled
DEBUG[28639] db.c: Unable to find key '3000' in family 'DND'
VERBOSE[28639] logger.c: -- dialparties.agi: Extension 3000 do not disturb is disabled
DEBUG[28639] db.c: Unable to find key '3004' in family 'CFB'
DEBUG[28639] db.c: Unable to find key '3004' in family 'CFU'
VERBOSE[28639] logger.c: > dialparties.agi: extnum 3004 has: cw: 1; hascfb: 0 [] hascfu: 0 []
VERBOSE[28639] logger.c: -- dialparties.agi: dbset CALLTRACE/3004 to 1718437XXXX
DEBUG[28639] db.c: Unable to find key '3000' in family 'CFB'
DEBUG[28639] db.c: Unable to find key '3000' in family 'CFU'
VERBOSE[28639] logger.c: > dialparties.agi: extnum 3000 has: cw: 1; hascfb: 0 [] hascfu: 0 []
VERBOSE[28639] logger.c: -- dialparties.agi: dbset CALLTRACE/3000 to 1718437XXXX
DEBUG[28639] db.c: Unable to find key '1917862XXXX#' in family 'CW'
DEBUG[28639] db.c: Unable to find key '1917862XXXX#' in family 'CFB'
DEBUG[28639] db.c: Unable to find key '1917862XXXX#' in family 'CFU'
VERBOSE[28639] logger.c: > dialparties.agi: extnum 1917862XXXX# has: cw: 0; hascfb: 0 [] hascfu: 0 []
VERBOSE[28639] logger.c: > dialparties.agi: Built External dialstring component for 1917862XXXX: Local/RG-3004-

1917862XXXX#@from-internal
VERBOSE[28639] logger.c: -- dialparties.agi: Filtered ARG3: 3004-3000-1917862XXXX
VERBOSE[28639] logger.c: dialparties.agi: FMUNIQUE: SIP/1168416176-b6f42a70, FMRERING: 0, FMREALPRERING: 2,

FMGRPTIME: 20
VERBOSE[28639] logger.c: > dialparties.agi: NODEST: 3004 adding M(auto-blkvm) to dialopts: trwM(auto-blkvm)
VERBOSE[28639] logger.c: -- AGI Script dialparties.agi completed, returning 0
DEBUG[28639] app_macro.c: Executed application: AGI
VERBOSE[28639] logger.c: -- Executing Dial("SIP/1168416176-b6f42a70", "Local/FMPR-3004@from-internal&Local/FMGL-

3000-1917862XXXX#@from-internal|22|trwM(auto-blkvm)") in new st
VERBOSE[28639] logger.c: -- Called FMPR-3004@from-internal
VERBOSE[28639] logger.c: -- Called FMGL-3000-1917862XXXX#@from-internal
VERBOSE[28639] logger.c: -- Local/FMGL-3000-1917862XXXX#@from-internal-f083,1 is ringing
VERBOSE[28639] logger.c: -- Local/FMGL-3000-1917862XXXX#@from-internal-f083,1 answered SIP/1168416176-b6f42a70
VERBOSE[28639] logger.c: -- Executing Set("Local/FMGL-3000-1917862XXXX#@from-internal-f083,1", "__MACRO_RESULT=")

in new stack
DEBUG[28639] app_macro.c: Executed application: Set
VERBOSE[28639] logger.c: -- Executing DBdel("Local/FMGL-3000-1917862XXXX#@from-internal-f083,1",

"BLKVM/3004/SIP/1168416176-b6f42a70") in new stack
VERBOSE[28639] logger.c: -- DBdel: family=BLKVM, key=3004/SIP/1168416176-b6f42a70
DEBUG[28639] db.c: Unable to find key '3004/SIP/1168416176-b6f42a70' in family 'BLKVM'
VERBOSE[28639] logger.c: -- DBdel: Error deleting key from database.
DEBUG[28639] app_macro.c: Executed application: dbDel
DEBUG[28639] app_dial.c: Macro exited with status 0
DEBUG[28639] channel.c: Got clone lock for masquerade on 'Local/1917862XXXX@from-internal-60fb,1' at 0x8b0b8e4
DEBUG[28639] channel.c: Putting channel Local/1917862XXXX@from-internal-60fb,1 in 4/4 formats
DEBUG[28639] channel.c: Released clone lock on 'Local/FMGL-3000-1917862XXXX#@from-internal-f083,1'
DEBUG[28639] channel.c: Done Masquerading Local/1917862XXXX@from-internal-60fb,1 (6)
DEBUG[28639] channel.c: Got DTMF on channel (Local/1917862XXXX@from-internal-60fb,1)
DEBUG[28639] channel.c: Bridge stops bridging channels SIP/1168416176-b6f42a70 and Local/1917862XXXX@from-internal-

60fb,1
DEBUG[28639] res_features.c: Feature interpret: chan=SIP/1168416176-b6f42a70, peer=Local/1917862XXXX@from-internal-

60fb,1, sense=2, features=18
DEBUG[28639] res_features.c: Set time limit to 500
DEBUG[28639] channel.c: Nobody there, continuing...
DEBUG[28639] channel.c: Bridge stops bridging channels SIP/1168416176-b6f42a70 and Local/1917862XXXX@from-internal-

60fb,1
DEBUG[28639] channel.c: Bridge stops bridging channels SIP/1168416176-b6f42a70 and Local/1917862XXXX@from-internal-

60fb,1
DEBUG[28639] res_features.c: Timed out for feature!
DEBUG[28639] channel.c: Got DTMF on channel (Local/1917862XXXX@from-internal-60fb,1)
DEBUG[28639] channel.c: Bridge stops bridging channels SIP/1168416176-b6f42a70 and Local/1917862XXXX@from-internal-

60fb,1
DEBUG[28639] res_features.c: Feature interpret: chan=SIP/1168416176-b6f42a70, peer=Local/1917862XXXX@from-internal-

60fb,1, sense=2, features=18
DEBUG[28639] res_features.c: Set time limit to 500
DEBUG[28639] channel.c: Bridge stops bridging channels SIP/1168416176-b6f42a70 and Local/1917862XXXX@from-internal-

60fb,1
DEBUG[28639] channel.c: Bridge stops bridging channels SIP/1168416176-b6f42a70 and Local/1917862XXXX@from-internal-

60fb,1
DEBUG[28639] res_features.c: Feature interpret: chan=SIP/1168416176-b6f42a70, peer=Local/1917862XXXX@from-internal-

60fb,1
DEBUG[28639] channel.c: Bridge stops bridging channels SIP/1168416176-b6f42a70 and Local/1917862XXXX@from-internal-

60fb,1
DEBUG[28639] res_features.c: Feature interpret: chan=SIP/1168416176-b6f42a70, peer=Local/1917862XXXX@from-internal-

60fb,1, sense=2, features=18
DEBUG[28639] res_features.c: Set time limit to 500
DEBUG[28639] channel.c: Bridge stops bridging channels SIP/1168416176-b6f42a70 and Local/1917862XXXX@from-internal-

60fb,1
DEBUG[28639] channel.c: Bridge stops bridging channels SIP/1168416176-b6f42a70 and Local/1917862XXXX@from-internal-

60fb,1
DEBUG[28639] res_features.c: Timed out for feature!
DEBUG[28639] channel.c: Didn't get a frame from channel: Local/1917862XXXX@from-internal-60fb,1
DEBUG[28639] channel.c: Bridge stops bridging channels SIP/1168416176-b6f42a70 and Local/1917862XXXX@from-internal-

60fb,1
DEBUG[28639] app_dial.c: Exiting with DIALSTATUS=ANSWER.
VERBOSE[28639] logger.c: == Spawn extension (macro-dial, s, 7) exited non-zero on 'SIP/1168416176-b6f42a70' in macro 'dial'
VERBOSE[28639] logger.c: == Spawn extension (macro-dial, s, 7) exited non-zero on 'SIP/1168416176-b6f42a70' in macro 'dial

-confirm'
VERBOSE[28639] logger.c: == Spawn extension (macro-dial, s, 7) exited non-zero on 'SIP/1168416176-b6f42a70'
VERBOSE[28639] logger.c: -- Executing Macro("SIP/1168416176-b6f42a70", "hangupcall") in new stack
DEBUG[28639] pbx.c: Expression result is '0'
VERBOSE[28639] logger.c: -- Executing GotoIf("SIP/1168416176-b6f42a70", "0?skiprg") in new stack
DEBUG[28639] pbx.c: Not taking any branch
DEBUG[28639] app_macro.c: Executed application: GotoIf
VERBOSE[28639] logger.c: -- Executing NoOp("SIP/1168416176-b6f42a70", "Cleaning Up Confirmation Flag:

RG/3004/SIP/1168416176-b6f42a70") in new stack
DEBUG[28639] app_macro.c: Executed application: Noop
VERBOSE[28639] logger.c: -- Executing DBdel("SIP/1168416176-b6f42a70", "RG/3004/SIP/1168416176-b6f42a70") in new

stack
VERBOSE[28639] logger.c: -- DBdel: family=RG, key=3004/SIP/1168416176-b6f42a70
DEBUG[28639] db.c: Unable to find key '3004/SIP/1168416176-b6f42a70' in family 'RG'
VERBOSE[28639] logger.c: -- DBdel: Error deleting key from database.
DEBUG[28639] app_macro.c: Executed application: dbDel
DEBUG[28639] pbx.c: Expression result is '0'
VERBOSE[28639] logger.c: -- Executing GotoIf("SIP/1168416176-b6f42a70", "0?skipblkvm") in new stack
DEBUG[28639] pbx.c: Not taking any branch
DEBUG[28639] app_macro.c: Executed application: GotoIf
VERBOSE[28639] logger.c: -- Executing NoOp("SIP/1168416176-b6f42a70", "Cleaning Up Block VM Flag:

BLKVM/3004/SIP/1168416176-b6f42a70") in new stack
DEBUG[28639] app_macro.c: Executed application: Noop
VERBOSE[28639] logger.c: -- Executing DBdel("SIP/1168416176-b6f42a70", "BLKVM/3004/SIP/1168416176-b6f42a70") in

new stack
VERBOSE[28639] logger.c: -- DBdel: family=BLKVM, key=3004/SIP/1168416176-b6f42a70
DEBUG[28639] db.c: Unable to find key '3004/SIP/1168416176-b6f42a70' in family 'BLKVM'
VERBOSE[28639] logger.c: -- DBdel: Error deleting key from database.
DEBUG[28639] app_macro.c: Executed application: dbDel
DEBUG[28639] pbx.c: Expression result is '0'
VERBOSE[28639] logger.c: -- Executing GotoIf("SIP/1168416176-b6f42a70", "0?theend") in new stack
DEBUG[28639] pbx.c: Not taking any branch
DEBUG[28639] app_macro.c: Executed application: GotoIf
VERBOSE[28639] logger.c: -- Executing DBdel("SIP/1168416176-b6f42a70", "FM/DND/3004/SIP/1168416176-b6f42a70") in

new stack
VERBOSE[28639] logger.c: -- DBdel: family=FM, key=DND/3004/SIP/1168416176-b6f42a70
DEBUG[28639] db.c: Unable to find key 'DND/3004/SIP/1168416176-b6f42a70' in family 'FM'
VERBOSE[28639] logger.c: -- DBdel: Error deleting key from database.
DEBUG[28639] app_macro.c: Executed application: dbDel
VERBOSE[28639] logger.c: -- Executing Hangup("SIP/1168416176-b6f42a70", "") in new stack
VERBOSE[28639] logger.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/1168416176-b6f42a70' in

macro 'hangupcall'
VERBOSE[28639] logger.c: == Spawn extension (macro-hangupcall, s, 9) exited non-zero on 'SIP/1168416176-b6f42a70'
DEBUG[28639] cdr_addon_mysql.c: cdr_mysql: inserting a CDR record.
DEBUG[28639] cdr_addon_mysql.c: cdr_mysql: SQL command as follows: INSERT INTO cdr

(calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition
DEBUG[28639] chan_sip.c: update_call_counter(1168416176) - decrement call limit counter

this does not look complete.

On March 8th, 2010 p_lindheimer (Developer) said:

this does not look complete. After the dialparties.agi I don't see where the external call to 917862XXXX is being sent out a trunk and that is the only one that would have the CID set, the CID is not set on internal follow-me extensions (maybe that should be clarified if it is confusing).

However, despite apparently missing call log information to see if it is trying to set the CID, this line is suspect:

VERBOSE[28639] logger.c: -- Executing ExecIf("SIP/1168416176-b6f42a70", "0|Set|__TRUNKCIDOVERRIDE=718252XXXX") in

if I am reading that correctly, it thinks that 718252XXXX has non-numeric characters and is thus not setting the override value since only numeric CIDs are allowed here. I am assuming that you edited that log entry to hide the actual number since iirc, I believe we validate and remove any non-digits anyhow when that is set. If not it may be a bug that we are not properly filtering the value but that filter in the dialplan was explicitly added in case other apps are manipulating the astdb variable where this is set.

So .. still non-conclusive and I would ask, if you are editing the log file, to just change the fixed value you are sending out to something non-sensitive and then minimize any editing you may feel you need to do to protect sensitive info in the log (like the phone num it is being follow-me-ed to which I understand you probably don't want in the forums).

__________________

Philippe Lindheimer - FreePBX Project Leader
FreePBX Training Opportunities - Click Here
Get Official Paid Support - Click Here