followme на мобильный [закрыт]

Кто может подсказать по переадресации? Сегодня переустановил свежую сборку freepbx distro с астериском 1.8 и не работает переадресация. Раньше стоял астер 1.6 и followme не работал так же. Собственно из за этого и решил переустановить систему. Когда звонишь на номер то сначала загорается трубка 404, а потом поступает звонок на мобильник, но и там и там тишина. кодек alow & ulaw Номер в followme в формате 79213336286#, пробовал через custom destonation - тоже не работает. звонок с внутреннего телефона на мобильник в таком формате походит все ок. Звонок проходит по схеме звонок с городского на sip линию - астер - другая sip линия - мобильный тел. При этом sip линия манго рапортует о том что абонент не доступен Вот лог: [2013-02-01 15:50:47] VERBOSE[1650] netsock2.c: == Using SIP RTP TOS bits 184 [2013-02-01 15:50:47] VERBOSE[1650] netsock2.c: == Using SIP RTP CoS mark 5 [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [78123855909@from-trunk-sip-78123855909:1] Set("SIP/78123855909-00000000", "GROUP()=OUT5") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [78123855909@from-trunk-sip-78123855909:2] Goto("SIP/78123855909-00000000", "from-trunk,78123855909,1") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Goto (from-trunk,78123855909,1) [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [78123855909@from-trunk:1] Set("SIP/78123855909-00000000", "FROMDID=78123855909") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [78123855909@from-trunk:2] Gosub("SIP/78123855909-00000000", "app-blacklist-check,s,1()") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [s@app-blacklist-check:1] GotoIf("SIP/78123855909-00000000", "0?blacklisted") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [s@app-blacklist-check:2] Set("SIP/78123855909-00000000", "CALLEDBLACKLIST=1") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [s@app-blacklist-check:3] Return("SIP/78123855909-00000000", "") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [78123855909@from-trunk:3] Set("SIP/78123855909-00000000", "CDR(did)=78123855909") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [78123855909@from-trunk:4] ExecIf("SIP/78123855909-00000000", "0 ?Set(CALLERID(name)=78124051501)") in new stack [2013-02-01 15:50:47] WARNING[2026] funccallerid.c: CALLERPRES is deprecated. Use CALLERID(name-pres) or CALLERID(num-pres) instead. [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [78123855909@from-trunk:5] Set("SIP/78123855909-00000000", "CALLINGPRESSV=allowednotscreened") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [78123855909@from-trunk:6] Set("SIP/78123855909-00000000", "CALLERPRES()=allowednotscreened") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [78123855909@from-trunk:7] Goto("SIP/78123855909-00000000", "from-did-direct,404,1") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Goto (from-did-direct,404,1) [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [404@from-did-direct:1] GotoIf("SIP/78123855909-00000000", "0?ext-local,404,1") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [404@from-did-direct:2] Macro("SIP/78123855909-00000000", "user-callerid,") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [s@macro-user-callerid:1] Set("SIP/78123855909-00000000", "AMPUSER=78124051501") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [s@macro-user-callerid:2] GotoIf("SIP/78123855909-00000000", "0?report") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [s@macro-user-callerid:3] ExecIf("SIP/78123855909-00000000", "1?Set(REALCALLERIDNUM=78124051501)") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [s@macro-user-callerid:4] Set("SIP/78123855909-00000000", "AMPUSER=") in new stack [2013-02-01 15:50:47] VERBOSE[1650] netsock2.c: == Using SIP RTP TOS bits 184 [2013-02-01 15:50:47] VERBOSE[1650] netsock2.c: == Using SIP RTP CoS mark 5 [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [s@macro-user-callerid:5] Set("SIP/78123855909-00000000", "AMPUSERCIDNAME=") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [78123855909@from-trunk-sip-78123855909:1] Set("SIP/78123855909-00000001", "GROUP()=OUT5") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [s@macro-user-callerid:6] GotoIf("SIP/78123855909-00000000", "1?report") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [78123855909@from-trunk-sip-78123855909:2] Goto("SIP/78123855909-00000001", "from-trunk,78123855909,1") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Goto (macro-user-callerid,s,13) [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Goto (from-trunk,78123855909,1) [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [s@macro-user-callerid:13] GotoIf("SIP/78123855909-00000000", "0?continue") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [78123855909@from-trunk:1] Set("SIP/78123855909-00000001", "FROMDID=78123855909") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [s@macro-user-callerid:14] Set("SIP/78123855909-00000000", "TTL=64") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [78123855909@from-trunk:2] Gosub("SIP/78123855909-00000001", "app-blacklist-check,s,1()") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [s@macro-user-callerid:15] GotoIf("SIP/78123855909-00000000", "1?continue") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Goto (macro-user-callerid,s,26) [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [s@macro-user-callerid:26] Set("SIP/78123855909-00000000", "CALLERID(number)=78124051501") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [s@macro-user-callerid:27] Set("SIP/78123855909-00000000", "CALLERID(name)=78124051501") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [s@app-blacklist-check:1] GotoIf("SIP/78123855909-00000001", "0?blacklisted") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [s@macro-user-callerid:28] Set("SIP/78123855909-00000000", "CHANNEL(language)=en") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [404@from-did-direct:3] Set("SIP/78123855909-00000000", "DIALOPTIONS=trI") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [404@from-did-direct:4] Set("SIP/78123855909-00000000", "CONNECTEDLINE(num)=404") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [404@from-did-direct:5] Set("SIP/78123855909-00000000", "CONNECTEDLINE(name,i)=dari") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [s@app-blacklist-check:2] Set("SIP/78123855909-00000001", "CALLEDBLACKLIST=1") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [404@from-did-direct:6] Set("SIP/78123855909-00000000", "FMDIALSTATUS=NOTINUSE") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [404@from-did-direct:7] Set("SIP/78123855909-00000000", "EXTTOCALL=404") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [404@from-did-direct:8] Set("SIP/78123855909-00000000", "PICKUPMARK=404") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [404@from-did-direct:9] Macro("SIP/78123855909-00000000", "blkvm-setifempty,") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [s@app-blacklist-check:3] Return("SIP/78123855909-00000001", "") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [78123855909@from-trunk:3] Set("SIP/78123855909-00000001", "CDR(did)=78123855909") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [s@macro-blkvm-setifempty:1] GotoIf("SIP/78123855909-00000000", "1?init") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Goto (macro-blkvm-setifempty,s,4) [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [s@macro-blkvm-setifempty:4] Set("SIP/78123855909-00000000", "BLKVMCHANNEL=SIP/78123855909-00000000") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [s@macro-blkvm-setifempty:5] Set("SIP/78123855909-00000000", "SHARED(BLKVM,SIP/78123855909-00000000)=TRUE") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [s@macro-blkvm-setifempty:6] Set("SIP/78123855909-00000000", "GOSUBRETVAL=TRUE") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [s@macro-blkvm-setifempty:7] MacroExit("SIP/78123855909-00000000", "") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [78123855909@from-trunk:4] ExecIf("SIP/78123855909-00000001", "0 ?Set(CALLERID(name)=78124051501)") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [404@from-did-direct:10] GotoIf("SIP/78123855909-00000000", "1?skipov") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Goto (from-did-direct,404,13) [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [404@from-did-direct:13] Set("SIP/78123855909-00000000", "RRNODEST=") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [404@from-did-direct:14] Set("SIP/78123855909-00000000", "NODEST=404") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [78123855909@from-trunk:5] Set("SIP/78123855909-00000001", "CALLINGPRESSV=allowednotscreened") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [404@from-did-direct:15] GosubIf("SIP/78123855909-00000000", "0?sub-fmsetcid,s,1()") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [404@from-did-direct:16] Set("SIP/78123855909-00000000", "RecordMethod=Group") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [404@from-did-direct:17] Gosub("SIP/78123855909-00000000", "sub-record-check,s,1(exten,404,)") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [78123855909@from-trunk:6] Set("SIP/78123855909-00000001", "CALLERPRES()=allowednotscreened") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [s@sub-record-check:1] GotoIf("SIP/78123855909-00000000", "1?check") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Goto (sub-record-check,s,6) [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [s@sub-record-check:6] Set("SIP/78123855909-00000000", "MONFMT=wav") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [s@sub-record-check:7] GotoIf("SIP/78123855909-00000000", "1?next") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Goto (sub-record-check,s,10) [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [s@sub-record-check:10] ExecIf("SIP/78123855909-00000000", "0?Return()") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [78123855909@from-trunk:7] Goto("SIP/78123855909-00000001", "from-did-direct,404,1") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [s@sub-record-check:11] GotoIf("SIP/78123855909-00000000", "0?exten,1") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [s@sub-record-check:12] Set("SIP/78123855909-00000000", "RECSTATUS=INITIALIZED") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [s@sub-record-check:13] ExecIf("SIP/78123855909-00000000", "0?Set(RECPOLICYMODE=)") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [s@sub-record-check:14] Set("SIP/78123855909-00000000", "NOW=1359719447") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [s@sub-record-check:15] Set("SIP/78123855909-00000000", "DAY=01") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Goto (from-did-direct,404,1) [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [s@sub-record-check:16] Set("SIP/78123855909-00000000", "MONTH=02") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [s@sub-record-check:17] Set("SIP/78123855909-00000000", "YEAR=2013") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [404@from-did-direct:1] GotoIf("SIP/78123855909-00000001", "0?ext-local,404,1") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [s@sub-record-check:18] Set("SIP/78123855909-00000000", "TIMESTR=20130201-155047") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [404@from-did-direct:2] Macro("SIP/78123855909-00000001", "user-callerid,") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [s@sub-record-check:19] Set("SIP/78123855909-00000000", "FROMEXTEN=78124051501") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [s@sub-record-check:20] Set("SIP/78123855909-00000000", "CALLFILENAME=exten-404-78124051501-20130201-155047-1359719447.0") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [s@sub-record-check:21] Goto("SIP/78123855909-00000000", "exten,1") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Goto (sub-record-check,exten,1) [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [exten@sub-record-check:1] GotoIf("SIP/78123855909-00000000", "0?callee") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [exten@sub-record-check:2] Set("SIP/78123855909-00000000", "RECPOLICYMODE=dontcare") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [exten@sub-record-check:3] GotoIf("SIP/78123855909-00000000", "1?caller") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Goto (sub-record-check,exten,10) [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [exten@sub-record-check:10] Set("SIP/78123855909-00000000", "RECPOLICYMODE=") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [exten@sub-record-check:11] GosubIf("SIP/78123855909-00000000", "0?record,1(exten,404,78124051501)") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [exten@sub-record-check:12] Return("SIP/78123855909-00000000", "") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [404@from-did-direct:18] Set("SIP/78123855909-00000000", "RingGroupMethod=ringallv2") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [404@from-did-direct:19] Set("SIP/78123855909-00000000", "FMGRP=404") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [404@from-did-direct:20] GotoIf("SIP/78123855909-00000000", "0?doconfirm") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [s@macro-user-callerid:1] Set("SIP/78123855909-00000001", "AMPUSER=78124051501") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [404@from-did-direct:21] Macro("SIP/78123855909-00000000", "dial,60,trI,79213336286#") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [s@macro-dial:1] GotoIf("SIP/78123855909-00000000", "1?dial") in new stack [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Goto (macro-dial,s,3) [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [s@macro-dial:3] AGI("SIP/78123855909-00000000", "dialparties.agi") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [s@macro-user-callerid:2] GotoIf("SIP/78123855909-00000001", "0?report") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [s@macro-user-callerid:3] ExecIf("SIP/78123855909-00000001", "1?Set(REALCALLERIDNUM=78124051501)") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [s@macro-user-callerid:4] Set("SIP/78123855909-00000001", "AMPUSER=") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [s@macro-user-callerid:5] Set("SIP/78123855909-00000001", "AMPUSERCIDNAME=") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [s@macro-user-callerid:6] GotoIf("SIP/78123855909-00000001", "1?report") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Goto (macro-user-callerid,s,13) [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [s@macro-user-callerid:13] GotoIf("SIP/78123855909-00000001", "0?continue") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [s@macro-user-callerid:14] Set("SIP/78123855909-00000001", "TTL=64") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [s@macro-user-callerid:15] GotoIf("SIP/78123855909-00000001", "1?continue") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Goto (macro-user-callerid,s,26) [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [s@macro-user-callerid:26] Set("SIP/78123855909-00000001", "CALLERID(number)=78124051501") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [s@macro-user-callerid:27] Set("SIP/78123855909-00000001", "CALLERID(name)=78124051501") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [s@macro-user-callerid:28] Set("SIP/78123855909-00000001", "CHANNEL(language)=en") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [404@from-did-direct:3] Set("SIP/78123855909-00000001", "DIALOPTIONS=trI") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [404@from-did-direct:4] Set("SIP/78123855909-00000001", "CONNECTEDLINE(num)=404") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [404@from-did-direct:5] Set("SIP/78123855909-00000001", "CONNECTEDLINE(name,i)=dari") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [404@from-did-direct:6] Set("SIP/78123855909-00000001", "FMDIALSTATUS=NOTINUSE") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [404@from-did-direct:7] Set("SIP/78123855909-00000001", "EXTTOCALL=404") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [404@from-did-direct:8] Set("SIP/78123855909-00000001", "PICKUPMARK=404") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [404@from-did-direct:9] Macro("SIP/78123855909-00000001", "blkvm-setifempty,") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [s@macro-blkvm-setifempty:1] GotoIf("SIP/78123855909-00000001", "1?init") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Goto (macro-blkvm-setifempty,s,4) [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [s@macro-blkvm-setifempty:4] Set("SIP/78123855909-00000001", "BLKVMCHANNEL=SIP/78123855909-00000001") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [s@macro-blkvm-setifempty:5] Set("SIP/78123855909-00000001", "SHARED(BLKVM,SIP/78123855909-00000001)=TRUE") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [s@macro-blkvm-setifempty:6] Set("SIP/78123855909-00000001", "GOSUBRETVAL=TRUE") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [s@macro-blkvm-setifempty:7] MacroExit("SIP/78123855909-00000001", "") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [404@from-did-direct:10] GotoIf("SIP/78123855909-00000001", "1?skipov") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Goto (from-did-direct,404,13) [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [404@from-did-direct:13] Set("SIP/78123855909-00000001", "RRNODEST=") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [404@from-did-direct:14] Set("SIP/78123855909-00000001", "NODEST=404") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [404@from-did-direct:15] GosubIf("SIP/78123855909-00000001", "0?sub-fmsetcid,s,1()") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [404@from-did-direct:16] Set("SIP/78123855909-00000001", "RecordMethod=Group") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [404@from-did-direct:17] Gosub("SIP/78123855909-00000001", "sub-record-check,s,1(exten,404,)") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [s@sub-record-check:1] GotoIf("SIP/78123855909-00000001", "1?check") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Goto (sub-record-check,s,6) [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [s@sub-record-check:6] Set("SIP/78123855909-00000001", "MONFMT=wav") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [s@sub-record-check:7] GotoIf("SIP/78123855909-00000001", "1?next") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Goto (sub-record-check,s,10) [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [s@sub-record-check:10] ExecIf("SIP/78123855909-00000001", "0?Return()") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [s@sub-record-check:11] GotoIf("SIP/78123855909-00000001", "0?exten,1") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [s@sub-record-check:12] Set("SIP/78123855909-00000001", "RECSTATUS=INITIALIZED") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [s@sub-record-check:13] ExecIf("SIP/78123855909-00000001", "0?Set(RECPOLICYMODE=)") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [s@sub-record-check:14] Set("SIP/78123855909-00000001", "NOW=1359719447") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [s@sub-record-check:15] Set("SIP/78123855909-00000001", "DAY=01") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [s@sub-record-check:16] Set("SIP/78123855909-00000001", "MONTH=02") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [s@sub-record-check:17] Set("SIP/78123855909-00000001", "YEAR=2013") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [s@sub-record-check:18] Set("SIP/78123855909-00000001", "TIMESTR=20130201-155047") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [s@sub-record-check:19] Set("SIP/78123855909-00000001", "FROMEXTEN=78124051501") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [s@sub-record-check:20] Set("SIP/78123855909-00000001", "CALLFILENAME=exten-404-78124051501-20130201-155047-1359719447.1") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [s@sub-record-check:21] Goto("SIP/78123855909-00000001", "exten,1") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Goto (sub-record-check,exten,1) [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [exten@sub-record-check:1] GotoIf("SIP/78123855909-00000001", "0?callee") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [exten@sub-record-check:2] Set("SIP/78123855909-00000001", "RECPOLICYMODE=dontcare") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [exten@sub-record-check:3] GotoIf("SIP/78123855909-00000001", "1?caller") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Goto (sub-record-check,exten,10) [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [exten@sub-record-check:10] Set("SIP/78123855909-00000001", "RECPOLICYMODE=") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [exten@sub-record-check:11] GosubIf("SIP/78123855909-00000001", "0?record,1(exten,404,78124051501)") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [exten@sub-record-check:12] Return("SIP/78123855909-00000001", "") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [404@from-did-direct:18] Set("SIP/78123855909-00000001", "RingGroupMethod=ringallv2") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [404@from-did-direct:19] Set("SIP/78123855909-00000001", "FMGRP=404") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [404@from-did-direct:20] GotoIf("SIP/78123855909-00000001", "0?doconfirm") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [404@from-did-direct:21] Macro("SIP/78123855909-00000001", "dial,60,trI,79213336286#") in new stack [2013-02-01 15:50:47] VERBOSE[2026] resagi.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [s@macro-dial:1] GotoIf("SIP/78123855909-00000001", "1?dial") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Goto (macro-dial,s,3) [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [s@macro-dial:3] AGI("SIP/78123855909-00000001", "dialparties.agi") in new stack [2013-02-01 15:50:47] VERBOSE[2027] resagi.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi [2013-02-01 15:50:47] VERBOSE[2026] resagi.c: dialparties.agi: Starting New Dialparties.agi [2013-02-01 15:50:47] VERBOSE[2026] resagi.c: dialparties.agi: Caller ID name is '78124051501' number is '78124051501' [2013-02-01 15:50:47] VERBOSE[2026] resagi.c: dialparties.agi: Methodology of ring is 'ringallv2' [2013-02-01 15:50:47] VERBOSE[2026] resagi.c: -- dialparties.agi: Added extension 79213336286# to extension map [2013-02-01 15:50:47] VERBOSE[2026] resagi.c: -- dialparties.agi: Extension 404 cf is disabled [2013-02-01 15:50:47] VERBOSE[2026] resagi.c: -- dialparties.agi: Extension 79213336286# cf is disabled [2013-02-01 15:50:47] VERBOSE[2026] resagi.c: -- dialparties.agi: Extension 404 do not disturb is disabled [2013-02-01 15:50:47] VERBOSE[2026] resagi.c: -- dialparties.agi: dbset CALLTRACE/404 to 78124051501 [2013-02-01 15:50:47] VERBOSE[2026] resagi.c: -- dialparties.agi: Filtered ARG3: 404-79213336286 [2013-02-01 15:50:47] VERBOSE[2026] resagi.c: -- <sip 78123855909-00000000="">AGI Script dialparties.agi completed, returning 0 [2013-02-01 15:50:47] VERBOSE[2026] pbx.c: -- Executing [s@macro-dial:7] Dial("SIP/78123855909-00000000", "Local/FMPR-404@from-internal&Local/FMGL-79213336286#@from-internal,62,trIM(auto-blkvm)") in new stack [2013-02-01 15:50:47] VERBOSE[2026] appdial.c: -- Called Local/FMPR-404@from-internal [2013-02-01 15:50:47] VERBOSE[2026] appdial.c: -- Called Local/FMGL-79213336286#@from-internal [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [FMPR-404@from-internal:1] NoCDR("Local/FMPR-404@from-internal-00000000;2", "") in new stack [2013-02-01 15:50:47] VERBOSE[2027] resagi.c: dialparties.agi: Starting New Dialparties.agi [2013-02-01 15:50:47] VERBOSE[2027] resagi.c: dialparties.agi: Caller ID name is '78124051501' number is '78124051501' [2013-02-01 15:50:47] VERBOSE[2027] resagi.c: dialparties.agi: Methodology of ring is 'ringallv2' [2013-02-01 15:50:47] VERBOSE[2027] resagi.c: -- dialparties.agi: Added extension 79213336286# to extension map [2013-02-01 15:50:47] VERBOSE[2027] resagi.c: -- dialparties.agi: Extension 404 cf is disabled [2013-02-01 15:50:47] VERBOSE[2027] resagi.c: -- dialparties.agi: Extension 79213336286# cf is disabled [2013-02-01 15:50:47] VERBOSE[2027] resagi.c: -- dialparties.agi: Extension 404 do not disturb is disabled [2013-02-01 15:50:47] VERBOSE[2027] resagi.c: -- dialparties.agi: dbset CALLTRACE/404 to 78124051501 [2013-02-01 15:50:47] VERBOSE[2027] resagi.c: -- dialparties.agi: Filtered ARG3: 404-79213336286 [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [FMPR-404@from-internal:2] Set("Local/FMPR-404@from-internal-00000000;2", "RingGroupMethod=") in new stack [2013-02-01 15:50:47] VERBOSE[2031] pbx.c: -- Executing [FMGL-79213336286#@from-internal:1] NoCDR("Local/FMGL-79213336286#@from-internal-00000001;2", "") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [FMPR-404@from-internal:3] Set("Local/FMPR-404@from-internal-00000000;2", "USECONFIRMATION=") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [FMPR-404@from-internal:4] Set("Local/FMPR-404@from-internal-00000000;2", "RINGGROUPINDEX=") in new stack [2013-02-01 15:50:47] VERBOSE[2031] pbx.c: -- Executing [FMGL-79213336286#@from-internal:2] Set("Local/FMGL-79213336286#@from-internal-00000001;2", "ENDLOOP=1359719449") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [FMPR-404@from-internal:5] Macro("Local/FMPR-404@from-internal-00000000;2", "simple-dial,404,2") in new stack [2013-02-01 15:50:47] VERBOSE[2031] pbx.c: -- Executing [FMGL-79213336286#@from-internal:3] GotoIf("Local/FMGL-79213336286#@from-internal-00000001;2", "0?dodnd") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [s@macro-simple-dial:1] Set("Local/FMPR-404@from-internal-00000000;2", "EXTTOCALL=404") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [s@macro-simple-dial:2] Set("Local/FMPR-404@from-internal-00000000;2", "RT=2") in new stack [2013-02-01 15:50:47] VERBOSE[2031] pbx.c: -- Executing [FMGL-79213336286#@from-internal:4] Wait("Local/FMGL-79213336286#@from-internal-00000001;2", "1") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [s@macro-simple-dial:3] Set("Local/FMPR-404@from-internal-00000000;2", "CFUEXT=") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [s@macro-simple-dial:4] Set("Local/FMPR-404@from-internal-00000000;2", "CFBEXT=") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [s@macro-simple-dial:5] Set("Local/FMPR-404@from-internal-00000000;2", "CWITMP=") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [s@macro-simple-dial:6] Macro("Local/FMPR-404@from-internal-00000000;2", "dial-one,2,tr,404") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [s@macro-dial-one:1] Set("Local/FMPR-404@from-internal-00000000;2", "DEXTEN=404") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [s@macro-dial-one:2] Set("Local/FMPR-404@from-internal-00000000;2", "DIALSTATUSCW=") in new stack [2013-02-01 15:50:47] VERBOSE[2027] resagi.c: -- <sip 78123855909-00000001="">AGI Script dialparties.agi completed, returning 0 [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [s@macro-dial-one:3] GosubIf("Local/FMPR-404@from-internal-00000000;2", "0?screen,1()") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [s@macro-dial-one:4] GosubIf("Local/FMPR-404@from-internal-00000000;2", "0?cf,1()") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [s@macro-dial-one:5] GotoIf("Local/FMPR-404@from-internal-00000000;2", "1?skip1") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Goto (macro-dial-one,s,8) [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [s@macro-dial-one:8] GotoIf("Local/FMPR-404@from-internal-00000000;2", "0?nodial") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [s@macro-dial-one:9] GotoIf("Local/FMPR-404@from-internal-00000000;2", "0?continue") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [s@macro-dial-one:10] Set("Local/FMPR-404@from-internal-00000000;2", "EXTHASCW=ENABLED") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [s@macro-dial-one:11] GotoIf("Local/FMPR-404@from-internal-00000000;2", "0?next1:cwinusebusy") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Goto (macro-dial-one,s,23) [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [s@macro-dial-one:23] GotoIf("Local/FMPR-404@from-internal-00000000;2", "1?next3:continue") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Goto (macro-dial-one,s,24) [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [s@macro-dial-one:24] ExecIf("Local/FMPR-404@from-internal-00000000;2", "0?Set(DIALSTATUSCW=BUSY)") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [s@macro-dial-one:25] GotoIf("Local/FMPR-404@from-internal-00000000;2", "0?nodial") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [s@macro-dial-one:26] GosubIf("Local/FMPR-404@from-internal-00000000;2", "1?dstring,1():dlocal,1()") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [dstring@macro-dial-one:1] Set("Local/FMPR-404@from-internal-00000000;2", "DSTRING=") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [dstring@macro-dial-one:2] Set("Local/FMPR-404@from-internal-00000000;2", "DEVICES=404") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [dstring@macro-dial-one:3] ExecIf("Local/FMPR-404@from-internal-00000000;2", "0?Return()") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [dstring@macro-dial-one:4] ExecIf("Local/FMPR-404@from-internal-00000000;2", "0?Set(DEVICES=04)") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [dstring@macro-dial-one:5] Set("Local/FMPR-404@from-internal-00000000;2", "LOOPCNT=1") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [dstring@macro-dial-one:6] Set("Local/FMPR-404@from-internal-00000000;2", "ITER=1") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [dstring@macro-dial-one:7] Set("Local/FMPR-404@from-internal-00000000;2", "THISDIAL=SIP/404") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [dstring@macro-dial-one:8] GosubIf("Local/FMPR-404@from-internal-00000000;2", "1?zap2dahdi,1()") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [zap2dahdi@macro-dial-one:1] ExecIf("Local/FMPR-404@from-internal-00000000;2", "0?Return()") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [zap2dahdi@macro-dial-one:2] Set("Local/FMPR-404@from-internal-00000000;2", "NEWDIAL=") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [zap2dahdi@macro-dial-one:3] Set("Local/FMPR-404@from-internal-00000000;2", "LOOPCNT2=1") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [zap2dahdi@macro-dial-one:4] Set("Local/FMPR-404@from-internal-00000000;2", "ITER2=1") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [zap2dahdi@macro-dial-one:5] Set("Local/FMPR-404@from-internal-00000000;2", "THISPART2=SIP/404") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [zap2dahdi@macro-dial-one:6] ExecIf("Local/FMPR-404@from-internal-00000000;2", "0?Set(THISPART2=DAHDI/404)") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [zap2dahdi@macro-dial-one:7] Set("Local/FMPR-404@from-internal-00000000;2", "NEWDIAL=SIP/404&") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [zap2dahdi@macro-dial-one:8] Set("Local/FMPR-404@from-internal-00000000;2", "ITER2=2") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [zap2dahdi@macro-dial-one:9] GotoIf("Local/FMPR-404@from-internal-00000000;2", "0?begin2") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [zap2dahdi@macro-dial-one:10] Set("Local/FMPR-404@from-internal-00000000;2", "THISDIAL=SIP/404") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [zap2dahdi@macro-dial-one:11] Return("Local/FMPR-404@from-internal-00000000;2", "") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [dstring@macro-dial-one:9] Set("Local/FMPR-404@from-internal-00000000;2", "DSTRING=SIP/404&") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [dstring@macro-dial-one:10] Set("Local/FMPR-404@from-internal-00000000;2", "ITER=2") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [dstring@macro-dial-one:11] GotoIf("Local/FMPR-404@from-internal-00000000;2", "0?begin") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [dstring@macro-dial-one:12] Set("Local/FMPR-404@from-internal-00000000;2", "DSTRING=SIP/404") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [dstring@macro-dial-one:13] Return("Local/FMPR-404@from-internal-00000000;2", "") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [s@macro-dial-one:27] GotoIf("Local/FMPR-404@from-internal-00000000;2", "0?nodial") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [s@macro-dial-one:28] GotoIf("Local/FMPR-404@from-internal-00000000;2", "0?skiptrace") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [s@macro-dial-one:29] GosubIf("Local/FMPR-404@from-internal-00000000;2", "1?ctset,1():ctclear,1()") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [ctset@macro-dial-one:1] Set("Local/FMPR-404@from-internal-00000000;2", "DB(CALLTRACE/404)=78124051501") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [ctset@macro-dial-one:2] Return("Local/FMPR-404@from-internal-00000000;2", "") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [s@macro-dial-one:30] Set("Local/FMPR-404@from-internal-00000000;2", "DOPTIONS=trM(auto-blkvm)") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [s@macro-dial-one:31] ExecIf("Local/FMPR-404@from-internal-00000000;2", "0?SIPAddHeader(Alert-Info: )") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [s@macro-dial-one:32] ExecIf("Local/FMPR-404@from-internal-00000000;2", "0?SIPAddHeader()") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [s@macro-dial-one:33] ExecIf("Local/FMPR-404@from-internal-00000000;2", "0?Set(CHANNEL(musicclass)=)") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [s@macro-dial-one:34] GosubIf("Local/FMPR-404@from-internal-00000000;2", "0?qwait,1()") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [s@macro-dial-one:35] Set("Local/FMPR-404@from-internal-00000000;2", "CWIGNORE=") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [s@macro-dial-one:36] Set("Local/FMPR-404@from-internal-00000000;2", "KEEPCID=TRUE") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [s@macro-dial-one:37] GotoIf("Local/FMPR-404@from-internal-00000000;2", "0?usegoto,1") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [s@macro-dial-one:38] GotoIf("Local/FMPR-404@from-internal-00000000;2", "1?godial") in new stack [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Goto (macro-dial-one,s,42) [2013-02-01 15:50:47] VERBOSE[2030] pbx.c: -- Executing [s@macro-dial-one:42] Dial("Local/FMPR-404@from-internal-00000000;2", "SIP/404,2,trM(auto-blkvm)") in new stack [2013-02-01 15:50:47] VERBOSE[2027] pbx.c: -- Executing [s@macro-dial:7] Dial("SIP/78123855909-00000001", "Local/FMPR-404@from-internal&Local/FMGL-79213336286#@from-internal,62,trIM(auto-blkvm)") in new stack [2013-02-01 15:50:47] VERBOSE[2030] netsock2.c: == Using SIP RTP TOS bits 184 [2013-02-01 15:50:47] VERBOSE[2030] netsock2.c: == Using SIP RTP CoS mark 5 [2013-02-01 15:50:47] VERBOSE[2027] appdial.c: -- Called Local/FMPR-404@from-internal [2013-02-01 15:50:47] VERBOSE[2027] appdial.c: -- Called Local/FMGL-79213336286#@from-internal [2013-02-01 15:50:47] VERBOSE[2030] appdial.c: -- Called SIP/404 [2013-02-01 15:50:47] VERBOSE[2026] appdial.c: -- Local/FMPR-404@from-internal-00000000;1 is ringing [2013-02-01 15:50:47] VERBOSE[2026] appdial.c: -- Connected line update to SIP/78123855909-00000000 prevented. [2013-02-01 15:50:47] VERBOSE[2026] appdial.c: -- Connected line update to SIP/78123855909-00000000 prevented. [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [FMPR-404@from-internal:1] NoCDR("Local/FMPR-404@from-internal-00000002;2", "") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [FMPR-404@from-internal:2] Set("Local/FMPR-404@from-internal-00000002;2", "RingGroupMethod=") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [FMPR-404@from-internal:3] Set("Local/FMPR-404@from-internal-00000002;2", "USECONFIRMATION=") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [FMPR-404@from-internal:4] Set("Local/FMPR-404@from-internal-00000002;2", "RINGGROUPINDEX=") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [FMPR-404@from-internal:5] Macro("Local/FMPR-404@from-internal-00000002;2", "simple-dial,404,2") in new stack [2013-02-01 15:50:47] VERBOSE[2033] pbx.c: -- Executing [FMGL-79213336286#@from-internal:1] NoCDR("Local/FMGL-79213336286#@from-internal-00000003;2", "") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-simple-dial:1] Set("Local/FMPR-404@from-internal-00000002;2", "EXTTOCALL=404") in new stack [2013-02-01 15:50:47] VERBOSE[2033] pbx.c: -- Executing [FMGL-79213336286#@from-internal:2] Set("Local/FMGL-79213336286#@from-internal-00000003;2", "ENDLOOP=1359719449") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-simple-dial:2] Set("Local/FMPR-404@from-internal-00000002;2", "RT=2") in new stack [2013-02-01 15:50:47] VERBOSE[2033] pbx.c: -- Executing [FMGL-79213336286#@from-internal:3] GotoIf("Local/FMGL-79213336286#@from-internal-00000003;2", "0?dodnd") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-simple-dial:3] Set("Local/FMPR-404@from-internal-00000002;2", "CFUEXT=") in new stack [2013-02-01 15:50:47] VERBOSE[2033] pbx.c: -- Executing [FMGL-79213336286#@from-internal:4] Wait("Local/FMGL-79213336286#@from-internal-00000003;2", "1") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-simple-dial:4] Set("Local/FMPR-404@from-internal-00000002;2", "CFBEXT=") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-simple-dial:5] Set("Local/FMPR-404@from-internal-00000002;2", "CWITMP=") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-simple-dial:6] Macro("Local/FMPR-404@from-internal-00000002;2", "dial-one,2,tr,404") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-dial-one:1] Set("Local/FMPR-404@from-internal-00000002;2", "DEXTEN=404") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-dial-one:2] Set("Local/FMPR-404@from-internal-00000002;2", "DIALSTATUSCW=") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-dial-one:3] GosubIf("Local/FMPR-404@from-internal-00000002;2", "0?screen,1()") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-dial-one:4] GosubIf("Local/FMPR-404@from-internal-00000002;2", "0?cf,1()") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-dial-one:5] GotoIf("Local/FMPR-404@from-internal-00000002;2", "1?skip1") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Goto (macro-dial-one,s,8) [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-dial-one:8] GotoIf("Local/FMPR-404@from-internal-00000002;2", "0?nodial") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-dial-one:9] GotoIf("Local/FMPR-404@from-internal-00000002;2", "0?continue") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-dial-one:10] Set("Local/FMPR-404@from-internal-00000002;2", "EXTHASCW=ENABLED") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-dial-one:11] GotoIf("Local/FMPR-404@from-internal-00000002;2", "0?next1:cwinusebusy") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Goto (macro-dial-one,s,23) [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-dial-one:23] GotoIf("Local/FMPR-404@from-internal-00000002;2", "1?next3:continue") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Goto (macro-dial-one,s,24) [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-dial-one:24] ExecIf("Local/FMPR-404@from-internal-00000002;2", "1?Set(DIALSTATUSCW=BUSY)") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-dial-one:25] GotoIf("Local/FMPR-404@from-internal-00000002;2", "0?nodial") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-dial-one:26] GosubIf("Local/FMPR-404@from-internal-00000002;2", "1?dstring,1():dlocal,1()") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [dstring@macro-dial-one:1] Set("Local/FMPR-404@from-internal-00000002;2", "DSTRING=") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [dstring@macro-dial-one:2] Set("Local/FMPR-404@from-internal-00000002;2", "DEVICES=404") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [dstring@macro-dial-one:3] ExecIf("Local/FMPR-404@from-internal-00000002;2", "0?Return()") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [dstring@macro-dial-one:4] ExecIf("Local/FMPR-404@from-internal-00000002;2", "0?Set(DEVICES=04)") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [dstring@macro-dial-one:5] Set("Local/FMPR-404@from-internal-00000002;2", "LOOPCNT=1") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [dstring@macro-dial-one:6] Set("Local/FMPR-404@from-internal-00000002;2", "ITER=1") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [dstring@macro-dial-one:7] Set("Local/FMPR-404@from-internal-00000002;2", "THISDIAL=SIP/404") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [dstring@macro-dial-one:8] GosubIf("Local/FMPR-404@from-internal-00000002;2", "1?zap2dahdi,1()") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [zap2dahdi@macro-dial-one:1] ExecIf("Local/FMPR-404@from-internal-00000002;2", "0?Return()") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [zap2dahdi@macro-dial-one:2] Set("Local/FMPR-404@from-internal-00000002;2", "NEWDIAL=") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [zap2dahdi@macro-dial-one:3] Set("Local/FMPR-404@from-internal-00000002;2", "LOOPCNT2=1") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [zap2dahdi@macro-dial-one:4] Set("Local/FMPR-404@from-internal-00000002;2", "ITER2=1") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [zap2dahdi@macro-dial-one:5] Set("Local/FMPR-404@from-internal-00000002;2", "THISPART2=SIP/404") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [zap2dahdi@macro-dial-one:6] ExecIf("Local/FMPR-404@from-internal-00000002;2", "0?Set(THISPART2=DAHDI/404)") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [zap2dahdi@macro-dial-one:7] Set("Local/FMPR-404@from-internal-00000002;2", "NEWDIAL=SIP/404&") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [zap2dahdi@macro-dial-one:8] Set("Local/FMPR-404@from-internal-00000002;2", "ITER2=2") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [zap2dahdi@macro-dial-one:9] GotoIf("Local/FMPR-404@from-internal-00000002;2", "0?begin2") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [zap2dahdi@macro-dial-one:10] Set("Local/FMPR-404@from-internal-00000002;2", "THISDIAL=SIP/404") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [zap2dahdi@macro-dial-one:11] Return("Local/FMPR-404@from-internal-00000002;2", "") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [dstring@macro-dial-one:9] Set("Local/FMPR-404@from-internal-00000002;2", "DSTRING=SIP/404&") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [dstring@macro-dial-one:10] Set("Local/FMPR-404@from-internal-00000002;2", "ITER=2") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [dstring@macro-dial-one:11] GotoIf("Local/FMPR-404@from-internal-00000002;2", "0?begin") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [dstring@macro-dial-one:12] Set("Local/FMPR-404@from-internal-00000002;2", "DSTRING=SIP/404") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [dstring@macro-dial-one:13] Return("Local/FMPR-404@from-internal-00000002;2", "") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-dial-one:27] GotoIf("Local/FMPR-404@from-internal-00000002;2", "0?nodial") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-dial-one:28] GotoIf("Local/FMPR-404@from-internal-00000002;2", "0?skiptrace") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-dial-one:29] GosubIf("Local/FMPR-404@from-internal-00000002;2", "1?ctset,1():ctclear,1()") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [ctset@macro-dial-one:1] Set("Local/FMPR-404@from-internal-00000002;2", "DB(CALLTRACE/404)=78124051501") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [ctset@macro-dial-one:2] Return("Local/FMPR-404@from-internal-00000002;2", "") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-dial-one:30] Set("Local/FMPR-404@from-internal-00000002;2", "DOPTIONS=trM(auto-blkvm)") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-dial-one:31] ExecIf("Local/FMPR-404@from-internal-00000002;2", "0?SIPAddHeader(Alert-Info: )") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-dial-one:32] ExecIf("Local/FMPR-404@from-internal-00000002;2", "0?SIPAddHeader()") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-dial-one:33] ExecIf("Local/FMPR-404@from-internal-00000002;2", "0?Set(CHANNEL(musicclass)=)") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-dial-one:34] GosubIf("Local/FMPR-404@from-internal-00000002;2", "0?qwait,1()") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-dial-one:35] Set("Local/FMPR-404@from-internal-00000002;2", "CWIGNORE=") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-dial-one:36] Set("Local/FMPR-404@from-internal-00000002;2", "KEEPCID=TRUE") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-dial-one:37] GotoIf("Local/FMPR-404@from-internal-00000002;2", "0?usegoto,1") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-dial-one:38] GotoIf("Local/FMPR-404@from-internal-00000002;2", "1?godial") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Goto (macro-dial-one,s,42) [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-dial-one:42] Dial("Local/FMPR-404@from-internal-00000002;2", "SIP/404,2,trM(auto-blkvm)") in new stack [2013-02-01 15:50:47] VERBOSE[2032] netsock2.c: == Using SIP RTP TOS bits 184 [2013-02-01 15:50:47] VERBOSE[2032] netsock2.c: == Using SIP RTP CoS mark 5 [2013-02-01 15:50:47] VERBOSE[2032] appdial.c: -- Called SIP/404 [2013-02-01 15:50:47] VERBOSE[2027] appdial.c: -- Local/FMPR-404@from-internal-00000002;1 is ringing [2013-02-01 15:50:47] VERBOSE[2027] appdial.c: -- Connected line update to SIP/78123855909-00000001 prevented. [2013-02-01 15:50:47] VERBOSE[2027] appdial.c: -- Connected line update to SIP/78123855909-00000001 prevented. [2013-02-01 15:50:47] VERBOSE[2030] appdial.c: -- SIP/404-00000002 is ringing [2013-02-01 15:50:47] VERBOSE[2026] appdial.c: -- Local/FMPR-404@from-internal-00000000;1 is ringing [2013-02-01 15:50:47] VERBOSE[1650] chansip.c: -- Got SIP response 486 "Busy here" back from 192.168.0.52:5060 [2013-02-01 15:50:47] VERBOSE[2032] appdial.c: -- SIP/404-00000003 is busy [2013-02-01 15:50:47] VERBOSE[2032] appdial.c: == Everyone is busy/congested at this time (1:1/0/0) [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-dial-one:43] ExecIf("Local/FMPR-404@from-internal-00000002;2", "1?Set(DIALSTATUS=BUSY)") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-dial-one:44] GosubIf("Local/FMPR-404@from-internal-00000002;2", "0?s-BUSY,1()") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-dial-one:45] MacroExit("Local/FMPR-404@from-internal-00000002;2", "") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-simple-dial:7] Set("Local/FMPR-404@from-internal-00000002;2", "CWIGNORE=") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-simple-dial:8] Set("Local/FMPR-404@from-internal-00000002;2", "PRDIALSTATUS=BUSY") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-simple-dial:9] GosubIf("Local/FMPR-404@from-internal-00000002;2", "0?docfu,1()") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-simple-dial:10] GosubIf("Local/FMPR-404@from-internal-00000002;2", "0?docfb,1()") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-simple-dial:11] Set("Local/FMPR-404@from-internal-00000002;2", "DIALSTATUS=BUSY") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s@macro-simple-dial:12] Goto("Local/FMPR-404@from-internal-00000002;2", "s-BUSY,1") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Goto (macro-simple-dial,s-BUSY,1) [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [s-BUSY@macro-simple-dial:1] NoOp("Local/FMPR-404@from-internal-00000002;2", "Extension is reporting s-BUSY") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [FMPR-404@from-internal:6] ExecIf("Local/FMPR-404@from-internal-00000002;2", "1?Set(SHARED(FMDND,SIP/78123855909-00000001)=DND)") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [FMPR-404@from-internal:7] Hangup("Local/FMPR-404@from-internal-00000002;2", "") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: == Spawn extension (from-internal, FMPR-404, 7) exited non-zero on 'Local/FMPR-404@from-internal-00000002;2' [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: -- Executing [h@from-internal:1] Hangup("Local/FMPR-404@from-internal-00000002;2", "") in new stack [2013-02-01 15:50:47] VERBOSE[2032] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on 'Local/FMPR-404@from-internal-00000002;2' [2013-02-01 15:50:48] VERBOSE[2031] pbx.c: -- Executing [FMGL-79213336286#@from-internal:5] GotoIf("Local/FMGL-79213336286#@from-internal-00000001;2", "1?start") in new stack [2013-02-01 15:50:48] VERBOSE[2031] pbx.c: -- Goto (from-internal,FMGL-79213336286#,3) [2013-02-01 15:50:48] VERBOSE[2031] pbx.c: -- Executing [FMGL-79213336286#@from-internal:3] GotoIf("Local/FMGL-79213336286#@from-internal-00000001;2", "0?dodnd") in new stack [2013-02-01 15:50:48] VERBOSE[2031] pbx.c: -- Executing [FMGL-79213336286#@from-internal:4] Wait("Local/FMGL-79213336286#@from-internal-00000001;2", "1") in new stack [2013-02-01 15:50:48] VERBOSE[2033] pbx.c: -- Executing [FMGL-79213336286#@from-internal:5] GotoIf("Local/FMGL-79213336286#@from-internal-00000003;2", "1?start") in new stack [2013-02-01 15:50:48] VERBOSE[2033] pbx.c: -- Goto (from-internal,FMGL-79213336286#,3) [2013-02-01 15:50:48] VERBOSE[2033] pbx.c: -- Executing [FMGL-79213336286#@from-internal:3] GotoIf("Local/FMGL-79213336286#@from-internal-00000003;2", "1?dodnd") in new stack [2013-02-01 15:50:48] VERBOSE[2033] pbx.c: -- Goto (from-internal,FMGL-79213336286#,19) [2013-02-01 15:50:48] VERBOSE[2033] pbx.c: -- Executing [FMGL-79213336286#@from-internal:19] Set("Local/FMGL-79213336286#@from-internal-00000003;2", "SHARED(FMDND,SIP/78123855909-00000001)=") in new stack [2013-02-01 15:50:48] VERBOSE[2033] pbx.c: -- Executing [FMGL-79213336286#@from-internal:20] GotoIf("Local/FMGL-79213336286#@from-internal-00000003;2", "1?dodial") in new stack [2013-02-01 15:50:48] VERBOSE[2033] pbx.c: -- Goto (from-internal,FMGL-79213336286#,7) [2013-02-01 15:50:48] VERBOSE[2033] pbx.c: -- Executing [FMGL-79213336286#@from-internal:7] Macro("Local/FMGL-79213336286#@from-internal-00000003;2", "dial,60,tr,79213336286#") in new stack [2013-02-01 15:50:48] VERBOSE[2033] pbx.c: -- Executing [s@macro-dial:1] GotoIf("Local/FMGL-79213336286#@from-internal-00000003;2", "1?dial") in new stack [2013-02-01 15:50:48] VERBOSE[2033] pbx.c: -- Goto (macro-dial,s,3) [2013-02-01 15:50:48] VERBOSE[2033] pbx.c: -- Executing [s@macro-dial:3] AGI("Local/FMGL-79213336286#@from-internal-00000003;2", "dialparties.agi") in new stack [2013-02-01 15:50:48] VERBOSE[2033] resagi.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi [2013-02-01 15:50:48] VERBOSE[2033] resagi.c: dialparties.agi: Starting New Dialparties.agi [2013-02-01 15:50:48] VERBOSE[2033] resagi.c: dialparties.agi: Caller ID name is '78124051501' number is '78124051501' [2013-02-01 15:50:48] VERBOSE[2033] resagi.c: dialparties.agi: Methodology of ring is 'ringall' [2013-02-01 15:50:48] VERBOSE[2033] resagi.c: -- dialparties.agi: Added extension 79213336286# to extension map [2013-02-01 15:50:48] VERBOSE[2033] resagi.c: -- dialparties.agi: Extension 79213336286# cf is disabled [2013-02-01 15:50:48] VERBOSE[2033] resagi.c: -- dialparties.agi: Filtered ARG3: 79213336286 [2013-02-01 15:50:48] VERBOSE[2033] resagi.c: -- <local fmgl-79213336286#@from-internal-00000003;2="">AGI Script dialparties.agi completed, returning 0 [2013-02-01 15:50:48] VERBOSE[2033] pbx.c: -- Executing [s@macro-dial:7] Dial("Local/FMGL-79213336286#@from-internal-00000003;2", "Local/79213336286@from-internal/n,60,trM(auto-blkvm)") in new stack [2013-02-01 15:50:48] VERBOSE[2033] appdial.c: -- Called Local/79213336286@from-internal/n [2013-02-01 15:50:48] VERBOSE[2027] appdial.c: -- Local/FMGL-79213336286#@from-internal-00000003;1 is ringing [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [79213336286@from-internal:1] Macro("Local/79213336286@from-internal-00000004;2", "user-callerid,LIMIT,") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-user-callerid:1] Set("Local/79213336286@from-internal-00000004;2", "AMPUSER=78124051501") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-user-callerid:2] GotoIf("Local/79213336286@from-internal-00000004;2", "0?report") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-user-callerid:3] ExecIf("Local/79213336286@from-internal-00000004;2", "1?Set(REALCALLERIDNUM=78124051501)") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-user-callerid:4] Set("Local/79213336286@from-internal-00000004;2", "AMPUSER=") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-user-callerid:5] Set("Local/79213336286@from-internal-00000004;2", "AMPUSERCIDNAME=") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-user-callerid:6] GotoIf("Local/79213336286@from-internal-00000004;2", "1?report") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Goto (macro-user-callerid,s,13) [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-user-callerid:13] GotoIf("Local/79213336286@from-internal-00000004;2", "1?continue") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Goto (macro-user-callerid,s,26) [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-user-callerid:26] Set("Local/79213336286@from-internal-00000004;2", "CALLERID(number)=78124051501") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-user-callerid:27] Set("Local/79213336286@from-internal-00000004;2", "CALLERID(name)=78124051501") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-user-callerid:28] Set("Local/79213336286@from-internal-00000004;2", "CHANNEL(language)=en") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [79213336286@from-internal:2] Set("Local/79213336286@from-internal-00000004;2", "MOHCLASS=default") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [79213336286@from-internal:3] Set("Local/79213336286@from-internal-00000004;2", "NODEST=") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [79213336286@from-internal:4] Gosub("Local/79213336286@from-internal-00000004;2", "sub-record-check,s,1(out,79213336286,)") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@sub-record-check:1] GotoIf("Local/79213336286@from-internal-00000004;2", "1?check") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Goto (sub-record-check,s,6) [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@sub-record-check:6] Set("Local/79213336286@from-internal-00000004;2", "MONFMT=wav") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@sub-record-check:7] GotoIf("Local/79213336286@from-internal-00000004;2", "1?next") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Goto (sub-record-check,s,10) [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@sub-record-check:10] ExecIf("Local/79213336286@from-internal-00000004;2", "0?Return()") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@sub-record-check:11] GotoIf("Local/79213336286@from-internal-00000004;2", "1?out,1") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Goto (sub-record-check,out,1) [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [out@sub-record-check:1] ExecIf("Local/79213336286@from-internal-00000004;2", "1?Set(RECPOLICYMODE=)") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [out@sub-record-check:2] GosubIf("Local/79213336286@from-internal-00000004;2", "0?record,1(exten,79213336286,78124051501)") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [out@sub-record-check:3] Return("Local/79213336286@from-internal-00000004;2", "") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [79213336286@from-internal:5] Macro("Local/79213336286@from-internal-00000004;2", "dialout-trunk,6,79213336286,") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-dialout-trunk:1] Set("Local/79213336286@from-internal-00000004;2", "DIALTRUNK=6") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-dialout-trunk:2] GosubIf("Local/79213336286@from-internal-00000004;2", "0?sub-pincheck,s,1()") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-dialout-trunk:3] GotoIf("Local/79213336286@from-internal-00000004;2", "0?disabletrunk,1") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-dialout-trunk:4] Set("Local/79213336286@from-internal-00000004;2", "DIALNUMBER=79213336286") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-dialout-trunk:5] Set("Local/79213336286@from-internal-00000004;2", "DIALTRUNKOPTIONS=tr") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-dialout-trunk:6] Set("Local/79213336286@from-internal-00000004;2", "OUTBOUNDGROUP=OUT6") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-dialout-trunk:7] GotoIf("Local/79213336286@from-internal-00000004;2", "1?nomax") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Goto (macro-dialout-trunk,s,9) [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-dialout-trunk:9] GotoIf("Local/79213336286@from-internal-00000004;2", "0?skipoutcid") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-dialout-trunk:10] Set("Local/79213336286@from-internal-00000004;2", "DIALTRUNKOPTIONS=") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-dialout-trunk:11] Macro("Local/79213336286@from-internal-00000004;2", "outbound-callerid,6") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-outbound-callerid:1] ExecIf("Local/79213336286@from-internal-00000004;2", "1?Set(CALLERPRES()=allowednotscreened)") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-outbound-callerid:2] ExecIf("Local/79213336286@from-internal-00000004;2", "0?Set(REALCALLERIDNUM=78124051501)") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-outbound-callerid:3] GotoIf("Local/79213336286@from-internal-00000004;2", "0?normcid") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-outbound-callerid:4] Set("Local/79213336286@from-internal-00000004;2", "USEROUTCID=78124051501") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-outbound-callerid:5] GotoIf("Local/79213336286@from-internal-00000004;2", "1?bypass") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Goto (macro-outbound-callerid,s,7) [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-outbound-callerid:7] Set("Local/79213336286@from-internal-00000004;2", "EMERGENCYCID=") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-outbound-callerid:8] Set("Local/79213336286@from-internal-00000004;2", "TRUNKOUTCID=78126434220") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-outbound-callerid:9] GotoIf("Local/79213336286@from-internal-00000004;2", "1?trunkcid") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Goto (macro-outbound-callerid,s,12) [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-outbound-callerid:12] ExecIf("Local/79213336286@from-internal-00000004;2", "1?Set(CALLERID(all)=78126434220)") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-outbound-callerid:13] ExecIf("Local/79213336286@from-internal-00000004;2", "1?Set(CALLERID(all)=78124051501)") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-outbound-callerid:14] ExecIf("Local/79213336286@from-internal-00000004;2", "0?Set(CALLERID(all)=)") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-outbound-callerid:15] ExecIf("Local/79213336286@from-internal-00000004;2", "0?Set(CALLERPRES()=prohibpassedscreen)") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-dialout-trunk:12] GosubIf("Local/79213336286@from-internal-00000004;2", "0?sub-flp-6,s,1()") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-dialout-trunk:13] Set("Local/79213336286@from-internal-00000004;2", "OUTNUM=79213336286") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-dialout-trunk:14] Set("Local/79213336286@from-internal-00000004;2", "custom=SIP/78126434220") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-dialout-trunk:15] ExecIf("Local/79213336286@from-internal-00000004;2", "0?Set(DIALTRUNKOPTIONS=M(setmusic^default))") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-dialout-trunk:16] ExecIf("Local/79213336286@from-internal-00000004;2", "0?Set(DIALTRUNKOPTIONS=M(confirm))") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-dialout-trunk:17] Macro("Local/79213336286@from-internal-00000004;2", "dialout-trunk-predial-hook,") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("Local/79213336286@from-internal-00000004;2", "") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-dialout-trunk:18] GotoIf("Local/79213336286@from-internal-00000004;2", "0?bypass,1") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-dialout-trunk:19] ExecIf("Local/79213336286@from-internal-00000004;2", "0?Set(CONNECTEDLINE(num,i)=79213336286)") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-dialout-trunk:20] ExecIf("Local/79213336286@from-internal-00000004;2", "0?Set(CONNECTEDLINE(name,i)=CID:78124051501)") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-dialout-trunk:21] GotoIf("Local/79213336286@from-internal-00000004;2", "0?customtrunk") in new stack [2013-02-01 15:50:48] VERBOSE[2035] pbx.c: -- Executing [s@macro-dialout-trunk:22] Dial("Local/79213336286@from-internal-00000004;2", "SIP/78126434220/79213336286,300,") in new stack [2013-02-01 15:50:48] VERBOSE[2035] netsock2.c: == Using SIP RTP TOS bits 184 [2013-02-01 15:50:48] VERBOSE[2035] netsock2.c: == Using SIP RTP CoS mark 5 [2013-02-01 15:50:48] VERBOSE[2035] appdial.c: -- Called SIP/78126434220/79213336286 [2013-02-01 15:50:49] VERBOSE[2031] pbx.c: -- Executing [FMGL-79213336286#@from-internal:5] GotoIf("Local/FMGL-79213336286#@from-internal-00000001;2", "0?start") in new stack [2013-02-01 15:50:49] VERBOSE[2031] pbx.c: -- Executing [FMGL-79213336286#@from-internal:6] Set("Local/FMGL-79213336286#@from-internal-00000001;2", "SHARED(FMDND,SIP/78123855909-00000000)=") in new stack [2013-02-01 15:50:49] VERBOSE[2031] pbx.c: -- Executing [FMGL-79213336286#@from-internal:7] Macro("Local/FMGL-79213336286#@from-internal-00000001;2", "dial,60,tr,79213336286#") in new stack [2013-02-01 15:50:49] VERBOSE[2031] pbx.c: -- Executing [s@macro-dial:1] GotoIf("Local/FMGL-79213336286#@from-internal-00000001;2", "1?dial") in new stack [2013-02-01 15:50:49] VERBOSE[2031] pbx.c: -- Goto (macro-dial,s,3) [2013-02-01 15:50:49] VERBOSE[2031] pbx.c: -- Executing [s@macro-dial:3] AGI("Local/FMGL-79213336286#@from-internal-00000001;2", "dialparties.agi") in new stack [2013-02-01 15:50:49] VERBOSE[2031] resagi.c: -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi [2013-02-01 15:50:49] VERBOSE[2030] appdial.c: -- Nobody picked up in 2000 ms [2013-02-01 15:50:49] VERBOSE[2030] pbx.c: -- Executing [s@macro-dial-one:43] ExecIf("Local/FMPR-404@from-internal-00000000;2", "0?Set(DIALSTATUS=)") in new stack [2013-02-01 15:50:49] VERBOSE[2030] pbx.c: -- Executing [s@macro-dial-one:44] GosubIf("Local/FMPR-404@from-internal-00000000;2", "0?s-NOANSWER,1()") in new stack [2013-02-01 15:50:49] VERBOSE[2030] pbx.c: -- Executing [s@macro-dial-one:45] MacroExit("Local/FMPR-404@from-internal-00000000;2", "") in new stack [2013-02-01 15:50:49] VERBOSE[2030] pbx.c: -- Executing [s@macro-simple-dial:7] Set("Local/FMPR-404@from-internal-00000000;2", "CWIGNORE=") in new stack [2013-02-01 15:50:49] VERBOSE[2030] pbx.c: -- Executing [s@macro-simple-dial:8] Set("Local/FMPR-404@from-internal-00000000;2", "PRDIALSTATUS=NOANSWER") in new stack [2013-02-01 15:50:49] VERBOSE[2030] pbx.c: -- Executing [s@macro-simple-dial:9] GosubIf("Local/FMPR-404@from-internal-00000000;2", "0?docfu,1()") in new stack [2013-02-01 15:50:49] VERBOSE[2030] pbx.c: -- Executing [s@macro-simple-dial:10] GosubIf("Local/FMPR-404@from-internal-00000000;2", "0?docfb,1()") in new stack [2013-02-01 15:50:49] VERBOSE[2030] pbx.c: -- Executing [s@macro-simple-dial:11] Set("Local/FMPR-404@from-internal-00000000;2", "DIALSTATUS=NOANSWER") in new stack [2013-02-01 15:50:49] VERBOSE[2030] pbx.c: -- Executing [s@macro-simple-dial:12] Goto("Local/FMPR-404@from-internal-00000000;2", "s-NOANSWER,1") in new stack [2013-02-01 15:50:49] VERBOSE[2030] pbx.c: -- Goto (macro-simple-dial,s-NOANSWER,1) [2013-02-01 15:50:49] VERBOSE[2030] pbx.c: -- Executing [s-NOANSWER@macro-simple-dial:1] NoOp("Local/FMPR-404@from-internal-00000000;2", "Extension is reporting s-NOANSWER") in new stack [2013-02-01 15:50:49] VERBOSE[2030] pbx.c: -- Executing [FMPR-404@from-internal:6] ExecIf("Local/FMPR-404@from-internal-00000000;2", "0?Set(SHARED(FMDND,SIP/78123855909-00000000)=DND)") in new stack [2013-02-01 15:50:49] VERBOSE[2030] pbx.c: -- Executing [FMPR-404@from-internal:7] Hangup("Local/FMPR-404@from-internal-00000000;2", "") in new stack [2013-02-01 15:50:49] VERBOSE[2030] pbx.c: == Spawn extension (from-internal, FMPR-404, 7) exited non-zero on 'Local/FMPR-404@from-internal-00000000;2' [2013-02-01 15:50:49] VERBOSE[2030] pbx.c: -- Executing [h@from-internal:1] Hangup("Local/FMPR-404@from-internal-00000000;2", "") in new stack [2013-02-01 15:50:49] VERBOSE[2030] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on 'Local/FMPR-404@from-internal-00000000;2' [2013-02-01 15:50:49] VERBOSE[2031] resagi.c: dialparties.agi: Starting New Dialparties.agi [2013-02-01 15:50:49] VERBOSE[2031] resagi.c: dialparties.agi: Caller ID name is '78124051501' number is '78124051501' [2013-02-01 15:50:49] VERBOSE[2031] resagi.c: dialparties.agi: Methodology of ring is 'ringall' [2013-02-01 15:50:49] VERBOSE[2031] resagi.c: -- dialparties.agi: Added extension 79213336286# to extension map [2013-02-01 15:50:49] VERBOSE[2031] resagi.c: -- dialparties.agi: Extension 79213336286# cf is disabled [2013-02-01 15:50:49] VERBOSE[2031] resagi.c: -- dialparties.agi: Filtered ARG3: 79213336286 [2013-02-01 15:50:49] VERBOSE[2031] resagi.c: -- <local fmgl-79213336286#@from-internal-00000001;2="">AGI Script dialparties.agi completed, returning 0 [2013-02-01 15:50:49] VERBOSE[2031] pbx.c: -- Executing [s@macro-dial:7] Dial("Local/FMGL-79213336286#@from-internal-00000001;2", "Local/79213336286@from-internal/n,60,trM(auto-blkvm)") in new stack [2013-02-01 15:50:49] VERBOSE[2031] appdial.c: -- Called Local/79213336286@from-internal/n [2013-02-01 15:50:49] VERBOSE[2026] appdial.c: -- Local/FMGL-79213336286#@from-internal-00000001;1 is ringing [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [79213336286@from-internal:1] Macro("Local/79213336286@from-internal-00000005;2", "user-callerid,LIMIT,") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-user-callerid:1] Set("Local/79213336286@from-internal-00000005;2", "AMPUSER=78124051501") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-user-callerid:2] GotoIf("Local/79213336286@from-internal-00000005;2", "0?report") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-user-callerid:3] ExecIf("Local/79213336286@from-internal-00000005;2", "1?Set(REALCALLERIDNUM=78124051501)") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-user-callerid:4] Set("Local/79213336286@from-internal-00000005;2", "AMPUSER=") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-user-callerid:5] Set("Local/79213336286@from-internal-00000005;2", "AMPUSERCIDNAME=") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-user-callerid:6] GotoIf("Local/79213336286@from-internal-00000005;2", "1?report") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Goto (macro-user-callerid,s,13) [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-user-callerid:13] GotoIf("Local/79213336286@from-internal-00000005;2", "1?continue") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Goto (macro-user-callerid,s,26) [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-user-callerid:26] Set("Local/79213336286@from-internal-00000005;2", "CALLERID(number)=78124051501") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-user-callerid:27] Set("Local/79213336286@from-internal-00000005;2", "CALLERID(name)=78124051501") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-user-callerid:28] Set("Local/79213336286@from-internal-00000005;2", "CHANNEL(language)=en") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [79213336286@from-internal:2] Set("Local/79213336286@from-internal-00000005;2", "MOHCLASS=default") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [79213336286@from-internal:3] Set("Local/79213336286@from-internal-00000005;2", "NODEST=") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [79213336286@from-internal:4] Gosub("Local/79213336286@from-internal-00000005;2", "sub-record-check,s,1(out,79213336286,)") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@sub-record-check:1] GotoIf("Local/79213336286@from-internal-00000005;2", "1?check") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Goto (sub-record-check,s,6) [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@sub-record-check:6] Set("Local/79213336286@from-internal-00000005;2", "MONFMT=wav") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@sub-record-check:7] GotoIf("Local/79213336286@from-internal-00000005;2", "1?next") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Goto (sub-record-check,s,10) [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@sub-record-check:10] ExecIf("Local/79213336286@from-internal-00000005;2", "0?Return()") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@sub-record-check:11] GotoIf("Local/79213336286@from-internal-00000005;2", "1?out,1") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Goto (sub-record-check,out,1) [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [out@sub-record-check:1] ExecIf("Local/79213336286@from-internal-00000005;2", "1?Set(RECPOLICYMODE=)") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [out@sub-record-check:2] GosubIf("Local/79213336286@from-internal-00000005;2", "0?record,1(exten,79213336286,78124051501)") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [out@sub-record-check:3] Return("Local/79213336286@from-internal-00000005;2", "") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [79213336286@from-internal:5] Macro("Local/79213336286@from-internal-00000005;2", "dialout-trunk,6,79213336286,") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-dialout-trunk:1] Set("Local/79213336286@from-internal-00000005;2", "DIALTRUNK=6") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-dialout-trunk:2] GosubIf("Local/79213336286@from-internal-00000005;2", "0?sub-pincheck,s,1()") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-dialout-trunk:3] GotoIf("Local/79213336286@from-internal-00000005;2", "0?disabletrunk,1") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-dialout-trunk:4] Set("Local/79213336286@from-internal-00000005;2", "DIALNUMBER=79213336286") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-dialout-trunk:5] Set("Local/79213336286@from-internal-00000005;2", "DIALTRUNKOPTIONS=tr") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-dialout-trunk:6] Set("Local/79213336286@from-internal-00000005;2", "OUTBOUNDGROUP=OUT6") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-dialout-trunk:7] GotoIf("Local/79213336286@from-internal-00000005;2", "1?nomax") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Goto (macro-dialout-trunk,s,9) [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-dialout-trunk:9] GotoIf("Local/79213336286@from-internal-00000005;2", "0?skipoutcid") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-dialout-trunk:10] Set("Local/79213336286@from-internal-00000005;2", "DIALTRUNKOPTIONS=") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-dialout-trunk:11] Macro("Local/79213336286@from-internal-00000005;2", "outbound-callerid,6") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-outbound-callerid:1] ExecIf("Local/79213336286@from-internal-00000005;2", "1?Set(CALLERPRES()=allowednotscreened)") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-outbound-callerid:2] ExecIf("Local/79213336286@from-internal-00000005;2", "0?Set(REALCALLERIDNUM=78124051501)") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-outbound-callerid:3] GotoIf("Local/79213336286@from-internal-00000005;2", "0?normcid") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-outbound-callerid:4] Set("Local/79213336286@from-internal-00000005;2", "USEROUTCID=78124051501") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-outbound-callerid:5] GotoIf("Local/79213336286@from-internal-00000005;2", "1?bypass") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Goto (macro-outbound-callerid,s,7) [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-outbound-callerid:7] Set("Local/79213336286@from-internal-00000005;2", "EMERGENCYCID=") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-outbound-callerid:8] Set("Local/79213336286@from-internal-00000005;2", "TRUNKOUTCID=78126434220") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-outbound-callerid:9] GotoIf("Local/79213336286@from-internal-00000005;2", "1?trunkcid") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Goto (macro-outbound-callerid,s,12) [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-outbound-callerid:12] ExecIf("Local/79213336286@from-internal-00000005;2", "1?Set(CALLERID(all)=78126434220)") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-outbound-callerid:13] ExecIf("Local/79213336286@from-internal-00000005;2", "1?Set(CALLERID(all)=78124051501)") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-outbound-callerid:14] ExecIf("Local/79213336286@from-internal-00000005;2", "0?Set(CALLERID(all)=)") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-outbound-callerid:15] ExecIf("Local/79213336286@from-internal-00000005;2", "0?Set(CALLERPRES()=prohibpassedscreen)") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-dialout-trunk:12] GosubIf("Local/79213336286@from-internal-00000005;2", "0?sub-flp-6,s,1()") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-dialout-trunk:13] Set("Local/79213336286@from-internal-00000005;2", "OUTNUM=79213336286") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-dialout-trunk:14] Set("Local/79213336286@from-internal-00000005;2", "custom=SIP/78126434220") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-dialout-trunk:15] ExecIf("Local/79213336286@from-internal-00000005;2", "0?Set(DIALTRUNKOPTIONS=M(setmusic^default))") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-dialout-trunk:16] ExecIf("Local/79213336286@from-internal-00000005;2", "0?Set(DIALTRUNKOPTIONS=M(confirm))") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-dialout-trunk:17] Macro("Local/79213336286@from-internal-00000005;2", "dialout-trunk-predial-hook,") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("Local/79213336286@from-internal-00000005;2", "") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-dialout-trunk:18] GotoIf("Local/79213336286@from-internal-00000005;2", "0?bypass,1") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-dialout-trunk:19] ExecIf("Local/79213336286@from-internal-00000005;2", "0?Set(CONNECTEDLINE(num,i)=79213336286)") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-dialout-trunk:20] ExecIf("Local/79213336286@from-internal-00000005;2", "0?Set(CONNECTEDLINE(name,i)=CID:78124051501)") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-dialout-trunk:21] GotoIf("Local/79213336286@from-internal-00000005;2", "0?customtrunk") in new stack [2013-02-01 15:50:49] VERBOSE[2037] pbx.c: -- Executing [s@macro-dialout-trunk:22] Dial("Local/79213336286@from-internal-00000005;2", "SIP/78126434220/79213336286,300,") in new stack [2013-02-01 15:50:49] VERBOSE[2037] netsock2.c: == Using SIP RTP TOS bits 184 [2013-02-01 15:50:49] VERBOSE[2037] netsock2.c: == Using SIP RTP CoS mark 5 [2013-02-01 15:50:49] VERBOSE[2037] appdial.c: -- Called SIP/78126434220/79213336286 [2013-02-01 15:50:51] VERBOSE[1650] chansip.c: -- Got SIP response 486 "Busy Here" back from 81.88.80.235:5060 [2013-02-01 15:50:51] VERBOSE[2037] appdial.c: -- SIP/78126434220-00000005 is busy [2013-02-01 15:50:51] VERBOSE[2037] appdial.c: == Everyone is busy/congested at this time (1:1/0/0) [2013-02-01 15:50:51] VERBOSE[2037] pbx.c: -- Executing [s@macro-dialout-trunk:23] NoOp("Local/79213336286@from-internal-00000005;2", "Dial failed for some reason with DIALSTATUS = BUSY and HANGUPCAUSE = 17") in new stack [2013-02-01 15:50:51] VERBOSE[2037] pbx.c: -- Executing [s@macro-dialout-trunk:24] Goto("Local/79213336286@from-internal-00000005;2", "s-BUSY,1") in new stack [2013-02-01 15:50:51] VERBOSE[2037] pbx.c: -- Goto (macro-dialout-trunk,s-BUSY,1) [2013-02-01 15:50:51] VERBOSE[2037] pbx.c: -- Executing [s-BUSY@macro-dialout-trunk:1] NoOp("Local/79213336286@from-internal-00000005;2", "Dial failed due to trunk reporting BUSY - giving up") in new stack [2013-02-01 15:50:51] VERBOSE[2037] pbx.c: -- Executing [s-BUSY@macro-dialout-trunk:2] PlayTones("Local/79213336286@from-internal-00000005;2", "busy") in new stack [2013-02-01 15:50:51] VERBOSE[2037] pbx.c: -- Executing [s-BUSY@macro-dialout-trunk:3] Busy("Local/79213336286@from-internal-00000005;2", "20") in new stack [2013-02-01 15:50:51] VERBOSE[2031] appdial.c: -- Local/79213336286@from-internal-00000005;1 is busy [2013-02-01 15:50:51] VERBOSE[2031] appdial.c: == Everyone is busy/congested at this time (1:1/0/0) [2013-02-01 15:50:51] VERBOSE[2031] pbx.c: -- Executing [s@macro-dial:8] Set("Local/FMGL-79213336286#@from-internal-00000001;2", "DIALSTATUS=BUSY") in new stack [2013-02-01 15:50:51] VERBOSE[2031] pbx.c: -- Executing [s@macro-dial:9] GosubIf("Local/FMGL-79213336286#@from-internal-00000001;2", "0?BUSY,1") in new stack [2013-02-01 15:50:51] VERBOSE[2031] pbx.c: -- Executing [FMGL-79213336286#@from-internal:8] Hangup("Local/FMGL-79213336286#@from-internal-00000001;2", "") in new stack [2013-02-01 15:50:51] VERBOSE[2031] pbx.c: == Spawn extension (from-internal, FMGL-79213336286#, 8) exited non-zero on 'Local/FMGL-79213336286#@from-internal-00000001;2' [2013-02-01 15:50:51] VERBOSE[2031] pbx.c: -- Executing [h@from-internal:1] Hangup("Local/FMGL-79213336286#@from-internal-00000001;2", "") in new stack [2013-02-01 15:50:51] VERBOSE[2031] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on 'Local/FMGL-79213336286#@from-internal-00000001;2' [2013-02-01 15:50:51] VERBOSE[2026] appdial.c: -- No one is available to answer at this time (2:0/0/0) [2013-02-01 15:50:51] VERBOSE[2026] pbx.c: -- Executing [s@macro-dial:8] Set("SIP/78123855909-00000000", "DIALSTATUS=NOANSWER") in new stack [2013-02-01 15:50:51] VERBOSE[2037] appmacro.c: == Spawn extension (macro-dialout-trunk, s-BUSY, 3) exited non-zero on 'Local/79213336286@from-internal-00000005;2' in macro 'dialout-trunk' [2013-02-01 15:50:51] VERBOSE[2026] pbx.c: -- Executing [s@macro-dial:9] GosubIf("SIP/78123855909-00000000", "0?NOANSWER,1") in new stack [2013-02-01 15:50:51] VERBOSE[2037] pbx.c: == Spawn extension (from-internal, 79213336286, 5) exited non-zero on 'Local/79213336286@from-internal-00000005;2' [2013-02-01 15:50:51] VERBOSE[2037] pbx.c: -- Executing [h@from-internal:1] Hangup("Local/79213336286@from-internal-00000005;2", "") in new stack [2013-02-01 15:50:51] VERBOSE[2037] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on 'Local/79213336286@from-internal-00000005;2' [2013-02-01 15:50:51] VERBOSE[2026] pbx.c: -- Executing [404@from-did-direct:22] Goto("SIP/78123855909-00000000", "nextstep") in new stack [2013-02-01 15:50:51] VERBOSE[2026] pbx.c: -- Goto (from-did-direct,404,24) [2013-02-01 15:50:51] VERBOSE[2026] pbx.c: -- Executing [404@from-did-direct:24] Set("SIP/78123855909-00000000", "RingGroupMethod=") in new stack [2013-02-01 15:50:51] VERBOSE[2026] pbx.c: -- Executing [404@from-did-direct:25] GotoIf("SIP/78123855909-00000000", "0?nodest") in new stack [2013-02-01 15:50:51] VERBOSE[2026] pbx.c: -- Executing [404@from-did-direct:26] Set("SIP/78123855909-00000000", "NODEST=") in new stack [2013-02-01 15:50:51] VERBOSE[2026] pbx.c: -- Executing [404@from-did-direct:27] Set("SIP/78123855909-00000000", "PICKUPMARK=") in new stack [2013-02-01 15:50:51] VERBOSE[2026] pbx.c: -- Executing [404@from-did-direct:28] Macro("SIP/78123855909-00000000", "blkvm-clr,") in new stack [2013-02-01 15:50:51] VERBOSE[2026] pbx.c: -- Executing [s@macro-blkvm-clr:1] Set("SIP/78123855909-00000000", "SHARED(BLKVM,SIP/78123855909-00000000)=") in new stack [2013-02-01 15:50:51] VERBOSE[2026] pbx.c: -- Executing [s@macro-blkvm-clr:2] Set("SIP/78123855909-00000000", "GOSUBRETVAL=") in new stack [2013-02-01 15:50:51] VERBOSE[2026] pbx.c: -- Executing [s@macro-blkvm-clr:3] MacroExit("SIP/78123855909-00000000", "") in new stack [2013-02-01 15:50:51] VERBOSE[2026] pbx.c: -- Executing [404@from-did-direct:29] Set("SIP/78123855909-00000000", "DIALSTATUS=NOANSWER") in new stack [2013-02-01 15:50:51] VERBOSE[2026] pbx.c: -- Executing [404@from-did-direct:30] Goto("SIP/78123855909-00000000", "ext-local,404,dest") in new stack [2013-02-01 15:50:51] VERBOSE[2026] pbx.c: -- Goto (ext-local,404,3) [2013-02-01 15:50:51] VERBOSE[2026] pbx.c: -- Executing [404@ext-local:3] Set("SIP/78123855909-00000000", "PICKUPMARK=") in new stack [2013-02-01 15:50:51] VERBOSE[2026] pbx.c: -- Executing [404@ext-local:4] Goto("SIP/78123855909-00000000", ",return,1") in new stack [2013-02-01 15:50:51] VERBOSE[2026] pbx.c: -- Goto (ext-local,return,1) [2013-02-01 15:50:51] WARNING[2026] pbx.c: Channel 'SIP/78123855909-00000000' sent into invalid extension 'return' in context 'ext-local', but no invalid handler [2013-02-01 15:50:51] VERBOSE[2026] pbx.c: -- Executing [h@ext-local:1] Macro("SIP/78123855909-00000000", "hangupcall,") in new stack [2013-02-01 15:50:51] VERBOSE[2026] pbx.c: -- Executing [s@macro-hangupcall:1] GotoIf("SIP/78123855909-00000000", "1?theend") in new stack [2013-02-01 15:50:51] VERBOSE[2026] pbx.c: -- Goto (macro-hangupcall,s,3) [2013-02-01 15:50:51] VERBOSE[2026] pbx.c: -- Executing [s@macro-hangupcall:3] ExecIf("SIP/78123855909-00000000", "0?Set(CDR(recordingfile)=)") in new stack [2013-02-01 15:50:51] VERBOSE[2026] pbx.c: -- Executing [s@macro-hangupcall:4] Hangup("SIP/78123855909-00000000", "") in new stack [2013-02-01 15:50:51] VERBOSE[2026] appmacro.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/78123855909-00000000' in macro 'hangupcall' [2013-02-01 15:50:51] VERBOSE[2026] pbx.c: == Spawn extension (ext-local, h, 1) exited non-zero on 'SIP/78123855909-00000000' [2013-02-01 15:50:51] VERBOSE[2027] appmacro.c: == Spawn extension (macro-dial, s, 7) exited non-zero on 'SIP/78123855909-00000001' in macro 'dial' [2013-02-01 15:50:51] VERBOSE[2033] appmacro.c: == Spawn extension (macro-dial, s, 7) exited non-zero on 'Local/FMGL-79213336286#@from-internal-00000003;2' in macro 'dial' [2013-02-01 15:50:51] VERBOSE[2027] pbx.c: == Spawn extension (from-did-direct, 404, 21) exited non-zero on 'SIP/78123855909-00000001' [2013-02-01 15:50:51] VERBOSE[2033] pbx.c: == Spawn extension (from-internal, FMGL-79213336286#, 7) exited non-zero on 'Local/FMGL-79213336286#@from-internal-00000003;2' [2013-02-01 15:50:51] VERBOSE[2033] pbx.c: -- Executing [h@from-internal:1] Hangup("Local/FMGL-79213336286#@from-internal-00000003;2", "") in new stack [2013-02-01 15:50:51] VERBOSE[2027] pbx.c: -- Executing [h@from-did-direct:1] Macro("SIP/78123855909-00000001", "hangupcall,") in new stack [2013-02-01 15:50:51] VERBOSE[2027] pbx.c: -- Executing [s@macro-hangupcall:1] GotoIf("SIP/78123855909-00000001", "1?theend") in new stack [2013-02-01 15:50:51] VERBOSE[2027] pbx.c: -- Goto (macro-hangupcall,s,3) [2013-02-01 15:50:51] VERBOSE[2035] appmacro.c: == Spawn extension (macro-dialout-trunk, s, 22) exited non-zero on 'Local/79213336286@from-internal-00000004;2' in macro 'dialout-trunk' [2013-02-01 15:50:51] VERBOSE[2027] pbx.c: -- Executing [s@macro-hangupcall:3] ExecIf("SIP/78123855909-00000001", "0?Set(CDR(recordingfile)=)") in new stack [2013-02-01 15:50:51] VERBOSE[2035] pbx.c: == Spawn extension (from-internal, 79213336286, 5) exited non-zero on 'Local/79213336286@from-internal-00000004;2' [2013-02-01 15:50:51] VERBOSE[2033] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on 'Local/FMGL-79213336286#@from-internal-00000003;2' [2013-02-01 15:50:51] VERBOSE[2035] pbx.c: -- Executing [h@from-internal:1] Hangup("Local/79213336286@from-internal-00000004;2", "") in new stack [2013-02-01 15:50:51] VERBOSE[2027] pbx.c: -- Executing [s@macro-hangupcall:4] Hangup("SIP/78123855909-00000001", "") in new stack [2013-02-01 15:50:51] VERBOSE[2027] appmacro.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/78123855909-00000001' in macro 'hangupcall' [2013-02-01 15:50:51] VERBOSE[2027] pbx.c: == Spawn extension (from-did-direct, h, 1) exited non-zero on 'SIP/78123855909-00000001' [2013-02-01 15:50:51] VERBOSE[2035] pbx.c: == Spawn extension (from-internal, h, 1) exited non-zero on 'Local/79213336286@from-internal-00000004;2'