Пожалуйста, войдите здесь. Часто задаваемые вопросы О нас
Задайте Ваш вопрос

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

нажмите, чтобы скрыть/показать версии 1
изначальная версия
редактировать

спросил 2017-10-27 13:58:30 +0400

khu.yam@mail.ru Gravatar khu.yam@mail.ru

Переадресайия на сотовый на 4 сек и обратно

Добрый день, уважаемые гуру. Поменял работу и вместе с ней с меди пришлось пересесть на FREE PBX. Не сочтите мой вопрос тупым. FreePBX 2.10.1.9 Задача: при входящем внешнем звонке, если секр не берет трубку переадресовать ей на мобильный. Попробовал: follow me добавить номер сотового# и соотв правило. не заработало. Каким то чудом в транк уходил CID звонящего. Ессно пров это отбивал. Перепробовал прописывать его везде результата-0. Плюнул. СОздал misc. destination с ее номером. Поставил его в follow me и на внутреннем при неответе.

Получил: Входящий звонок- 10 секунд звонит офисный. потом звонит сотовый ровно 5 секунд, потом звонок опять возвращается на сотовый. Подскажите пожалуйста где увеличить время ожидания попытки дозвониться до сотового?

Переадресайия на сотовый на 4 сек и обратно

Добрый день, уважаемые гуру. Поменял работу и вместе с ней с меди пришлось пересесть на FREE PBX. Не сочтите мой вопрос тупым. FreePBX 2.10.1.9 Задача: при входящем внешнем звонке, если секр не берет трубку переадресовать ей на мобильный. Попробовал: follow me добавить номер сотового# и соотв правило. не заработало. Каким то чудом в транк уходил CID звонящего. Ессно пров это отбивал. Перепробовал прописывать его везде результата-0. Плюнул. СОздал misc. destination с ее номером. Поставил его в follow me и на внутреннем при неответе.

Получил: Входящий звонок- 10 секунд звонит офисный. потом звонит сотовый ровно 5 секунд, потом звонок опять возвращается на сотовый. Подскажите пожалуйста где увеличить время ожидания попытки дозвониться до сотового?

решил приложить лог происходящего при звонке 893XXXXX655 звонящий 210 внутренний номер секр 893XXXXX615 сотовый секр.

    [2017-10-27 13:14:52] VERBOSE[27563] pbx.c:     -- Executing [s@macro-hangupcall                                                                                        :1] GotoIf("SIP/GOC-00008306", "1?theend") in new stack
    [2017-10-27 13:14:52] VERBOSE[27563] pbx.c:     -- Goto (macro-hangupcall,s,3)
    [2017-10-27 13:14:52] VERBOSE[27563] pbx.c:     -- Executing [s@macro-hangupcall                                                                                        :3] ExecIf("SIP/GOC-00008306",

 "0?Set(CDR(recordingfile)=)") in new stack
[2017-10-27 13:14:52] VERBOSE[27563] pbx.c:     -- Executing [s@macro-hangupcall                                                                                        :4] Hangup("SIP/GOC-00008306", "") in new stack
[2017-10-27 13:14:52] VERBOSE[27563] app_macro.c:   == Spawn extension (macro-ha                                                                                        ngupcall, s, 4) exited non-zero on 'SIP/GOC-00008306' in macro 'hangupcall'
[2017-10-27 13:14:52] VERBOSE[27563] features.c:   == Spawn extension (macro-dia                                                                                        lout-trunk, h, 1) exited non-zero on 'SIP/GOC-00008306'
[2017-10-27 13:14:52] VERBOSE[27563] app_macro.c:   == Spawn extension (macro-di                                                                                        alout-trunk, s, 22) exited non-zero on 'SIP/GOC-00008306' in macro 'dialout-trun                                                                                        k'
[2017-10-27 13:14:52] VERBOSE[27563] pbx.c:   == Spawn extension (from-internal,                                                                                         893XXXXX615, 6) exited non-zero on 'SIP/GOC-00008306'
[2017-10-27 13:15:25] NOTICE[3538] chan_sip.c: Peer '120' is now Lagged. (2016ms                                                                                         / 2000ms)
[2017-10-27 13:15:47] NOTICE[3538] chan_sip.c: Peer '120' is now Reachable. (27m                                                                                        s / 2000ms)
[2017-10-27 13:17:15] VERBOSE[3538] netsock2.c:   == Using SIP RTP TOS bits 184
[2017-10-27 13:17:15] VERBOSE[3538] netsock2.c:   == Using SIP RTP CoS mark 5
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [10024@from-trunk-s                                                                                        ip-GOC:1] Set("SIP/GOC-00008308", "GROUP()=OUT_5") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [10024@from-trunk-s                                                                                        ip-GOC:2] Goto("SIP/GOC-00008308", "from-trunk,10024,1") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Goto (from-trunk,10024,1)
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [10024@from-trunk:1                                                                                        ] Set("SIP/GOC-00008308", "__FROM_DID=10024") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [10024@from-trunk:2                                                                                        ] Set("SIP/GOC-00008308", "CDR(did)=10024") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [10024@from-trunk:3                                                                                        ] ExecIf("SIP/GOC-00008308", "0 ?Set(CALLERID(name)=893XXXXX655)") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [10024@from-trunk:4                                                                                        ] Set("SIP/GOC-00008308", "__CALLINGPRES_SV=allowed_not_screened") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [10024@from-trunk:5                                                                                        ] Set("SIP/GOC-00008308", "CALLERPRES()=allowed_not_screened") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [10024@from-trunk:6                                                                                        ] Goto("SIP/GOC-00008308", "from-did-direct,201,1") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Goto (from-did-direct,201,1)
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [201@from-did-direc                                                                                        t:1] GotoIf("SIP/GOC-00008308", "0?ext-local,201,1") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [201@from-did-direc                                                                                        t:2] Macro("SIP/GOC-00008308", "user-callerid,") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-user-calle                                                                                        rid:1] Set("SIP/GOC-00008308", "AMPUSER=893XXXXX655") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-user-calle                                                                                        rid:2] GotoIf("SIP/GOC-00008308", "0?report") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-user-calle                                                                                        rid:3] ExecIf("SIP/GOC-00008308", "1?Set(REALCALLERIDNUM=893XXXXX655)") in new s                                                                                        tack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-user-calle                                                                                        rid:4] Set("SIP/GOC-00008308", "AMPUSER=") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-user-calle                                                                                        rid:5] Set("SIP/GOC-00008308", "AMPUSERCIDNAME=") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-user-calle                                                                                        rid:6] GotoIf("SIP/GOC-00008308", "1?report") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Goto (macro-user-callerid,s,1                                                                                        1)
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-user-calle                                                                                        rid:11] GotoIf("SIP/GOC-00008308", "0?continue") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-user-calle                                                                                        rid:12] Set("SIP/GOC-00008308", "__TTL=64") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-user-calle                                                                                        rid:13] GotoIf("SIP/GOC-00008308", "1?continue") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Goto (macro-user-callerid,s,2                                                                                        4)
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-user-calle                                                                                        rid:24] Set("SIP/GOC-00008308", "CALLERID(number)=893XXXXX655") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-user-calle                                                                                        rid:25] Set("SIP/GOC-00008308", "CALLERID(name)=893XXXXX655") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-user-calle                                                                                        rid:26] Set("SIP/GOC-00008308", "CHANNEL(language)=en") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [201@from-did-direc                                                                                        t:3] Set("SIP/GOC-00008308", "DIAL_OPTIONS=trI") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [201@from-did-direc                                                                                        t:4] Set("SIP/GOC-00008308", "CONNECTEDLINE(num)=201") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [201@from-did-direc                                                                                        t:5] Set("SIP/GOC-00008308", "CONNECTEDLINE(name,i)=Ekaterina Leontyeva") in new                                                                                         stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [201@from-did-direc                                                                                        t:6] Set("SIP/GOC-00008308", "FM_DIALSTATUS=NOT_INUSE") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [201@from-did-direc                                                                                        t:7] Set("SIP/GOC-00008308", "__EXTTOCALL=201") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [201@from-did-direc                                                                                        t:8] Set("SIP/GOC-00008308", "__PICKUPMARK=201") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [201@from-did-direc                                                                                        t:9] Macro("SIP/GOC-00008308", "blkvm-setifempty,") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-blkvm-seti                                                                                        fempty:1] GotoIf("SIP/GOC-00008308", "1?init") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Goto (macro-blkvm-setifempty,                                                                                        s,4)
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-blkvm-seti                                                                                        fempty:4] Set("SIP/GOC-00008308", "__BLKVM_CHANNEL=SIP/GOC-00008308") in new sta                                                                                        ck
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-blkvm-seti                                                                                        fempty:5] Set("SIP/GOC-00008308", "SHARED(BLKVM,SIP/GOC-00008308)=TRUE") in new                                                                                         stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-blkvm-seti                                                                                        fempty:6] Set("SIP/GOC-00008308", "GOSUB_RETVAL=TRUE") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-blkvm-seti                                                                                        fempty:7] MacroExit("SIP/GOC-00008308", "") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [201@from-did-direc                                                                                        t:10] GotoIf("SIP/GOC-00008308", "1?skipov") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Goto (from-did-direct,201,13)
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [201@from-did-direc                                                                                        t:13] Set("SIP/GOC-00008308", "RRNODEST=") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [201@from-did-direc                                                                                        t:14] Set("SIP/GOC-00008308", "__NODEST=201") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [201@from-did-direc                                                                                        t:15] GosubIf("SIP/GOC-00008308", "1?sub-fmsetcid,s,1()") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@sub-fmsetcid:1]                                                                                         Goto("SIP/GOC-00008308", "s-did,1") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Goto (sub-fmsetcid,s-did,1)
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s-did@sub-fmsetcid                                                                                        :1] ExecIf("SIP/GOC-00008308", "1?Set(__REALCALLERIDNUM=10024)") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s-did@sub-fmsetcid                                                                                        :2] Return("SIP/GOC-00008308", "") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [201@from-did-direc                                                                                        t:16] Set("SIP/GOC-00008308", "RecordMethod=Group") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [201@from-did-direc                                                                                        t:17] Gosub("SIP/GOC-00008308", "sub-record-check,s,1(exten,201,)") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@sub-record-check                                                                                        :1] GotoIf("SIP/GOC-00008308", "1?check") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Goto (sub-record-check,s,6)
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@sub-record-check                                                                                        :6] Set("SIP/GOC-00008308", "__MON_FMT=wav") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@sub-record-check                                                                                        :7] GotoIf("SIP/GOC-00008308", "1?next") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Goto (sub-record-check,s,10)
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@sub-record-check                                                                                        :10] ExecIf("SIP/GOC-00008308", "0?Return()") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@sub-record-check                                                                                        :11] GotoIf("SIP/GOC-00008308", "0?exten,1") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@sub-record-check                                                                                        :12] Set("SIP/GOC-00008308", "__REC_STATUS=INITIALIZED") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@sub-record-check                                                                                        :13] ExecIf("SIP/GOC-00008308", "0?Set(__REC_POLICY_MODE=)") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@sub-record-check                                                                                        :14] Set("SIP/GOC-00008308", "NOW=1509095835") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@sub-record-check                                                                                        :15] Set("SIP/GOC-00008308", "__DAY=27") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@sub-record-check                                                                                        :16] Set("SIP/GOC-00008308", "__MONTH=10") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@sub-record-check                                                                                        :17] Set("SIP/GOC-00008308", "__YEAR=2017") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@sub-record-check                                                                                        :18] Set("SIP/GOC-00008308", "__TIMESTR=20171027-131715") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@sub-record-check                                                                                        :19] Set("SIP/GOC-00008308", "__FROMEXTEN=10024") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@sub-record-check                                                                                        :20] Set("SIP/GOC-00008308", "__CALLFILENAME=exten-201-10024-20171027-131715-150                                                                                        9095835.38367") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@sub-record-check                                                                                        :21] Goto("SIP/GOC-00008308", "exten,1") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Goto (sub-record-check,exten,                                                                                        1)
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [exten@sub-record-c                                                                                        heck:1] GotoIf("SIP/GOC-00008308", "0?callee") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [exten@sub-record-c                                                                                        heck:2] Set("SIP/GOC-00008308", "__REC_POLICY_MODE=dontcare") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [exten@sub-record-c                                                                                        heck:3] GotoIf("SIP/GOC-00008308", "1?caller") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Goto (sub-record-check,exten,                                                                                        10)
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [exten@sub-record-c                                                                                        heck:10] Set("SIP/GOC-00008308", "__REC_POLICY_MODE=") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [exten@sub-record-c                                                                                        heck:11] GosubIf("SIP/GOC-00008308", "0?record,1(exten,201,10024)") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [exten@sub-record-c                                                                                        heck:12] Return("SIP/GOC-00008308", "") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [201@from-did-direc                                                                                        t:18] Set("SIP/GOC-00008308", "RingGroupMethod=ringallv2") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [201@from-did-direc                                                                                        t:19] Set("SIP/GOC-00008308", "_FMGRP=201") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [201@from-did-direc                                                                                        t:20] GotoIf("SIP/GOC-00008308", "0?doconfirm") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [201@from-did-direc                                                                                        t:21] Macro("SIP/GOC-00008308", "dial,20,trI,201") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-dial:1] Go                                                                                        toIf("SIP/GOC-00008308", "1?dial") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Goto (macro-dial,s,3)
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-dial:3] AG                                                                                        I("SIP/GOC-00008308", "dialparties.agi") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] res_agi.c:     -- Launched AGI Script /var/                                                                                        lib/asterisk/agi-bin/dialparties.agi
[2017-10-27 13:17:15] VERBOSE[27599] res_agi.c:  dialparties.agi: Starting New D                                                                                        ialparties.agi
[2017-10-27 13:17:15] VERBOSE[27599] res_agi.c:  dialparties.agi: Caller ID name                                                                                         is '893XXXXX655' number is '893XXXXX655'
[2017-10-27 13:17:15] VERBOSE[27599] res_agi.c:  dialparties.agi: Methodology of                                                                                         ring is  'ringallv2'
[2017-10-27 13:17:15] VERBOSE[27599] res_agi.c:     -- dialparties.agi: Added ex                                                                                        tension 201 to extension map
[2017-10-27 13:17:15] VERBOSE[27599] res_agi.c:     -- dialparties.agi: Extensio                                                                                        n 201 cf is disabled
[2017-10-27 13:17:15] VERBOSE[27599] res_agi.c:     -- dialparties.agi: Extensio                                                                                        n 201 do not disturb is disabled
[2017-10-27 13:17:15] VERBOSE[27599] res_agi.c:     -- dialparties.agi: dbset CA                                                                                        LLTRACE/201 to 893XXXXX655
[2017-10-27 13:17:15] VERBOSE[27599] res_agi.c:     -- dialparties.agi: Filtered                                                                                         ARG3: 201
[2017-10-27 13:17:15] VERBOSE[27599] res_agi.c:     -- <SIP/GOC-00008308>AGI Scr                                                                                        ipt dialparties.agi completed, returning 0
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-dial:7] Di                                                                                        al("SIP/GOC-00008308", "SIP/201,20,trIM(auto-blkvm)") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] netsock2.c:   == Using SIP RTP TOS bits 184
[2017-10-27 13:17:15] VERBOSE[27599] netsock2.c:   == Using SIP RTP CoS mark 5
[2017-10-27 13:17:15] VERBOSE[27599] app_dial.c:     -- Called SIP/201
[2017-10-27 13:17:15] VERBOSE[27599] app_dial.c:     -- Connected line update to                                                                                         SIP/GOC-00008308 prevented.
[2017-10-27 13:17:16] VERBOSE[27599] app_dial.c:     -- SIP/201-00008309 is ring                                                                                        ing
[2017-10-27 13:17:25] VERBOSE[27599] app_macro.c:   == Spawn extension (macro-dial, s, 7) exited non-zero on 'SIP/GOC-00008308' in macro 'dial'
[2017-10-27 13:17:25] VERBOSE[27599] pbx.c:   == Spawn extension (from-did-direct, 201, 21) exited non-zero on 'SIP/GOC-00008308'
[2017-10-27 13:17:25] VERBOSE[27599] pbx.c:     -- Executing [h@from-did-direct:1] Macro("SIP/GOC-00008308", "hangupcall,") in new stack
[2017-10-27 13:17:25] VERBOSE[27599] pbx.c:     -- Executing [s@macro-hangupcall:1] GotoIf("SIP/GOC-00008308", "1?theend") in new stack
[2017-10-27 13:17:25] VERBOSE[27599] pbx.c:     -- Goto (macro-hangupcall,s,3)
[2017-10-27 13:17:25] VERBOSE[27599] pbx.c:     -- Executing [s@macro-hangupcall:3] ExecIf("SIP/GOC-00008308", "0?Set(CDR(recordingfile)=)") in new stack
[2017-10-27 13:17:25] VERBOSE[27599] pbx.c:     -- Executing [s@macro-hangupcall:4] Hangup("SIP/GOC-00008308", "") in new stack
[2017-10-27 13:17:25] VERBOSE[27599] app_macro.c:   == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/GOC-00008308' in macro 'hangupcall'
[2017-10-27 13:17:25] VERBOSE[27599] pbx.c:   == Spawn extension (from-did-direct, h, 1) exited non-zero on 'SIP/GOC-00008308'
[2017-10-27 13:17:25] VERBOSE[3538] netsock2.c:   == Using SIP RTP TOS bits 184
[2017-10-27 13:17:25] VERBOSE[3538] netsock2.c:   == Using SIP RTP CoS mark 5
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [10025@from-trunk-sip-GOC:1] Set("SIP/GOC-0000830a", "GROUP()=OUT_5") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [10025@from-trunk-sip-GOC:2] Goto("SIP/GOC-0000830a", "from-trunk,10025,1") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Goto (from-trunk,10025,1)
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [10025@from-trunk:1] Set("SIP/GOC-0000830a", "__FROM_DID=10025") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [10025@from-trunk:2] Set("SIP/GOC-0000830a", "CDR(did)=10025") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [10025@from-trunk:3] ExecIf("SIP/GOC-0000830a", "0 ?Set(CALLERID(name)=893XXXXX655)") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [10025@from-trunk:4] Set("SIP/GOC-0000830a", "__CALLINGPRES_SV=allowed_not_screened") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [10025@from-trunk:5] Set("SIP/GOC-0000830a", "CALLERPRES()=allowed_not_screened") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [10025@from-trunk:6] Goto("SIP/GOC-0000830a", "from-did-direct,201,1") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Goto (from-did-direct,201,1)
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [201@from-did-direct:1] GotoIf("SIP/GOC-0000830a", "0?ext-local,201,1") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [201@from-did-direct:2] Macro("SIP/GOC-0000830a", "user-callerid,") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-user-callerid:1] Set("SIP/GOC-0000830a", "AMPUSER=893XXXXX655") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-user-callerid:2] GotoIf("SIP/GOC-0000830a", "0?report") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-user-callerid:3] ExecIf("SIP/GOC-0000830a", "1?Set(REALCALLERIDNUM=893XXXXX655)") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-user-callerid:4] Set("SIP/GOC-0000830a", "AMPUSER=") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-user-callerid:5] Set("SIP/GOC-0000830a", "AMPUSERCIDNAME=") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-user-callerid:6] GotoIf("SIP/GOC-0000830a", "1?report") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Goto (macro-user-callerid,s,11)
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-user-callerid:11] GotoIf("SIP/GOC-0000830a", "0?continue") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-user-callerid:12] Set("SIP/GOC-0000830a", "__TTL=64") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-user-callerid:13] GotoIf("SIP/GOC-0000830a", "1?continue") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Goto (macro-user-callerid,s,24)
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-user-callerid:24] Set("SIP/GOC-0000830a", "CALLERID(number)=893XXXXX655") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-user-callerid:25] Set("SIP/GOC-0000830a", "CALLERID(name)=893XXXXX655") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-user-callerid:26] Set("SIP/GOC-0000830a", "CHANNEL(language)=en") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [201@from-did-direct:3] Set("SIP/GOC-0000830a", "DIAL_OPTIONS=trI") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [201@from-did-direct:4] Set("SIP/GOC-0000830a", "CONNECTEDLINE(num)=201") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [201@from-did-direct:5] Set("SIP/GOC-0000830a", "CONNECTEDLINE(name,i)=Ekaterina Leontyeva") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [201@from-did-direct:6] Set("SIP/GOC-0000830a", "FM_DIALSTATUS=NOT_INUSE") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [201@from-did-direct:7] Set("SIP/GOC-0000830a", "__EXTTOCALL=201") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [201@from-did-direct:8] Set("SIP/GOC-0000830a", "__PICKUPMARK=201") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [201@from-did-direct:9] Macro("SIP/GOC-0000830a", "blkvm-setifempty,") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-blkvm-setifempty:1] GotoIf("SIP/GOC-0000830a", "1?init") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Goto (macro-blkvm-setifempty,s,4)
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-blkvm-setifempty:4] Set("SIP/GOC-0000830a", "__BLKVM_CHANNEL=SIP/GOC-0000830a") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-blkvm-setifempty:5] Set("SIP/GOC-0000830a", "SHARED(BLKVM,SIP/GOC-0000830a)=TRUE") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-blkvm-setifempty:6] Set("SIP/GOC-0000830a", "GOSUB_RETVAL=TRUE") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-blkvm-setifempty:7] MacroExit("SIP/GOC-0000830a", "") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [201@from-did-direct:10] GotoIf("SIP/GOC-0000830a", "1?skipov") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Goto (from-did-direct,201,13)
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [201@from-did-direct:13] Set("SIP/GOC-0000830a", "RRNODEST=") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [201@from-did-direct:14] Set("SIP/GOC-0000830a", "__NODEST=201") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [201@from-did-direct:15] GosubIf("SIP/GOC-0000830a", "1?sub-fmsetcid,s,1()") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@sub-fmsetcid:1] Goto("SIP/GOC-0000830a", "s-did,1") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Goto (sub-fmsetcid,s-did,1)
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s-did@sub-fmsetcid:1] ExecIf("SIP/GOC-0000830a", "1?Set(__REALCALLERIDNUM=10025)") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s-did@sub-fmsetcid:2] Return("SIP/GOC-0000830a", "") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [201@from-did-direct:16] Set("SIP/GOC-0000830a", "RecordMethod=Group") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [201@from-did-direct:17] Gosub("SIP/GOC-0000830a", "sub-record-check,s,1(exten,201,)") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@sub-record-check:1] GotoIf("SIP/GOC-0000830a", "1?check") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Goto (sub-record-check,s,6)
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@sub-record-check:6] Set("SIP/GOC-0000830a", "__MON_FMT=wav") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@sub-record-check:7] GotoIf("SIP/GOC-0000830a", "1?next") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Goto (sub-record-check,s,10)
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@sub-record-check:10] ExecIf("SIP/GOC-0000830a", "0?Return()") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@sub-record-check:11] GotoIf("SIP/GOC-0000830a", "0?exten,1") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@sub-record-check:12] Set("SIP/GOC-0000830a", "__REC_STATUS=INITIALIZED") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@sub-record-check:13] ExecIf("SIP/GOC-0000830a", "0?Set(__REC_POLICY_MODE=)") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@sub-record-check:14] Set("SIP/GOC-0000830a", "NOW=1509095845") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@sub-record-check:15] Set("SIP/GOC-0000830a", "__DAY=27") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@sub-record-check:16] Set("SIP/GOC-0000830a", "__MONTH=10") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@sub-record-check:17] Set("SIP/GOC-0000830a", "__YEAR=2017") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@sub-record-check:18] Set("SIP/GOC-0000830a", "__TIMESTR=20171027-131725") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@sub-record-check:19] Set("SIP/GOC-0000830a", "__FROMEXTEN=10025") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@sub-record-check:20] Set("SIP/GOC-0000830a", "__CALLFILENAME=exten-201-10025-20171027-131725-1509095845.38369") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@sub-record-check:21] Goto("SIP/GOC-0000830a", "exten,1") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Goto (sub-record-check,exten,1)
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [exten@sub-record-check:1] GotoIf("SIP/GOC-0000830a", "0?callee") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [exten@sub-record-check:2] Set("SIP/GOC-0000830a", "__REC_POLICY_MODE=dontcare") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [exten@sub-record-check:3] GotoIf("SIP/GOC-0000830a", "1?caller") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Goto (sub-record-check,exten,10)
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [exten@sub-record-check:10] Set("SIP/GOC-0000830a", "__REC_POLICY_MODE=") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [exten@sub-record-check:11] GosubIf("SIP/GOC-0000830a", "0?record,1(exten,201,10025)") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [exten@sub-record-check:12] Return("SIP/GOC-0000830a", "") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [201@from-did-direct:18] Set("SIP/GOC-0000830a", "RingGroupMethod=ringallv2") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [201@from-did-direct:19] Set("SIP/GOC-0000830a", "_FMGRP=201") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [201@from-did-direct:20] GotoIf("SIP/GOC-0000830a", "0?doconfirm") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [201@from-did-direct:21] Macro("SIP/GOC-0000830a", "dial,20,trI,201") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-dial:1] GotoIf("SIP/GOC-0000830a", "1?dial") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Goto (macro-dial,s,3)
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-dial:3] AGI("SIP/GOC-0000830a", "dialparties.agi") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] res_agi.c:     -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[2017-10-27 13:17:25] VERBOSE[27601] res_agi.c:  dialparties.agi: Starting New Dialparties.agi
[2017-10-27 13:17:25] VERBOSE[27601] res_agi.c:  dialparties.agi: Caller ID name is '893XXXXX655' number is '893XXXXX655'
[2017-10-27 13:17:25] VERBOSE[27601] res_agi.c:  dialparties.agi: Methodology of ring is  'ringallv2'
[2017-10-27 13:17:25] VERBOSE[27601] res_agi.c:     -- dialparties.agi: Added extension 201 to extension map
[2017-10-27 13:17:25] VERBOSE[27601] res_agi.c:     -- dialparties.agi: Extension 201 cf is disabled
[2017-10-27 13:17:25] VERBOSE[27601] res_agi.c:     -- dialparties.agi: Extension 201 do not disturb is disabled
[2017-10-27 13:17:25] VERBOSE[27601] res_agi.c:     -- dialparties.agi: dbset CALLTRACE/201 to 893XXXXX655
[2017-10-27 13:17:25] VERBOSE[27601] res_agi.c:     -- dialparties.agi: Filtered ARG3: 201
[2017-10-27 13:17:25] VERBOSE[27601] res_agi.c:     -- <SIP/GOC-0000830a>AGI Script dialparties.agi completed, returning 0
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-dial:7] Dial("SIP/GOC-0000830a", "SIP/201,20,trIM(auto-blkvm)") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] netsock2.c:   == Using SIP RTP TOS bits 184
[2017-10-27 13:17:25] VERBOSE[27601] netsock2.c:   == Using SIP RTP CoS mark 5
[2017-10-27 13:17:25] VERBOSE[27601] app_dial.c:     -- Called SIP/201
[2017-10-27 13:17:25] VERBOSE[27601] app_dial.c:     -- Connected line update to SIP/GOC-0000830a prevented.
[2017-10-27 13:17:26] VERBOSE[27601] app_dial.c:     -- SIP/201-0000830b is ringing
[2017-10-27 13:17:35] VERBOSE[27601] app_macro.c:   == Spawn extension (macro-dial, s, 7) exited non-zero on 'SIP/GOC-0000830a' in macro 'dial'
[2017-10-27 13:17:35] VERBOSE[27601] pbx.c:   == Spawn extension (from-did-direct, 201, 21) exited non-zero on 'SIP/GOC-0000830a'
[2017-10-27 13:17:35] VERBOSE[27601] pbx.c:     -- Executing [h@from-did-direct:1] Macro("SIP/GOC-0000830a", "hangupcall,") in new stack
[2017-10-27 13:17:35] VERBOSE[27601] pbx.c:     -- Executing [s@macro-hangupcall:1] GotoIf("SIP/GOC-0000830a", "1?theend") in new stack
[2017-10-27 13:17:35] VERBOSE[27601] pbx.c:     -- Goto (macro-hangupcall,s,3)
[2017-10-27 13:17:35] VERBOSE[27601] pbx.c:     -- Executing [s@macro-hangupcall:3] ExecIf("SIP/GOC-0000830a", "0?Set(CDR(recordingfile)=)") in new stack
[2017-10-27 13:17:35] VERBOSE[27601] pbx.c:     -- Executing [s@macro-hangupcall:4] Hangup("SIP/GOC-0000830a", "") in new stack
[2017-10-27 13:17:35] VERBOSE[27601] app_macro.c:   == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/GOC-0000830a' in macro 'hangupcall'
[2017-10-27 13:17:35] VERBOSE[27601] pbx.c:   == Spawn extension (from-did-direct, h, 1) exited non-zero on 'SIP/GOC-0000830a'
[2017-10-27 13:17:36] VERBOSE[3538] netsock2.c:   == Using SIP RTP TOS bits 184
[2017-10-27 13:17:36] VERBOSE[3538] netsock2.c:   == Using SIP RTP CoS mark 5
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [10026@from-trunk-sip-GOC:1] Set("SIP/GOC-0000830c", "GROUP()=OUT_5") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [10026@from-trunk-sip-GOC:2] Goto("SIP/GOC-0000830c", "from-trunk,10026,1") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Goto (from-trunk,10026,1)
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [10026@from-trunk:1] Set("SIP/GOC-0000830c", "__FROM_DID=10026") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [10026@from-trunk:2] Set("SIP/GOC-0000830c", "CDR(did)=10026") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [10026@from-trunk:3] ExecIf("SIP/GOC-0000830c", "0 ?Set(CALLERID(name)=893XXXXX655)") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [10026@from-trunk:4] Set("SIP/GOC-0000830c", "__CALLINGPRES_SV=allowed_not_screened") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [10026@from-trunk:5] Set("SIP/GOC-0000830c", "CALLERPRES()=allowed_not_screened") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [10026@from-trunk:6] Goto("SIP/GOC-0000830c", "ext-miscdests,1,1") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Goto (ext-miscdests,1,1)
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [1@ext-miscdests:1] NoOp("SIP/GOC-0000830c", "MiscDest: Kate") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [1@ext-miscdests:2] Goto("SIP/GOC-0000830c", "from-internal,893XXXXX615,1") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Goto (from-internal,893XXXXX615,1)
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [893XXXXX615@from-internal:1] Macro("SIP/GOC-0000830c", "user-callerid,LIMIT,") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-user-callerid:1] Set("SIP/GOC-0000830c", "AMPUSER=893XXXXX655") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-user-callerid:2] GotoIf("SIP/GOC-0000830c", "0?report") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-user-callerid:3] ExecIf("SIP/GOC-0000830c", "1?Set(REALCALLERIDNUM=893XXXXX655)") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-user-callerid:4] Set("SIP/GOC-0000830c", "AMPUSER=") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-user-callerid:5] Set("SIP/GOC-0000830c", "AMPUSERCIDNAME=") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-user-callerid:6] GotoIf("SIP/GOC-0000830c", "1?report") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Goto (macro-user-callerid,s,11)
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-user-callerid:11] GotoIf("SIP/GOC-0000830c", "1?continue") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Goto (macro-user-callerid,s,24)
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-user-callerid:24] Set("SIP/GOC-0000830c", "CALLERID(number)=893XXXXX655") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-user-callerid:25] Set("SIP/GOC-0000830c", "CALLERID(name)=893XXXXX655") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-user-callerid:26] Set("SIP/GOC-0000830c", "CHANNEL(language)=en") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [893XXXXX615@from-internal:2] Set("SIP/GOC-0000830c", "MOHCLASS=default") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [893XXXXX615@from-internal:3] ExecIf("SIP/GOC-0000830c", "1?Set(TRUNKCIDOVERRIDE=759020)") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [893XXXXX615@from-internal:4] Set("SIP/GOC-0000830c", "_NODEST=") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [893XXXXX615@from-internal:5] Gosub("SIP/GOC-0000830c", "sub-record-check,s,1(out,893XXXXX615,)") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@sub-record-check:1] GotoIf("SIP/GOC-0000830c", "1?check") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Goto (sub-record-check,s,6)
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@sub-record-check:6] Set("SIP/GOC-0000830c", "__MON_FMT=wav") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@sub-record-check:7] GotoIf("SIP/GOC-0000830c", "1?next") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Goto (sub-record-check,s,10)
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@sub-record-check:10] ExecIf("SIP/GOC-0000830c", "0?Return()") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@sub-record-check:11] GotoIf("SIP/GOC-0000830c", "0?out,1") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@sub-record-check:12] Set("SIP/GOC-0000830c", "__REC_STATUS=INITIALIZED") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@sub-record-check:13] ExecIf("SIP/GOC-0000830c", "0?Set(__REC_POLICY_MODE=)") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@sub-record-check:14] Set("SIP/GOC-0000830c", "NOW=1509095856") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@sub-record-check:15] Set("SIP/GOC-0000830c", "__DAY=27") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@sub-record-check:16] Set("SIP/GOC-0000830c", "__MONTH=10") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@sub-record-check:17] Set("SIP/GOC-0000830c", "__YEAR=2017") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@sub-record-check:18] Set("SIP/GOC-0000830c", "__TIMESTR=20171027-131736") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@sub-record-check:19] Set("SIP/GOC-0000830c", "__FROMEXTEN=893XXXXX655") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@sub-record-check:20] Set("SIP/GOC-0000830c", "__CALLFILENAME=out-893XXXXX615-893XXXXX655-20171027-131736-1509095856.38371") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@sub-record-check:21] Goto("SIP/GOC-0000830c", "out,1") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Goto (sub-record-check,out,1)
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [out@sub-record-check:1] ExecIf("SIP/GOC-0000830c", "1?Set(__REC_POLICY_MODE=)") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [out@sub-record-check:2] GosubIf("SIP/GOC-0000830c", "0?record,1(exten,893XXXXX615,893XXXXX655)") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [out@sub-record-check:3] Return("SIP/GOC-0000830c", "") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [893XXXXX615@from-internal:6] Macro("SIP/GOC-0000830c", "dialout-trunk,2,893XXXXX615,") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:1] Set("SIP/GOC-0000830c", "DIAL_TRUNK=2") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:2] GosubIf("SIP/GOC-0000830c", "0?sub-pincheck,s,1()") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:3] GotoIf("SIP/GOC-0000830c", "0?disabletrunk,1") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:4] Set("SIP/GOC-0000830c", "DIAL_NUMBER=893XXXXX615") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:5] Set("SIP/GOC-0000830c", "DIAL_TRUNK_OPTIONS=tr") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:6] Set("SIP/GOC-0000830c", "OUTBOUND_GROUP=OUT_2") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:7] GotoIf("SIP/GOC-0000830c", "1?nomax") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Goto (macro-dialout-trunk,s,9)
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:9] GotoIf("SIP/GOC-0000830c", "0?skipoutcid") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:10] Set("SIP/GOC-0000830c", "DIAL_TRUNK_OPTIONS=") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:11] Macro("SIP/GOC-0000830c", "outbound-callerid,2") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-outbound-callerid:1] ExecIf("SIP/GOC-0000830c", "1?Set(CALLERPRES()=allowed_not_screened)") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-outbound-callerid:2] ExecIf("SIP/GOC-0000830c", "0?Set(REALCALLERIDNUM=893XXXXX655)") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-outbound-callerid:3] GotoIf("SIP/GOC-0000830c", "1?normcid") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Goto (macro-outbound-callerid,s,6)
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-outbound-callerid:6] Set("SIP/GOC-0000830c", "USEROUTCID=") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-outbound-callerid:7] Set("SIP/GOC-0000830c", "EMERGENCYCID=") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-outbound-callerid:8] Set("SIP/GOC-0000830c", "TRUNKOUTCID=<78422759020>") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-outbound-callerid:9] GotoIf("SIP/GOC-0000830c", "1?trunkcid") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Goto (macro-outbound-callerid,s,12)
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-outbound-callerid:12] ExecIf("SIP/GOC-0000830c", "1?Set(CALLERID(all)=<78422759020>)") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-outbound-callerid:13] ExecIf("SIP/GOC-0000830c", "0?Set(CALLERID(all)=)") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-outbound-callerid:14] ExecIf("SIP/GOC-0000830c", "1?Set(CALLERID(all)=759020)") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-outbound-callerid:15] ExecIf("SIP/GOC-0000830c", "0?Set(CALLERPRES()=prohib_passed_screen)") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:12] GosubIf("SIP/GOC-0000830c", "0?sub-flp-2,s,1()") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:13] Set("SIP/GOC-0000830c", "OUTNUM=893XXXXX615") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:14] Set("SIP/GOC-0000830c", "custom=SIP/QW") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:15] ExecIf("SIP/GOC-0000830c", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default))") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:16] ExecIf("SIP/GOC-0000830c", "0?Set(DIAL_TRUNK_OPTIONS=M(confirm))") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:17] Macro("SIP/GOC-0000830c", "dialout-trunk-predial-hook,") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/GOC-0000830c", "") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:18] GotoIf("SIP/GOC-0000830c", "0?bypass,1") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:19] ExecIf("SIP/GOC-0000830c", "0?Set(CONNECTEDLINE(num,i)=893XXXXX615)") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:20] ExecIf("SIP/GOC-0000830c", "0?Set(CONNECTEDLINE(name,i)=CID:759020)") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:21] GotoIf("SIP/GOC-0000830c", "0?customtrunk") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:22] Dial("SIP/GOC-0000830c", "SIP/QW/893XXXXX615,300,") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] netsock2.c:   == Using SIP RTP TOS bits 184
[2017-10-27 13:17:36] VERBOSE[27603] netsock2.c:   == Using SIP RTP CoS mark 5
[2017-10-27 13:17:36] VERBOSE[27603] app_dial.c:     -- Called SIP/QW/893XXXXX615
[2017-10-27 13:17:36] VERBOSE[27603] app_dial.c:     -- SIP/QW-0000830d is ringing
[2017-10-27 13:17:40] VERBOSE[27603] app_dial.c:     -- SIP/QW-0000830d is ringing
[2017-10-27 13:17:46] VERBOSE[27603] app_macro.c:   == Spawn extension (macro-dialout-trunk, s, 22) exited non-zero on 'SIP/GOC-0000830c' in macro 'dialout-trunk'
[2017-10-27 13:17:46] VERBOSE[27603] pbx.c:   == Spawn extension (from-internal, 893XXXXX615, 6) exited non-zero on 'SIP/GOC-0000830c'
[2017-10-27 13:17:46] VERBOSE[27603] pbx.c:     -- Executing [h@from-internal:1] Hangup("SIP/GOC-0000830c", "") in new stack
[2017-10-27 13:17:46] VERBOSE[27603] pbx.c:   == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/GOC-0000830c'
[2017-10-27 13:17:46] VERBOSE[3538] netsock2.c:   == Using SIP RTP TOS bits 184
[2017-10-27 13:17:46] VERBOSE[3538] netsock2.c:   == Using SIP RTP CoS mark 5
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [759020@from-trunk-sip-GOC:1] Set("SIP/GOC-0000830e", "GROUP()=OUT_5") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [759020@from-trunk-sip-GOC:2] Goto("SIP/GOC-0000830e", "from-trunk,759020,1") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Goto (from-trunk,759020,1)
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [759020@from-trunk:1] Set("SIP/GOC-0000830e", "__FROM_DID=759020") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [759020@from-trunk:2] Set("SIP/GOC-0000830e", "CDR(did)=759020") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [759020@from-trunk:3] ExecIf("SIP/GOC-0000830e", "0 ?Set(CALLERID(name)=893XXXXX655)") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [759020@from-trunk:4] Set("SIP/GOC-0000830e", "__CALLINGPRES_SV=allowed_not_screened") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [759020@from-trunk:5] Set("SIP/GOC-0000830e", "CALLERPRES()=allowed_not_screened") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [759020@from-trunk:6] Goto("SIP/GOC-0000830e", "from-did-direct,201,1") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Goto (from-did-direct,201,1)
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [201@from-did-direct:1] GotoIf("SIP/GOC-0000830e", "0?ext-local,201,1") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [201@from-did-direct:2] Macro("SIP/GOC-0000830e", "user-callerid,") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-user-callerid:1] Set("SIP/GOC-0000830e", "AMPUSER=893XXXXX655") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-user-callerid:2] GotoIf("SIP/GOC-0000830e", "0?report") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-user-callerid:3] ExecIf("SIP/GOC-0000830e", "1?Set(REALCALLERIDNUM=893XXXXX655)") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-user-callerid:4] Set("SIP/GOC-0000830e", "AMPUSER=") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-user-callerid:5] Set("SIP/GOC-0000830e", "AMPUSERCIDNAME=") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-user-callerid:6] GotoIf("SIP/GOC-0000830e", "1?report") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Goto (macro-user-callerid,s,11)
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-user-callerid:11] GotoIf("SIP/GOC-0000830e", "0?continue") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-user-callerid:12] Set("SIP/GOC-0000830e", "__TTL=64") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-user-callerid:13] GotoIf("SIP/GOC-0000830e", "1?continue") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Goto (macro-user-callerid,s,24)
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-user-callerid:24] Set("SIP/GOC-0000830e", "CALLERID(number)=893XXXXX655") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-user-callerid:25] Set("SIP/GOC-0000830e", "CALLERID(name)=893XXXXX655") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-user-callerid:26] Set("SIP/GOC-0000830e", "CHANNEL(language)=en") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [201@from-did-direct:3] Set("SIP/GOC-0000830e", "DIAL_OPTIONS=trI") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [201@from-did-direct:4] Set("SIP/GOC-0000830e", "CONNECTEDLINE(num)=201") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [201@from-did-direct:5] Set("SIP/GOC-0000830e", "CONNECTEDLINE(name,i)=Ekaterina Leontyeva") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [201@from-did-direct:6] Set("SIP/GOC-0000830e", "FM_DIALSTATUS=NOT_INUSE") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [201@from-did-direct:7] Set("SIP/GOC-0000830e", "__EXTTOCALL=201") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [201@from-did-direct:8] Set("SIP/GOC-0000830e", "__PICKUPMARK=201") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [201@from-did-direct:9] Macro("SIP/GOC-0000830e", "blkvm-setifempty,") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-blkvm-setifempty:1] GotoIf("SIP/GOC-0000830e", "1?init") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Goto (macro-blkvm-setifempty,s,4)
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-blkvm-setifempty:4] Set("SIP/GOC-0000830e", "__BLKVM_CHANNEL=SIP/GOC-0000830e") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-blkvm-setifempty:5] Set("SIP/GOC-0000830e", "SHARED(BLKVM,SIP/GOC-0000830e)=TRUE") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-blkvm-setifempty:6] Set("SIP/GOC-0000830e", "GOSUB_RETVAL=TRUE") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-blkvm-setifempty:7] MacroExit("SIP/GOC-0000830e", "") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [201@from-did-direct:10] GotoIf("SIP/GOC-0000830e", "1?skipov") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Goto (from-did-direct,201,13)
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [201@from-did-direct:13] Set("SIP/GOC-0000830e", "RRNODEST=") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [201@from-did-direct:14] Set("SIP/GOC-0000830e", "__NODEST=201") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [201@from-did-direct:15] GosubIf("SIP/GOC-0000830e", "1?sub-fmsetcid,s,1()") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@sub-fmsetcid:1] Goto("SIP/GOC-0000830e", "s-did,1") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Goto (sub-fmsetcid,s-did,1)
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s-did@sub-fmsetcid:1] ExecIf("SIP/GOC-0000830e", "1?Set(__REALCALLERIDNUM=759020)") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s-did@sub-fmsetcid:2] Return("SIP/GOC-0000830e", "") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [201@from-did-direct:16] Set("SIP/GOC-0000830e", "RecordMethod=Group") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [201@from-did-direct:17] Gosub("SIP/GOC-0000830e", "sub-record-check,s,1(exten,201,)") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@sub-record-check:1] GotoIf("SIP/GOC-0000830e", "1?check") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Goto (sub-record-check,s,6)
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@sub-record-check:6] Set("SIP/GOC-0000830e", "__MON_FMT=wav") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@sub-record-check:7] GotoIf("SIP/GOC-0000830e", "1?next") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Goto (sub-record-check,s,10)
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@sub-record-check:10] ExecIf("SIP/GOC-0000830e", "0?Return()") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@sub-record-check:11] GotoIf("SIP/GOC-0000830e", "0?exten,1") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@sub-record-check:12] Set("SIP/GOC-0000830e", "__REC_STATUS=INITIALIZED") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@sub-record-check:13] ExecIf("SIP/GOC-0000830e", "0?Set(__REC_POLICY_MODE=)") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@sub-record-check:14] Set("SIP/GOC-0000830e", "NOW=1509095866") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@sub-record-check:15] Set("SIP/GOC-0000830e", "__DAY=27") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@sub-record-check:16] Set("SIP/GOC-0000830e", "__MONTH=10") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@sub-record-check:17] Set("SIP/GOC-0000830e", "__YEAR=2017") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@sub-record-check:18] Set("SIP/GOC-0000830e", "__TIMESTR=20171027-131746") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@sub-record-check:19] Set("SIP/GOC-0000830e", "__FROMEXTEN=759020") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@sub-record-check:20] Set("SIP/GOC-0000830e", "__CALLFILENAME=exten-201-759020-20171027-131746-1509095866.38373") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@sub-record-check:21] Goto("SIP/GOC-0000830e", "exten,1") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Goto (sub-record-check,exten,1)
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [exten@sub-record-check:1] GotoIf("SIP/GOC-0000830e", "0?callee") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [exten@sub-record-check:2] Set("SIP/GOC-0000830e", "__REC_POLICY_MODE=dontcare") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [exten@sub-record-check:3] GotoIf("SIP/GOC-0000830e", "1?caller") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Goto (sub-record-check,exten,10)
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [exten@sub-record-check:10] Set("SIP/GOC-0000830e", "__REC_POLICY_MODE=") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [exten@sub-record-check:11] GosubIf("SIP/GOC-0000830e", "0?record,1(exten,201,759020)") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [exten@sub-record-check:12] Return("SIP/GOC-0000830e", "") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [201@from-did-direct:18] Set("SIP/GOC-0000830e", "RingGroupMethod=ringallv2") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [201@from-did-direct:19] Set("SIP/GOC-0000830e", "_FMGRP=201") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [201@from-did-direct:20] GotoIf("SIP/GOC-0000830e", "0?doconfirm") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [201@from-did-direct:21] Macro("SIP/GOC-0000830e", "dial,20,trI,201") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-dial:1] GotoIf("SIP/GOC-0000830e", "1?dial") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Goto (macro-dial,s,3)
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-dial:3] AGI("SIP/GOC-0000830e", "dialparties.agi") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] res_agi.c:     -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[2017-10-27 13:17:46] VERBOSE[27604] res_agi.c:  dialparties.agi: Starting New Dialparties.agi
[2017-10-27 13:17:46] VERBOSE[27604] res_agi.c:  dialparties.agi: Caller ID name is '893XXXXX655' number is '893XXXXX655'
[2017-10-27 13:17:46] VERBOSE[27604] res_agi.c:  dialparties.agi: Methodology of ring is  'ringallv2'
[2017-10-27 13:17:46] VERBOSE[27604] res_agi.c:     -- dialparties.agi: Added extension 201 to extension map
[2017-10-27 13:17:46] VERBOSE[27604] res_agi.c:     -- dialparties.agi: Extension 201 cf is disabled
[2017-10-27 13:17:46] VERBOSE[27604] res_agi.c:     -- dialparties.agi: Extension 201 do not disturb is disabled
[2017-10-27 13:17:46] VERBOSE[27604] res_agi.c:     -- dialparties.agi: dbset CALLTRACE/201 to 893XXXXX655
[2017-10-27 13:17:46] VERBOSE[27604] res_agi.c:     -- dialparties.agi: Filtered ARG3: 201
[2017-10-27 13:17:46] VERBOSE[27604] res_agi.c:     -- <SIP/GOC-0000830e>AGI Script dialparties.agi completed, returning 0
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-dial:7] Dial("SIP/GOC-0000830e", "SIP/201,20,trIM(auto-blkvm)") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] netsock2.c:   == Using SIP RTP TOS bits 184
[2017-10-27 13:17:46] VERBOSE[27604] netsock2.c:   == Using SIP RTP CoS mark 5
[2017-10-27 13:17:46] VERBOSE[27604] app_dial.c:     -- Called SIP/201
[2017-10-27 13:17:46] VERBOSE[27604] app_dial.c:     -- Connected line update to SIP/GOC-0000830e prevented.
[2017-10-27 13:17:46] VERBOSE[27604] app_dial.c:     -- SIP/201-0000830f is ringing
[2017-10-27 13:17:48] VERBOSE[27604] app_macro.c:   == Spawn extension (macro-dial, s, 7) exited non-zero on 'SIP/GOC-0000830e' in macro 'dial'
[2017-10-27 13:17:48] VERBOSE[27604] pbx.c:   == Spawn extension (from-did-direct, 201, 21) exited non-zero on 'SIP/GOC-0000830e'
[2017-10-27 13:17:48] VERBOSE[27604] pbx.c:     -- Executing [h@from-did-direct:1] Macro("SIP/GOC-0000830e", "hangupcall,") in new stack
[2017-10-27 13:17:48] VERBOSE[27604] pbx.c:     -- Executing [s@macro-hangupcall:1] GotoIf("SIP/GOC-0000830e", "1?theend") in new stack
[2017-10-27 13:17:48] VERBOSE[27604] pbx.c:     -- Goto (macro-hangupcall,s,3)
[2017-10-27 13:17:48] VERBOSE[27604] pbx.c:     -- Executing [s@macro-hangupcall:3] ExecIf("SIP/GOC-0000830e", "0?Set(CDR(recordingfile)=)") in new stack
[2017-10-27 13:17:48] VERBOSE[27604] pbx.c:     -- Executing [s@macro-hangupcall:4] Hangup("SIP/GOC-0000830e", "") in new stack
[2017-10-27 13:17:48] VERBOSE[27604] app_macro.c:   == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/GOC-0000830e' in macro 'hangupcall'
[2017-10-27 13:17:48] VERBOSE[27604] pbx.c:   == Spawn extension (from-did-direct, h, 1) exited non-zero on 'SIP/GOC-0000830e'

Переадресайия Переадресация на сотовый на 4 сек и обратно

Добрый день, уважаемые гуру. Поменял работу и вместе с ней с меди пришлось пересесть на FREE PBX. Не сочтите мой вопрос тупым. FreePBX 2.10.1.9 Задача: при входящем внешнем звонке, если секр не берет трубку переадресовать ей на мобильный. Попробовал: follow me добавить номер сотового# и соотв правило. не заработало. Каким то чудом в транк уходил CID звонящего. Ессно пров это отбивал. Перепробовал прописывать его везде результата-0. Плюнул. СОздал misc. destination с ее номером. Поставил его в follow me и на внутреннем при неответе.

Получил: Входящий звонок- 10 секунд звонит офисный. потом звонит сотовый ровно 5 секунд, потом звонок опять возвращается на сотовый. Подскажите пожалуйста где увеличить время ожидания попытки дозвониться до сотового?

решил приложить лог происходящего при звонке 893XXXXX655 звонящий 210 внутренний номер секр 893XXXXX615 сотовый секр.

    [2017-10-27 13:14:52] VERBOSE[27563] pbx.c:     -- Executing [s@macro-hangupcall                                                                                        :1] GotoIf("SIP/GOC-00008306", "1?theend") in new stack
    [2017-10-27 13:14:52] VERBOSE[27563] pbx.c:     -- Goto (macro-hangupcall,s,3)
    [2017-10-27 13:14:52] VERBOSE[27563] pbx.c:     -- Executing [s@macro-hangupcall                                                                                        :3] ExecIf("SIP/GOC-00008306",

 "0?Set(CDR(recordingfile)=)") in new stack
[2017-10-27 13:14:52] VERBOSE[27563] pbx.c:     -- Executing [s@macro-hangupcall                                                                                        :4] Hangup("SIP/GOC-00008306", "") in new stack
[2017-10-27 13:14:52] VERBOSE[27563] app_macro.c:   == Spawn extension (macro-ha                                                                                        ngupcall, s, 4) exited non-zero on 'SIP/GOC-00008306' in macro 'hangupcall'
[2017-10-27 13:14:52] VERBOSE[27563] features.c:   == Spawn extension (macro-dia                                                                                        lout-trunk, h, 1) exited non-zero on 'SIP/GOC-00008306'
[2017-10-27 13:14:52] VERBOSE[27563] app_macro.c:   == Spawn extension (macro-di                                                                                        alout-trunk, s, 22) exited non-zero on 'SIP/GOC-00008306' in macro 'dialout-trun                                                                                        k'
[2017-10-27 13:14:52] VERBOSE[27563] pbx.c:   == Spawn extension (from-internal,                                                                                         893XXXXX615, 6) exited non-zero on 'SIP/GOC-00008306'
[2017-10-27 13:15:25] NOTICE[3538] chan_sip.c: Peer '120' is now Lagged. (2016ms                                                                                         / 2000ms)
[2017-10-27 13:15:47] NOTICE[3538] chan_sip.c: Peer '120' is now Reachable. (27m                                                                                        s / 2000ms)
[2017-10-27 13:17:15] VERBOSE[3538] netsock2.c:   == Using SIP RTP TOS bits 184
[2017-10-27 13:17:15] VERBOSE[3538] netsock2.c:   == Using SIP RTP CoS mark 5
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [10024@from-trunk-s                                                                                        ip-GOC:1] Set("SIP/GOC-00008308", "GROUP()=OUT_5") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [10024@from-trunk-s                                                                                        ip-GOC:2] Goto("SIP/GOC-00008308", "from-trunk,10024,1") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Goto (from-trunk,10024,1)
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [10024@from-trunk:1                                                                                        ] Set("SIP/GOC-00008308", "__FROM_DID=10024") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [10024@from-trunk:2                                                                                        ] Set("SIP/GOC-00008308", "CDR(did)=10024") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [10024@from-trunk:3                                                                                        ] ExecIf("SIP/GOC-00008308", "0 ?Set(CALLERID(name)=893XXXXX655)") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [10024@from-trunk:4                                                                                        ] Set("SIP/GOC-00008308", "__CALLINGPRES_SV=allowed_not_screened") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [10024@from-trunk:5                                                                                        ] Set("SIP/GOC-00008308", "CALLERPRES()=allowed_not_screened") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [10024@from-trunk:6                                                                                        ] Goto("SIP/GOC-00008308", "from-did-direct,201,1") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Goto (from-did-direct,201,1)
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [201@from-did-direc                                                                                        t:1] GotoIf("SIP/GOC-00008308", "0?ext-local,201,1") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [201@from-did-direc                                                                                        t:2] Macro("SIP/GOC-00008308", "user-callerid,") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-user-calle                                                                                        rid:1] Set("SIP/GOC-00008308", "AMPUSER=893XXXXX655") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-user-calle                                                                                        rid:2] GotoIf("SIP/GOC-00008308", "0?report") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-user-calle                                                                                        rid:3] ExecIf("SIP/GOC-00008308", "1?Set(REALCALLERIDNUM=893XXXXX655)") in new s                                                                                        tack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-user-calle                                                                                        rid:4] Set("SIP/GOC-00008308", "AMPUSER=") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-user-calle                                                                                        rid:5] Set("SIP/GOC-00008308", "AMPUSERCIDNAME=") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-user-calle                                                                                        rid:6] GotoIf("SIP/GOC-00008308", "1?report") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Goto (macro-user-callerid,s,1                                                                                        1)
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-user-calle                                                                                        rid:11] GotoIf("SIP/GOC-00008308", "0?continue") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-user-calle                                                                                        rid:12] Set("SIP/GOC-00008308", "__TTL=64") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-user-calle                                                                                        rid:13] GotoIf("SIP/GOC-00008308", "1?continue") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Goto (macro-user-callerid,s,2                                                                                        4)
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-user-calle                                                                                        rid:24] Set("SIP/GOC-00008308", "CALLERID(number)=893XXXXX655") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-user-calle                                                                                        rid:25] Set("SIP/GOC-00008308", "CALLERID(name)=893XXXXX655") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-user-calle                                                                                        rid:26] Set("SIP/GOC-00008308", "CHANNEL(language)=en") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [201@from-did-direc                                                                                        t:3] Set("SIP/GOC-00008308", "DIAL_OPTIONS=trI") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [201@from-did-direc                                                                                        t:4] Set("SIP/GOC-00008308", "CONNECTEDLINE(num)=201") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [201@from-did-direc                                                                                        t:5] Set("SIP/GOC-00008308", "CONNECTEDLINE(name,i)=Ekaterina Leontyeva") in new                                                                                         stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [201@from-did-direc                                                                                        t:6] Set("SIP/GOC-00008308", "FM_DIALSTATUS=NOT_INUSE") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [201@from-did-direc                                                                                        t:7] Set("SIP/GOC-00008308", "__EXTTOCALL=201") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [201@from-did-direc                                                                                        t:8] Set("SIP/GOC-00008308", "__PICKUPMARK=201") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [201@from-did-direc                                                                                        t:9] Macro("SIP/GOC-00008308", "blkvm-setifempty,") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-blkvm-seti                                                                                        fempty:1] GotoIf("SIP/GOC-00008308", "1?init") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Goto (macro-blkvm-setifempty,                                                                                        s,4)
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-blkvm-seti                                                                                        fempty:4] Set("SIP/GOC-00008308", "__BLKVM_CHANNEL=SIP/GOC-00008308") in new sta                                                                                        ck
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-blkvm-seti                                                                                        fempty:5] Set("SIP/GOC-00008308", "SHARED(BLKVM,SIP/GOC-00008308)=TRUE") in new                                                                                         stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-blkvm-seti                                                                                        fempty:6] Set("SIP/GOC-00008308", "GOSUB_RETVAL=TRUE") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-blkvm-seti                                                                                        fempty:7] MacroExit("SIP/GOC-00008308", "") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [201@from-did-direc                                                                                        t:10] GotoIf("SIP/GOC-00008308", "1?skipov") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Goto (from-did-direct,201,13)
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [201@from-did-direc                                                                                        t:13] Set("SIP/GOC-00008308", "RRNODEST=") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [201@from-did-direc                                                                                        t:14] Set("SIP/GOC-00008308", "__NODEST=201") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [201@from-did-direc                                                                                        t:15] GosubIf("SIP/GOC-00008308", "1?sub-fmsetcid,s,1()") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@sub-fmsetcid:1]                                                                                         Goto("SIP/GOC-00008308", "s-did,1") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Goto (sub-fmsetcid,s-did,1)
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s-did@sub-fmsetcid                                                                                        :1] ExecIf("SIP/GOC-00008308", "1?Set(__REALCALLERIDNUM=10024)") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s-did@sub-fmsetcid                                                                                        :2] Return("SIP/GOC-00008308", "") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [201@from-did-direc                                                                                        t:16] Set("SIP/GOC-00008308", "RecordMethod=Group") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [201@from-did-direc                                                                                        t:17] Gosub("SIP/GOC-00008308", "sub-record-check,s,1(exten,201,)") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@sub-record-check                                                                                        :1] GotoIf("SIP/GOC-00008308", "1?check") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Goto (sub-record-check,s,6)
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@sub-record-check                                                                                        :6] Set("SIP/GOC-00008308", "__MON_FMT=wav") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@sub-record-check                                                                                        :7] GotoIf("SIP/GOC-00008308", "1?next") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Goto (sub-record-check,s,10)
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@sub-record-check                                                                                        :10] ExecIf("SIP/GOC-00008308", "0?Return()") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@sub-record-check                                                                                        :11] GotoIf("SIP/GOC-00008308", "0?exten,1") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@sub-record-check                                                                                        :12] Set("SIP/GOC-00008308", "__REC_STATUS=INITIALIZED") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@sub-record-check                                                                                        :13] ExecIf("SIP/GOC-00008308", "0?Set(__REC_POLICY_MODE=)") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@sub-record-check                                                                                        :14] Set("SIP/GOC-00008308", "NOW=1509095835") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@sub-record-check                                                                                        :15] Set("SIP/GOC-00008308", "__DAY=27") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@sub-record-check                                                                                        :16] Set("SIP/GOC-00008308", "__MONTH=10") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@sub-record-check                                                                                        :17] Set("SIP/GOC-00008308", "__YEAR=2017") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@sub-record-check                                                                                        :18] Set("SIP/GOC-00008308", "__TIMESTR=20171027-131715") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@sub-record-check                                                                                        :19] Set("SIP/GOC-00008308", "__FROMEXTEN=10024") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@sub-record-check                                                                                        :20] Set("SIP/GOC-00008308", "__CALLFILENAME=exten-201-10024-20171027-131715-150                                                                                        9095835.38367") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@sub-record-check                                                                                        :21] Goto("SIP/GOC-00008308", "exten,1") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Goto (sub-record-check,exten,                                                                                        1)
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [exten@sub-record-c                                                                                        heck:1] GotoIf("SIP/GOC-00008308", "0?callee") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [exten@sub-record-c                                                                                        heck:2] Set("SIP/GOC-00008308", "__REC_POLICY_MODE=dontcare") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [exten@sub-record-c                                                                                        heck:3] GotoIf("SIP/GOC-00008308", "1?caller") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Goto (sub-record-check,exten,                                                                                        10)
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [exten@sub-record-c                                                                                        heck:10] Set("SIP/GOC-00008308", "__REC_POLICY_MODE=") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [exten@sub-record-c                                                                                        heck:11] GosubIf("SIP/GOC-00008308", "0?record,1(exten,201,10024)") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [exten@sub-record-c                                                                                        heck:12] Return("SIP/GOC-00008308", "") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [201@from-did-direc                                                                                        t:18] Set("SIP/GOC-00008308", "RingGroupMethod=ringallv2") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [201@from-did-direc                                                                                        t:19] Set("SIP/GOC-00008308", "_FMGRP=201") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [201@from-did-direc                                                                                        t:20] GotoIf("SIP/GOC-00008308", "0?doconfirm") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [201@from-did-direc                                                                                        t:21] Macro("SIP/GOC-00008308", "dial,20,trI,201") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-dial:1] Go                                                                                        toIf("SIP/GOC-00008308", "1?dial") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Goto (macro-dial,s,3)
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-dial:3] AG                                                                                        I("SIP/GOC-00008308", "dialparties.agi") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] res_agi.c:     -- Launched AGI Script /var/                                                                                        lib/asterisk/agi-bin/dialparties.agi
[2017-10-27 13:17:15] VERBOSE[27599] res_agi.c:  dialparties.agi: Starting New D                                                                                        ialparties.agi
[2017-10-27 13:17:15] VERBOSE[27599] res_agi.c:  dialparties.agi: Caller ID name                                                                                         is '893XXXXX655' number is '893XXXXX655'
[2017-10-27 13:17:15] VERBOSE[27599] res_agi.c:  dialparties.agi: Methodology of                                                                                         ring is  'ringallv2'
[2017-10-27 13:17:15] VERBOSE[27599] res_agi.c:     -- dialparties.agi: Added ex                                                                                        tension 201 to extension map
[2017-10-27 13:17:15] VERBOSE[27599] res_agi.c:     -- dialparties.agi: Extensio                                                                                        n 201 cf is disabled
[2017-10-27 13:17:15] VERBOSE[27599] res_agi.c:     -- dialparties.agi: Extensio                                                                                        n 201 do not disturb is disabled
[2017-10-27 13:17:15] VERBOSE[27599] res_agi.c:     -- dialparties.agi: dbset CA                                                                                        LLTRACE/201 to 893XXXXX655
[2017-10-27 13:17:15] VERBOSE[27599] res_agi.c:     -- dialparties.agi: Filtered                                                                                         ARG3: 201
[2017-10-27 13:17:15] VERBOSE[27599] res_agi.c:     -- <SIP/GOC-00008308>AGI Scr                                                                                        ipt dialparties.agi completed, returning 0
[2017-10-27 13:17:15] VERBOSE[27599] pbx.c:     -- Executing [s@macro-dial:7] Di                                                                                        al("SIP/GOC-00008308", "SIP/201,20,trIM(auto-blkvm)") in new stack
[2017-10-27 13:17:15] VERBOSE[27599] netsock2.c:   == Using SIP RTP TOS bits 184
[2017-10-27 13:17:15] VERBOSE[27599] netsock2.c:   == Using SIP RTP CoS mark 5
[2017-10-27 13:17:15] VERBOSE[27599] app_dial.c:     -- Called SIP/201
[2017-10-27 13:17:15] VERBOSE[27599] app_dial.c:     -- Connected line update to                                                                                         SIP/GOC-00008308 prevented.
[2017-10-27 13:17:16] VERBOSE[27599] app_dial.c:     -- SIP/201-00008309 is ring                                                                                        ing
[2017-10-27 13:17:25] VERBOSE[27599] app_macro.c:   == Spawn extension (macro-dial, s, 7) exited non-zero on 'SIP/GOC-00008308' in macro 'dial'
[2017-10-27 13:17:25] VERBOSE[27599] pbx.c:   == Spawn extension (from-did-direct, 201, 21) exited non-zero on 'SIP/GOC-00008308'
[2017-10-27 13:17:25] VERBOSE[27599] pbx.c:     -- Executing [h@from-did-direct:1] Macro("SIP/GOC-00008308", "hangupcall,") in new stack
[2017-10-27 13:17:25] VERBOSE[27599] pbx.c:     -- Executing [s@macro-hangupcall:1] GotoIf("SIP/GOC-00008308", "1?theend") in new stack
[2017-10-27 13:17:25] VERBOSE[27599] pbx.c:     -- Goto (macro-hangupcall,s,3)
[2017-10-27 13:17:25] VERBOSE[27599] pbx.c:     -- Executing [s@macro-hangupcall:3] ExecIf("SIP/GOC-00008308", "0?Set(CDR(recordingfile)=)") in new stack
[2017-10-27 13:17:25] VERBOSE[27599] pbx.c:     -- Executing [s@macro-hangupcall:4] Hangup("SIP/GOC-00008308", "") in new stack
[2017-10-27 13:17:25] VERBOSE[27599] app_macro.c:   == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/GOC-00008308' in macro 'hangupcall'
[2017-10-27 13:17:25] VERBOSE[27599] pbx.c:   == Spawn extension (from-did-direct, h, 1) exited non-zero on 'SIP/GOC-00008308'
[2017-10-27 13:17:25] VERBOSE[3538] netsock2.c:   == Using SIP RTP TOS bits 184
[2017-10-27 13:17:25] VERBOSE[3538] netsock2.c:   == Using SIP RTP CoS mark 5
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [10025@from-trunk-sip-GOC:1] Set("SIP/GOC-0000830a", "GROUP()=OUT_5") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [10025@from-trunk-sip-GOC:2] Goto("SIP/GOC-0000830a", "from-trunk,10025,1") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Goto (from-trunk,10025,1)
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [10025@from-trunk:1] Set("SIP/GOC-0000830a", "__FROM_DID=10025") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [10025@from-trunk:2] Set("SIP/GOC-0000830a", "CDR(did)=10025") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [10025@from-trunk:3] ExecIf("SIP/GOC-0000830a", "0 ?Set(CALLERID(name)=893XXXXX655)") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [10025@from-trunk:4] Set("SIP/GOC-0000830a", "__CALLINGPRES_SV=allowed_not_screened") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [10025@from-trunk:5] Set("SIP/GOC-0000830a", "CALLERPRES()=allowed_not_screened") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [10025@from-trunk:6] Goto("SIP/GOC-0000830a", "from-did-direct,201,1") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Goto (from-did-direct,201,1)
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [201@from-did-direct:1] GotoIf("SIP/GOC-0000830a", "0?ext-local,201,1") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [201@from-did-direct:2] Macro("SIP/GOC-0000830a", "user-callerid,") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-user-callerid:1] Set("SIP/GOC-0000830a", "AMPUSER=893XXXXX655") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-user-callerid:2] GotoIf("SIP/GOC-0000830a", "0?report") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-user-callerid:3] ExecIf("SIP/GOC-0000830a", "1?Set(REALCALLERIDNUM=893XXXXX655)") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-user-callerid:4] Set("SIP/GOC-0000830a", "AMPUSER=") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-user-callerid:5] Set("SIP/GOC-0000830a", "AMPUSERCIDNAME=") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-user-callerid:6] GotoIf("SIP/GOC-0000830a", "1?report") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Goto (macro-user-callerid,s,11)
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-user-callerid:11] GotoIf("SIP/GOC-0000830a", "0?continue") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-user-callerid:12] Set("SIP/GOC-0000830a", "__TTL=64") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-user-callerid:13] GotoIf("SIP/GOC-0000830a", "1?continue") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Goto (macro-user-callerid,s,24)
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-user-callerid:24] Set("SIP/GOC-0000830a", "CALLERID(number)=893XXXXX655") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-user-callerid:25] Set("SIP/GOC-0000830a", "CALLERID(name)=893XXXXX655") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-user-callerid:26] Set("SIP/GOC-0000830a", "CHANNEL(language)=en") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [201@from-did-direct:3] Set("SIP/GOC-0000830a", "DIAL_OPTIONS=trI") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [201@from-did-direct:4] Set("SIP/GOC-0000830a", "CONNECTEDLINE(num)=201") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [201@from-did-direct:5] Set("SIP/GOC-0000830a", "CONNECTEDLINE(name,i)=Ekaterina Leontyeva") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [201@from-did-direct:6] Set("SIP/GOC-0000830a", "FM_DIALSTATUS=NOT_INUSE") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [201@from-did-direct:7] Set("SIP/GOC-0000830a", "__EXTTOCALL=201") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [201@from-did-direct:8] Set("SIP/GOC-0000830a", "__PICKUPMARK=201") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [201@from-did-direct:9] Macro("SIP/GOC-0000830a", "blkvm-setifempty,") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-blkvm-setifempty:1] GotoIf("SIP/GOC-0000830a", "1?init") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Goto (macro-blkvm-setifempty,s,4)
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-blkvm-setifempty:4] Set("SIP/GOC-0000830a", "__BLKVM_CHANNEL=SIP/GOC-0000830a") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-blkvm-setifempty:5] Set("SIP/GOC-0000830a", "SHARED(BLKVM,SIP/GOC-0000830a)=TRUE") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-blkvm-setifempty:6] Set("SIP/GOC-0000830a", "GOSUB_RETVAL=TRUE") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-blkvm-setifempty:7] MacroExit("SIP/GOC-0000830a", "") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [201@from-did-direct:10] GotoIf("SIP/GOC-0000830a", "1?skipov") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Goto (from-did-direct,201,13)
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [201@from-did-direct:13] Set("SIP/GOC-0000830a", "RRNODEST=") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [201@from-did-direct:14] Set("SIP/GOC-0000830a", "__NODEST=201") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [201@from-did-direct:15] GosubIf("SIP/GOC-0000830a", "1?sub-fmsetcid,s,1()") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@sub-fmsetcid:1] Goto("SIP/GOC-0000830a", "s-did,1") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Goto (sub-fmsetcid,s-did,1)
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s-did@sub-fmsetcid:1] ExecIf("SIP/GOC-0000830a", "1?Set(__REALCALLERIDNUM=10025)") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s-did@sub-fmsetcid:2] Return("SIP/GOC-0000830a", "") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [201@from-did-direct:16] Set("SIP/GOC-0000830a", "RecordMethod=Group") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [201@from-did-direct:17] Gosub("SIP/GOC-0000830a", "sub-record-check,s,1(exten,201,)") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@sub-record-check:1] GotoIf("SIP/GOC-0000830a", "1?check") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Goto (sub-record-check,s,6)
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@sub-record-check:6] Set("SIP/GOC-0000830a", "__MON_FMT=wav") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@sub-record-check:7] GotoIf("SIP/GOC-0000830a", "1?next") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Goto (sub-record-check,s,10)
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@sub-record-check:10] ExecIf("SIP/GOC-0000830a", "0?Return()") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@sub-record-check:11] GotoIf("SIP/GOC-0000830a", "0?exten,1") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@sub-record-check:12] Set("SIP/GOC-0000830a", "__REC_STATUS=INITIALIZED") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@sub-record-check:13] ExecIf("SIP/GOC-0000830a", "0?Set(__REC_POLICY_MODE=)") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@sub-record-check:14] Set("SIP/GOC-0000830a", "NOW=1509095845") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@sub-record-check:15] Set("SIP/GOC-0000830a", "__DAY=27") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@sub-record-check:16] Set("SIP/GOC-0000830a", "__MONTH=10") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@sub-record-check:17] Set("SIP/GOC-0000830a", "__YEAR=2017") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@sub-record-check:18] Set("SIP/GOC-0000830a", "__TIMESTR=20171027-131725") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@sub-record-check:19] Set("SIP/GOC-0000830a", "__FROMEXTEN=10025") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@sub-record-check:20] Set("SIP/GOC-0000830a", "__CALLFILENAME=exten-201-10025-20171027-131725-1509095845.38369") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@sub-record-check:21] Goto("SIP/GOC-0000830a", "exten,1") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Goto (sub-record-check,exten,1)
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [exten@sub-record-check:1] GotoIf("SIP/GOC-0000830a", "0?callee") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [exten@sub-record-check:2] Set("SIP/GOC-0000830a", "__REC_POLICY_MODE=dontcare") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [exten@sub-record-check:3] GotoIf("SIP/GOC-0000830a", "1?caller") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Goto (sub-record-check,exten,10)
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [exten@sub-record-check:10] Set("SIP/GOC-0000830a", "__REC_POLICY_MODE=") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [exten@sub-record-check:11] GosubIf("SIP/GOC-0000830a", "0?record,1(exten,201,10025)") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [exten@sub-record-check:12] Return("SIP/GOC-0000830a", "") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [201@from-did-direct:18] Set("SIP/GOC-0000830a", "RingGroupMethod=ringallv2") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [201@from-did-direct:19] Set("SIP/GOC-0000830a", "_FMGRP=201") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [201@from-did-direct:20] GotoIf("SIP/GOC-0000830a", "0?doconfirm") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [201@from-did-direct:21] Macro("SIP/GOC-0000830a", "dial,20,trI,201") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-dial:1] GotoIf("SIP/GOC-0000830a", "1?dial") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Goto (macro-dial,s,3)
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-dial:3] AGI("SIP/GOC-0000830a", "dialparties.agi") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] res_agi.c:     -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[2017-10-27 13:17:25] VERBOSE[27601] res_agi.c:  dialparties.agi: Starting New Dialparties.agi
[2017-10-27 13:17:25] VERBOSE[27601] res_agi.c:  dialparties.agi: Caller ID name is '893XXXXX655' number is '893XXXXX655'
[2017-10-27 13:17:25] VERBOSE[27601] res_agi.c:  dialparties.agi: Methodology of ring is  'ringallv2'
[2017-10-27 13:17:25] VERBOSE[27601] res_agi.c:     -- dialparties.agi: Added extension 201 to extension map
[2017-10-27 13:17:25] VERBOSE[27601] res_agi.c:     -- dialparties.agi: Extension 201 cf is disabled
[2017-10-27 13:17:25] VERBOSE[27601] res_agi.c:     -- dialparties.agi: Extension 201 do not disturb is disabled
[2017-10-27 13:17:25] VERBOSE[27601] res_agi.c:     -- dialparties.agi: dbset CALLTRACE/201 to 893XXXXX655
[2017-10-27 13:17:25] VERBOSE[27601] res_agi.c:     -- dialparties.agi: Filtered ARG3: 201
[2017-10-27 13:17:25] VERBOSE[27601] res_agi.c:     -- <SIP/GOC-0000830a>AGI Script dialparties.agi completed, returning 0
[2017-10-27 13:17:25] VERBOSE[27601] pbx.c:     -- Executing [s@macro-dial:7] Dial("SIP/GOC-0000830a", "SIP/201,20,trIM(auto-blkvm)") in new stack
[2017-10-27 13:17:25] VERBOSE[27601] netsock2.c:   == Using SIP RTP TOS bits 184
[2017-10-27 13:17:25] VERBOSE[27601] netsock2.c:   == Using SIP RTP CoS mark 5
[2017-10-27 13:17:25] VERBOSE[27601] app_dial.c:     -- Called SIP/201
[2017-10-27 13:17:25] VERBOSE[27601] app_dial.c:     -- Connected line update to SIP/GOC-0000830a prevented.
[2017-10-27 13:17:26] VERBOSE[27601] app_dial.c:     -- SIP/201-0000830b is ringing
[2017-10-27 13:17:35] VERBOSE[27601] app_macro.c:   == Spawn extension (macro-dial, s, 7) exited non-zero on 'SIP/GOC-0000830a' in macro 'dial'
[2017-10-27 13:17:35] VERBOSE[27601] pbx.c:   == Spawn extension (from-did-direct, 201, 21) exited non-zero on 'SIP/GOC-0000830a'
[2017-10-27 13:17:35] VERBOSE[27601] pbx.c:     -- Executing [h@from-did-direct:1] Macro("SIP/GOC-0000830a", "hangupcall,") in new stack
[2017-10-27 13:17:35] VERBOSE[27601] pbx.c:     -- Executing [s@macro-hangupcall:1] GotoIf("SIP/GOC-0000830a", "1?theend") in new stack
[2017-10-27 13:17:35] VERBOSE[27601] pbx.c:     -- Goto (macro-hangupcall,s,3)
[2017-10-27 13:17:35] VERBOSE[27601] pbx.c:     -- Executing [s@macro-hangupcall:3] ExecIf("SIP/GOC-0000830a", "0?Set(CDR(recordingfile)=)") in new stack
[2017-10-27 13:17:35] VERBOSE[27601] pbx.c:     -- Executing [s@macro-hangupcall:4] Hangup("SIP/GOC-0000830a", "") in new stack
[2017-10-27 13:17:35] VERBOSE[27601] app_macro.c:   == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/GOC-0000830a' in macro 'hangupcall'
[2017-10-27 13:17:35] VERBOSE[27601] pbx.c:   == Spawn extension (from-did-direct, h, 1) exited non-zero on 'SIP/GOC-0000830a'
[2017-10-27 13:17:36] VERBOSE[3538] netsock2.c:   == Using SIP RTP TOS bits 184
[2017-10-27 13:17:36] VERBOSE[3538] netsock2.c:   == Using SIP RTP CoS mark 5
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [10026@from-trunk-sip-GOC:1] Set("SIP/GOC-0000830c", "GROUP()=OUT_5") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [10026@from-trunk-sip-GOC:2] Goto("SIP/GOC-0000830c", "from-trunk,10026,1") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Goto (from-trunk,10026,1)
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [10026@from-trunk:1] Set("SIP/GOC-0000830c", "__FROM_DID=10026") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [10026@from-trunk:2] Set("SIP/GOC-0000830c", "CDR(did)=10026") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [10026@from-trunk:3] ExecIf("SIP/GOC-0000830c", "0 ?Set(CALLERID(name)=893XXXXX655)") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [10026@from-trunk:4] Set("SIP/GOC-0000830c", "__CALLINGPRES_SV=allowed_not_screened") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [10026@from-trunk:5] Set("SIP/GOC-0000830c", "CALLERPRES()=allowed_not_screened") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [10026@from-trunk:6] Goto("SIP/GOC-0000830c", "ext-miscdests,1,1") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Goto (ext-miscdests,1,1)
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [1@ext-miscdests:1] NoOp("SIP/GOC-0000830c", "MiscDest: Kate") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [1@ext-miscdests:2] Goto("SIP/GOC-0000830c", "from-internal,893XXXXX615,1") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Goto (from-internal,893XXXXX615,1)
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [893XXXXX615@from-internal:1] Macro("SIP/GOC-0000830c", "user-callerid,LIMIT,") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-user-callerid:1] Set("SIP/GOC-0000830c", "AMPUSER=893XXXXX655") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-user-callerid:2] GotoIf("SIP/GOC-0000830c", "0?report") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-user-callerid:3] ExecIf("SIP/GOC-0000830c", "1?Set(REALCALLERIDNUM=893XXXXX655)") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-user-callerid:4] Set("SIP/GOC-0000830c", "AMPUSER=") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-user-callerid:5] Set("SIP/GOC-0000830c", "AMPUSERCIDNAME=") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-user-callerid:6] GotoIf("SIP/GOC-0000830c", "1?report") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Goto (macro-user-callerid,s,11)
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-user-callerid:11] GotoIf("SIP/GOC-0000830c", "1?continue") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Goto (macro-user-callerid,s,24)
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-user-callerid:24] Set("SIP/GOC-0000830c", "CALLERID(number)=893XXXXX655") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-user-callerid:25] Set("SIP/GOC-0000830c", "CALLERID(name)=893XXXXX655") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-user-callerid:26] Set("SIP/GOC-0000830c", "CHANNEL(language)=en") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [893XXXXX615@from-internal:2] Set("SIP/GOC-0000830c", "MOHCLASS=default") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [893XXXXX615@from-internal:3] ExecIf("SIP/GOC-0000830c", "1?Set(TRUNKCIDOVERRIDE=759020)") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [893XXXXX615@from-internal:4] Set("SIP/GOC-0000830c", "_NODEST=") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [893XXXXX615@from-internal:5] Gosub("SIP/GOC-0000830c", "sub-record-check,s,1(out,893XXXXX615,)") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@sub-record-check:1] GotoIf("SIP/GOC-0000830c", "1?check") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Goto (sub-record-check,s,6)
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@sub-record-check:6] Set("SIP/GOC-0000830c", "__MON_FMT=wav") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@sub-record-check:7] GotoIf("SIP/GOC-0000830c", "1?next") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Goto (sub-record-check,s,10)
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@sub-record-check:10] ExecIf("SIP/GOC-0000830c", "0?Return()") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@sub-record-check:11] GotoIf("SIP/GOC-0000830c", "0?out,1") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@sub-record-check:12] Set("SIP/GOC-0000830c", "__REC_STATUS=INITIALIZED") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@sub-record-check:13] ExecIf("SIP/GOC-0000830c", "0?Set(__REC_POLICY_MODE=)") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@sub-record-check:14] Set("SIP/GOC-0000830c", "NOW=1509095856") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@sub-record-check:15] Set("SIP/GOC-0000830c", "__DAY=27") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@sub-record-check:16] Set("SIP/GOC-0000830c", "__MONTH=10") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@sub-record-check:17] Set("SIP/GOC-0000830c", "__YEAR=2017") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@sub-record-check:18] Set("SIP/GOC-0000830c", "__TIMESTR=20171027-131736") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@sub-record-check:19] Set("SIP/GOC-0000830c", "__FROMEXTEN=893XXXXX655") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@sub-record-check:20] Set("SIP/GOC-0000830c", "__CALLFILENAME=out-893XXXXX615-893XXXXX655-20171027-131736-1509095856.38371") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@sub-record-check:21] Goto("SIP/GOC-0000830c", "out,1") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Goto (sub-record-check,out,1)
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [out@sub-record-check:1] ExecIf("SIP/GOC-0000830c", "1?Set(__REC_POLICY_MODE=)") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [out@sub-record-check:2] GosubIf("SIP/GOC-0000830c", "0?record,1(exten,893XXXXX615,893XXXXX655)") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [out@sub-record-check:3] Return("SIP/GOC-0000830c", "") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [893XXXXX615@from-internal:6] Macro("SIP/GOC-0000830c", "dialout-trunk,2,893XXXXX615,") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:1] Set("SIP/GOC-0000830c", "DIAL_TRUNK=2") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:2] GosubIf("SIP/GOC-0000830c", "0?sub-pincheck,s,1()") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:3] GotoIf("SIP/GOC-0000830c", "0?disabletrunk,1") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:4] Set("SIP/GOC-0000830c", "DIAL_NUMBER=893XXXXX615") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:5] Set("SIP/GOC-0000830c", "DIAL_TRUNK_OPTIONS=tr") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:6] Set("SIP/GOC-0000830c", "OUTBOUND_GROUP=OUT_2") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:7] GotoIf("SIP/GOC-0000830c", "1?nomax") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Goto (macro-dialout-trunk,s,9)
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:9] GotoIf("SIP/GOC-0000830c", "0?skipoutcid") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:10] Set("SIP/GOC-0000830c", "DIAL_TRUNK_OPTIONS=") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:11] Macro("SIP/GOC-0000830c", "outbound-callerid,2") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-outbound-callerid:1] ExecIf("SIP/GOC-0000830c", "1?Set(CALLERPRES()=allowed_not_screened)") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-outbound-callerid:2] ExecIf("SIP/GOC-0000830c", "0?Set(REALCALLERIDNUM=893XXXXX655)") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-outbound-callerid:3] GotoIf("SIP/GOC-0000830c", "1?normcid") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Goto (macro-outbound-callerid,s,6)
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-outbound-callerid:6] Set("SIP/GOC-0000830c", "USEROUTCID=") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-outbound-callerid:7] Set("SIP/GOC-0000830c", "EMERGENCYCID=") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-outbound-callerid:8] Set("SIP/GOC-0000830c", "TRUNKOUTCID=<78422759020>") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-outbound-callerid:9] GotoIf("SIP/GOC-0000830c", "1?trunkcid") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Goto (macro-outbound-callerid,s,12)
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-outbound-callerid:12] ExecIf("SIP/GOC-0000830c", "1?Set(CALLERID(all)=<78422759020>)") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-outbound-callerid:13] ExecIf("SIP/GOC-0000830c", "0?Set(CALLERID(all)=)") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-outbound-callerid:14] ExecIf("SIP/GOC-0000830c", "1?Set(CALLERID(all)=759020)") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-outbound-callerid:15] ExecIf("SIP/GOC-0000830c", "0?Set(CALLERPRES()=prohib_passed_screen)") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:12] GosubIf("SIP/GOC-0000830c", "0?sub-flp-2,s,1()") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:13] Set("SIP/GOC-0000830c", "OUTNUM=893XXXXX615") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:14] Set("SIP/GOC-0000830c", "custom=SIP/QW") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:15] ExecIf("SIP/GOC-0000830c", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default))") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:16] ExecIf("SIP/GOC-0000830c", "0?Set(DIAL_TRUNK_OPTIONS=M(confirm))") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:17] Macro("SIP/GOC-0000830c", "dialout-trunk-predial-hook,") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/GOC-0000830c", "") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:18] GotoIf("SIP/GOC-0000830c", "0?bypass,1") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:19] ExecIf("SIP/GOC-0000830c", "0?Set(CONNECTEDLINE(num,i)=893XXXXX615)") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:20] ExecIf("SIP/GOC-0000830c", "0?Set(CONNECTEDLINE(name,i)=CID:759020)") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:21] GotoIf("SIP/GOC-0000830c", "0?customtrunk") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] pbx.c:     -- Executing [s@macro-dialout-trunk:22] Dial("SIP/GOC-0000830c", "SIP/QW/893XXXXX615,300,") in new stack
[2017-10-27 13:17:36] VERBOSE[27603] netsock2.c:   == Using SIP RTP TOS bits 184
[2017-10-27 13:17:36] VERBOSE[27603] netsock2.c:   == Using SIP RTP CoS mark 5
[2017-10-27 13:17:36] VERBOSE[27603] app_dial.c:     -- Called SIP/QW/893XXXXX615
[2017-10-27 13:17:36] VERBOSE[27603] app_dial.c:     -- SIP/QW-0000830d is ringing
[2017-10-27 13:17:40] VERBOSE[27603] app_dial.c:     -- SIP/QW-0000830d is ringing
[2017-10-27 13:17:46] VERBOSE[27603] app_macro.c:   == Spawn extension (macro-dialout-trunk, s, 22) exited non-zero on 'SIP/GOC-0000830c' in macro 'dialout-trunk'
[2017-10-27 13:17:46] VERBOSE[27603] pbx.c:   == Spawn extension (from-internal, 893XXXXX615, 6) exited non-zero on 'SIP/GOC-0000830c'
[2017-10-27 13:17:46] VERBOSE[27603] pbx.c:     -- Executing [h@from-internal:1] Hangup("SIP/GOC-0000830c", "") in new stack
[2017-10-27 13:17:46] VERBOSE[27603] pbx.c:   == Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/GOC-0000830c'
[2017-10-27 13:17:46] VERBOSE[3538] netsock2.c:   == Using SIP RTP TOS bits 184
[2017-10-27 13:17:46] VERBOSE[3538] netsock2.c:   == Using SIP RTP CoS mark 5
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [759020@from-trunk-sip-GOC:1] Set("SIP/GOC-0000830e", "GROUP()=OUT_5") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [759020@from-trunk-sip-GOC:2] Goto("SIP/GOC-0000830e", "from-trunk,759020,1") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Goto (from-trunk,759020,1)
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [759020@from-trunk:1] Set("SIP/GOC-0000830e", "__FROM_DID=759020") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [759020@from-trunk:2] Set("SIP/GOC-0000830e", "CDR(did)=759020") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [759020@from-trunk:3] ExecIf("SIP/GOC-0000830e", "0 ?Set(CALLERID(name)=893XXXXX655)") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [759020@from-trunk:4] Set("SIP/GOC-0000830e", "__CALLINGPRES_SV=allowed_not_screened") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [759020@from-trunk:5] Set("SIP/GOC-0000830e", "CALLERPRES()=allowed_not_screened") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [759020@from-trunk:6] Goto("SIP/GOC-0000830e", "from-did-direct,201,1") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Goto (from-did-direct,201,1)
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [201@from-did-direct:1] GotoIf("SIP/GOC-0000830e", "0?ext-local,201,1") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [201@from-did-direct:2] Macro("SIP/GOC-0000830e", "user-callerid,") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-user-callerid:1] Set("SIP/GOC-0000830e", "AMPUSER=893XXXXX655") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-user-callerid:2] GotoIf("SIP/GOC-0000830e", "0?report") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-user-callerid:3] ExecIf("SIP/GOC-0000830e", "1?Set(REALCALLERIDNUM=893XXXXX655)") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-user-callerid:4] Set("SIP/GOC-0000830e", "AMPUSER=") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-user-callerid:5] Set("SIP/GOC-0000830e", "AMPUSERCIDNAME=") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-user-callerid:6] GotoIf("SIP/GOC-0000830e", "1?report") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Goto (macro-user-callerid,s,11)
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-user-callerid:11] GotoIf("SIP/GOC-0000830e", "0?continue") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-user-callerid:12] Set("SIP/GOC-0000830e", "__TTL=64") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-user-callerid:13] GotoIf("SIP/GOC-0000830e", "1?continue") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Goto (macro-user-callerid,s,24)
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-user-callerid:24] Set("SIP/GOC-0000830e", "CALLERID(number)=893XXXXX655") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-user-callerid:25] Set("SIP/GOC-0000830e", "CALLERID(name)=893XXXXX655") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-user-callerid:26] Set("SIP/GOC-0000830e", "CHANNEL(language)=en") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [201@from-did-direct:3] Set("SIP/GOC-0000830e", "DIAL_OPTIONS=trI") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [201@from-did-direct:4] Set("SIP/GOC-0000830e", "CONNECTEDLINE(num)=201") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [201@from-did-direct:5] Set("SIP/GOC-0000830e", "CONNECTEDLINE(name,i)=Ekaterina Leontyeva") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [201@from-did-direct:6] Set("SIP/GOC-0000830e", "FM_DIALSTATUS=NOT_INUSE") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [201@from-did-direct:7] Set("SIP/GOC-0000830e", "__EXTTOCALL=201") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [201@from-did-direct:8] Set("SIP/GOC-0000830e", "__PICKUPMARK=201") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [201@from-did-direct:9] Macro("SIP/GOC-0000830e", "blkvm-setifempty,") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-blkvm-setifempty:1] GotoIf("SIP/GOC-0000830e", "1?init") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Goto (macro-blkvm-setifempty,s,4)
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-blkvm-setifempty:4] Set("SIP/GOC-0000830e", "__BLKVM_CHANNEL=SIP/GOC-0000830e") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-blkvm-setifempty:5] Set("SIP/GOC-0000830e", "SHARED(BLKVM,SIP/GOC-0000830e)=TRUE") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-blkvm-setifempty:6] Set("SIP/GOC-0000830e", "GOSUB_RETVAL=TRUE") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-blkvm-setifempty:7] MacroExit("SIP/GOC-0000830e", "") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [201@from-did-direct:10] GotoIf("SIP/GOC-0000830e", "1?skipov") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Goto (from-did-direct,201,13)
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [201@from-did-direct:13] Set("SIP/GOC-0000830e", "RRNODEST=") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [201@from-did-direct:14] Set("SIP/GOC-0000830e", "__NODEST=201") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [201@from-did-direct:15] GosubIf("SIP/GOC-0000830e", "1?sub-fmsetcid,s,1()") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@sub-fmsetcid:1] Goto("SIP/GOC-0000830e", "s-did,1") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Goto (sub-fmsetcid,s-did,1)
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s-did@sub-fmsetcid:1] ExecIf("SIP/GOC-0000830e", "1?Set(__REALCALLERIDNUM=759020)") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s-did@sub-fmsetcid:2] Return("SIP/GOC-0000830e", "") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [201@from-did-direct:16] Set("SIP/GOC-0000830e", "RecordMethod=Group") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [201@from-did-direct:17] Gosub("SIP/GOC-0000830e", "sub-record-check,s,1(exten,201,)") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@sub-record-check:1] GotoIf("SIP/GOC-0000830e", "1?check") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Goto (sub-record-check,s,6)
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@sub-record-check:6] Set("SIP/GOC-0000830e", "__MON_FMT=wav") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@sub-record-check:7] GotoIf("SIP/GOC-0000830e", "1?next") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Goto (sub-record-check,s,10)
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@sub-record-check:10] ExecIf("SIP/GOC-0000830e", "0?Return()") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@sub-record-check:11] GotoIf("SIP/GOC-0000830e", "0?exten,1") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@sub-record-check:12] Set("SIP/GOC-0000830e", "__REC_STATUS=INITIALIZED") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@sub-record-check:13] ExecIf("SIP/GOC-0000830e", "0?Set(__REC_POLICY_MODE=)") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@sub-record-check:14] Set("SIP/GOC-0000830e", "NOW=1509095866") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@sub-record-check:15] Set("SIP/GOC-0000830e", "__DAY=27") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@sub-record-check:16] Set("SIP/GOC-0000830e", "__MONTH=10") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@sub-record-check:17] Set("SIP/GOC-0000830e", "__YEAR=2017") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@sub-record-check:18] Set("SIP/GOC-0000830e", "__TIMESTR=20171027-131746") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@sub-record-check:19] Set("SIP/GOC-0000830e", "__FROMEXTEN=759020") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@sub-record-check:20] Set("SIP/GOC-0000830e", "__CALLFILENAME=exten-201-759020-20171027-131746-1509095866.38373") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@sub-record-check:21] Goto("SIP/GOC-0000830e", "exten,1") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Goto (sub-record-check,exten,1)
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [exten@sub-record-check:1] GotoIf("SIP/GOC-0000830e", "0?callee") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [exten@sub-record-check:2] Set("SIP/GOC-0000830e", "__REC_POLICY_MODE=dontcare") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [exten@sub-record-check:3] GotoIf("SIP/GOC-0000830e", "1?caller") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Goto (sub-record-check,exten,10)
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [exten@sub-record-check:10] Set("SIP/GOC-0000830e", "__REC_POLICY_MODE=") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [exten@sub-record-check:11] GosubIf("SIP/GOC-0000830e", "0?record,1(exten,201,759020)") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [exten@sub-record-check:12] Return("SIP/GOC-0000830e", "") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [201@from-did-direct:18] Set("SIP/GOC-0000830e", "RingGroupMethod=ringallv2") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [201@from-did-direct:19] Set("SIP/GOC-0000830e", "_FMGRP=201") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [201@from-did-direct:20] GotoIf("SIP/GOC-0000830e", "0?doconfirm") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [201@from-did-direct:21] Macro("SIP/GOC-0000830e", "dial,20,trI,201") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-dial:1] GotoIf("SIP/GOC-0000830e", "1?dial") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Goto (macro-dial,s,3)
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-dial:3] AGI("SIP/GOC-0000830e", "dialparties.agi") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] res_agi.c:     -- Launched AGI Script /var/lib/asterisk/agi-bin/dialparties.agi
[2017-10-27 13:17:46] VERBOSE[27604] res_agi.c:  dialparties.agi: Starting New Dialparties.agi
[2017-10-27 13:17:46] VERBOSE[27604] res_agi.c:  dialparties.agi: Caller ID name is '893XXXXX655' number is '893XXXXX655'
[2017-10-27 13:17:46] VERBOSE[27604] res_agi.c:  dialparties.agi: Methodology of ring is  'ringallv2'
[2017-10-27 13:17:46] VERBOSE[27604] res_agi.c:     -- dialparties.agi: Added extension 201 to extension map
[2017-10-27 13:17:46] VERBOSE[27604] res_agi.c:     -- dialparties.agi: Extension 201 cf is disabled
[2017-10-27 13:17:46] VERBOSE[27604] res_agi.c:     -- dialparties.agi: Extension 201 do not disturb is disabled
[2017-10-27 13:17:46] VERBOSE[27604] res_agi.c:     -- dialparties.agi: dbset CALLTRACE/201 to 893XXXXX655
[2017-10-27 13:17:46] VERBOSE[27604] res_agi.c:     -- dialparties.agi: Filtered ARG3: 201
[2017-10-27 13:17:46] VERBOSE[27604] res_agi.c:     -- <SIP/GOC-0000830e>AGI Script dialparties.agi completed, returning 0
[2017-10-27 13:17:46] VERBOSE[27604] pbx.c:     -- Executing [s@macro-dial:7] Dial("SIP/GOC-0000830e", "SIP/201,20,trIM(auto-blkvm)") in new stack
[2017-10-27 13:17:46] VERBOSE[27604] netsock2.c:   == Using SIP RTP TOS bits 184
[2017-10-27 13:17:46] VERBOSE[27604] netsock2.c:   == Using SIP RTP CoS mark 5
[2017-10-27 13:17:46] VERBOSE[27604] app_dial.c:     -- Called SIP/201
[2017-10-27 13:17:46] VERBOSE[27604] app_dial.c:     -- Connected line update to SIP/GOC-0000830e prevented.
[2017-10-27 13:17:46] VERBOSE[27604] app_dial.c:     -- SIP/201-0000830f is ringing
[2017-10-27 13:17:48] VERBOSE[27604] app_macro.c:   == Spawn extension (macro-dial, s, 7) exited non-zero on 'SIP/GOC-0000830e' in macro 'dial'
[2017-10-27 13:17:48] VERBOSE[27604] pbx.c:   == Spawn extension (from-did-direct, 201, 21) exited non-zero on 'SIP/GOC-0000830e'
[2017-10-27 13:17:48] VERBOSE[27604] pbx.c:     -- Executing [h@from-did-direct:1] Macro("SIP/GOC-0000830e", "hangupcall,") in new stack
[2017-10-27 13:17:48] VERBOSE[27604] pbx.c:     -- Executing [s@macro-hangupcall:1] GotoIf("SIP/GOC-0000830e", "1?theend") in new stack
[2017-10-27 13:17:48] VERBOSE[27604] pbx.c:     -- Goto (macro-hangupcall,s,3)
[2017-10-27 13:17:48] VERBOSE[27604] pbx.c:     -- Executing [s@macro-hangupcall:3] ExecIf("SIP/GOC-0000830e", "0?Set(CDR(recordingfile)=)") in new stack
[2017-10-27 13:17:48] VERBOSE[27604] pbx.c:     -- Executing [s@macro-hangupcall:4] Hangup("SIP/GOC-0000830e", "") in new stack
[2017-10-27 13:17:48] VERBOSE[27604] app_macro.c:   == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/GOC-0000830e' in macro 'hangupcall'
[2017-10-27 13:17:48] VERBOSE[27604] pbx.c:   == Spawn extension (from-did-direct, h, 1) exited non-zero on 'SIP/GOC-0000830e'

Проект компании "АТС Дизайн"
Asterisk® и Digium® являются зарегистрированными торговыми марками компании Digium, Inc., США.
IP АТС Asterisk распространяется под лицензией GNU GPL.