-
Type:
Bug
-
Status: Admin Closed
-
Priority:
Major
-
Resolution: Not an issue
-
Affects Version/s: 2.11
-
Fix Version/s: None
-
Component/s: Core
-
Environment:
Production
-
ToDo:
-
Asterisk Version:Asterisk 13
-
Distro Version:SHMZ release 6.5 (Final)
-
Distro:FreePBX Distro
Good afternoon,
dialparties.agi adds dial timeout of 2 seconds instead of pre-configured (extensions / advance settings) for any extension which is part of ring group.
Example. We have extension 5000 which is part of the ring group and 5009 which is not.
Call from 5000 to 5010 is ok (expected dial timeout):
-- Executing [5010@from-internal:1] Set("SIP/5000-0000472b", "__RINGTIMER=20") in new stack
-- Executing [5010@from-internal:2] Macro("SIP/5000-0000472b", "exten-vm,5010,5010,1,1,1") in new stack
-- Executing [s@macro-exten-vm:1] Macro("SIP/5000-0000472b", "user-callerid,") in new stack
-- Executing [s@macro-user-callerid:1] Set("SIP/5000-0000472b", "TOUCH_MONITOR=1427033086.119749") in new stack
-- Executing [s@macro-user-callerid:2] Set("SIP/5000-0000472b", "AMPUSER=5000") in new stack
-- Executing [s@macro-user-callerid:3] GotoIf("SIP/5000-0000472b", "0?report") in new stack
-- Executing [s@macro-user-callerid:4] ExecIf("SIP/5000-0000472b", "1?Set(REALCALLERIDNUM=5000)") in new stack
-- Executing [s@macro-user-callerid:5] Set("SIP/5000-0000472b", "AMPUSER=5000") in new stack
-- Executing [s@macro-user-callerid:6] GotoIf("SIP/5000-0000472b", "0?limit") in new stack
-- Executing [s@macro-user-callerid:7] Set("SIP/5000-0000472b", "AMPUSERCIDNAME=Canyon Recep") in new stack
-- Executing [s@macro-user-callerid:8] GotoIf("SIP/5000-0000472b", "0?report") in new stack
-- Executing [s@macro-user-callerid:9] Set("SIP/5000-0000472b", "AMPUSERCID=5000") in new stack
-- Executing [s@macro-user-callerid:10] Set("SIP/5000-0000472b", "__DIAL_OPTIONS=Ttr") in new stack
-- Executing [s@macro-user-callerid:11] Set("SIP/5000-0000472b", "CALLERID(all)="Canyon Recep" <5000>") in new stack
-- Executing [s@macro-user-callerid:12] GotoIf("SIP/5000-0000472b", "0?limit") in new stack
-- Executing [s@macro-user-callerid:13] ExecIf("SIP/5000-0000472b", "0?Set(GROUP(concurrency_limit)=5000)") in new stack
-- Executing [s@macro-user-callerid:14] GosubIf("SIP/5000-0000472b", "7?sub-ccss,s,1(macro-exten-vm,5010)") in new stack
-- Executing [s@sub-ccss:1] ExecIf("SIP/5000-0000472b", "0?Return()") in new stack
-- Executing [s@sub-ccss:2] Set("SIP/5000-0000472b", "CCSS_SETUP=TRUE") in new stack
-- Executing [s@sub-ccss:3] GosubIf("SIP/5000-0000472b", "0?monitor_config,1(macro-exten-vm,5010):monitor_default,1(macro-exten-vm,5010)") in new stack
-- Executing [monitor_default@sub-ccss:1] GotoIf("SIP/5000-0000472b", "1?is_exten") in new stack
-- Goto (sub-ccss,monitor_default,4)
-- Executing [monitor_default@sub-ccss:4] Set("SIP/5000-0000472b", "CALLCOMPLETION(cc_monitor_policy)=generic") in new stack
-- Executing [monitor_default@sub-ccss:5] Set("SIP/5000-0000472b", "CALLCOMPLETION(cc_max_monitors)=5") in new stack
-- Executing [monitor_default@sub-ccss:6] Return("SIP/5000-0000472b", "TRUE") in new stack
-- Executing [s@sub-ccss:4] GosubIf("SIP/5000-0000472b", "7?agent_config,1():agent_default,1()") in new stack
-- Executing [agent_config@sub-ccss:1] Set("SIP/5000-0000472b", "CALLCOMPLETION(cc_agent_policy)=generic") in new stack
-- Executing [agent_config@sub-ccss:2] Set("SIP/5000-0000472b", "CALLCOMPLETION(cc_offer_timer)=30") in new stack
-- Executing [agent_config@sub-ccss:3] Set("SIP/5000-0000472b", "CALLCOMPLETION(ccbs_available_timer)=") in new stack
-- Executing [agent_config@sub-ccss:4] Set("SIP/5000-0000472b", "CALLCOMPLETION(ccnr_available_timer)=") in new stack
-- Executing [agent_config@sub-ccss:5] Set("SIP/5000-0000472b", "CALLCOMPLETION(cc_callback_macro)=ccss-default") in new stack
[2015-03-22 07:04:46] WARNING[24281][C-000029ec]: ccss.c:1012 ast_set_cc_callback_macro: Usage of cc_callback_macro is deprecated. Please use cc_callback_sub instead.
-- Executing [agent_config@sub-ccss:6] ExecIf("SIP/5000-0000472b", "1?Set(CALLCOMPLETION(cc_recall_timer)=)") in new stack
-- Executing [agent_config@sub-ccss:7] ExecIf("SIP/5000-0000472b", "1?Set(CALLCOMPLETION(cc_max_agents)=)") in new stack
-- Executing [agent_config@sub-ccss:8] ExecIf("SIP/5000-0000472b", "0?Set(CALLCOMPLETION(cc_agent_dialstring)=Local/5000_5010@from-ccss-)") in new stack
-- Executing [agent_config@sub-ccss:9] Set("SIP/5000-0000472b", "CALLCOMPLETION(cc_callback_macro)=ccss-default") in new stack
[2015-03-22 07:04:46] WARNING[24281][C-000029ec]: ccss.c:1012 ast_set_cc_callback_macro: Usage of cc_callback_macro is deprecated. Please use cc_callback_sub instead.
-- Executing [agent_config@sub-ccss:10] Return("SIP/5000-0000472b", "") in new stack
-- Executing [s@sub-ccss:5] Set("SIP/5000-0000472b", "DB(AMPUSER/5000/ccss/last_number)=5010") in new stack
-- Executing [s@sub-ccss:6] Return("SIP/5000-0000472b", "") in new stack
-- Executing [s@macro-user-callerid:15] ExecIf("SIP/5000-0000472b", "0?Set(CHANNEL(language)=)") in new stack
-- Executing [s@macro-user-callerid:16] GotoIf("SIP/5000-0000472b", "0?continue") in new stack
-- Executing [s@macro-user-callerid:17] ExecIf("SIP/5000-0000472b", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
-- Executing [s@macro-user-callerid:18] Set("SIP/5000-0000472b", "__TTL=64") in new stack
-- Executing [s@macro-user-callerid:19] GotoIf("SIP/5000-0000472b", "1?continue") in new stack
-- Goto (macro-user-callerid,s,30)
-- Executing [s@macro-user-callerid:30] Set("SIP/5000-0000472b", "CALLERID(number)=5000") in new stack
-- Executing [s@macro-user-callerid:31] Set("SIP/5000-0000472b", "CALLERID(name)=Canyon Recep") in new stack
-- Executing [s@macro-user-callerid:32] Set("SIP/5000-0000472b", "CDR(cnum)=5000") in new stack
-- Executing [s@macro-user-callerid:33] Set("SIP/5000-0000472b", "CDR(cnam)=Canyon Recep") in new stack
-- Executing [s@macro-user-callerid:34] Set("SIP/5000-0000472b", "CHANNEL(language)=en") in new stack
-- Executing [s@macro-exten-vm:2] Set("SIP/5000-0000472b", "RingGroupMethod=none") in new stack
-- Executing [s@macro-exten-vm:3] Set("SIP/5000-0000472b", "__EXTTOCALL=5010") in new stack
-- Executing [s@macro-exten-vm:4] Set("SIP/5000-0000472b", "__PICKUPMARK=5010") in new stack
-- Executing [s@macro-exten-vm:5] Set("SIP/5000-0000472b", "RT=20") in new stack
-- Executing [s@macro-exten-vm:6] ExecIf("SIP/5000-0000472b", "0?Macro(vm,5010,DIRECTDIAL,)") in new stack
-- Executing [s@macro-exten-vm:7] ExecIf("SIP/5000-0000472b", "0?MacroExit()") in new stack
-- Executing [s@macro-exten-vm:8] Gosub("SIP/5000-0000472b", "sub-record-check,s,1(exten,5010,dontcare)") in new stack
-- Executing [s@sub-record-check:1] GotoIf("SIP/5000-0000472b", "0?initialized") in new stack
-- Executing [s@sub-record-check:2] Set("SIP/5000-0000472b", "__REC_STATUS=INITIALIZED") in new stack
-- Executing [s@sub-record-check:3] Set("SIP/5000-0000472b", "NOW=1427033086") in new stack
-- Executing [s@sub-record-check:4] Set("SIP/5000-0000472b", "__DAY=22") in new stack
-- Executing [s@sub-record-check:5] Set("SIP/5000-0000472b", "__MONTH=03") in new stack
-- Executing [s@sub-record-check:6] Set("SIP/5000-0000472b", "__YEAR=2015") in new stack
-- Executing [s@sub-record-check:7] Set("SIP/5000-0000472b", "__TIMESTR=20150322-070446") in new stack
-- Executing [s@sub-record-check:8] Set("SIP/5000-0000472b", "__FROMEXTEN=5000") in new stack
-- Executing [s@sub-record-check:9] Set("SIP/5000-0000472b", "__MON_FMT=wav") in new stack
-- Executing [s@sub-record-check:10] NoOp("SIP/5000-0000472b", "Recordings initialized") in new stack
-- Executing [s@sub-record-check:11] ExecIf("SIP/5000-0000472b", "0?Set(ARG3=dontcare)") in new stack
-- Executing [s@sub-record-check:12] Set("SIP/5000-0000472b", "REC_POLICY_MODE_SAVE=") in new stack
-- Executing [s@sub-record-check:13] ExecIf("SIP/5000-0000472b", "0?Set(REC_STATUS=NO)") in new stack
-- Executing [s@sub-record-check:14] GotoIf("SIP/5000-0000472b", "5?checkaction") in new stack
-- Goto (sub-record-check,s,17)
-- Executing [s@sub-record-check:17] GotoIf("SIP/5000-0000472b", "1?sub-record-check,exten,1") in new stack
-- Goto (sub-record-check,exten,1)
-- Executing [exten@sub-record-check:1] NoOp("SIP/5000-0000472b", "Exten Recording Check between 5000 and 5010") in new stack
-- Executing [exten@sub-record-check:2] Set("SIP/5000-0000472b", "CALLTYPE=internal") in new stack
-- Executing [exten@sub-record-check:3] ExecIf("SIP/5000-0000472b", "0?Set(CALLTYPE=)") in new stack
-- Executing [exten@sub-record-check:4] Set("SIP/5000-0000472b", "CALLEE=dontcare") in new stack
-- Executing [exten@sub-record-check:5] ExecIf("SIP/5000-0000472b", "0?Set(CALLEE=dontcare)") in new stack
-- Executing [exten@sub-record-check:6] GotoIf("SIP/5000-0000472b", "0?callee") in new stack
-- Executing [exten@sub-record-check:7] GotoIf("SIP/5000-0000472b", "1?caller") in new stack
-- Goto (sub-record-check,exten,13)
-- Executing [exten@sub-record-check:13] Set("SIP/5000-0000472b", "RECMODE=dontcare") in new stack
-- Executing [exten@sub-record-check:14] ExecIf("SIP/5000-0000472b", "0?Set(RECMODE=dontcare)") in new stack
-- Executing [exten@sub-record-check:15] ExecIf("SIP/5000-0000472b", "1?Set(RECMODE=dontcare)") in new stack
-- Executing [exten@sub-record-check:16] Gosub("SIP/5000-0000472b", "recordcheck,1(dontcare,internal,5010)") in new stack
-- Executing [recordcheck@sub-record-check:1] NoOp("SIP/5000-0000472b", "Starting recording check against dontcare") in new stack
-- Executing [recordcheck@sub-record-check:2] Goto("SIP/5000-0000472b", "dontcare") in new stack
-- Goto (sub-record-check,recordcheck,3)
-- Executing [recordcheck@sub-record-check:3] Return("SIP/5000-0000472b", "") in new stack
-- Executing [exten@sub-record-check:17] Return("SIP/5000-0000472b", "") in new stack
-- Executing [s@macro-exten-vm:9] GotoIf("SIP/5000-0000472b", "1?macrodial") in new stack
-- Goto (macro-exten-vm,s,15)
-- Executing [s@macro-exten-vm:15] GosubIf("SIP/5000-0000472b", "0?clrheader,1()") in new stack
-- Executing [s@macro-exten-vm:16] Macro("SIP/5000-0000472b", "dial-one,20,Ttr,5010") in new stack
-- Executing [s@macro-dial-one:1] Set("SIP/5000-0000472b", "DEXTEN=5010") in new stack
-- Executing [s@macro-dial-one:2] Set("SIP/5000-0000472b", "DIALSTATUS_CW=") in new stack
-- Executing [s@macro-dial-one:3] GosubIf("SIP/5000-0000472b", "0?screen,1()") in new stack
-- Executing [s@macro-dial-one:4] GosubIf("SIP/5000-0000472b", "0?cf,1()") in new stack
-- Executing [s@macro-dial-one:5] GotoIf("SIP/5000-0000472b", "1?skip1") in new stack
-- Goto (macro-dial-one,s,8)
-- Executing [s@macro-dial-one:8] GotoIf("SIP/5000-0000472b", "0?nodial") in new stack
-- Executing [s@macro-dial-one:9] GotoIf("SIP/5000-0000472b", "0?continue") in new stack
-- Executing [s@macro-dial-one:10] Set("SIP/5000-0000472b", "EXTHASCW=") in new stack
-- Executing [s@macro-dial-one:11] GotoIf("SIP/5000-0000472b", "1?next1:cwinusebusy") in new stack
-- Goto (macro-dial-one,s,12)
-- Executing [s@macro-dial-one:12] GotoIf("SIP/5000-0000472b", "0?docfu:skip3") in new stack
-- Goto (macro-dial-one,s,16)
-- Executing [s@macro-dial-one:16] GotoIf("SIP/5000-0000472b", "1?next2:continue") in new stack
-- Goto (macro-dial-one,s,17)
-- Executing [s@macro-dial-one:17] GotoIf("SIP/5000-0000472b", "1?continue") in new stack
-- Goto (macro-dial-one,s,25)
-- Executing [s@macro-dial-one:25] GotoIf("SIP/5000-0000472b", "0?nodial") in new stack
-- Executing [s@macro-dial-one:26] GosubIf("SIP/5000-0000472b", "1?dstring,1():dlocal,1()") in new stack
-- Executing [dstring@macro-dial-one:1] Set("SIP/5000-0000472b", "DSTRING=") in new stack
-- Executing [dstring@macro-dial-one:2] Set("SIP/5000-0000472b", "DEVICES=5010") in new stack
-- Executing [dstring@macro-dial-one:3] ExecIf("SIP/5000-0000472b", "0?Return()") in new stack
-- Executing [dstring@macro-dial-one:4] ExecIf("SIP/5000-0000472b", "0?Set(DEVICES=010)") in new stack
-- Executing [dstring@macro-dial-one:5] Set("SIP/5000-0000472b", "LOOPCNT=1") in new stack
-- Executing [dstring@macro-dial-one:6] Set("SIP/5000-0000472b", "ITER=1") in new stack
-- Executing [dstring@macro-dial-one:7] Set("SIP/5000-0000472b", "THISDIAL=SIP/5010") in new stack
-- Executing [dstring@macro-dial-one:8] GosubIf("SIP/5000-0000472b", "1?zap2dahdi,1()") in new stack
-- Executing [zap2dahdi@macro-dial-one:1] ExecIf("SIP/5000-0000472b", "0?Return()") in new stack
-- Executing [zap2dahdi@macro-dial-one:2] Set("SIP/5000-0000472b", "NEWDIAL=") in new stack
-- Executing [zap2dahdi@macro-dial-one:3] Set("SIP/5000-0000472b", "LOOPCNT2=1") in new stack
-- Executing [zap2dahdi@macro-dial-one:4] Set("SIP/5000-0000472b", "ITER2=1") in new stack
-- Executing [zap2dahdi@macro-dial-one:5] Set("SIP/5000-0000472b", "THISPART2=SIP/5010") in new stack
-- Executing [zap2dahdi@macro-dial-one:6] ExecIf("SIP/5000-0000472b", "0?Set(THISPART2=DAHDI/5010)") in new stack
-- Executing [zap2dahdi@macro-dial-one:7] Set("SIP/5000-0000472b", "NEWDIAL=SIP/5010&") in new stack
-- Executing [zap2dahdi@macro-dial-one:8] Set("SIP/5000-0000472b", "ITER2=2") in new stack
-- Executing [zap2dahdi@macro-dial-one:9] GotoIf("SIP/5000-0000472b", "0?begin2") in new stack
-- Executing [zap2dahdi@macro-dial-one:10] Set("SIP/5000-0000472b", "THISDIAL=SIP/5010") in new stack
-- Executing [zap2dahdi@macro-dial-one:11] Return("SIP/5000-0000472b", "") in new stack
-- Executing [dstring@macro-dial-one:9] GotoIf("SIP/5000-0000472b", "1?doset") in new stack
-- Goto (macro-dial-one,dstring,12)
-- Executing [dstring@macro-dial-one:12] Set("SIP/5000-0000472b", "DSTRING=SIP/5010&") in new stack
-- Executing [dstring@macro-dial-one:13] Set("SIP/5000-0000472b", "ITER=2") in new stack
-- Executing [dstring@macro-dial-one:14] GotoIf("SIP/5000-0000472b", "0?begin") in new stack
-- Executing [dstring@macro-dial-one:15] Set("SIP/5000-0000472b", "DSTRING=SIP/5010") in new stack
-- Executing [dstring@macro-dial-one:16] Return("SIP/5000-0000472b", "") in new stack
-- Executing [s@macro-dial-one:27] GotoIf("SIP/5000-0000472b", "0?nodial") in new stack
-- Executing [s@macro-dial-one:28] GotoIf("SIP/5000-0000472b", "0?skiptrace") in new stack
-- Executing [s@macro-dial-one:29] GosubIf("SIP/5000-0000472b", "1?ctset,1():ctclear,1()") in new stack
-- Executing [ctset@macro-dial-one:1] Set("SIP/5000-0000472b", "DB(CALLTRACE/5010)=5000") in new stack
-- Executing [ctset@macro-dial-one:2] Return("SIP/5000-0000472b", "") in new stack
-- Executing [s@macro-dial-one:30] Set("SIP/5000-0000472b", "D_OPTIONS=Ttr") in new stack
-- Executing [s@macro-dial-one:31] ExecIf("SIP/5000-0000472b", "0?SIPAddHeader(Alert-Info: )") in new stack
-- Executing [s@macro-dial-one:32] ExecIf("SIP/5000-0000472b", "0?SIPAddHeader()") in new stack
-- Executing [s@macro-dial-one:33] ExecIf("SIP/5000-0000472b", "0?Set(CHANNEL(musicclass)=)") in new stack
-- Executing [s@macro-dial-one:34] GosubIf("SIP/5000-0000472b", "0?qwait,1()") in new stack
-- Executing [s@macro-dial-one:35] Set("SIP/5000-0000472b", "__CWIGNORE=") in new stack
-- Executing [s@macro-dial-one:36] Set("SIP/5000-0000472b", "__KEEPCID=TRUE") in new stack
-- Executing [s@macro-dial-one:37] GotoIf("SIP/5000-0000472b", "0?usegoto,1") in new stack
-- Executing [s@macro-dial-one:38] GotoIf("SIP/5000-0000472b", "0?godial") in new stack
-- Executing [s@macro-dial-one:39] Gosub("SIP/5000-0000472b", "sub-presencestate-display,s,1(5010)") in new stack
[2015-03-22 07:04:46] WARNING[24281][C-000029ec]: func_presencestate.c:133 presence_read: PRESENCE_STATE unknown
-- Executing [s@sub-presencestate-display:1] Goto("SIP/5000-0000472b", "state-,1") in new stack
-- Goto (sub-presencestate-display,state-,1)
-- Executing [state-@sub-presencestate-display:1] Set("SIP/5000-0000472b", "PRESENCESTATE_DISPLAY=") in new stack
-- Executing [state-@sub-presencestate-display:2] Return("SIP/5000-0000472b", "") in new stack
-- Executing [s@macro-dial-one:40] Set("SIP/5000-0000472b", "CONNECTEDLINE(name,i)=Canyon DOC") in new stack
-- Executing [s@macro-dial-one:41] Set("SIP/5000-0000472b", "CONNECTEDLINE(num)=5010") in new stack
-- Executing [s@macro-dial-one:42] Set("SIP/5000-0000472b", "D_OPTIONS=TtrI") in new stack
-- Executing [s@macro-dial-one:43] Macro("SIP/5000-0000472b", "dialout-one-predial-hook,") in new stack
-- Executing [s@macro-dialout-one-predial-hook:1] MacroExit("SIP/5000-0000472b", "") in new stack
-- Executing [s@macro-dial-one:44] Dial("SIP/5000-0000472b", "SIP/5010,20,TtrI") in new stack
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
-- Called SIP/5010
-- Connected line update to SIP/5000-0000472b prevented.
-- SIP/5010-0000472c is ringing
== Spawn extension (macro-dial-one, s, 44) exited non-zero on 'SIP/5000-0000472b' in macro 'dial-one'
== Spawn extension (macro-exten-vm, s, 16) exited non-zero on 'SIP/5000-0000472b' in macro 'exten-vm'
== Spawn extension (from-internal, 5010, 2) exited non-zero on 'SIP/5000-0000472b'
-- Executing [h@from-internal:1] Hangup("SIP/5000-0000472b", "") in new stack
== Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/5000-0000472b'
This is important in our case:
-- Executing [s@macro-dial-one:44] Dial("SIP/5000-0000472b", "SIP/5010,20,TtrI") in new stack
dial timeout 20 seconds as per our configuration.
now let's look for the call from 5010 to 5000:
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
-- Executing [5000@from-internal:1] GotoIf("SIP/5010-0000472d", "0?ext-local,5000,1") in new stack
-- Executing [5000@from-internal:2] Macro("SIP/5010-0000472d", "user-callerid,") in new stack
-- Executing [s@macro-user-callerid:1] Set("SIP/5010-0000472d", "TOUCH_MONITOR=1427033198.119759") in new stack
-- Executing [s@macro-user-callerid:2] Set("SIP/5010-0000472d", "AMPUSER=5010") in new stack
-- Executing [s@macro-user-callerid:3] GotoIf("SIP/5010-0000472d", "0?report") in new stack
-- Executing [s@macro-user-callerid:4] ExecIf("SIP/5010-0000472d", "1?Set(REALCALLERIDNUM=5010)") in new stack
-- Executing [s@macro-user-callerid:5] Set("SIP/5010-0000472d", "AMPUSER=5010") in new stack
-- Executing [s@macro-user-callerid:6] GotoIf("SIP/5010-0000472d", "0?limit") in new stack
-- Executing [s@macro-user-callerid:7] Set("SIP/5010-0000472d", "AMPUSERCIDNAME=Canyon DOC") in new stack
-- Executing [s@macro-user-callerid:8] GotoIf("SIP/5010-0000472d", "0?report") in new stack
-- Executing [s@macro-user-callerid:9] Set("SIP/5010-0000472d", "AMPUSERCID=5010") in new stack
-- Executing [s@macro-user-callerid:10] Set("SIP/5010-0000472d", "__DIAL_OPTIONS=Ttr") in new stack
-- Executing [s@macro-user-callerid:11] Set("SIP/5010-0000472d", "CALLERID(all)="Canyon DOC" <5010>") in new stack
-- Executing [s@macro-user-callerid:12] GotoIf("SIP/5010-0000472d", "0?limit") in new stack
-- Executing [s@macro-user-callerid:13] ExecIf("SIP/5010-0000472d", "0?Set(GROUP(concurrency_limit)=5010)") in new stack
-- Executing [s@macro-user-callerid:14] GosubIf("SIP/5010-0000472d", "7?sub-ccss,s,1(from-internal,5000)") in new stack
-- Executing [s@sub-ccss:1] ExecIf("SIP/5010-0000472d", "0?Return()") in new stack
-- Executing [s@sub-ccss:2] Set("SIP/5010-0000472d", "CCSS_SETUP=TRUE") in new stack
-- Executing [s@sub-ccss:3] GosubIf("SIP/5010-0000472d", "0?monitor_config,1(from-internal,5000):monitor_default,1(from-internal,5000)") in new stack
-- Executing [monitor_default@sub-ccss:1] GotoIf("SIP/5010-0000472d", "1?is_exten") in new stack
-- Goto (sub-ccss,monitor_default,4)
-- Executing [monitor_default@sub-ccss:4] Set("SIP/5010-0000472d", "CALLCOMPLETION(cc_monitor_policy)=generic") in new stack
-- Executing [monitor_default@sub-ccss:5] Set("SIP/5010-0000472d", "CALLCOMPLETION(cc_max_monitors)=5") in new stack
-- Executing [monitor_default@sub-ccss:6] Return("SIP/5010-0000472d", "TRUE") in new stack
-- Executing [s@sub-ccss:4] GosubIf("SIP/5010-0000472d", "7?agent_config,1():agent_default,1()") in new stack
-- Executing [agent_config@sub-ccss:1] Set("SIP/5010-0000472d", "CALLCOMPLETION(cc_agent_policy)=generic") in new stack
-- Executing [agent_config@sub-ccss:2] Set("SIP/5010-0000472d", "CALLCOMPLETION(cc_offer_timer)=30") in new stack
-- Executing [agent_config@sub-ccss:3] Set("SIP/5010-0000472d", "CALLCOMPLETION(ccbs_available_timer)=") in new stack
-- Executing [agent_config@sub-ccss:4] Set("SIP/5010-0000472d", "CALLCOMPLETION(ccnr_available_timer)=") in new stack
-- Executing [agent_config@sub-ccss:5] Set("SIP/5010-0000472d", "CALLCOMPLETION(cc_callback_macro)=ccss-default") in new stack
[2015-03-22 07:06:39] WARNING[24336][C-000029ed]: ccss.c:1012 ast_set_cc_callback_macro: Usage of cc_callback_macro is deprecated. Please use cc_callback_sub instead.
-- Executing [agent_config@sub-ccss:6] ExecIf("SIP/5010-0000472d", "1?Set(CALLCOMPLETION(cc_recall_timer)=)") in new stack
-- Executing [agent_config@sub-ccss:7] ExecIf("SIP/5010-0000472d", "1?Set(CALLCOMPLETION(cc_max_agents)=)") in new stack
-- Executing [agent_config@sub-ccss:8] ExecIf("SIP/5010-0000472d", "0?Set(CALLCOMPLETION(cc_agent_dialstring)=Local/5010_5000@from-ccss-)") in new stack
-- Executing [agent_config@sub-ccss:9] Set("SIP/5010-0000472d", "CALLCOMPLETION(cc_callback_macro)=ccss-default") in new stack
[2015-03-22 07:06:39] WARNING[24336][C-000029ed]: ccss.c:1012 ast_set_cc_callback_macro: Usage of cc_callback_macro is deprecated. Please use cc_callback_sub instead.
-- Executing [agent_config@sub-ccss:10] Return("SIP/5010-0000472d", "") in new stack
-- Executing [s@sub-ccss:5] Set("SIP/5010-0000472d", "DB(AMPUSER/5010/ccss/last_number)=5000") in new stack
-- Executing [s@sub-ccss:6] Return("SIP/5010-0000472d", "") in new stack
-- Executing [s@macro-user-callerid:15] ExecIf("SIP/5010-0000472d", "0?Set(CHANNEL(language)=)") in new stack
-- Executing [s@macro-user-callerid:16] GotoIf("SIP/5010-0000472d", "0?continue") in new stack
-- Executing [s@macro-user-callerid:17] ExecIf("SIP/5010-0000472d", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
-- Executing [s@macro-user-callerid:18] Set("SIP/5010-0000472d", "__TTL=64") in new stack
-- Executing [s@macro-user-callerid:19] GotoIf("SIP/5010-0000472d", "1?continue") in new stack
-- Goto (macro-user-callerid,s,30)
-- Executing [s@macro-user-callerid:30] Set("SIP/5010-0000472d", "CALLERID(number)=5010") in new stack
-- Executing [s@macro-user-callerid:31] Set("SIP/5010-0000472d", "CALLERID(name)=Canyon DOC") in new stack
-- Executing [s@macro-user-callerid:32] Set("SIP/5010-0000472d", "CDR(cnum)=5010") in new stack
-- Executing [s@macro-user-callerid:33] Set("SIP/5010-0000472d", "CDR(cnam)=Canyon DOC") in new stack
-- Executing [s@macro-user-callerid:34] Set("SIP/5010-0000472d", "CHANNEL(language)=en") in new stack
-- Executing [5000@from-internal:3] Set("SIP/5010-0000472d", "DIAL_OPTIONS=TtrI") in new stack
-- Executing [5000@from-internal:4] Set("SIP/5010-0000472d", "CONNECTEDLINE(num,i)=5000") in new stack
-- Executing [5000@from-internal:5] Gosub("SIP/5010-0000472d", "sub-presencestate-display,s,1(5000)") in new stack
[2015-03-22 07:06:39] WARNING[24336][C-000029ed]: func_presencestate.c:133 presence_read: PRESENCE_STATE unknown
-- Executing [s@sub-presencestate-display:1] Goto("SIP/5010-0000472d", "state-,1") in new stack
-- Goto (sub-presencestate-display,state-,1)
-- Executing [state-@sub-presencestate-display:1] Set("SIP/5010-0000472d", "PRESENCESTATE_DISPLAY=") in new stack
-- Executing [state-@sub-presencestate-display:2] Return("SIP/5010-0000472d", "") in new stack
-- Executing [5000@from-internal:6] Set("SIP/5010-0000472d", "CONNECTEDLINE(name)=Canyon Recep") in new stack
-- Executing [5000@from-internal:7] Set("SIP/5010-0000472d", "FM_DIALSTATUS=NOT_INUSE") in new stack
-- Executing [5000@from-internal:8] Set("SIP/5010-0000472d", "__EXTTOCALL=5000") in new stack
-- Executing [5000@from-internal:9] Set("SIP/5010-0000472d", "__PICKUPMARK=5000") in new stack
-- Executing [5000@from-internal:10] Macro("SIP/5010-0000472d", "blkvm-setifempty,") in new stack
-- Executing [s@macro-blkvm-setifempty:1] GotoIf("SIP/5010-0000472d", "1?init") in new stack
-- Goto (macro-blkvm-setifempty,s,4)
-- Executing [s@macro-blkvm-setifempty:4] Set("SIP/5010-0000472d", "__BLKVM_CHANNEL=SIP/5010-0000472d") in new stack
-- Executing [s@macro-blkvm-setifempty:5] Set("SIP/5010-0000472d", "SHARED(BLKVM,SIP/5010-0000472d)=TRUE") in new stack
-- Executing [s@macro-blkvm-setifempty:6] Set("SIP/5010-0000472d", "GOSUB_RETVAL=TRUE") in new stack
-- Executing [s@macro-blkvm-setifempty:7] MacroExit("SIP/5010-0000472d", "") in new stack
-- Executing [5000@from-internal:11] GotoIf("SIP/5010-0000472d", "1?skipov") in new stack
-- Goto (from-internal,5000,14)
-- Executing [5000@from-internal:14] Set("SIP/5010-0000472d", "RRNODEST=") in new stack
-- Executing [5000@from-internal:15] Set("SIP/5010-0000472d", "__NODEST=5000") in new stack
-- Executing [5000@from-internal:16] GosubIf("SIP/5010-0000472d", "0?sub-fmsetcid,s,1()") in new stack
-- Executing [5000@from-internal:17] Set("SIP/5010-0000472d", "RecordMethod=Group") in new stack
-- Executing [5000@from-internal:18] Gosub("SIP/5010-0000472d", "sub-record-check,s,1(exten,5000,)") in new stack
-- Executing [s@sub-record-check:1] GotoIf("SIP/5010-0000472d", "0?initialized") in new stack
-- Executing [s@sub-record-check:2] Set("SIP/5010-0000472d", "__REC_STATUS=INITIALIZED") in new stack
-- Executing [s@sub-record-check:3] Set("SIP/5010-0000472d", "NOW=1427033199") in new stack
-- Executing [s@sub-record-check:4] Set("SIP/5010-0000472d", "__DAY=22") in new stack
-- Executing [s@sub-record-check:5] Set("SIP/5010-0000472d", "__MONTH=03") in new stack
-- Executing [s@sub-record-check:6] Set("SIP/5010-0000472d", "__YEAR=2015") in new stack
-- Executing [s@sub-record-check:7] Set("SIP/5010-0000472d", "__TIMESTR=20150322-070639") in new stack
-- Executing [s@sub-record-check:8] Set("SIP/5010-0000472d", "__FROMEXTEN=5010") in new stack
-- Executing [s@sub-record-check:9] Set("SIP/5010-0000472d", "__MON_FMT=wav") in new stack
-- Executing [s@sub-record-check:10] NoOp("SIP/5010-0000472d", "Recordings initialized") in new stack
-- Executing [s@sub-record-check:11] ExecIf("SIP/5010-0000472d", "0?Set(ARG3=dontcare)") in new stack
-- Executing [s@sub-record-check:12] Set("SIP/5010-0000472d", "REC_POLICY_MODE_SAVE=") in new stack
-- Executing [s@sub-record-check:13] ExecIf("SIP/5010-0000472d", "0?Set(REC_STATUS=NO)") in new stack
-- Executing [s@sub-record-check:14] GotoIf("SIP/5010-0000472d", "5?checkaction") in new stack
-- Goto (sub-record-check,s,17)
-- Executing [s@sub-record-check:17] GotoIf("SIP/5010-0000472d", "1?sub-record-check,exten,1") in new stack
-- Goto (sub-record-check,exten,1)
-- Executing [exten@sub-record-check:1] NoOp("SIP/5010-0000472d", "Exten Recording Check between 5010 and 5000") in new stack
-- Executing [exten@sub-record-check:2] Set("SIP/5010-0000472d", "CALLTYPE=internal") in new stack
-- Executing [exten@sub-record-check:3] ExecIf("SIP/5010-0000472d", "0?Set(CALLTYPE=)") in new stack
-- Executing [exten@sub-record-check:4] Set("SIP/5010-0000472d", "CALLEE=dontcare") in new stack
-- Executing [exten@sub-record-check:5] ExecIf("SIP/5010-0000472d", "0?Set(CALLEE=dontcare)") in new stack
-- Executing [exten@sub-record-check:6] GotoIf("SIP/5010-0000472d", "0?callee") in new stack
-- Executing [exten@sub-record-check:7] GotoIf("SIP/5010-0000472d", "1?caller") in new stack
-- Goto (sub-record-check,exten,13)
-- Executing [exten@sub-record-check:13] Set("SIP/5010-0000472d", "RECMODE=dontcare") in new stack
-- Executing [exten@sub-record-check:14] ExecIf("SIP/5010-0000472d", "0?Set(RECMODE=dontcare)") in new stack
-- Executing [exten@sub-record-check:15] ExecIf("SIP/5010-0000472d", "1?Set(RECMODE=dontcare)") in new stack
-- Executing [exten@sub-record-check:16] Gosub("SIP/5010-0000472d", "recordcheck,1(dontcare,internal,5000)") in new stack
-- Executing [recordcheck@sub-record-check:1] NoOp("SIP/5010-0000472d", "Starting recording check against dontcare") in new stack
-- Executing [recordcheck@sub-record-check:2] Goto("SIP/5010-0000472d", "dontcare") in new stack
-- Goto (sub-record-check,recordcheck,3)
-- Executing [recordcheck@sub-record-check:3] Return("SIP/5010-0000472d", "") in new stack
-- Executing [exten@sub-record-check:17] Return("SIP/5010-0000472d", "") in new stack
-- Executing [5000@from-internal:19] Set("SIP/5010-0000472d", "RingGroupMethod=ringallv2") in new stack
-- Executing [5000@from-internal:20] Set("SIP/5010-0000472d", "_FMGRP=5000") in new stack
-- Executing [5000@from-internal:21] GotoIf("SIP/5010-0000472d", "0?doconfirm") in new stack
[2015-03-22 07:06:39] WARNING[24336][C-000029ed]: ast_expr2.fl:470 ast_yyerror: ast_yyerror(): syntax error: syntax error, unexpected '+', expecting $end; Input:
+
^
[2015-03-22 07:06:39] WARNING[24336][C-000029ed]: ast_expr2.fl:474 ast_yyerror: If you have questions, please refer to https://wiki.asterisk.org/wiki/display/AST/Channel+Variables
-- Executing [5000@from-internal:22] Macro("SIP/5010-0000472d", "dial,,TtrI,") in new stack
-- Executing [s@macro-dial:1] GotoIf("SIP/5010-0000472d", "1?dial") in new stack
-- Goto (macro-dial,s,3)
-- Executing [s@macro-dial:3] AGI("SIP/5010-0000472d", "dialparties.agi") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
dialparties.agi: Starting New Dialparties.agi
dialparties.agi: Caller ID name is 'Canyon DOC' number is '5010'
> dialparties.agi: USE_CONFIRMATION: 'FALSE'
> dialparties.agi: RINGGROUP_INDEX: ''
dialparties.agi: Methodology of ring is 'ringallv2'
> dialparties.agi: got fmgrp_prering: 2, fmgrp_grptime:
> dialparties.agi: fmgrp_totalprering: 2
> dialparties.agi: extension not in group list, ringing only during prering time
> dialparties.agi: ringallv2 ring times: REALPRERING: 2, PRERING: 2
-- dialparties.agi: Extension 5000 cf is disabled
-- dialparties.agi: Extension 5000 do not disturb is disabled
> dialparties.agi: extnum 5000 has: cw: 0; hascfb: 0 [] hascfu: 0 []
dialparties.agi: EXTENSION_STATE: 0 (NOT_INUSE)
dialparties.agi: Extension 5000 has ExtensionState: 0
-- dialparties.agi: Checking CW and CFB status for extension 5000
-- dialparties.agi: dbset CALLTRACE/5000 to 5010
-- dialparties.agi: Filtered ARG3: 5000
> dialparties.agi: NODEST: 5000 adding M(auto-blkvm) to dialopts: TtrIM(auto-blkvm)
> dialparties.agi: NODEST: 5000 blkvm enabled macro already in dialopts: TtrIM(auto-blkvm)
-- <SIP/5010-0000472d>AGI Script dialparties.agi completed, returning 0
-- Executing [s@macro-dial:7] Dial("SIP/5010-0000472d", "SIP/5000,2,TtrIM(auto-blkvm)") in new stack
== Using SIP RTP TOS bits 184
== Using SIP RTP CoS mark 5
-- Called SIP/5000
-- Connected line update to SIP/5010-0000472d prevented.
-- SIP/5000-0000472e is ringing
-- Nobody picked up in 2000 ms
-- Executing [s@macro-dial:8] Set("SIP/5010-0000472d", "DIALSTATUS=NOANSWER") in new stack
-- Executing [s@macro-dial:9] GosubIf("SIP/5010-0000472d", "0?NOANSWER,1") in new stack
-- Executing [5000@from-internal:23] Goto("SIP/5010-0000472d", "nextstep") in new stack
-- Goto (from-internal,5000,25)
-- Executing [5000@from-internal:25] Set("SIP/5010-0000472d", "RingGroupMethod=") in new stack
-- Executing [5000@from-internal:26] GotoIf("SIP/5010-0000472d", "0?nodest") in new stack
-- Executing [5000@from-internal:27] Set("SIP/5010-0000472d", "__NODEST=") in new stack
-- Executing [5000@from-internal:28] Set("SIP/5010-0000472d", "__PICKUPMARK=") in new stack
-- Executing [5000@from-internal:29] Macro("SIP/5010-0000472d", "blkvm-clr,") in new stack
-- Executing [s@macro-blkvm-clr:1] Set("SIP/5010-0000472d", "SHARED(BLKVM,SIP/5010-0000472d)=") in new stack
-- Executing [s@macro-blkvm-clr:2] Set("SIP/5010-0000472d", "GOSUB_RETVAL=") in new stack
-- Executing [s@macro-blkvm-clr:3] MacroExit("SIP/5010-0000472d", "") in new stack
-- Executing [5000@from-internal:30] Set("SIP/5010-0000472d", "DIALSTATUS=NOANSWER") in new stack
-- Executing [5000@from-internal:31] Goto("SIP/5010-0000472d", "ivr-1,s,1") in new stack
-- Goto (ivr-1,s,1)
-- Executing [s@ivr-1:1] Set("SIP/5010-0000472d", "TIMEOUT_LOOPCOUNT=0") in new stack
-- Executing [s@ivr-1:2] Set("SIP/5010-0000472d", "INVALID_LOOPCOUNT=0") in new stack
-- Executing [s@ivr-1:3] Set("SIP/5010-0000472d", "_IVR_CONTEXT_ivr-1=") in new stack
-- Executing [s@ivr-1:4] Set("SIP/5010-0000472d", "_IVR_CONTEXT=ivr-1") in new stack
-- Executing [s@ivr-1:5] Set("SIP/5010-0000472d", "__IVR_RETVM=") in new stack
-- Executing [s@ivr-1:6] GotoIf("SIP/5010-0000472d", "0?skip") in new stack
-- Executing [s@ivr-1:7] Answer("SIP/5010-0000472d", "") in new stack
> 0x7f1c4c1f35e0 -- Probation passed - setting RTP source address to 10.91.254.2:8000
-- Executing [s@ivr-1:8] Wait("SIP/5010-0000472d", "1") in new stack
-- Executing [s@ivr-1:9] Set("SIP/5010-0000472d", "IVR_MSG=") in new stack
-- Executing [s@ivr-1:10] Set("SIP/5010-0000472d", "TIMEOUT(digit)=3") in new stack
-- Digit timeout set to 3.000
-- Executing [s@ivr-1:11] ExecIf("SIP/5010-0000472d", "0?Background()") in new stack
-- Executing [s@ivr-1:12] WaitExten("SIP/5010-0000472d", "10,") in new stack
Executing [s@macro-dial:7] Dial("SIP/5010-0000472d", "SIP/5000,2,TtrIM(auto-blkvm)") in new stack
dialparties.agi adds 2 seconds of timeout instead of 20 seconds.
