Queue not work

Hi.
I installed FreePBX Distro 32 bit stable.
Extensions and Ring Groups work fine.
But queues don’t work.
I tried this manual but it didn’t help http://www.freepbx.org/simple-queue-setup
I make call and it’s just hang up.
When I call, in log file I see this:

[2013-01-24 11:37:07] VERBOSE[1832] netsock2.c: == Using SIP RTP TOS bits 184
[2013-01-24 11:37:07] VERBOSE[1832] netsock2.c: == Using SIP RTP CoS mark 5
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [telemat[email protected]:1] Goto(“SIP/tsl_test-00000026”, “from-pstn,4162773764,1”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Goto (from-pstn,4162773764,1)
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:1] Set(“SIP/tsl_test-00000026”, “__FROM_DID=4162773764”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:2] Gosub(“SIP/tsl_test-00000026”, “app-blacklist-check,s,1()”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/tsl_test-00000026”, “0?blacklisted”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:2] Set(“SIP/tsl_test-00000026”, “CALLED_BLACKLIST=1”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:3] Return(“SIP/tsl_test-00000026”, “”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:3] Set(“SIP/tsl_test-00000026”, “CDR(did)=4162773764”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:4] ExecIf(“SIP/tsl_test-00000026”, “0 ?Set(CALLERID(name)=84162231717)”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:5] Set(“SIP/tsl_test-00000026”, “__CALLINGPRES_SV=allowed_not_screened”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:6] Set(“SIP/tsl_test-00000026”, “CALLERPRES()=allowed_not_screened”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:7] Goto(“SIP/tsl_test-00000026”, “ext-queues,7007,1”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Goto (ext-queues,7007,1)
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:1] Macro(“SIP/tsl_test-00000026”, “user-callerid,”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:1] Set(“SIP/tsl_test-00000026”, “AMPUSER=84162231717”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:2] GotoIf(“SIP/tsl_test-00000026”, “0?report”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:3] ExecIf(“SIP/tsl_test-00000026”, “1?Set(REALCALLERIDNUM=84162231717)”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:4] Set(“SIP/tsl_test-00000026”, “AMPUSER=”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:5] Set(“SIP/tsl_test-00000026”, “AMPUSERCIDNAME=”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:6] GotoIf(“SIP/tsl_test-00000026”, “1?report”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Goto (macro-user-callerid,s,13)
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:13] GotoIf(“SIP/tsl_test-00000026”, “0?continue”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:14] Set(“SIP/tsl_test-00000026”, “__TTL=64”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:15] GotoIf(“SIP/tsl_test-00000026”, “1?continue”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Goto (macro-user-callerid,s,26)
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:26] Set(“SIP/tsl_test-00000026”, “CALLERID(number)=84162231717”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:27] Set(“SIP/tsl_test-00000026”, “CALLERID(name)=84162231717”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:28] Set(“SIP/tsl_test-00000026”, “CHANNEL(language)=en”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:2] Answer(“SIP/tsl_test-00000026”, “”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:3] Macro(“SIP/tsl_test-00000026”, “blkvm-set,reset”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:1] ExecIf(“SIP/tsl_test-00000026”, “1?Set(__BLKVM_CHANNEL=SIP/tsl_test-00000026)”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:2] Set(“SIP/tsl_test-00000026”, “SHARED(BLKVM,SIP/tsl_test-00000026)=TRUE”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:3] Set(“SIP/tsl_test-00000026”, “GOSUB_RETVAL=TRUE”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:4] MacroExit(“SIP/tsl_test-00000026”, “”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:4] ExecIf(“SIP/tsl_test-00000026”, “1?Set(_DIAL_OPTIONS=trM(auto-blkvm))”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:5] Set(“SIP/tsl_test-00000026”, “__NODEST=7007”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:6] Gosub(“SIP/tsl_test-00000026”, “sub-record-check,s,1(q,7007,dontcare)”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/tsl_test-00000026”, “1?check”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Goto (sub-record-check,s,6)
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:6] Set(“SIP/tsl_test-00000026”, “__MON_FMT=wav”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:7] GotoIf(“SIP/tsl_test-00000026”, “1?next”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Goto (sub-record-check,s,10)
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:10] ExecIf(“SIP/tsl_test-00000026”, “0?Return()”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:11] GotoIf(“SIP/tsl_test-00000026”, “0?q,1”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:12] Set(“SIP/tsl_test-00000026”, “__REC_STATUS=INITIALIZED”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:13] ExecIf(“SIP/tsl_test-00000026”, “1?Set(__REC_POLICY_MODE=dontcare)”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:14] Set(“SIP/tsl_test-00000026”, “NOW=1358991427”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:15] Set(“SIP/tsl_test-00000026”, “__DAY=24”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:16] Set(“SIP/tsl_test-00000026”, “__MONTH=01”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:17] Set(“SIP/tsl_test-00000026”, “__YEAR=2013”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:18] Set(“SIP/tsl_test-00000026”, “__TIMESTR=20130124-113707”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:19] Set(“SIP/tsl_test-00000026”, “__FROMEXTEN=84162231717”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:20] Set(“SIP/tsl_test-00000026”, “__CALLFILENAME=q-7007-84162231717-20130124-113707-1358991427.82”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:21] Goto(“SIP/tsl_test-00000026”, “q,1”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Goto (sub-record-check,q,1)
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:1] GosubIf(“SIP/tsl_test-00000026”, “0?recq,1(q,7007,84162231717)”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:2] Return(“SIP/tsl_test-00000026”, “”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:7] Set(“SIP/tsl_test-00000026”, “__MOHCLASS=default”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:8] QueueLog(“SIP/tsl_test-00000026”, “7007,1358991427.82,NONE,DID,4162773764”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:9] Queue(“SIP/tsl_test-00000026”, “7007,t,”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] res_musiconhold.c: – Started music on hold, class ‘default’, on SIP/tsl_test-00000026
[2013-01-24 11:37:07] VERBOSE[3325] app_queue.c: – Called Local/[email protected]/n
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:1] Set(“Local/[email protected];2”, “QAGENT=1235”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:2] Goto(“Local/[email protected];2”, “7007,1”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Goto (from-queue,7007,1)
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:1] Goto(“Local/[email protected];2”, “from-internal,1235,1”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Goto (from-internal,1235,1)
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:1] Set(“Local/[email protected];2”, “__RINGTIMER=20”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:2] Macro(“Local/[email protected];2”, “exten-vm,novm,1235,1,1,1”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:1] Macro(“Local/[email protected];2”, “user-callerid,”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:1] Set(“Local/[email protected];2”, “AMPUSER=84162231717”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:2] GotoIf(“Local/[email protected];2”, “1?report”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Goto (macro-user-callerid,s,13)
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:13] GotoIf(“Local/[email protected];2”, “0?continue”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:14] Set(“Local/[email protected];2”, “__TTL=63”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:15] GotoIf(“Local/[email protected];2”, “1?continue”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Goto (macro-user-callerid,s,26)
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:26] Set(“Local/[email protected];2”, “CALLERID(number)=84162231717”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:27] Set(“Local/[email protected];2”, “CALLERID(name)=84162231717”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:28] Set(“Local/[email protected];2”, “CHANNEL(language)=en”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:2] Set(“Local/[email protected];2”, “RingGroupMethod=none”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:3] Set(“Local/[email protected];2”, “__EXTTOCALL=1235”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:4] Set(“Local/[email protected];2”, “__PICKUPMARK=1235”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:5] Set(“Local/[email protected];2”, “RT=20”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:6] Gosub(“Local/[email protected];2”, “sub-record-check,s,1(exten,1235,)”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:1] GotoIf(“Local/[email protected];2”, “1?check”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Goto (sub-record-check,s,6)
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:6] Set(“Local/[email protected];2”, “__MON_FMT=wav”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:7] GotoIf(“Local/[email protected];2”, “1?next”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Goto (sub-record-check,s,10)
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:10] ExecIf(“Local/[email protected];2”, “0?Return()”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:11] GotoIf(“Local/[email protected];2”, “1?exten,1”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Goto (sub-record-check,exten,1)
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:1] GotoIf(“Local/[email protected];2”, “1?callee”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Goto (sub-record-check,exten,8)
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:8] GosubIf(“Local/[email protected];2”, “0?record,1(exten,1235,84162231717)”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:9] Return(“Local/[email protected];2”, “”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:7] GotoIf(“Local/[email protected];2”, “10?macrodial”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Goto (macro-exten-vm,s,13)
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:13] GosubIf(“Local/[email protected];2”, “0?clrheader,1()”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:14] Macro(“Local/[email protected];2”, “dial-one,20,trM(auto-blkvm),1235”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:1] Set(“Local/[email protected];2”, “DEXTEN=1235”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:2] Set(“Local/[email protected];2”, “DIALSTATUS_CW=”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:3] GosubIf(“Local/[email protected];2”, “0?screen,1()”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:4] GosubIf(“Local/[email protected];2”, “0?cf,1()”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:5] GotoIf(“Local/[email protected];2”, “1?skip1”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Goto (macro-dial-one,s,8)
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:8] GotoIf(“Local/[email protected];2”, “0?nodial”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:9] GotoIf(“Local/[email protected];2”, “0?continue”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:10] Set(“Local/[email protected];2”, “EXTHASCW=”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:11] GotoIf(“Local/[email protected];2”, “1?next1:cwinusebusy”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Goto (macro-dial-one,s,12)
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:12] GotoIf(“Local/[email protected];2”, “0?docfu:skip3”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Goto (macro-dial-one,s,16)
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:16] GotoIf(“Local/[email protected];2”, “1?next2:continue”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Goto (macro-dial-one,s,17)
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:17] GotoIf(“Local/[email protected];2”, “1?continue”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Goto (macro-dial-one,s,25)
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:25] GotoIf(“Local/[email protected];2”, “0?nodial”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:26] GosubIf(“Local/[email protected];2”, “1?dstring,1():dlocal,1()”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:1] Set(“Local/[email protected];2”, “DSTRING=”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:2] Set(“Local/[email protected];2”, “DEVICES=1235”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:3] ExecIf(“Local/[email protected];2”, “0?Return()”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:4] ExecIf(“Local/[email protected];2”, “0?Set(DEVICES=235)”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:5] Set(“Local/[email protected];2”, “LOOPCNT=1”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:6] Set(“Local/[email protected];2”, “ITER=1”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:7] Set(“Local/[email protected];2”, “THISDIAL=SIP/1235”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:8] GosubIf(“Local/[email protected];2”, “1?zap2dahdi,1()”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:1] ExecIf(“Local/[email protected];2”, “0?Return()”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:2] Set(“Local/[email protected];2”, “NEWDIAL=”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:3] Set(“Local/[email protected];2”, “LOOPCNT2=1”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:4] Set(“Local/[email protected];2”, “ITER2=1”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:5] Set(“Local/[email protected];2”, “THISPART2=SIP/1235”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:6] ExecIf(“Local/[email protected];2”, “0?Set(THISPART2=DAHDI/1235)”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:7] Set(“Local/[email protected];2”, “NEWDIAL=SIP/1235&”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:8] Set(“Local/[email protected];2”, “ITER2=2”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:9] GotoIf(“Local/[email protected];2”, “0?begin2”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:10] Set(“Local/[email protected];2”, “THISDIAL=SIP/1235”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:11] Return(“Local/[email protected];2”, “”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:9] Set(“Local/[email protected];2”, “DSTRING=SIP/1235&”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:10] Set(“Local/[email protected];2”, “ITER=2”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:11] GotoIf(“Local/[email protected];2”, “0?begin”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:12] Set(“Local/[email protected];2”, “DSTRING=SIP/1235”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:13] Return(“Local/[email protected];2”, “”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:27] GotoIf(“Local/[email protected];2”, “0?nodial”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:28] GotoIf(“Local/[email protected];2”, “0?skiptrace”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:29] GosubIf(“Local/[email protected];2”, “1?ctset,1():ctclear,1()”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:1] Set(“Local/[email protected];2”, “DB(CALLTRACE/1235)=84162231717”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:2] Return(“Local/[email protected];2”, “”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] res_musiconhold.c: – Stopped music on hold on SIP/tsl_test-00000026
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: == Spawn extension (ext-queues, 7007, 9) exited non-zero on ‘SIP/tsl_test-00000026’
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:1] Macro(“SIP/tsl_test-00000026”, “hangupcall,”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:30] Set(“Local/[email protected];2”, “D_OPTIONS=trM(auto-blkvm)”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/tsl_test-00000026”, “1?theend”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Goto (macro-hangupcall,s,3)
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:3] ExecIf(“SIP/tsl_test-00000026”, “0?Set(CDR(recordingfile)=)”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: – Executing [[email protected]:1] Hangup(“Local/[email protected];2”, “”) in new stack
[2013-01-24 11:37:07] VERBOSE[3326] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on ‘Local/[email protected];2’
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: – Executing [[email protected]:4] Hangup(“SIP/tsl_test-00000026”, “”) in new stack
[2013-01-24 11:37:07] VERBOSE[3325] app_macro.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘SIP/tsl_test-00000026’ in macro ‘hangupcall’
[2013-01-24 11:37:07] VERBOSE[3325] pbx.c: == Spawn extension (ext-queues, h, 1) exited non-zero on ‘SIP/tsl_test-00000026’
[2013-01-24 11:37:07] VERBOSE[1832] netsock2.c: == Using SIP RTP TOS bits 184
[2013-01-24 11:37:07] VERBOSE[1832] netsock2.c: == Using SIP RTP CoS mark 5
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:1] Goto(“SIP/tsl_test-00000027”, “from-pstn,4162773764,1”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Goto (from-pstn,4162773764,1)
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:1] Set(“SIP/tsl_test-00000027”, “__FROM_DID=4162773764”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:2] Gosub(“SIP/tsl_test-00000027”, “app-blacklist-check,s,1()”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/tsl_test-00000027”, “0?blacklisted”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:2] Set(“SIP/tsl_test-00000027”, “CALLED_BLACKLIST=1”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:3] Return(“SIP/tsl_test-00000027”, “”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:3] Set(“SIP/tsl_test-00000027”, “CDR(did)=4162773764”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:4] ExecIf(“SIP/tsl_test-00000027”, “0 ?Set(CALLERID(name)=84162231717)”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:5] Set(“SIP/tsl_test-00000027”, “__CALLINGPRES_SV=allowed_not_screened”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:6] Set(“SIP/tsl_test-00000027”, “CALLERPRES()=allowed_not_screened”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:7] Goto(“SIP/tsl_test-00000027”, “ext-queues,7007,1”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Goto (ext-queues,7007,1)
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:1] Macro(“SIP/tsl_test-00000027”, “user-callerid,”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:1] Set(“SIP/tsl_test-00000027”, “AMPUSER=84162231717”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:2] GotoIf(“SIP/tsl_test-00000027”, “0?report”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:3] ExecIf(“SIP/tsl_test-00000027”, “1?Set(REALCALLERIDNUM=84162231717)”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:4] Set(“SIP/tsl_test-00000027”, “AMPUSER=”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:5] Set(“SIP/tsl_test-00000027”, “AMPUSERCIDNAME=”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:6] GotoIf(“SIP/tsl_test-00000027”, “1?report”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Goto (macro-user-callerid,s,13)
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:13] GotoIf(“SIP/tsl_test-00000027”, “0?continue”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:14] Set(“SIP/tsl_test-00000027”, “__TTL=64”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:15] GotoIf(“SIP/tsl_test-00000027”, “1?continue”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Goto (macro-user-callerid,s,26)
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:26] Set(“SIP/tsl_test-00000027”, “CALLERID(number)=84162231717”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:27] Set(“SIP/tsl_test-00000027”, “CALLERID(name)=84162231717”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:28] Set(“SIP/tsl_test-00000027”, “CHANNEL(language)=en”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:2] Answer(“SIP/tsl_test-00000027”, “”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:3] Macro(“SIP/tsl_test-00000027”, “blkvm-set,reset”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:1] ExecIf(“SIP/tsl_test-00000027”, “1?Set(__BLKVM_CHANNEL=SIP/tsl_test-00000027)”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:2] Set(“SIP/tsl_test-00000027”, “SHARED(BLKVM,SIP/tsl_test-00000027)=TRUE”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:3] Set(“SIP/tsl_test-00000027”, “GOSUB_RETVAL=TRUE”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:4] MacroExit(“SIP/tsl_test-00000027”, “”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:4] ExecIf(“SIP/tsl_test-00000027”, “1?Set(_DIAL_OPTIONS=trM(auto-blkvm))”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:5] Set(“SIP/tsl_test-00000027”, “__NODEST=7007”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:6] Gosub(“SIP/tsl_test-00000027”, “sub-record-check,s,1(q,7007,dontcare)”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/tsl_test-00000027”, “1?check”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Goto (sub-record-check,s,6)
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:6] Set(“SIP/tsl_test-00000027”, “__MON_FMT=wav”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:7] GotoIf(“SIP/tsl_test-00000027”, “1?next”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Goto (sub-record-check,s,10)
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:10] ExecIf(“SIP/tsl_test-00000027”, “0?Return()”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:11] GotoIf(“SIP/tsl_test-00000027”, “0?q,1”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:12] Set(“SIP/tsl_test-00000027”, “__REC_STATUS=INITIALIZED”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:13] ExecIf(“SIP/tsl_test-00000027”, “1?Set(__REC_POLICY_MODE=dontcare)”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:14] Set(“SIP/tsl_test-00000027”, “NOW=1358991427”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:15] Set(“SIP/tsl_test-00000027”, “__DAY=24”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:16] Set(“SIP/tsl_test-00000027”, “__MONTH=01”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:17] Set(“SIP/tsl_test-00000027”, “__YEAR=2013”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:18] Set(“SIP/tsl_test-00000027”, “__TIMESTR=20130124-113707”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:19] Set(“SIP/tsl_test-00000027”, “__FROMEXTEN=84162231717”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:20] Set(“SIP/tsl_test-00000027”, “__CALLFILENAME=q-7007-84162231717-20130124-113707-1358991427.85”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:21] Goto(“SIP/tsl_test-00000027”, “q,1”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Goto (sub-record-check,q,1)
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:1] GosubIf(“SIP/tsl_test-00000027”, “0?recq,1(q,7007,84162231717)”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:2] Return(“SIP/tsl_test-00000027”, “”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:7] Set(“SIP/tsl_test-00000027”, “__MOHCLASS=default”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:8] QueueLog(“SIP/tsl_test-00000027”, “7007,1358991427.85,NONE,DID,4162773764”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:9] Queue(“SIP/tsl_test-00000027”, “7007,t,”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] res_musiconhold.c: – Started music on hold, class ‘default’, on SIP/tsl_test-00000027
[2013-01-24 11:37:07] VERBOSE[3328] app_queue.c: – Called Local/[email protected]/n
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:1] Set(“Local/[email protected];2”, “QAGENT=1236”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:2] Goto(“Local/[email protected];2”, “7007,1”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Goto (from-queue,7007,1)
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:1] Goto(“Local/[email protected];2”, “from-internal,1236,1”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Goto (from-internal,1236,1)
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:1] Set(“Local/[email protected];2”, “__RINGTIMER=20”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:2] Macro(“Local/[email protected];2”, “exten-vm,novm,1236,1,1,1”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:1] Macro(“Local/[email protected];2”, “user-callerid,”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:1] Set(“Local/[email protected];2”, “AMPUSER=84162231717”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:2] GotoIf(“Local/[email protected];2”, “1?report”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Goto (macro-user-callerid,s,13)
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:13] GotoIf(“Local/[email protected];2”, “0?continue”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:14] Set(“Local/[email protected];2”, “__TTL=63”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:15] GotoIf(“Local/[email protected];2”, “1?continue”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Goto (macro-user-callerid,s,26)
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:26] Set(“Local/[email protected];2”, “CALLERID(number)=84162231717”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:27] Set(“Local/[email protected]7;2”, “CALLERID(name)=84162231717”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:28] Set(“Local/[email protected];2”, “CHANNEL(language)=en”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:2] Set(“Local/[email protected];2”, “RingGroupMethod=none”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:3] Set(“Local/[email protected];2”, “__EXTTOCALL=1236”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:4] Set(“Local/[email protected];2”, “__PICKUPMARK=1236”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:5] Set(“Local/[email protected];2”, “RT=20”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:6] Gosub(“Local/[email protected];2”, “sub-record-check,s,1(exten,1236,)”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:1] GotoIf(“Local/[email protected];2”, “1?check”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Goto (sub-record-check,s,6)
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:6] Set(“Local/[email protected];2”, “__MON_FMT=wav”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:7] GotoIf(“Local/[email protected];2”, “1?next”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Goto (sub-record-check,s,10)
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:10] ExecIf(“Local/[email protected];2”, “0?Return()”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:11] GotoIf(“Local/[email protected];2”, “1?exten,1”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Goto (sub-record-check,exten,1)
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:1] GotoIf(“Local/[email protected];2”, “1?callee”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Goto (sub-record-check,exten,8)
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:8] GosubIf(“Local/[email protected];2”, “0?record,1(exten,1236,84162231717)”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:9] Return(“Local/[email protected];2”, “”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:7] GotoIf(“Local/[email protected];2”, “10?macrodial”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Goto (macro-exten-vm,s,13)
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:13] GosubIf(“Local/[email protected];2”, “0?clrheader,1()”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:14] Macro(“Local/[email protected];2”, “dial-one,20,trM(auto-blkvm),1236”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:1] Set(“Local/[email protected];2”, “DEXTEN=1236”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:2] Set(“Local/[email protected];2”, “DIALSTATUS_CW=”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:3] GosubIf(“Local/[email protected];2”, “0?screen,1()”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:4] GosubIf(“Local/[email protected];2”, “0?cf,1()”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:5] GotoIf(“Local/[email protected];2”, “1?skip1”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Goto (macro-dial-one,s,8)
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:8] GotoIf(“Local/[email protected];2”, “0?nodial”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:9] GotoIf(“Local/[email protected];2”, “0?continue”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:10] Set(“Local/[email protected];2”, “EXTHASCW=”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:11] GotoIf(“Local/[email protected];2”, “1?next1:cwinusebusy”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Goto (macro-dial-one,s,12)
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:12] GotoIf(“Local/[email protected];2”, “0?docfu:skip3”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Goto (macro-dial-one,s,16)
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:16] GotoIf(“Local/[email protected];2”, “1?next2:continue”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Goto (macro-dial-one,s,17)
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:17] GotoIf(“Local/[email protected];2”, “1?continue”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Goto (macro-dial-one,s,25)
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:25] GotoIf(“Local/[email protected];2”, “0?nodial”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:26] GosubIf(“Local/[email protected];2”, “1?dstring,1():dlocal,1()”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:1] Set(“Local/[email protected];2”, “DSTRING=”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:2] Set(“Local/[email protected];2”, “DEVICES=1236”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:3] ExecIf(“Local/[email protected];2”, “0?Return()”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:4] ExecIf(“Local/[email protected];2”, “0?Set(DEVICES=236)”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:5] Set(“Local/[email protected];2”, “LOOPCNT=1”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:6] Set(“Local/[email protected];2”, “ITER=1”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:7] Set(“Local/[email protected];2”, “THISDIAL=SIP/1236”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:8] GosubIf(“Local/[email protected];2”, “1?zap2dahdi,1()”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:1] ExecIf(“Local/[email protected];2”, “0?Return()”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:2] Set(“Local/[email protected];2”, “NEWDIAL=”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:3] Set(“Local/[email protected];2”, “LOOPCNT2=1”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:4] Set(“Local/[email protected];2”, “ITER2=1”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:5] Set(“Local/[email protected];2”, “THISPART2=SIP/1236”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:6] ExecIf(“Local/[email protected];2”, “0?Set(THISPART2=DAHDI/1236)”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:7] Set(“Local/[email protected];2”, “NEWDIAL=SIP/1236&”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:8] Set(“Local/[email protected];2”, “ITER2=2”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:9] GotoIf(“Local/[email protected];2”, “0?begin2”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:10] Set(“Local/[email protected];2”, “THISDIAL=SIP/1236”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:11] Return(“Local/[email protected];2”, “”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:9] Set(“Local/[email protected];2”, “DSTRING=SIP/1236&”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:10] Set(“Local/[email protected];2”, “ITER=2”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:11] GotoIf(“Local/[email protected];2”, “0?begin”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:12] Set(“Local/[email protected];2”, “DSTRING=SIP/1236”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:13] Return(“Local/[email protected];2”, “”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:27] GotoIf(“Local/[email protected];2”, “0?nodial”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:28] GotoIf(“Local/[email protected];2”, “0?skiptrace”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:29] GosubIf(“Local/[email protected];2”, “1?ctset,1():ctclear,1()”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:1] Set(“Local/[email protected];2”, “DB(CALLTRACE/1236)=84162231717”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:2] Return(“Local/[email protected];2”, “”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:30] Set(“Local/[email protected];2”, “D_OPTIONS=trM(auto-blkvm)”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:31] ExecIf(“Local/[email protected];2”, “0?SIPAddHeader(Alert-Info: )”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:32] ExecIf(“Local/[email protected];2”, “0?SIPAddHeader()”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:33] ExecIf(“Local/[email protected];2”, “1?Set(CHANNEL(musicclass)=default)”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:34] GosubIf(“Local/[email protected];2”, “0?qwait,1()”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:35] Set(“Local/[email protected];2”, “__CWIGNORE=”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:36] Set(“Local/[email protected];2”, “__KEEPCID=TRUE”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:37] GotoIf(“Local/[email protected];2”, “0?usegoto,1”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:38] GotoIf(“Local/[email protected];2”, “1?godial”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Goto (macro-dial-one,s,42)
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:42] Dial(“Local/[email protected];2”, “SIP/1236,20,trM(auto-blkvm)”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] netsock2.c: == Using SIP RTP TOS bits 184
[2013-01-24 11:37:07] VERBOSE[3329] netsock2.c: == Using SIP RTP CoS mark 5
[2013-01-24 11:37:07] VERBOSE[3329] app_dial.c: – Called SIP/1236
[2013-01-24 11:37:07] VERBOSE[3328] app_queue.c: – Local/[email protected];1 is ringing
[2013-01-24 11:37:07] VERBOSE[3328] res_musiconhold.c: – Stopped music on hold on SIP/tsl_test-00000027
[2013-01-24 11:37:07] VERBOSE[3329] app_macro.c: == Spawn extension (macro-dial-one, s, 42) exited non-zero on ‘Local/[email protected];2’ in macro ‘dial-one’
[2013-01-24 11:37:07] VERBOSE[3329] app_macro.c: == Spawn extension (macro-exten-vm, s, 14) exited non-zero on ‘Local/[email protected];2’ in macro ‘exten-vm’
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: == Spawn extension (from-internal, 1236, 2) exited non-zero on ‘Local/[email protected];2’
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: – Executing [[email protected]:1] Hangup(“Local/[email protected];2”, “”) in new stack
[2013-01-24 11:37:07] VERBOSE[3329] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on ‘Local/[email protected];2’
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: == Spawn extension (ext-queues, 7007, 9) exited non-zero on ‘SIP/tsl_test-00000027’
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:1] Macro(“SIP/tsl_test-00000027”, “hangupcall,”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:1] GotoIf(“SIP/tsl_test-00000027”, “1?theend”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Goto (macro-hangupcall,s,3)
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:3] ExecIf(“SIP/tsl_test-00000027”, “0?Set(CDR(recordingfile)=)”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: – Executing [[email protected]:4] Hangup(“SIP/tsl_test-00000027”, “”) in new stack
[2013-01-24 11:37:07] VERBOSE[3328] app_macro.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on ‘SIP/tsl_test-00000027’ in macro ‘hangupcall’
[2013-01-24 11:37:07] VERBOSE[3328] pbx.c: == Spawn extension (ext-queues, h, 1) exited non-zero on ‘SIP/tsl_test-00000027’

What is wrong, help please.

What extensions do you have in the queue?

I was about to ask the same thing Bill …

There are two static agents in my queue:
1235,0
1236,0
Queue number is 7007, may be it’s important.

Is your ring strategy set to ringall ?
roundrobin as per the artice you mention does not exist.

My ring strategy is set to rrmemory.

What about queue? The queue is still not working.

What does

queue show 7007

from the asterisk CLI show

7007 has 0 calls (max unlimited) in ‘rrmemory’ strategy (0s holdtime, 0s talktime), W:0, C:0, A:0, SL:0.0% within 60s
Members:
1235 (Local/[email protected]/n) (Not in use) has taken no calls yet
1236 (Local/[email protected]/n) (Unavailable) has taken no calls yet
No Callers

Anything wrong?

Do a sip show peers and see if our extensions are registering.
Also setp a soft phone and make it part of the queue and see if that rings and works.

Yes, in FreePBX System Status I see:
IP Phones Online 1
IP Trunks Online 1
IP Trunk Registrations 1

My softphone is ok. Registered extension 1235 is part of queue 7007.
I can make only outgoing calls from softphone, but incoming calls not work.

‘sip show peers’ show this:

Name/username Host Dyn Forcerport ACL Port Status
1235/1235 ...100 D A 5060 OK (1 ms)
1236 (Unspecified) D A 0 UNKNOWN
1237 (Unspecified) D A 0 UNKNOWN
tsl_test/telem_asteri
..*.132 N 5060 Unmonitored
4 sip peers [Monitored: 1 online, 2 offline Unmonitored: 1 online, 0 offline]

1235 OK
1236 and 1237 say UNKNOWN
Hint: [Monitored: 1 online, 2 Offline]
You need to get your phones registered first. What phones are you using?
Which endpoint manager are you using?

I found the reason for not working queue.
For sure it will be useful to someone.
This file /etc/asterisk/extensions_additional.conf is necessary to add two lines to the section [ext-queues] before line exten => 7007,n,Answer.
This two lines is:
exten => 7007,n,Ringing()
exten => 7007,n,Wait(1)

7007 is name of my queue.
And after that is necessary type in console asterisk -r and type core reload

If you apply any changes from the web interface, these two lines will be gone again.

This advice is not required, is not how you add custom code to FreePBX (as author noted any changes will overwrite it) and should not be used.