Проблемы с входящими звонками Asterisk freePBX

[ded]

Кому актуально, но не в силах - в раздел Бизнес.
А вот это

username=bKF2CLjetYw
secret=5yvREE86bc91wF4N

на грани окончания карьеры VoIP телефониста.

[awsswa]

лучше починить быстрее, а то у меня есть тут картинка из телеграмма
с печальными последствиями
года товарищ и сам не смог и другим не дал чинить - и уволили его к черту

[zzuz]

Где там моя тетя в Уругвае ? Есть теперь возможность бесплатно делать вызовы по ночам . Делаем ставки, как быстро тетя из Уругвая получит мильон вызовов от поклонников.

[djem]

А теперь заходишь в Asterisk CLI:

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

sudo asterisk -rvvvT

и делаешь входящий вызов - что выдаст - копируй и покажи нам (лучше спрячь под [spoiler]), не жадничай. там же и будет видна ошибка.

PRIME_BBCODE_SPOILER_SHOW PRIME_BBCODE_SPOILER:

[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_request: attendedtransfer-rec-restart.php
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_channel: SIP/MTS-000003fd
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_language: ru
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_type: SIP
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_uniqueid: 1548939956.1021
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_version: 15.5.0
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_callerid: 9181007773
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_calleridname: unknown
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_callingpres: 0
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_callingani2: 0
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_callington: 0
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_callingtns: 0
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_dnid: 9181007773
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_rdnis: unknown
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_context: macro-hangupcall
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_extension: s
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_priority: 5
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_enhanced: 0.0
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_accountcode:
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_threadid: 140357227575040
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_arg_1:
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_arg_2: /var/spool/asterisk/monitor/2019/01/31/in-9181007773-9882401000-20190131-160556-1548939956.1021.wav
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >>
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Script attendedtransfer-rec-restart.php completed, returning 0
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] pbx.c: Executing [s@macro-hangupcall:6] Hangup("SIP/MTS-000003fd", "") in new stack
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] app_macro.c: Spawn extension (macro-hangupcall, s, 6) exited non-zero on 'SIP/MTS-000003fd' in macro 'hangupcall'
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/MTS-000003fd'
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] app_stack.c: SIP/MTS-000003fd Internal Gosub(crm-hangup,s,1) start
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/MTS-000003fd", "Sending Hangup to CRM") in new stack
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/MTS-000003fd", "HANGUP CAUSE: 21") in new stack
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/MTS-000003fd", "0?Set(__CRM_VOICEMAIL=)") in new stack
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/MTS-000003fd", "MASTER CHANNEL: 1548939956.1021 = 1548939956.1021") in new stack
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/MTS-000003fd", "0?return") in new stack
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] pbx.c: Executing [s@crm-hangup:6] Set("SIP/MTS-000003fd", "__CRM_HANGUP=1") in new stack
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] pbx.c: Executing [s@crm-hangup:7] AGI("SIP/MTS-000003fd", "sangomacrm.agi") in new stack
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_request: sangomacrm.agi
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_channel: SIP/MTS-000003fd
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_language: ru
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_type: SIP
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_uniqueid: 1548939956.1021
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_version: 15.5.0
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_callerid: 9181007773
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_calleridname: unknown
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_callingpres: 0
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_callingani2: 0
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_callington: 0
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_callingtns: 0
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_dnid: 9181007773
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_rdnis: unknown
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_context: crm-hangup
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_extension: s
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_priority: 7
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_enhanced: 0.0
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_accountcode:
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >> agi_threadid: 140357227575040
[2019-01-31 16:06:23] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Tx >>
[2019-01-31 16:06:24] VERBOSE[25689][C-00000025] res_agi.c: <SIP/MTS-000003fd>AGI Script sangomacrm.agi completed, returning 0
[2019-01-31 16:06:24] VERBOSE[25689][C-00000025] pbx.c: Executing [s@crm-hangup:8] Return("SIP/MTS-000003fd", "") in new stack
[2019-01-31 16:06:24] VERBOSE[25689][C-00000025] app_stack.c: Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/MTS-000003fd'
[2019-01-31 16:06:24] VERBOSE[25689][C-00000025] app_stack.c: SIP/MTS-000003fd Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2019-01-31 16:06:24] VERBOSE[25691][C-00000025] app_mixmonitor.c: MixMonitor close filestream (mixed)
[2019-01-31 16:06:24] VERBOSE[25691][C-00000025] app_mixmonitor.c: End MixMonitor Recording SIP/MTS-000003fd
[2019-01-31 16:07:26] VERBOSE[3237][C-00000026] netsock2.c: Using SIP RTP TOS bits 184
[2019-01-31 16:07:26] VERBOSE[3237][C-00000026] netsock2.c: Using SIP RTP CoS mark 5
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [9181007773@from-trunk:1] Set("SIP/MTS-000003ff", "__DIRECTION=INBOUND") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [9181007773@from-trunk:2] Gosub("SIP/MTS-000003ff", "sub-record-check,s,1(in,9181007773,yes)") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/MTS-000003ff", "0?initialized") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [s@sub-record-check:2] Set("SIP/MTS-000003ff", "__REC_STATUS=INITIALIZED") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [s@sub-record-check:3] Set("SIP/MTS-000003ff", "NOW=1548940046") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [s@sub-record-check:4] Set("SIP/MTS-000003ff", "__DAY=31") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [s@sub-record-check:5] Set("SIP/MTS-000003ff", "__MONTH=01") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [s@sub-record-check:6] Set("SIP/MTS-000003ff", "__YEAR=2019") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [s@sub-record-check:7] Set("SIP/MTS-000003ff", "__TIMESTR=20190131-160726") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [s@sub-record-check:8] Set("SIP/MTS-000003ff", "__FROMEXTEN=unknown") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [s@sub-record-check:9] Set("SIP/MTS-000003ff", "__MON_FMT=wav") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/MTS-000003ff", "Recordings initialized") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/MTS-000003ff", "0?Set(ARG3=dontcare)") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [s@sub-record-check:12] Set("SIP/MTS-000003ff", "REC_POLICY_MODE_SAVE=") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/MTS-000003ff", "0?Set(REC_STATUS=NO)") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/MTS-000003ff", "2?checkaction") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx_builtins.c: Goto (sub-record-check,s,17)
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/MTS-000003ff", "1?sub-record-check,in,1") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx_builtins.c: Goto (sub-record-check,in,1)
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [in@sub-record-check:1] NoOp("SIP/MTS-000003ff", "Inbound Recording Check to 9181007773") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [in@sub-record-check:2] Set("SIP/MTS-000003ff", "FROMEXTEN=unknown") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [in@sub-record-check:3] ExecIf("SIP/MTS-000003ff", "10?Set(FROMEXTEN=9881875404)") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [in@sub-record-check:4] Gosub("SIP/MTS-000003ff", "recordcheck,1(yes,in,9181007773)") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/MTS-000003ff", "Starting recording check against yes") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/MTS-000003ff", "yes") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx_builtins.c: Goto (sub-record-check,recordcheck,9)
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [recordcheck@sub-record-check:9] ExecIf("SIP/MTS-000003ff", "0?Return()") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [recordcheck@sub-record-check:10] Set("SIP/MTS-000003ff", "__REC_POLICY_MODE=YES") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [recordcheck@sub-record-check:11] Goto("SIP/MTS-000003ff", "startrec") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx_builtins.c: Goto (sub-record-check,recordcheck,16)
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [recordcheck@sub-record-check:16] NoOp("SIP/MTS-000003ff", "Starting recording: in, 9181007773") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [recordcheck@sub-record-check:17] Set("SIP/MTS-000003ff", "__CALLFILENAME=in-9181007773-9881875404-20190131-160726-1548940046.1023") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [recordcheck@sub-record-check:18] MixMonitor("SIP/MTS-000003ff", "2019/01/31/in-9181007773-9881875404-20190131-160726-1548940046.1023.wav,abi(LOCAL_MIXMON_ID),") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [recordcheck@sub-record-check:19] Set("SIP/MTS-000003ff", "__MIXMON_ID=0x7fa790025130") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [recordcheck@sub-record-check:20] Set("SIP/MTS-000003ff", "__RECORD_ID=SIP/MTS-000003ff") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [recordcheck@sub-record-check:21] Set("SIP/MTS-000003ff", "__REC_STATUS=RECORDING") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [recordcheck@sub-record-check:22] Set("SIP/MTS-000003ff", "CDR(recordingfile)=in-9181007773-9881875404-20190131-160726-1548940046.1023.wav") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [recordcheck@sub-record-check:23] Return("SIP/MTS-000003ff", "") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [in@sub-record-check:5] Return("SIP/MTS-000003ff", "") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [9181007773@from-trunk:3] Set("SIP/MTS-000003ff", "CHANNEL(tonezone)=us") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [9181007773@from-trunk:4] Set("SIP/MTS-000003ff", "__FROM_DID=9181007773") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [9181007773@from-trunk:5] Set("SIP/MTS-000003ff", "returnhere=1") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [9181007773@from-trunk:6] Gosub("SIP/MTS-000003ff", "app-blacklist-check,s,1()") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [s@app-blacklist-check:1] GotoIf("SIP/MTS-000003ff", "0?blacklisted") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [s@app-blacklist-check:2] Set("SIP/MTS-000003ff", "CALLED_BLACKLIST=1") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [s@app-blacklist-check:3] Return("SIP/MTS-000003ff", "") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [9181007773@from-trunk:7] Set("SIP/MTS-000003ff", "CDR(did)=9181007773") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [9181007773@from-trunk:8] GotoIf("SIP/MTS-000003ff", "0?") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [9181007773@from-trunk:9] ExecIf("SIP/MTS-000003ff", "1 ?Set(CALLERID(name)=9881875404)") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [9181007773@from-trunk:10] Set("SIP/MTS-000003ff", "__MOHCLASS=") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [9181007773@from-trunk:11] Ringing("SIP/MTS-000003ff", "") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [9181007773@from-trunk:12] Set("SIP/MTS-000003ff", "__RINGINGSENT=TRUE") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [9181007773@from-trunk:13] Set("SIP/MTS-000003ff", "__REVERSAL_REJECT=FALSE") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [9181007773@from-trunk:14] GotoIf("SIP/MTS-000003ff", "1?post-reverse-charge") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx_builtins.c: Goto (from-trunk,9181007773,16)
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [9181007773@from-trunk:16] NoOp("SIP/MTS-000003ff", "") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [9181007773@from-trunk:17] Set("SIP/MTS-000003ff", "__CALLINGNAMEPRES_SV=allowed_not_screened") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [9181007773@from-trunk:18] Set("SIP/MTS-000003ff", "__CALLINGNUMPRES_SV=allowed_not_screened") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [9181007773@from-trunk:19] Set("SIP/MTS-000003ff", "CALLERID(name-pres)=allowed_not_screened") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [9181007773@from-trunk:20] Set("SIP/MTS-000003ff", "CALLERID(num-pres)=allowed_not_screened") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [9181007773@from-trunk:21] NoOp("SIP/MTS-000003ff", "CallerID Entry Point") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [9181007773@from-trunk:22] Set("SIP/MTS-000003ff", "__CRM_DIRECTION=INBOUND") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [9181007773@from-trunk:23] Set("SIP/MTS-000003ff", "__CRM_SOURCE=9881875404") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [9181007773@from-trunk:24] Set("SIP/MTS-000003ff", "__CRM_LINKEDID=1548940046.1023") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [9181007773@from-trunk:25] ExecIf("SIP/MTS-000003ff", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [9181007773@from-trunk:26] Goto("SIP/MTS-000003ff", "ivr-1,s,1") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx_builtins.c: Goto (ivr-1,s,1)
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [s@ivr-1:1] Set("SIP/MTS-000003ff", "INVALID_LOOPCOUNT=0") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [s@ivr-1:2] Set("SIP/MTS-000003ff", "_IVR_CONTEXT_ivr-1=") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [s@ivr-1:3] Set("SIP/MTS-000003ff", "_IVR_CONTEXT=ivr-1") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [s@ivr-1:4] Set("SIP/MTS-000003ff", "__IVR_RETVM=") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [s@ivr-1:5] GotoIf("SIP/MTS-000003ff", "0?skip") in new stack
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [s@ivr-1:6] Answer("SIP/MTS-000003ff", "") in new stack
[2019-01-31 16:07:26] VERBOSE[26445][C-00000026] app_mixmonitor.c: Begin MixMonitor Recording SIP/MTS-000003ff
[2019-01-31 16:07:26] VERBOSE[26443][C-00000026] pbx.c: Executing [s@ivr-1:7] Wait("SIP/MTS-000003ff", "1") in new stack
[2019-01-31 16:07:27] VERBOSE[26443][C-00000026] pbx.c: Executing [s@ivr-1:8] Set("SIP/MTS-000003ff", "IVR_MSG=custom/-_-") in new stack
[2019-01-31 16:07:27] VERBOSE[26443][C-00000026] pbx.c: Executing [s@ivr-1:9] Set("SIP/MTS-000003ff", "TIMEOUT(digit)=3") in new stack
[2019-01-31 16:07:27] VERBOSE[26443][C-00000026] func_timeout.c: Digit timeout set to 3.000
[2019-01-31 16:07:27] VERBOSE[26443][C-00000026] pbx.c: Executing [s@ivr-1:10] ExecIf("SIP/MTS-000003ff", "1?Background(custom/-_-)") in new stack
[2019-01-31 16:07:27] VERBOSE[26443][C-00000026] file.c: <SIP/MTS-000003ff> Playing 'custom/-_-.slin' (language 'ru')
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [1@ivr-1:1] Set("SIP/MTS-000003ff", "__ivrreturn=0") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [1@ivr-1:2] AGI("SIP/MTS-000003ff", "sms_rec.php") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sms_rec.php
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_request: sms_rec.php
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_channel: SIP/MTS-000003ff
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_language: ru
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_type: SIP
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_uniqueid: 1548940046.1023
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_version: 15.5.0
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_callerid: 9881875404
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_calleridname: 9881875404
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_callingpres: 0
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_callingani2: 0
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_callington: 0
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_callingtns: 0
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_dnid: 9181007773
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_rdnis: unknown
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_context: ivr-1
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_extension: 1
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_priority: 2
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_enhanced: 0.0
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_accountcode:
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_threadid: 140357227575040
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >>
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Script sms_rec.php completed, returning 0
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [1@ivr-1:3] Goto("SIP/MTS-000003ff", "from-internal,89282343335,1") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx_builtins.c: Goto (from-internal,89282343335,1)
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [89282343335@from-internal:1] Macro("SIP/MTS-000003ff", "user-callerid,LIMIT,EXTERNAL,") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-user-callerid:1] Set("SIP/MTS-000003ff", "TOUCH_MONITOR=1548940046.1023") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-user-callerid:2] Set("SIP/MTS-000003ff", "AMPUSER=9881875404") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-user-callerid:3] GotoIf("SIP/MTS-000003ff", "0?report") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-user-callerid:4] ExecIf("SIP/MTS-000003ff", "1?Set(REALCALLERIDNUM=9881875404)") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-user-callerid:5] Set("SIP/MTS-000003ff", "AMPUSER=") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-user-callerid:6] GotoIf("SIP/MTS-000003ff", "0?limit") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-user-callerid:7] Set("SIP/MTS-000003ff", "AMPUSERCIDNAME=") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-user-callerid:8] ExecIf("SIP/MTS-000003ff", "0?Set(__CIDMASQUERADING=TRUE)") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-user-callerid:9] GotoIf("SIP/MTS-000003ff", "1?report") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx_builtins.c: Goto (macro-user-callerid,s,16)
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-user-callerid:16] NoOp("SIP/MTS-000003ff", "Macro Depth is 1") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-user-callerid:17] GotoIf("SIP/MTS-000003ff", "1?report2:macroerror") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx_builtins.c: Goto (macro-user-callerid,s,18)
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-user-callerid:18] GotoIf("SIP/MTS-000003ff", "1?continue") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx_builtins.c: Goto (macro-user-callerid,s,37)
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-user-callerid:37] Set("SIP/MTS-000003ff", "CALLERID(number)=9881875404") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-user-callerid:38] Set("SIP/MTS-000003ff", "CALLERID(name)=9881875404") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-user-callerid:39] GotoIf("SIP/MTS-000003ff", "0?cnum") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-user-callerid:40] Set("SIP/MTS-000003ff", "CDR(cnam)=9881875404") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-user-callerid:41] Set("SIP/MTS-000003ff", "CDR(cnum)=9881875404") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-user-callerid:42] Set("SIP/MTS-000003ff", "CHANNEL(language)=ru") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [89282343335@from-internal:2] Gosub("SIP/MTS-000003ff", "sub-record-check,s,1(out,89282343335,yes)") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@sub-record-check:1] GotoIf("SIP/MTS-000003ff", "10?initialized") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx_builtins.c: Goto (sub-record-check,s,10)
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@sub-record-check:10] NoOp("SIP/MTS-000003ff", "Recordings initialized") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@sub-record-check:11] ExecIf("SIP/MTS-000003ff", "0?Set(ARG3=dontcare)") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@sub-record-check:12] Set("SIP/MTS-000003ff", "REC_POLICY_MODE_SAVE=YES") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@sub-record-check:13] ExecIf("SIP/MTS-000003ff", "0?Set(REC_STATUS=NO)") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@sub-record-check:14] GotoIf("SIP/MTS-000003ff", "3?checkaction") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx_builtins.c: Goto (sub-record-check,s,17)
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@sub-record-check:17] GotoIf("SIP/MTS-000003ff", "1?sub-record-check,out,1") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx_builtins.c: Goto (sub-record-check,out,1)
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [out@sub-record-check:1] NoOp("SIP/MTS-000003ff", "Outbound Recording Check from 9881875404 to 89282343335") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [out@sub-record-check:2] Set("SIP/MTS-000003ff", "RECMODE=") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [out@sub-record-check:3] ExecIf("SIP/MTS-000003ff", "1?Goto(routewins)") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx_builtins.c: Goto (sub-record-check,out,7)
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [out@sub-record-check:7] Gosub("SIP/MTS-000003ff", "recordcheck,1(yes,out,89282343335)") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [recordcheck@sub-record-check:1] NoOp("SIP/MTS-000003ff", "Starting recording check against yes") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [recordcheck@sub-record-check:2] Goto("SIP/MTS-000003ff", "yes") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx_builtins.c: Goto (sub-record-check,recordcheck,9)
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [recordcheck@sub-record-check:9] ExecIf("SIP/MTS-000003ff", "1?Return()") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [out@sub-record-check:8] Return("SIP/MTS-000003ff", "") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [89282343335@from-internal:3] ExecIf("SIP/MTS-000003ff", "0 ?Set(CDR(accountcode)=)") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [89282343335@from-internal:4] Set("SIP/MTS-000003ff", "MOHCLASS=default") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [89282343335@from-internal:5] ExecIf("SIP/MTS-000003ff", "1?Set(TRUNKCIDOVERRIDE=9181007773)") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [89282343335@from-internal:6] Set("SIP/MTS-000003ff", "_NODEST=") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [89282343335@from-internal:7] Macro("SIP/MTS-000003ff", "dialout-trunk,2,89282343335,,off") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-dialout-trunk:1] Set("SIP/MTS-000003ff", "DIAL_TRUNK=2") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-dialout-trunk:2] ExecIf("SIP/MTS-000003ff", "1?Set(DIAL_OPTIONS=Hhtr)") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-dialout-trunk:3] GosubIf("SIP/MTS-000003ff", "0?sub-pincheck,s,1()") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-dialout-trunk:4] ExecIf("SIP/MTS-000003ff", "0?Set(CALLERID(num)=)") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-dialout-trunk:5] GotoIf("SIP/MTS-000003ff", "0?disabletrunk,1") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-dialout-trunk:6] Set("SIP/MTS-000003ff", "DIAL_NUMBER=89282343335") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-dialout-trunk:7] Set("SIP/MTS-000003ff", "DIAL_TRUNK_OPTIONS=Hhtr") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-dialout-trunk:8] Set("SIP/MTS-000003ff", "OUTBOUND_GROUP=OUT_2") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-dialout-trunk:9] Set("SIP/MTS-000003ff", "DIAL_TRUNK_OPTIONS=t") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-dialout-trunk:10] GotoIf("SIP/MTS-000003ff", "1?nomax") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx_builtins.c: Goto (macro-dialout-trunk,s,12)
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-dialout-trunk:12] GotoIf("SIP/MTS-000003ff", "0?skipoutcid") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-dialout-trunk:13] Macro("SIP/MTS-000003ff", "outbound-callerid,2") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-outbound-callerid:1] NoOp("SIP/MTS-000003ff", "9881875404") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-outbound-callerid:2] NoOp("SIP/MTS-000003ff", "") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-outbound-callerid:3] NoOp("SIP/MTS-000003ff", "off") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-outbound-callerid:4] ExecIf("SIP/MTS-000003ff", "1?Set(CALLERPRES(name-pres)=allowed_not_screened)") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-outbound-callerid:5] ExecIf("SIP/MTS-000003ff", "1?Set(CALLERPRES(num-pres)=allowed_not_screened)") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-outbound-callerid:6] ExecIf("SIP/MTS-000003ff", "0?Set(REALCALLERIDNUM=9881875404)") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-outbound-callerid:7] ExecIf("SIP/MTS-000003ff", "0?Set(AMPUSER=9881875404)") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-outbound-callerid:8] GotoIf("SIP/MTS-000003ff", "1?normcid") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx_builtins.c: Goto (macro-outbound-callerid,s,12)
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-outbound-callerid:12] Set("SIP/MTS-000003ff", "USEROUTCID=") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-outbound-callerid:13] Set("SIP/MTS-000003ff", "EMERGENCYCID=") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-outbound-callerid:14] Set("SIP/MTS-000003ff", "TRUNKOUTCID=9181007773") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-outbound-callerid:15] GotoIf("SIP/MTS-000003ff", "1?trunkcid") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx_builtins.c: Goto (macro-outbound-callerid,s,20)
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-outbound-callerid:20] ExecIf("SIP/MTS-000003ff", "1?Set(CALLERID(all)=9181007773)") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-outbound-callerid:21] ExecIf("SIP/MTS-000003ff", "0?Set(CALLERID(all)=)") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-outbound-callerid:22] ExecIf("SIP/MTS-000003ff", "1?Set(CALLERID(all)=9181007773)") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-outbound-callerid:23] ExecIf("SIP/MTS-000003ff", "0?Set(CALLERPRES(name-pres)=prohib_passed_screen)") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-outbound-callerid:24] ExecIf("SIP/MTS-000003ff", "0?Set(CALLERPRES(num-pres)=prohib_passed_screen)") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-outbound-callerid:25] Set("SIP/MTS-000003ff", "CDR(outbound_cnum)=9181007773") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-outbound-callerid:26] Set("SIP/MTS-000003ff", "CDR(outbound_cnam)=") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-dialout-trunk:14] GosubIf("SIP/MTS-000003ff", "0?sub-flp-2,s,1()") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-dialout-trunk:15] Set("SIP/MTS-000003ff", "OUTNUM=89282343335") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-dialout-trunk:16] Set("SIP/MTS-000003ff", "custom=SIP/MTS") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-dialout-trunk:17] ExecIf("SIP/MTS-000003ff", "0?Set(DIAL_TRUNK_OPTIONS=M(setmusic^default)t)") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-dialout-trunk:18] ExecIf("SIP/MTS-000003ff", "0?Set(DIAL_TRUNK_OPTIONS=tM(confirm))") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-dialout-trunk:19] Macro("SIP/MTS-000003ff", "dialout-trunk-predial-hook,") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-dialout-trunk-predial-hook:1] MacroExit("SIP/MTS-000003ff", "") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-dialout-trunk:20] GotoIf("SIP/MTS-000003ff", "1?skipcrm") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx_builtins.c: Goto (macro-dialout-trunk,s,26)
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-dialout-trunk:26] NoOp("SIP/MTS-000003ff", "CRM Finished") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-dialout-trunk:27] GotoIf("SIP/MTS-000003ff", "0?bypass,1") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-dialout-trunk:28] ExecIf("SIP/MTS-000003ff", "0?Set(CONNECTEDLINE(num,i)=89282343335)") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-dialout-trunk:29] ExecIf("SIP/MTS-000003ff", "0?Set(CONNECTEDLINE(name,i)=CID:9181007773)") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-dialout-trunk:30] ExecIf("SIP/MTS-000003ff", "0?Set(CONNECTEDLINE(name,i)=CID:(Hidden)9181007773)") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-dialout-trunk:31] GotoIf("SIP/MTS-000003ff", "0?customtrunk") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-dialout-trunk:32] Dial("SIP/MTS-000003ff", "SIP/MTS/89282343335,300,tb(func-apply-sipheaders^s^1,(2))") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] netsock2.c: Using SIP RTP TOS bits 184
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] netsock2.c: Using SIP RTP CoS mark 5
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] app_stack.c: SIP/MTS-00000400 Internal Gosub(func-apply-sipheaders,s,1(2)) start
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@func-apply-sipheaders:1] ExecIf("SIP/MTS-00000400", "1?Set(CHANNEL(hangup_handler_push)=crm-hangup,s,1)") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@func-apply-sipheaders:2] NoOp("SIP/MTS-00000400", "Applying SIP Headers to channel 2") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@func-apply-sipheaders:3] Set("SIP/MTS-00000400", "SIPHEADERKEYS=") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@func-apply-sipheaders:4] ExecIf("SIP/MTS-00000400", "0?Set(Rheader=1)") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@func-apply-sipheaders:5] While("SIP/MTS-00000400", "0") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] app_while.c: Jumping to priority 9
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@func-apply-sipheaders:10] ExecIf("SIP/MTS-00000400", "0?SIPRemoveHeader(Alert-Info:)") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@func-apply-sipheaders:11] ExecIf("SIP/MTS-00000400", "0?Set(PJSIP_HEADER(remove,Alert-Info)=)") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@func-apply-sipheaders:12] Return("SIP/MTS-00000400", "") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] app_stack.c: Spawn extension (from-trunk, 89282343335, 1) exited non-zero on 'SIP/MTS-00000400'
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] app_stack.c: SIP/MTS-00000400 Internal Gosub(func-apply-sipheaders,s,1(2)) complete GOSUB_RETVAL=
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] app_dial.c: Called SIP/MTS/89282343335
[2019-01-31 16:07:33] WARNING[3237][C-00000026] chan_sip.c: Received response: "Forbidden" from '<sip:9181007773@109.235.190.34:5160>;tag=as41037952'
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] app_stack.c: SIP/MTS-00000400 Internal Gosub(crm-hangup,s,1) start
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/MTS-00000400", "Sending Hangup to CRM") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/MTS-00000400", "HANGUP CAUSE: 21") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/MTS-00000400", "0?Set(__CRM_VOICEMAIL=)") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/MTS-00000400", "MASTER CHANNEL: 1548940053.1024 = 1548940046.1023") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/MTS-00000400", "1?return") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx_builtins.c: Goto (crm-hangup,s,8)
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@crm-hangup:8] Return("SIP/MTS-00000400", "") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] app_stack.c: Spawn extension (from-trunk, 89282343335, 1) exited non-zero on 'SIP/MTS-00000400'
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] app_stack.c: SIP/MTS-00000400 Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] app_dial.c: Everyone is busy/congested at this time (1:0/0/1)
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-dialout-trunk:33] NoOp("SIP/MTS-000003ff", "Dial failed for some reason with DIALSTATUS = CHANUNAVAIL and HANGUPCAUSE = 21") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-dialout-trunk:34] GotoIf("SIP/MTS-000003ff", "0?continue,1:s-CHANUNAVAIL,1") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx_builtins.c: Goto (macro-dialout-trunk,s-CHANUNAVAIL,1)
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s-CHANUNAVAIL@macro-dialout-trunk:1] Set("SIP/MTS-000003ff", "RC=21") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s-CHANUNAVAIL@macro-dialout-trunk:2] Goto("SIP/MTS-000003ff", "21,1") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx_builtins.c: Goto (macro-dialout-trunk,21,1)
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [21@macro-dialout-trunk:1] Goto("SIP/MTS-000003ff", "continue,1") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx_builtins.c: Goto (macro-dialout-trunk,continue,1)
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [continue@macro-dialout-trunk:1] NoOp("SIP/MTS-000003ff", "TRUNK Dial failed due to CHANUNAVAIL HANGUPCAUSE: 21 - failing through to other trunks") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [continue@macro-dialout-trunk:2] ExecIf("SIP/MTS-000003ff", "0?Set(CALLERID(number)=)") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [89282343335@from-internal:8] Macro("SIP/MTS-000003ff", "outisbusy,") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-outisbusy:1] Progress("SIP/MTS-000003ff", "") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-outisbusy:2] GotoIf("SIP/MTS-000003ff", "0?emergency,1") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-outisbusy:3] GotoIf("SIP/MTS-000003ff", "0?intracompany,1") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-outisbusy:4] Playback("SIP/MTS-000003ff", "all-circuits-busy-now&please-try-call-later, noanswer") in new stack
[2019-01-31 16:07:33] VERBOSE[26443][C-00000026] file.c: <SIP/MTS-000003ff> Playing 'all-circuits-busy-now.ulaw' (language 'ru')
[2019-01-31 16:07:36] VERBOSE[26443][C-00000026] file.c: <SIP/MTS-000003ff> Playing 'please-try-call-later.alaw' (language 'ru')
[2019-01-31 16:07:38] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-outisbusy:5] Congestion("SIP/MTS-000003ff", "20") in new stack
[2019-01-31 16:07:38] VERBOSE[26443][C-00000026] app_macro.c: Spawn extension (macro-outisbusy, s, 5) exited non-zero on 'SIP/MTS-000003ff' in macro 'outisbusy'
[2019-01-31 16:07:38] VERBOSE[26443][C-00000026] pbx.c: Spawn extension (from-internal, 89282343335, 8) exited non-zero on 'SIP/MTS-000003ff'
[2019-01-31 16:07:38] VERBOSE[26443][C-00000026] pbx.c: Executing [h@from-internal:1] Macro("SIP/MTS-000003ff", "hangupcall") in new stack
[2019-01-31 16:07:38] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-hangupcall:1] GotoIf("SIP/MTS-000003ff", "1?theend") in new stack
[2019-01-31 16:07:38] VERBOSE[26443][C-00000026] pbx_builtins.c: Goto (macro-hangupcall,s,3)
[2019-01-31 16:07:38] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-hangupcall:3] ExecIf("SIP/MTS-000003ff", "0?Set(CDR(recordingfile)=)") in new stack
[2019-01-31 16:07:38] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-hangupcall:4] NoOp("SIP/MTS-000003ff", " monior file= /var/spool/asterisk/monitor/2019/01/31/in-9181007773-9881875404-20190131-160726-1548940046.1023.wav") in new stack
[2019-01-31 16:07:38] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-hangupcall:5] AGI("SIP/MTS-000003ff", "attendedtransfer-rec-restart.php,,/var/spool/asterisk/monitor/2019/01/31/in-9181007773-9881875404-20190131-160726-1548940046.1023.wav") in new stack
[2019-01-31 16:07:38] VERBOSE[26443][C-00000026] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/attendedtransfer-rec-restart.php
[2019-01-31 16:07:38] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_request: attendedtransfer-rec-restart.php
[2019-01-31 16:07:38] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_channel: SIP/MTS-000003ff
[2019-01-31 16:07:38] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_language: ru
[2019-01-31 16:07:38] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_type: SIP
[2019-01-31 16:07:38] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_uniqueid: 1548940046.1023
[2019-01-31 16:07:38] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_version: 15.5.0
[2019-01-31 16:07:38] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_callerid: 9181007773
[2019-01-31 16:07:38] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_calleridname: unknown
[2019-01-31 16:07:38] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_callingpres: 0
[2019-01-31 16:07:38] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_callingani2: 0
[2019-01-31 16:07:38] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_callington: 0
[2019-01-31 16:07:38] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_callingtns: 0
[2019-01-31 16:07:38] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_dnid: 9181007773
[2019-01-31 16:07:38] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_rdnis: unknown
[2019-01-31 16:07:38] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_context: macro-hangupcall
[2019-01-31 16:07:38] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_extension: s
[2019-01-31 16:07:38] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_priority: 5
[2019-01-31 16:07:38] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_enhanced: 0.0
[2019-01-31 16:07:38] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_accountcode:
[2019-01-31 16:07:38] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_threadid: 140357227575040
[2019-01-31 16:07:38] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_arg_1:
[2019-01-31 16:07:38] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_arg_2: /var/spool/asterisk/monitor/2019/01/31/in-9181007773-9881875404-20190131-160726-1548940046.1023.wav
[2019-01-31 16:07:38] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >>
[2019-01-31 16:07:39] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Script attendedtransfer-rec-restart.php completed, returning 0
[2019-01-31 16:07:39] VERBOSE[26443][C-00000026] pbx.c: Executing [s@macro-hangupcall:6] Hangup("SIP/MTS-000003ff", "") in new stack
[2019-01-31 16:07:39] VERBOSE[26443][C-00000026] app_macro.c: Spawn extension (macro-hangupcall, s, 6) exited non-zero on 'SIP/MTS-000003ff' in macro 'hangupcall'
[2019-01-31 16:07:39] VERBOSE[26443][C-00000026] pbx.c: Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/MTS-000003ff'
[2019-01-31 16:07:39] VERBOSE[26443][C-00000026] app_stack.c: SIP/MTS-000003ff Internal Gosub(crm-hangup,s,1) start
[2019-01-31 16:07:39] VERBOSE[26443][C-00000026] pbx.c: Executing [s@crm-hangup:1] NoOp("SIP/MTS-000003ff", "Sending Hangup to CRM") in new stack
[2019-01-31 16:07:39] VERBOSE[26443][C-00000026] pbx.c: Executing [s@crm-hangup:2] NoOp("SIP/MTS-000003ff", "HANGUP CAUSE: 21") in new stack
[2019-01-31 16:07:39] VERBOSE[26443][C-00000026] pbx.c: Executing [s@crm-hangup:3] ExecIf("SIP/MTS-000003ff", "0?Set(__CRM_VOICEMAIL=)") in new stack
[2019-01-31 16:07:39] VERBOSE[26443][C-00000026] pbx.c: Executing [s@crm-hangup:4] NoOp("SIP/MTS-000003ff", "MASTER CHANNEL: 1548940046.1023 = 1548940046.1023") in new stack
[2019-01-31 16:07:39] VERBOSE[26443][C-00000026] pbx.c: Executing [s@crm-hangup:5] GotoIf("SIP/MTS-000003ff", "0?return") in new stack
[2019-01-31 16:07:39] VERBOSE[26443][C-00000026] pbx.c: Executing [s@crm-hangup:6] Set("SIP/MTS-000003ff", "__CRM_HANGUP=1") in new stack
[2019-01-31 16:07:39] VERBOSE[26443][C-00000026] pbx.c: Executing [s@crm-hangup:7] AGI("SIP/MTS-000003ff", "sangomacrm.agi") in new stack
[2019-01-31 16:07:39] VERBOSE[26443][C-00000026] res_agi.c: Launched AGI Script /var/lib/asterisk/agi-bin/sangomacrm.agi
[2019-01-31 16:07:39] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_request: sangomacrm.agi
[2019-01-31 16:07:39] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_channel: SIP/MTS-000003ff
[2019-01-31 16:07:39] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_language: ru
[2019-01-31 16:07:39] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_type: SIP
[2019-01-31 16:07:39] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_uniqueid: 1548940046.1023
[2019-01-31 16:07:39] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_version: 15.5.0
[2019-01-31 16:07:39] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_callerid: 9181007773
[2019-01-31 16:07:39] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_calleridname: unknown
[2019-01-31 16:07:39] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_callingpres: 0
[2019-01-31 16:07:39] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_callingani2: 0
[2019-01-31 16:07:39] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_callington: 0
[2019-01-31 16:07:39] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_callingtns: 0
[2019-01-31 16:07:39] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_dnid: 9181007773
[2019-01-31 16:07:39] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_rdnis: unknown
[2019-01-31 16:07:39] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_context: crm-hangup
[2019-01-31 16:07:39] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_extension: s
[2019-01-31 16:07:39] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_priority: 7
[2019-01-31 16:07:39] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_enhanced: 0.0
[2019-01-31 16:07:39] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_accountcode:
[2019-01-31 16:07:39] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >> agi_threadid: 140357227575040
[2019-01-31 16:07:39] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Tx >>
[2019-01-31 16:07:39] VERBOSE[26443][C-00000026] res_agi.c: <SIP/MTS-000003ff>AGI Script sangomacrm.agi completed, returning 0
[2019-01-31 16:07:39] VERBOSE[26443][C-00000026] pbx.c: Executing [s@crm-hangup:8] Return("SIP/MTS-000003ff", "") in new stack
[2019-01-31 16:07:39] VERBOSE[26443][C-00000026] app_stack.c: Spawn extension (from-internal, h, 1) exited non-zero on 'SIP/MTS-000003ff'
[2019-01-31 16:07:39] VERBOSE[26443][C-00000026] app_stack.c: SIP/MTS-000003ff Internal Gosub(crm-hangup,s,1) complete GOSUB_RETVAL=
[2019-01-31 16:07:39] VERBOSE[26445][C-00000026] app_mixmonitor.c: MixMonitor close filestream (mixed)
[2019-01-31 16:07:39] VERBOSE[26445][C-00000026] app_mixmonitor.c: End MixMonitor Recording SIP/MTS-000003ff

Не отвечает МТС сервер?
[2019-01-31 16:06:16] WARNING[3237][C-00000025] chan_sip.c: Received response: "Forbidden" from '<sip:9181007773@109.235.190.34:5160>;tag=as18f482bc'

[Zavr2008]

insecure=very

2019 год шёл, а чайники до сих пор поливали кипятком себе на ноги))

СРОЧНО международку отключайте пишите заяву прову... Иначе налетите по-полной.
А по настройкам транка - по большей части они уже не валидные, ищите другую шпаргалку в инете, поновее. Ну или книжку начните читать, или попросите кого-нить тут помочь за пару рублей..