ASTERISK-24722: Call terminates without executing 'h' extension

[Home]

Summary: ASTERISK-24722: Call terminates without executing 'h' extension

Reporter: Belikov Alexander (marintrooper) Labels:

Date Opened: 2015-01-25 23:02:17.000-0600 Date Closed: 2015-02-21 10:58:52.000-0600

Priority: Minor Regression?

Status: Closed/Complete Components: Applications/app_dial

Versions: 11.13.0 Frequency of
Occurrence Constant

Related
Issues:

Environment: Attachments:

Description: Asterisk 11.13.0.
I give a call to the group of extensions like this:
{noformat}
exten=s,n,Dial(${ARG1},${ARG2},${MOHCLASS}twgFM(Set_Answer_Time^${UNIQUEID_FIRST}))
{noformat}
where ARG1- group of extensions (use voiceone+mysql)
ARG2-timeout.Do not use by me.

If I call the number registered on asterisk, and hang up after I spoke, then everything works fine.
But if I hung up before the lift tube, the call does not go on h extention.

Log:
{noformat}
-- Executing [73439333100@provider_33:1] NoOp("SIP/w_73439333100-00002775", "Incoming-73439333100:trunk_554 CallerID:"" <79658770268> - Calling:73439333100") in new stack
-- Executing [73439333100@provider_33:2] AGI("SIP/w_73439333100-00002775", "incoming.php,answered=73439333100&channel=554&rule=2&uniqueid=1422247984.12995") in new stack
-- Launched AGI Script /var/lib/asterisk/agi-bin/incoming.php
<SIP/w_73439333100-00002775>AGI Tx >> agi_request: incoming.php
<SIP/w_73439333100-00002775>AGI Tx >> agi_channel: SIP/w_73439333100-00002775
<SIP/w_73439333100-00002775>AGI Tx >> agi_language: ru
<SIP/w_73439333100-00002775>AGI Tx >> agi_type: SIP
<SIP/w_73439333100-00002775>AGI Tx >> agi_uniqueid: 1422247984.12995
<SIP/w_73439333100-00002775>AGI Tx >> agi_version: 11.13.0
<SIP/w_73439333100-00002775>AGI Tx >> agi_callerid: 79658770268
<SIP/w_73439333100-00002775>AGI Tx >> agi_calleridname: unknown
<SIP/w_73439333100-00002775>AGI Tx >> agi_callingpres: 0
<SIP/w_73439333100-00002775>AGI Tx >> agi_callingani2: 0
<SIP/w_73439333100-00002775>AGI Tx >> agi_callington: 0
<SIP/w_73439333100-00002775>AGI Tx >> agi_callingtns: 0
<SIP/w_73439333100-00002775>AGI Tx >> agi_dnid: 73439333100
<SIP/w_73439333100-00002775>AGI Tx >> agi_rdnis: unknown
<SIP/w_73439333100-00002775>AGI Tx >> agi_context: provider_33
<SIP/w_73439333100-00002775>AGI Tx >> agi_extension: 73439333100
<SIP/w_73439333100-00002775>AGI Tx >> agi_priority: 2
<SIP/w_73439333100-00002775>AGI Tx >> agi_enhanced: 0.0
<SIP/w_73439333100-00002775>AGI Tx >> agi_accountcode:
<SIP/w_73439333100-00002775>AGI Tx >> agi_threadid: 139677706327808
<SIP/w_73439333100-00002775>AGI Tx >> agi_arg_1: answered=73439333100&channel=554&rule=2&uniqueid=1422247984.12995
<SIP/w_73439333100-00002775>AGI Tx >>
<SIP/w_73439333100-00002775>AGI Rx << SET VARIABLE agi_extension "73439333100"
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE CHANNEL
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (SIP/w_73439333100-00002775)
<SIP/w_73439333100-00002775>AGI Rx << EXEC set GROUP()=SIP/w_73439333100
-- AGI Script Executing Application: (set) Options: (GROUP()=SIP/w_73439333100)
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=0
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE CALLERID(num)
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (79658770268)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE CHANNEL
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (SIP/w_73439333100-00002775)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE UNIQUEID
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (1422247984.12995)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE UNIQUEID
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (1422247984.12995)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE UNIQUEID
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (1422247984.12995)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE SIP_HEADER(TO)
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (<sip:73439333100@192.168.1.254:5060>)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE SIP_HEADER(FROM)
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (<sip:79658770268@85.12.253.210>;tag=as5eb4b6b3)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE UNIQUEID
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (1422247984.12995)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE UNIQUEID
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (1422247984.12995)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE UNIQUEID
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (1422247984.12995)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE UNIQUEID
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (1422247984.12995)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE UNIQUEID
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (1422247984.12995)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE UNIQUEID
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (1422247984.12995)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE UNIQUEID
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (1422247984.12995)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE UNIQUEID
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (1422247984.12995)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE UNIQUEID
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (1422247984.12995)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE UNIQUEID
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (1422247984.12995)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE UNIQUEID
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (1422247984.12995)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE UNIQUEID
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (1422247984.12995)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE UNIQUEID
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (1422247984.12995)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE UNIQUEID
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (1422247984.12995)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE UNIQUEID
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (1422247984.12995)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE UNIQUEID
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (1422247984.12995)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE UNIQUEID
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (1422247984.12995)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE UNIQUEID
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (1422247984.12995)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE UNIQUEID
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (1422247984.12995)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE UNIQUEID
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (1422247984.12995)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE UNIQUEID
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (1422247984.12995)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE UNIQUEID
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (1422247984.12995)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE UNIQUEID
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (1422247984.12995)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE UNIQUEID
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (1422247984.12995)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE UNIQUEID
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (1422247984.12995)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE UNIQUEID
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (1422247984.12995)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE UNIQUEID
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (1422247984.12995)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE UNIQUEID
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (1422247984.12995)
<SIP/w_73439333100-00002775>AGI Rx << EXEC macro incoming-call-to-group,SIP/101&SIP/102&SIP/103&SIP/104&SIP/105&SIP/106&SIP/107&SIP/108&SIP/109&SIP/110&SIP/111&SIP/112&SIP/113&SIP/114&SIP/115&SIP/116&SIP/117&SIP/118&SIP/119&SIP/120&SIP/555&SIP/121&SIP/122&SIP/124&SIP/125&SIP/126&SIP/127&SIP/128&SIP/129&SIP/130&SIP/131&SIP/132&SIP/133&SIP/134&SIP/135&SIP/136&SIP/137&SIP/138&SIP/139&SIP/140&SIP/141&SIP/142&SIP/143&SIP/144&SIP/145&SIP/146&SIP/147&SIP/148&SIP/149&SIP/150&SIP/151&SIP/152&SIP/153&SIP/154&SIP/155&SIP/156&SIP/157&SIP/158&SIP/159,"","","","DefaultOutgoingRule","001*","","","","","","","","","","","",""
-- AGI Script Executing Application: (macro) Options: (incoming-call-to-group,SIP/101&SIP/102&SIP/103&SIP/104&SIP/105&SIP/106&SIP/107&SIP/108&SIP/109&SIP/110&SIP/111&SIP/112&SIP/113&SIP/114&SIP/115&SIP/116&SIP/117&SIP/118&SIP/119&SIP/120&SIP/555&SIP/121&SIP/122&SIP/124&SIP/125&SIP/126&SIP/127&SIP/128&SIP/129&SIP/130&SIP/131&SIP/132&SIP/133&SIP/134&SIP/135&SIP/136&SIP/137&SIP/138&SIP/139&SIP/140&SIP/141&SIP/142&SIP/143&SIP/144&SIP/145&SIP/146&SIP/147&SIP/148&SIP/149&SIP/150&SIP/151&SIP/152&SIP/153&SIP/154&SIP/155&SIP/156&SIP/157&SIP/158&SIP/159,,,,DefaultOutgoingRule,001*,,,,,,,,,,,,)
-- Executing [s@macro-incoming-call-to-group:1] Set("SIP/w_73439333100-00002775", "CHANNEL(language)=ru") in new stack
-- Executing [s@macro-incoming-call-to-group:2] NoOp("SIP/w_73439333100-00002775", ":79658770268") in new stack
-- Executing [s@macro-incoming-call-to-group:3] Set("SIP/w_73439333100-00002775", "__X-fs-uid=") in new stack
-- Executing [s@macro-incoming-call-to-group:4] Set("SIP/w_73439333100-00002775", "MIXMONITOR_PATH=/var/spool/asterisk/monitor/") in new stack
-- Executing [s@macro-incoming-call-to-group:5] Set("SIP/w_73439333100-00002775", "ARG14=0") in new stack
-- Executing [s@macro-incoming-call-to-group:6] Set("SIP/w_73439333100-00002775", "CALLERID(num)=79658770268") in new stack
-- Executing [s@macro-incoming-call-to-group:7] Set("SIP/w_73439333100-00002775", "CALLERID(num)=79658770268") in new stack
-- Executing [s@macro-incoming-call-to-group:8] Set("SIP/w_73439333100-00002775", "CALLERID_NUM_AGI=79658770268") in new stack
-- Executing [s@macro-incoming-call-to-group:9] Set("SIP/w_73439333100-00002775", "__CIN_STPREFIX=001*") in new stack
-- Executing [s@macro-incoming-call-to-group:10] Macro("SIP/w_73439333100-00002775", "ChangeIncomingNumber") in new stack
-- Executing [s@macro-ChangeIncomingNumber:1] NoOp("SIP/w_73439333100-00002775", "Change Incoming Number") in new stack
-- Executing [s@macro-ChangeIncomingNumber:2] MacroExit("SIP/w_73439333100-00002775", "") in new stack
-- Executing [s@macro-incoming-call-to-group:11] TryExec("SIP/w_73439333100-00002775", "AGI(/management/bin/Asterisk_bin/Agent_AGI_DevServer.agi.php)") in new stack
-- Launched AGI Script /management/bin/Asterisk_bin/Agent_AGI_DevServer.agi.php
<SIP/w_73439333100-00002775>AGI Tx >> agi_request: /management/bin/Asterisk_bin/Agent_AGI_DevServer.agi.php
<SIP/w_73439333100-00002775>AGI Tx >> agi_channel: SIP/w_73439333100-00002775
<SIP/w_73439333100-00002775>AGI Tx >> agi_language: ru
<SIP/w_73439333100-00002775>AGI Tx >> agi_type: SIP
<SIP/w_73439333100-00002775>AGI Tx >> agi_uniqueid: 1422247984.12995
<SIP/w_73439333100-00002775>AGI Tx >> agi_version: 11.13.0
<SIP/w_73439333100-00002775>AGI Tx >> agi_callerid: 79658770268
<SIP/w_73439333100-00002775>AGI Tx >> agi_calleridname: unknown
<SIP/w_73439333100-00002775>AGI Tx >> agi_callingpres: 0
<SIP/w_73439333100-00002775>AGI Tx >> agi_callingani2: 0
<SIP/w_73439333100-00002775>AGI Tx >> agi_callington: 0
<SIP/w_73439333100-00002775>AGI Tx >> agi_callingtns: 0
<SIP/w_73439333100-00002775>AGI Tx >> agi_dnid: 73439333100
<SIP/w_73439333100-00002775>AGI Tx >> agi_rdnis: unknown
<SIP/w_73439333100-00002775>AGI Tx >> agi_context: macro-incoming-call-to-group
<SIP/w_73439333100-00002775>AGI Tx >> agi_extension: s
<SIP/w_73439333100-00002775>AGI Tx >> agi_priority: 11
<SIP/w_73439333100-00002775>AGI Tx >> agi_enhanced: 0.0
<SIP/w_73439333100-00002775>AGI Tx >> agi_accountcode:
<SIP/w_73439333100-00002775>AGI Tx >> agi_threadid: 139677706327808
<SIP/w_73439333100-00002775>AGI Tx >>
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE NEW_DIAL_PLAN
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=0
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE CALLERID(num)
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (79658770268)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE AIVR
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=0
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE UNIQUEID
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (1422247984.12995)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE STPREFIX
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=0
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE ARG6
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (001*)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE STSUFFIX
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=0
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE AUDIO_MSG
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=0
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE ORDER_ID
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=0
<SIP/w_73439333100-00002775>AGI Rx << SET VARIABLE AGI_SERVER_IP "127.0.0.1"
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1
<SIP/w_73439333100-00002775>AGI Rx << SET VARIABLE AGI_SERVER_PORT "9800"
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1
<SIP/w_73439333100-00002775>AGI Rx << SET VARIABLE AGI_SERVER_COUNTRY "russia"
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1
<SIP/w_73439333100-00002775>AGI Rx << VERBOSE "AGI VERBOSE(26466): <-----> Line 132 AUDIO_MSG=|CLIDnum=+79658770268|ORDER_ID=|AGI_SERVER_IP=127.0.0.1|AGI_SERVER_PORT=9800|AGI_SERVER_COUNTRY=russia|STPREFIX=001*|STSUFFIX=|UNIQUEID=1422247984.12995" 1
/management/bin/Asterisk_bin/Agent_AGI_DevServer.agi.php: AGI VERBOSE(26466): <-----> Line 132 AUDIO_MSG=|CLIDnum=+79658770268|ORDER_ID=|AGI_SERVER_IP=127.0.0.1|AGI_SERVER_PORT=9800|AGI_SERVER_COUNTRY=russia|STPREFIX=001*|STSUFFIX=|UNIQUEID=1422247984.12995
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1
<SIP/w_73439333100-00002775>AGI Rx << VERBOSE "AGI DEBUG(26466): <-----> Line 156 URL = http://127.0.0.1:9800/got_call3?prefix=001*&phone=+79658770268&call_id=1422247984.12995" 1
/management/bin/Asterisk_bin/Agent_AGI_DevServer.agi.php: AGI DEBUG(26466): <-----> Line 156 URL = http://127.0.0.1:9800/got_call3?prefix=001*&phone=+79658770268&call_id=1422247984.12995
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1
<SIP/w_73439333100-00002775>AGI Rx << VERBOSE "AGI DEBUG(26466): <-----> Line 160 json = {"items":[{"acronym":"send-invite-notification","state":0},{"acronym":"double-tariff","state":0}]}" 1
/management/bin/Asterisk_bin/Agent_AGI_DevServer.agi.php: AGI DEBUG(26466): <-----> Line 160 json = {items:[{acronym:send-invite-notification,state:0},{acronym:double-tariff,state:0}]}
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1
<SIP/w_73439333100-00002775>AGI Rx << VERBOSE "AGI DEBUG26466): <-----> Line 327 make_request time spent: 0.002673864364624" 1
/management/bin/Asterisk_bin/Agent_AGI_DevServer.agi.php: AGI DEBUG26466): <-----> Line 327 make_request time spent: 0.002673864364624
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1
<SIP/w_73439333100-00002775>AGI Rx << VERBOSE "AGI VERBOSE(26466): <-----> Line 331 Config SMS_SEND: notSend | DOUBLE_TARIFF: OFF" 1
/management/bin/Asterisk_bin/Agent_AGI_DevServer.agi.php: AGI VERBOSE(26466): <-----> Line 331 Config SMS_SEND: notSend | DOUBLE_TARIFF: OFF
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1
<SIP/w_73439333100-00002775>AGI Rx << SET VARIABLE BITFLAG "0000"
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE AUDIO_MSG
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=0
<SIP/w_73439333100-00002775>AGI Rx << VERBOSE "AGI DEBUG(26466): <-----> Line 374 AUDIO_MSG: " 1
/management/bin/Asterisk_bin/Agent_AGI_DevServer.agi.php: AGI DEBUG(26466): <-----> Line 374 AUDIO_MSG:
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1
<SIP/w_73439333100-00002775>AGI Rx << VERBOSE "Exit from Agent_AGI_DevServer.agi.php" 1
/management/bin/Asterisk_bin/Agent_AGI_DevServer.agi.php: Exit from Agent_AGI_DevServer.agi.php
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1
-- <SIP/w_73439333100-00002775>AGI Script /management/bin/Asterisk_bin/Agent_AGI_DevServer.agi.php completed, returning 0
-- Executing [s@macro-incoming-call-to-group:12] NoOp("SIP/w_73439333100-00002775", "--> ORDER_ID= <--") in new stack
-- Executing [s@macro-incoming-call-to-group:13] Set("SIP/w_73439333100-00002775", "NoCLIDChange=") in new stack
-- Executing [s@macro-incoming-call-to-group:14] GotoIf("SIP/w_73439333100-00002775", "0?NoCLIDChange") in new stack
-- Executing [s@macro-incoming-call-to-group:15] Set("SIP/w_73439333100-00002775", "CALLERID(num)=79658770268") in new stack
-- Executing [s@macro-incoming-call-to-group:16] Set("SIP/w_73439333100-00002775", "CALLERID(num)=79658770268") in new stack
-- Executing [s@macro-incoming-call-to-group:17] Set("SIP/w_73439333100-00002775", "CALLERID(num)=79658770268") in new stack
-- Executing [s@macro-incoming-call-to-group:18] Set("SIP/w_73439333100-00002775", "CALLERID(num)=89658770268") in new stack
-- Executing [s@macro-incoming-call-to-group:19] Set("SIP/w_73439333100-00002775", "CALLERID(num)=89658770268") in new stack
-- Executing [s@macro-incoming-call-to-group:20] NoOp("SIP/w_73439333100-00002775", "End change CALLERID(num)") in new stack
-- Executing [s@macro-incoming-call-to-group:21] ExecIf("SIP/w_73439333100-00002775", "0?Macro()") in new stack
-- Executing [s@macro-incoming-call-to-group:22] Set("SIP/w_73439333100-00002775", "CALLERID(num)=89658770268") in new stack
-- Executing [s@macro-incoming-call-to-group:23] Set("SIP/w_73439333100-00002775", "ARG11=0") in new stack
-- Executing [s@macro-incoming-call-to-group:24] Set("SIP/w_73439333100-00002775", "ARG12=0") in new stack
-- Executing [s@macro-incoming-call-to-group:25] Set("SIP/w_73439333100-00002775", "ARG13=1") in new stack
-- Executing [s@macro-incoming-call-to-group:26] Set("SIP/w_73439333100-00002775", "__CLIENTDROPCALL=0") in new stack
-- Executing [s@macro-incoming-call-to-group:27] Set("SIP/w_73439333100-00002775", "DSC=""") in new stack
-- Executing [s@macro-incoming-call-to-group:28] Set("SIP/w_73439333100-00002775", "DSD=""") in new stack
-- Executing [s@macro-incoming-call-to-group:29] Set("SIP/w_73439333100-00002775", "__UNIQUEID_FIRST=1422247984.12995") in new stack
-- Executing [s@macro-incoming-call-to-group:30] Set("SIP/w_73439333100-00002775", "__STPREFIX=001*") in new stack
-- Executing [s@macro-incoming-call-to-group:31] Set("SIP/w_73439333100-00002775", "__BILLING_CALLS="true"") in new stack
-- Executing [s@macro-incoming-call-to-group:32] Set("SIP/w_73439333100-00002775", "__ANSWEREDTIMECALLS=0") in new stack
-- Executing [s@macro-incoming-call-to-group:33] Set("SIP/w_73439333100-00002775", "__TYPECALLS="1"") in new stack
-- Executing [s@macro-incoming-call-to-group:34] Set("SIP/w_73439333100-00002775", "ARG7=") in new stack
-- Executing [s@macro-incoming-call-to-group:35] Set("SIP/w_73439333100-00002775", "__STSUFFIX=") in new stack
-- Executing [s@macro-incoming-call-to-group:36] Set("SIP/w_73439333100-00002775", "__BEGIN_FILE_NAME=IN") in new stack
-- Executing [s@macro-incoming-call-to-group:37] Set("SIP/w_73439333100-00002775", "__TOUCH_MONITOR_WOEXT=IN_20150126-095304_89658770268") in new stack
-- Executing [s@macro-incoming-call-to-group:38] Set("SIP/w_73439333100-00002775", "__TOUCH_MONITOR=IN_20150126-095304_89658770268_NotAnswered.wav") in new stack
-- Executing [s@macro-incoming-call-to-group:39] Set("SIP/w_73439333100-00002775", "__CB=0") in new stack
-- Executing [s@macro-incoming-call-to-group:40] Set("SIP/w_73439333100-00002775", "__CB_TIMEOUT=0") in new stack
-- Executing [s@macro-incoming-call-to-group:41] Set("SIP/w_73439333100-00002775", "__CB_EXT=0") in new stack
-- Executing [s@macro-incoming-call-to-group:42] Set("SIP/w_73439333100-00002775", "__CBN=0") in new stack
-- Executing [s@macro-incoming-call-to-group:43] Set("SIP/w_73439333100-00002775", "__STSUFFIX=") in new stack
-- Executing [s@macro-incoming-call-to-group:44] Set("SIP/w_73439333100-00002775", "__STPREFIX=001*") in new stack
-- Executing [s@macro-incoming-call-to-group:45] Set("SIP/w_73439333100-00002775", "__TYPECALLS="1"") in new stack
-- Executing [s@macro-incoming-call-to-group:46] Set("SIP/w_73439333100-00002775", "__TYPECALLS="1"") in new stack
-- Executing [s@macro-incoming-call-to-group:47] Set("SIP/w_73439333100-00002775", "CALLERID_num=89658770268") in new stack
-- Executing [s@macro-incoming-call-to-group:48] Set("SIP/w_73439333100-00002775", "CALLERID(num)=001*89658770268") in new stack
-- Executing [s@macro-incoming-call-to-group:49] Set("SIP/w_73439333100-00002775", "CDR(userfield)="" <001*89658770268>") in new stack
-- Executing [s@macro-incoming-call-to-group:50] GotoIf("SIP/w_73439333100-00002775", "0?MixA") in new stack
-- Executing [s@macro-incoming-call-to-group:51] MixMonitor("SIP/w_73439333100-00002775", "/var/spool/asterisk/monitor/IN_20150126-095304_89658770268_NotAnswered.wav") in new stack
-- Executing [s@macro-incoming-call-to-group:52] Goto("SIP/w_73439333100-00002775", "AfterMixStart") in new stack
-- Goto (macro-incoming-call-to-group,s,54)
[2015-01-26 09:53:04] WARNING[26463][C-00000fa0]: db.c:367 db_get_common: Family and key length must be less than 253 bytes
[2015-01-26 09:53:04] WARNING[26463][C-00000fa0]: db.c:367 db_get_common: Family and key length must be less than 253 bytes
-- Executing [s@macro-incoming-call-to-group:54] GotoIf("SIP/w_73439333100-00002775", "0?DefaultOutgoingRule,,1") in new stack
-- Executing [s@macro-incoming-call-to-group:55] Set("SIP/w_73439333100-00002775", "ALERT_EXTERNAL=TRUE") in new stack
-- Executing [s@macro-incoming-call-to-group:56] Set("SIP/w_73439333100-00002775", "__STARTCALL=1422247984") in new stack
-- Executing [s@macro-incoming-call-to-group:57] GotoIf("SIP/w_73439333100-00002775", "0?AfterDial") in new stack
-- Executing [s@macro-incoming-call-to-group:58] TryExec("SIP/w_73439333100-00002775", "AGI(/management/bin/Asterisk_bin/robot-ivr.agi.php)") in new stack
-- Launched AGI Script /management/bin/Asterisk_bin/robot-ivr.agi.php
== Begin MixMonitor Recording SIP/w_73439333100-00002775
<SIP/w_73439333100-00002775>AGI Tx >> agi_request: /management/bin/Asterisk_bin/robot-ivr.agi.php
<SIP/w_73439333100-00002775>AGI Tx >> agi_channel: SIP/w_73439333100-00002775
<SIP/w_73439333100-00002775>AGI Tx >> agi_language: ru
<SIP/w_73439333100-00002775>AGI Tx >> agi_type: SIP
<SIP/w_73439333100-00002775>AGI Tx >> agi_uniqueid: 1422247984.12995
<SIP/w_73439333100-00002775>AGI Tx >> agi_version: 11.13.0
<SIP/w_73439333100-00002775>AGI Tx >> agi_callerid: 001*89658770268
<SIP/w_73439333100-00002775>AGI Tx >> agi_calleridname: unknown
<SIP/w_73439333100-00002775>AGI Tx >> agi_callingpres: 0
<SIP/w_73439333100-00002775>AGI Tx >> agi_callingani2: 0
<SIP/w_73439333100-00002775>AGI Tx >> agi_callington: 0
<SIP/w_73439333100-00002775>AGI Tx >> agi_callingtns: 0
<SIP/w_73439333100-00002775>AGI Tx >> agi_dnid: 73439333100
<SIP/w_73439333100-00002775>AGI Tx >> agi_rdnis: unknown
<SIP/w_73439333100-00002775>AGI Tx >> agi_context: macro-incoming-call-to-group
<SIP/w_73439333100-00002775>AGI Tx >> agi_extension: s
<SIP/w_73439333100-00002775>AGI Tx >> agi_priority: 58
<SIP/w_73439333100-00002775>AGI Tx >> agi_enhanced: 0.0
<SIP/w_73439333100-00002775>AGI Tx >> agi_accountcode:
<SIP/w_73439333100-00002775>AGI Tx >> agi_threadid: 139677706327808
<SIP/w_73439333100-00002775>AGI Tx >>
<SIP/w_73439333100-00002775>AGI Rx << VERBOSE "AGI DEBUG(26469): <-----> Line 74" 1
/management/bin/Asterisk_bin/robot-ivr.agi.php: AGI DEBUG(26469): <-----> Line 74
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE CALLERID(num)
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (001*89658770268)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE ORDER_ID
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=0
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE ORDER_STATUS_ID
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=0
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE AUDIO_MSG
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=0
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE AGI_SERVER_IP
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (127.0.0.1)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE AGI_SERVER_PORT
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (9800)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE AGI_SERVER_COUNTRY
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (russia)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE AIVR
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=0
<SIP/w_73439333100-00002775>AGI Rx << VERBOSE "AGI VERBOSE(26469): <-----> Line 87 AUDIO_MSG=|CLIDnum=001*89658770268|ORDER_ID=|ORDER_STATUS_ID=|AGI_SERVER_IP=127.0.0.1|AGI_SERVER_PORT=9800|AIVR=" 1
/management/bin/Asterisk_bin/robot-ivr.agi.php: AGI VERBOSE(26469): <-----> Line 87 AUDIO_MSG=|CLIDnum=001*89658770268|ORDER_ID=|ORDER_STATUS_ID=|AGI_SERVER_IP=127.0.0.1|AGI_SERVER_PORT=9800|AIVR=
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1
<SIP/w_73439333100-00002775>AGI Rx << VERBOSE "AGI DEBUG(26469): <-----> Line 89 CLID number is 001*89658770268" 1
/management/bin/Asterisk_bin/robot-ivr.agi.php: AGI DEBUG(26469): <-----> Line 89 CLID number is 001*89658770268
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1
<SIP/w_73439333100-00002775>AGI Rx << VERBOSE "Exit from robot-ivr.agi.php" 1
/management/bin/Asterisk_bin/robot-ivr.agi.php: Exit from robot-ivr.agi.php
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1
-- <SIP/w_73439333100-00002775>AGI Script /management/bin/Asterisk_bin/robot-ivr.agi.php completed, returning 0
-- Executing [s@macro-incoming-call-to-group:59] TryExec("SIP/w_73439333100-00002775", "AGI(/management/bin/Asterisk_bin/before-play-hello.agi.php)") in new stack
-- Launched AGI Script /management/bin/Asterisk_bin/before-play-hello.agi.php
<SIP/w_73439333100-00002775>AGI Tx >> agi_request: /management/bin/Asterisk_bin/before-play-hello.agi.php
<SIP/w_73439333100-00002775>AGI Tx >> agi_channel: SIP/w_73439333100-00002775
<SIP/w_73439333100-00002775>AGI Tx >> agi_language: ru
<SIP/w_73439333100-00002775>AGI Tx >> agi_type: SIP
<SIP/w_73439333100-00002775>AGI Tx >> agi_uniqueid: 1422247984.12995
<SIP/w_73439333100-00002775>AGI Tx >> agi_version: 11.13.0
<SIP/w_73439333100-00002775>AGI Tx >> agi_callerid: 001*89658770268
<SIP/w_73439333100-00002775>AGI Tx >> agi_calleridname: unknown
<SIP/w_73439333100-00002775>AGI Tx >> agi_callingpres: 0
<SIP/w_73439333100-00002775>AGI Tx >> agi_callingani2: 0
<SIP/w_73439333100-00002775>AGI Tx >> agi_callington: 0
<SIP/w_73439333100-00002775>AGI Tx >> agi_callingtns: 0
<SIP/w_73439333100-00002775>AGI Tx >> agi_dnid: 73439333100
<SIP/w_73439333100-00002775>AGI Tx >> agi_rdnis: unknown
<SIP/w_73439333100-00002775>AGI Tx >> agi_context: macro-incoming-call-to-group
<SIP/w_73439333100-00002775>AGI Tx >> agi_extension: s
<SIP/w_73439333100-00002775>AGI Tx >> agi_priority: 59
<SIP/w_73439333100-00002775>AGI Tx >> agi_enhanced: 0.0
<SIP/w_73439333100-00002775>AGI Tx >> agi_accountcode:
<SIP/w_73439333100-00002775>AGI Tx >> agi_threadid: 139677706327808
<SIP/w_73439333100-00002775>AGI Tx >>
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE ARG4
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 ()
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE AIVR
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=0
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE STPREFIX
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 (001*)
<SIP/w_73439333100-00002775>AGI Rx << GET VARIABLE STSUFFIX
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1 ()
<SIP/w_73439333100-00002775>AGI Rx << VERBOSE "----> " 1
/management/bin/Asterisk_bin/before-play-hello.agi.php: ---->
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1
<SIP/w_73439333100-00002775>AGI Rx << VERBOSE "----> " 1
/management/bin/Asterisk_bin/before-play-hello.agi.php: ---->
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1
<SIP/w_73439333100-00002775>AGI Rx << VERBOSE "----> 001*" 1
/management/bin/Asterisk_bin/before-play-hello.agi.php: ----> 001*
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1
<SIP/w_73439333100-00002775>AGI Rx << VERBOSE "----> " 1
/management/bin/Asterisk_bin/before-play-hello.agi.php: ---->
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1
<SIP/w_73439333100-00002775>AGI Rx << VERBOSE "Exit from before-play-hello.agi.php" 1
/management/bin/Asterisk_bin/before-play-hello.agi.php: Exit from before-play-hello.agi.php
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=1
-- <SIP/w_73439333100-00002775>AGI Script /management/bin/Asterisk_bin/before-play-hello.agi.php completed, returning 0
-- Executing [s@macro-incoming-call-to-group:60] GotoIf("SIP/w_73439333100-00002775", "1?AutoInformator") in new stack
-- Goto (macro-incoming-call-to-group,s,68)
-- Executing [s@macro-incoming-call-to-group:68] TryExec("SIP/w_73439333100-00002775", "AGI(/management/bin/Asterisk_bin/after-play-hello.agi.php)") in new stack
-- Launched AGI Script /management/bin/Asterisk_bin/after-play-hello.agi.php
<SIP/w_73439333100-00002775>AGI Tx >> agi_request: /management/bin/Asterisk_bin/after-play-hello.agi.php
<SIP/w_73439333100-00002775>AGI Tx >> agi_channel: SIP/w_73439333100-00002775
<SIP/w_73439333100-00002775>AGI Tx >> agi_language: ru
<SIP/w_73439333100-00002775>AGI Tx >> agi_type: SIP
<SIP/w_73439333100-00002775>AGI Tx >> agi_uniqueid: 1422247984.12995
<SIP/w_73439333100-00002775>AGI Tx >> agi_version: 11.13.0
<SIP/w_73439333100-00002775>AGI Tx >> agi_callerid: 001*89658770268
<SIP/w_73439333100-00002775>AGI Tx >> agi_calleridname: unknown
<SIP/w_73439333100-00002775>AGI Tx >> agi_callingpres: 0
<SIP/w_73439333100-00002775>AGI Tx >> agi_callingani2: 0
<SIP/w_73439333100-00002775>AGI Tx >> agi_callington: 0
<SIP/w_73439333100-00002775>AGI Tx >> agi_callingtns: 0
<SIP/w_73439333100-00002775>AGI Tx >> agi_dnid: 73439333100
<SIP/w_73439333100-00002775>AGI Tx >> agi_rdnis: unknown
<SIP/w_73439333100-00002775>AGI Tx >> agi_context: macro-incoming-call-to-group
<SIP/w_73439333100-00002775>AGI Tx >> agi_extension: s
<SIP/w_73439333100-00002775>AGI Tx >> agi_priority: 68
<SIP/w_73439333100-00002775>AGI Tx >> agi_enhanced: 0.0
<SIP/w_73439333100-00002775>AGI Tx >> agi_accountcode:
<SIP/w_73439333100-00002775>AGI Tx >> agi_threadid: 139677706327808
<SIP/w_73439333100-00002775>AGI Tx >>
-- <SIP/w_73439333100-00002775>AGI Script /management/bin/Asterisk_bin/after-play-hello.agi.php completed, returning 0
-- Executing [s@macro-incoming-call-to-group:69] GotoIf("SIP/w_73439333100-00002775", "1?CheckMOH") in new stack
-- Goto (macro-incoming-call-to-group,s,77)
-- Executing [s@macro-incoming-call-to-group:77] GotoIf("SIP/w_73439333100-00002775", "1?NoACheckMOH") in new stack
-- Goto (macro-incoming-call-to-group,s,81)
-- Executing [s@macro-incoming-call-to-group:81] Set("SIP/w_73439333100-00002775", "MOHCLASS=") in new stack
-- Executing [s@macro-incoming-call-to-group:82] Set("SIP/w_73439333100-00002775", "__STARTDIAL=1422247984") in new stack
-- Executing [s@macro-incoming-call-to-group:83] Set("SIP/w_73439333100-00002775", "BITFLAG=0001") in new stack
-- Executing [s@macro-incoming-call-to-group:84] Dial("SIP/w_73439333100-00002775", "SIP/101&SIP/102&SIP/103&SIP/104&SIP/105&SIP/106&SIP/107&SIP/108&SIP/109&SIP/110&SIP/111&SIP/112&SIP/113&SIP/114&SIP/115&SIP/116&SIP/117&SIP/118&SIP/119&SIP/120&SIP/555&SIP/121&SIP/122&SIP/124&SIP/125&SIP/126&SIP/127&SIP/128&SIP/129&SIP/130&SIP/131&SIP/132&SIP/133&SIP/134&SIP/135&SIP/136&SIP/137&SIP/138&SIP/139&SIP/140&SIP/141&SIP/142&SIP/143&SIP/144&SIP/145&SIP/146&SIP/147&SIP/148&SIP/149&SIP/150&SIP/151&SIP/152&SIP/153&SIP/154&SIP/155&SIP/156&SIP/157&SIP/158&SIP/159,,twgFM(Set_Answer_Time^1422247984.12995)") in new stack
== Using SIP RTP CoS mark 5
== Using SIP RTP CoS mark 5
-- Called SIP/103
-- Called SIP/107
-- SIP/107-00002777 connected line has changed. Saving it until answer for SIP/w_73439333100-00002775
-- SIP/103-00002776 connected line has changed. Saving it until answer for SIP/w_73439333100-00002775
-- SIP/103-00002776 is ringing
-- SIP/107-00002777 is ringing
== Spawn extension (macro-incoming-call-to-group, s, 84) exited non-zero on 'SIP/w_73439333100-00002775' in macro 'incoming-call-to-group'
<SIP/w_73439333100-00002775>AGI Tx >> 200 result=-1
-- <SIP/w_73439333100-00002775>AGI Script incoming.php completed, returning 4
== Spawn extension (provider_33, 73439333100, 2) exited non-zero on 'SIP/w_73439333100-00002775'
== MixMonitor close filestream (mixed)
== End MixMonitor Recording SIP/w_73439333100-00002775
{noformat}

Dump:
{noformat}
09:53:00.482596 IP 85.12.253.210.5060 > 192.168.1.254.5060: SIP, length: 555
E..GzB..9...U............3.POPTIONS sip:73439333100@192.168.1.254:5060 SIP/2.0
Via: SIP/2.0/UDP 85.12.253.210:5060;branch=z9hG4bK046adb69;rport
Max-Forwards: 70
From: "asterisk" <sip:asterisk@85.12.253.210>;tag=as5966e67e
To: <sip:73439333100@192.168.1.254:5060>
Contact: <sip:asterisk@85.12.253.210:5060>
Call-ID: 40a6f3e213023b7b286c45cb39d2add3@85.12.253.210:5060
CSeq: 102 OPTIONS
User-Agent: centrex
Date: Mon, 26 Jan 2015 04:51:54 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Length: 0

09:53:00.482913 IP 192.168.1.254.5060 > 85.12.253.210.5060: SIP, length: 541
E..9....@..Z....U........%2.SIP/2.0 200 OK
Via: SIP/2.0/UDP 85.12.253.210:5060;branch=z9hG4bK046adb69;received=85.12.253.210;rport=5060
From: "asterisk" <sip:asterisk@85.12.253.210>;tag=as5966e67e
To: <sip:73439333100@192.168.1.254:5060>;tag=as0f03b86d
Call-ID: 40a6f3e213023b7b286c45cb39d2add3@85.12.253.210:5060
CSeq: 102 OPTIONS
Server: Asterisk PBX 11.13.0
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Contact: <sip:192.168.1.254:5060>
Accept: application/sdp
Content-Length: 0

09:53:04.069619 IP 85.12.253.210.5060 > 192.168.1.254.5060: SIP, length: 891
E...zC..9...U...............INVITE sip:73439333100@192.168.1.254:5060 SIP/2.0
Via: SIP/2.0/UDP 85.12.253.210:5060;branch=z9hG4bK37e028d4;rport
Max-Forwards: 70
From: <sip:79658770268@85.12.253.210>;tag=as5eb4b6b3
To: <sip:73439333100@192.168.1.254:5060>
Contact: <sip:79658770268@85.12.253.210:5060>
Call-ID: 1e046f554cdd0e884c36e54f158b070c@85.12.253.210:5060
CSeq: 102 INVITE
User-Agent: centrex
Date: Mon, 26 Jan 2015 04:51:58 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Content-Type: application/sdp
Content-Length: 310

v=0
o=root 889287661 889287661 IN IP4 85.12.253.210
s=Asterisk PBX 11.7.0-rc1
c=IN IP4 85.12.253.210
t=0 0
m=audio 19760 RTP/AVP 18 8 0 101
a=rtpmap:18 G729/8000
a=fmtp:18 annexb=no
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

09:53:04.072296 IP 192.168.1.254.5060 > 85.12.253.210.5060: SIP, length: 543
E..;.0..@.......U........'9kOPTIONS sip:85.12.253.210 SIP/2.0
Via: SIP/2.0/UDP 192.168.1.254:5060;branch=z9hG4bK45051295
Max-Forwards: 70
From: "asterisk" <sip:73439333100@192.168.1.254>;tag=as1349b42e
To: <sip:85.12.253.210>
Contact: <sip:73439333100@192.168.1.254:5060>
Call-ID: 25502c8540dd086c7fbba2d44673c538@192.168.1.254:5060
CSeq: 102 OPTIONS
User-Agent: Asterisk PBX 11.13.0
Date: Mon, 26 Jan 2015 04:53:04 GMT
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0

09:53:04.073825 IP 192.168.1.254.5060 > 85.12.253.210.5060: SIP, length: 545
E..=.1..@.......U........).ZSIP/2.0 100 Trying
Via: SIP/2.0/UDP 85.12.253.210:5060;branch=z9hG4bK37e028d4;received=85.12.253.210;rport=5060
From: <sip:79658770268@85.12.253.210>;tag=as5eb4b6b3
To: <sip:73439333100@192.168.1.254:5060>
Call-ID: 1e046f554cdd0e884c36e54f158b070c@85.12.253.210:5060
CSeq: 102 INVITE
Server: Asterisk PBX 11.13.0
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: <sip:73439333100@192.168.1.254:5060>
Content-Length: 0

09:53:04.076756 IP 85.12.253.210.5060 > 192.168.1.254.5060: SIP, length: 477
E...zD..9..*U..............sSIP/2.0 404 Not Found
Via: SIP/2.0/UDP 192.168.1.254:5060;branch=z9hG4bK45051295;received=185.12.252.29;rport=1025
From: "asterisk" <sip:73439333100@192.168.1.254>;tag=as1349b42e
To: <sip:85.12.253.210>;tag=as258d7ad0
Call-ID: 25502c8540dd086c7fbba2d44673c538@192.168.1.254:5060
CSeq: 102 OPTIONS
Server: centrex
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH
Supported: replaces, timer
Accept: application/sdp
Content-Length: 0

09:53:04.565596 IP 192.168.1.254.5060 > 85.12.253.210.5060: SIP, length: 561
E..M.<..@.......U........9C.SIP/2.0 180 Ringing
Via: SIP/2.0/UDP 85.12.253.210:5060;branch=z9hG4bK37e028d4;received=85.12.253.210;rport=5060
From: <sip:79658770268@85.12.253.210>;tag=as5eb4b6b3
To: <sip:73439333100@192.168.1.254:5060>;tag=as3c045aec
Call-ID: 1e046f554cdd0e884c36e54f158b070c@85.12.253.210:5060
CSeq: 102 INVITE
Server: Asterisk PBX 11.13.0
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Session-Expires: 1800;refresher=uas
Contact: <sip:73439333100@192.168.1.254:5060>
Content-Length: 0

09:53:06.581625 IP 85.12.253.210.5060 > 192.168.1.254.5060: SIP, length: 353
E..}zE..9...U............i..CANCEL sip:73439333100@192.168.1.254:5060 SIP/2.0
Via: SIP/2.0/UDP 85.12.253.210:5060;branch=z9hG4bK37e028d4;rport
Max-Forwards: 70
From: <sip:79658770268@85.12.253.210>;tag=as5eb4b6b3
To: <sip:73439333100@192.168.1.254:5060>
Call-ID: 1e046f554cdd0e884c36e54f158b070c@85.12.253.210:5060
CSeq: 102 CANCEL
User-Agent: centrex
Content-Length: 0

09:53:06.581910 IP 192.168.1.254.5060 > 85.12.253.210.5060: SIP, length: 488
E.......@.......U.........K.SIP/2.0 487 Request Terminated
Via: SIP/2.0/UDP 85.12.253.210:5060;branch=z9hG4bK37e028d4;received=85.12.253.210;rport=5060
From: <sip:79658770268@85.12.253.210>;tag=as5eb4b6b3
To: <sip:73439333100@192.168.1.254:5060>;tag=as3c045aec
Call-ID: 1e046f554cdd0e884c36e54f158b070c@85.12.253.210:5060
CSeq: 102 INVITE
Server: Asterisk PBX 11.13.0
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0

09:53:06.581954 IP 192.168.1.254.5060 > 85.12.253.210.5060: SIP, length: 472
E.......@.......U...........SIP/2.0 200 OK
Via: SIP/2.0/UDP 85.12.253.210:5060;branch=z9hG4bK37e028d4;received=85.12.253.210;rport=5060
From: <sip:79658770268@85.12.253.210>;tag=as5eb4b6b3
To: <sip:73439333100@192.168.1.254:5060>;tag=as3c045aec
Call-ID: 1e046f554cdd0e884c36e54f158b070c@85.12.253.210:5060
CSeq: 102 CANCEL
Server: Asterisk PBX 11.13.0
Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Supported: replaces, timer
Content-Length: 0

09:53:06.585587 IP 85.12.253.210.5060 > 192.168.1.254.5060: SIP, length: 409
E...zF..9..lU...............ACK sip:73439333100@192.168.1.254:5060 SIP/2.0
Via: SIP/2.0/UDP 85.12.253.210:5060;branch=z9hG4bK37e028d4;rport
Max-Forwards: 70
From: <sip:79658770268@85.12.253.210>;tag=as5eb4b6b3
To: <sip:73439333100@192.168.1.254:5060>;tag=as3c045aec
Contact: <sip:79658770268@85.12.253.210:5060>
Call-ID: 1e046f554cdd0e884c36e54f158b070c@85.12.253.210:5060
CSeq: 102 ACK
User-Agent: centrex
Content-Length: 0
{noformat}

Dial plan:
{noformat}
;
; EST
; Macros: Incoming call to group
; 28.05.2011
;
;
; Ystanavlivaem peremennie kanala
exten = s,1,Set(CHANNEL(language)=ru)
exten = s,n,NoOp(${CALLERID(name)}:${CALLERID(num)})
exten = s,n,Set(__X-fs-uid=${SIP_HEADER(X-fs-uid)})
exten = s,n,Set(MIXMONITOR_PATH=/var/spool/asterisk/monitor/)
;
; Esli zvonjat cherez AddPak, to berem nomer zvonjawego iz zagolovka sip protokola
exten = s,n,Set(ARG14=${IF($[${EXISTS(${ARG14})} = 0]?0:${IF($["${REGEX("^[0-9]+$" ${ARG14})}"="1"]?${ARG14}:0)})})
; Obrabativaem perevod s drugogo Asterisk servera
exten = s,n,Set(CALLERID(num)=${IF($["${CALLERID(num)}"="both"]?${CALLERID(name)}:${CALLERID(num)})})
;
exten = s,n,Set(CALLERID(num)=${IF($["${ARG14}"="1"]?${CUT(CUT(SIP_HEADER(Remote-Party-Id),@,1),:,2)}:${IF($["${ARG8}"="1"]?${CALLERID(name)}:${CALLERID(num)})})})
exten = s,n,Set(CALLERID_NUM_AGI=${CALLERID(num)})
;
; Obrabatyvaem lokal'nye pravila obrabotki vhodjawego nomera. Bol'she nichego.
exten = s,n,Set(__CIN_STPREFIX=${IF($[${EXISTS(${STPREFIX})} = 0]?${ARG6}:${STPREFIX})})
exten = s,n,Macro(ChangeIncomingNumber)
;
; Zapuskaem posrednika mezhdu AGI i device-service
exten = s,n,TryExec(AGI(/management/bin/Asterisk_bin/Agent_AGI_DevServer.agi.php))
exten = s,n,NoOp(--> ORDER_ID=${ORDER_ID} <--)
;
exten = s,n,Set(NoCLIDChange=${IF($[${EXISTS(${ARG18})} = 0]?${NoCLIDChange}:1)})
;
; Obrabatyvaem
exten = s,n,GotoIf($["${NoCLIDChange}" = "1"]?NoCLIDChange)
exten = s,n,Set(CALLERID(num)=${IF($["${CALLERID(num):3:1}"="*"]?${CALLERID(num):4}:${CALLERID(num)})})
exten = s,n,Set(CALLERID(num)=${IF($["${CALLERID(num):0:3}" = "8+7"]?8${CALLERID(num):3}:${CALLERID(num)})})
exten = s,n,Set(CALLERID(num)=${IF($["${CALLERID(num):0:2}"="+7"]?8${CALLERID(num):-10}:${CALLERID(num)})})
exten = s,n,Set(CALLERID(num)=${IF($["${CALLERID(num):0:1}"="7"]?${IF($["${LEN(${CALLERID(num)})}"="11"]?8${CALLERID(num):-10}:${CALLERID(num)})}:${CALLERID(num)})})
exten = s,n,Set(CALLERID(num)=${IF($["${LEN(${CALLERID(num)})}"="10"]?8${CALLERID(num)}:${CALLERID(num)})})
exten = s,n(NoCLIDChange),NoOp(End change CALLERID(num))
;
exten = s,n,ExecIf($["${LEN(${ARG18})}">"1"]?Macro(${ARG18}))
;
; Esli zamesto nomera peredalos' nazvanie tranka(t.e. ne cifry, s vozmozhnym pljusom vperedi), to pishem chto ne opredelen.
exten = s,n,Set(CALLERID(num)=${IF($["${REGEX("^[+*0-9]*[0-9]+$" ${CALLERID(num)})}"="1"]?${CALLERID(num)}:Anonymous)})
;
; Proverjaem na celostnost' peremennye
exten = s,n,Set(ARG11=${IF($[${EXISTS(${ARG11})} = 0]?0:${IF($["${REGEX("^[0-9]+$" ${ARG11})}"="1"]?${ARG11}:0)})})
exten = s,n,Set(ARG12=${IF($[${EXISTS(${ARG12})} = 0]?0:${IF($["${REGEX("^[0-9]+$" ${ARG12})}"="1"]?${ARG12}:0)})})
exten = s,n,Set(ARG13=${IF($[${EXISTS(${ARG13})} = 0]?1:${IF($["${REGEX("^[0-9]+$" ${ARG13})}"="1"]?${ARG13}:1)})})
;
; Perdustanavlivaet neobhodimye peremennye
exten = s,n,Set(__CLIENTDROPCALL=0) ; Esli razgovor prervet klient, to znachenie budet ravno 1
exten = s,n,Set(DSC="") ; Dlja formirovanija imeni fajla proslushki. DialStatusClient
exten = s,n,Set(DSD="") ; Dlja formirovanija imeni fajla proslushki. DialStatusDispetcher
exten = s,n,Set(__UNIQUEID_FIRST=${UNIQUEID})
exten = s,n,Set(__STPREFIX=${IF($[${EXISTS(${STPREFIX})} = 0]?${ARG6}:${STPREFIX})})
exten = s,n,Set(__BILLING_CALLS="true")
exten = s,n,Set(__ANSWEREDTIMECALLS=0)
; 1 - Vhodjawij
; 2 - Ishodjawij
; 3 - Lokal'nyj
; 4 - Konsol'nyj
; 5 - Service
; 6 - CallBack
; 7 - IncomingToCallBack (Budet sozdan obratniy vizov.)
; 8 - Autoinformator
exten = s,n,Set(__TYPECALLS="1")
;
; Stroim suffiks dlja monitora: esli suffiks ne zadan, i ekstenshen ne raven s, to podstavljaem otdannyj ekstenshen v suffiks.
; Teper' ponimaem na kakuju liniju prishel zvonok
exten = s,n,Set(ARG7=${IF($["${LEN(${ARG7})}"="0"]?${IF($["${EXTEN}"="s"]?"":-${EXTEN})}:-${ARG7})})
exten = s,n,Set(__STSUFFIX=${IF($[${EXISTS(${STSUFFIX})} = 0]?${ARG7}:-${STSUFFIX})})
;
; Formiruem imja fajla dlja zapisi razgovora
exten = s,n,Set(__BEGIN_FILE_NAME=${IF($["${AIVR}" = "1"]?AUTO:IN)})
exten = s,n,Set(__TOUCH_MONITOR_WOEXT=${BEGIN_FILE_NAME}_${STRFTIME(${EPOCH},,%Y%m%d-%H%M%S)}_${CALLERID(num)})
exten = s,n,Set(__TOUCH_MONITOR=${IF($["${AIVR}" = "1"]?${TOUCH_MONITOR}:${TOUCH_MONITOR_WOEXT}_NotAnswered.wav)})
;
; Start: Obrabotka CallBack vizova
exten = s,n,Set(__CB=${IF($[${EXISTS(${ARG15})} = 0]?0:${IF($["${REGEX("^[0-9]+$" ${ARG15})}"="1"]?${ARG15}:0)})})
exten = s,n,Set(__CB_TIMEOUT=${IF($[${EXISTS(${ARG16})} = 0]?0:${IF($["${REGEX("^[0-9]+$" ${ARG16})}"="1"]?${ARG16}:0)})})
exten = s,n,Set(__CB_EXT=${IF($[${EXISTS(${ARG17})} = 0]?0:${IF($["${REGEX("^[0-9]+$" ${ARG17})}"="1"]?${ARG17}:9000)})})
exten = s,n,Set(__CBN=${IF($[${EXISTS(${CBN})} = 0]?0:${IF($["${REGEX("^[0-9]+$" ${CBN})}"="1"]?${CBN}:0)})})
exten = s,n,Set(__STSUFFIX=${IF($[${CBN}=1]?${CBN_Suffix}:${STSUFFIX})})
exten = s,n,Set(__STPREFIX=${IF($[${CBN}=1]?${CBN_Prefix}:${STPREFIX})})
exten = s,n,Set(__TYPECALLS=${IF($["${CBN}"="1"]?6:${TYPECALLS})})
exten = s,n,Set(__TYPECALLS=${IF($["${AIVR}"="1"]?8:${TYPECALLS})})
; Stop: Obrabotka CallBack vizova
;
; Sobiraem CallerID v pravil'nyj format
exten = s,n,Set(CALLERID_num=${CALLERID(num)})
exten = s,n,Set(CALLERID(num)=${STPREFIX}${CALLERID(num)})
;
; Dobavlyaem v polzovatelskoe pole CLID
exten = s,n,Set(CDR(userfield)=${CALLERID(all)})
;
; Ne vse ponimajut dva statusa 180 Ringing. Odin 180 posylaet asterisk. Jeto vidimo perestrahovka. Esli po dampu ne budet ringa, to raskomentit'
;exten = s,n,Ringing
exten = s,n,GotoIf($["${AIVR}" = "1"]?MixA)
exten = s,n,MixMonitor(${MIXMONITOR_PATH}${TOUCH_MONITOR})
exten = s,n,Goto(AfterMixStart)
exten = s,n(MixA),MixMonitor(${MIXMONITOR_PATH}${TOUCH_MONITOR},a)
;Get CFU key
exten = s,n(AfterMixStart),GotoIf(${EXISTS(${DB(CFU/${ARG1})})}?${ARG5},${DB(CFU/${ARG1})},1)
exten = s,n,Set(ALERT_EXTERNAL=TRUE)
;
; Fiksiruem vremja nachala zvonka. T.e. pered podnjatiem trubki i proigryvaniem privetstvija.
exten = s,n,Set(__STARTCALL=${EPOCH})
;
exten = s,n,GotoIf($[${CB}!=0 && ${CB_TIMEOUT}=0 && ${CBN}!=1]?AfterDial)
;
exten = s,n,TryExec(AGI(/management/bin/Asterisk_bin/robot-ivr.agi.php))
exten = s,n,TryExec(AGI(/management/bin/Asterisk_bin/before-play-hello.agi.php))
;
; Vosproizvodim fajl privetstvija 1
exten = s,n(Audio1),GotoIf($[${EXISTS(${ARG4})} = 0]?AutoInformator)
exten = s,n,GotoIf($["${_noAudio1}" = "1"]?Audio2)
exten = s,n,Answer
exten = s,n,Set(_execanswer=1)
exten = s,n,Wait(${ARG13})
exten = s,n,Set(PLAYAUDIO1=${IF($[${EXISTS(${PLAYAUDIO1})} = 0]?${ARG4}:${PLAYAUDIO1})})
exten = s,n,ExecIf($["${ARG11}" = "0"]?Playback(${PLAYAUDIO1}))
exten = s,n,ExecIf($["${ARG11}" != "0"]?Background(${PLAYAUDIO1}))
;
; Vosproizvedenie zvukovogo invormacionnogo faila klienty
exten = s,n(AutoInformator),TryExec(AGI(/management/bin/Asterisk_bin/after-play-hello.agi.php))
;
; Vosproizvodim fajl privetstvija 2
exten = s,n(Audio2),GotoIf($[${EXISTS(${ARG10})} = 0]?CheckMOH)
exten = s,n,GotoIf($["${_noAudio2}" = "1"]?CheckMOH) ;; Znachenie peremennoi vistavlyaem v predidushih AGI
exten = s,n,GotoIf($["${_execanswer}"="1"]?NoAAudio2)
exten = s,n,Answer
exten = s,n,Set(_execanswer=1)
exten = s,n(NoAAudio2),Set(PLAYAUDIO2=${IF($[${EXISTS(${PLAYAUDIO2})} = 0]?${ARG10}:${PLAYAUDIO2})})
exten = s,n,ExecIf($["${ARG12}" = "0"]?Playback(${PLAYAUDIO2}))
exten = s,n,ExecIf($["${ARG12}" != "0"]?Background(${PLAYAUDIO2}))
;
; Proverjaem MOH (If blank set to empty string, else set to the "m()" flag required by Dial() )
exten = s,n(CheckMOH),GotoIf(${ISNULL(${ARG9})}?NoACheckMOH)
exten = s,n,GotoIf($["${_execanswer}"="1"]?NoACheckMOH)
exten = s,n,Answer
exten = s,n,Set(_execanswer=1)
exten = s,n(NoACheckMOH),Set(MOHCLASS=${IF($[${EXISTS(${MOHCLASS})} = 0]?${IF(${ISNULL(${ARG9})}?:m(${ARG9}))}:m(${MOHCLASS}))})
;
; Fiksiruem vremja, kogda nachalsja zvonok
exten = s,n,Set(__STARTDIAL=${EPOCH})
;
exten = s,n,Set(BITFLAG=${BITFLAG:0:3}1${BITFLAG:4})
;
; Razdaem zvonok podkljuchennym dispetcheram i operatoram
exten = s,n,Dial(${ARG1},${ARG2},${MOHCLASS}twgFM(Set_Answer_Time^${UNIQUEID_FIRST}))
;
; Pogovorili. Ostanavlivaem zapis' razgovorov.
exten = s,n(AfterDial),StopMixMonitor
;
exten = s,n,Set(__STOPDIAL=${EPOCH})
exten = s,n,Set(CALLERID(num)=${CALLERID_num})
exten = s,n,Set(__STARTCALL=${IF($["${STARTCALL}"=""]?${STOPDIAL}:${STARTCALL})})
;
exten = s,n,System(/management/bin/Asterisk_bin/fileexists.sh /tmp/Asterisk_tmp/${UNIQUEID_FIRST})
exten = s,n,ExecIf($["${SYSTEMSTATUS}"="FAILURE" || "${SYSTEMSTATUS}"="APPERROR"]?Goto(NoFile))
exten = s,n,Set(__ANSWEREDTIMECALLS=${FILE(/tmp/Asterisk_tmp/${UNIQUEID_FIRST},0,10)})
exten = s,n,TrySystem(/bin/rm -f /tmp/Asterisk_tmp/${UNIQUEID_FIRST})
exten = s,n,Goto(FileExist)
exten = s,n(NoFile),Set(ANSWEREDTIMECALLS=${STOPDIAL})
;
exten = s,n(FileExist),Set(__STARTDIAL=${IF($["${STARTDIAL}"=""]?${STOPDIAL}:${STARTDIAL})})
exten = s,n,Set(__ANSWEREDTIMECALLS=${IF($["${ANSWEREDTIMECALLS}"=""]?${STOPDIAL}:${ANSWEREDTIMECALLS})})
exten = s,n,Set(__DIALTIME=${MATH(${STOPDIAL}-${ANSWEREDTIMECALLS},i)})
exten = s,n,Set(__DIALTIME=${CUT(DIALTIME,.,1)})
exten = s,n,Set(__CALLTIME=${MATH(${STOPDIAL}-${STARTCALL},i)})
exten = s,n,Set(__CALLTIME=${CUT(CALLTIME,.,1)})
exten = s,n,Set(__WAITFORPLAYHELLO=${MATH(${STARTDIAL}-${STARTCALL},i)})
exten = s,n,Set(__WAITFORPLAYHELLO=${CUT(WAITFORPLAYHELLO,.,1)})
exten = s,n,Set(__WAITFORANSWER=${IF($[${ANSWEREDTIMECALLS}=0 || "${ANSWEREDTIMECALLS}"=""]?0:${MATH(${ANSWEREDTIMECALLS}-${STARTDIAL}-${WAITFORPLAYHELLO},i)})})
exten = s,n,Set(__WAITFORANSWER=${CUT(WAITFORANSWER,.,1)})
exten = s,n,Set(__MYUSERFIELD="")
exten = s,n,Set(DIALSTATUS=${IF($["${DIALSTATUS}"=""]?NOANSWER:${DIALSTATUS})})
;
; Dial return dial status:
; CHANUNAVAIL; CONGESTION; NOANSWER; BUSY; ANSWER; CANCEL; DONTCALL; TORTURE; INVALIDARGS
;
;exten = s,n,Set(DSD=${IF($["${DIALSTATUS}"="ANSWER"]?A:${DSD})})
;exten = s,n,Set(DSD=${IF($["${DIALSTATUS}"="BUSY"]?B:${DSD})})
;exten = s,n,Set(DSD=${IF($["${DIALSTATUS}"="NOANSWER" || "${DIALSTATUS}"="CANCEL" || "${DIALSTATUS}"="CONGESTION" || "${DIALSTATUS}"="CHANUNAVAIL"]?N:${DSD})})
;
;exten = s,n,Set(DSC=${IF($["${CLIENTDROPCALL}"="1"]?C:D)})
;
exten = s,n,Set(DIALTIME=${IF($["${DIALTIME}"=""]?1111:${DIALTIME})})
exten = s,n,Set(WAITFORANSWER=${IF($["${WAITFORANSWER}"=""]?1111:${WAITFORANSWER})})
;
exten = s,n,NoOp(CB=${CB},CBN=${CBN},MATH=${MATH(${WAITFORPLAYHELLO}+${WAITFORANSWER},i)},CB_TIMEOUT=${CB_TIMEOUT})
;
exten = s,n,Set(TOUCH_MONITOR_WOEXT=${IF($[${CB}>0 && ${CBN}!=1 && (${MATH(${WAITFORPLAYHELLO}+${WAITFORANSWER},i)}<=${CB_TIMEOUT} || ${CB_TIMEOUT}=0) && ${DIALTIME}=0]?INtoCB${TOUCH_MONITOR_WOEXT:3}:${TOUCH_MONITOR_WOEXT})})
exten = s,n,Set(TOUCH_MONITOR_WOEXT=${IF($["${TOUCH_MONITOR_WOEXT}"=""]?ErrorAndNotNamedCalls:${TOUCH_MONITOR_WOEXT})})
;
exten = s,n,Set(__FIRST_ANSWER_CALLS=${IF($["${FIRST_ANSWER_CALLS}"=""]?${IF($["${DIALEDPEERNUMBER}"=""]?-1:${DIALEDPEERNUMBER})}:${FIRST_ANSWER_CALLS})})
;
exten = s,n,Set(FILENAME=${TOUCH_MONITOR_WOEXT}_${FIRST_ANSWER_CALLS}_${DIALTIME}_${WAITFORANSWER}${STSUFFIX}.wav)
;
exten = s,n,GotoIf($[${CB}=0 || ${DIALTIME}>0 || ${CBN}=1]?Stat)
exten = s,n,Set(__TYPECALLS=${IF($[${MATH(${WAITFORPLAYHELLO}+${WAITFORANSWER},i)}<=${CB_TIMEOUT}]?7:${TYPECALLS})})
;
;
; Pokazivaem znachenie peremennih kotorie figuriruyt v AGI
exten = s,n(Stat),NoOp(DUMP: UNIQUEID_FIRST=${UNIQUEID_FIRST},DIALSTATUS=${DIALSTATUS},STPREFIX=${STPREFIX},CALLERID(num)=${CALLERID(num)},STSUFFIX=${STSUFFIX},FIRST_ANSWER_CALLS=${FIRST_ANSWER_CALLS},STARTCALL=${STARTCALL},STARTDIAL=${STARTDIAL},ANSWEREDTIMECALLS=${ANSWEREDTIMECALLS},STOPDIAL=${STOPDIAL},CALLTIME=${CALLTIME},WAITFORPLAYHELLO=${WAITFORPLAYHELLO},WAITFORANSWER=${WAITFORANSWER},DIALTIME=${DIALTIME},FILENAME=${FILENAME},__TYPECALLS=${TYPECALLS})
;
exten = s,n,GotoIf($["${TOUCH_MONITOR}"=""]?noRemove)
;
exten = s,n,System(/bin/mv -f /var/spool/asterisk/monitor/${TOUCH_MONITOR} /var/spool/asterisk/monitor/${FILENAME})
exten = s,n,ExecIf($["${SYSTEMSTATUS}"="FAILURE"]?NoOp(System command return error))
;
exten = s,n(noRemove),Set(DIALEND="1") ; Nuzhen dlja togo, chto-by posle perehoda na kontekst h(sbros/otmena zvonka) ne vypolnjat' jetot kod snova
;
; Perehodim na status, kotoryj ustanovilsja po okonchaniju razgovora
exten = s,n,Goto(exit,1)
;
;
exten = exit,1,NoOp(CLIENTDROPCALL=${CLIENTDROPCALL})
exten = exit,n,GotoIf($["${CLIENTDROPCALL}"="1"]?exitdead,1)
exten = exit,n,TryExec(AGI(/management/bin/Asterisk_bin/change_cdr_records.agi.php,noonce))
exten = exit,n,TryExec(AGI(/management/bin/Asterisk_bin/end_of_macros.agi.php,once))
exten = exit,n,ExecIf($[${TYPECALLS}=7 && ${CB_TIMEOUT}=0]?TryExec(Hangup))
exten = exit,n,ExecIf($[${TYPECALLS}=7 && (${CLIENTDROPCALL}=1 || ${CB_TIMEOUT}=0)]?TryExec(AGI(/management/bin/Asterisk_bin/create_callback.agi.php)))
exten = exit,n,TryExec(MacroExit)
exten = exit,n,TryExec(Hangup)
;
exten = exitdead,1,TryExec(AGI(/management/bin/Asterisk_bin/change_cdr_records.agi.php,noonce))
exten = exitdead,n,TryExec(AGI(/management/bin/Asterisk_bin/end_of_macros.agi.php,once))
exten = exitdead,n,ExecIf($[${TYPECALLS}=7 && ${CB_TIMEOUT}=0]?TryExec(Hangup))
exten = exitdead,n,ExecIf($[${TYPECALLS}=7 && (${CLIENTDROPCALL}=1 || ${CB_TIMEOUT}=0)]?TryExec(AGI(/management/bin/Asterisk_bin/create_callback.agi.php)))
exten = exitdead,n,TryExec(MacroExit)
;
;
exten = h,1,NoOp(Drop calls. DialStatus=${DIALSTATUS})
exten = h,n,Set(CLIENTDROPCALL=1)
exten = h,n,GotoIf($[${DIALEND}="1"]?exit,1)
exten = h,n,Set(DIALSTATUS=${IF($["${DIALSTATUS}"=""]?NOANSWER:${DIALSTATUS})})
exten = h,n,Goto(s,AfterDial)
;
exten = failed,1,Set(NumberDialed=${CUT(PassedInfo,,1)})
exten = failed,n,SetCDRUserField(${NumberDialed})
exten = failed,n,TryExec(MacroExit)
;
; END MACROS
{noformat}

Comments: By: Rusty Newton (rnewton) 2015-02-03 18:33:49.460-0600

I believe in this case that the 'h' extension for the *context where the macro is called* would be executed and not the 'h' extension inside the macro.

Can you verify whether the context where the macro is called has an 'h' extension?

If it does, then please attach a debug log of both a working and non-working scenario. The debug log should be collected as described here: https://wiki.asterisk.org/wiki/display/AST/Collecting+Debug+Information

The key being to have both verbose and debug included and set to the levels specified.

If you can, please simplify the dialplan involved to make it easier for us to debug. That is, please only involve one or two phones, no AGI, etc. Remove all unnecessary elements for reproduction.
By: Rusty Newton (rnewton) 2015-02-03 18:34:40.644-0600

Another note, that if we can't figure this out - [Hangup Handlers|https://wiki.asterisk.org/wiki/display/AST/Hangup+Handlers] may be a work-around for you.
By: Matt Jordan (mjordan) 2015-02-21 10:58:53.097-0600

Not only does this not look like a bug - as Rusty pointed out - but there has been no response to his questions. Closing out as "Not a Bug."
By: Belikov Alexander (marintrooper) 2015-03-09 06:00:07.421-0500

I ask forgiveness for protraction. Hangup handler really helped.