Can not dial out from action bar

[size=150:18c0rxa3]I can not dial out from the action bar. It calls me but when I answer it immediately hangs up.[/size:18c0rxa3]

[size=150:18c0rxa3]Here is the asterisk debug when I click an extension then click the dial button (ext 1003 calling 1002):[/size:18c0rxa3]
== Starting SIP/1003-09120c28 at from-internal,1002,1 failed so falling back to exten 's'
== Starting SIP/1003-09120c28 at from-internal,s,1 still failed so falling back to context 'default'
[Jul 3 22:24:00] WARNING[7848]: pbx.c:2474 __ast_pbx_run: Channel 'SIP/1003-09120c28' sent into invalid extension 's' in context 'default', but no invalid handler
[Jul 3 22:24:00] WARNING[7848]: pbx.c:2474 __ast_pbx_run: Channel 'SIP/1003-09120c28' sent into invalid extension 's' in context 'default', but no invalid handler

[size=150:18c0rxa3]Here is a successful call directly from my SIP client to ext 1000[/size:18c0rxa3]
-- Executing [1002@from-inside:1] Macro("SIP/1003-0916c5e0", "tl-set-variables2|from-inside-redir|") in new stack
-- Executing [s@macro-tl-set-variables2:1] Set("SIP/1003-0916c5e0", "__tenant=") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-set-variables2:2] Set("SIP/1003-0916c5e0", "CDR(userfield)=") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-set-variables2:3] Set("SIP/1003-0916c5e0", "__FROM_INSIDE=1") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-set-variables2:4] Set("SIP/1003-0916c5e0", "__MOH=default") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-set-variables2:5] GotoIf("SIP/1003-0916c5e0", "1 ?setmoh") in new stack
-- Goto (macro-tl-set-variables2,s,7)
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-tl-set-variables2:7] SetMusicOnHold("SIP/1003-0916c5e0", "default") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: SetMusicOnHold
-- Executing [s@macro-tl-set-variables2:8] Goto("SIP/1003-0916c5e0", "from-inside-redir|1002|1") in new stack
-- Goto (from-inside-redir,1002,1)
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Goto
== Channel 'SIP/1003-0916c5e0' jumping out of macro 'tl-set-variables2'
-- Executing [1002@from-inside-redir:1] Macro("SIP/1003-0916c5e0", "tl-stdexten|SIP/1002|1002|") in new stack
-- Executing [s@macro-tl-stdexten:1] Set("SIP/1003-0916c5e0", "__DIALED_NUMBER=1002") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-stdexten:2] Set("SIP/1003-0916c5e0", "__PICKUPMARK=1002") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-stdexten:3] ExecIf("SIP/1003-0916c5e0", "0|SIPAddHeader|Alert-Info: <>") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: ExecIf
-- Executing [s@macro-tl-stdexten:4] GotoIf("SIP/1003-0916c5e0", "0?doingringgroup") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-tl-stdexten:5] Macro("SIP/1003-0916c5e0", "tl-userexten-base|SIP/1002|1002|") in new stack
-- Executing [s@macro-tl-userexten-base:1] GotoIf("SIP/1003-0916c5e0", "1?set_options") in new stack
-- Goto (macro-tl-userexten-base,s,8)
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-tl-userexten-base:8] Set("SIP/1003-0916c5e0", "OPTIONS=rtT") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-userexten-base:9] Set("SIP/1003-0916c5e0", "__PHONE=SIP/1002") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-userexten-base:10] Set("SIP/1003-0916c5e0", "__VM_MBOX=1002") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-userexten-base:11] Set("SIP/1003-0916c5e0", "THISEXT=TL/1002") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-userexten-base:12] Set("SIP/1003-0916c5e0", "DB(TL/1002/lastcallerid)=1003") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-userexten-base:13] Set("SIP/1003-0916c5e0", "_CLIMYID=1002") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-userexten-base:14] Set("SIP/1003-0916c5e0", "THISCHAN=TL/1002") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-userexten-base:15] GotoIf("SIP/1003-0916c5e0", "0?beenhere") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-tl-userexten-base:16] Set("SIP/1003-0916c5e0", "_ORIG_EXTEN=1002") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-userexten-base:17] Set("SIP/1003-0916c5e0", "_ORIG_EXTEN_USER=TL/1002") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-userexten-base:18] Macro("SIP/1003-0916c5e0", "tl-notify") in new stack
[Jul 3 22:26:21] DEBUG[8137]: func_db.c:70 function_db_read: DB: TL/1002/ADDRESS not found in database.
-- Executing [s@macro-tl-notify:1] Set("SIP/1003-0916c5e0", "ADDRESS=") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-notify:2] UserEvent("SIP/1003-0916c5e0", "TlNotify|dialed: 1002|callerID: 1003|tenant: ") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: UserEvent
-- Executing [s@macro-tl-notify:3] NoOp("SIP/1003-0916c5e0", "TL/1002") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: NoOp
-- Executing [s@macro-tl-notify:4] GotoIf("SIP/1003-0916c5e0", "1?s-exit|1") in new stack
-- Goto (macro-tl-notify,s-exit,1)
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [s-exit@macro-tl-notify:1] MacroExit("SIP/1003-0916c5e0", "") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Macro
-- Executing [s@macro-tl-userexten-base:19] Goto("SIP/1003-0916c5e0", "checkformat") in new stack
-- Goto (macro-tl-userexten-base,s,21)
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Goto
-- Executing [s@macro-tl-userexten-base:21] GotoIf("SIP/1003-0916c5e0", "1?cont1") in new stack
-- Goto (macro-tl-userexten-base,s,23)
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-tl-userexten-base:23] Set("SIP/1003-0916c5e0", "RECORD_CALLEE=1") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-userexten-base:24] Macro("SIP/1003-0916c5e0", "tl-set-myvariables") in new stack
-- Executing [s@macro-tl-set-myvariables:1] Set("SIP/1003-0916c5e0", "MY_CHAN=1003-0916c5e0") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-set-myvariables:2] NoOp("SIP/1003-0916c5e0", "THECHANNEL=SIP/1003-0916c5e0") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: NoOp
-- Executing [s@macro-tl-set-myvariables:3] Set("SIP/1003-0916c5e0", "zap=0") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-set-myvariables:4] GotoIf("SIP/1003-0916c5e0", "1?usechannel") in new stack
-- Goto (macro-tl-set-myvariables,s,9)
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-tl-set-myvariables:9] Set("SIP/1003-0916c5e0", "local=0") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-set-myvariables:10] GotoIf("SIP/1003-0916c5e0", "1?useit") in new stack
-- Goto (macro-tl-set-myvariables,s,12)
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-tl-set-myvariables:12] Set("SIP/1003-0916c5e0", "__MYEXTENSION=1003") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-set-myvariables:13] Set("SIP/1003-0916c5e0", "__MYID=1003") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Macro
-- Executing [s@macro-tl-userexten-base:25] Set("SIP/1003-0916c5e0", "RECORD_CALLER=") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-userexten-base:26] Set("SIP/1003-0916c5e0", "VM=1") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
[Jul 3 22:26:21] DEBUG[8137]: func_db.c:70 function_db_read: DB: TL/1002/VMT0 not found in database.
-- Executing [s@macro-tl-userexten-base:27] Set("SIP/1003-0916c5e0", "VMT0=") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
[Jul 3 22:26:21] DEBUG[8137]: func_db.c:70 function_db_read: DB: TL/1002/CFNAEXT not found in database.
-- Executing [s@macro-tl-userexten-base:28] Set("SIP/1003-0916c5e0", "CFNAEXT=") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
[Jul 3 22:26:21] DEBUG[8137]: func_db.c:70 function_db_read: DB: TL/1002/CFNAAN not found in database.
-- Executing [s@macro-tl-userexten-base:29] Set("SIP/1003-0916c5e0", "CFNAAN=") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-userexten-base:30] GotoIf("SIP/1003-0916c5e0", "0?done_checkrecord") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-tl-userexten-base:31] GotoIf("SIP/1003-0916c5e0", "0?check_caller") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-tl-userexten-base:32] Set("SIP/1003-0916c5e0", "FNAME=in") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-userexten-base:33] GotoIf("SIP/1003-0916c5e0", "1?check_if_record") in new stack
-- Goto (macro-tl-userexten-base,s,35)
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-tl-userexten-base:35] GotoIf("SIP/1003-0916c5e0", "0?check_onetouch") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-tl-userexten-base:36] GotoIf("SIP/1003-0916c5e0", "1?record") in new stack
-- Goto (macro-tl-userexten-base,s,38)
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-tl-userexten-base:38] MixMonitor("SIP/1003-0916c5e0", "in-1002-2009-07-03-22:26:21-1003.wav|b") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: MixMonitor
-- Executing [s@macro-tl-userexten-base:39] GotoIf("SIP/1003-0916c5e0", "1?check_caller_onetouch") in new stack
-- Goto (macro-tl-userexten-base,s,42)
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-tl-userexten-base:42] GotoIf("SIP/1003-0916c5e0", "1?check_if_onetouch") in new stack
-- Goto (macro-tl-userexten-base,s,45)
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-tl-userexten-base:45] GotoIf("SIP/1003-0916c5e0", "0?done_checkrecord") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-tl-userexten-base:46] Set("SIP/1003-0916c5e0", "TOUCH_MONITOR_FORMAT=wav") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-userexten-base:47] Set("SIP/1003-0916c5e0", "TOUCH_MONITOR=in-1002-2009-07-03-22:26:21-1003") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-userexten-base:48] NoOp("SIP/1003-0916c5e0", "") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: NoOp
-- Executing [s@macro-tl-userexten-base:49] NoOp("SIP/1003-0916c5e0", "RECORD_CALLEE=1") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: NoOp
-- Executing [s@macro-tl-userexten-base:50] NoOp("SIP/1003-0916c5e0", "RECORD_CALLER=") in new stack
== Begin MixMonitor Recording SIP/1003-0916c5e0
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: NoOp
-- Executing [s@macro-tl-userexten-base:51] NoOp("SIP/1003-0916c5e0", "OPTIONS=rtT") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: NoOp
-- Executing [s@macro-tl-userexten-base:52] NoOp("SIP/1003-0916c5e0", "TOUCH_MONITOR=in-1002-2009-07-03-22:26:21-1003") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: NoOp
-- Executing [s@macro-tl-userexten-base:53] GotoIf("SIP/1003-0916c5e0", "0?next1") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-tl-userexten-base:54] Set("SIP/1003-0916c5e0", "TIMEOUT=") in new stack
Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-userexten-base:55] GotoIf("SIP/1003-0916c5e0", "0?next1") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-tl-userexten-base:56] Set("SIP/1003-0916c5e0", "TIMEOUT=20") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-userexten-base:57] Set("SIP/1003-0916c5e0", "CDR(userfield)=") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
[Jul 3 22:26:21] DEBUG[8137]: func_db.c:70 function_db_read: DB: TL/1002/SCREEN not found in database.
-- Executing [s@macro-tl-userexten-base:58] Set("SIP/1003-0916c5e0", "SCREEN=") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
[Jul 3 22:26:21] DEBUG[8137]: func_db.c:70 function_db_read: DB: TL/1002/CFCONFIRM not found in database.
-- Executing [s@macro-tl-userexten-base:59] Set("SIP/1003-0916c5e0", "CONFIRM=") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-userexten-base:60] GotoIf("SIP/1003-0916c5e0", "1?getblock") in new stack
-- Goto (macro-tl-userexten-base,s,65)
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: GotoIf
[Jul 3 22:26:21] DEBUG[8137]: func_db.c:70 function_db_read: DB: TL/1002/BLOCK not found in database.
-- Executing [s@macro-tl-userexten-base:65] Set("SIP/1003-0916c5e0", "BLOCK=") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-userexten-base:66] GotoIf("SIP/1003-0916c5e0", "1?getrecord") in new stack
-- Goto (macro-tl-userexten-base,s,70)
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-tl-userexten-base:70] Set("SIP/1003-0916c5e0", "RECORD=1") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-userexten-base:71] NoOp("SIP/1003-0916c5e0", "calleridnum=1003 ") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: NoOp
-- Executing [s@macro-tl-userexten-base:72] GotoIf("SIP/1003-0916c5e0", "1?screening") in new stack
-- Goto (macro-tl-userexten-base,s,104)
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-tl-userexten-base:104] GotoIf("SIP/1003-0916c5e0", "1?recording") in new stack
-- Goto (macro-tl-userexten-base,s,130)
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-tl-userexten-base:130] GotoIf("SIP/1003-0916c5e0", "0?forwarding") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-tl-userexten-base:131] GotoIf("SIP/1003-0916c5e0", "0?forwarding") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: GotoIf
[Jul 3 22:26:21] DEBUG[8137]: func_db.c:70 function_db_read: DB: TL/1002/CFA not found in database.
-- Executing [s@macro-tl-userexten-base:132] Set("SIP/1003-0916c5e0", "FORWARD=") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-userexten-base:133] GotoIf("SIP/1003-0916c5e0", "1?followmecheck") in new stack
-- Goto (macro-tl-userexten-base,s,140)
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: GotoIf
[Jul 3 22:26:21] DEBUG[8137]: func_db.c:70 function_db_read: DB: TL/1002/CFNA not found in database.
-- Executing [s@macro-tl-userexten-base:140] Set("SIP/1003-0916c5e0", "FORWARD=") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-userexten-base:141] Set("SIP/1003-0916c5e0", "__FOLLOWME=0") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-userexten-base:142] GotoIf("SIP/1003-0916c5e0", "1?checkchannel") in new stack
-- Goto (macro-tl-userexten-base,s,154)
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-tl-userexten-base:154] ChanIsAvail("SIP/1003-0916c5e0", "SIP/1002") in new stack
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: ChanIsAvail
-- Executing [s@macro-tl-userexten-base:155] GotoIf("SIP/1003-0916c5e0", "1?chanavail") in new stack
-- Goto (macro-tl-userexten-base,s,157)
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-tl-userexten-base:157] GotoIf("SIP/1003-0916c5e0", "1?dial") in new stack
-- Goto (macro-tl-userexten-base,s,163)
[Jul 3 22:26:21] DEBUG[8137]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-tl-userexten-base:163] Dial("SIP/1003-0916c5e0", "SIP/1002|20|rtT") in new stack
-- Called 1002



[size=150:18c0rxa3]When I try to dial an outside number from the action bar I get this from the asterisk debug log:[/size:18c0rxa3]
== Starting SIP/1003-0916c5e0 at from-internal,93560092,1 failed so falling back to exten 's'
== Starting SIP/1003-0916c5e0 at from-internal,s,1 still failed so falling back to context 'default'
[Jul 3 22:18:10] WARNING[7553]: pbx.c:2474 __ast_pbx_run: Channel 'SIP/1003-0916c5e0' sent into invalid extension 's' in context 'default', but no invalid handler
[Jul 3 22:18:10] WARNING[7553]: pbx.c:2474 __ast_pbx_run: Channel 'SIP/1003-0916c5e0' sent into invalid extension 's' in context 'default', but no invalid handler

[size=150:18c0rxa3]When I dial straight from my SIP client it is successful:[/size:18c0rxa3]
-- Executing [93560092@from-inside:1] Macro("SIP/1003-0916c5e0", "tl-set-variables2|from-inside-redir|") in new stack
-- Executing [s@macro-tl-set-variables2:1] Set("SIP/1003-0916c5e0", "__tenant=") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-set-variables2:2] Set("SIP/1003-0916c5e0", "CDR(userfield)=") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-set-variables2:3] Set("SIP/1003-0916c5e0", "__FROM_INSIDE=1") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-set-variables2:4] Set("SIP/1003-0916c5e0", "__MOH=default") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-set-variables2:5] GotoIf("SIP/1003-0916c5e0", "1 ?setmoh") in new stack
-- Goto (macro-tl-set-variables2,s,7)
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-tl-set-variables2:7] SetMusicOnHold("SIP/1003-0916c5e0", "default") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: SetMusicOnHold
-- Executing [s@macro-tl-set-variables2:8] Goto("SIP/1003-0916c5e0", "from-inside-redir|93560092|1") in new stack
-- Goto (from-inside-redir,93560092,1)
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: Goto
== Channel 'SIP/1003-0916c5e0' jumping out of macro 'tl-set-variables2'
-- Executing [93560092@from-inside-redir:1] Macro("SIP/1003-0916c5e0", "tl-dialout-1-trunk|60|DAHDI/g1||1") in new stack
-- Executing [s@macro-tl-dialout-1-trunk:1] GotoIf("SIP/1003-0916c5e0", "0?dial") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-tl-dialout-1-trunk:2] Set("SIP/1003-0916c5e0", "__DIALED_NUMBER=93560092") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-dialout-1-trunk:3] Macro("SIP/1003-0916c5e0", "tl-dialout-base|60|DAHDI/g1||1|") in new stack
[Jul 3 22:21:03] DEBUG[7845]: func_db.c:70 function_db_read: DB: TL/TENANT//status not found in database.
-- Executing [s@macro-tl-dialout-base:1] Set("SIP/1003-0916c5e0", "status=") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-dialout-base:2] GotoIf("SIP/1003-0916c5e0", "1?enabled") in new stack
-- Goto (macro-tl-dialout-base,s,5)
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-tl-dialout-base:5] GotoIf("SIP/1003-0916c5e0", "1?allclear") in new stack
-- Goto (macro-tl-dialout-base,s,13)
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-tl-dialout-base:13] Set("SIP/1003-0916c5e0", "i=2") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-dialout-base:14] Macro("SIP/1003-0916c5e0", "tl-set-myvariables") in new stack
-- Executing [s@macro-tl-set-myvariables:1] Set("SIP/1003-0916c5e0", "MY_CHAN=1003-0916c5e0") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-set-myvariables:2] NoOp("SIP/1003-0916c5e0", "THECHANNEL=SIP/1003-0916c5e0") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: NoOp
-- Executing [s@macro-tl-set-myvariables:3] Set("SIP/1003-0916c5e0", "zap=0") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-set-myvariables:4] GotoIf("SIP/1003-0916c5e0", "1?usechannel") in new stack
-- Goto (macro-tl-set-myvariables,s,9)
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-tl-set-myvariables:9] Set("SIP/1003-0916c5e0", "local=0") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-set-myvariables:10] GotoIf("SIP/1003-0916c5e0", "1?useit") in new stack
-- Goto (macro-tl-set-myvariables,s,12)
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-tl-set-myvariables:12] Set("SIP/1003-0916c5e0", "__MYEXTENSION=1003") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-set-myvariables:13] Set("SIP/1003-0916c5e0", "__MYID=1003") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: Set
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: Macro
-- Executing [s@macro-tl-dialout-base:15] SetAMAFlags("SIP/1003-0916c5e0", "billing") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: SetAMAFlags
-- Executing [s@macro-tl-dialout-base:16] Set("SIP/1003-0916c5e0", "RECORD=") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-dialout-base:17] GotoIf("SIP/1003-0916c5e0", "1?done_checkrecord") in new stack
-- Goto (macro-tl-dialout-base,s,26)
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-tl-dialout-base:26] NoOp("SIP/1003-0916c5e0", "RECORD=") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: NoOp
-- Executing [s@macro-tl-dialout-base:27] NoOp("SIP/1003-0916c5e0", "TOUCH_MONITOR=") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: NoOp
-- Executing [s@macro-tl-dialout-base:28] NoOp("SIP/1003-0916c5e0", "RECORD_OPTIONS=") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: NoOp
-- Executing [s@macro-tl-dialout-base:29] NoOp("SIP/1003-0916c5e0", "RECORDING_FORMAT=wav") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: NoOp
-- Executing [s@macro-tl-dialout-base:30] Set("SIP/1003-0916c5e0", "CALLERID(name)=") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-dialout-base:31] GotoIf("SIP/1003-0916c5e0", "1?calleridallowed") in new stack
-- Goto (macro-tl-dialout-base,s,39)
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-tl-dialout-base:39] NoOp("SIP/1003-0916c5e0", "CLIMYID=") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: NoOp
-- Executing [s@macro-tl-dialout-base:40] NoOp("SIP/1003-0916c5e0", "MYID=1003") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: NoOp
[Jul 3 22:21:03] DEBUG[7845]: func_db.c:70 function_db_read: DB: TL//callerid not found in database.
-- Executing [s@macro-tl-dialout-base:41] Set("SIP/1003-0916c5e0", "CLI=") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-dialout-base:42] GotoIf("SIP/1003-0916c5e0", "0?chkcallfwd") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-tl-dialout-base:43] Set("SIP/1003-0916c5e0", "CLI=8087488993") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-dialout-base:44] GotoIf("SIP/1003-0916c5e0", "1?chkcallfwd") in new stack
-- Goto (macro-tl-dialout-base,s,47)
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-tl-dialout-base:47] NoOp("SIP/1003-0916c5e0", "MYID=1003") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: NoOp
-- Executing [s@macro-tl-dialout-base:48] NoOp("SIP/1003-0916c5e0", "INCOMINGCLI=") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: NoOp
-- Executing [s@macro-tl-dialout-base:49] NoOp("SIP/1003-0916c5e0", "CALLFWD=") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: NoOp
-- Executing [s@macro-tl-dialout-base:50] GotoIf("SIP/1003-0916c5e0", "1?setcalleridasabove") in new stack
-- Goto (macro-tl-dialout-base,s,58)
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-tl-dialout-base:58] GotoIf("SIP/1003-0916c5e0", "0?nocallerid") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [s@macro-tl-dialout-base:59] Set("SIP/1003-0916c5e0", "CALLERID(num)=8087488993") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-dialout-base:60] Set("SIP/1003-0916c5e0", "temp=") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [s@macro-tl-dialout-base:61] GotoIf("SIP/1003-0916c5e0", "1?onetrunk|1") in new stack
-- Goto (macro-tl-dialout-base,onetrunk,1)
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [onetrunk@macro-tl-dialout-base:1] Set("SIP/1003-0916c5e0", "FULLNAME=DAHDI/g1") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [onetrunk@macro-tl-dialout-base:2] Set("SIP/1003-0916c5e0", "TRUNK=g1") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [onetrunk@macro-tl-dialout-base:3] GotoIf("SIP/1003-0916c5e0", "0?failed|1") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [onetrunk@macro-tl-dialout-base:4] Set("SIP/1003-0916c5e0", "TRUNK_STATUS=1") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [onetrunk@macro-tl-dialout-base:5] GotoIf("SIP/1003-0916c5e0", "0?next|1") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [onetrunk@macro-tl-dialout-base:6] Set("SIP/1003-0916c5e0", "ROUTE_PREPEND=") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [onetrunk@macro-tl-dialout-base:7] Set("SIP/1003-0916c5e0", "ROUTE_STRIP=1") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [onetrunk@macro-tl-dialout-base:8] Set("SIP/1003-0916c5e0", "ROUTE_OPTIONS=") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [onetrunk@macro-tl-dialout-base:9] Set("SIP/1003-0916c5e0", "NUMBER_TO_DIAL=3560092") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [onetrunk@macro-tl-dialout-base:10] Set("SIP/1003-0916c5e0", "TRUNK_DIALSTRING=") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [onetrunk@macro-tl-dialout-base:11] Set("SIP/1003-0916c5e0", "TRUNK_NAME=g1") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [onetrunk@macro-tl-dialout-base:12] Set("SIP/1003-0916c5e0", "TRUNK_PROTOCOL=DAHDI") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [onetrunk@macro-tl-dialout-base:13] Set("SIP/1003-0916c5e0", "TRUNK_STRIP=0") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [onetrunk@macro-tl-dialout-base:14] Set("SIP/1003-0916c5e0", "TRUNK_PREPEND=") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [onetrunk@macro-tl-dialout-base:15] Set("SIP/1003-0916c5e0", "CALLERID(num)=8087488993") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: Set
-- Executing [onetrunk@macro-tl-dialout-base:16] GotoIf("SIP/1003-0916c5e0", "0?dial-CUSTOM|1") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [onetrunk@macro-tl-dialout-base:17] Goto("SIP/1003-0916c5e0", "dial-DAHDI|1") in new stack
-- Goto (macro-tl-dialout-base,dial-DAHDI,1)
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: Goto
-- Executing [dial-DAHDI@macro-tl-dialout-base:1] GotoIf("SIP/1003-0916c5e0", "1?NoOpt") in new stack
-- Goto (macro-tl-dialout-base,dial-DAHDI,4)
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [dial-DAHDI@macro-tl-dialout-base:4] GotoIf("SIP/1003-0916c5e0", "0?noarg") in new stack
[Jul 3 22:21:03] DEBUG[7845]: app_macro.c:373 _macro_exec: Executed application: GotoIf
-- Executing [dial-DAHDI@macro-tl-dialout-base:5] Dial("SIP/1003-0916c5e0", "DAHDI/g1/3560092|60") in new stack
-- Requested transfer capability: 0x00 - SPEECH
-- Called g1/3560092
[Jul 3 22:21:03] DEBUG[320]: chan_dahdi.c:9568 pri_dchannel: Queuing frame from PRI_EVENT_PROCEEDING on channel 0/1 span 1
-- DAHDI/1-1 is proceeding passing it to SIP/1003-0916c5e0
-- DAHDI/1-1 is ringing
[Jul 3 22:21:05] DEBUG[320]: chan_dahdi.c:1759 dahdi_enable_ec: Echo cancellation already on
-- DAHDI/1-1 answered SIP/1003-0916c5e0


Can you see the problem?

-Matt

Comments

  • Hi Matt. FOP2 is firing the call, but asterisk does not like the destination number.

    What is the extension and context for your buttons in the .cfg file? You must be sure that the extension is valid within the context. And when dialing out, that the context for the original extension is able to dial the number you type.

    in the logs for the successful call I see mentioned the context "from-inside"

    So, in the buttonfile, the context for your extension or other extensions should be aso 'from-inside'

    In your logs I do not see the originate action, so I am not able to see what context did you configure. Anyways, the good news is that is a misconfiguration, not a bug. Please check the buttonfile and change the context to one that includes the extension or numbers that you want to dial.

    Best regards,

    --
    Nicolás
  • Here is the buttons.cfg

    The mailbox was '1003@default'. I will test again with these settings when I get back.

    Do I need a context to be able to dial and outside number from the action bar?


    [SIP/1000]
    type=extension
    extension=1000
    context=from-internal
    label=Matt
    mailbox=1000@from-internal
    extenvoicemail=*1000@from-internal

    [SIP/1002]
    type=extension
    extension=1002
    context=from-internal
    label=Seth
    mailbox=1002@from-internal
    extenvoicemail=*1002@from-internal

    [SIP/1003]
    type=extension
    extension=1003
    context=from-internal
    label=X-Lite
    mailbox=1003@from-internal
    extenvoicemail=*1003@from-internal

    [DAHDI/1]
    type=trunk
    label=DAHDI 1-23
    channel=DAHDI/1
    channel=DAHDI/2
    channel=DAHDI/3
    channel=DAHDI/4
    channel=DAHDI/5
    channel=DAHDI/6
    channel=DAHDI/7
    channel=DAHDI/8
    channel=DAHDI/9
    channel=DAHDI/10
    channel=DAHDI/11
    channel=DAHDI/12
    channel=DAHDI/13
    channel=DAHDI/14
    channel=DAHDI/15
    channel=DAHDI/16
    channel=DAHDI/17
    channel=DAHDI/18
    channel=DAHDI/19
    channel=DAHDI/20
    channel=DAHDI/21
    channel=DAHDI/22
    channel=DAHDI/23
  • Hi,

    In the extension definitions in your buttonfile replace "context=from-internal" with "context=from-inside"

    I believe you are running thirdlane software, I believe this will apply to anyone using it. If you or some other user grants me ssh access to a machine running thirdlane software I will be able to write an automatic config script for it.


    Best regards,

    --
    Nicolás
  • Aloha,

    I apologize, that was a silly mistake! Thank you for pointing it out.

    I am working on a single tennant Thirdlane box now. I will set up a multi-tennant box and give you ssh access. That would be great if you could create a script.

    I have been talking about the product on the Thirdlane forum - http://www.thirdlane.com/forum/anyone-tried-fop2 - I will be happy to announce the auto script when it is completed. A very knowledgable poster asked if it is 1.6 compatiable; I use 1.4.x now.

    Will the CDR product work with any version of asterisk? The CDR we get from Thirdlane is not good. We are looking for something better.

    -Matt
  • Aloha Matt,

    FOP2 is compatible with Asterisk 1.6. There is currently a minor bug in the whisper functionality that I am about to fix.. I will also change the transfer code to use attendant transfers by default on 1.6. I am taking my time because I want to detect the asterisk version automatically so there is no need to touch config files to take advantage of it.

    I will try to get the CDR product ready as soon as possible as there is much interest in it. It is currently working on several places, but I have to clean a version up to make it general enough. There will be a free and a paid version, just like the call center stats. I like the CDRs myself, but the approach is quite unique, it is similar to the call center stats, grouping by extension with drilldown.

    Please try to catch me online on the live help to arrange the ssh access. Best regards,

    --
    Nicolás
Sign In or Register to comment.