asterisk.ru

Ситуация такая:
Звонки приходят по SIP от провайдера и сразу попадают в очередь в которой нет ни одного агента и сидят там, слушают музыку. Оттуда они с помощью AMI переводятся в IVR

Action: Redirect
Channel: 1375772491.147
Context: ivr-9
Exten: s
Priority: 1

В IVR при нажатии кнопки 1 они переводятся на внутреннего абонента 300. Если абонент доступен, то они нормально общаются. Если абонент не доступен то должна заработать голосовая почта, но вместо этого входящий звонок просто сбрасывается.

При этом, если настроить чтобы входящие звонки приходили сразу в IVR, то при нажатии кнопки 1 они переводятся на внутреннего абонента 300. Если абонент доступен, то они нормально общаются. Если абонент не доступен, то голосовая почта нормально отрабатывает.

Резюме: если в обработке звонка поучаствовал AMI, то вместо попадания в голосовую почту звонок сбрасывается. Если звонок прошел без переключения с помощью AMI то голосовая почто работает.

asterisk 11.5

личный блог завели?

где логи?

Лог где звонок сбросился
[2013-08-06 11:19:44] VERBOSE[1957][C-00000034] netsock2.c: == Using SIP RTP TOS bits 184
[2013-08-06 11:19:44] VERBOSE[1957][C-00000034] netsock2.c: == Using SIP RTP CoS mark 5
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [672117@from-trunk-sip-peterstar:1] Set("SIP/peterstar-00000055", "GROUP()=OUT_3") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [672117@from-trunk-sip-peterstar:2] Goto("SIP/peterstar-00000055", "from-trunk,672117,1") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Goto (from-trunk,672117,1)
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [672117@from-trunk:1] NoOp("SIP/peterstar-00000055", "Catch-All DID Match - Found 672117 - You probably want a DID for this.") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [672117@from-trunk:2] Goto("SIP/peterstar-00000055", "ext-did,s,1") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Goto (ext-did,s,1)
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@ext-did:1] ExecIf("SIP/peterstar-00000055", "1?Set(__FROM_DID=s)") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@ext-did:2] Set("SIP/peterstar-00000055", "CDR(did)=s") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@ext-did:3] ExecIf("SIP/peterstar-00000055", "1 ?Set(CALLERID(name)=8142672120)") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@ext-did:4] Set("SIP/peterstar-00000055", "__CALLINGPRES_SV=allowed_not_screened") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@ext-did:5] Set("SIP/peterstar-00000055", "CALLERPRES()=allowed_not_screened") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@ext-did:6] Goto("SIP/peterstar-00000055", "ext-callrecording,1,1") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Goto (ext-callrecording,1,1)
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [1@ext-callrecording:1] Gosub("SIP/peterstar-00000055", "sub-record-check,s,1(force,s,always)") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@sub-record-check:1] Set("SIP/peterstar-00000055", "REC_POLICY_MODE_SAVE=") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@sub-record-check:2] GotoIf("SIP/peterstar-00000055", "1?check") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Goto (sub-record-check,s,7)
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@sub-record-check:7] Set("SIP/peterstar-00000055", "__MON_FMT=wav") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@sub-record-check:8] GotoIf("SIP/peterstar-00000055", "1?next") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Goto (sub-record-check,s,11)
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@sub-record-check:11] ExecIf("SIP/peterstar-00000055", "0?Return()") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@sub-record-check:12] ExecIf("SIP/peterstar-00000055", "1?Set(__REC_POLICY_MODE=always)") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@sub-record-check:13] GotoIf("SIP/peterstar-00000055", "0?force,1") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@sub-record-check:14] Set("SIP/peterstar-00000055", "__REC_STATUS=INITIALIZED") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@sub-record-check:15] Set("SIP/peterstar-00000055", "NOW=1375773584") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@sub-record-check:16] Set("SIP/peterstar-00000055", "__DAY=06") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@sub-record-check:17] Set("SIP/peterstar-00000055", "__MONTH=08") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@sub-record-check:18] Set("SIP/peterstar-00000055", "__YEAR=2013") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@sub-record-check:19] Set("SIP/peterstar-00000055", "__TIMESTR=20130806-111944") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@sub-record-check:20] Set("SIP/peterstar-00000055", "__FROMEXTEN=unknown") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@sub-record-check:21] Set("SIP/peterstar-00000055", "__CALLFILENAME=force-s-unknown-20130806-111944-1375773584.149") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@sub-record-check:22] Goto("SIP/peterstar-00000055", "force,1") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Goto (sub-record-check,force,1)
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [force@sub-record-check:1] GosubIf("SIP/peterstar-00000055", "1?record,1(force,always,unknown)") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [record@sub-record-check:1] Set("SIP/peterstar-00000055", "AUDIOHOOK_INHERIT(MixMonitor)=yes") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [record@sub-record-check:2] MixMonitor("SIP/peterstar-00000055", "2013/08/06/force-s-unknown-20130806-111944-1375773584.149.wav,,") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [record@sub-record-check:3] Set("SIP/peterstar-00000055", "__REC_STATUS=RECORDING") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [record@sub-record-check:4] Set("SIP/peterstar-00000055", "CDR(recordingfile)=force-s-unknown-20130806-111944-1375773584.149.wav") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [record@sub-record-check:5] Return("SIP/peterstar-00000055", "") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [force@sub-record-check:2] Return("SIP/peterstar-00000055", "") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [1@ext-callrecording:2] Goto("SIP/peterstar-00000055", "ext-queues,799,1") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Goto (ext-queues,799,1)
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [799@ext-queues:1] Macro("SIP/peterstar-00000055", "user-callerid,") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:1] Set("SIP/peterstar-00000055", "TOUCH_MONITOR=1375773584.149") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:2] Set("SIP/peterstar-00000055", "AMPUSER=8142672120") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:3] GotoIf("SIP/peterstar-00000055", "0?report") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:4] ExecIf("SIP/peterstar-00000055", "1?Set(REALCALLERIDNUM=8142672120)") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:5] Set("SIP/peterstar-00000055", "AMPUSER=") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:6] Set("SIP/peterstar-00000055", "AMPUSERCIDNAME=") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:7] GotoIf("SIP/peterstar-00000055", "1?report") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Goto (macro-user-callerid,s,13)
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:13] GotoIf("SIP/peterstar-00000055", "0?continue") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:14] Set("SIP/peterstar-00000055", "__TTL=64") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:15] GotoIf("SIP/peterstar-00000055", "1?continue") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Goto (macro-user-callerid,s,26)
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:26] Set("SIP/peterstar-00000055", "CALLERID(number)=8142672120") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:27] Set("SIP/peterstar-00000055", "CALLERID(name)=8142672120") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:28] Set("SIP/peterstar-00000055", "CDR(cnum)=8142672120") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:29] Set("SIP/peterstar-00000055", "CDR(cnam)=8142672120") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:30] Set("SIP/peterstar-00000055", "CHANNEL(language)=en") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [799@ext-queues:2] Answer("SIP/peterstar-00000055", "") in new stack
[2013-08-06 11:19:44] VERBOSE[5117][C-00000034] app_mixmonitor.c: == Begin MixMonitor Recording SIP/peterstar-00000055
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [799@ext-queues:3] Macro("SIP/peterstar-00000055", "blkvm-set,reset") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-blkvm-set:1] ExecIf("SIP/peterstar-00000055", "1?Set(__BLKVM_CHANNEL=SIP/peterstar-00000055)") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-blkvm-set:2] Set("SIP/peterstar-00000055", "SHARED(BLKVM,SIP/peterstar-00000055)=TRUE") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-blkvm-set:3] Set("SIP/peterstar-00000055", "GOSUB_RETVAL=TRUE") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-blkvm-set:4] MacroExit("SIP/peterstar-00000055", "") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [799@ext-queues:4] ExecIf("SIP/peterstar-00000055", "1?Set(_DIAL_OPTIONS=TtrM(auto-blkvm))") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [799@ext-queues:5] Set("SIP/peterstar-00000055", "__NODEST=799") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [799@ext-queues:6] Set("SIP/peterstar-00000055", "QCIDPP=") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [799@ext-queues:7] ExecIf("SIP/peterstar-00000055", "0?Macro(prepend-cid, )") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [799@ext-queues:8] Set("SIP/peterstar-00000055", "QAINFO=") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [799@ext-queues:9] ExecIf("SIP/peterstar-00000055", "0?Set(__ALERT_INFO=)") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [799@ext-queues:10] Set("SIP/peterstar-00000055", "QJOINMSG=") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [799@ext-queues:11] Set("SIP/peterstar-00000055", "QMOH=") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [799@ext-queues:12] ExecIf("SIP/peterstar-00000055", "0?Set(__MOHCLASS=)") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [799@ext-queues:13] Set("SIP/peterstar-00000055", "QCANCELMISSED=C") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [799@ext-queues:14] Set("SIP/peterstar-00000055", "QRETRY=") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [799@ext-queues:15] Set("SIP/peterstar-00000055", "QOPTIONS=tC") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [799@ext-queues:16] Set("SIP/peterstar-00000055", "QAGI=") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [799@ext-queues:17] Set("SIP/peterstar-00000055", "QRULE=") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [799@ext-queues:18] Set("SIP/peterstar-00000055", "QPOSITION=") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [799@ext-queues:19] Gosub("SIP/peterstar-00000055", "sub-record-check,s,1(q,799,dontcare)") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@sub-record-check:1] Set("SIP/peterstar-00000055", "REC_POLICY_MODE_SAVE=always") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@sub-record-check:2] GotoIf("SIP/peterstar-00000055", "1?check") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Goto (sub-record-check,s,7)
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@sub-record-check:7] Set("SIP/peterstar-00000055", "__MON_FMT=wav") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@sub-record-check:8] GotoIf("SIP/peterstar-00000055", "0?next") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@sub-record-check:9] Set("SIP/peterstar-00000055", "CDR(recordingfile)=force-s-unknown-20130806-111944-1375773584.149.wav") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@sub-record-check:10] Return("SIP/peterstar-00000055", "") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [799@ext-queues:20] Set("SIP/peterstar-00000055", "__CWIGNORE=TRUE") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [799@ext-queues:21] ExecIf("SIP/peterstar-00000055", "0?Playback(, )") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [799@ext-queues:22] QueueLog("SIP/peterstar-00000055", "799,1375773584.149,NONE,DID,s") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [799@ext-queues:23] Set("SIP/peterstar-00000055", "QAANNOUNCE=") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] pbx.c: -- Executing [799@ext-queues:24] Queue("SIP/peterstar-00000055", "799,tC,,,,,,,,") in new stack
[2013-08-06 11:19:44] VERBOSE[5116][C-00000034] res_musiconhold.c: -- Started music on hold, class 'default', on SIP/peterstar-00000055
[2013-08-06 11:20:02] VERBOSE[5116][C-00000034] res_musiconhold.c: -- Stopped music on hold on SIP/peterstar-00000055
[2013-08-06 11:20:02] VERBOSE[5116][C-00000034] pbx.c: == Spawn extension (ivr-9, s, 1) exited non-zero on 'SIP/peterstar-00000055'
[2013-08-06 11:20:02] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@ivr-9:1] Set("SIP/peterstar-00000055", "TIMEOUT_LOOPCOUNT=0") in new stack
[2013-08-06 11:20:02] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@ivr-9:2] Set("SIP/peterstar-00000055", "INVALID_LOOPCOUNT=0") in new stack
[2013-08-06 11:20:02] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@ivr-9:3] Set("SIP/peterstar-00000055", "_IVR_CONTEXT_ivr-9=") in new stack
[2013-08-06 11:20:02] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@ivr-9:4] Set("SIP/peterstar-00000055", "_IVR_CONTEXT=ivr-9") in new stack
[2013-08-06 11:20:02] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@ivr-9:5] Set("SIP/peterstar-00000055", "__IVR_RETVM=") in new stack
[2013-08-06 11:20:02] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@ivr-9:6] GotoIf("SIP/peterstar-00000055", "1?skip") in new stack
[2013-08-06 11:20:02] VERBOSE[5116][C-00000034] pbx.c: -- Goto (ivr-9,s,9)
[2013-08-06 11:20:02] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@ivr-9:9] Set("SIP/peterstar-00000055", "IVR_MSG=custom/kons-its") in new stack
[2013-08-06 11:20:02] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@ivr-9:10] Set("SIP/peterstar-00000055", "TIMEOUT(digit)=3") in new stack
[2013-08-06 11:20:02] VERBOSE[5116][C-00000034] func_timeout.c: -- Digit timeout set to 3.000
[2013-08-06 11:20:02] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@ivr-9:11] ExecIf("SIP/peterstar-00000055", "1?Background(custom/kons-its)") in new stack
[2013-08-06 11:20:02] VERBOSE[5116][C-00000034] file.c: -- <SIP/peterstar-00000055> Playing 'custom/kons-its.slin' (language 'en')
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [1@ivr-9:1] Goto("SIP/peterstar-00000055", "from-did-direct,301,1") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Goto (from-did-direct,301,1)
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [301@from-did-direct:1] Set("SIP/peterstar-00000055", "__RINGTIMER=15") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [301@from-did-direct:2] Macro("SIP/peterstar-00000055", "exten-vm,301,301,0,0,0") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-exten-vm:1] Macro("SIP/peterstar-00000055", "user-callerid,") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:1] Set("SIP/peterstar-00000055", "TOUCH_MONITOR=1375773584.149") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:2] Set("SIP/peterstar-00000055", "AMPUSER=8142672120") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:3] GotoIf("SIP/peterstar-00000055", "0?report") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:4] ExecIf("SIP/peterstar-00000055", "0?Set(REALCALLERIDNUM=8142672120)") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:5] Set("SIP/peterstar-00000055", "AMPUSER=") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:6] Set("SIP/peterstar-00000055", "AMPUSERCIDNAME=") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:7] GotoIf("SIP/peterstar-00000055", "1?report") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Goto (macro-user-callerid,s,13)
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:13] GotoIf("SIP/peterstar-00000055", "0?continue") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:14] Set("SIP/peterstar-00000055", "__TTL=63") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:15] GotoIf("SIP/peterstar-00000055", "1?continue") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Goto (macro-user-callerid,s,26)
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:26] Set("SIP/peterstar-00000055", "CALLERID(number)=8142672120") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:27] Set("SIP/peterstar-00000055", "CALLERID(name)=8142672120") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:28] Set("SIP/peterstar-00000055", "CDR(cnum)=8142672120") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:29] Set("SIP/peterstar-00000055", "CDR(cnam)=8142672120") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:30] Set("SIP/peterstar-00000055", "CHANNEL(language)=en") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-exten-vm:2] Set("SIP/peterstar-00000055", "RingGroupMethod=none") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-exten-vm:3] Set("SIP/peterstar-00000055", "__EXTTOCALL=301") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-exten-vm:4] Set("SIP/peterstar-00000055", "__PICKUPMARK=301") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-exten-vm:5] Set("SIP/peterstar-00000055", "RT=15") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-exten-vm:6] ExecIf("SIP/peterstar-00000055", "0?Macro(vm,301,DIRECTDIAL,)") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-exten-vm:7] ExecIf("SIP/peterstar-00000055", "0?MacroExit()") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-exten-vm:8] Gosub("SIP/peterstar-00000055", "sub-record-check,s,1(exten,301,)") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@sub-record-check:1] Set("SIP/peterstar-00000055", "REC_POLICY_MODE_SAVE=always") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@sub-record-check:2] GotoIf("SIP/peterstar-00000055", "1?check") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Goto (sub-record-check,s,7)
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@sub-record-check:7] Set("SIP/peterstar-00000055", "__MON_FMT=wav") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@sub-record-check:8] GotoIf("SIP/peterstar-00000055", "0?next") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@sub-record-check:9] Set("SIP/peterstar-00000055", "CDR(recordingfile)=force-s-unknown-20130806-111944-1375773584.149.wav") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@sub-record-check:10] Return("SIP/peterstar-00000055", "") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-exten-vm:9] Macro("SIP/peterstar-00000055", "dial-one,15,TtrM(auto-blkvm),301") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-dial-one:1] Set("SIP/peterstar-00000055", "DEXTEN=301") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-dial-one:2] Set("SIP/peterstar-00000055", "DIALSTATUS_CW=") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-dial-one:3] GosubIf("SIP/peterstar-00000055", "0?screen,1()") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-dial-one:4] GosubIf("SIP/peterstar-00000055", "0?cf,1()") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-dial-one:5] GotoIf("SIP/peterstar-00000055", "1?skip1") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Goto (macro-dial-one,s,8)
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-dial-one:8] GotoIf("SIP/peterstar-00000055", "0?nodial") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-dial-one:9] GotoIf("SIP/peterstar-00000055", "0?continue") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-dial-one:10] Set("SIP/peterstar-00000055", "EXTHASCW=") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-dial-one:11] GotoIf("SIP/peterstar-00000055", "1?next1:cwinusebusy") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Goto (macro-dial-one,s,12)
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-dial-one:12] GotoIf("SIP/peterstar-00000055", "0?docfu:skip3") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Goto (macro-dial-one,s,16)
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-dial-one:16] GotoIf("SIP/peterstar-00000055", "1?next2:continue") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Goto (macro-dial-one,s,17)
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-dial-one:17] GotoIf("SIP/peterstar-00000055", "1?continue") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Goto (macro-dial-one,s,25)
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-dial-one:25] GotoIf("SIP/peterstar-00000055", "0?nodial") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-dial-one:26] GosubIf("SIP/peterstar-00000055", "1?dstring,1():dlocal,1()") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [dstring@macro-dial-one:1] Set("SIP/peterstar-00000055", "DSTRING=") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [dstring@macro-dial-one:2] Set("SIP/peterstar-00000055", "DEVICES=301") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [dstring@macro-dial-one:3] ExecIf("SIP/peterstar-00000055", "0?Return()") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [dstring@macro-dial-one:4] ExecIf("SIP/peterstar-00000055", "0?Set(DEVICES=01)") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [dstring@macro-dial-one:5] Set("SIP/peterstar-00000055", "LOOPCNT=1") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [dstring@macro-dial-one:6] Set("SIP/peterstar-00000055", "ITER=1") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [dstring@macro-dial-one:7] Set("SIP/peterstar-00000055", "THISDIAL=SIP/301") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [dstring@macro-dial-one:8] GosubIf("SIP/peterstar-00000055", "1?zap2dahdi,1()") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [zap2dahdi@macro-dial-one:1] ExecIf("SIP/peterstar-00000055", "0?Return()") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [zap2dahdi@macro-dial-one:2] Set("SIP/peterstar-00000055", "NEWDIAL=") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [zap2dahdi@macro-dial-one:3] Set("SIP/peterstar-00000055", "LOOPCNT2=1") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [zap2dahdi@macro-dial-one:4] Set("SIP/peterstar-00000055", "ITER2=1") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [zap2dahdi@macro-dial-one:5] Set("SIP/peterstar-00000055", "THISPART2=SIP/301") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [zap2dahdi@macro-dial-one:6] ExecIf("SIP/peterstar-00000055", "0?Set(THISPART2=DAHDI/301)") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [zap2dahdi@macro-dial-one:7] Set("SIP/peterstar-00000055", "NEWDIAL=SIP/301&") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [zap2dahdi@macro-dial-one:8] Set("SIP/peterstar-00000055", "ITER2=2") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [zap2dahdi@macro-dial-one:9] GotoIf("SIP/peterstar-00000055", "0?begin2") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [zap2dahdi@macro-dial-one:10] Set("SIP/peterstar-00000055", "THISDIAL=SIP/301") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [zap2dahdi@macro-dial-one:11] Return("SIP/peterstar-00000055", "") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [dstring@macro-dial-one:9] Set("SIP/peterstar-00000055", "DSTRING=SIP/301&") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [dstring@macro-dial-one:10] Set("SIP/peterstar-00000055", "ITER=2") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [dstring@macro-dial-one:11] GotoIf("SIP/peterstar-00000055", "0?begin") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [dstring@macro-dial-one:12] Set("SIP/peterstar-00000055", "DSTRING=SIP/301") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [dstring@macro-dial-one:13] Return("SIP/peterstar-00000055", "") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-dial-one:27] GotoIf("SIP/peterstar-00000055", "0?nodial") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-dial-one:28] GotoIf("SIP/peterstar-00000055", "0?skiptrace") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-dial-one:29] GosubIf("SIP/peterstar-00000055", "1?ctset,1():ctclear,1()") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [ctset@macro-dial-one:1] Set("SIP/peterstar-00000055", "DB(CALLTRACE/301)=8142672120") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [ctset@macro-dial-one:2] Return("SIP/peterstar-00000055", "") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-dial-one:30] Set("SIP/peterstar-00000055", "D_OPTIONS=TtrM(auto-blkvm)") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-dial-one:31] ExecIf("SIP/peterstar-00000055", "0?SIPAddHeader(Alert-Info: )") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-dial-one:32] ExecIf("SIP/peterstar-00000055", "0?SIPAddHeader()") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-dial-one:33] ExecIf("SIP/peterstar-00000055", "0?Set(CHANNEL(musicclass)=)") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-dial-one:34] GosubIf("SIP/peterstar-00000055", "0?qwait,1()") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-dial-one:35] Set("SIP/peterstar-00000055", "__CWIGNORE=TRUE") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-dial-one:36] Set("SIP/peterstar-00000055", "__KEEPCID=TRUE") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-dial-one:37] GotoIf("SIP/peterstar-00000055", "0?usegoto,1") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-dial-one:38] GotoIf("SIP/peterstar-00000055", "1?godial") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Goto (macro-dial-one,s,42)
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-dial-one:42] Dial("SIP/peterstar-00000055", "SIP/301,15,TtrM(auto-blkvm)") in new stack
[2013-08-06 11:20:06] WARNING[5116][C-00000034] app_dial.c: Unable to create channel of type 'SIP' (cause 20 - Subscriber absent)
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] app_dial.c: == Everyone is busy/congested at this time (1:0/0/1)
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-dial-one:43] ExecIf("SIP/peterstar-00000055", "0?MacroExit()") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-dial-one:44] ExecIf("SIP/peterstar-00000055", "0?Set(DIALSTATUS=)") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-dial-one:45] GosubIf("SIP/peterstar-00000055", "0?s-CHANUNAVAIL,1()") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-dial-one:46] MacroExit("SIP/peterstar-00000055", "") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-exten-vm:10] Set("SIP/peterstar-00000055", "SV_DIALSTATUS=CHANUNAVAIL") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-exten-vm:11] GosubIf("SIP/peterstar-00000055", "0?docfu,1()") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-exten-vm:12] GosubIf("SIP/peterstar-00000055", "0?docfb,1()") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-exten-vm:13] Set("SIP/peterstar-00000055", "DIALSTATUS=CHANUNAVAIL") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-exten-vm:14] ExecIf("SIP/peterstar-00000055", "0?MacroExit()") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-exten-vm:15] GotoIf("SIP/peterstar-00000055", "0?s-CHANUNAVAIL,1") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-exten-vm:16] Macro("SIP/peterstar-00000055", "vm,301,CHANUNAVAIL,") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-vm:1] Macro("SIP/peterstar-00000055", "user-callerid,SKIPTTL") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:1] Set("SIP/peterstar-00000055", "TOUCH_MONITOR=1375773584.149") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:2] Set("SIP/peterstar-00000055", "AMPUSER=8142672120") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:3] GotoIf("SIP/peterstar-00000055", "0?report") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:4] ExecIf("SIP/peterstar-00000055", "0?Set(REALCALLERIDNUM=8142672120)") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:5] Set("SIP/peterstar-00000055", "AMPUSER=") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:6] Set("SIP/peterstar-00000055", "AMPUSERCIDNAME=") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:7] GotoIf("SIP/peterstar-00000055", "1?report") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Goto (macro-user-callerid,s,13)
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:13] GotoIf("SIP/peterstar-00000055", "1?continue") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Goto (macro-user-callerid,s,26)
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:26] Set("SIP/peterstar-00000055", "CALLERID(number)=8142672120") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:27] Set("SIP/peterstar-00000055", "CALLERID(name)=8142672120") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:28] Set("SIP/peterstar-00000055", "CDR(cnum)=8142672120") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:29] Set("SIP/peterstar-00000055", "CDR(cnam)=8142672120") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-user-callerid:30] Set("SIP/peterstar-00000055", "CHANNEL(language)=en") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-vm:2] Set("SIP/peterstar-00000055", "VMGAIN=g(12)") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-vm:3] Macro("SIP/peterstar-00000055", "blkvm-check,") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-blkvm-check:1] Set("SIP/peterstar-00000055", "GOSUB_RETVAL=TRUE") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-blkvm-check:2] ExecIf("SIP/peterstar-00000055", "0?Set(GOSUB_RETVAL=TRUE)") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-blkvm-check:3] MacroExit("SIP/peterstar-00000055", "") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-vm:4] GotoIf("SIP/peterstar-00000055", "0?vmx,1") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-vm:5] Hangup("SIP/peterstar-00000055", "") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] app_macro.c: == Spawn extension (macro-vm, s, 5) exited non-zero on 'SIP/peterstar-00000055' in macro 'vm'
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] app_macro.c: == Spawn extension (macro-exten-vm, s, 16) exited non-zero on 'SIP/peterstar-00000055' in macro 'exten-vm'
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: == Spawn extension (from-did-direct, 301, 2) exited non-zero on 'SIP/peterstar-00000055'
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [h@from-did-direct:1] Macro("SIP/peterstar-00000055", "hangupcall,") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-hangupcall:1] GotoIf("SIP/peterstar-00000055", "1?theend") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Goto (macro-hangupcall,s,3)
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-hangupcall:3] ExecIf("SIP/peterstar-00000055", "0?Set(CDR(recordingfile)=)") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: -- Executing [s@macro-hangupcall:4] Hangup("SIP/peterstar-00000055", "") in new stack
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] app_macro.c: == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/peterstar-00000055' in macro 'hangupcall'
[2013-08-06 11:20:06] VERBOSE[5116][C-00000034] pbx.c: == Spawn extension (from-did-direct, h, 1) exited non-zero on 'SIP/peterstar-00000055'
[2013-08-06 11:20:06] VERBOSE[5117][C-00000034] app_mixmonitor.c: == MixMonitor close filestream (mixed)
[2013-08-06 11:20:06] VERBOSE[5117][C-00000034] app_mixmonitor.c: == End MixMonitor Recording SIP/peterstar-00000055

Лог где звонок ушел в голосовую почту

[2013-08-06 11:23:22] VERBOSE[1957][C-00000035] netsock2.c: == Using SIP RTP TOS bits 184
[2013-08-06 11:23:22] VERBOSE[1957][C-00000035] netsock2.c: == Using SIP RTP CoS mark 5
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [672117@from-trunk-sip-peterstar:1] Set("SIP/peterstar-00000056", "GROUP()=OUT_3") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [672117@from-trunk-sip-peterstar:2] Goto("SIP/peterstar-00000056", "from-trunk,672117,1") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Goto (from-trunk,672117,1)
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [672117@from-trunk:1] NoOp("SIP/peterstar-00000056", "Catch-All DID Match - Found 672117 - You probably want a DID for this.") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [672117@from-trunk:2] Goto("SIP/peterstar-00000056", "ext-did,s,1") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Goto (ext-did,s,1)
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@ext-did:1] ExecIf("SIP/peterstar-00000056", "1?Set(__FROM_DID=s)") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@ext-did:2] Set("SIP/peterstar-00000056", "CDR(did)=s") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@ext-did:3] ExecIf("SIP/peterstar-00000056", "1 ?Set(CALLERID(name)=8142672120)") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@ext-did:4] Set("SIP/peterstar-00000056", "__CALLINGPRES_SV=allowed_not_screened") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@ext-did:5] Set("SIP/peterstar-00000056", "CALLERPRES()=allowed_not_screened") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@ext-did:6] Goto("SIP/peterstar-00000056", "ext-callrecording,1,1") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Goto (ext-callrecording,1,1)
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [1@ext-callrecording:1] Gosub("SIP/peterstar-00000056", "sub-record-check,s,1(force,s,always)") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@sub-record-check:1] Set("SIP/peterstar-00000056", "REC_POLICY_MODE_SAVE=") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@sub-record-check:2] GotoIf("SIP/peterstar-00000056", "1?check") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Goto (sub-record-check,s,7)
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@sub-record-check:7] Set("SIP/peterstar-00000056", "__MON_FMT=wav") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@sub-record-check:8] GotoIf("SIP/peterstar-00000056", "1?next") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Goto (sub-record-check,s,11)
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@sub-record-check:11] ExecIf("SIP/peterstar-00000056", "0?Return()") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@sub-record-check:12] ExecIf("SIP/peterstar-00000056", "1?Set(__REC_POLICY_MODE=always)") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@sub-record-check:13] GotoIf("SIP/peterstar-00000056", "0?force,1") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@sub-record-check:14] Set("SIP/peterstar-00000056", "__REC_STATUS=INITIALIZED") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@sub-record-check:15] Set("SIP/peterstar-00000056", "NOW=1375773802") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@sub-record-check:16] Set("SIP/peterstar-00000056", "__DAY=06") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@sub-record-check:17] Set("SIP/peterstar-00000056", "__MONTH=08") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@sub-record-check:18] Set("SIP/peterstar-00000056", "__YEAR=2013") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@sub-record-check:19] Set("SIP/peterstar-00000056", "__TIMESTR=20130806-112322") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@sub-record-check:20] Set("SIP/peterstar-00000056", "__FROMEXTEN=unknown") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@sub-record-check:21] Set("SIP/peterstar-00000056", "__CALLFILENAME=force-s-unknown-20130806-112322-1375773802.150") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@sub-record-check:22] Goto("SIP/peterstar-00000056", "force,1") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Goto (sub-record-check,force,1)
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [force@sub-record-check:1] GosubIf("SIP/peterstar-00000056", "1?record,1(force,always,unknown)") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [record@sub-record-check:1] Set("SIP/peterstar-00000056", "AUDIOHOOK_INHERIT(MixMonitor)=yes") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [record@sub-record-check:2] MixMonitor("SIP/peterstar-00000056", "2013/08/06/force-s-unknown-20130806-112322-1375773802.150.wav,,") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [record@sub-record-check:3] Set("SIP/peterstar-00000056", "__REC_STATUS=RECORDING") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [record@sub-record-check:4] Set("SIP/peterstar-00000056", "CDR(recordingfile)=force-s-unknown-20130806-112322-1375773802.150.wav") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [record@sub-record-check:5] Return("SIP/peterstar-00000056", "") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [force@sub-record-check:2] Return("SIP/peterstar-00000056", "") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [1@ext-callrecording:2] Goto("SIP/peterstar-00000056", "ivr-9,s,1") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Goto (ivr-9,s,1)
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@ivr-9:1] Set("SIP/peterstar-00000056", "TIMEOUT_LOOPCOUNT=0") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@ivr-9:2] Set("SIP/peterstar-00000056", "INVALID_LOOPCOUNT=0") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@ivr-9:3] Set("SIP/peterstar-00000056", "_IVR_CONTEXT_ivr-9=") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@ivr-9:4] Set("SIP/peterstar-00000056", "_IVR_CONTEXT=ivr-9") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@ivr-9:5] Set("SIP/peterstar-00000056", "__IVR_RETVM=") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@ivr-9:6] GotoIf("SIP/peterstar-00000056", "0?skip") in new stack
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@ivr-9:7] Answer("SIP/peterstar-00000056", "") in new stack
[2013-08-06 11:23:22] VERBOSE[5159][C-00000035] app_mixmonitor.c: == Begin MixMonitor Recording SIP/peterstar-00000056
[2013-08-06 11:23:22] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@ivr-9:8] Wait("SIP/peterstar-00000056", "1") in new stack
[2013-08-06 11:23:23] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@ivr-9:9] Set("SIP/peterstar-00000056", "IVR_MSG=custom/kons-its") in new stack
[2013-08-06 11:23:23] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@ivr-9:10] Set("SIP/peterstar-00000056", "TIMEOUT(digit)=3") in new stack
[2013-08-06 11:23:23] VERBOSE[5158][C-00000035] func_timeout.c: -- Digit timeout set to 3.000
[2013-08-06 11:23:23] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@ivr-9:11] ExecIf("SIP/peterstar-00000056", "1?Background(custom/kons-its)") in new stack
[2013-08-06 11:23:23] VERBOSE[5158][C-00000035] file.c: -- <SIP/peterstar-00000056> Playing 'custom/kons-its.slin' (language 'en')
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [1@ivr-9:1] Goto("SIP/peterstar-00000056", "from-did-direct,301,1") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Goto (from-did-direct,301,1)
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [301@from-did-direct:1] Set("SIP/peterstar-00000056", "__RINGTIMER=15") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [301@from-did-direct:2] Macro("SIP/peterstar-00000056", "exten-vm,301,301,0,0,0") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-exten-vm:1] Macro("SIP/peterstar-00000056", "user-callerid,") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-user-callerid:1] Set("SIP/peterstar-00000056", "TOUCH_MONITOR=1375773802.150") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-user-callerid:2] Set("SIP/peterstar-00000056", "AMPUSER=8142672120") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-user-callerid:3] GotoIf("SIP/peterstar-00000056", "0?report") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-user-callerid:4] ExecIf("SIP/peterstar-00000056", "1?Set(REALCALLERIDNUM=8142672120)") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-user-callerid:5] Set("SIP/peterstar-00000056", "AMPUSER=") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-user-callerid:6] Set("SIP/peterstar-00000056", "AMPUSERCIDNAME=") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-user-callerid:7] GotoIf("SIP/peterstar-00000056", "1?report") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Goto (macro-user-callerid,s,13)
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-user-callerid:13] GotoIf("SIP/peterstar-00000056", "0?continue") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-user-callerid:14] Set("SIP/peterstar-00000056", "__TTL=64") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-user-callerid:15] GotoIf("SIP/peterstar-00000056", "1?continue") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Goto (macro-user-callerid,s,26)
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-user-callerid:26] Set("SIP/peterstar-00000056", "CALLERID(number)=8142672120") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-user-callerid:27] Set("SIP/peterstar-00000056", "CALLERID(name)=8142672120") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-user-callerid:28] Set("SIP/peterstar-00000056", "CDR(cnum)=8142672120") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-user-callerid:29] Set("SIP/peterstar-00000056", "CDR(cnam)=8142672120") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-user-callerid:30] Set("SIP/peterstar-00000056", "CHANNEL(language)=en") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-exten-vm:2] Set("SIP/peterstar-00000056", "RingGroupMethod=none") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-exten-vm:3] Set("SIP/peterstar-00000056", "__EXTTOCALL=301") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-exten-vm:4] Set("SIP/peterstar-00000056", "__PICKUPMARK=301") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-exten-vm:5] Set("SIP/peterstar-00000056", "RT=15") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-exten-vm:6] ExecIf("SIP/peterstar-00000056", "0?Macro(vm,301,DIRECTDIAL,)") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-exten-vm:7] ExecIf("SIP/peterstar-00000056", "0?MacroExit()") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-exten-vm:8] Gosub("SIP/peterstar-00000056", "sub-record-check,s,1(exten,301,)") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@sub-record-check:1] Set("SIP/peterstar-00000056", "REC_POLICY_MODE_SAVE=always") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@sub-record-check:2] GotoIf("SIP/peterstar-00000056", "1?check") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Goto (sub-record-check,s,7)
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@sub-record-check:7] Set("SIP/peterstar-00000056", "__MON_FMT=wav") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@sub-record-check:8] GotoIf("SIP/peterstar-00000056", "0?next") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@sub-record-check:9] Set("SIP/peterstar-00000056", "CDR(recordingfile)=force-s-unknown-20130806-112322-1375773802.150.wav") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@sub-record-check:10] Return("SIP/peterstar-00000056", "") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-exten-vm:9] Macro("SIP/peterstar-00000056", "dial-one,15,Ttr,301") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-dial-one:1] Set("SIP/peterstar-00000056", "DEXTEN=301") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-dial-one:2] Set("SIP/peterstar-00000056", "DIALSTATUS_CW=") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-dial-one:3] GosubIf("SIP/peterstar-00000056", "0?screen,1()") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-dial-one:4] GosubIf("SIP/peterstar-00000056", "0?cf,1()") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-dial-one:5] GotoIf("SIP/peterstar-00000056", "1?skip1") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Goto (macro-dial-one,s,8)
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-dial-one:8] GotoIf("SIP/peterstar-00000056", "0?nodial") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-dial-one:9] GotoIf("SIP/peterstar-00000056", "0?continue") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-dial-one:10] Set("SIP/peterstar-00000056", "EXTHASCW=ENABLED") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-dial-one:11] GotoIf("SIP/peterstar-00000056", "0?next1:cwinusebusy") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Goto (macro-dial-one,s,23)
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-dial-one:23] GotoIf("SIP/peterstar-00000056", "1?next3:continue") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Goto (macro-dial-one,s,24)
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-dial-one:24] ExecIf("SIP/peterstar-00000056", "0?Set(DIALSTATUS_CW=BUSY)") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-dial-one:25] GotoIf("SIP/peterstar-00000056", "0?nodial") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-dial-one:26] GosubIf("SIP/peterstar-00000056", "1?dstring,1():dlocal,1()") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [dstring@macro-dial-one:1] Set("SIP/peterstar-00000056", "DSTRING=") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [dstring@macro-dial-one:2] Set("SIP/peterstar-00000056", "DEVICES=301") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [dstring@macro-dial-one:3] ExecIf("SIP/peterstar-00000056", "0?Return()") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [dstring@macro-dial-one:4] ExecIf("SIP/peterstar-00000056", "0?Set(DEVICES=01)") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [dstring@macro-dial-one:5] Set("SIP/peterstar-00000056", "LOOPCNT=1") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [dstring@macro-dial-one:6] Set("SIP/peterstar-00000056", "ITER=1") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [dstring@macro-dial-one:7] Set("SIP/peterstar-00000056", "THISDIAL=SIP/301") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [dstring@macro-dial-one:8] GosubIf("SIP/peterstar-00000056", "1?zap2dahdi,1()") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [zap2dahdi@macro-dial-one:1] ExecIf("SIP/peterstar-00000056", "0?Return()") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [zap2dahdi@macro-dial-one:2] Set("SIP/peterstar-00000056", "NEWDIAL=") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [zap2dahdi@macro-dial-one:3] Set("SIP/peterstar-00000056", "LOOPCNT2=1") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [zap2dahdi@macro-dial-one:4] Set("SIP/peterstar-00000056", "ITER2=1") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [zap2dahdi@macro-dial-one:5] Set("SIP/peterstar-00000056", "THISPART2=SIP/301") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [zap2dahdi@macro-dial-one:6] ExecIf("SIP/peterstar-00000056", "0?Set(THISPART2=DAHDI/301)") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [zap2dahdi@macro-dial-one:7] Set("SIP/peterstar-00000056", "NEWDIAL=SIP/301&") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [zap2dahdi@macro-dial-one:8] Set("SIP/peterstar-00000056", "ITER2=2") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [zap2dahdi@macro-dial-one:9] GotoIf("SIP/peterstar-00000056", "0?begin2") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [zap2dahdi@macro-dial-one:10] Set("SIP/peterstar-00000056", "THISDIAL=SIP/301") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [zap2dahdi@macro-dial-one:11] Return("SIP/peterstar-00000056", "") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [dstring@macro-dial-one:9] Set("SIP/peterstar-00000056", "DSTRING=SIP/301&") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [dstring@macro-dial-one:10] Set("SIP/peterstar-00000056", "ITER=2") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [dstring@macro-dial-one:11] GotoIf("SIP/peterstar-00000056", "0?begin") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [dstring@macro-dial-one:12] Set("SIP/peterstar-00000056", "DSTRING=SIP/301") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [dstring@macro-dial-one:13] Return("SIP/peterstar-00000056", "") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-dial-one:27] GotoIf("SIP/peterstar-00000056", "0?nodial") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-dial-one:28] GotoIf("SIP/peterstar-00000056", "0?skiptrace") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-dial-one:29] GosubIf("SIP/peterstar-00000056", "1?ctset,1():ctclear,1()") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [ctset@macro-dial-one:1] Set("SIP/peterstar-00000056", "DB(CALLTRACE/301)=8142672120") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [ctset@macro-dial-one:2] Return("SIP/peterstar-00000056", "") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-dial-one:30] Set("SIP/peterstar-00000056", "D_OPTIONS=Ttr") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-dial-one:31] ExecIf("SIP/peterstar-00000056", "0?SIPAddHeader(Alert-Info: )") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-dial-one:32] ExecIf("SIP/peterstar-00000056", "0?SIPAddHeader()") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-dial-one:33] ExecIf("SIP/peterstar-00000056", "0?Set(CHANNEL(musicclass)=)") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-dial-one:34] GosubIf("SIP/peterstar-00000056", "0?qwait,1()") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-dial-one:35] Set("SIP/peterstar-00000056", "__CWIGNORE=") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-dial-one:36] Set("SIP/peterstar-00000056", "__KEEPCID=TRUE") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-dial-one:37] GotoIf("SIP/peterstar-00000056", "0?usegoto,1") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-dial-one:38] GotoIf("SIP/peterstar-00000056", "1?godial") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Goto (macro-dial-one,s,42)
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-dial-one:42] Dial("SIP/peterstar-00000056", "SIP/301,15,Ttr") in new stack
[2013-08-06 11:23:27] WARNING[5158][C-00000035] app_dial.c: Unable to create channel of type 'SIP' (cause 20 - Subscriber absent)
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] app_dial.c: == Everyone is busy/congested at this time (1:0/0/1)
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-dial-one:43] ExecIf("SIP/peterstar-00000056", "0?MacroExit()") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-dial-one:44] ExecIf("SIP/peterstar-00000056", "0?Set(DIALSTATUS=)") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-dial-one:45] GosubIf("SIP/peterstar-00000056", "0?s-CHANUNAVAIL,1()") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-dial-one:46] MacroExit("SIP/peterstar-00000056", "") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-exten-vm:10] Set("SIP/peterstar-00000056", "SV_DIALSTATUS=CHANUNAVAIL") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-exten-vm:11] GosubIf("SIP/peterstar-00000056", "0?docfu,1()") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-exten-vm:12] GosubIf("SIP/peterstar-00000056", "0?docfb,1()") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-exten-vm:13] Set("SIP/peterstar-00000056", "DIALSTATUS=CHANUNAVAIL") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-exten-vm:14] ExecIf("SIP/peterstar-00000056", "0?MacroExit()") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-exten-vm:15] GotoIf("SIP/peterstar-00000056", "0?s-CHANUNAVAIL,1") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-exten-vm:16] Macro("SIP/peterstar-00000056", "vm,301,CHANUNAVAIL,") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-vm:1] Macro("SIP/peterstar-00000056", "user-callerid,SKIPTTL") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-user-callerid:1] Set("SIP/peterstar-00000056", "TOUCH_MONITOR=1375773802.150") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-user-callerid:2] Set("SIP/peterstar-00000056", "AMPUSER=8142672120") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-user-callerid:3] GotoIf("SIP/peterstar-00000056", "0?report") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-user-callerid:4] ExecIf("SIP/peterstar-00000056", "0?Set(REALCALLERIDNUM=8142672120)") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-user-callerid:5] Set("SIP/peterstar-00000056", "AMPUSER=") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-user-callerid:6] Set("SIP/peterstar-00000056", "AMPUSERCIDNAME=") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-user-callerid:7] GotoIf("SIP/peterstar-00000056", "1?report") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Goto (macro-user-callerid,s,13)
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-user-callerid:13] GotoIf("SIP/peterstar-00000056", "1?continue") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Goto (macro-user-callerid,s,26)
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-user-callerid:26] Set("SIP/peterstar-00000056", "CALLERID(number)=8142672120") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-user-callerid:27] Set("SIP/peterstar-00000056", "CALLERID(name)=8142672120") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-user-callerid:28] Set("SIP/peterstar-00000056", "CDR(cnum)=8142672120") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-user-callerid:29] Set("SIP/peterstar-00000056", "CDR(cnam)=8142672120") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-user-callerid:30] Set("SIP/peterstar-00000056", "CHANNEL(language)=en") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-vm:2] Set("SIP/peterstar-00000056", "VMGAIN=g(12)") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-vm:3] Macro("SIP/peterstar-00000056", "blkvm-check,") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-blkvm-check:1] Set("SIP/peterstar-00000056", "GOSUB_RETVAL=") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-blkvm-check:2] ExecIf("SIP/peterstar-00000056", "0?Set(GOSUB_RETVAL=TRUE)") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-blkvm-check:3] MacroExit("SIP/peterstar-00000056", "") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-vm:4] GotoIf("SIP/peterstar-00000056", "1?vmx,1") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Goto (macro-vm,vmx,1)
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [vmx@macro-vm:1] Set("SIP/peterstar-00000056", "MEXTEN=301") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [vmx@macro-vm:2] Set("SIP/peterstar-00000056", "MMODE=CHANUNAVAIL") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [vmx@macro-vm:3] Set("SIP/peterstar-00000056", "RETVM=") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [vmx@macro-vm:4] Set("SIP/peterstar-00000056", "MODE=unavail") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [vmx@macro-vm:5] GotoIf("SIP/peterstar-00000056", "1?chknomsg") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Goto (macro-vm,vmx,7)
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [vmx@macro-vm:7] GotoIf("SIP/peterstar-00000056", "0?s-CHANUNAVAIL,1") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [vmx@macro-vm:8] GotoIf("SIP/peterstar-00000056", "1?notdirect") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Goto (macro-vm,vmx,10)
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [vmx@macro-vm:10] NoOp("SIP/peterstar-00000056", "Checking if ext 301 is enabled: ") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [vmx@macro-vm:11] GotoIf("SIP/peterstar-00000056", "1?s-CHANUNAVAIL,1") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Goto (macro-vm,s-CHANUNAVAIL,1)
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s-CHANUNAVAIL@macro-vm:1] Macro("SIP/peterstar-00000056", "get-vmcontext,301") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-get-vmcontext:1] Set("SIP/peterstar-00000056", "VMCONTEXT=default") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-get-vmcontext:2] GotoIf("SIP/peterstar-00000056", "0?200:300") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Goto (macro-get-vmcontext,s,300)
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s@macro-get-vmcontext:300] NoOp("SIP/peterstar-00000056", "") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] pbx.c: -- Executing [s-CHANUNAVAIL@macro-vm:2] VoiceMail("SIP/peterstar-00000056", "301@default,ug(12)") in new stack
[2013-08-06 11:23:27] VERBOSE[5158][C-00000035] file.c: -- <SIP/peterstar-00000056> Playing 'vm-theperson.slin' (language 'en')
[2013-08-06 11:23:28] VERBOSE[5158][C-00000035] file.c: -- <SIP/peterstar-00000056> Playing 'digits/3.slin' (language 'en')
[2013-08-06 11:23:29] VERBOSE[5158][C-00000035] file.c: -- <SIP/peterstar-00000056> Playing 'digits/0.slin' (language 'en')
[2013-08-06 11:23:29] VERBOSE[5158][C-00000035] file.c: -- <SIP/peterstar-00000056> Playing 'digits/1.slin' (language 'en')
[2013-08-06 11:23:30] VERBOSE[5158][C-00000035] file.c: -- <SIP/peterstar-00000056> Playing 'vm-isunavail.slin' (language 'en')
[2013-08-06 11:23:31] VERBOSE[5158][C-00000035] file.c: -- <SIP/peterstar-00000056> Playing 'vm-intro.slin' (language 'en')

dshuffin, зачем переводите с помощью AMI? Вы какую-то свою обработку сами написали? Сильно напоминает
http://forum.asterisk.ru/viewtopic.php?f=5&t=3231 У вас нет пира SIP/301 Судя по логу - Voice mail работает.

P.S. Чтобы бросаться километрами логов - посмотрите на тег /spoiler когда делаете пост.

разобраться почему не срабатывает вот это условие

ded писал(а):dshuffin, зачем переводите с помощью AMI? Вы какую-то свою обработку сами написали? Сильно напоминает
http://forum.asterisk.ru/viewtopic.php?f=5&t=3231

Да, у нас 1с-ка рулит звонками, причем по достаточно сложной схеме. Можно было-бы 1с-кой только .conf файлы делать, но тогда пришлось-бы их изменять практически после каждого звонка (привязка к последнему контактировавшему и т.п.

ded писал(а):У вас нет пира SIP/301

В астериске он заведен, но его телефон выключен, поэтому он unawailable, поэтому его голосовая почта идет в ход.

ded писал(а):Судя по логу - Voice mail работает.

Это часть того лога где не было переключения с помощью AMI.

Vlad1983 писал(а):разобраться почему не срабатывает вот это условие

Спасибо, попробую посмотреть.

К сожалению мне не хватило упорства разобраться зачем нужно macro-blkvm-check поэтому я в файлике extensions_additional.conf оставил то, что там было а в файлик extensions_override_freepbx.conf добавил это:

одно сделали что-то другое сломали
нормальный подход