История изменений [назад]

Доброго времени суток. Подскажите пожалуйста куда копать никак не пойму. Возможно просто не могу сформулировать вопрос для гугла, возможно проблема специфическая. Что имею :

1)Asterisk (Ver. 11.17.1)

2)FreePBX 12.0.66

3)Небольшие дополнения в extension_override-freepbx.conf(Цель-создание юзер эвентов при подъеме трубки).

Проблема. При диале вызывается макрос, который устанавливает канальные наследуемые переменные(надеюсь я правильно написал). Вообщем выглядит так:

    [macro-dial-one]
....
exten => s,n,Set(D_OPTIONS=${IF($["${NODEST}"!="" & ${REGEX("(M[(]auto-blkvm[)])" ${ARG2})} != 1]?${ARG2}M(auto-blkvm,${EVENT_TYPE},${CALLER_NUMBER},${DEXTEN},${UNIQUEID},${CALLFILENAME}):${ARG2}M(user-event,${EVENT_TYPE},${CALLER_NUMBER},${DEXTEN},${UNIQUEID},${CALLFILENAME}))})
exten => s,n,Dial(${DSTRING},${ARG1},${D_OPTIONS})

[macro-user-event]
exten => s,1,GotoIf($["${ARG1}"="DirectCall"]?direct:transfer)
exten => s,n,Set(_BACKUP_RECENT_CALLER=${RECENT_CALLER})
exten => s,n,Set(_BACKUP_RECENT_CALLEE=${RECENT_CALLEE})
exten => s,n,Set(__RECENT_CALLER=${ARG2})
exten => s,n,Set(__RECENT_CALLEE=${ARG3})
exten => s,n,GosubIf($["${ARG5}"!="outgoing"]?record,1())
exten => s,n,UserEvent(${ARG1},CALLER: ${ARG2},CALLEE: ${ARG3},CALLUNIQUEID: ${ARG4},FILENAME: ${CALLFILENAME})
exten => s,n,MacroExit()
exten => s,n(transfer),Set(_BACKUP_RECENT_CALLER=${RECENT_CALLER})
exten => s,n,Set(_BACKUP_RECENT_CALLEE=${RECENT_CALLEE})
exten => s,n,Set(__RECENT_CALLER=${IF($["${FROMEXTEN}"="${BACKUP_RECENT_CALLER}"]?${BACKUP_RECENT_CALLEE}:${BACKUP_RECENT_CALLER})})
exten => s,n,Set(__RECENT_CALLEE=${ARG3})
exten => s,n,Set(ARG1="TransferCall")
exten => s,n,GosubIf($["${ARG5}"!="outgoing"]?record,1())
exten => s,n,UserEvent(${ARG1},CALLER: ${RECENT_CALLER},CALLEE: ${ARG3},CALLUNIQUEID: ${ARG4},FILENAME: ${CALLFILENAME})
exten => s,n,MacroExit()

Суть проблемы: При условных переводах иногда возникает ошибка(примерно раз в день) из 20-30 переводов, на разных экстеншенах вне зависимости от времени в самом начале при попадании перевода в from-internal-xfer отсутствуют переменные заведенные в маросах. С непереведенными звонками такого не происходит.

P.S. Я извиняюсь за ошибки и вообще не умею письменно излагать мысли. Буду благодарен за любую помощь/направление. Всем заранее огромное спасибо.

Доброго времени суток. Подскажите пожалуйста куда копать никак не пойму. Возможно просто не могу сформулировать вопрос для гугла, возможно проблема специфическая. Что имею :

1)Asterisk (Ver. 11.17.1)

2)FreePBX 12.0.66

3)Небольшие дополнения в extension_override-freepbx.conf(Цель-создание юзер эвентов при подъеме трубки).

Проблема. При диале вызывается макрос, который устанавливает канальные наследуемые переменные(надеюсь я правильно написал). Вообщем выглядит так:

    [macro-dial-one]
....
exten => s,n,Set(D_OPTIONS=${IF($["${NODEST}"!="" & ${REGEX("(M[(]auto-blkvm[)])" ${ARG2})} != 1]?${ARG2}M(auto-blkvm,${EVENT_TYPE},${CALLER_NUMBER},${DEXTEN},${UNIQUEID},${CALLFILENAME}):${ARG2}M(user-event,${EVENT_TYPE},${CALLER_NUMBER},${DEXTEN},${UNIQUEID},${CALLFILENAME}))})
exten => s,n,Dial(${DSTRING},${ARG1},${D_OPTIONS})

[macro-user-event]
exten => s,1,GotoIf($["${ARG1}"="DirectCall"]?direct:transfer)
exten => s,n,Set(_BACKUP_RECENT_CALLER=${RECENT_CALLER})
exten => s,n,Set(_BACKUP_RECENT_CALLEE=${RECENT_CALLEE})
exten => s,n,Set(__RECENT_CALLER=${ARG2})
exten => s,n,Set(__RECENT_CALLEE=${ARG3})
exten => s,n,GosubIf($["${ARG5}"!="outgoing"]?record,1())
exten => s,n,UserEvent(${ARG1},CALLER: ${ARG2},CALLEE: ${ARG3},CALLUNIQUEID: ${ARG4},FILENAME: ${CALLFILENAME})
exten => s,n,MacroExit()
exten => s,n(transfer),Set(_BACKUP_RECENT_CALLER=${RECENT_CALLER})
exten => s,n,Set(_BACKUP_RECENT_CALLEE=${RECENT_CALLEE})
exten => s,n,Set(__RECENT_CALLER=${IF($["${FROMEXTEN}"="${BACKUP_RECENT_CALLER}"]?${BACKUP_RECENT_CALLEE}:${BACKUP_RECENT_CALLER})})
exten => s,n,Set(__RECENT_CALLEE=${ARG3})
exten => s,n,Set(ARG1="TransferCall")
exten => s,n,GosubIf($["${ARG5}"!="outgoing"]?record,1())
exten => s,n,UserEvent(${ARG1},CALLER: ${RECENT_CALLER},CALLEE: ${ARG3},CALLUNIQUEID: ${ARG4},FILENAME: ${CALLFILENAME})
exten => s,n,MacroExit()

Суть проблемы: При условных переводах иногда возникает ошибка(примерно раз в день) из 20-30 переводов, на разных экстеншенах вне зависимости от времени в самом начале при попадании перевода в from-internal-xfer отсутствуют переменные заведенные в маросах. С непереведенными звонками такого не происходит.

P.S. Я извиняюсь за ошибки и вообще не умею письменно излагать мысли. Буду благодарен за любую помощь/направление. Всем заранее огромное спасибо.

Доброго времени суток. Подскажите пожалуйста куда копать никак не пойму. Возможно просто не могу сформулировать вопрос для гугла, возможно проблема специфическая. Что имею :

1)Asterisk (Ver. 11.17.1)

2)FreePBX 12.0.66

3)Небольшие дополнения в extension_override-freepbx.conf(Цель-создание юзер эвентов при подъеме трубки).

Проблема. При диале вызывается макрос, который устанавливает канальные наследуемые переменные(надеюсь я правильно написал). Вообщем выглядит так:

    [macro-dial-one]
....
exten => s,n,Set(D_OPTIONS=${IF($["${NODEST}"!="" & ${REGEX("(M[(]auto-blkvm[)])" ${ARG2})} != 1]?${ARG2}M(auto-blkvm,${EVENT_TYPE},${CALLER_NUMBER},${DEXTEN},${UNIQUEID},${CALLFILENAME}):${ARG2}M(user-event,${EVENT_TYPE},${CALLER_NUMBER},${DEXTEN},${UNIQUEID},${CALLFILENAME}))})
exten => s,n,Dial(${DSTRING},${ARG1},${D_OPTIONS})

[macro-user-event]
exten => s,1,GotoIf($["${ARG1}"="DirectCall"]?direct:transfer)
exten => s,n,Set(_BACKUP_RECENT_CALLER=${RECENT_CALLER})
exten => s,n,Set(_BACKUP_RECENT_CALLEE=${RECENT_CALLEE})
exten => s,n,Set(__RECENT_CALLER=${ARG2})
exten => s,n,Set(__RECENT_CALLEE=${ARG3})
exten => s,n,GosubIf($["${ARG5}"!="outgoing"]?record,1())
exten => s,n,UserEvent(${ARG1},CALLER: ${ARG2},CALLEE: ${ARG3},CALLUNIQUEID: ${ARG4},FILENAME: ${CALLFILENAME})
exten => s,n,MacroExit()
exten => s,n(transfer),Set(_BACKUP_RECENT_CALLER=${RECENT_CALLER})
exten => s,n,Set(_BACKUP_RECENT_CALLEE=${RECENT_CALLEE})
exten => s,n,Set(__RECENT_CALLER=${IF($["${FROMEXTEN}"="${BACKUP_RECENT_CALLER}"]?${BACKUP_RECENT_CALLEE}:${BACKUP_RECENT_CALLER})})
exten => s,n,Set(__RECENT_CALLEE=${ARG3})
exten => s,n,Set(ARG1="TransferCall")
exten => s,n,GosubIf($["${ARG5}"!="outgoing"]?record,1())
exten => s,n,UserEvent(${ARG1},CALLER: ${RECENT_CALLER},CALLEE: ${ARG3},CALLUNIQUEID: ${ARG4},FILENAME: ${CALLFILENAME})
exten => s,n,MacroExit()

Суть проблемы: При условных переводах иногда возникает ошибка(примерно раз в день) из 20-30 переводов, на разных экстеншенах вне зависимости от времени в самом начале при попадании перевода в from-internal-xfer отсутствуют переменные заведенные в маросах. С непереведенными звонками такого не происходит.

P.S. Я извиняюсь за ошибки и вообще не умею письменно излагать мысли. Буду благодарен за любую помощь/направление. Всем заранее огромное спасибо.

   [2015-06-17 17:52:52]   [1202@from-queue:1] Set("Local/1202@from-queue-000004f1;2", "QAGENT=1202") in new stack
[2015-06-17 17:52:52]   [1202@from-queue:2] Goto("Local/1202@from-queue-000004f1;2", "9002,1") in new stack
[2015-06-17 17:52:52]  pbx.c: -- Goto (from-queue,9002,1)
[2015-06-17 17:52:52]   [9002@from-queue:1] Goto("Local/1202@from-queue-000004f1;2", "from-internal,1202,1") in new stack
[2015-06-17 17:52:52]  pbx.c: -- Goto (from-internal,1202,1)
[2015-06-17 17:52:52]   [1202@from-internal:1] Set("Local/1202@from-queue-000004f1;2", "__RINGTIMER=15") in new stack
[2015-06-17 17:52:52]   [1202@from-internal:2] Macro("Local/1202@from-queue-000004f1;2", "exten-vm,novm,1202,1,1,1") in new stack
[2015-06-17 17:52:52]   [s@macro-exten-vm:1] Macro("Local/1202@from-queue-000004f1;2", "user-callerid,") in new stack
[2015-06-17 17:52:52]   [s@macro-user-callerid:1] Set("Local/1202@from-queue-000004f1;2", "TOUCH_MONITOR=1434545572.5145") in new stack
[2015-06-17 17:52:52]   [s@macro-user-callerid:2] Set("Local/1202@from-queue-000004f1;2", "AMPUSER=89823435698") in new stack
[2015-06-17 17:52:52]   [s@macro-user-callerid:3] GotoIf("Local/1202@from-queue-000004f1;2", "1?report") in new stack
[2015-06-17 17:52:52]  pbx.c: -- Goto (macro-user-callerid,s,16)
[2015-06-17 17:52:52]   [s@macro-user-callerid:16] GotoIf("Local/1202@from-queue-000004f1;2", "0?continue") in new stack
[2015-06-17 17:52:52]   [s@macro-user-callerid:17] ExecIf("Local/1202@from-queue-000004f1;2", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
[2015-06-17 17:52:52]   [s@macro-user-callerid:18] Set("Local/1202@from-queue-000004f1;2", "__TTL=63") in new stack
[2015-06-17 17:52:52]   [s@macro-user-callerid:19] GotoIf("Local/1202@from-queue-000004f1;2", "1?continue") in new stack
[2015-06-17 17:52:52]  pbx.c: -- Goto (macro-user-callerid,s,30)
[2015-06-17 17:52:52]   [s@macro-user-callerid:30] Set("Local/1202@from-queue-000004f1;2", "CALLERID(number)=89823435698") in new stack
[2015-06-17 17:52:52]   [s@macro-user-callerid:31] Set("Local/1202@from-queue-000004f1;2", "CALLERID(name)=89823435698") in new stack
[2015-06-17 17:52:52]   [s@macro-user-callerid:32] Set("Local/1202@from-queue-000004f1;2", "CDR(cnum)=89823435698") in new stack
[2015-06-17 17:52:52]   [s@macro-user-callerid:33] Set("Local/1202@from-queue-000004f1;2", "CDR(cnam)=89823435698") in new stack
[2015-06-17 17:52:52]   [s@macro-user-callerid:34] DumpChan("Local/1202@from-queue-000004f1;2", "") in new stack
[2015-06-17 17:52:52]  app_dumpchan.c:
Dumping Info For Channel: Local/1202@from-queue-000004f1;2:
================================================================================
Info:
Name= Local/1202@from-queue-000004f1;2
Type= Local
UniqueID= 1434545572.5145
LinkedID= 1434545571.5143
CallerIDNum= 89823435698
CallerIDName= 89823435698
ConnectedLineIDNum= 905936
ConnectedLineIDName=(N/A)
DNIDDigits= (N/A)
RDNIS= 87132906900
Parkinglot=
Language= en
State= Ring (4)
Rings= 0
NativeFormat= (alaw)
WriteFormat= alaw
ReadFormat= alaw
RawWriteFormat= alaw
RawReadFormat= alaw
WriteTranscode= No
ReadTranscode= No
1stFileDescriptor= -1
Framesin= 0
Framesout= 0
TimetoHangup= 0
ElapsedTime= 0h0m0s
DirectBridge= <none>
IndirectBridge= <none>
Context= macro-user-callerid
Extension= s
Priority= 34
CallGroup=
PickupGroup=
Application= DumpChan
Data= (Empty)
Blocking_in= (Not Blocking)

Variables:
MACRO_DEPTH=2
TTL=63
CALLEE_ACCOUNCODE=
AMPUSER=89823435698
TOUCH_MONITOR=1434545572.5145
ARG1=
MACRO_PRIORITY=1
MACRO_CONTEXT=macro-exten-vm
MACRO_EXTEN=s
ARG5=1
ARG4=1
ARG3=1
ARG2=1202
RINGTIMER=15
DB_RESULT=0
QAGENT=1202
MOHCLASS=default
CWIGNORE=TRUE
MON_FMT=wav
FROMEXTEN=89823435698
TIMESTR=20150617-175252
YEAR=2015
MONTH=06
DAY=17
REC_STATUS=INITIALIZED
NODEST=9002
DIAL_OPTIONS=TtrM(auto-blkvm)
BLKVM_CHANNEL=SIP/418027-000009cb
CALLINGPRES_SV=allowed_not_screened
FROM_DID=905936
SIPRDNISDOMAIN=10.14.0.2;user=phone
SIPREDIRECTREASON=unknown
PRIREDIRECTREASON=UNKNOWN
================================================================================
[2015-06-17 17:52:52]   [s@macro-user-callerid:35] GotoIf("Local/1202@from-queue-000004f1;2", "0?xfer:end") in new stack
[2015-06-17 17:52:52]  pbx.c: -- Goto (macro-user-callerid,s,38)
[2015-06-17 17:52:52]   [s@macro-user-callerid:38] Set("Local/1202@from-queue-000004f1;2", "CHANNEL(language)=ru") in new stack
[2015-06-17 17:52:52]   [s@macro-exten-vm:2] Set("Local/1202@from-queue-000004f1;2", "RingGroupMethod=none") in new stack
[2015-06-17 17:52:52]   [s@macro-exten-vm:3] Set("Local/1202@from-queue-000004f1;2", "__EXTTOCALL=1202") in new stack
[2015-06-17 17:52:52]   [s@macro-exten-vm:4] Set("Local/1202@from-queue-000004f1;2", "__PICKUPMARK=1202") in new stack
[2015-06-17 17:52:52]   [s@macro-exten-vm:5] Set("Local/1202@from-queue-000004f1;2", "RT=15") in new stack
[2015-06-17 17:52:52]   [s@macro-exten-vm:6] ExecIf("Local/1202@from-queue-000004f1;2", "0?Macro(vm,novm,DIRECTDIAL,)") in new stack
[2015-06-17 17:52:52]   [s@macro-exten-vm:7] ExecIf("Local/1202@from-queue-000004f1;2", "0?MacroExit()") in new stack
[2015-06-17 17:52:52]   [s@macro-exten-vm:8] Gosub("Local/1202@from-queue-000004f1;2", "sub-record-check,s,1(exten,1202,dontcare)") in new stack
[2015-06-17 17:52:52]   [s@sub-record-check:1] GotoIf("Local/1202@from-queue-000004f1;2", "11?initialized") in new stack
[2015-06-17 17:52:52]  pbx.c: -- Goto (sub-record-check,s,10)
[2015-06-17 17:52:52]   [s@sub-record-check:10] NoOp("Local/1202@from-queue-000004f1;2", "Recordings initialized") in new stack
[2015-06-17 17:52:52]   [s@sub-record-check:11] ExecIf("Local/1202@from-queue-000004f1;2", "0?Set(__RRNODEST=)") in new stack
[2015-06-17 17:52:52]   [s@sub-record-check:12] ExecIf("Local/1202@from-queue-000004f1;2", "0?Set(__NODEST=)") in new stack
[2015-06-17 17:52:52]   [s@sub-record-check:13] ExecIf("Local/1202@from-queue-000004f1;2", "0?Set(ARG3=dontcare)") in new stack
[2015-06-17 17:52:52]   [s@sub-record-check:14] Set("Local/1202@from-queue-000004f1;2", "REC_POLICY_MODE_SAVE=") in new stack
[2015-06-17 17:52:52]   [s@sub-record-check:15] ExecIf("Local/1202@from-queue-000004f1;2", "0?Set(REC_STATUS=NO)") in new stack
[2015-06-17 17:52:52]   [s@sub-record-check:16] GotoIf("Local/1202@from-queue-000004f1;2", "5?checkaction") in new stack
[2015-06-17 17:52:52]  pbx.c: -- Goto (sub-record-check,s,19)
[2015-06-17 17:52:52]   [s@sub-record-check:19] GotoIf("Local/1202@from-queue-000004f1;2", "1?sub-record-check,exten,1") in new stack
[2015-06-17 17:52:52]  pbx.c: -- Goto (sub-record-check,exten,1)
[2015-06-17 17:52:52]   [exten@sub-record-check:1] NoOp("Local/1202@from-queue-000004f1;2", "Exten Recording Check between 89823435698 and 1202") in new stack
[2015-06-17 17:52:52]   [exten@sub-record-check:2] Set("Local/1202@from-queue-000004f1;2", "CALLTYPE=external") in new stack
[2015-06-17 17:52:52]   [exten@sub-record-check:3] ExecIf("Local/1202@from-queue-000004f1;2", "0?Set(CALLTYPE=)") in new stack
[2015-06-17 17:52:52]   [exten@sub-record-check:4] Set("Local/1202@from-queue-000004f1;2", "CALLEE=dontcare") in new stack
[2015-06-17 17:52:52]   [exten@sub-record-check:5] ExecIf("Local/1202@from-queue-000004f1;2", "0?Set(CALLEE=dontcare)") in new stack
[2015-06-17 17:52:52]   [exten@sub-record-check:6] GotoIf("Local/1202@from-queue-000004f1;2", "1?callee") in new stack
[2015-06-17 17:52:52]  pbx.c: -- Goto (sub-record-check,exten,11)
[2015-06-17 17:52:52]   [exten@sub-record-check:11] Gosub("Local/1202@from-queue-000004f1;2", "recordcheck,1(dontcare,external,1202)") in new stack
[2015-06-17 17:52:52]   [recordcheck@sub-record-check:1] NoOp("Local/1202@from-queue-000004f1;2", "Starting recording check against dontcare") in new stack
[2015-06-17 17:52:52]   [recordcheck@sub-record-check:2] Goto("Local/1202@from-queue-000004f1;2", "dontcare") in new stack
[2015-06-17 17:52:52]  pbx.c: -- Goto (sub-record-check,recordcheck,3)
[2015-06-17 17:52:52]   [recordcheck@sub-record-check:3] Return("Local/1202@from-queue-000004f1;2", "") in new stack
[2015-06-17 17:52:52]   [exten@sub-record-check:12] Return("Local/1202@from-queue-000004f1;2", "") in new stack
[2015-06-17 17:52:52]   [s@macro-exten-vm:9] Macro("Local/1202@from-queue-000004f1;2", "dial-one,15,TtrM(auto-blkvm),1202") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:1] Set("Local/1202@from-queue-000004f1;2", "DEXTEN=1202") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:2] Set("Local/1202@from-queue-000004f1;2", "DIALSTATUS_CW=") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:3] GosubIf("Local/1202@from-queue-000004f1;2", "0?screen,1()") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:4] GosubIf("Local/1202@from-queue-000004f1;2", "0?cf,1()") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:5] GotoIf("Local/1202@from-queue-000004f1;2", "1?skip1") in new stack
[2015-06-17 17:52:52]  pbx.c: -- Goto (macro-dial-one,s,8)
[2015-06-17 17:52:52]   [s@macro-dial-one:8] GotoIf("Local/1202@from-queue-000004f1;2", "0?nodial") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:9] GotoIf("Local/1202@from-queue-000004f1;2", "0?continue") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:10] Set("Local/1202@from-queue-000004f1;2", "EXTHASCW=") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:11] GotoIf("Local/1202@from-queue-000004f1;2", "1?next1:cwinusebusy") in new stack
[2015-06-17 17:52:52]  pbx.c: -- Goto (macro-dial-one,s,12)
[2015-06-17 17:52:52] VERBOSE[13041][C-00000358]  [ctset@macro-dial-one:2] Return("Local/1104@from-queue-000004f3;2", "") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:12] GotoIf("Local/1202@from-queue-000004f1;2", "0?docfu:skip3") in new stack
[2015-06-17 17:52:52]  pbx.c: -- Goto (macro-dial-one,s,16)
[2015-06-17 17:52:52]   [s@macro-dial-one:16] GotoIf("Local/1202@from-queue-000004f1;2", "1?next2:continue") in new stack
[2015-06-17 17:52:52]  pbx.c: -- Goto (macro-dial-one,s,17)
[2015-06-17 17:52:52]   [s@macro-dial-one:17] GotoIf("Local/1202@from-queue-000004f1;2", "1?continue") in new stack
[2015-06-17 17:52:52]  pbx.c: -- Goto (macro-dial-one,s,25)
[2015-06-17 17:52:52]   [s@macro-dial-one:25] GotoIf("Local/1202@from-queue-000004f1;2", "0?nodial") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:26] GosubIf("Local/1202@from-queue-000004f1;2", "1?dstring,1():dlocal,1()") in new stack
[2015-06-17 17:52:52]   [dstring@macro-dial-one:1] Set("Local/1202@from-queue-000004f1;2", "DSTRING=") in new stack
[2015-06-17 17:52:52]   [dstring@macro-dial-one:2] Set("Local/1202@from-queue-000004f1;2", "DEVICES=1202") in new stack
[2015-06-17 17:52:52]   [dstring@macro-dial-one:3] ExecIf("Local/1202@from-queue-000004f1;2", "0?Return()") in new stack
[2015-06-17 17:52:52]   [dstring@macro-dial-one:4] ExecIf("Local/1202@from-queue-000004f1;2", "0?Set(DEVICES=202)") in new stack
[2015-06-17 17:52:52]   [dstring@macro-dial-one:5] Set("Local/1202@from-queue-000004f1;2", "LOOPCNT=1") in new stack
[2015-06-17 17:52:52]   [dstring@macro-dial-one:6] Set("Local/1202@from-queue-000004f1;2", "ITER=1") in new stack
[2015-06-17 17:52:52]   [dstring@macro-dial-one:7] Set("Local/1202@from-queue-000004f1;2", "THISDIAL=SIP/1202") in new stack
[2015-06-17 17:52:52]   [dstring@macro-dial-one:8] GosubIf("Local/1202@from-queue-000004f1;2", "1?zap2dahdi,1()") in new stack
[2015-06-17 17:52:52]   [zap2dahdi@macro-dial-one:1] ExecIf("Local/1202@from-queue-000004f1;2", "0?Return()") in new stack
[2015-06-17 17:52:52]   [zap2dahdi@macro-dial-one:2] Set("Local/1202@from-queue-000004f1;2", "NEWDIAL=") in new stack
[2015-06-17 17:52:52]   [zap2dahdi@macro-dial-one:3] Set("Local/1202@from-queue-000004f1;2", "LOOPCNT2=1") in new stack
[2015-06-17 17:52:52]   [zap2dahdi@macro-dial-one:4] Set("Local/1202@from-queue-000004f1;2", "ITER2=1") in new stack
[2015-06-17 17:52:52]   [zap2dahdi@macro-dial-one:5] Set("Local/1202@from-queue-000004f1;2", "THISPART2=SIP/1202") in new stack
[2015-06-17 17:52:52]   [zap2dahdi@macro-dial-one:6] ExecIf("Local/1202@from-queue-000004f1;2", "0?Set(THISPART2=DAHDI/1202)") in new stack
[2015-06-17 17:52:52]   [zap2dahdi@macro-dial-one:7] Set("Local/1202@from-queue-000004f1;2", "NEWDIAL=SIP/1202&") in new stack
[2015-06-17 17:52:52]   [zap2dahdi@macro-dial-one:8] Set("Local/1202@from-queue-000004f1;2", "ITER2=2") in new stack
[2015-06-17 17:52:52]   [zap2dahdi@macro-dial-one:9] GotoIf("Local/1202@from-queue-000004f1;2", "0?begin2") in new stack
[2015-06-17 17:52:52]   [zap2dahdi@macro-dial-one:10] Set("Local/1202@from-queue-000004f1;2", "THISDIAL=SIP/1202") in new stack
[2015-06-17 17:52:52]   [zap2dahdi@macro-dial-one:11] Return("Local/1202@from-queue-000004f1;2", "") in new stack
[2015-06-17 17:52:52]   [dstring@macro-dial-one:9] GotoIf("Local/1202@from-queue-000004f1;2", "1?doset") in new stack
[2015-06-17 17:52:52]  pbx.c: -- Goto (macro-dial-one,dstring,12)
[2015-06-17 17:52:52]   [dstring@macro-dial-one:12] Set("Local/1202@from-queue-000004f1;2", "DSTRING=SIP/1202&") in new stack
[2015-06-17 17:52:52]   [dstring@macro-dial-one:13] Set("Local/1202@from-queue-000004f1;2", "ITER=2") in new stack
[2015-06-17 17:52:52]   [dstring@macro-dial-one:14] GotoIf("Local/1202@from-queue-000004f1;2", "0?begin") in new stack
[2015-06-17 17:52:52]   [dstring@macro-dial-one:15] Set("Local/1202@from-queue-000004f1;2", "DSTRING=SIP/1202") in new stack
[2015-06-17 17:52:52]   [dstring@macro-dial-one:16] Return("Local/1202@from-queue-000004f1;2", "") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:27] GotoIf("Local/1202@from-queue-000004f1;2", "0?nodial") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:28] GotoIf("Local/1202@from-queue-000004f1;2", "0?skiptrace") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:29] GosubIf("Local/1202@from-queue-000004f1;2", "1?ctset,1():ctclear,1()") in new stack
[2015-06-17 17:52:52]   [ctset@macro-dial-one:1] Set("Local/1202@from-queue-000004f1;2", "DB(CALLTRACE/1202)=89823435698") in new stack
[2015-06-17 17:52:52]   [ctset@macro-dial-one:2] Return("Local/1202@from-queue-000004f1;2", "") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:30] Set("Local/1202@from-queue-000004f1;2", "EVENT_TYPE="DirectCall"") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:31] Set("Local/1202@from-queue-000004f1;2", "CALLER_NUMBER=89823435698") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:32] NoOp("Local/1202@from-queue-000004f1;2", "1202,1202") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:33] Set("Local/1202@from-queue-000004f1;2", "D_OPTIONS=TtrM(auto-blkvm)M(1c-event,"DirectCall",89823435698,1202,1434545572.5145,)") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:34] ExecIf("Local/1202@from-queue-000004f1;2", "0?SIPAddHeader(Alert-Info: )") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:35] ExecIf("Local/1202@from-queue-000004f1;2", "0?SIPAddHeader()") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:36] ExecIf("Local/1202@from-queue-000004f1;2", "1?Set(CHANNEL(musicclass)=default)") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:37] GosubIf("Local/1202@from-queue-000004f1;2", "0?qwait,1()") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:38] Set("Local/1202@from-queue-000004f1;2", "__CWIGNORE=TRUE") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:39] Set("Local/1202@from-queue-000004f1;2", "__KEEPCID=TRUE") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:40] GotoIf("Local/1202@from-queue-000004f1;2", "0?usegoto,1") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:41] GotoIf("Local/1202@from-queue-000004f1;2", "1?godial") in new stack
[2015-06-17 17:52:52]  pbx.c: -- Goto (macro-dial-one,s,46)
[2015-06-17 17:52:52]   [s@macro-dial-one:46] Macro("Local/1202@from-queue-000004f1;2", "dialout-one-predial-hook,") in new stack
[2015-06-17 17:52:52]   [s@macro-dialout-one-predial-hook:1] MacroExit("Local/1202@from-queue-000004f1;2", "") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:47] Dial("Local/1202@from-queue-000004f1;2", "SIP/1202,15,TtrM(auto-blkvm)M(1c-event,"DirectCall",89823435698,1202,1434545572.5145,)") in new stack
[2015-06-17 17:52:52]  netsock2.c: == Using SIP RTP TOS bits 184
[2015-06-17 17:52:52]  netsock2.c: == Using SIP RTP CoS mark 5
[2015-06-17 17:52:52]  app_dial.c: -- Called SIP/1104
[2015-06-17 17:52:52]  app_queue.c: -- Local/1104@from-queue-000004f3;1 is ringing
[2015-06-17 17:52:52]  app_queue.c: -- Local/1104@from-queue-000004f3;1 connected line has changed. Saving it until answer for SIP/418027-000009cb
[2015-06-17 17:52:52]  app_queue.c: -- Local/1104@from-queue-000004f3;1 connected line has changed. Saving it until answer for SIP/418027-000009cb
[2015-06-17 17:52:52]  app_dial.c: -- Called SIP/1202
[2015-06-17 17:52:52]  app_queue.c: -- Local/1202@from-queue-000004f1;1 is ringing
[2015-06-17 17:52:52]  app_queue.c: -- Local/1202@from-queue-000004f1;1 connected line has changed. Saving it until answer for SIP/418027-000009cb
[2015-06-17 17:52:52]  app_queue.c: -- Local/1202@from-queue-000004f1;1 connected line has changed. Saving it until answer for SIP/418027-000009cb
[2015-06-17 17:52:52]  app_dial.c: -- SIP/1202-000009cf is ringing
[2015-06-17 17:52:52]  app_queue.c: -- Local/1202@from-queue-000004f1;1 is ringing
[2015-06-17 17:52:52]  app_dial.c: -- SIP/1108-000009ce is ringing
[2015-06-17 17:52:52]  app_queue.c: -- Local/1108@from-queue-000004f2;1 is ringing
[2015-06-17 17:52:52]  app_dial.c: -- SIP/1104-000009cc is ringing
[2015-06-17 17:52:52]  app_queue.c: -- Local/1104@from-queue-000004f3;1 is ringing
[2015-06-17 17:52:52]  app_dial.c: -- SIP/1107-000009cd is ringing
[2015-06-17 17:52:52]  app_queue.c: -- Local/1107@from-queue-000004f4;1 is ringing
[2015-06-17 17:52:52]  app_dial.c: -- SIP/1104-000009cc is ringing
[2015-06-17 17:52:53]  app_dial.c: -- SIP/1104-000009cc is ringing
[2015-06-17 17:52:55]  app_dial.c: -- SIP/1104-000009cc is ringing
[2015-06-17 17:53:02]  app_dial.c: -- SIP/1202-000009cf answered Local/1202@from-queue-000004f1;2
[2015-06-17 17:53:02]   [s@macro-user-event:1] GotoIf("SIP/1202-000009cf", "1?direct:somecall") in new stack
[2015-06-17 17:53:02]  pbx.c: -- Goto (macro-user-event,s,2)
[2015-06-17 17:53:02]   [s@macro-user-event:2] GotoIf("SIP/1202-000009cf", "0?transfer") in new stack
[2015-06-17 17:53:02]   [s@macro-user-event:3] Set("SIP/1202-000009cf", "_BACKUP_RECENT_CALLER=") in new stack
[2015-06-17 17:53:02]   [s@macro-user-event:4] Set("SIP/1202-000009cf", "_BACKUP_RECENT_CALLEE=") in new stack
[2015-06-17 17:53:02]   [s@macro-user-event:5] Set("SIP/1202-000009cf", "__RECENT_CALLER=89823435698") in new stack
[2015-06-17 17:53:02]   [s@macro-user-event:6] Set("SIP/1202-000009cf", "__RECENT_CALLEE=1202") in new stack
[2015-06-17 17:53:02]   [s@macro-user-event:7] Set("SIP/1202-000009cf", "__Direct="NO"") in new stack
[2015-06-17 17:53:02]   [s@macro-user-event:8] GosubIf("SIP/1202-000009cf", "1?record,1()") in new stack
[2015-06-17 17:53:02]   [record@macro-user-event:1] Set("SIP/1202-000009cf", "__REC_STATUS=INITIALIZED") in new stack
[2015-06-17 17:53:02]   [record@macro-user-event:2] Set("SIP/1202-000009cf", "NOW=1434545582") in new stack
[2015-06-17 17:53:02]   [record@macro-user-event:3] Set("SIP/1202-000009cf", "__DAY=17") in new stack
[2015-06-17 17:53:02]   [record@macro-user-event:4] Set("SIP/1202-000009cf", "__MONTH=06") in new stack
[2015-06-17 17:53:02]   [record@macro-user-event:5] Set("SIP/1202-000009cf", "__YEAR=2015") in new stack
[2015-06-17 17:53:02]   [record@macro-user-event:6] Set("SIP/1202-000009cf", "__TIMESTR=20150617-175302") in new stack
[2015-06-17 17:53:02]   [record@macro-user-event:7] Set("SIP/1202-000009cf", "__MON_FMT=wav") in new stack
[2015-06-17 17:53:02]   [record@macro-user-event:8] NoOp("SIP/1202-000009cf", "Starting recording: 89823435698, 1202") in new stack
[2015-06-17 17:53:02]   [record@macro-user-event:9] Set("SIP/1202-000009cf", "AUDIOHOOK_INHERIT(MixMonitor)=yes") in new stack
[2015-06-17 17:53:02]   [record@macro-user-event:10] Set("SIP/1202-000009cf", "__CALLFILENAME=DirectCall-89823435698-1202-20150617-175302-1434545572.5145") in new stack
[2015-06-17 17:53:02]   [record@macro-user-event:11] MixMonitor("SIP/1202-000009cf", "2015/06/17/DirectCall-89823435698-1202-20150617-175302-1434545572.5145.wav,ai(LOCAL_MIXMON_ID),") in new stack
[2015-06-17 17:53:02]   [record@macro-user-event:12] Set("SIP/1202-000009cf", "__MIXMON_ID=0x7f93045cd090") in new stack
[2015-06-17 17:53:02]   [record@macro-user-event:13] Set("SIP/1202-000009cf", "__RECORD_ID=SIP/1202-000009cf") in new stack
[2015-06-17 17:53:02]   [record@macro-user-event:14] Set("SIP/1202-000009cf", "__REC_STATUS=RECORDING") in new stack
[2015-06-17 17:53:02]   [record@macro-user-event:15] Set("SIP/1202-000009cf", "CDR(recordingfile)=DirectCall-89823435698-1202-20150617-175302-1434545572.5145.wav") in new stack
[2015-06-17 17:53:02]   [record@macro-user-event:16] Return("SIP/1202-000009cf", "") in new stack
[2015-06-17 17:53:02]   [s@macro-user-event:9] UserEvent("SIP/1202-000009cf", "DirectCall,CALLER: 89823435698,CALLEE: 1202,CALLUNIQUEID: 1434545572.5145,FILENAME: DirectCall-89823435698-1202-20150617-175302-1434545572.5145") in new stack
[2015-06-17 17:53:02]   [s@macro-user-event:10] MacroExit("SIP/1202-000009cf", "") in new stack

[2015-06-17 18:05:01]   [1104@from-internal-xfer:1] Set("Local/1104@from-internal-xfer-000004f5;2", "__RINGTIMER=15") in new stack
[2015-06-17 18:05:01]   [1104@from-internal-xfer:2] Macro("Local/1104@from-internal-xfer-000004f5;2", "exten-vm,novm,1104,1,1,1") in new stack
[2015-06-17 18:05:01]   [s@macro-exten-vm:1] Macro("Local/1104@from-internal-xfer-000004f5;2", "user-callerid,") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:1] Set("Local/1104@from-internal-xfer-000004f5;2", "TOUCH_MONITOR=1434546301.5158") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:2] Set("Local/1104@from-internal-xfer-000004f5;2", "AMPUSER=1202") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:3] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "0?report") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:4] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "1?Set(REALCALLERIDNUM=1202)") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:5] Set("Local/1104@from-internal-xfer-000004f5;2", "AMPUSER=1202") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:6] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "0?limit") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:7] Set("Local/1104@from-internal-xfer-000004f5;2", "AMPUSERCIDNAME=kuan") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:8] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "0?report") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:9] Set("Local/1104@from-internal-xfer-000004f5;2", "AMPUSERCID=1202") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:10] Set("Local/1104@from-internal-xfer-000004f5;2", "__DIAL_OPTIONS=Ttr") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:11] Set("Local/1104@from-internal-xfer-000004f5;2", "CALLERID(all)="kuan" <1202>") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:12] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "0?limit") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:13] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "0?Set(GROUP(concurrency_limit)=1202)") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:14] GosubIf("Local/1104@from-internal-xfer-000004f5;2", "7?sub-ccss,s,1(macro-exten-vm,)") in new stack
[2015-06-17 18:05:01]   [s@sub-ccss:1] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "0?Return()") in new stack
[2015-06-17 18:05:01]   [s@sub-ccss:2] Set("Local/1104@from-internal-xfer-000004f5;2", "CCSS_SETUP=TRUE") in new stack
[2015-06-17 18:05:01]   [s@sub-ccss:3] GosubIf("Local/1104@from-internal-xfer-000004f5;2", "0?monitor_config,1(macro-exten-vm,):monitor_default,1(macro-exten-vm,)") in new stack
[2015-06-17 18:05:01]   [monitor_default@sub-ccss:1] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "0?is_exten") in new stack
[2015-06-17 18:05:01]   [monitor_default@sub-ccss:2] StackPop("Local/1104@from-internal-xfer-000004f5;2", "") in new stack
[2015-06-17 18:05:01]   [monitor_default@sub-ccss:3] Return("Local/1104@from-internal-xfer-000004f5;2", "FALSE") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:15] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "0?Set(CHANNEL(language)=)") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:16] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "0?continue") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:17] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:18] Set("Local/1104@from-internal-xfer-000004f5;2", "__TTL=63") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:19] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "1?continue") in new stack
[2015-06-17 18:05:01]  pbx.c: -- Goto (macro-user-callerid,s,30)
[2015-06-17 18:05:01]   [s@macro-user-callerid:30] Set("Local/1104@from-internal-xfer-000004f5;2", "CALLERID(number)=1202") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:31] Set("Local/1104@from-internal-xfer-000004f5;2", "CALLERID(name)=kuan") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:32] Set("Local/1104@from-internal-xfer-000004f5;2", "CDR(cnum)=1202") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:33] Set("Local/1104@from-internal-xfer-000004f5;2", "CDR(cnam)=kuan") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:34] DumpChan("Local/1104@from-internal-xfer-000004f5;2", "") in new stack
[2015-06-17 18:05:01]  app_dumpchan.c:
Dumping Info For Channel: Local/1104@from-internal-xfer-000004f5;2:
================================================================================
Info:
Name= Local/1104@from-internal-xfer-000004f5;2
Type= Local
UniqueID= 1434546301.5158
LinkedID= 1434545571.5143
CallerIDNum= 1202
CallerIDName= kuan
ConnectedLineIDNum= (N/A)
ConnectedLineIDName=(N/A)
DNIDDigits= (N/A)
RDNIS= (N/A)
Parkinglot=
Language= ru
State= Ring (4)
Rings= 0
NativeFormat= (alaw)
WriteFormat= alaw
ReadFormat= alaw
RawWriteFormat= alaw
RawReadFormat= alaw
WriteTranscode= No
ReadTranscode= No
1stFileDescriptor= -1
Framesin= 0
Framesout= 0
TimetoHangup= 0
ElapsedTime= 0h0m0s
DirectBridge= <none>
IndirectBridge= <none>
Context= macro-user-callerid
Extension= s
Priority= 34
CallGroup=
PickupGroup=
Application= DumpChan
Data= (Empty)
Blocking_in= (Not Blocking)

Variables:
MACRO_DEPTH=2
TTL=63
CALLEE_ACCOUNCODE=
DB_RESULT=
GOSUB_RETVAL=FALSE
CCSS_SETUP=TRUE
DIAL_OPTIONS=Ttr
AMPUSERCID=1202
AMPUSERCIDNAME=kuan
AMPUSER=1202
REALCALLERIDNUM=1202
TOUCH_MONITOR=1434546301.5158
ARG1=
MACRO_PRIORITY=1
MACRO_CONTEXT=macro-exten-vm
MACRO_EXTEN=s
ARG5=1
ARG4=1
ARG3=1
ARG2=1104
RINGTIMER=15
TRANSFERERNAME=Local/1202@from-queue-000004f1;1
MOHCLASS=default
CWIGNORE=TRUE
MON_FMT=wav
FROMEXTEN=89823435698
TIMESTR=20150617-175252
YEAR=2015
MONTH=06
DAY=17
REC_STATUS=INITIALIZED
NODEST=9002
BLKVM_CHANNEL=SIP/418027-000009cb
CALLINGPRES_SV=allowed_not_screened
FROM_DID=905936
SIPRDNISDOMAIN=10.14.0.2;user=phone
SIPREDIRECTREASON=unknown
PRIREDIRECTREASON=UNKNOWN
================================================================================
[2015-06-17 18:05:01]   [s@macro-user-callerid:35] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "1?xfer:end") in new stack
[2015-06-17 18:05:01]  pbx.c: -- Goto (macro-user-callerid,s,36)
[2015-06-17 18:05:01]   [s@macro-user-callerid:36] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "0?Set(CID_STRING=89823435698:from kuan):Set(CID_STRING=:from kuan)") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:37] Set("Local/1104@from-internal-xfer-000004f5;2", "CALLERID(name)=:from kuan") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:38] Set("Local/1104@from-internal-xfer-000004f5;2", "CHANNEL(language)=ru") in new stack
[2015-06-17 18:05:01]   [s@macro-exten-vm:2] Set("Local/1104@from-internal-xfer-000004f5;2", "RingGroupMethod=none") in new stack
[2015-06-17 18:05:01]   [s@macro-exten-vm:3] Set("Local/1104@from-internal-xfer-000004f5;2", "__EXTTOCALL=1104") in new stack
[2015-06-17 18:05:01]   [s@macro-exten-vm:4] Set("Local/1104@from-internal-xfer-000004f5;2", "__PICKUPMARK=1104") in new stack
[2015-06-17 18:05:01]   [s@macro-exten-vm:5] Set("Local/1104@from-internal-xfer-000004f5;2", "RT=15") in new stack
[2015-06-17 18:05:01]   [s@macro-exten-vm:6] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "0?Macro(vm,novm,DIRECTDIAL,)") in new stack
[2015-06-17 18:05:01]   [s@macro-exten-vm:7] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "0?MacroExit()") in new stack
[2015-06-17 18:05:01]   [s@macro-exten-vm:8] Gosub("Local/1104@from-internal-xfer-000004f5;2", "sub-record-check,s,1(exten,1104,dontcare)") in new stack
[2015-06-17 18:05:01]   [s@sub-record-check:1] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "11?initialized") in new stack
[2015-06-17 18:05:01]  pbx.c: -- Goto (sub-record-check,s,10)
[2015-06-17 18:05:01]   [s@sub-record-check:10] NoOp("Local/1104@from-internal-xfer-000004f5;2", "Recordings initialized") in new stack
[2015-06-17 18:05:01]   [s@sub-record-check:11] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "0?Set(__RRNODEST=)") in new stack
[2015-06-17 18:05:01]   [s@sub-record-check:12] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "0?Set(__NODEST=)") in new stack
[2015-06-17 18:05:01]   [s@sub-record-check:13] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "0?Set(ARG3=dontcare)") in new stack
[2015-06-17 18:05:01]   [s@sub-record-check:14] Set("Local/1104@from-internal-xfer-000004f5;2", "REC_POLICY_MODE_SAVE=") in new stack
[2015-06-17 18:05:01]   [s@sub-record-check:15] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "0?Set(REC_STATUS=NO)") in new stack
[2015-06-17 18:05:01]   [s@sub-record-check:16] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "5?checkaction") in new stack
[2015-06-17 18:05:01]  pbx.c: -- Goto (sub-record-check,s,19)
[2015-06-17 18:05:01]   [s@sub-record-check:19] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "1?sub-record-check,exten,1") in new stack
[2015-06-17 18:05:01]  pbx.c: -- Goto (sub-record-check,exten,1)
[2015-06-17 18:05:01]   [exten@sub-record-check:1] NoOp("Local/1104@from-internal-xfer-000004f5;2", "Exten Recording Check between 89823435698 and 1104") in new stack
[2015-06-17 18:05:01]   [exten@sub-record-check:2] Set("Local/1104@from-internal-xfer-000004f5;2", "CALLTYPE=external") in new stack
[2015-06-17 18:05:01]   [exten@sub-record-check:3] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "0?Set(CALLTYPE=)") in new stack
[2015-06-17 18:05:01]   [exten@sub-record-check:4] Set("Local/1104@from-internal-xfer-000004f5;2", "CALLEE=dontcare") in new stack
[2015-06-17 18:05:01]   [exten@sub-record-check:5] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "0?Set(CALLEE=dontcare)") in new stack
[2015-06-17 18:05:01]   [exten@sub-record-check:6] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "1?callee") in new stack
[2015-06-17 18:05:01]  pbx.c: -- Goto (sub-record-check,exten,11)
[2015-06-17 18:05:01]   [exten@sub-record-check:11] Gosub("Local/1104@from-internal-xfer-000004f5;2", "recordcheck,1(dontcare,external,1104)") in new stack
[2015-06-17 18:05:01]   [recordcheck@sub-record-check:1] NoOp("Local/1104@from-internal-xfer-000004f5;2", "Starting recording check against dontcare") in new stack
[2015-06-17 18:05:01]   [recordcheck@sub-record-check:2] Goto("Local/1104@from-internal-xfer-000004f5;2", "dontcare") in new stack
[2015-06-17 18:05:01]  pbx.c: -- Goto (sub-record-check,recordcheck,3)
[2015-06-17 18:05:01]   [recordcheck@sub-record-check:3] Return("Local/1104@from-internal-xfer-000004f5;2", "") in new stack
[2015-06-17 18:05:01]   [exten@sub-record-check:12] Return("Local/1104@from-internal-xfer-000004f5;2", "") in new stack
[2015-06-17 18:05:01]   [s@macro-exten-vm:9] Macro("Local/1104@from-internal-xfer-000004f5;2", "dial-one,15,Ttr,1104") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:1] Set("Local/1104@from-internal-xfer-000004f5;2", "DEXTEN=1104") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:2] Set("Local/1104@from-internal-xfer-000004f5;2", "DIALSTATUS_CW=") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:3] GosubIf("Local/1104@from-internal-xfer-000004f5;2", "0?screen,1()") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:4] GosubIf("Local/1104@from-internal-xfer-000004f5;2", "0?cf,1()") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:5] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "1?skip1") in new stack
[2015-06-17 18:05:01]  pbx.c: -- Goto (macro-dial-one,s,8)
[2015-06-17 18:05:01]   [s@macro-dial-one:8] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "0?nodial") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:9] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "0?continue") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:10] Set("Local/1104@from-internal-xfer-000004f5;2", "EXTHASCW=") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:11] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "1?next1:cwinusebusy") in new stack
[2015-06-17 18:05:01]  pbx.c: -- Goto (macro-dial-one,s,12)
[2015-06-17 18:05:01]   [s@macro-dial-one:12] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "0?docfu:skip3") in new stack
[2015-06-17 18:05:01]  pbx.c: -- Goto (macro-dial-one,s,16)
[2015-06-17 18:05:01]   [s@macro-dial-one:16] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "1?next2:continue") in new stack
[2015-06-17 18:05:01]  pbx.c: -- Goto (macro-dial-one,s,17)
[2015-06-17 18:05:01]   [s@macro-dial-one:17] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "1?continue") in new stack
[2015-06-17 18:05:01]  pbx.c: -- Goto (macro-dial-one,s,25)
[2015-06-17 18:05:01]   [s@macro-dial-one:25] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "0?nodial") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:26] GosubIf("Local/1104@from-internal-xfer-000004f5;2", "1?dstring,1():dlocal,1()") in new stack
[2015-06-17 18:05:01]   [dstring@macro-dial-one:1] Set("Local/1104@from-internal-xfer-000004f5;2", "DSTRING=") in new stack
[2015-06-17 18:05:01]   [dstring@macro-dial-one:2] Set("Local/1104@from-internal-xfer-000004f5;2", "DEVICES=1104") in new stack
[2015-06-17 18:05:01]   [dstring@macro-dial-one:3] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "0?Return()") in new stack
[2015-06-17 18:05:01]   [dstring@macro-dial-one:4] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "0?Set(DEVICES=104)") in new stack
[2015-06-17 18:05:01]   [dstring@macro-dial-one:5] Set("Local/1104@from-internal-xfer-000004f5;2", "LOOPCNT=1") in new stack
[2015-06-17 18:05:01]   [dstring@macro-dial-one:6] Set("Local/1104@from-internal-xfer-000004f5;2", "ITER=1") in new stack
[2015-06-17 18:05:01]   [dstring@macro-dial-one:7] Set("Local/1104@from-internal-xfer-000004f5;2", "THISDIAL=SIP/1104") in new stack
[2015-06-17 18:05:01]   [dstring@macro-dial-one:8] GosubIf("Local/1104@from-internal-xfer-000004f5;2", "1?zap2dahdi,1()") in new stack
[2015-06-17 18:05:01]   [zap2dahdi@macro-dial-one:1] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "0?Return()") in new stack
[2015-06-17 18:05:01]   [zap2dahdi@macro-dial-one:2] Set("Local/1104@from-internal-xfer-000004f5;2", "NEWDIAL=") in new stack
[2015-06-17 18:05:01]   [zap2dahdi@macro-dial-one:3] Set("Local/1104@from-internal-xfer-000004f5;2", "LOOPCNT2=1") in new stack
[2015-06-17 18:05:01]   [zap2dahdi@macro-dial-one:4] Set("Local/1104@from-internal-xfer-000004f5;2", "ITER2=1") in new stack
[2015-06-17 18:05:01]   [zap2dahdi@macro-dial-one:5] Set("Local/1104@from-internal-xfer-000004f5;2", "THISPART2=SIP/1104") in new stack
[2015-06-17 18:05:01]   [zap2dahdi@macro-dial-one:6] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "0?Set(THISPART2=DAHDI/1104)") in new stack
[2015-06-17 18:05:01]   [zap2dahdi@macro-dial-one:7] Set("Local/1104@from-internal-xfer-000004f5;2", "NEWDIAL=SIP/1104&") in new stack
[2015-06-17 18:05:01]   [zap2dahdi@macro-dial-one:8] Set("Local/1104@from-internal-xfer-000004f5;2", "ITER2=2") in new stack
[2015-06-17 18:05:01]   [zap2dahdi@macro-dial-one:9] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "0?begin2") in new stack
[2015-06-17 18:05:01]   [zap2dahdi@macro-dial-one:10] Set("Local/1104@from-internal-xfer-000004f5;2", "THISDIAL=SIP/1104") in new stack
[2015-06-17 18:05:01]   [zap2dahdi@macro-dial-one:11] Return("Local/1104@from-internal-xfer-000004f5;2", "") in new stack
[2015-06-17 18:05:01]   [dstring@macro-dial-one:9] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "1?doset") in new stack
[2015-06-17 18:05:01]  pbx.c: -- Goto (macro-dial-one,dstring,12)
[2015-06-17 18:05:01]   [dstring@macro-dial-one:12] Set("Local/1104@from-internal-xfer-000004f5;2", "DSTRING=SIP/1104&") in new stack
[2015-06-17 18:05:01]   [dstring@macro-dial-one:13] Set("Local/1104@from-internal-xfer-000004f5;2", "ITER=2") in new stack
[2015-06-17 18:05:01]   [dstring@macro-dial-one:14] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "0?begin") in new stack
[2015-06-17 18:05:01]   [dstring@macro-dial-one:15] Set("Local/1104@from-internal-xfer-000004f5;2", "DSTRING=SIP/1104") in new stack
[2015-06-17 18:05:01]   [dstring@macro-dial-one:16] Return("Local/1104@from-internal-xfer-000004f5;2", "") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:27] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "0?nodial") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:28] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "0?skiptrace") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:29] GosubIf("Local/1104@from-internal-xfer-000004f5;2", "1?ctset,1():ctclear,1()") in new stack
[2015-06-17 18:05:01]   [ctset@macro-dial-one:1] Set("Local/1104@from-internal-xfer-000004f5;2", "DB(CALLTRACE/1104)=1202") in new stack
[2015-06-17 18:05:01]   [ctset@macro-dial-one:2] Return("Local/1104@from-internal-xfer-000004f5;2", "") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:30] Set("Local/1104@from-internal-xfer-000004f5;2", "EVENT_TYPE=TransferCall") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:31] Set("Local/1104@from-internal-xfer-000004f5;2", "CALLER_NUMBER=89823435698") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:32] NoOp("Local/1104@from-internal-xfer-000004f5;2", "1104,1104") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:33] Set("Local/1104@from-internal-xfer-000004f5;2", "D_OPTIONS=TtrM(auto-blkvm,TransferCall,89823435698,1104,1434546301.5158,)") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:34] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "0?SIPAddHeader(Alert-Info: )") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:35] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "0?SIPAddHeader()") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:36] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "1?Set(CHANNEL(musicclass)=default)") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:37] GosubIf("Local/1104@from-internal-xfer-000004f5;2", "0?qwait,1()") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:38] Set("Local/1104@from-internal-xfer-000004f5;2", "__CWIGNORE=TRUE") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:39] Set("Local/1104@from-internal-xfer-000004f5;2", "__KEEPCID=TRUE") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:40] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "0?usegoto,1") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:41] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "0?godial") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:42] Gosub("Local/1104@from-internal-xfer-000004f5;2", "sub-presencestate-display,s,1(1104)") in new stack
[2015-06-17 18:05:01] WARNING[13420][C-00000358] func_presencestate.c: PRESENCE_STATE unknown
[2015-06-17 18:05:01]   [s@sub-presencestate-display:1] Goto("Local/1104@from-internal-xfer-000004f5;2", "state-,1") in new stack
[2015-06-17 18:05:01]  pbx.c: -- Goto (sub-presencestate-display,state-,1)
[2015-06-17 18:05:01]   [state-@sub-presencestate-display:1] Set("Local/1104@from-internal-xfer-000004f5;2", "PRESENCESTATE_DISPLAY=") in new stack
[2015-06-17 18:05:01]   [state-@sub-presencestate-display:2] Return("Local/1104@from-internal-xfer-000004f5;2", "") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:43] Set("Local/1104@from-internal-xfer-000004f5;2", "CONNECTEDLINE(name,i)=miva") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:44] Set("Local/1104@from-internal-xfer-000004f5;2", "CONNECTEDLINE(num)=1104") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:45] Set("Local/1104@from-internal-xfer-000004f5;2", "D_OPTIONS=TtrM(auto-blkvm,TransferCall,89823435698,1104,1434546301.5158,)I") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:46] Macro("Local/1104@from-internal-xfer-000004f5;2", "dialout-one-predial-hook,") in new stack
[2015-06-17 18:05:01]   [s@macro-dialout-one-predial-hook:1] MacroExit("Local/1104@from-internal-xfer-000004f5;2", "") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:47] Dial("Local/1104@from-internal-xfer-000004f5;2", "SIP/1104,15,TtrM(auto-blkvm,TransferCall,89823435698,1104,1434546301.5158,)I") in new stack
[2015-06-17 18:05:01]  netsock2.c: == Using SIP RTP TOS bits 184
[2015-06-17 18:05:01]  netsock2.c: == Using SIP RTP CoS mark 5
[2015-06-17 18:05:01]  app_dial.c: -- Called SIP/1104
[2015-06-17 18:05:01]  app_dial.c: -- Connected line update to Local/1104@from-internal-xfer-000004f5;2 prevented.
[2015-06-17 18:05:01]  features.c: -- Local/1104@from-internal-xfer-000004f5;1 is ringing
[2015-06-17 18:05:01]  app_dial.c: -- SIP/1104-000009d1 is ringing
[2015-06-17 18:05:01]  features.c: -- Local/1104@from-internal-xfer-000004f5;1 is ringing
[2015-06-17 18:05:02]  app_dial.c: -- SIP/1104-000009d1 is ringing
[2015-06-17 18:05:03]  app_dial.c: -- SIP/1104-000009d1 is ringing
[2015-06-17 18:05:05]  app_dial.c: -- SIP/1104-000009d1 is ringing
[2015-06-17 18:05:08]  app_dial.c: -- Connected line update to Local/1104@from-internal-xfer-000004f5;2 prevented.
[2015-06-17 18:05:08]  app_dial.c: -- SIP/1104-000009d1 answered Local/1104@from-internal-xfer-000004f5;2
[2015-06-17 18:05:08]   [s@macro-auto-blkvm:1] Set("SIP/1104-000009d1", "__MACRO_RESULT=") in new stack
[2015-06-17 18:05:08]   [s@macro-auto-blkvm:2] Set("SIP/1104-000009d1", "CFIGNORE=") in new stack
[2015-06-17 18:05:08]   [s@macro-auto-blkvm:3] Set("SIP/1104-000009d1", "MASTER_CHANNEL(CFIGNORE)=") in new stack
[2015-06-17 18:05:08]   [s@macro-auto-blkvm:4] Set("SIP/1104-000009d1", "FORWARD_CONTEXT=from-internal") in new stack
[2015-06-17 18:05:08]   [s@macro-auto-blkvm:5] Set("SIP/1104-000009d1", "MASTER_CHANNEL(FORWARD_CONTEXT)=from-internal") in new stack
[2015-06-17 18:05:08]   [s@macro-auto-blkvm:6] Macro("SIP/1104-000009d1", "1c-event,TransferCall,89823435698,1104,1434546301.5158,") in new stack
[2015-06-17 18:05:08]   [s@macro-user-event:1] GotoIf("SIP/1104-000009d1", "0?direct:somecall") in new stack
[2015-06-17 18:05:08]  pbx.c: -- Goto (macro-user-event,s,11)
[2015-06-17 18:05:08]   [s@macro-user-event:11] GotoIf("SIP/1104-000009d1", "0?group:transfer") in new stack
[2015-06-17 18:05:08]  pbx.c: -- Goto (macro-user-event,s,12)
[2015-06-17 18:05:08]   [s@macro-user-event:12] Set("SIP/1104-000009d1", "_BACKUP_RECENT_CALLER=") in new stack
[2015-06-17 18:05:08]   [s@macro-user-event:13] Set("SIP/1104-000009d1", "_BACKUP_RECENT_CALLEE=") in new stack
[2015-06-17 18:05:08]   [s@macro-user-event:14] Set("SIP/1104-000009d1", "__RECENT_CALLER=") in new stack
[2015-06-17 18:05:08]   [s@macro-user-event:15] Set("SIP/1104-000009d1", "__RECENT_CALLEE=1104") in new stack
[2015-06-17 18:05:08]   [s@macro-user-event:16] Set("SIP/1104-000009d1", "ARG1="TransferCall"") in new stack
[2015-06-17 18:05:08]   [s@macro-user-event:17] GosubIf("SIP/1104-000009d1", "1?record,1()") in new stack

Доброго времени суток. Подскажите пожалуйста куда копать никак не пойму. Возможно просто не могу сформулировать вопрос для гугла, возможно проблема специфическая. Что имею :

1)Asterisk (Ver. 11.17.1)

2)FreePBX 12.0.66

3)Небольшие дополнения в extension_override-freepbx.conf(Цель-создание юзер эвентов при подъеме трубки).

Проблема. При диале вызывается макрос, который устанавливает канальные наследуемые переменные(надеюсь я правильно написал). Вообщем выглядит так:

    [macro-dial-one]
....
exten => s,n,Set(D_OPTIONS=${IF($["${NODEST}"!="" & ${REGEX("(M[(]auto-blkvm[)])" ${ARG2})} != 1]?${ARG2}M(auto-blkvm,${EVENT_TYPE},${CALLER_NUMBER},${DEXTEN},${UNIQUEID},${CALLFILENAME}):${ARG2}M(user-event,${EVENT_TYPE},${CALLER_NUMBER},${DEXTEN},${UNIQUEID},${CALLFILENAME}))})
exten => s,n,Dial(${DSTRING},${ARG1},${D_OPTIONS})

[macro-user-event]
exten => s,1,GotoIf($["${ARG1}"="DirectCall"]?direct:transfer)
exten => s,n,Set(_BACKUP_RECENT_CALLER=${RECENT_CALLER})
exten => s,n,Set(_BACKUP_RECENT_CALLEE=${RECENT_CALLEE})
exten => s,n,Set(__RECENT_CALLER=${ARG2})
exten => s,n,Set(__RECENT_CALLEE=${ARG3})
exten => s,n,GosubIf($["${ARG5}"!="outgoing"]?record,1())
exten => s,n,UserEvent(${ARG1},CALLER: ${ARG2},CALLEE: ${ARG3},CALLUNIQUEID: ${ARG4},FILENAME: ${CALLFILENAME})
exten => s,n,MacroExit()
exten => s,n(transfer),Set(_BACKUP_RECENT_CALLER=${RECENT_CALLER})
exten => s,n,Set(_BACKUP_RECENT_CALLEE=${RECENT_CALLEE})
exten => s,n,Set(__RECENT_CALLER=${IF($["${FROMEXTEN}"="${BACKUP_RECENT_CALLER}"]?${BACKUP_RECENT_CALLEE}:${BACKUP_RECENT_CALLER})})
exten => s,n,Set(__RECENT_CALLEE=${ARG3})
exten => s,n,Set(ARG1="TransferCall")
exten => s,n,GosubIf($["${ARG5}"!="outgoing"]?record,1())
exten => s,n,UserEvent(${ARG1},CALLER: ${RECENT_CALLER},CALLEE: ${ARG3},CALLUNIQUEID: ${ARG4},FILENAME: ${CALLFILENAME})
exten => s,n,MacroExit()

Суть проблемы: При условных переводах иногда возникает ошибка(примерно раз в день) из 20-30 переводов, на разных экстеншенах вне зависимости от времени в самом начале при попадании перевода в from-internal-xfer отсутствуют переменные заведенные в маросах. С непереведенными звонками такого не происходит.

P.S. Я извиняюсь за ошибки и вообще не умею письменно излагать мысли. Буду благодарен за любую помощь/направление. Всем заранее огромное спасибо.

апдейт! Логи. Вот пришел звонок.Переменные установились __RECENT_CALLER:

   [2015-06-17 17:52:52]   [1202@from-queue:1] Set("Local/1202@from-queue-000004f1;2", "QAGENT=1202") in new stack
[2015-06-17 17:52:52]   [1202@from-queue:2] Goto("Local/1202@from-queue-000004f1;2", "9002,1") in new stack
[2015-06-17 17:52:52]  pbx.c: -- Goto (from-queue,9002,1)
[2015-06-17 17:52:52]   [9002@from-queue:1] Goto("Local/1202@from-queue-000004f1;2", "from-internal,1202,1") in new stack
[2015-06-17 17:52:52]  pbx.c: -- Goto (from-internal,1202,1)
[2015-06-17 17:52:52]   [1202@from-internal:1] Set("Local/1202@from-queue-000004f1;2", "__RINGTIMER=15") in new stack
[2015-06-17 17:52:52]   [1202@from-internal:2] Macro("Local/1202@from-queue-000004f1;2", "exten-vm,novm,1202,1,1,1") in new stack
[2015-06-17 17:52:52]   [s@macro-exten-vm:1] Macro("Local/1202@from-queue-000004f1;2", "user-callerid,") in new stack
[2015-06-17 17:52:52]   [s@macro-user-callerid:1] Set("Local/1202@from-queue-000004f1;2", "TOUCH_MONITOR=1434545572.5145") in new stack
[2015-06-17 17:52:52]   [s@macro-user-callerid:2] Set("Local/1202@from-queue-000004f1;2", "AMPUSER=89823435698") in new stack
[2015-06-17 17:52:52]   [s@macro-user-callerid:3] GotoIf("Local/1202@from-queue-000004f1;2", "1?report") in new stack
[2015-06-17 17:52:52]  pbx.c: -- Goto (macro-user-callerid,s,16)
[2015-06-17 17:52:52]   [s@macro-user-callerid:16] GotoIf("Local/1202@from-queue-000004f1;2", "0?continue") in new stack
[2015-06-17 17:52:52]   [s@macro-user-callerid:17] ExecIf("Local/1202@from-queue-000004f1;2", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
[2015-06-17 17:52:52]   [s@macro-user-callerid:18] Set("Local/1202@from-queue-000004f1;2", "__TTL=63") in new stack
[2015-06-17 17:52:52]   [s@macro-user-callerid:19] GotoIf("Local/1202@from-queue-000004f1;2", "1?continue") in new stack
[2015-06-17 17:52:52]  pbx.c: -- Goto (macro-user-callerid,s,30)
[2015-06-17 17:52:52]   [s@macro-user-callerid:30] Set("Local/1202@from-queue-000004f1;2", "CALLERID(number)=89823435698") in new stack
[2015-06-17 17:52:52]   [s@macro-user-callerid:31] Set("Local/1202@from-queue-000004f1;2", "CALLERID(name)=89823435698") in new stack
[2015-06-17 17:52:52]   [s@macro-user-callerid:32] Set("Local/1202@from-queue-000004f1;2", "CDR(cnum)=89823435698") in new stack
[2015-06-17 17:52:52]   [s@macro-user-callerid:33] Set("Local/1202@from-queue-000004f1;2", "CDR(cnam)=89823435698") in new stack
[2015-06-17 17:52:52]   [s@macro-user-callerid:34] DumpChan("Local/1202@from-queue-000004f1;2", "") in new stack
[2015-06-17 17:52:52]  app_dumpchan.c:
Dumping Info For Channel: Local/1202@from-queue-000004f1;2:
================================================================================
Info:
Name= Local/1202@from-queue-000004f1;2
Type= Local
UniqueID= 1434545572.5145
LinkedID= 1434545571.5143
CallerIDNum= 89823435698
CallerIDName= 89823435698
ConnectedLineIDNum= 905936
ConnectedLineIDName=(N/A)
DNIDDigits= (N/A)
RDNIS= 87132906900
Parkinglot=
Language= en
State= Ring (4)
Rings= 0
NativeFormat= (alaw)
WriteFormat= alaw
ReadFormat= alaw
RawWriteFormat= alaw
RawReadFormat= alaw
WriteTranscode= No
ReadTranscode= No
1stFileDescriptor= -1
Framesin= 0
Framesout= 0
TimetoHangup= 0
ElapsedTime= 0h0m0s
DirectBridge= <none>
IndirectBridge= <none>
Context= macro-user-callerid
Extension= s
Priority= 34
CallGroup=
PickupGroup=
Application= DumpChan
Data= (Empty)
Blocking_in= (Not Blocking)

Variables:
MACRO_DEPTH=2
TTL=63
CALLEE_ACCOUNCODE=
AMPUSER=89823435698
TOUCH_MONITOR=1434545572.5145
ARG1=
MACRO_PRIORITY=1
MACRO_CONTEXT=macro-exten-vm
MACRO_EXTEN=s
ARG5=1
ARG4=1
ARG3=1
ARG2=1202
RINGTIMER=15
DB_RESULT=0
QAGENT=1202
MOHCLASS=default
CWIGNORE=TRUE
MON_FMT=wav
FROMEXTEN=89823435698
TIMESTR=20150617-175252
YEAR=2015
MONTH=06
DAY=17
REC_STATUS=INITIALIZED
NODEST=9002
DIAL_OPTIONS=TtrM(auto-blkvm)
BLKVM_CHANNEL=SIP/418027-000009cb
CALLINGPRES_SV=allowed_not_screened
FROM_DID=905936
SIPRDNISDOMAIN=10.14.0.2;user=phone
SIPREDIRECTREASON=unknown
PRIREDIRECTREASON=UNKNOWN
================================================================================
[2015-06-17 17:52:52]   [s@macro-user-callerid:35] GotoIf("Local/1202@from-queue-000004f1;2", "0?xfer:end") in new stack
[2015-06-17 17:52:52]  pbx.c: -- Goto (macro-user-callerid,s,38)
[2015-06-17 17:52:52]   [s@macro-user-callerid:38] Set("Local/1202@from-queue-000004f1;2", "CHANNEL(language)=ru") in new stack
[2015-06-17 17:52:52]   [s@macro-exten-vm:2] Set("Local/1202@from-queue-000004f1;2", "RingGroupMethod=none") in new stack
[2015-06-17 17:52:52]   [s@macro-exten-vm:3] Set("Local/1202@from-queue-000004f1;2", "__EXTTOCALL=1202") in new stack
[2015-06-17 17:52:52]   [s@macro-exten-vm:4] Set("Local/1202@from-queue-000004f1;2", "__PICKUPMARK=1202") in new stack
[2015-06-17 17:52:52]   [s@macro-exten-vm:5] Set("Local/1202@from-queue-000004f1;2", "RT=15") in new stack
[2015-06-17 17:52:52]   [s@macro-exten-vm:6] ExecIf("Local/1202@from-queue-000004f1;2", "0?Macro(vm,novm,DIRECTDIAL,)") in new stack
[2015-06-17 17:52:52]   [s@macro-exten-vm:7] ExecIf("Local/1202@from-queue-000004f1;2", "0?MacroExit()") in new stack
[2015-06-17 17:52:52]   [s@macro-exten-vm:8] Gosub("Local/1202@from-queue-000004f1;2", "sub-record-check,s,1(exten,1202,dontcare)") in new stack
[2015-06-17 17:52:52]   [s@sub-record-check:1] GotoIf("Local/1202@from-queue-000004f1;2", "11?initialized") in new stack
[2015-06-17 17:52:52]  pbx.c: -- Goto (sub-record-check,s,10)
[2015-06-17 17:52:52]   [s@sub-record-check:10] NoOp("Local/1202@from-queue-000004f1;2", "Recordings initialized") in new stack
[2015-06-17 17:52:52]   [s@sub-record-check:11] ExecIf("Local/1202@from-queue-000004f1;2", "0?Set(__RRNODEST=)") in new stack
[2015-06-17 17:52:52]   [s@sub-record-check:12] ExecIf("Local/1202@from-queue-000004f1;2", "0?Set(__NODEST=)") in new stack
[2015-06-17 17:52:52]   [s@sub-record-check:13] ExecIf("Local/1202@from-queue-000004f1;2", "0?Set(ARG3=dontcare)") in new stack
[2015-06-17 17:52:52]   [s@sub-record-check:14] Set("Local/1202@from-queue-000004f1;2", "REC_POLICY_MODE_SAVE=") in new stack
[2015-06-17 17:52:52]   [s@sub-record-check:15] ExecIf("Local/1202@from-queue-000004f1;2", "0?Set(REC_STATUS=NO)") in new stack
[2015-06-17 17:52:52]   [s@sub-record-check:16] GotoIf("Local/1202@from-queue-000004f1;2", "5?checkaction") in new stack
[2015-06-17 17:52:52]  pbx.c: -- Goto (sub-record-check,s,19)
[2015-06-17 17:52:52]   [s@sub-record-check:19] GotoIf("Local/1202@from-queue-000004f1;2", "1?sub-record-check,exten,1") in new stack
[2015-06-17 17:52:52]  pbx.c: -- Goto (sub-record-check,exten,1)
[2015-06-17 17:52:52]   [exten@sub-record-check:1] NoOp("Local/1202@from-queue-000004f1;2", "Exten Recording Check between 89823435698 and 1202") in new stack
[2015-06-17 17:52:52]   [exten@sub-record-check:2] Set("Local/1202@from-queue-000004f1;2", "CALLTYPE=external") in new stack
[2015-06-17 17:52:52]   [exten@sub-record-check:3] ExecIf("Local/1202@from-queue-000004f1;2", "0?Set(CALLTYPE=)") in new stack
[2015-06-17 17:52:52]   [exten@sub-record-check:4] Set("Local/1202@from-queue-000004f1;2", "CALLEE=dontcare") in new stack
[2015-06-17 17:52:52]   [exten@sub-record-check:5] ExecIf("Local/1202@from-queue-000004f1;2", "0?Set(CALLEE=dontcare)") in new stack
[2015-06-17 17:52:52]   [exten@sub-record-check:6] GotoIf("Local/1202@from-queue-000004f1;2", "1?callee") in new stack
[2015-06-17 17:52:52]  pbx.c: -- Goto (sub-record-check,exten,11)
[2015-06-17 17:52:52]   [exten@sub-record-check:11] Gosub("Local/1202@from-queue-000004f1;2", "recordcheck,1(dontcare,external,1202)") in new stack
[2015-06-17 17:52:52]   [recordcheck@sub-record-check:1] NoOp("Local/1202@from-queue-000004f1;2", "Starting recording check against dontcare") in new stack
[2015-06-17 17:52:52]   [recordcheck@sub-record-check:2] Goto("Local/1202@from-queue-000004f1;2", "dontcare") in new stack
[2015-06-17 17:52:52]  pbx.c: -- Goto (sub-record-check,recordcheck,3)
[2015-06-17 17:52:52]   [recordcheck@sub-record-check:3] Return("Local/1202@from-queue-000004f1;2", "") in new stack
[2015-06-17 17:52:52]   [exten@sub-record-check:12] Return("Local/1202@from-queue-000004f1;2", "") in new stack
[2015-06-17 17:52:52]   [s@macro-exten-vm:9] Macro("Local/1202@from-queue-000004f1;2", "dial-one,15,TtrM(auto-blkvm),1202") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:1] Set("Local/1202@from-queue-000004f1;2", "DEXTEN=1202") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:2] Set("Local/1202@from-queue-000004f1;2", "DIALSTATUS_CW=") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:3] GosubIf("Local/1202@from-queue-000004f1;2", "0?screen,1()") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:4] GosubIf("Local/1202@from-queue-000004f1;2", "0?cf,1()") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:5] GotoIf("Local/1202@from-queue-000004f1;2", "1?skip1") in new stack
[2015-06-17 17:52:52]  pbx.c: -- Goto (macro-dial-one,s,8)
[2015-06-17 17:52:52]   [s@macro-dial-one:8] GotoIf("Local/1202@from-queue-000004f1;2", "0?nodial") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:9] GotoIf("Local/1202@from-queue-000004f1;2", "0?continue") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:10] Set("Local/1202@from-queue-000004f1;2", "EXTHASCW=") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:11] GotoIf("Local/1202@from-queue-000004f1;2", "1?next1:cwinusebusy") in new stack
[2015-06-17 17:52:52]  pbx.c: -- Goto (macro-dial-one,s,12)
[2015-06-17 17:52:52] VERBOSE[13041][C-00000358]  [ctset@macro-dial-one:2] Return("Local/1104@from-queue-000004f3;2", "") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:12] GotoIf("Local/1202@from-queue-000004f1;2", "0?docfu:skip3") in new stack
[2015-06-17 17:52:52]  pbx.c: -- Goto (macro-dial-one,s,16)
[2015-06-17 17:52:52]   [s@macro-dial-one:16] GotoIf("Local/1202@from-queue-000004f1;2", "1?next2:continue") in new stack
[2015-06-17 17:52:52]  pbx.c: -- Goto (macro-dial-one,s,17)
[2015-06-17 17:52:52]   [s@macro-dial-one:17] GotoIf("Local/1202@from-queue-000004f1;2", "1?continue") in new stack
[2015-06-17 17:52:52]  pbx.c: -- Goto (macro-dial-one,s,25)
[2015-06-17 17:52:52]   [s@macro-dial-one:25] GotoIf("Local/1202@from-queue-000004f1;2", "0?nodial") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:26] GosubIf("Local/1202@from-queue-000004f1;2", "1?dstring,1():dlocal,1()") in new stack
[2015-06-17 17:52:52]   [dstring@macro-dial-one:1] Set("Local/1202@from-queue-000004f1;2", "DSTRING=") in new stack
[2015-06-17 17:52:52]   [dstring@macro-dial-one:2] Set("Local/1202@from-queue-000004f1;2", "DEVICES=1202") in new stack
[2015-06-17 17:52:52]   [dstring@macro-dial-one:3] ExecIf("Local/1202@from-queue-000004f1;2", "0?Return()") in new stack
[2015-06-17 17:52:52]   [dstring@macro-dial-one:4] ExecIf("Local/1202@from-queue-000004f1;2", "0?Set(DEVICES=202)") in new stack
[2015-06-17 17:52:52]   [dstring@macro-dial-one:5] Set("Local/1202@from-queue-000004f1;2", "LOOPCNT=1") in new stack
[2015-06-17 17:52:52]   [dstring@macro-dial-one:6] Set("Local/1202@from-queue-000004f1;2", "ITER=1") in new stack
[2015-06-17 17:52:52]   [dstring@macro-dial-one:7] Set("Local/1202@from-queue-000004f1;2", "THISDIAL=SIP/1202") in new stack
[2015-06-17 17:52:52]   [dstring@macro-dial-one:8] GosubIf("Local/1202@from-queue-000004f1;2", "1?zap2dahdi,1()") in new stack
[2015-06-17 17:52:52]   [zap2dahdi@macro-dial-one:1] ExecIf("Local/1202@from-queue-000004f1;2", "0?Return()") in new stack
[2015-06-17 17:52:52]   [zap2dahdi@macro-dial-one:2] Set("Local/1202@from-queue-000004f1;2", "NEWDIAL=") in new stack
[2015-06-17 17:52:52]   [zap2dahdi@macro-dial-one:3] Set("Local/1202@from-queue-000004f1;2", "LOOPCNT2=1") in new stack
[2015-06-17 17:52:52]   [zap2dahdi@macro-dial-one:4] Set("Local/1202@from-queue-000004f1;2", "ITER2=1") in new stack
[2015-06-17 17:52:52]   [zap2dahdi@macro-dial-one:5] Set("Local/1202@from-queue-000004f1;2", "THISPART2=SIP/1202") in new stack
[2015-06-17 17:52:52]   [zap2dahdi@macro-dial-one:6] ExecIf("Local/1202@from-queue-000004f1;2", "0?Set(THISPART2=DAHDI/1202)") in new stack
[2015-06-17 17:52:52]   [zap2dahdi@macro-dial-one:7] Set("Local/1202@from-queue-000004f1;2", "NEWDIAL=SIP/1202&") in new stack
[2015-06-17 17:52:52]   [zap2dahdi@macro-dial-one:8] Set("Local/1202@from-queue-000004f1;2", "ITER2=2") in new stack
[2015-06-17 17:52:52]   [zap2dahdi@macro-dial-one:9] GotoIf("Local/1202@from-queue-000004f1;2", "0?begin2") in new stack
[2015-06-17 17:52:52]   [zap2dahdi@macro-dial-one:10] Set("Local/1202@from-queue-000004f1;2", "THISDIAL=SIP/1202") in new stack
[2015-06-17 17:52:52]   [zap2dahdi@macro-dial-one:11] Return("Local/1202@from-queue-000004f1;2", "") in new stack
[2015-06-17 17:52:52]   [dstring@macro-dial-one:9] GotoIf("Local/1202@from-queue-000004f1;2", "1?doset") in new stack
[2015-06-17 17:52:52]  pbx.c: -- Goto (macro-dial-one,dstring,12)
[2015-06-17 17:52:52]   [dstring@macro-dial-one:12] Set("Local/1202@from-queue-000004f1;2", "DSTRING=SIP/1202&") in new stack
[2015-06-17 17:52:52]   [dstring@macro-dial-one:13] Set("Local/1202@from-queue-000004f1;2", "ITER=2") in new stack
[2015-06-17 17:52:52]   [dstring@macro-dial-one:14] GotoIf("Local/1202@from-queue-000004f1;2", "0?begin") in new stack
[2015-06-17 17:52:52]   [dstring@macro-dial-one:15] Set("Local/1202@from-queue-000004f1;2", "DSTRING=SIP/1202") in new stack
[2015-06-17 17:52:52]   [dstring@macro-dial-one:16] Return("Local/1202@from-queue-000004f1;2", "") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:27] GotoIf("Local/1202@from-queue-000004f1;2", "0?nodial") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:28] GotoIf("Local/1202@from-queue-000004f1;2", "0?skiptrace") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:29] GosubIf("Local/1202@from-queue-000004f1;2", "1?ctset,1():ctclear,1()") in new stack
[2015-06-17 17:52:52]   [ctset@macro-dial-one:1] Set("Local/1202@from-queue-000004f1;2", "DB(CALLTRACE/1202)=89823435698") in new stack
[2015-06-17 17:52:52]   [ctset@macro-dial-one:2] Return("Local/1202@from-queue-000004f1;2", "") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:30] Set("Local/1202@from-queue-000004f1;2", "EVENT_TYPE="DirectCall"") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:31] Set("Local/1202@from-queue-000004f1;2", "CALLER_NUMBER=89823435698") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:32] NoOp("Local/1202@from-queue-000004f1;2", "1202,1202") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:33] Set("Local/1202@from-queue-000004f1;2", "D_OPTIONS=TtrM(auto-blkvm)M(1c-event,"DirectCall",89823435698,1202,1434545572.5145,)") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:34] ExecIf("Local/1202@from-queue-000004f1;2", "0?SIPAddHeader(Alert-Info: )") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:35] ExecIf("Local/1202@from-queue-000004f1;2", "0?SIPAddHeader()") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:36] ExecIf("Local/1202@from-queue-000004f1;2", "1?Set(CHANNEL(musicclass)=default)") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:37] GosubIf("Local/1202@from-queue-000004f1;2", "0?qwait,1()") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:38] Set("Local/1202@from-queue-000004f1;2", "__CWIGNORE=TRUE") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:39] Set("Local/1202@from-queue-000004f1;2", "__KEEPCID=TRUE") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:40] GotoIf("Local/1202@from-queue-000004f1;2", "0?usegoto,1") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:41] GotoIf("Local/1202@from-queue-000004f1;2", "1?godial") in new stack
[2015-06-17 17:52:52]  pbx.c: -- Goto (macro-dial-one,s,46)
[2015-06-17 17:52:52]   [s@macro-dial-one:46] Macro("Local/1202@from-queue-000004f1;2", "dialout-one-predial-hook,") in new stack
[2015-06-17 17:52:52]   [s@macro-dialout-one-predial-hook:1] MacroExit("Local/1202@from-queue-000004f1;2", "") in new stack
[2015-06-17 17:52:52]   [s@macro-dial-one:47] Dial("Local/1202@from-queue-000004f1;2", "SIP/1202,15,TtrM(auto-blkvm)M(1c-event,"DirectCall",89823435698,1202,1434545572.5145,)") in new stack
[2015-06-17 17:52:52]  netsock2.c: == Using SIP RTP TOS bits 184
[2015-06-17 17:52:52]  netsock2.c: == Using SIP RTP CoS mark 5
[2015-06-17 17:52:52]  app_dial.c: -- Called SIP/1104
[2015-06-17 17:52:52]  app_queue.c: -- Local/1104@from-queue-000004f3;1 is ringing
[2015-06-17 17:52:52]  app_queue.c: -- Local/1104@from-queue-000004f3;1 connected line has changed. Saving it until answer for SIP/418027-000009cb
[2015-06-17 17:52:52]  app_queue.c: -- Local/1104@from-queue-000004f3;1 connected line has changed. Saving it until answer for SIP/418027-000009cb
[2015-06-17 17:52:52]  app_dial.c: -- Called SIP/1202
[2015-06-17 17:52:52]  app_queue.c: -- Local/1202@from-queue-000004f1;1 is ringing
[2015-06-17 17:52:52]  app_queue.c: -- Local/1202@from-queue-000004f1;1 connected line has changed. Saving it until answer for SIP/418027-000009cb
[2015-06-17 17:52:52]  app_queue.c: -- Local/1202@from-queue-000004f1;1 connected line has changed. Saving it until answer for SIP/418027-000009cb
[2015-06-17 17:52:52]  app_dial.c: -- SIP/1202-000009cf is ringing
[2015-06-17 17:52:52]  app_queue.c: -- Local/1202@from-queue-000004f1;1 is ringing
[2015-06-17 17:52:52]  app_dial.c: -- SIP/1108-000009ce is ringing
[2015-06-17 17:52:52]  app_queue.c: -- Local/1108@from-queue-000004f2;1 is ringing
[2015-06-17 17:52:52]  app_dial.c: -- SIP/1104-000009cc is ringing
[2015-06-17 17:52:52]  app_queue.c: -- Local/1104@from-queue-000004f3;1 is ringing
[2015-06-17 17:52:52]  app_dial.c: -- SIP/1107-000009cd is ringing
[2015-06-17 17:52:52]  app_queue.c: -- Local/1107@from-queue-000004f4;1 is ringing
[2015-06-17 17:52:52]  app_dial.c: -- SIP/1104-000009cc is ringing
[2015-06-17 17:52:53]  app_dial.c: -- SIP/1104-000009cc is ringing
[2015-06-17 17:52:55]  app_dial.c: -- SIP/1104-000009cc is ringing
[2015-06-17 17:53:02]  app_dial.c: -- SIP/1202-000009cf answered Local/1202@from-queue-000004f1;2
[2015-06-17 17:53:02]   [s@macro-user-event:1] GotoIf("SIP/1202-000009cf", "1?direct:somecall") "1?direct:transfer") in new stack
[2015-06-17 17:53:02]  pbx.c: -- Goto (macro-user-event,s,2)
[2015-06-17 17:53:02]   [s@macro-user-event:2] GotoIf("SIP/1202-000009cf", "0?transfer") Set("SIP/1202-000009cf", "_BACKUP_RECENT_CALLER=") in new stack
[2015-06-17 17:53:02]   [s@macro-user-event:3] Set("SIP/1202-000009cf", "_BACKUP_RECENT_CALLER=") "_BACKUP_RECENT_CALLEE=") in new stack
[2015-06-17 17:53:02]   [s@macro-user-event:4] Set("SIP/1202-000009cf", "_BACKUP_RECENT_CALLEE=") "__RECENT_CALLER=89823435698") in new stack
[2015-06-17 17:53:02]   [s@macro-user-event:5] Set("SIP/1202-000009cf", "__RECENT_CALLER=89823435698") "__RECENT_CALLEE=1202") in new stack
[2015-06-17 17:53:02]   [s@macro-user-event:6] Set("SIP/1202-000009cf", "__RECENT_CALLEE=1202") "__Direct="NO"") in new stack
[2015-06-17 17:53:02]   [s@macro-user-event:7] Set("SIP/1202-000009cf", "__Direct="NO"") in new stack
[2015-06-17 17:53:02]   [s@macro-user-event:8] GosubIf("SIP/1202-000009cf", "1?record,1()") in new stack
[2015-06-17 17:53:02]   [record@macro-user-event:1] Set("SIP/1202-000009cf", "__REC_STATUS=INITIALIZED") in new stack
[2015-06-17 17:53:02]   [record@macro-user-event:2] Set("SIP/1202-000009cf", "NOW=1434545582") in new stack
[2015-06-17 17:53:02]   [record@macro-user-event:3] Set("SIP/1202-000009cf", "__DAY=17") in new stack
[2015-06-17 17:53:02]   [record@macro-user-event:4] Set("SIP/1202-000009cf", "__MONTH=06") in new stack
[2015-06-17 17:53:02]   [record@macro-user-event:5] Set("SIP/1202-000009cf", "__YEAR=2015") in new stack
[2015-06-17 17:53:02]   [record@macro-user-event:6] Set("SIP/1202-000009cf", "__TIMESTR=20150617-175302") in new stack
[2015-06-17 17:53:02]   [record@macro-user-event:7] Set("SIP/1202-000009cf", "__MON_FMT=wav") in new stack
[2015-06-17 17:53:02]   [record@macro-user-event:8] NoOp("SIP/1202-000009cf", "Starting recording: 89823435698, 1202") in new stack
[2015-06-17 17:53:02]   [record@macro-user-event:9] Set("SIP/1202-000009cf", "AUDIOHOOK_INHERIT(MixMonitor)=yes") in new stack
[2015-06-17 17:53:02]   [record@macro-user-event:10] Set("SIP/1202-000009cf", "__CALLFILENAME=DirectCall-89823435698-1202-20150617-175302-1434545572.5145") in new stack
[2015-06-17 17:53:02]   [record@macro-user-event:11] MixMonitor("SIP/1202-000009cf", "2015/06/17/DirectCall-89823435698-1202-20150617-175302-1434545572.5145.wav,ai(LOCAL_MIXMON_ID),") in new stack
[2015-06-17 17:53:02]   [record@macro-user-event:12] Set("SIP/1202-000009cf", "__MIXMON_ID=0x7f93045cd090") in new stack
[2015-06-17 17:53:02]   [record@macro-user-event:13] Set("SIP/1202-000009cf", "__RECORD_ID=SIP/1202-000009cf") in new stack
[2015-06-17 17:53:02]   [record@macro-user-event:14] Set("SIP/1202-000009cf", "__REC_STATUS=RECORDING") in new stack
[2015-06-17 17:53:02]   [record@macro-user-event:15] Set("SIP/1202-000009cf", "CDR(recordingfile)=DirectCall-89823435698-1202-20150617-175302-1434545572.5145.wav") in new stack
[2015-06-17 17:53:02]   [record@macro-user-event:16] Return("SIP/1202-000009cf", "") in new stack
[2015-06-17 17:53:02]   [s@macro-user-event:9] [s@macro-user-event:10] UserEvent("SIP/1202-000009cf", "DirectCall,CALLER: 89823435698,CALLEE: 1202,CALLUNIQUEID: 1434545572.5145,FILENAME: DirectCall-89823435698-1202-20150617-175302-1434545572.5145") in new stack
[2015-06-17 17:53:02]   [s@macro-user-event:10] [s@macro-user-event:11] MacroExit("SIP/1202-000009cf", "") in new stack

Потом идет условный перевод.Тут уже в дампчане видно что никаких переменных нет.

[2015-06-17 18:05:01]   [1104@from-internal-xfer:1] Set("Local/1104@from-internal-xfer-000004f5;2", "__RINGTIMER=15") in new stack
[2015-06-17 18:05:01]   [1104@from-internal-xfer:2] Macro("Local/1104@from-internal-xfer-000004f5;2", "exten-vm,novm,1104,1,1,1") in new stack
[2015-06-17 18:05:01]   [s@macro-exten-vm:1] Macro("Local/1104@from-internal-xfer-000004f5;2", "user-callerid,") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:1] Set("Local/1104@from-internal-xfer-000004f5;2", "TOUCH_MONITOR=1434546301.5158") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:2] Set("Local/1104@from-internal-xfer-000004f5;2", "AMPUSER=1202") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:3] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "0?report") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:4] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "1?Set(REALCALLERIDNUM=1202)") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:5] Set("Local/1104@from-internal-xfer-000004f5;2", "AMPUSER=1202") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:6] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "0?limit") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:7] Set("Local/1104@from-internal-xfer-000004f5;2", "AMPUSERCIDNAME=kuan") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:8] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "0?report") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:9] Set("Local/1104@from-internal-xfer-000004f5;2", "AMPUSERCID=1202") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:10] Set("Local/1104@from-internal-xfer-000004f5;2", "__DIAL_OPTIONS=Ttr") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:11] Set("Local/1104@from-internal-xfer-000004f5;2", "CALLERID(all)="kuan" <1202>") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:12] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "0?limit") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:13] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "0?Set(GROUP(concurrency_limit)=1202)") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:14] GosubIf("Local/1104@from-internal-xfer-000004f5;2", "7?sub-ccss,s,1(macro-exten-vm,)") in new stack
[2015-06-17 18:05:01]   [s@sub-ccss:1] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "0?Return()") in new stack
[2015-06-17 18:05:01]   [s@sub-ccss:2] Set("Local/1104@from-internal-xfer-000004f5;2", "CCSS_SETUP=TRUE") in new stack
[2015-06-17 18:05:01]   [s@sub-ccss:3] GosubIf("Local/1104@from-internal-xfer-000004f5;2", "0?monitor_config,1(macro-exten-vm,):monitor_default,1(macro-exten-vm,)") in new stack
[2015-06-17 18:05:01]   [monitor_default@sub-ccss:1] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "0?is_exten") in new stack
[2015-06-17 18:05:01]   [monitor_default@sub-ccss:2] StackPop("Local/1104@from-internal-xfer-000004f5;2", "") in new stack
[2015-06-17 18:05:01]   [monitor_default@sub-ccss:3] Return("Local/1104@from-internal-xfer-000004f5;2", "FALSE") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:15] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "0?Set(CHANNEL(language)=)") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:16] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "0?continue") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:17] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "1?Set(__CALLEE_ACCOUNCODE=)") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:18] Set("Local/1104@from-internal-xfer-000004f5;2", "__TTL=63") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:19] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "1?continue") in new stack
[2015-06-17 18:05:01]  pbx.c: -- Goto (macro-user-callerid,s,30)
[2015-06-17 18:05:01]   [s@macro-user-callerid:30] Set("Local/1104@from-internal-xfer-000004f5;2", "CALLERID(number)=1202") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:31] Set("Local/1104@from-internal-xfer-000004f5;2", "CALLERID(name)=kuan") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:32] Set("Local/1104@from-internal-xfer-000004f5;2", "CDR(cnum)=1202") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:33] Set("Local/1104@from-internal-xfer-000004f5;2", "CDR(cnam)=kuan") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:34] DumpChan("Local/1104@from-internal-xfer-000004f5;2", "") in new stack
[2015-06-17 18:05:01]  app_dumpchan.c:
Dumping Info For Channel: Local/1104@from-internal-xfer-000004f5;2:
================================================================================
Info:
Name= Local/1104@from-internal-xfer-000004f5;2
Type= Local
UniqueID= 1434546301.5158
LinkedID= 1434545571.5143
CallerIDNum= 1202
CallerIDName= kuan
ConnectedLineIDNum= (N/A)
ConnectedLineIDName=(N/A)
DNIDDigits= (N/A)
RDNIS= (N/A)
Parkinglot=
Language= ru
State= Ring (4)
Rings= 0
NativeFormat= (alaw)
WriteFormat= alaw
ReadFormat= alaw
RawWriteFormat= alaw
RawReadFormat= alaw
WriteTranscode= No
ReadTranscode= No
1stFileDescriptor= -1
Framesin= 0
Framesout= 0
TimetoHangup= 0
ElapsedTime= 0h0m0s
DirectBridge= <none>
IndirectBridge= <none>
Context= macro-user-callerid
Extension= s
Priority= 34
CallGroup=
PickupGroup=
Application= DumpChan
Data= (Empty)
Blocking_in= (Not Blocking)

Variables:
MACRO_DEPTH=2
TTL=63
CALLEE_ACCOUNCODE=
DB_RESULT=
GOSUB_RETVAL=FALSE
CCSS_SETUP=TRUE
DIAL_OPTIONS=Ttr
AMPUSERCID=1202
AMPUSERCIDNAME=kuan
AMPUSER=1202
REALCALLERIDNUM=1202
TOUCH_MONITOR=1434546301.5158
ARG1=
MACRO_PRIORITY=1
MACRO_CONTEXT=macro-exten-vm
MACRO_EXTEN=s
ARG5=1
ARG4=1
ARG3=1
ARG2=1104
RINGTIMER=15
TRANSFERERNAME=Local/1202@from-queue-000004f1;1
MOHCLASS=default
CWIGNORE=TRUE
MON_FMT=wav
FROMEXTEN=89823435698
TIMESTR=20150617-175252
YEAR=2015
MONTH=06
DAY=17
REC_STATUS=INITIALIZED
NODEST=9002
BLKVM_CHANNEL=SIP/418027-000009cb
CALLINGPRES_SV=allowed_not_screened
FROM_DID=905936
SIPRDNISDOMAIN=10.14.0.2;user=phone
SIPREDIRECTREASON=unknown
PRIREDIRECTREASON=UNKNOWN
================================================================================
[2015-06-17 18:05:01]   [s@macro-user-callerid:35] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "1?xfer:end") in new stack
[2015-06-17 18:05:01]  pbx.c: -- Goto (macro-user-callerid,s,36)
[2015-06-17 18:05:01]   [s@macro-user-callerid:36] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "0?Set(CID_STRING=89823435698:from kuan):Set(CID_STRING=:from kuan)") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:37] Set("Local/1104@from-internal-xfer-000004f5;2", "CALLERID(name)=:from kuan") in new stack
[2015-06-17 18:05:01]   [s@macro-user-callerid:38] Set("Local/1104@from-internal-xfer-000004f5;2", "CHANNEL(language)=ru") in new stack
[2015-06-17 18:05:01]   [s@macro-exten-vm:2] Set("Local/1104@from-internal-xfer-000004f5;2", "RingGroupMethod=none") in new stack
[2015-06-17 18:05:01]   [s@macro-exten-vm:3] Set("Local/1104@from-internal-xfer-000004f5;2", "__EXTTOCALL=1104") in new stack
[2015-06-17 18:05:01]   [s@macro-exten-vm:4] Set("Local/1104@from-internal-xfer-000004f5;2", "__PICKUPMARK=1104") in new stack
[2015-06-17 18:05:01]   [s@macro-exten-vm:5] Set("Local/1104@from-internal-xfer-000004f5;2", "RT=15") in new stack
[2015-06-17 18:05:01]   [s@macro-exten-vm:6] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "0?Macro(vm,novm,DIRECTDIAL,)") in new stack
[2015-06-17 18:05:01]   [s@macro-exten-vm:7] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "0?MacroExit()") in new stack
[2015-06-17 18:05:01]   [s@macro-exten-vm:8] Gosub("Local/1104@from-internal-xfer-000004f5;2", "sub-record-check,s,1(exten,1104,dontcare)") in new stack
[2015-06-17 18:05:01]   [s@sub-record-check:1] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "11?initialized") in new stack
[2015-06-17 18:05:01]  pbx.c: -- Goto (sub-record-check,s,10)
[2015-06-17 18:05:01]   [s@sub-record-check:10] NoOp("Local/1104@from-internal-xfer-000004f5;2", "Recordings initialized") in new stack
[2015-06-17 18:05:01]   [s@sub-record-check:11] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "0?Set(__RRNODEST=)") in new stack
[2015-06-17 18:05:01]   [s@sub-record-check:12] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "0?Set(__NODEST=)") in new stack
[2015-06-17 18:05:01]   [s@sub-record-check:13] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "0?Set(ARG3=dontcare)") in new stack
[2015-06-17 18:05:01]   [s@sub-record-check:14] Set("Local/1104@from-internal-xfer-000004f5;2", "REC_POLICY_MODE_SAVE=") in new stack
[2015-06-17 18:05:01]   [s@sub-record-check:15] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "0?Set(REC_STATUS=NO)") in new stack
[2015-06-17 18:05:01]   [s@sub-record-check:16] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "5?checkaction") in new stack
[2015-06-17 18:05:01]  pbx.c: -- Goto (sub-record-check,s,19)
[2015-06-17 18:05:01]   [s@sub-record-check:19] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "1?sub-record-check,exten,1") in new stack
[2015-06-17 18:05:01]  pbx.c: -- Goto (sub-record-check,exten,1)
[2015-06-17 18:05:01]   [exten@sub-record-check:1] NoOp("Local/1104@from-internal-xfer-000004f5;2", "Exten Recording Check between 89823435698 and 1104") in new stack
[2015-06-17 18:05:01]   [exten@sub-record-check:2] Set("Local/1104@from-internal-xfer-000004f5;2", "CALLTYPE=external") in new stack
[2015-06-17 18:05:01]   [exten@sub-record-check:3] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "0?Set(CALLTYPE=)") in new stack
[2015-06-17 18:05:01]   [exten@sub-record-check:4] Set("Local/1104@from-internal-xfer-000004f5;2", "CALLEE=dontcare") in new stack
[2015-06-17 18:05:01]   [exten@sub-record-check:5] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "0?Set(CALLEE=dontcare)") in new stack
[2015-06-17 18:05:01]   [exten@sub-record-check:6] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "1?callee") in new stack
[2015-06-17 18:05:01]  pbx.c: -- Goto (sub-record-check,exten,11)
[2015-06-17 18:05:01]   [exten@sub-record-check:11] Gosub("Local/1104@from-internal-xfer-000004f5;2", "recordcheck,1(dontcare,external,1104)") in new stack
[2015-06-17 18:05:01]   [recordcheck@sub-record-check:1] NoOp("Local/1104@from-internal-xfer-000004f5;2", "Starting recording check against dontcare") in new stack
[2015-06-17 18:05:01]   [recordcheck@sub-record-check:2] Goto("Local/1104@from-internal-xfer-000004f5;2", "dontcare") in new stack
[2015-06-17 18:05:01]  pbx.c: -- Goto (sub-record-check,recordcheck,3)
[2015-06-17 18:05:01]   [recordcheck@sub-record-check:3] Return("Local/1104@from-internal-xfer-000004f5;2", "") in new stack
[2015-06-17 18:05:01]   [exten@sub-record-check:12] Return("Local/1104@from-internal-xfer-000004f5;2", "") in new stack
[2015-06-17 18:05:01]   [s@macro-exten-vm:9] Macro("Local/1104@from-internal-xfer-000004f5;2", "dial-one,15,Ttr,1104") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:1] Set("Local/1104@from-internal-xfer-000004f5;2", "DEXTEN=1104") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:2] Set("Local/1104@from-internal-xfer-000004f5;2", "DIALSTATUS_CW=") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:3] GosubIf("Local/1104@from-internal-xfer-000004f5;2", "0?screen,1()") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:4] GosubIf("Local/1104@from-internal-xfer-000004f5;2", "0?cf,1()") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:5] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "1?skip1") in new stack
[2015-06-17 18:05:01]  pbx.c: -- Goto (macro-dial-one,s,8)
[2015-06-17 18:05:01]   [s@macro-dial-one:8] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "0?nodial") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:9] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "0?continue") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:10] Set("Local/1104@from-internal-xfer-000004f5;2", "EXTHASCW=") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:11] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "1?next1:cwinusebusy") in new stack
[2015-06-17 18:05:01]  pbx.c: -- Goto (macro-dial-one,s,12)
[2015-06-17 18:05:01]   [s@macro-dial-one:12] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "0?docfu:skip3") in new stack
[2015-06-17 18:05:01]  pbx.c: -- Goto (macro-dial-one,s,16)
[2015-06-17 18:05:01]   [s@macro-dial-one:16] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "1?next2:continue") in new stack
[2015-06-17 18:05:01]  pbx.c: -- Goto (macro-dial-one,s,17)
[2015-06-17 18:05:01]   [s@macro-dial-one:17] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "1?continue") in new stack
[2015-06-17 18:05:01]  pbx.c: -- Goto (macro-dial-one,s,25)
[2015-06-17 18:05:01]   [s@macro-dial-one:25] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "0?nodial") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:26] GosubIf("Local/1104@from-internal-xfer-000004f5;2", "1?dstring,1():dlocal,1()") in new stack
[2015-06-17 18:05:01]   [dstring@macro-dial-one:1] Set("Local/1104@from-internal-xfer-000004f5;2", "DSTRING=") in new stack
[2015-06-17 18:05:01]   [dstring@macro-dial-one:2] Set("Local/1104@from-internal-xfer-000004f5;2", "DEVICES=1104") in new stack
[2015-06-17 18:05:01]   [dstring@macro-dial-one:3] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "0?Return()") in new stack
[2015-06-17 18:05:01]   [dstring@macro-dial-one:4] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "0?Set(DEVICES=104)") in new stack
[2015-06-17 18:05:01]   [dstring@macro-dial-one:5] Set("Local/1104@from-internal-xfer-000004f5;2", "LOOPCNT=1") in new stack
[2015-06-17 18:05:01]   [dstring@macro-dial-one:6] Set("Local/1104@from-internal-xfer-000004f5;2", "ITER=1") in new stack
[2015-06-17 18:05:01]   [dstring@macro-dial-one:7] Set("Local/1104@from-internal-xfer-000004f5;2", "THISDIAL=SIP/1104") in new stack
[2015-06-17 18:05:01]   [dstring@macro-dial-one:8] GosubIf("Local/1104@from-internal-xfer-000004f5;2", "1?zap2dahdi,1()") in new stack
[2015-06-17 18:05:01]   [zap2dahdi@macro-dial-one:1] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "0?Return()") in new stack
[2015-06-17 18:05:01]   [zap2dahdi@macro-dial-one:2] Set("Local/1104@from-internal-xfer-000004f5;2", "NEWDIAL=") in new stack
[2015-06-17 18:05:01]   [zap2dahdi@macro-dial-one:3] Set("Local/1104@from-internal-xfer-000004f5;2", "LOOPCNT2=1") in new stack
[2015-06-17 18:05:01]   [zap2dahdi@macro-dial-one:4] Set("Local/1104@from-internal-xfer-000004f5;2", "ITER2=1") in new stack
[2015-06-17 18:05:01]   [zap2dahdi@macro-dial-one:5] Set("Local/1104@from-internal-xfer-000004f5;2", "THISPART2=SIP/1104") in new stack
[2015-06-17 18:05:01]   [zap2dahdi@macro-dial-one:6] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "0?Set(THISPART2=DAHDI/1104)") in new stack
[2015-06-17 18:05:01]   [zap2dahdi@macro-dial-one:7] Set("Local/1104@from-internal-xfer-000004f5;2", "NEWDIAL=SIP/1104&") in new stack
[2015-06-17 18:05:01]   [zap2dahdi@macro-dial-one:8] Set("Local/1104@from-internal-xfer-000004f5;2", "ITER2=2") in new stack
[2015-06-17 18:05:01]   [zap2dahdi@macro-dial-one:9] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "0?begin2") in new stack
[2015-06-17 18:05:01]   [zap2dahdi@macro-dial-one:10] Set("Local/1104@from-internal-xfer-000004f5;2", "THISDIAL=SIP/1104") in new stack
[2015-06-17 18:05:01]   [zap2dahdi@macro-dial-one:11] Return("Local/1104@from-internal-xfer-000004f5;2", "") in new stack
[2015-06-17 18:05:01]   [dstring@macro-dial-one:9] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "1?doset") in new stack
[2015-06-17 18:05:01]  pbx.c: -- Goto (macro-dial-one,dstring,12)
[2015-06-17 18:05:01]   [dstring@macro-dial-one:12] Set("Local/1104@from-internal-xfer-000004f5;2", "DSTRING=SIP/1104&") in new stack
[2015-06-17 18:05:01]   [dstring@macro-dial-one:13] Set("Local/1104@from-internal-xfer-000004f5;2", "ITER=2") in new stack
[2015-06-17 18:05:01]   [dstring@macro-dial-one:14] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "0?begin") in new stack
[2015-06-17 18:05:01]   [dstring@macro-dial-one:15] Set("Local/1104@from-internal-xfer-000004f5;2", "DSTRING=SIP/1104") in new stack
[2015-06-17 18:05:01]   [dstring@macro-dial-one:16] Return("Local/1104@from-internal-xfer-000004f5;2", "") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:27] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "0?nodial") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:28] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "0?skiptrace") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:29] GosubIf("Local/1104@from-internal-xfer-000004f5;2", "1?ctset,1():ctclear,1()") in new stack
[2015-06-17 18:05:01]   [ctset@macro-dial-one:1] Set("Local/1104@from-internal-xfer-000004f5;2", "DB(CALLTRACE/1104)=1202") in new stack
[2015-06-17 18:05:01]   [ctset@macro-dial-one:2] Return("Local/1104@from-internal-xfer-000004f5;2", "") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:30] Set("Local/1104@from-internal-xfer-000004f5;2", "EVENT_TYPE=TransferCall") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:31] Set("Local/1104@from-internal-xfer-000004f5;2", "CALLER_NUMBER=89823435698") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:32] NoOp("Local/1104@from-internal-xfer-000004f5;2", "1104,1104") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:33] Set("Local/1104@from-internal-xfer-000004f5;2", "D_OPTIONS=TtrM(auto-blkvm,TransferCall,89823435698,1104,1434546301.5158,)") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:34] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "0?SIPAddHeader(Alert-Info: )") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:35] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "0?SIPAddHeader()") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:36] ExecIf("Local/1104@from-internal-xfer-000004f5;2", "1?Set(CHANNEL(musicclass)=default)") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:37] GosubIf("Local/1104@from-internal-xfer-000004f5;2", "0?qwait,1()") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:38] Set("Local/1104@from-internal-xfer-000004f5;2", "__CWIGNORE=TRUE") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:39] Set("Local/1104@from-internal-xfer-000004f5;2", "__KEEPCID=TRUE") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:40] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "0?usegoto,1") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:41] GotoIf("Local/1104@from-internal-xfer-000004f5;2", "0?godial") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:42] Gosub("Local/1104@from-internal-xfer-000004f5;2", "sub-presencestate-display,s,1(1104)") in new stack
[2015-06-17 18:05:01] WARNING[13420][C-00000358] func_presencestate.c: PRESENCE_STATE unknown
[2015-06-17 18:05:01]   [s@sub-presencestate-display:1] Goto("Local/1104@from-internal-xfer-000004f5;2", "state-,1") in new stack
[2015-06-17 18:05:01]  pbx.c: -- Goto (sub-presencestate-display,state-,1)
[2015-06-17 18:05:01]   [state-@sub-presencestate-display:1] Set("Local/1104@from-internal-xfer-000004f5;2", "PRESENCESTATE_DISPLAY=") in new stack
[2015-06-17 18:05:01]   [state-@sub-presencestate-display:2] Return("Local/1104@from-internal-xfer-000004f5;2", "") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:43] Set("Local/1104@from-internal-xfer-000004f5;2", "CONNECTEDLINE(name,i)=miva") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:44] Set("Local/1104@from-internal-xfer-000004f5;2", "CONNECTEDLINE(num)=1104") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:45] Set("Local/1104@from-internal-xfer-000004f5;2", "D_OPTIONS=TtrM(auto-blkvm,TransferCall,89823435698,1104,1434546301.5158,)I") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:46] Macro("Local/1104@from-internal-xfer-000004f5;2", "dialout-one-predial-hook,") in new stack
[2015-06-17 18:05:01]   [s@macro-dialout-one-predial-hook:1] MacroExit("Local/1104@from-internal-xfer-000004f5;2", "") in new stack
[2015-06-17 18:05:01]   [s@macro-dial-one:47] Dial("Local/1104@from-internal-xfer-000004f5;2", "SIP/1104,15,TtrM(auto-blkvm,TransferCall,89823435698,1104,1434546301.5158,)I") in new stack
[2015-06-17 18:05:01]  netsock2.c: == Using SIP RTP TOS bits 184
[2015-06-17 18:05:01]  netsock2.c: == Using SIP RTP CoS mark 5
[2015-06-17 18:05:01]  app_dial.c: -- Called SIP/1104
[2015-06-17 18:05:01]  app_dial.c: -- Connected line update to Local/1104@from-internal-xfer-000004f5;2 prevented.
[2015-06-17 18:05:01]  features.c: -- Local/1104@from-internal-xfer-000004f5;1 is ringing
[2015-06-17 18:05:01]  app_dial.c: -- SIP/1104-000009d1 is ringing
[2015-06-17 18:05:01]  features.c: -- Local/1104@from-internal-xfer-000004f5;1 is ringing
[2015-06-17 18:05:02]  app_dial.c: -- SIP/1104-000009d1 is ringing
[2015-06-17 18:05:03]  app_dial.c: -- SIP/1104-000009d1 is ringing
[2015-06-17 18:05:05]  app_dial.c: -- SIP/1104-000009d1 is ringing
[2015-06-17 18:05:08]  app_dial.c: -- Connected line update to Local/1104@from-internal-xfer-000004f5;2 prevented.
[2015-06-17 18:05:08]  app_dial.c: -- SIP/1104-000009d1 answered Local/1104@from-internal-xfer-000004f5;2
[2015-06-17 18:05:08]   [s@macro-auto-blkvm:1] Set("SIP/1104-000009d1", "__MACRO_RESULT=") in new stack
[2015-06-17 18:05:08]   [s@macro-auto-blkvm:2] Set("SIP/1104-000009d1", "CFIGNORE=") in new stack
[2015-06-17 18:05:08]   [s@macro-auto-blkvm:3] Set("SIP/1104-000009d1", "MASTER_CHANNEL(CFIGNORE)=") in new stack
[2015-06-17 18:05:08]   [s@macro-auto-blkvm:4] Set("SIP/1104-000009d1", "FORWARD_CONTEXT=from-internal") in new stack
[2015-06-17 18:05:08]   [s@macro-auto-blkvm:5] Set("SIP/1104-000009d1", "MASTER_CHANNEL(FORWARD_CONTEXT)=from-internal") in new stack
[2015-06-17 18:05:08]   [s@macro-auto-blkvm:6] Macro("SIP/1104-000009d1", "1c-event,TransferCall,89823435698,1104,1434546301.5158,") in new stack
[2015-06-17 18:05:08]   [s@macro-user-event:1] GotoIf("SIP/1104-000009d1", "0?direct:somecall") "0?direct:transfer") in new stack
[2015-06-17 18:05:08]  pbx.c: -- Goto (macro-user-event,s,11)
[2015-06-17 18:05:08]   [s@macro-user-event:11] GotoIf("SIP/1104-000009d1", "0?group:transfer") in new stack
[2015-06-17 18:05:08]  pbx.c: -- Goto (macro-user-event,s,12)
Set("SIP/1104-000009d1", "_BACKUP_RECENT_CALLER=") in new stack
[2015-06-17 18:05:08]   [s@macro-user-event:12] Set("SIP/1104-000009d1", "_BACKUP_RECENT_CALLER=") "_BACKUP_RECENT_CALLEE=") in new stack
[2015-06-17 18:05:08]   [s@macro-user-event:13] Set("SIP/1104-000009d1", "_BACKUP_RECENT_CALLEE=") "__RECENT_CALLER=") in new stack
[2015-06-17 18:05:08]   [s@macro-user-event:14] Set("SIP/1104-000009d1", "__RECENT_CALLER=") "__RECENT_CALLEE=1104") in new stack
[2015-06-17 18:05:08]   [s@macro-user-event:15] Set("SIP/1104-000009d1", "__RECENT_CALLEE=1104") "ARG1="TransferCall"") in new stack
[2015-06-17 18:05:08]   [s@macro-user-event:16] Set("SIP/1104-000009d1", "ARG1="TransferCall"") in new stack
[2015-06-17 18:05:08]   [s@macro-user-event:17] GosubIf("SIP/1104-000009d1", "1?record,1()") in new stack

Соответственно выдается практически пустой евент и CID при переводе выходит криво.