Переадресация входящих из вне на внешний номер (Freebpx)

[ded]

попробуйте выставить
promiscredir = yes

Ещё момент.
В sip_custom.conf указано promiscredir=yes
Иначе 302 редирект у МТС не работает. Исходящие не проходят.

[Vlad1983]

так это на пире прова
если с IP-фона 302 летит то там тоже нужно

дамп сигналки покажет

[gaspaarr]

Аналогичная проблема, пример входящего внешнего звонка и попытка форварда на внешний номер. Звонки пытаюсь форвардить используя функцию телефонного аппарата Panasonic TGP500. Чуть позже проверил, даже на внутренние номера форвард не работает с тем же сообщением: app_dial.c:958 do_forward: Not accepting call completion offers from call-forward recipient SIP/10.123.123.252-00003936

Код: Выделить всё

В настройках пробовал ставить
Advanced General Settings
Other SIP Settings
promiscredir=yes
allowtransfer=yes

Дебаг со стороны транка (через него приходят внешний входящий и попытка форварда на 84957555545)

sip*CLI>
sip*CLI>
  == Using SIP RTP TOS bits 184
  == Using SIP RTP CoS mark 5
    -- Executing [74951123456@from-trunk:1] Set("SIP/EXTERNALTRUNK-00003931", "__FROM_DID=74951123456") in new stack
    -- Executing [74951123456@from-trunk:2] Gosub("SIP/EXTERNALTRUNK-00003931", "sub-record-check,s,1(in,74951123456,force)") in new stack
    -- Executing [s@sub-record-check:1] GotoIf("SIP/EXTERNALTRUNK-00003931", "0?initialized") in new stack
    -- Executing [s@sub-record-check:2] Set("SIP/EXTERNALTRUNK-00003931", "__REC_STATUS=INITIALIZED") in new stack
    -- Executing [s@sub-record-check:3] Set("SIP/EXTERNALTRUNK-00003931", "NOW=1447181097") in new stack
    -- Executing [s@sub-record-check:4] Set("SIP/EXTERNALTRUNK-00003931", "__DAY=10") in new stack
    -- Executing [s@sub-record-check:5] Set("SIP/EXTERNALTRUNK-00003931", "__MONTH=11") in new stack
    -- Executing [s@sub-record-check:6] Set("SIP/EXTERNALTRUNK-00003931", "__YEAR=2015") in new stack
    -- Executing [s@sub-record-check:7] Set("SIP/EXTERNALTRUNK-00003931", "__TIMESTR=20151110-214457") in new stack
    -- Executing [s@sub-record-check:8] Set("SIP/EXTERNALTRUNK-00003931", "__FROMEXTEN=unknown") in new stack
    -- Executing [s@sub-record-check:9] Set("SIP/EXTERNALTRUNK-00003931", "__MON_FMT=wav") in new stack
    -- Executing [s@sub-record-check:10] NoOp("SIP/EXTERNALTRUNK-00003931", "Recordings initialized") in new stack
    -- Executing [s@sub-record-check:11] ExecIf("SIP/EXTERNALTRUNK-00003931", "0?Set(ARG3=dontcare)") in new stack
    -- Executing [s@sub-record-check:12] Set("SIP/EXTERNALTRUNK-00003931", "REC_POLICY_MODE_SAVE=") in new stack
    -- Executing [s@sub-record-check:13] ExecIf("SIP/EXTERNALTRUNK-00003931", "0?Set(REC_STATUS=NO)") in new stack
    -- Executing [s@sub-record-check:14] GotoIf("SIP/EXTERNALTRUNK-00003931", "2?checkaction") in new stack
    -- Goto (sub-record-check,s,17)
    -- Executing [s@sub-record-check:17] GotoIf("SIP/EXTERNALTRUNK-00003931", "1?sub-record-check,in,1") in new stack
    -- Goto (sub-record-check,in,1)
    -- Executing [in@sub-record-check:1] NoOp("SIP/EXTERNALTRUNK-00003931", "Inbound Recording Check to 74951123456") in new stack
    -- Executing [in@sub-record-check:2] Set("SIP/EXTERNALTRUNK-00003931", "FROMEXTEN=unknown") in new stack
    -- Executing [in@sub-record-check:3] ExecIf("SIP/EXTERNALTRUNK-00003931", "10?Set(FROMEXTEN=9105555555)") in new stack
    -- Executing [in@sub-record-check:4] Gosub("SIP/EXTERNALTRUNK-00003931", "recordcheck,1(force,in,74951123456)") in new stack
    -- Executing [recordcheck@sub-record-check:1] NoOp("SIP/EXTERNALTRUNK-00003931", "Starting recording check against force") in new stack
    -- Executing [recordcheck@sub-record-check:2] Goto("SIP/EXTERNALTRUNK-00003931", "force") in new stack
    -- Goto (sub-record-check,recordcheck,5)
    -- Executing [recordcheck@sub-record-check:5] Set("SIP/EXTERNALTRUNK-00003931", "__REC_POLICY_MODE=FORCE") in new stack
    -- Executing [recordcheck@sub-record-check:6] GotoIf("SIP/EXTERNALTRUNK-00003931", "1?startrec") in new stack
    -- Goto (sub-record-check,recordcheck,16)
    -- Executing [recordcheck@sub-record-check:16] NoOp("SIP/EXTERNALTRUNK-00003931", "Starting recording: in, 74951123456") in new stack
    -- Executing [recordcheck@sub-record-check:17] Set("SIP/EXTERNALTRUNK-00003931", "AUDIOHOOK_INHERIT(MixMonitor)=yes") in new stack
    -- Executing [recordcheck@sub-record-check:18] Set("SIP/EXTERNALTRUNK-00003931", "__CALLFILENAME=in-74951123456-9105555555-20151110-214457-1447181097.18113") in new stack
    -- Executing [recordcheck@sub-record-check:19] MixMonitor("SIP/EXTERNALTRUNK-00003931", "2015/11/10/in-74951123456-9105555555-20151110-214457-1447181097.18113.wav,ai(LOCAL_MIXMON_ID),") in new stack
    -- Executing [recordcheck@sub-record-check:20] Set("SIP/EXTERNALTRUNK-00003931", "__MIXMON_ID=0x2390b80") in new stack
    -- Executing [recordcheck@sub-record-check:21] Set("SIP/EXTERNALTRUNK-00003931", "__RECORD_ID=SIP/EXTERNALTRUNK-00003931") in new stack
    -- Executing [recordcheck@sub-record-check:22] Set("SIP/EXTERNALTRUNK-00003931", "__REC_STATUS=RECORDING") in new stack
    -- Executing [recordcheck@sub-record-check:23] Set("SIP/EXTERNALTRUNK-00003931", "CDR(recordingfile)=in-74951123456-9105555555-20151110-214457-1447181097.18113.wav") in new stack
    -- Executing [recordcheck@sub-record-check:24] Return("SIP/EXTERNALTRUNK-00003931", "") in new stack
    -- Executing [in@sub-record-check:5] Return("SIP/EXTERNALTRUNK-00003931", "") in new stack
    -- Executing [74951123456@from-trunk:3] Gosub("SIP/EXTERNALTRUNK-00003931", "app-blacklist-check,s,1()") in new stack
    -- Executing [s@app-blacklist-check:1] GotoIf("SIP/EXTERNALTRUNK-00003931", "0?blacklisted") in new stack
    -- Executing [s@app-blacklist-check:2] Set("SIP/EXTERNALTRUNK-00003931", "CALLED_BLACKLIST=1") in new stack
    -- Executing [s@app-blacklist-check:3] Return("SIP/EXTERNALTRUNK-00003931", "") in new stack
    -- Executing [74951123456@from-trunk:4] Set("SIP/EXTERNALTRUNK-00003931", "CDR(did)=74951123456") in new stack
    -- Executing [74951123456@from-trunk:5] ExecIf("SIP/EXTERNALTRUNK-00003931", "1 ?Set(CALLERID(name)=9105555555)") in new stack
    -- Executing [74951123456@from-trunk:6] Set("SIP/EXTERNALTRUNK-00003931", "CHANNEL(musicclass)=default") in new stack
    -- Executing [74951123456@from-trunk:7] Set("SIP/EXTERNALTRUNK-00003931", "__MOHCLASS=default") in new stack
[2015-11-10 21:44:57] WARNING[9325][C-00002036]: func_channel.c:538 func_channel_read: Unknown or unavailable item requested: 'reversecharge'
[2015-11-10 21:44:57] WARNING[9325][C-00002036]: func_channel.c:538 func_channel_read: Unknown or unavailable item requested: 'reversecharge'
    -- Executing [74951123456@from-trunk:8] GotoIf("SIP/EXTERNALTRUNK-00003931", "0?macro-hangupcall") in new stack
    -- Executing [74951123456@from-trunk:9] Set("SIP/EXTERNALTRUNK-00003931", "__CALLINGPRES_SV=allowed_not_screened") in new stack
    -- Executing [74951123456@from-trunk:10] Set("SIP/EXTERNALTRUNK-00003931", "CALLERPRES()=allowed_not_screened") in new stack
    -- Executing [74951123456@from-trunk:11] Goto("SIP/EXTERNALTRUNK-00003931", "app-setcid,1,1") in new stack
    -- Goto (app-setcid,1,1)
    -- Executing [1@app-setcid:1] NoOp("SIP/EXTERNALTRUNK-00003931", "(74951123456) Changing cid to  <89105555555>") in new stack
    -- Executing [1@app-setcid:2] Set("SIP/EXTERNALTRUNK-00003931", "CALLERID(name)=") in new stack
    -- Executing [1@app-setcid:3] Set("SIP/EXTERNALTRUNK-00003931", "CALLERID(num)=89105555555") in new stack
    -- Executing [1@app-setcid:4] Goto("SIP/EXTERNALTRUNK-00003931", "from-did-direct,299,1") in new stack
    -- Goto (from-did-direct,299,1)
    -- Executing [299@from-did-direct:1] Set("SIP/EXTERNALTRUNK-00003931", "__RINGTIMER=60") in new stack
  == Begin MixMonitor Recording SIP/EXTERNALTRUNK-00003931
    -- Executing [299@from-did-direct:2] Macro("SIP/EXTERNALTRUNK-00003931", "exten-vm,novm,299,1,0,0") in new stack
    -- Executing [s@macro-exten-vm:1] Macro("SIP/EXTERNALTRUNK-00003931", "user-callerid,") in new stack
    -- Executing [s@macro-user-callerid:1] Set("SIP/EXTERNALTRUNK-00003931", "TOUCH_MONITOR=1447181097.18113") in new stack
    -- Executing [s@macro-user-callerid:2] Set("SIP/EXTERNALTRUNK-00003931", "AMPUSER=89105555555") in new stack
    -- Executing [s@macro-user-callerid:3] GotoIf("SIP/EXTERNALTRUNK-00003931", "0?report") in new stack
    -- Executing [s@macro-user-callerid:4] ExecIf("SIP/EXTERNALTRUNK-00003931", "1?Set(REALCALLERIDNUM=89105555555)") in new stack
    -- Executing [s@macro-user-callerid:5] Set("SIP/EXTERNALTRUNK-00003931", "AMPUSER=") in new stack
    -- Executing [s@macro-user-callerid:6] GotoIf("SIP/EXTERNALTRUNK-00003931", "0?limit") in new stack
    -- Executing [s@macro-user-callerid:7] Set("SIP/EXTERNALTRUNK-00003931", "AMPUSERCIDNAME=") in new stack
    -- Executing [s@macro-user-callerid:8] GotoIf("SIP/EXTERNALTRUNK-00003931", "1?report") in new stack
    -- Goto (macro-user-callerid,s,16)
    -- Executing [s@macro-user-callerid:16] GotoIf("SIP/EXTERNALTRUNK-00003931", "0?continue") in new stack
    -- Executing [s@macro-user-callerid:17] Set("SIP/EXTERNALTRUNK-00003931", "__TTL=64") in new stack
    -- Executing [s@macro-user-callerid:18] GotoIf("SIP/EXTERNALTRUNK-00003931", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,29)
    -- Executing [s@macro-user-callerid:29] Set("SIP/EXTERNALTRUNK-00003931", "CALLERID(number)=89105555555") in new stack
    -- Executing [s@macro-user-callerid:30] Set("SIP/EXTERNALTRUNK-00003931", "CALLERID(name)=") in new stack
    -- Executing [s@macro-user-callerid:31] Set("SIP/EXTERNALTRUNK-00003931", "CDR(cnum)=89105555555") in new stack
    -- Executing [s@macro-user-callerid:32] Set("SIP/EXTERNALTRUNK-00003931", "CDR(cnam)=") in new stack
    -- Executing [s@macro-user-callerid:33] Set("SIP/EXTERNALTRUNK-00003931", "CHANNEL(language)=ru") in new stack
    -- Executing [s@macro-exten-vm:2] Set("SIP/EXTERNALTRUNK-00003931", "RingGroupMethod=none") in new stack
    -- Executing [s@macro-exten-vm:3] Set("SIP/EXTERNALTRUNK-00003931", "__EXTTOCALL=299") in new stack
    -- Executing [s@macro-exten-vm:4] Set("SIP/EXTERNALTRUNK-00003931", "__PICKUPMARK=299") in new stack
    -- Executing [s@macro-exten-vm:5] Set("SIP/EXTERNALTRUNK-00003931", "RT=60") in new stack
    -- Executing [s@macro-exten-vm:6] Gosub("SIP/EXTERNALTRUNK-00003931", "sub-record-check,s,1(exten,299,dontcare)") in new stack
    -- Executing [s@sub-record-check:1] GotoIf("SIP/EXTERNALTRUNK-00003931", "10?initialized") in new stack
    -- Goto (sub-record-check,s,10)
    -- Executing [s@sub-record-check:10] NoOp("SIP/EXTERNALTRUNK-00003931", "Recordings initialized") in new stack
    -- Executing [s@sub-record-check:11] ExecIf("SIP/EXTERNALTRUNK-00003931", "0?Set(ARG3=dontcare)") in new stack
    -- Executing [s@sub-record-check:12] Set("SIP/EXTERNALTRUNK-00003931", "REC_POLICY_MODE_SAVE=FORCE") in new stack
    -- Executing [s@sub-record-check:13] ExecIf("SIP/EXTERNALTRUNK-00003931", "0?Set(REC_STATUS=NO)") in new stack
    -- Executing [s@sub-record-check:14] GotoIf("SIP/EXTERNALTRUNK-00003931", "5?checkaction") in new stack
    -- Goto (sub-record-check,s,17)
    -- Executing [s@sub-record-check:17] GotoIf("SIP/EXTERNALTRUNK-00003931", "1?sub-record-check,exten,1") in new stack
    -- Goto (sub-record-check,exten,1)
    -- Executing [exten@sub-record-check:1] NoOp("SIP/EXTERNALTRUNK-00003931", "Exten Recording Check between 9105555555 and 299") in new stack
    -- Executing [exten@sub-record-check:2] Set("SIP/EXTERNALTRUNK-00003931", "CALLTYPE=external") in new stack
    -- Executing [exten@sub-record-check:3] ExecIf("SIP/EXTERNALTRUNK-00003931", "0?Set(CALLTYPE=)") in new stack
    -- Executing [exten@sub-record-check:4] Set("SIP/EXTERNALTRUNK-00003931", "CALLEE=dontcare") in new stack
    -- Executing [exten@sub-record-check:5] ExecIf("SIP/EXTERNALTRUNK-00003931", "0?Set(CALLEE=dontcare)") in new stack
    -- Executing [exten@sub-record-check:6] GotoIf("SIP/EXTERNALTRUNK-00003931", "1?callee") in new stack
    -- Goto (sub-record-check,exten,11)
    -- Executing [exten@sub-record-check:11] Gosub("SIP/EXTERNALTRUNK-00003931", "recordcheck,1(dontcare,external,299)") in new stack
    -- Executing [recordcheck@sub-record-check:1] NoOp("SIP/EXTERNALTRUNK-00003931", "Starting recording check against dontcare") in new stack
    -- Executing [recordcheck@sub-record-check:2] Goto("SIP/EXTERNALTRUNK-00003931", "dontcare") in new stack
    -- Goto (sub-record-check,recordcheck,3)
    -- Executing [recordcheck@sub-record-check:3] Return("SIP/EXTERNALTRUNK-00003931", "") in new stack
    -- Executing [exten@sub-record-check:12] Return("SIP/EXTERNALTRUNK-00003931", "") in new stack
    -- Executing [s@macro-exten-vm:7] Macro("SIP/EXTERNALTRUNK-00003931", "dial-one,60,TtrL(3600000:60000:30501),299") in new stack
    -- Executing [s@macro-dial-one:1] Set("SIP/EXTERNALTRUNK-00003931", "DEXTEN=299") in new stack
    -- Executing [s@macro-dial-one:2] Set("SIP/EXTERNALTRUNK-00003931", "DIALSTATUS_CW=") in new stack
    -- Executing [s@macro-dial-one:3] GosubIf("SIP/EXTERNALTRUNK-00003931", "0?screen,1()") in new stack
    -- Executing [s@macro-dial-one:4] GosubIf("SIP/EXTERNALTRUNK-00003931", "0?cf,1()") in new stack
    -- Executing [s@macro-dial-one:5] GotoIf("SIP/EXTERNALTRUNK-00003931", "1?skip1") in new stack
    -- Goto (macro-dial-one,s,8)
    -- Executing [s@macro-dial-one:8] GotoIf("SIP/EXTERNALTRUNK-00003931", "0?nodial") in new stack
    -- Executing [s@macro-dial-one:9] GotoIf("SIP/EXTERNALTRUNK-00003931", "0?continue") in new stack
    -- Executing [s@macro-dial-one:10] Set("SIP/EXTERNALTRUNK-00003931", "EXTHASCW=ENABLED") in new stack
    -- Executing [s@macro-dial-one:11] GotoIf("SIP/EXTERNALTRUNK-00003931", "0?next1:cwinusebusy") in new stack
    -- Goto (macro-dial-one,s,23)
    -- Executing [s@macro-dial-one:23] GotoIf("SIP/EXTERNALTRUNK-00003931", "1?next3:continue") in new stack
    -- Goto (macro-dial-one,s,24)
    -- Executing [s@macro-dial-one:24] ExecIf("SIP/EXTERNALTRUNK-00003931", "0?Set(DIALSTATUS_CW=BUSY)") in new stack
    -- Executing [s@macro-dial-one:25] GotoIf("SIP/EXTERNALTRUNK-00003931", "0?nodial") in new stack
    -- Executing [s@macro-dial-one:26] GosubIf("SIP/EXTERNALTRUNK-00003931", "1?dstring,1():dlocal,1()") in new stack
    -- Executing [dstring@macro-dial-one:1] Set("SIP/EXTERNALTRUNK-00003931", "DSTRING=") in new stack
    -- Executing [dstring@macro-dial-one:2] Set("SIP/EXTERNALTRUNK-00003931", "DEVICES=299") in new stack
    -- Executing [dstring@macro-dial-one:3] ExecIf("SIP/EXTERNALTRUNK-00003931", "0?Return()") in new stack
    -- Executing [dstring@macro-dial-one:4] ExecIf("SIP/EXTERNALTRUNK-00003931", "0?Set(DEVICES=99)") in new stack
    -- Executing [dstring@macro-dial-one:5] Set("SIP/EXTERNALTRUNK-00003931", "LOOPCNT=1") in new stack
    -- Executing [dstring@macro-dial-one:6] Set("SIP/EXTERNALTRUNK-00003931", "ITER=1") in new stack
    -- Executing [dstring@macro-dial-one:7] Set("SIP/EXTERNALTRUNK-00003931", "THISDIAL=SIP/299") in new stack
    -- Executing [dstring@macro-dial-one:8] GosubIf("SIP/EXTERNALTRUNK-00003931", "1?zap2dahdi,1()") in new stack
    -- Executing [zap2dahdi@macro-dial-one:1] ExecIf("SIP/EXTERNALTRUNK-00003931", "0?Return()") in new stack
    -- Executing [zap2dahdi@macro-dial-one:2] Set("SIP/EXTERNALTRUNK-00003931", "NEWDIAL=") in new stack
    -- Executing [zap2dahdi@macro-dial-one:3] Set("SIP/EXTERNALTRUNK-00003931", "LOOPCNT2=1") in new stack
    -- Executing [zap2dahdi@macro-dial-one:4] Set("SIP/EXTERNALTRUNK-00003931", "ITER2=1") in new stack
    -- Executing [zap2dahdi@macro-dial-one:5] Set("SIP/EXTERNALTRUNK-00003931", "THISPART2=SIP/299") in new stack
    -- Executing [zap2dahdi@macro-dial-one:6] ExecIf("SIP/EXTERNALTRUNK-00003931", "0?Set(THISPART2=DAHDI/299)") in new stack
    -- Executing [zap2dahdi@macro-dial-one:7] Set("SIP/EXTERNALTRUNK-00003931", "NEWDIAL=SIP/299&") in new stack
    -- Executing [zap2dahdi@macro-dial-one:8] Set("SIP/EXTERNALTRUNK-00003931", "ITER2=2") in new stack
    -- Executing [zap2dahdi@macro-dial-one:9] GotoIf("SIP/EXTERNALTRUNK-00003931", "0?begin2") in new stack
    -- Executing [zap2dahdi@macro-dial-one:10] Set("SIP/EXTERNALTRUNK-00003931", "THISDIAL=SIP/299") in new stack
    -- Executing [zap2dahdi@macro-dial-one:11] Return("SIP/EXTERNALTRUNK-00003931", "") in new stack
    -- Executing [dstring@macro-dial-one:9] GotoIf("SIP/EXTERNALTRUNK-00003931", "1?doset") in new stack
    -- Goto (macro-dial-one,dstring,13)
    -- Executing [dstring@macro-dial-one:13] Set("SIP/EXTERNALTRUNK-00003931", "DSTRING=SIP/299&") in new stack
    -- Executing [dstring@macro-dial-one:14] Set("SIP/EXTERNALTRUNK-00003931", "ITER=2") in new stack
    -- Executing [dstring@macro-dial-one:15] GotoIf("SIP/EXTERNALTRUNK-00003931", "0?begin") in new stack
    -- Executing [dstring@macro-dial-one:16] ExecIf("SIP/EXTERNALTRUNK-00003931", "0?Return()") in new stack
    -- Executing [dstring@macro-dial-one:17] Set("SIP/EXTERNALTRUNK-00003931", "DSTRING=SIP/299") in new stack
    -- Executing [dstring@macro-dial-one:18] Return("SIP/EXTERNALTRUNK-00003931", "") in new stack
    -- Executing [s@macro-dial-one:27] GotoIf("SIP/EXTERNALTRUNK-00003931", "0?nodial") in new stack
    -- Executing [s@macro-dial-one:28] GotoIf("SIP/EXTERNALTRUNK-00003931", "0?skiptrace") in new stack
    -- Executing [s@macro-dial-one:29] GosubIf("SIP/EXTERNALTRUNK-00003931", "1?ctset,1():ctclear,1()") in new stack
    -- Executing [ctset@macro-dial-one:1] Set("SIP/EXTERNALTRUNK-00003931", "DB(CALLTRACE/299)=89105555555") in new stack
    -- Executing [ctset@macro-dial-one:2] Return("SIP/EXTERNALTRUNK-00003931", "") in new stack
    -- Executing [s@macro-dial-one:30] Set("SIP/EXTERNALTRUNK-00003931", "D_OPTIONS=TtrL(3600000:60000:30501)") in new stack
    -- Executing [s@macro-dial-one:31] ExecIf("SIP/EXTERNALTRUNK-00003931", "0?SIPAddHeader(Alert-Info: )") in new stack
    -- Executing [s@macro-dial-one:32] ExecIf("SIP/EXTERNALTRUNK-00003931", "0?SIPAddHeader()") in new stack
    -- Executing [s@macro-dial-one:33] ExecIf("SIP/EXTERNALTRUNK-00003931", "1?Set(CHANNEL(musicclass)=default)") in new stack
    -- Executing [s@macro-dial-one:34] GosubIf("SIP/EXTERNALTRUNK-00003931", "0?qwait,1()") in new stack
    -- Executing [s@macro-dial-one:35] Set("SIP/EXTERNALTRUNK-00003931", "__CWIGNORE=") in new stack
    -- Executing [s@macro-dial-one:36] Set("SIP/EXTERNALTRUNK-00003931", "__KEEPCID=TRUE") in new stack
    -- Executing [s@macro-dial-one:37] GotoIf("SIP/EXTERNALTRUNK-00003931", "0?usegoto,1") in new stack
    -- Executing [s@macro-dial-one:38] GotoIf("SIP/EXTERNALTRUNK-00003931", "1?godial") in new stack
    -- Goto (macro-dial-one,s,43)
    -- Executing [s@macro-dial-one:43] Macro("SIP/EXTERNALTRUNK-00003931", "dialout-one-predial-hook,") in new stack
    -- Executing [s@macro-dialout-one-predial-hook:1] MacroExit("SIP/EXTERNALTRUNK-00003931", "") in new stack
    -- Executing [s@macro-dial-one:44] Dial("SIP/EXTERNALTRUNK-00003931", "SIP/299,60,TtrL(3600000:60000:30501)") in new stack
       > Limit Data for this call:
       > timelimit      = 3600000 ms (3600.000 s)
       > play_warning   = 60000 ms (60.000 s)
       > play_to_caller = yes
       > play_to_callee = no
       > warning_freq   = 30501 ms (30.501 s)
       > start_sound    =
       > warning_sound  = timeleft
       > end_sound      =
  == Using SIP RTP TOS bits 184
  == Using SIP RTP CoS mark 5
    -- Called SIP/299
    -- Got SIP response 302 "Moved Temporarily" back from 10.123.222.250:5120
    -- Now forwarding SIP/EXTERNALTRUNK-00003931 to 'SIP/84957555545::::UDP@10.123.123.252' (thanks to SIP/299-00003932)
  == Using SIP RTP TOS bits 184
  == Using SIP RTP CoS mark 5
[2015-11-10 21:44:57] NOTICE[9325][C-00002036]: app_dial.c:958 do_forward: Not accepting call completion offers from call-forward recipient SIP/10.123.123.252-00003933
[2015-11-10 21:44:57] NOTICE[9325][C-00002036]: app_dial.c:958 do_forward: Not accepting call completion offers from call-forward recipient SIP/10.123.123.252-00003933
[2015-11-10 21:44:57] NOTICE[1789][C-00002036]: chan_sip.c:23155 handle_response_invite: Failed to authenticate on INVITE to '<sip:89105555555@10.123.123.252>;tag=as41d6dc33'
[2015-11-10 21:44:57] NOTICE[1789][C-00002036]: chan_sip.c:23155 handle_response_invite: Failed to authenticate on INVITE to '<sip:89105555555@10.123.123.252>;tag=as41d6dc33'
    -- SIP/10.123.123.252-00003933 is circuit-busy
  == Everyone is busy/congested at this time (1:0/1/0)
    -- Executing [s@macro-dial-one:45] ExecIf("SIP/EXTERNALTRUNK-00003931", "0?MacroExit()") in new stack
    -- Executing [s@macro-dial-one:46] ExecIf("SIP/EXTERNALTRUNK-00003931", "0?Set(DIALSTATUS=)") in new stack
    -- Executing [s@macro-dial-one:47] GosubIf("SIP/EXTERNALTRUNK-00003931", "0?s-CONGESTION,1()") in new stack
    -- Executing [s@macro-dial-one:48] MacroExit("SIP/EXTERNALTRUNK-00003931", "") in new stack
    -- Executing [s@macro-exten-vm:8] Set("SIP/EXTERNALTRUNK-00003931", "SV_DIALSTATUS=CONGESTION") in new stack
    -- Executing [s@macro-exten-vm:9] GosubIf("SIP/EXTERNALTRUNK-00003931", "0?docfu,1()") in new stack
    -- Executing [s@macro-exten-vm:10] GosubIf("SIP/EXTERNALTRUNK-00003931", "0?docfb,1()") in new stack
    -- Executing [s@macro-exten-vm:11] Set("SIP/EXTERNALTRUNK-00003931", "DIALSTATUS=CONGESTION") in new stack
    -- Executing [s@macro-exten-vm:12] ExecIf("SIP/EXTERNALTRUNK-00003931", "0?MacroExit()") in new stack
    -- Executing [s@macro-exten-vm:13] GotoIf("SIP/EXTERNALTRUNK-00003931", "1?s-CONGESTION,1") in new stack
    -- Goto (macro-exten-vm,s-CONGESTION,1)
    -- Executing [s-CONGESTION@macro-exten-vm:1] GotoIf("SIP/EXTERNALTRUNK-00003931", "0?exit,1") in new stack
    -- Executing [s-CONGESTION@macro-exten-vm:2] PlayTones("SIP/EXTERNALTRUNK-00003931", "congestion") in new stack
[2015-11-10 21:44:57] WARNING[9325][C-00002036]: translate.c:340 framein: no samples for alawtolin
[2015-11-10 21:44:57] WARNING[9325][C-00002036]: translate.c:340 framein: no samples for alawtolin
    -- Executing [s-CONGESTION@macro-exten-vm:3] Congestion("SIP/EXTERNALTRUNK-00003931", "10") in new stack
  == Spawn extension (macro-exten-vm, s-CONGESTION, 3) exited non-zero on 'SIP/EXTERNALTRUNK-00003931' in macro 'exten-vm'
  == Spawn extension (from-did-direct, 299, 2) exited non-zero on 'SIP/EXTERNALTRUNK-00003931'
    -- Executing [h@from-did-direct:1] Macro("SIP/EXTERNALTRUNK-00003931", "hangupcall,") in new stack
    -- Executing [s@macro-hangupcall:1] ExecIf("SIP/EXTERNALTRUNK-00003931", "0?Set(CDR(recordingfile)=in-74951123456-9105555555-20151110-214457-1447181097.18113.wav)") in new stack
    -- Executing [s@macro-hangupcall:2] GotoIf("SIP/EXTERNALTRUNK-00003931", "1?theend") in new stack
    -- Goto (macro-hangupcall,s,4)
    -- Executing [s@macro-hangupcall:4] Hangup("SIP/EXTERNALTRUNK-00003931", "") in new stack
  == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/EXTERNALTRUNK-00003931' in macro 'hangupcall'
  == Spawn extension (from-did-direct, h, 1) exited non-zero on 'SIP/EXTERNALTRUNK-00003931'
  == MixMonitor close filestream (mixed)
  == End MixMonitor Recording SIP/EXTERNALTRUNK-00003931

<--- SIP read from UDP:195.2.2.2:5060 --->
ACK sip:74951123456@192.168.66.33:5060 SIP/2.0
Via: SIP/2.0/UDP 195.2.2.2:5060;x-route-tag="tgrp:CS2K-trunk";branch=z9hG4bK1CF7C761F
From: <sip:9105555555@195.2.2.2>;tag=417411C8-C8E
To: <sip:74951123456@192.168.66.33>;tag=as33a5a4ed
Date: Tue, 10 Nov 2015 18:44:57 GMT
Call-ID: F93EC95A-871111E5-9077FA8F-1C65F570@195.2.2.2
Max-Forwards: 70
CSeq: 101 ACK
Content-Length: 0

<------------->
--- (9 headers 0 lines) ---
Really destroying SIP dialog 'F93EC95A-871111E5-9077FA8F-1C65F570@195.2.2.2' Method: ACK
Reliably Transmitting (NAT) to 195.2.2.2:5060:
OPTIONS sip:195.2.2.2 SIP/2.0
Via: SIP/2.0/UDP 192.168.66.33:5060;branch=z9hG4bK56693636;rport
Max-Forwards: 70
From: "Unknown" <sip:Unknown@192.168.66.33>;tag=as02bb5ae5
To: <sip:195.2.2.2>
Contact: <sip:Unknown@192.168.66.33:5060>
Call-ID: 513ddda133904f9f469eca2b2a22fa82@192.168.66.33:5060
CSeq: 102 OPTIONS
User-Agent: FPBX-12.0.76.2(11.19.0)
Date: Tue, 10 Nov 2015 18:45:02 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0


---

<--- SIP read from UDP:195.2.2.2:5060 --->
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.66.33:5060;branch=z9hG4bK56693636;rport
From: "Unknown" <sip:Unknown@192.168.66.33>;tag=as02bb5ae5
To: <sip:195.2.2.2>;tag=417428B8-241
Date: Tue, 10 Nov 2015 18:45:02 GMT
Call-ID: 513ddda133904f9f469eca2b2a22fa82@192.168.66.33:5060
Server: Cisco-SIPGateway/IOS-12.x
CSeq: 102 OPTIONS
Supported: 100rel,replaces
Allow: INVITE, OPTIONS, BYE, CANCEL, ACK, PRACK, COMET, REFER, SUBSCRIBE, NOTIFY, INFO, UPDATE, REGISTER
Accept: application/sdp
Allow-Events: telephone-event
Content-Length: 458
Content-Type: application/sdp

v=0
o=CiscoSystemsSIP-GW-UserAgent 3552 6351 IN IP4 195.2.2.2
s=SIP Call
c=IN IP4 195.2.2.2
t=0 0
m=audio 0 RTP/AVP 18 0 8 4 2 3
c=IN IP4 195.2.2.2
m=image 0 udptl t38
c=IN IP4 195.2.2.2
a=T38FaxVersion:0
a=T38MaxBitRate:9600
a=T38FaxFillBitRemoval:0
a=T38FaxTranscodingMMR:0
a=T38FaxTranscodingJBIG:0
a=T38FaxRateManagement:transferredTCF
a=T38FaxMaxBuffer:200
a=T38FaxMaxDatagram:72
a=T38FaxUdpEC:t38UDPRedundancy
<------------->
--- (14 headers 18 lines) ---
Really destroying SIP dialog '513ddda133904f9f469eca2b2a22fa82@192.168.66.33:5060' Method: OPTIONS
sip*CLI>













Дебаг со стороны экстеншена 299, на него приходит входящий звонок, и он инициирует 302 - форвард


sip*CLI>
sip*CLI>
sip*CLI>
sip*CLI>
sip*CLI>
sip*CLI>
sip*CLI>
sip*CLI>
sip*CLI>
sip*CLI>
sip*CLI>
[2015-11-10 21:45:47] NOTICE[1789]: chan_sip.c:15104 sip_reregister:    -- Re-registration for  12345@sipnet.ru
[2015-11-10 21:45:47] NOTICE[1789]: chan_sip.c:15104 sip_reregister:    -- Re-registration for  12345@sipnet.ru
  == Using SIP RTP TOS bits 184
  == Using SIP RTP CoS mark 5
    -- Executing [74951123456@from-trunk:1] Set("SIP/EXTERNALTRUNK-00003934", "__FROM_DID=74951123456") in new stack
    -- Executing [74951123456@from-trunk:2] Gosub("SIP/EXTERNALTRUNK-00003934", "sub-record-check,s,1(in,74951123456,force)") in new stack
    -- Executing [s@sub-record-check:1] GotoIf("SIP/EXTERNALTRUNK-00003934", "0?initialized") in new stack
    -- Executing [s@sub-record-check:2] Set("SIP/EXTERNALTRUNK-00003934", "__REC_STATUS=INITIALIZED") in new stack
    -- Executing [s@sub-record-check:3] Set("SIP/EXTERNALTRUNK-00003934", "NOW=1447181147") in new stack
    -- Executing [s@sub-record-check:4] Set("SIP/EXTERNALTRUNK-00003934", "__DAY=10") in new stack
    -- Executing [s@sub-record-check:5] Set("SIP/EXTERNALTRUNK-00003934", "__MONTH=11") in new stack
    -- Executing [s@sub-record-check:6] Set("SIP/EXTERNALTRUNK-00003934", "__YEAR=2015") in new stack
    -- Executing [s@sub-record-check:7] Set("SIP/EXTERNALTRUNK-00003934", "__TIMESTR=20151110-214547") in new stack
    -- Executing [s@sub-record-check:8] Set("SIP/EXTERNALTRUNK-00003934", "__FROMEXTEN=unknown") in new stack
    -- Executing [s@sub-record-check:9] Set("SIP/EXTERNALTRUNK-00003934", "__MON_FMT=wav") in new stack
    -- Executing [s@sub-record-check:10] NoOp("SIP/EXTERNALTRUNK-00003934", "Recordings initialized") in new stack
    -- Executing [s@sub-record-check:11] ExecIf("SIP/EXTERNALTRUNK-00003934", "0?Set(ARG3=dontcare)") in new stack
    -- Executing [s@sub-record-check:12] Set("SIP/EXTERNALTRUNK-00003934", "REC_POLICY_MODE_SAVE=") in new stack
    -- Executing [s@sub-record-check:13] ExecIf("SIP/EXTERNALTRUNK-00003934", "0?Set(REC_STATUS=NO)") in new stack
    -- Executing [s@sub-record-check:14] GotoIf("SIP/EXTERNALTRUNK-00003934", "2?checkaction") in new stack
    -- Goto (sub-record-check,s,17)
    -- Executing [s@sub-record-check:17] GotoIf("SIP/EXTERNALTRUNK-00003934", "1?sub-record-check,in,1") in new stack
    -- Goto (sub-record-check,in,1)
    -- Executing [in@sub-record-check:1] NoOp("SIP/EXTERNALTRUNK-00003934", "Inbound Recording Check to 74951123456") in new stack
    -- Executing [in@sub-record-check:2] Set("SIP/EXTERNALTRUNK-00003934", "FROMEXTEN=unknown") in new stack
    -- Executing [in@sub-record-check:3] ExecIf("SIP/EXTERNALTRUNK-00003934", "10?Set(FROMEXTEN=9105555555)") in new stack
    -- Executing [in@sub-record-check:4] Gosub("SIP/EXTERNALTRUNK-00003934", "recordcheck,1(force,in,74951123456)") in new stack
    -- Executing [recordcheck@sub-record-check:1] NoOp("SIP/EXTERNALTRUNK-00003934", "Starting recording check against force") in new stack
    -- Executing [recordcheck@sub-record-check:2] Goto("SIP/EXTERNALTRUNK-00003934", "force") in new stack
    -- Goto (sub-record-check,recordcheck,5)
    -- Executing [recordcheck@sub-record-check:5] Set("SIP/EXTERNALTRUNK-00003934", "__REC_POLICY_MODE=FORCE") in new stack
    -- Executing [recordcheck@sub-record-check:6] GotoIf("SIP/EXTERNALTRUNK-00003934", "1?startrec") in new stack
    -- Goto (sub-record-check,recordcheck,16)
    -- Executing [recordcheck@sub-record-check:16] NoOp("SIP/EXTERNALTRUNK-00003934", "Starting recording: in, 74951123456") in new stack
    -- Executing [recordcheck@sub-record-check:17] Set("SIP/EXTERNALTRUNK-00003934", "AUDIOHOOK_INHERIT(MixMonitor)=yes") in new stack
    -- Executing [recordcheck@sub-record-check:18] Set("SIP/EXTERNALTRUNK-00003934", "__CALLFILENAME=in-74951123456-9105555555-20151110-214547-1447181147.18116") in new stack
    -- Executing [recordcheck@sub-record-check:19] MixMonitor("SIP/EXTERNALTRUNK-00003934", "2015/11/10/in-74951123456-9105555555-20151110-214547-1447181147.18116.wav,ai(LOCAL_MIXMON_ID),") in new stack
    -- Executing [recordcheck@sub-record-check:20] Set("SIP/EXTERNALTRUNK-00003934", "__MIXMON_ID=0x23dc3a0") in new stack
    -- Executing [recordcheck@sub-record-check:21] Set("SIP/EXTERNALTRUNK-00003934", "__RECORD_ID=SIP/EXTERNALTRUNK-00003934") in new stack
    -- Executing [recordcheck@sub-record-check:22] Set("SIP/EXTERNALTRUNK-00003934", "__REC_STATUS=RECORDING") in new stack
    -- Executing [recordcheck@sub-record-check:23] Set("SIP/EXTERNALTRUNK-00003934", "CDR(recordingfile)=in-74951123456-9105555555-20151110-214547-1447181147.18116.wav") in new stack
    -- Executing [recordcheck@sub-record-check:24] Return("SIP/EXTERNALTRUNK-00003934", "") in new stack
    -- Executing [in@sub-record-check:5] Return("SIP/EXTERNALTRUNK-00003934", "") in new stack
    -- Executing [74951123456@from-trunk:3] Gosub("SIP/EXTERNALTRUNK-00003934", "app-blacklist-check,s,1()") in new stack
    -- Executing [s@app-blacklist-check:1] GotoIf("SIP/EXTERNALTRUNK-00003934", "0?blacklisted") in new stack
    -- Executing [s@app-blacklist-check:2] Set("SIP/EXTERNALTRUNK-00003934", "CALLED_BLACKLIST=1") in new stack
    -- Executing [s@app-blacklist-check:3] Return("SIP/EXTERNALTRUNK-00003934", "") in new stack
    -- Executing [74951123456@from-trunk:4] Set("SIP/EXTERNALTRUNK-00003934", "CDR(did)=74951123456") in new stack
    -- Executing [74951123456@from-trunk:5] ExecIf("SIP/EXTERNALTRUNK-00003934", "1 ?Set(CALLERID(name)=9105555555)") in new stack
    -- Executing [74951123456@from-trunk:6] Set("SIP/EXTERNALTRUNK-00003934", "CHANNEL(musicclass)=default") in new stack
    -- Executing [74951123456@from-trunk:7] Set("SIP/EXTERNALTRUNK-00003934", "__MOHCLASS=default") in new stack
[2015-11-10 21:45:47] WARNING[9356][C-00002038]: func_channel.c:538 func_channel_read: Unknown or unavailable item requested: 'reversecharge'
[2015-11-10 21:45:47] WARNING[9356][C-00002038]: func_channel.c:538 func_channel_read: Unknown or unavailable item requested: 'reversecharge'
    -- Executing [74951123456@from-trunk:8] GotoIf("SIP/EXTERNALTRUNK-00003934", "0?macro-hangupcall") in new stack
    -- Executing [74951123456@from-trunk:9] Set("SIP/EXTERNALTRUNK-00003934", "__CALLINGPRES_SV=allowed_not_screened") in new stack
    -- Executing [74951123456@from-trunk:10] Set("SIP/EXTERNALTRUNK-00003934", "CALLERPRES()=allowed_not_screened") in new stack
    -- Executing [74951123456@from-trunk:11] Goto("SIP/EXTERNALTRUNK-00003934", "app-setcid,1,1") in new stack
    -- Goto (app-setcid,1,1)
    -- Executing [1@app-setcid:1] NoOp("SIP/EXTERNALTRUNK-00003934", "(74951123456) Changing cid to  <89105555555>") in new stack
    -- Executing [1@app-setcid:2] Set("SIP/EXTERNALTRUNK-00003934", "CALLERID(name)=") in new stack
    -- Executing [1@app-setcid:3] Set("SIP/EXTERNALTRUNK-00003934", "CALLERID(num)=89105555555") in new stack
    -- Executing [1@app-setcid:4] Goto("SIP/EXTERNALTRUNK-00003934", "from-did-direct,299,1") in new stack
    -- Goto (from-did-direct,299,1)
  == Begin MixMonitor Recording SIP/EXTERNALTRUNK-00003934
    -- Executing [299@from-did-direct:1] Set("SIP/EXTERNALTRUNK-00003934", "__RINGTIMER=60") in new stack
    -- Executing [299@from-did-direct:2] Macro("SIP/EXTERNALTRUNK-00003934", "exten-vm,novm,299,1,0,0") in new stack
    -- Executing [s@macro-exten-vm:1] Macro("SIP/EXTERNALTRUNK-00003934", "user-callerid,") in new stack
[2015-11-10 21:45:47] NOTICE[1789]: chan_sip.c:23665 handle_response_register: Outbound Registration: Expiry for sipnet.ru is 120 sec (Scheduling reregistration in 105 s)
[2015-11-10 21:45:47] NOTICE[1789]: chan_sip.c:23665 handle_response_register: Outbound Registration: Expiry for sipnet.ru is 120 sec (Scheduling reregistration in 105 s)
    -- Executing [s@macro-user-callerid:1] Set("SIP/EXTERNALTRUNK-00003934", "TOUCH_MONITOR=1447181147.18116") in new stack
    -- Executing [s@macro-user-callerid:2] Set("SIP/EXTERNALTRUNK-00003934", "AMPUSER=89105555555") in new stack
    -- Executing [s@macro-user-callerid:3] GotoIf("SIP/EXTERNALTRUNK-00003934", "0?report") in new stack
    -- Executing [s@macro-user-callerid:4] ExecIf("SIP/EXTERNALTRUNK-00003934", "1?Set(REALCALLERIDNUM=89105555555)") in new stack
    -- Executing [s@macro-user-callerid:5] Set("SIP/EXTERNALTRUNK-00003934", "AMPUSER=") in new stack
    -- Executing [s@macro-user-callerid:6] GotoIf("SIP/EXTERNALTRUNK-00003934", "0?limit") in new stack
    -- Executing [s@macro-user-callerid:7] Set("SIP/EXTERNALTRUNK-00003934", "AMPUSERCIDNAME=") in new stack
    -- Executing [s@macro-user-callerid:8] GotoIf("SIP/EXTERNALTRUNK-00003934", "1?report") in new stack
    -- Goto (macro-user-callerid,s,16)
    -- Executing [s@macro-user-callerid:16] GotoIf("SIP/EXTERNALTRUNK-00003934", "0?continue") in new stack
    -- Executing [s@macro-user-callerid:17] Set("SIP/EXTERNALTRUNK-00003934", "__TTL=64") in new stack
    -- Executing [s@macro-user-callerid:18] GotoIf("SIP/EXTERNALTRUNK-00003934", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,29)
    -- Executing [s@macro-user-callerid:29] Set("SIP/EXTERNALTRUNK-00003934", "CALLERID(number)=89105555555") in new stack
    -- Executing [s@macro-user-callerid:30] Set("SIP/EXTERNALTRUNK-00003934", "CALLERID(name)=") in new stack
    -- Executing [s@macro-user-callerid:31] Set("SIP/EXTERNALTRUNK-00003934", "CDR(cnum)=89105555555") in new stack
    -- Executing [s@macro-user-callerid:32] Set("SIP/EXTERNALTRUNK-00003934", "CDR(cnam)=") in new stack
    -- Executing [s@macro-user-callerid:33] Set("SIP/EXTERNALTRUNK-00003934", "CHANNEL(language)=ru") in new stack
    -- Executing [s@macro-exten-vm:2] Set("SIP/EXTERNALTRUNK-00003934", "RingGroupMethod=none") in new stack
    -- Executing [s@macro-exten-vm:3] Set("SIP/EXTERNALTRUNK-00003934", "__EXTTOCALL=299") in new stack
    -- Executing [s@macro-exten-vm:4] Set("SIP/EXTERNALTRUNK-00003934", "__PICKUPMARK=299") in new stack
    -- Executing [s@macro-exten-vm:5] Set("SIP/EXTERNALTRUNK-00003934", "RT=60") in new stack
    -- Executing [s@macro-exten-vm:6] Gosub("SIP/EXTERNALTRUNK-00003934", "sub-record-check,s,1(exten,299,dontcare)") in new stack
    -- Executing [s@sub-record-check:1] GotoIf("SIP/EXTERNALTRUNK-00003934", "10?initialized") in new stack
    -- Goto (sub-record-check,s,10)
    -- Executing [s@sub-record-check:10] NoOp("SIP/EXTERNALTRUNK-00003934", "Recordings initialized") in new stack
    -- Executing [s@sub-record-check:11] ExecIf("SIP/EXTERNALTRUNK-00003934", "0?Set(ARG3=dontcare)") in new stack
    -- Executing [s@sub-record-check:12] Set("SIP/EXTERNALTRUNK-00003934", "REC_POLICY_MODE_SAVE=FORCE") in new stack
    -- Executing [s@sub-record-check:13] ExecIf("SIP/EXTERNALTRUNK-00003934", "0?Set(REC_STATUS=NO)") in new stack
    -- Executing [s@sub-record-check:14] GotoIf("SIP/EXTERNALTRUNK-00003934", "5?checkaction") in new stack
    -- Goto (sub-record-check,s,17)
    -- Executing [s@sub-record-check:17] GotoIf("SIP/EXTERNALTRUNK-00003934", "1?sub-record-check,exten,1") in new stack
    -- Goto (sub-record-check,exten,1)
    -- Executing [exten@sub-record-check:1] NoOp("SIP/EXTERNALTRUNK-00003934", "Exten Recording Check between 9105555555 and 299") in new stack
    -- Executing [exten@sub-record-check:2] Set("SIP/EXTERNALTRUNK-00003934", "CALLTYPE=external") in new stack
    -- Executing [exten@sub-record-check:3] ExecIf("SIP/EXTERNALTRUNK-00003934", "0?Set(CALLTYPE=)") in new stack
    -- Executing [exten@sub-record-check:4] Set("SIP/EXTERNALTRUNK-00003934", "CALLEE=dontcare") in new stack
    -- Executing [exten@sub-record-check:5] ExecIf("SIP/EXTERNALTRUNK-00003934", "0?Set(CALLEE=dontcare)") in new stack
    -- Executing [exten@sub-record-check:6] GotoIf("SIP/EXTERNALTRUNK-00003934", "1?callee") in new stack
    -- Goto (sub-record-check,exten,11)
    -- Executing [exten@sub-record-check:11] Gosub("SIP/EXTERNALTRUNK-00003934", "recordcheck,1(dontcare,external,299)") in new stack
    -- Executing [recordcheck@sub-record-check:1] NoOp("SIP/EXTERNALTRUNK-00003934", "Starting recording check against dontcare") in new stack
    -- Executing [recordcheck@sub-record-check:2] Goto("SIP/EXTERNALTRUNK-00003934", "dontcare") in new stack
    -- Goto (sub-record-check,recordcheck,3)
    -- Executing [recordcheck@sub-record-check:3] Return("SIP/EXTERNALTRUNK-00003934", "") in new stack
    -- Executing [exten@sub-record-check:12] Return("SIP/EXTERNALTRUNK-00003934", "") in new stack
    -- Executing [s@macro-exten-vm:7] Macro("SIP/EXTERNALTRUNK-00003934", "dial-one,60,TtrL(3600000:60000:30501),299") in new stack
    -- Executing [s@macro-dial-one:1] Set("SIP/EXTERNALTRUNK-00003934", "DEXTEN=299") in new stack
    -- Executing [s@macro-dial-one:2] Set("SIP/EXTERNALTRUNK-00003934", "DIALSTATUS_CW=") in new stack
    -- Executing [s@macro-dial-one:3] GosubIf("SIP/EXTERNALTRUNK-00003934", "0?screen,1()") in new stack
    -- Executing [s@macro-dial-one:4] GosubIf("SIP/EXTERNALTRUNK-00003934", "0?cf,1()") in new stack
    -- Executing [s@macro-dial-one:5] GotoIf("SIP/EXTERNALTRUNK-00003934", "1?skip1") in new stack
    -- Goto (macro-dial-one,s,8)
    -- Executing [s@macro-dial-one:8] GotoIf("SIP/EXTERNALTRUNK-00003934", "0?nodial") in new stack
    -- Executing [s@macro-dial-one:9] GotoIf("SIP/EXTERNALTRUNK-00003934", "0?continue") in new stack
    -- Executing [s@macro-dial-one:10] Set("SIP/EXTERNALTRUNK-00003934", "EXTHASCW=ENABLED") in new stack
    -- Executing [s@macro-dial-one:11] GotoIf("SIP/EXTERNALTRUNK-00003934", "0?next1:cwinusebusy") in new stack
    -- Goto (macro-dial-one,s,23)
    -- Executing [s@macro-dial-one:23] GotoIf("SIP/EXTERNALTRUNK-00003934", "1?next3:continue") in new stack
    -- Goto (macro-dial-one,s,24)
    -- Executing [s@macro-dial-one:24] ExecIf("SIP/EXTERNALTRUNK-00003934", "0?Set(DIALSTATUS_CW=BUSY)") in new stack
    -- Executing [s@macro-dial-one:25] GotoIf("SIP/EXTERNALTRUNK-00003934", "0?nodial") in new stack
    -- Executing [s@macro-dial-one:26] GosubIf("SIP/EXTERNALTRUNK-00003934", "1?dstring,1():dlocal,1()") in new stack
    -- Executing [dstring@macro-dial-one:1] Set("SIP/EXTERNALTRUNK-00003934", "DSTRING=") in new stack
    -- Executing [dstring@macro-dial-one:2] Set("SIP/EXTERNALTRUNK-00003934", "DEVICES=299") in new stack
    -- Executing [dstring@macro-dial-one:3] ExecIf("SIP/EXTERNALTRUNK-00003934", "0?Return()") in new stack
    -- Executing [dstring@macro-dial-one:4] ExecIf("SIP/EXTERNALTRUNK-00003934", "0?Set(DEVICES=99)") in new stack
    -- Executing [dstring@macro-dial-one:5] Set("SIP/EXTERNALTRUNK-00003934", "LOOPCNT=1") in new stack
    -- Executing [dstring@macro-dial-one:6] Set("SIP/EXTERNALTRUNK-00003934", "ITER=1") in new stack
    -- Executing [dstring@macro-dial-one:7] Set("SIP/EXTERNALTRUNK-00003934", "THISDIAL=SIP/299") in new stack
    -- Executing [dstring@macro-dial-one:8] GosubIf("SIP/EXTERNALTRUNK-00003934", "1?zap2dahdi,1()") in new stack
    -- Executing [zap2dahdi@macro-dial-one:1] ExecIf("SIP/EXTERNALTRUNK-00003934", "0?Return()") in new stack
    -- Executing [zap2dahdi@macro-dial-one:2] Set("SIP/EXTERNALTRUNK-00003934", "NEWDIAL=") in new stack
    -- Executing [zap2dahdi@macro-dial-one:3] Set("SIP/EXTERNALTRUNK-00003934", "LOOPCNT2=1") in new stack
    -- Executing [zap2dahdi@macro-dial-one:4] Set("SIP/EXTERNALTRUNK-00003934", "ITER2=1") in new stack
    -- Executing [zap2dahdi@macro-dial-one:5] Set("SIP/EXTERNALTRUNK-00003934", "THISPART2=SIP/299") in new stack
    -- Executing [zap2dahdi@macro-dial-one:6] ExecIf("SIP/EXTERNALTRUNK-00003934", "0?Set(THISPART2=DAHDI/299)") in new stack
    -- Executing [zap2dahdi@macro-dial-one:7] Set("SIP/EXTERNALTRUNK-00003934", "NEWDIAL=SIP/299&") in new stack
    -- Executing [zap2dahdi@macro-dial-one:8] Set("SIP/EXTERNALTRUNK-00003934", "ITER2=2") in new stack
    -- Executing [zap2dahdi@macro-dial-one:9] GotoIf("SIP/EXTERNALTRUNK-00003934", "0?begin2") in new stack
    -- Executing [zap2dahdi@macro-dial-one:10] Set("SIP/EXTERNALTRUNK-00003934", "THISDIAL=SIP/299") in new stack
    -- Executing [zap2dahdi@macro-dial-one:11] Return("SIP/EXTERNALTRUNK-00003934", "") in new stack
    -- Executing [dstring@macro-dial-one:9] GotoIf("SIP/EXTERNALTRUNK-00003934", "1?doset") in new stack
    -- Goto (macro-dial-one,dstring,13)
    -- Executing [dstring@macro-dial-one:13] Set("SIP/EXTERNALTRUNK-00003934", "DSTRING=SIP/299&") in new stack
    -- Executing [dstring@macro-dial-one:14] Set("SIP/EXTERNALTRUNK-00003934", "ITER=2") in new stack
    -- Executing [dstring@macro-dial-one:15] GotoIf("SIP/EXTERNALTRUNK-00003934", "0?begin") in new stack
    -- Executing [dstring@macro-dial-one:16] ExecIf("SIP/EXTERNALTRUNK-00003934", "0?Return()") in new stack
    -- Executing [dstring@macro-dial-one:17] Set("SIP/EXTERNALTRUNK-00003934", "DSTRING=SIP/299") in new stack
    -- Executing [dstring@macro-dial-one:18] Return("SIP/EXTERNALTRUNK-00003934", "") in new stack
    -- Executing [s@macro-dial-one:27] GotoIf("SIP/EXTERNALTRUNK-00003934", "0?nodial") in new stack
    -- Executing [s@macro-dial-one:28] GotoIf("SIP/EXTERNALTRUNK-00003934", "0?skiptrace") in new stack
    -- Executing [s@macro-dial-one:29] GosubIf("SIP/EXTERNALTRUNK-00003934", "1?ctset,1():ctclear,1()") in new stack
    -- Executing [ctset@macro-dial-one:1] Set("SIP/EXTERNALTRUNK-00003934", "DB(CALLTRACE/299)=89105555555") in new stack
    -- Executing [ctset@macro-dial-one:2] Return("SIP/EXTERNALTRUNK-00003934", "") in new stack
    -- Executing [s@macro-dial-one:30] Set("SIP/EXTERNALTRUNK-00003934", "D_OPTIONS=TtrL(3600000:60000:30501)") in new stack
    -- Executing [s@macro-dial-one:31] ExecIf("SIP/EXTERNALTRUNK-00003934", "0?SIPAddHeader(Alert-Info: )") in new stack
    -- Executing [s@macro-dial-one:32] ExecIf("SIP/EXTERNALTRUNK-00003934", "0?SIPAddHeader()") in new stack
    -- Executing [s@macro-dial-one:33] ExecIf("SIP/EXTERNALTRUNK-00003934", "1?Set(CHANNEL(musicclass)=default)") in new stack
    -- Executing [s@macro-dial-one:34] GosubIf("SIP/EXTERNALTRUNK-00003934", "0?qwait,1()") in new stack
    -- Executing [s@macro-dial-one:35] Set("SIP/EXTERNALTRUNK-00003934", "__CWIGNORE=") in new stack
    -- Executing [s@macro-dial-one:36] Set("SIP/EXTERNALTRUNK-00003934", "__KEEPCID=TRUE") in new stack
    -- Executing [s@macro-dial-one:37] GotoIf("SIP/EXTERNALTRUNK-00003934", "0?usegoto,1") in new stack
    -- Executing [s@macro-dial-one:38] GotoIf("SIP/EXTERNALTRUNK-00003934", "1?godial") in new stack
    -- Goto (macro-dial-one,s,43)
    -- Executing [s@macro-dial-one:43] Macro("SIP/EXTERNALTRUNK-00003934", "dialout-one-predial-hook,") in new stack
    -- Executing [s@macro-dialout-one-predial-hook:1] MacroExit("SIP/EXTERNALTRUNK-00003934", "") in new stack
    -- Executing [s@macro-dial-one:44] Dial("SIP/EXTERNALTRUNK-00003934", "SIP/299,60,TtrL(3600000:60000:30501)") in new stack
       > Limit Data for this call:
       > timelimit      = 3600000 ms (3600.000 s)
       > play_warning   = 60000 ms (60.000 s)
       > play_to_caller = yes
       > play_to_callee = no
       > warning_freq   = 30501 ms (30.501 s)
       > start_sound    =
       > warning_sound  = timeleft
       > end_sound      =
  == Using SIP RTP TOS bits 184
  == Using SIP RTP CoS mark 5
Audio is at 12924
Adding codec 100004 (alaw) to SDP
Adding codec 100012 (g722) to SDP
Adding codec 100003 (ulaw) to SDP
Adding non-codec 0x1 (telephone-event) to SDP
Reliably Transmitting (NAT) to 10.123.222.250:5120:
INVITE sip:299@192.168.254.150:5120 SIP/2.0
Via: SIP/2.0/UDP 10.123.123.252:5060;branch=z9hG4bK41f73bbf;rport
Max-Forwards: 70
From: <sip:89105555555@10.123.123.252>;tag=as3d1b1608
To: <sip:299@192.168.254.150:5120>
Contact: <sip:89105555555@10.123.123.252:5060>
Call-ID: 70fe53c379db32ce662734166ab5fb25@10.123.123.252:5060
CSeq: 102 INVITE
User-Agent: FPBX-12.0.76.2(11.19.0)
Date: Tue, 10 Nov 2015 18:45:47 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
P-Asserted-Identity: "89105555555" <sip:89105555555@10.123.123.252>
Content-Type: application/sdp
Content-Length: 286

v=0
o=root 131297692 131297692 IN IP4 10.123.123.252
s=Asterisk PBX 11.19.0
c=IN IP4 10.123.123.252
t=0 0
m=audio 12924 RTP/AVP 8 9 0 101
a=rtpmap:8 PCMA/8000
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv

---
    -- Called SIP/299

<--- SIP read from UDP:10.123.222.250:5120 --->
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 10.123.123.252:5060;branch=z9hG4bK41f73bbf;rport=5060
To: <sip:299@192.168.254.150>
From: <sip:89105555555@10.123.123.252>;tag=as3d1b1608
Call-ID: 70fe53c379db32ce662734166ab5fb25@10.123.123.252:5060
CSeq: 102 INVITE
Content-Length: 0

<------------->
--- (7 headers 0 lines) ---

<--- SIP read from UDP:10.123.222.250:5120 --->
SIP/2.0 302 Moved Temporarily
Via: SIP/2.0/UDP 10.123.123.252:5060;branch=z9hG4bK41f73bbf;rport=5060
To: <sip:299@192.168.254.150>;tag=3041330473
From: <sip:89105555555@10.123.123.252>;tag=as3d1b1608
Call-ID: 70fe53c379db32ce662734166ab5fb25@10.123.123.252:5060
CSeq: 102 INVITE
Contact: <sip:84957555545@10.123.123.252>
Allow: INVITE,ACK,CANCEL,BYE,OPTIONS,NOTIFY,REFER,UPDATE
Diversion: "89105555555" <sip:299@10.123.123.252>;reason=unconditional
Content-Length: 0

<------------->
--- (10 headers 0 lines) ---
    -- Got SIP response 302 "Moved Temporarily" back from 10.123.222.250:5120
RDNIS for this call is 299 (reason unconditional)
Transmitting (NAT) to 10.123.222.250:5120:
ACK sip:299@192.168.254.150:5120 SIP/2.0
Via: SIP/2.0/UDP 10.123.123.252:5060;branch=z9hG4bK41f73bbf;rport
Max-Forwards: 70
From: <sip:89105555555@10.123.123.252>;tag=as3d1b1608
To: <sip:299@192.168.254.150:5120>;tag=3041330473
Contact: <sip:89105555555@10.123.123.252:5060>
Call-ID: 70fe53c379db32ce662734166ab5fb25@10.123.123.252:5060
CSeq: 102 ACK
User-Agent: FPBX-12.0.76.2(11.19.0)
Content-Length: 0


---
    -- Now forwarding SIP/EXTERNALTRUNK-00003934 to 'SIP/84957555545::::UDP@10.123.123.252' (thanks to SIP/299-00003935)
  == Using SIP RTP TOS bits 184
  == Using SIP RTP CoS mark 5
[2015-11-10 21:45:47] NOTICE[9356][C-00002038]: app_dial.c:958 do_forward: Not accepting call completion offers from call-forward recipient SIP/10.123.123.252-00003936
[2015-11-10 21:45:47] NOTICE[9356][C-00002038]: app_dial.c:958 do_forward: Not accepting call completion offers from call-forward recipient SIP/10.123.123.252-00003936
Really destroying SIP dialog '70fe53c379db32ce662734166ab5fb25@10.123.123.252:5060' Method: INVITE
[2015-11-10 21:45:47] NOTICE[1789][C-00002038]: chan_sip.c:23155 handle_response_invite: Failed to authenticate on INVITE to '<sip:89105555555@10.123.123.252>;tag=as3a15e867'
[2015-11-10 21:45:47] NOTICE[1789][C-00002038]: chan_sip.c:23155 handle_response_invite: Failed to authenticate on INVITE to '<sip:89105555555@10.123.123.252>;tag=as3a15e867'
    -- SIP/10.123.123.252-00003936 is circuit-busy
  == Everyone is busy/congested at this time (1:0/1/0)
    -- Executing [s@macro-dial-one:45] ExecIf("SIP/EXTERNALTRUNK-00003934", "0?MacroExit()") in new stack
    -- Executing [s@macro-dial-one:46] ExecIf("SIP/EXTERNALTRUNK-00003934", "0?Set(DIALSTATUS=)") in new stack
    -- Executing [s@macro-dial-one:47] GosubIf("SIP/EXTERNALTRUNK-00003934", "0?s-CONGESTION,1()") in new stack
    -- Executing [s@macro-dial-one:48] MacroExit("SIP/EXTERNALTRUNK-00003934", "") in new stack
    -- Executing [s@macro-exten-vm:8] Set("SIP/EXTERNALTRUNK-00003934", "SV_DIALSTATUS=CONGESTION") in new stack
    -- Executing [s@macro-exten-vm:9] GosubIf("SIP/EXTERNALTRUNK-00003934", "0?docfu,1()") in new stack
    -- Executing [s@macro-exten-vm:10] GosubIf("SIP/EXTERNALTRUNK-00003934", "0?docfb,1()") in new stack
    -- Executing [s@macro-exten-vm:11] Set("SIP/EXTERNALTRUNK-00003934", "DIALSTATUS=CONGESTION") in new stack
    -- Executing [s@macro-exten-vm:12] ExecIf("SIP/EXTERNALTRUNK-00003934", "0?MacroExit()") in new stack
    -- Executing [s@macro-exten-vm:13] GotoIf("SIP/EXTERNALTRUNK-00003934", "1?s-CONGESTION,1") in new stack
    -- Goto (macro-exten-vm,s-CONGESTION,1)
    -- Executing [s-CONGESTION@macro-exten-vm:1] GotoIf("SIP/EXTERNALTRUNK-00003934", "0?exit,1") in new stack
    -- Executing [s-CONGESTION@macro-exten-vm:2] PlayTones("SIP/EXTERNALTRUNK-00003934", "congestion") in new stack
[2015-11-10 21:45:47] WARNING[9356][C-00002038]: translate.c:340 framein: no samples for alawtolin
[2015-11-10 21:45:47] WARNING[9356][C-00002038]: translate.c:340 framein: no samples for alawtolin
    -- Executing [s-CONGESTION@macro-exten-vm:3] Congestion("SIP/EXTERNALTRUNK-00003934", "10") in new stack
  == Spawn extension (macro-exten-vm, s-CONGESTION, 3) exited non-zero on 'SIP/EXTERNALTRUNK-00003934' in macro 'exten-vm'
  == Spawn extension (from-did-direct, 299, 2) exited non-zero on 'SIP/EXTERNALTRUNK-00003934'
    -- Executing [h@from-did-direct:1] Macro("SIP/EXTERNALTRUNK-00003934", "hangupcall,") in new stack
    -- Executing [s@macro-hangupcall:1] ExecIf("SIP/EXTERNALTRUNK-00003934", "0?Set(CDR(recordingfile)=in-74951123456-9105555555-20151110-214547-1447181147.18116.wav)") in new stack
    -- Executing [s@macro-hangupcall:2] GotoIf("SIP/EXTERNALTRUNK-00003934", "1?theend") in new stack
    -- Goto (macro-hangupcall,s,4)
    -- Executing [s@macro-hangupcall:4] Hangup("SIP/EXTERNALTRUNK-00003934", "") in new stack
  == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/EXTERNALTRUNK-00003934' in macro 'hangupcall'
  == Spawn extension (from-did-direct, h, 1) exited non-zero on 'SIP/EXTERNALTRUNK-00003934'
  == MixMonitor close filestream (mixed)
  == End MixMonitor Recording SIP/EXTERNALTRUNK-00003934

[ded]

Где-то в настройках телефонного аппарата Panasonic TGP500 кроме поля перенаправлятора "Куда" ещё может быть опция типа "Разрешить перенаправлять" - Yes/No
и вот она у вас стоит в No, именно это даёт ответ Not accepting call completion offers from call-forward recipient.
Если нет такой - пробуйте отключить опции p-asserted identity и trust-rpid в настройках пира телефона на Астериске в OFF.

[gaspaarr]

Такой опции нет, более того в вэбе дублируются настройки переадресации сделанный на самом аппарате, и там все включено.
Нашел такие пункты Разрешенный SSAF (фильтр адреса SIP ресурса), Поддерживает 100rel (RFC 3262) - стоят в нет, но вряд ли это оно?

На FPBX пробовал отключать указанные настройки, как глобально, так и для конкретного ext -- без изменений.

app_dial.c:958 do_forward: Not accepting call completion offers from call-forward recipient

[ded]

Там ещё вижу Failed to authenticate on INVITE to '<sip:89105555555@10.123.123.252>;tag=as3a15e867'
Вот этот пир - 10.123.123.252 это кто/что?
Если это провайдер, то перенаправляемый вызов (из мира - опять в мир) пытается уйти без аутентификации,или с неверной аутентификацией.

[gaspaarr]

10.123.123.252 это мой FPBX, провайдерский 195.2.2.2

[ded]

Вот что происходит: звонок в мир обрабатывается в контексте from-internal, и нормально выбегает через транк провайдера.
Звонок из мира прибегает через транк провайдера в контекст from-pstn (или from-did) и проходит по станции руководствуясь входящей маршрутизацией.
Но в этом случае,получая 302 Moved temporarely, вызов остаётся в наружном контексте from-pstn (или from-did),откуда по умолчанию невозможны звонки из транка в транк, чтобы не назвонили.
Пробуйте поставить insecure=invite на провайдерском пире. Но - не уверен.

[Zavr2008]

если там без регистрации, наверное insecure=port,invite. Опять же тогда зачем fromuser/fromdomain?
sip debug полный бы звонка по прову..

по 302 сообщению - да, promiscredir=yes - у нас тоже у клиентов зеленых на их сети с протеями без этого не работало.

[gaspaarr]

В общем ситуация вообще непонятная, переадресация не работает даже если переадресую с внутреннего 199, на внутренний же 175, вот лог входящего звонка с 255 на 199, должна была пройти переадресация (302 Moved) на 175, но ее не произошло по той же причине: Not accepting call completion offers from call-forward recipient

Код: Выделить всё

sip*CLI>
sip*CLI>
sip*CLI>
  == Using SIP RTP TOS bits 184
  == Using SIP RTP CoS mark 5
       > [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,userfield) VALUES ('CHAN_START',{ts '2015-11-14 16:45:01.344932'},'255','255','','','','199','ALL','SIP/255-00004b7c','','',3,'','1447508701.23646','1447508701.23646','','','')]
    -- Executing [199@ALL:1] Set("SIP/255-00004b7c", "__RINGTIMER=60") in new stack
    -- Executing [199@ALL:2] Macro("SIP/255-00004b7c", "exten-vm,novm,199,1,0,0") in new stack
    -- Executing [s@macro-exten-vm:1] Macro("SIP/255-00004b7c", "user-callerid,") in new stack
    -- Executing [s@macro-user-callerid:1] Set("SIP/255-00004b7c", "TOUCH_MONITOR=1447508701.23646") in new stack
    -- Executing [s@macro-user-callerid:2] Set("SIP/255-00004b7c", "AMPUSER=255") in new stack
    -- Executing [s@macro-user-callerid:3] GotoIf("SIP/255-00004b7c", "0?report") in new stack
    -- Executing [s@macro-user-callerid:4] ExecIf("SIP/255-00004b7c", "1?Set(REALCALLERIDNUM=255)") in new stack
    -- Executing [s@macro-user-callerid:5] Set("SIP/255-00004b7c", "AMPUSER=255") in new stack
    -- Executing [s@macro-user-callerid:6] GotoIf("SIP/255-00004b7c", "0?limit") in new stack
    -- Executing [s@macro-user-callerid:7] Set("SIP/255-00004b7c", "AMPUSERCIDNAME=255") in new stack
    -- Executing [s@macro-user-callerid:8] GotoIf("SIP/255-00004b7c", "0?report") in new stack
    -- Executing [s@macro-user-callerid:9] Set("SIP/255-00004b7c", "AMPUSERCID=255") in new stack
    -- Executing [s@macro-user-callerid:10] Set("SIP/255-00004b7c", "__DIAL_OPTIONS=TtrL(3600000:60000:30501)") in new stack
    -- Executing [s@macro-user-callerid:11] Set("SIP/255-00004b7c", "CALLERID(all)="255" <255>") in new stack
    -- Executing [s@macro-user-callerid:12] GotoIf("SIP/255-00004b7c", "0?limit") in new stack
    -- Executing [s@macro-user-callerid:13] ExecIf("SIP/255-00004b7c", "0?Set(GROUP(concurrency_limit)=255)") in new stack
    -- Executing [s@macro-user-callerid:14] GosubIf("SIP/255-00004b7c", "7?sub-ccss,s,1(macro-exten-vm,199)") in new stack
    -- Executing [s@sub-ccss:1] ExecIf("SIP/255-00004b7c", "0?Return()") in new stack
    -- Executing [s@sub-ccss:2] Set("SIP/255-00004b7c", "CCSS_SETUP=TRUE") in new stack
    -- Executing [s@sub-ccss:3] GosubIf("SIP/255-00004b7c", "0?monitor_config,1(macro-exten-vm,199):monitor_default,1(macro-exten-vm,199)") in new stack
    -- Executing [monitor_default@sub-ccss:1] GotoIf("SIP/255-00004b7c", "1?is_exten") in new stack
    -- Goto (sub-ccss,monitor_default,4)
    -- Executing [monitor_default@sub-ccss:4] Set("SIP/255-00004b7c", "CALLCOMPLETION(cc_monitor_policy)=generic") in new stack
    -- Executing [monitor_default@sub-ccss:5] Set("SIP/255-00004b7c", "CALLCOMPLETION(cc_max_monitors)=5") in new stack
    -- Executing [monitor_default@sub-ccss:6] Return("SIP/255-00004b7c", "TRUE") in new stack
    -- Executing [s@sub-ccss:4] GosubIf("SIP/255-00004b7c", "7?agent_config,1():agent_default,1()") in new stack
    -- Executing [agent_config@sub-ccss:1] Set("SIP/255-00004b7c", "CALLCOMPLETION(cc_agent_policy)=generic") in new stack
    -- Executing [agent_config@sub-ccss:2] Set("SIP/255-00004b7c", "CALLCOMPLETION(cc_offer_timer)=30") in new stack
    -- Executing [agent_config@sub-ccss:3] Set("SIP/255-00004b7c", "CALLCOMPLETION(ccbs_available_timer)=") in new stack
    -- Executing [agent_config@sub-ccss:4] Set("SIP/255-00004b7c", "CALLCOMPLETION(ccnr_available_timer)=") in new stack
    -- Executing [agent_config@sub-ccss:5] Set("SIP/255-00004b7c", "CALLCOMPLETION(cc_callback_macro)=ccss-default") in new stack
[2015-11-14 16:45:01] WARNING[1954][C-000029f0]: ccss.c:1000 ast_set_cc_callback_macro: Usage of cc_callback_macro is deprecated.  Please use cc_callback_sub instead.
[2015-11-14 16:45:01] WARNING[1954][C-000029f0]: ccss.c:1000 ast_set_cc_callback_macro: Usage of cc_callback_macro is deprecated.  Please use cc_callback_sub instead.
    -- Executing [agent_config@sub-ccss:6] ExecIf("SIP/255-00004b7c", "1?Set(CALLCOMPLETION(cc_recall_timer)=)") in new stack
    -- Executing [agent_config@sub-ccss:7] ExecIf("SIP/255-00004b7c", "1?Set(CALLCOMPLETION(cc_max_agents)=)") in new stack
    -- Executing [agent_config@sub-ccss:8] ExecIf("SIP/255-00004b7c", "0?Set(CALLCOMPLETION(cc_agent_dialstring)=Local/255_199@from-ccss-)") in new stack
    -- Executing [agent_config@sub-ccss:9] Set("SIP/255-00004b7c", "CALLCOMPLETION(cc_callback_macro)=ccss-default") in new stack
[2015-11-14 16:45:01] WARNING[1954][C-000029f0]: ccss.c:1000 ast_set_cc_callback_macro: Usage of cc_callback_macro is deprecated.  Please use cc_callback_sub instead.
[2015-11-14 16:45:01] WARNING[1954][C-000029f0]: ccss.c:1000 ast_set_cc_callback_macro: Usage of cc_callback_macro is deprecated.  Please use cc_callback_sub instead.
    -- Executing [agent_config@sub-ccss:10] Return("SIP/255-00004b7c", "") in new stack
    -- Executing [s@sub-ccss:5] Set("SIP/255-00004b7c", "DB(AMPUSER/255/ccss/last_number)=199") in new stack
    -- Executing [s@sub-ccss:6] Return("SIP/255-00004b7c", "") in new stack
    -- Executing [s@macro-user-callerid:15] ExecIf("SIP/255-00004b7c", "0?Set(CHANNEL(language)=)") in new stack
    -- Executing [s@macro-user-callerid:16] GotoIf("SIP/255-00004b7c", "0?continue") in new stack
    -- Executing [s@macro-user-callerid:17] Set("SIP/255-00004b7c", "__TTL=64") in new stack
    -- Executing [s@macro-user-callerid:18] GotoIf("SIP/255-00004b7c", "1?continue") in new stack
    -- Goto (macro-user-callerid,s,29)
    -- Executing [s@macro-user-callerid:29] Set("SIP/255-00004b7c", "CALLERID(number)=255") in new stack
    -- Executing [s@macro-user-callerid:30] Set("SIP/255-00004b7c", "CALLERID(name)=255") in new stack
    -- Executing [s@macro-user-callerid:31] Set("SIP/255-00004b7c", "CDR(cnum)=255") in new stack
    -- Executing [s@macro-user-callerid:32] Set("SIP/255-00004b7c", "CDR(cnam)=255") in new stack
    -- Executing [s@macro-user-callerid:33] Set("SIP/255-00004b7c", "CHANNEL(language)=ru") in new stack
    -- Executing [s@macro-exten-vm:2] Set("SIP/255-00004b7c", "RingGroupMethod=none") in new stack
    -- Executing [s@macro-exten-vm:3] Set("SIP/255-00004b7c", "__EXTTOCALL=199") in new stack
    -- Executing [s@macro-exten-vm:4] Set("SIP/255-00004b7c", "__PICKUPMARK=199") in new stack
    -- Executing [s@macro-exten-vm:5] Set("SIP/255-00004b7c", "RT=60") in new stack
    -- Executing [s@macro-exten-vm:6] Gosub("SIP/255-00004b7c", "sub-record-check,s,1(exten,199,dontcare)") in new stack
    -- Executing [s@sub-record-check:1] GotoIf("SIP/255-00004b7c", "0?initialized") in new stack
    -- Executing [s@sub-record-check:2] Set("SIP/255-00004b7c", "__REC_STATUS=INITIALIZED") in new stack
    -- Executing [s@sub-record-check:3] Set("SIP/255-00004b7c", "NOW=1447508701") in new stack
    -- Executing [s@sub-record-check:4] Set("SIP/255-00004b7c", "__DAY=14") in new stack
    -- Executing [s@sub-record-check:5] Set("SIP/255-00004b7c", "__MONTH=11") in new stack
    -- Executing [s@sub-record-check:6] Set("SIP/255-00004b7c", "__YEAR=2015") in new stack
    -- Executing [s@sub-record-check:7] Set("SIP/255-00004b7c", "__TIMESTR=20151114-164501") in new stack
    -- Executing [s@sub-record-check:8] Set("SIP/255-00004b7c", "__FROMEXTEN=255") in new stack
    -- Executing [s@sub-record-check:9] Set("SIP/255-00004b7c", "__MON_FMT=wav") in new stack
    -- Executing [s@sub-record-check:10] NoOp("SIP/255-00004b7c", "Recordings initialized") in new stack
    -- Executing [s@sub-record-check:11] ExecIf("SIP/255-00004b7c", "0?Set(ARG3=dontcare)") in new stack
    -- Executing [s@sub-record-check:12] Set("SIP/255-00004b7c", "REC_POLICY_MODE_SAVE=") in new stack
    -- Executing [s@sub-record-check:13] ExecIf("SIP/255-00004b7c", "0?Set(REC_STATUS=NO)") in new stack
    -- Executing [s@sub-record-check:14] GotoIf("SIP/255-00004b7c", "5?checkaction") in new stack
    -- Goto (sub-record-check,s,17)
    -- Executing [s@sub-record-check:17] GotoIf("SIP/255-00004b7c", "1?sub-record-check,exten,1") in new stack
    -- Goto (sub-record-check,exten,1)
    -- Executing [exten@sub-record-check:1] NoOp("SIP/255-00004b7c", "Exten Recording Check between 255 and 199") in new stack
    -- Executing [exten@sub-record-check:2] Set("SIP/255-00004b7c", "CALLTYPE=internal") in new stack
    -- Executing [exten@sub-record-check:3] ExecIf("SIP/255-00004b7c", "0?Set(CALLTYPE=)") in new stack
    -- Executing [exten@sub-record-check:4] Set("SIP/255-00004b7c", "CALLEE=dontcare") in new stack
    -- Executing [exten@sub-record-check:5] ExecIf("SIP/255-00004b7c", "0?Set(CALLEE=dontcare)") in new stack
    -- Executing [exten@sub-record-check:6] GotoIf("SIP/255-00004b7c", "0?callee") in new stack
    -- Executing [exten@sub-record-check:7] GotoIf("SIP/255-00004b7c", "1?caller") in new stack
    -- Goto (sub-record-check,exten,13)
    -- Executing [exten@sub-record-check:13] Set("SIP/255-00004b7c", "RECMODE=force") in new stack
    -- Executing [exten@sub-record-check:14] ExecIf("SIP/255-00004b7c", "0?Set(RECMODE=dontcare)") in new stack
    -- Executing [exten@sub-record-check:15] ExecIf("SIP/255-00004b7c", "0?Set(RECMODE=dontcare)") in new stack
    -- Executing [exten@sub-record-check:16] Gosub("SIP/255-00004b7c", "recordcheck,1(force,internal,199)") in new stack
    -- Executing [recordcheck@sub-record-check:1] NoOp("SIP/255-00004b7c", "Starting recording check against force") in new stack
    -- Executing [recordcheck@sub-record-check:2] Goto("SIP/255-00004b7c", "force") in new stack
    -- Goto (sub-record-check,recordcheck,5)
    -- Executing [recordcheck@sub-record-check:5] Set("SIP/255-00004b7c", "__REC_POLICY_MODE=FORCE") in new stack
    -- Executing [recordcheck@sub-record-check:6] GotoIf("SIP/255-00004b7c", "1?startrec") in new stack
    -- Goto (sub-record-check,recordcheck,16)
    -- Executing [recordcheck@sub-record-check:16] NoOp("SIP/255-00004b7c", "Starting recording: internal, 199") in new stack
    -- Executing [recordcheck@sub-record-check:17] Set("SIP/255-00004b7c", "AUDIOHOOK_INHERIT(MixMonitor)=yes") in new stack
    -- Executing [recordcheck@sub-record-check:18] Set("SIP/255-00004b7c", "__CALLFILENAME=internal-199-255-20151114-164501-1447508701.23646") in new stack
    -- Executing [recordcheck@sub-record-check:19] MixMonitor("SIP/255-00004b7c", "2015/11/14/internal-199-255-20151114-164501-1447508701.23646.wav,ai(LOCAL_MIXMON_ID),") in new stack
    -- Executing [recordcheck@sub-record-check:20] Set("SIP/255-00004b7c", "__MIXMON_ID=0x2abc6b0") in new stack
    -- Executing [recordcheck@sub-record-check:21] Set("SIP/255-00004b7c", "__RECORD_ID=SIP/255-00004b7c") in new stack
    -- Executing [recordcheck@sub-record-check:22] Set("SIP/255-00004b7c", "__REC_STATUS=RECORDING") in new stack
    -- Executing [recordcheck@sub-record-check:23] Set("SIP/255-00004b7c", "CDR(recordingfile)=internal-199-255-20151114-164501-1447508701.23646.wav") in new stack
    -- Executing [recordcheck@sub-record-check:24] Return("SIP/255-00004b7c", "") in new stack
    -- Executing [exten@sub-record-check:17] Return("SIP/255-00004b7c", "") in new stack
    -- Executing [s@macro-exten-vm:7] Macro("SIP/255-00004b7c", "dial-one,60,TtrL(3600000:60000:30501),199") in new stack
    -- Executing [s@macro-dial-one:1] Set("SIP/255-00004b7c", "DEXTEN=199") in new stack
    -- Executing [s@macro-dial-one:2] Set("SIP/255-00004b7c", "DIALSTATUS_CW=") in new stack
    -- Executing [s@macro-dial-one:3] GosubIf("SIP/255-00004b7c", "0?screen,1()") in new stack
    -- Executing [s@macro-dial-one:4] GosubIf("SIP/255-00004b7c", "0?cf,1()") in new stack
    -- Executing [s@macro-dial-one:5] GotoIf("SIP/255-00004b7c", "1?skip1") in new stack
    -- Goto (macro-dial-one,s,8)
    -- Executing [s@macro-dial-one:8] GotoIf("SIP/255-00004b7c", "0?nodial") in new stack
    -- Executing [s@macro-dial-one:9] GotoIf("SIP/255-00004b7c", "0?continue") in new stack
    -- Executing [s@macro-dial-one:10] Set("SIP/255-00004b7c", "EXTHASCW=ENABLED") in new stack
  == Begin MixMonitor Recording SIP/255-00004b7c
    -- Executing [s@macro-dial-one:11] GotoIf("SIP/255-00004b7c", "0?next1:cwinusebusy") in new stack
    -- Goto (macro-dial-one,s,23)
    -- Executing [s@macro-dial-one:23] GotoIf("SIP/255-00004b7c", "1?next3:continue") in new stack
    -- Goto (macro-dial-one,s,24)
    -- Executing [s@macro-dial-one:24] ExecIf("SIP/255-00004b7c", "0?Set(DIALSTATUS_CW=BUSY)") in new stack
    -- Executing [s@macro-dial-one:25] GotoIf("SIP/255-00004b7c", "0?nodial") in new stack
    -- Executing [s@macro-dial-one:26] GosubIf("SIP/255-00004b7c", "1?dstring,1():dlocal,1()") in new stack
    -- Executing [dstring@macro-dial-one:1] Set("SIP/255-00004b7c", "DSTRING=") in new stack
    -- Executing [dstring@macro-dial-one:2] Set("SIP/255-00004b7c", "DEVICES=199") in new stack
    -- Executing [dstring@macro-dial-one:3] ExecIf("SIP/255-00004b7c", "0?Return()") in new stack
    -- Executing [dstring@macro-dial-one:4] ExecIf("SIP/255-00004b7c", "0?Set(DEVICES=99)") in new stack
    -- Executing [dstring@macro-dial-one:5] Set("SIP/255-00004b7c", "LOOPCNT=1") in new stack
    -- Executing [dstring@macro-dial-one:6] Set("SIP/255-00004b7c", "ITER=1") in new stack
    -- Executing [dstring@macro-dial-one:7] Set("SIP/255-00004b7c", "THISDIAL=SIP/199") in new stack
    -- Executing [dstring@macro-dial-one:8] GosubIf("SIP/255-00004b7c", "1?zap2dahdi,1()") in new stack
    -- Executing [zap2dahdi@macro-dial-one:1] ExecIf("SIP/255-00004b7c", "0?Return()") in new stack
    -- Executing [zap2dahdi@macro-dial-one:2] Set("SIP/255-00004b7c", "NEWDIAL=") in new stack
    -- Executing [zap2dahdi@macro-dial-one:3] Set("SIP/255-00004b7c", "LOOPCNT2=1") in new stack
    -- Executing [zap2dahdi@macro-dial-one:4] Set("SIP/255-00004b7c", "ITER2=1") in new stack
    -- Executing [zap2dahdi@macro-dial-one:5] Set("SIP/255-00004b7c", "THISPART2=SIP/199") in new stack
    -- Executing [zap2dahdi@macro-dial-one:6] ExecIf("SIP/255-00004b7c", "0?Set(THISPART2=DAHDI/199)") in new stack
    -- Executing [zap2dahdi@macro-dial-one:7] Set("SIP/255-00004b7c", "NEWDIAL=SIP/199&") in new stack
    -- Executing [zap2dahdi@macro-dial-one:8] Set("SIP/255-00004b7c", "ITER2=2") in new stack
    -- Executing [zap2dahdi@macro-dial-one:9] GotoIf("SIP/255-00004b7c", "0?begin2") in new stack
    -- Executing [zap2dahdi@macro-dial-one:10] Set("SIP/255-00004b7c", "THISDIAL=SIP/199") in new stack
    -- Executing [zap2dahdi@macro-dial-one:11] Return("SIP/255-00004b7c", "") in new stack
    -- Executing [dstring@macro-dial-one:9] GotoIf("SIP/255-00004b7c", "1?doset") in new stack
    -- Goto (macro-dial-one,dstring,13)
    -- Executing [dstring@macro-dial-one:13] Set("SIP/255-00004b7c", "DSTRING=SIP/199&") in new stack
    -- Executing [dstring@macro-dial-one:14] Set("SIP/255-00004b7c", "ITER=2") in new stack
    -- Executing [dstring@macro-dial-one:15] GotoIf("SIP/255-00004b7c", "0?begin") in new stack
    -- Executing [dstring@macro-dial-one:16] ExecIf("SIP/255-00004b7c", "0?Return()") in new stack
    -- Executing [dstring@macro-dial-one:17] Set("SIP/255-00004b7c", "DSTRING=SIP/199") in new stack
    -- Executing [dstring@macro-dial-one:18] Return("SIP/255-00004b7c", "") in new stack
    -- Executing [s@macro-dial-one:27] GotoIf("SIP/255-00004b7c", "0?nodial") in new stack
    -- Executing [s@macro-dial-one:28] GotoIf("SIP/255-00004b7c", "0?skiptrace") in new stack
    -- Executing [s@macro-dial-one:29] GosubIf("SIP/255-00004b7c", "1?ctset,1():ctclear,1()") in new stack
    -- Executing [ctset@macro-dial-one:1] Set("SIP/255-00004b7c", "DB(CALLTRACE/199)=255") in new stack
    -- Executing [ctset@macro-dial-one:2] Return("SIP/255-00004b7c", "") in new stack
    -- Executing [s@macro-dial-one:30] Set("SIP/255-00004b7c", "D_OPTIONS=TtrL(3600000:60000:30501)") in new stack
    -- Executing [s@macro-dial-one:31] ExecIf("SIP/255-00004b7c", "0?SIPAddHeader(Alert-Info: )") in new stack
    -- Executing [s@macro-dial-one:32] ExecIf("SIP/255-00004b7c", "0?SIPAddHeader()") in new stack
    -- Executing [s@macro-dial-one:33] ExecIf("SIP/255-00004b7c", "0?Set(CHANNEL(musicclass)=)") in new stack
    -- Executing [s@macro-dial-one:34] GosubIf("SIP/255-00004b7c", "0?qwait,1()") in new stack
    -- Executing [s@macro-dial-one:35] Set("SIP/255-00004b7c", "__CWIGNORE=") in new stack
    -- Executing [s@macro-dial-one:36] Set("SIP/255-00004b7c", "__KEEPCID=TRUE") in new stack
    -- Executing [s@macro-dial-one:37] GotoIf("SIP/255-00004b7c", "0?usegoto,1") in new stack
    -- Executing [s@macro-dial-one:38] GotoIf("SIP/255-00004b7c", "0?godial") in new stack
    -- Executing [s@macro-dial-one:39] Gosub("SIP/255-00004b7c", "sub-presencestate-display,s,1(199)") in new stack
[2015-11-14 16:45:01] WARNING[1954][C-000029f0]: func_presencestate.c:132 presence_read: PRESENCE_STATE unknown
[2015-11-14 16:45:01] WARNING[1954][C-000029f0]: func_presencestate.c:132 presence_read: PRESENCE_STATE unknown
    -- Executing [s@sub-presencestate-display:1] Goto("SIP/255-00004b7c", "state-,1") in new stack
    -- Goto (sub-presencestate-display,state-,1)
    -- Executing [state-@sub-presencestate-display:1] Set("SIP/255-00004b7c", "PRESENCESTATE_DISPLAY=") in new stack
    -- Executing [state-@sub-presencestate-display:2] Return("SIP/255-00004b7c", "") in new stack
    -- Executing [s@macro-dial-one:40] Set("SIP/255-00004b7c", "CONNECTEDLINE(name,i)=199") in new stack
    -- Executing [s@macro-dial-one:41] Set("SIP/255-00004b7c", "CONNECTEDLINE(num)=199") in new stack
    -- Executing [s@macro-dial-one:42] Set("SIP/255-00004b7c", "D_OPTIONS=TtrL(3600000:60000:30501)I") in new stack
    -- Executing [s@macro-dial-one:43] Macro("SIP/255-00004b7c", "dialout-one-predial-hook,") in new stack
    -- Executing [s@macro-dialout-one-predial-hook:1] MacroExit("SIP/255-00004b7c", "") in new stack
    -- Executing [s@macro-dial-one:44] Dial("SIP/255-00004b7c", "SIP/199,60,TtrL(3600000:60000:30501)I") in new stack
       > Limit Data for this call:
       > timelimit      = 3600000 ms (3600.000 s)
       > play_warning   = 60000 ms (60.000 s)
       > play_to_caller = yes
       > play_to_callee = no
       > warning_freq   = 30501 ms (30.501 s)
       > start_sound    =
       > warning_sound  = timeleft
       > end_sound      =
  == Using SIP RTP TOS bits 184
  == Using SIP RTP CoS mark 5
       > [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,userfield) VALUES ('CHAN_START',{ts '2015-11-14 16:45:01.419429'},'199','199','','','','s','ALL','SIP/199-00004b7d','','',3,'','1447508701.23647','1447508701.23646','','','')]
Audio is at 19710
Adding codec 100012 (g722) to SDP
Adding codec 100004 (alaw) to SDP
Adding codec 100003 (ulaw) to SDP
Adding non-codec 0x1 (telephone-event) to SDP
Reliably Transmitting (NAT) to 10.255.250.250:5120:
INVITE sip:199@192.168.10.150:5120 SIP/2.0
Via: SIP/2.0/UDP 10.123.123.252:5060;branch=z9hG4bK7e13e3ef;rport
Max-Forwards: 70
From: "255" <sip:255@10.123.123.252>;tag=as7608bddd
To: <sip:199@192.168.10.150:5120>
Contact: <sip:255@10.123.123.252:5060>
Call-ID: 1f8068e629af9c45247d64f23df39d47@10.123.123.252:5060
CSeq: 102 INVITE
User-Agent: FPBX-12.0.76.2(11.19.0)
Date: Sat, 14 Nov 2015 13:45:01 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Type: application/sdp
Content-Length: 286

v=0
o=root 204067665 204067665 IN IP4 10.123.123.252
s=Asterisk PBX 11.19.0
c=IN IP4 10.123.123.252
t=0 0
m=audio 19710 RTP/AVP 9 8 0 101
a=rtpmap:9 G722/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=ptime:20
a=sendrecv

---
    -- Called SIP/199
    -- Connected line update to SIP/255-00004b7c prevented.

<--- SIP read from UDP:10.255.250.250:5120 --->
SIP/2.0 100 Trying
Via: SIP/2.0/UDP 10.123.123.252:5060;branch=z9hG4bK7e13e3ef;rport=5060
To: <sip:199@192.168.10.150>
From: "255" <sip:255@10.123.123.252>;tag=as7608bddd
Call-ID: 1f8068e629af9c45247d64f23df39d47@10.123.123.252:5060
CSeq: 102 INVITE
Content-Length: 0

<------------->
--- (7 headers 0 lines) ---

<--- SIP read from UDP:10.255.250.250:5120 --->
SIP/2.0 302 Moved Temporarily
Via: SIP/2.0/UDP 10.123.123.252:5060;branch=z9hG4bK7e13e3ef;rport=5060
To: <sip:199@192.168.10.150>;tag=842367596
From: "255" <sip:255@10.123.123.252>;tag=as7608bddd
Call-ID: 1f8068e629af9c45247d64f23df39d47@10.123.123.252:5060
CSeq: 102 INVITE
Contact: <sip:175@10.123.123.252>
Allow: INVITE,ACK,CANCEL,BYE,OPTIONS,NOTIFY,REFER,UPDATE
Diversion: "255" <sip:199@10.123.123.252>;reason=unconditional
Content-Length: 0

<------------->
--- (10 headers 0 lines) ---
    -- Got SIP response 302 "Moved Temporarily" back from 10.255.250.250:5120
RDNIS for this call is 199 (reason unconditional)
Transmitting (NAT) to 10.255.250.250:5120:
ACK sip:199@192.168.10.150:5120 SIP/2.0
Via: SIP/2.0/UDP 10.123.123.252:5060;branch=z9hG4bK7e13e3ef;rport
Max-Forwards: 70
From: "255" <sip:255@10.123.123.252>;tag=as7608bddd
To: <sip:199@192.168.10.150:5120>;tag=842367596
Contact: <sip:255@10.123.123.252:5060>
Call-ID: 1f8068e629af9c45247d64f23df39d47@10.123.123.252:5060
CSeq: 102 ACK
User-Agent: FPBX-12.0.76.2(11.19.0)
Content-Length: 0


---
    -- Now forwarding SIP/255-00004b7c to 'SIP/175::::UDP@10.123.123.252' (thanks to SIP/199-00004b7d)
  == Using SIP RTP TOS bits 184
  == Using SIP RTP CoS mark 5
[2015-11-14 16:45:01] NOTICE[1954][C-000029f0]: app_dial.c:958 do_forward: Not accepting call completion offers from call-forward recipient SIP/10.123.123.252-00004b7e
[2015-11-14 16:45:01] NOTICE[1954][C-000029f0]: app_dial.c:958 do_forward: Not accepting call completion offers from call-forward recipient SIP/10.123.123.252-00004b7e
       > [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,userfield) VALUES ('FORWARD',{ts '2015-11-14 16:45:01.647537'},'255','255','255','','199','s','macro-dial-one','SIP/255-00004b7c','Dial','SIP/199,60,TtrL(3600000:60000:30501)I',3,'','1447508701.23646','1447508701.23646','','','')]
       > [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,userfield) VALUES ('CHAN_START',{ts '2015-11-14 16:45:01.648004'},'','','','','','s','from-sip-external','SIP/10.123.123.252-00004b7e','','',3,'','1447508701.23648','1447508701.23646','','','')]
       > [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,userfield) VALUES ('HANGUP',{ts '2015-11-14 16:45:01.648339'},'199','199','199','199','','199','ALL','SIP/199-00004b7d','AppDial','(Outgoing Line)',3,'','1447508701.23647','1447508701.23646','','','')]
       > [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,userfield) VALUES ('CHAN_END',{ts '2015-11-14 16:45:01.650512'},'199','199','199','199','','199','ALL','SIP/199-00004b7d','AppDial','(Outgoing Line)',3,'','1447508701.23647','1447508701.23646','','','')]
Really destroying SIP dialog '1f8068e629af9c45247d64f23df39d47@10.123.123.252:5060' Method: INVITE
[2015-11-14 16:45:01] NOTICE[1789][C-000029f0]: chan_sip.c:23155 handle_response_invite: Failed to authenticate on INVITE to '"255" <sip:255@10.123.123.252>;tag=as254caf5f'
[2015-11-14 16:45:01] NOTICE[1789][C-000029f0]: chan_sip.c:23155 handle_response_invite: Failed to authenticate on INVITE to '"255" <sip:255@10.123.123.252>;tag=as254caf5f'
    -- SIP/10.123.123.252-00004b7e is circuit-busy
  == Everyone is busy/congested at this time (1:0/1/0)
    -- Executing [s@macro-dial-one:45] ExecIf("SIP/255-00004b7c", "0?MacroExit()") in new stack
    -- Executing [s@macro-dial-one:46] ExecIf("SIP/255-00004b7c", "0?Set(DIALSTATUS=)") in new stack
    -- Executing [s@macro-dial-one:47] GosubIf("SIP/255-00004b7c", "0?s-CONGESTION,1()") in new stack
       > [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,userfield) VALUES ('HANGUP',{ts '2015-11-14 16:45:01.652235'},'','199','','199','','','from-sip-external','SIP/10.123.123.252-00004b7e','AppDial','(Outgoing Line)',3,'','1447508701.23648','1447508701.23646','','','')]
       > [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,userfield) VALUES ('CHAN_END',{ts '2015-11-14 16:45:01.652294'},'','199','','199','','','from-sip-external','SIP/10.123.123.252-00004b7e','AppDial','(Outgoing Line)',3,'','1447508701.23648','1447508701.23646','','','')]
    -- Executing [s@macro-dial-one:48] MacroExit("SIP/255-00004b7c", "") in new stack
    -- Executing [s@macro-exten-vm:8] Set("SIP/255-00004b7c", "SV_DIALSTATUS=CONGESTION") in new stack
    -- Executing [s@macro-exten-vm:9] GosubIf("SIP/255-00004b7c", "0?docfu,1()") in new stack
    -- Executing [s@macro-exten-vm:10] GosubIf("SIP/255-00004b7c", "0?docfb,1()") in new stack
    -- Executing [s@macro-exten-vm:11] Set("SIP/255-00004b7c", "DIALSTATUS=CONGESTION") in new stack
    -- Executing [s@macro-exten-vm:12] ExecIf("SIP/255-00004b7c", "0?MacroExit()") in new stack
    -- Executing [s@macro-exten-vm:13] GotoIf("SIP/255-00004b7c", "1?s-CONGESTION,1") in new stack
    -- Goto (macro-exten-vm,s-CONGESTION,1)
    -- Executing [s-CONGESTION@macro-exten-vm:1] GotoIf("SIP/255-00004b7c", "0?exit,1") in new stack
    -- Executing [s-CONGESTION@macro-exten-vm:2] PlayTones("SIP/255-00004b7c", "congestion") in new stack
[2015-11-14 16:45:01] WARNING[1954][C-000029f0]: translate.c:340 framein: no samples for g722tolin16
[2015-11-14 16:45:01] WARNING[1954][C-000029f0]: translate.c:340 framein: no samples for g722tolin16
    -- Executing [s-CONGESTION@macro-exten-vm:3] Congestion("SIP/255-00004b7c", "10") in new stack
  == Spawn extension (macro-exten-vm, s-CONGESTION, 3) exited non-zero on 'SIP/255-00004b7c' in macro 'exten-vm'
  == Spawn extension (ALL, 199, 2) exited non-zero on 'SIP/255-00004b7c'
    -- Executing [h@ALL:1] Macro("SIP/255-00004b7c", "hangupcall,") in new stack
    -- Executing [s@macro-hangupcall:1] ExecIf("SIP/255-00004b7c", "0?Set(CDR(recordingfile)=internal-199-255-20151114-164501-1447508701.23646.wav)") in new stack
    -- Executing [s@macro-hangupcall:2] GotoIf("SIP/255-00004b7c", "1?theend") in new stack
    -- Goto (macro-hangupcall,s,4)
    -- Executing [s@macro-hangupcall:4] Hangup("SIP/255-00004b7c", "") in new stack
  == Spawn extension (macro-hangupcall, s, 4) exited non-zero on 'SIP/255-00004b7c' in macro 'hangupcall'
  == Spawn extension (ALL, h, 1) exited non-zero on 'SIP/255-00004b7c'
       > [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,userfield) VALUES ('HANGUP',{ts '2015-11-14 16:45:01.664153'},'255','255','255','','199','h','ALL','SIP/255-00004b7c','','',3,'','1447508701.23646','1447508701.23646','','','')]
  == MixMonitor close filestream (mixed)
       > [INSERT INTO cdr (calldate,clid,src,dst,dcontext,channel,dstchannel,lastapp,lastdata,duration,billsec,disposition,amaflags,uniqueid,recordingfile,cnum,cnam) VALUES ({ ts '2015-11-14 16:45:01' },'"255" <255>','255','199','ALL','SIP/255-00004b7c','SIP/199-00004b7d','Congestion','10',0,0,'FAILED',3,'1447508701.23646','internal-199-255-20151114-164501-1447508701.23646.wav','255','255')]
       > [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,userfield) VALUES ('CHAN_END',{ts '2015-11-14 16:45:01.666794'},'255','255','255','','199','h','ALL','SIP/255-00004b7c','','',3,'','1447508701.23646','1447508701.23646','','','')]
       > [INSERT INTO cel (eventtype,eventtime,cid_name,cid_num,cid_ani,cid_rdnis,cid_dnid,exten,context,channame,appname,appdata,amaflags,accountcode,uniqueid,linkedid,peer,userdeftype,userfield) VALUES ('LINKEDID_END',{ts '2015-11-14 16:45:01.667235'},'255','255','255','','199','h','ALL','SIP/255-00004b7c','','',3,'','1447508701.23646','1447508701.23646','','','')]
  == End MixMonitor Recording SIP/255-00004b7c
sip*CLI>
sip*CLI>
sip*CLI>