В экстеншн с названием call-limit добавил переход на другой контекст:
Код:
Тег Тип Аргумент break inline Группа Приоритет
condition ${call_direction} ^(inbound|outbound)$ 0 5
action set max_calls=1 true 0 6
action limit hash inbound ${domain_uuid} ${max_calls} USER_BUSY XML test.root.sip.telezon.ru 0 20
в контексте test.root.sip.telezon.ru создал экстеншн, с которого направляю вызов в lua скрипт:
Код:
Тег Тип Аргумент break inline Группа Приоритет
condition destination_number USER_BUSY 0 3
action set originate_disposition=USER_BUSY true 0 5
action set originate_causes=${uuid};USER_BUSY true 0 6
action lua app.lua failure_handler 0 13
Вызов обрывается, вместо того, чтобы переадресоваться.
Вот логи (хронология снизу вверх):
Код:
91edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.444282 [DEBUG] switch_core_state_machine.c:751 (sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071) State DESTROY going to sleep
91edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.444282 [DEBUG] switch_core_state_machine.c:181 sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071 Standard DESTROY
91edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.444282 [DEBUG] mod_sofia.c:343 sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071 SOFIA DESTROY
91edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.444282 [DEBUG] switch_core_state_machine.c:751 (sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071) State DESTROY
91edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.444282 [DEBUG] switch_core_state_machine.c:741 (sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071) Running State Change CS_DESTROY (Cur 2 Tot 513)
91edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.444282 [NOTICE] switch_core_session.c:1687 Close Channel sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071 [CS_DESTROY]
91edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.444282 [NOTICE] switch_core_session.c:1683 Session 513 (sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071) Ended
91edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.444282 [DEBUG] switch_core_session.c:1665 Session 513 (sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071) Locked, Waiting on external entities
91edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.444282 [DEBUG] switch_core_state_machine.c:610 (sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071) State Change CS_REPORTING -> CS_DESTROY
91edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.444282 [DEBUG] switch_core_state_machine.c:938 (sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071) State REPORTING going to sleep
91edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.444282 [DEBUG] switch_core_state_machine.c:174 sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071 Standard REPORTING, cause: NORMAL_CLEARING
2017-08-03 14:52:53.364304 [ERR] mod_xml_radius.c:930 Didn't match: inbound == ^inbound
variable_start_stamp: [2091edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.364304 [DEBUG] switch_core_session.c:2815 sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
variable_digits_dialed: [none]
variable_hangup_cause_q850: [16]
variable_hangup_cause: [NORMAL_CLEARING]
variable_originate_disposition: [USER_BUSY]
variable_call_uuid: [91edb049-7fc7-4b55-b061-f68ffcd2d192]
variable_originate_causes: [91edb049-7fc7-4b55-b061-f68ffcd2d192;USER_BUSY]
variable_transfer_source: [1501746773:f9cc6f79-52ec-418d-9b61-455612101d25:bl_xfer:USER_BUSY/test.root.sip.telezon.ru/XML]
variable_max_forwards: [68]
variable_RFC2822_DATE: [Thu, 03 Aug 2017 14:52:53 +0700]
variable_export_vars: [origination_callee_id_name]
variable_origination_callee_id_name: [2197828]
variable_from_user_exists: [false]
variable_max_calls: [1]
variable_DP_MATCH: [ARRAY::inbound|:inbound]
variable_user_exists: [false]
variable_transfer_history: [ARRAY::1501746773:70ebd484-a3c7-403b-b187-3ce31868e648:bl_xfer:2197828/root.sip.telezon.ru/XML|:1501746773:f9cc6f79-52ec-418d-9b61-455612101d25:bl_xfer:USER_BUSY/test.root.sip.telezon.ru/XML]
variable_domain_name: [root.sip.telezon.ru]
variable_domain_uuid: [072b7d42-22c3-410a-8754-f37228f0a69b]
variable_call_direction: [inbound]
variable_endpoint_disposition: [RECEIVED]
variable_dtmf_type: [rfc2833]
variable_write_rate: [8000]
variable_write_codec: [PCMA]
variable_original_read_rate: [8000]
variable_read_rate: [8000]
variable_original_read_codec: [PCMA]
variable_read_codec: [PCMA]
variable_rtp_last_audio_codec_string: [PCMA@8000h@20i@1c]
variable_rtp_use_codec_channels: [1]
variable_rtp_use_codec_ptime: [20]
variable_rtp_use_codec_rate: [8000]
variable_rtp_use_codec_name: [PCMA]
variable_rtp_audio_recv_pt: [8]
variable_remote_media_port: [30464]
variable_remote_media_ip: [10.248.0.6]
variable_audio_media_flow: [sendrecv]
variable_rtp_use_codec_string: [G7221@32000h,G7221@16000h,G722,PCMU,PCMA,GSM]
]
a=silenceSupp:off - - - -
a=ptime:20
a=fmtp:101 0-15
a=rtpmap:101 telephone-event/8000
a=fmtp:18 annexb=no
a=rtpmap:18 G729/8000
a=rtpmap:8 PCMA/8000
m=audio 30464 RTP/AVP 8 18 101
t=0 0
91edb049-7fc7-4b55-b061-f68ffcd2d192 c=IN IP4 10.248.0.6
91edb049-7fc7-4b55-b061-f68ffcd2d192 s=-
91edb049-7fc7-4b55-b061-f68ffcd2d192 o=- 1501746773 1501746773 IN IP4 10.248.0.6
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_switch_r_sdp: [v=0
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_sip_via_rport: [5071]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_sip_via_port: [5071]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_sip_via_host: [10.248.0.6]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_sip_user_agent: [TS-v4.5.1-18cW]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_sip_contact_host: [10.248.0.6]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_sip_contact_uri: [3912458888@10.248.0.6:5071]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_sip_contact_port: [5071]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_sip_contact_user: [3912458888]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_sip_contact_params: [user=phone]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_sip_to_host: [10.248.0.5]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_sip_to_uri: [3912197828@10.248.0.5:5080]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_sip_to_port: [5080]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_sip_to_user: [3912197828]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_sip_to_params: [user=phone]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_sip_req_host: [10.248.0.5]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_sip_req_uri: [3912197828@10.248.0.5:5080]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_sip_req_port: [5080]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_sip_req_user: [3912197828]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_sip_req_params: [user=phone]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_sip_allow: [ACK, BYE, CANCEL, INFO, INVITE, OPTIONS, REFER, REGISTER, SUBSCRIBE, UPDATE]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_sip_full_to: [<sip:3912197828@10.248.0.5:5080;user=phone>]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_sip_full_from: [<sip:3912458888@10.248.0.6:5071;user=phone>;tag=1122111681-3876659320-620771245-1827378832]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_sip_full_via: [SIP/2.0/UDP 10.248.0.6:5071;rport=5071;branch=z9hG4bK-2654804161-3876659320-620771501-1827378832]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_recovery_profile_name: [10_248_0_5-5080]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_sofia_profile_name: [10_248_0_5-5080]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_sip_from_tag: [1122111681-3876659320-620771245-1827378832]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_sip_from_user_stripped: [3912458888]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_sip_via_protocol: [udp]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_sip_received_port: [5071]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_sip_received_ip: [10.248.0.6]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_sip_invite_stamp: [1501746773304290]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_sip_network_port: [5071]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_sip_network_ip: [10.248.0.6]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_sip_local_network_addr: [10.248.0.5]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_ep_codec_string: [CORE_PCM_MODULE.PCMA@8000h@20i@64000b]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_sip_call_id: [BFF11D01DE163488A9E73237C399D5C7]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_channel_name: [sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_video_media_flow: [sendrecv]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_sip_from_host: [10.248.0.6]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_sip_from_uri: [3912458888@10.248.0.6:5071]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_sip_from_port: [5071]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_sip_from_user: [3912458888]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_sip_from_params: [user=phone]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_session_id: [513]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_uuid: [91edb049-7fc7-4b55-b061-f68ffcd2d192]
91edb049-7fc7-4b55-b061-f68ffcd2d192 variable_direction: [inbound]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Caller-Privacy-Hide-Number: [false]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Caller-Privacy-Hide-Name: [false]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Caller-Screen-Bit: [true]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Caller-Channel-Hold-Accum: [0]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Caller-Channel-Last-Hold: [0]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Caller-Channel-Bridged-Time: [0]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Caller-Channel-Resurrect-Time: [0]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Caller-Channel-Transfer-Time: [0]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Caller-Channel-Hangup-Time: [1501746773364304]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Caller-Channel-Progress-Media-Time: [0]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Caller-Channel-Progress-Time: [0]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Caller-Channel-Answered-Time: [0]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Caller-Channel-Created-Time: [1501746773304290]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Caller-Profile-Created-Time: [1501746773364304]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Caller-Profile-Index: [3]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Caller-Channel-Name: [sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Caller-RDNIS: [2197828]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Caller-Context: [test.root.sip.telezon.ru]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Caller-Transfer-Source: [1501746773:f9cc6f79-52ec-418d-9b61-455612101d25:bl_xfer:USER_BUSY/test.root.sip.telezon.ru/XML]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Caller-Source: [mod_sofia]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Caller-Unique-ID: [91edb049-7fc7-4b55-b061-f68ffcd2d192]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Caller-Destination-Number: [USER_BUSY]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Caller-ANI: [3912458888]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Caller-Network-Addr: [10.248.0.6]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Caller-Orig-Caller-ID-Number: [3912458888]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Caller-Orig-Caller-ID-Name: [3912458888]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Caller-Caller-ID-Number: [3912458888]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Caller-Caller-ID-Name: [3912458888]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Caller-Dialplan: [XML]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Caller-Username: [3912458888]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Caller-Logical-Direction: [inbound]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Caller-Direction: [inbound]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Channel-Write-Codec-Bit-Rate: [64000]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Channel-Write-Codec-Rate: [8000]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Channel-Write-Codec-Name: [PCMA]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Channel-Read-Codec-Bit-Rate: [64000]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Channel-Read-Codec-Rate: [8000]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Channel-Read-Codec-Name: [PCMA]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Hangup-Cause: [NORMAL_CLEARING]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Answer-State: [hangup]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Channel-Call-UUID: [91edb049-7fc7-4b55-b061-f68ffcd2d192]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Channel-HIT-Dialplan: [true]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Presence-Call-Direction: [inbound]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Call-Direction: [inbound]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Unique-ID: [91edb049-7fc7-4b55-b061-f68ffcd2d192]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Channel-Name: [sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Channel-State-Number: [11]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Channel-Call-State: [HANGUP]
91edb049-7fc7-4b55-b061-f68ffcd2d192 Channel-State: [CS_REPORTING]
91edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.364304 [INFO] mod_dptools.c:1761 CHANNEL_DATA:
91edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.364304 [DEBUG] switch_core_session.c:2800 sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071 skip receive message [APPLICATION_EXEC] (channel is hungup already)
91edb049-7fc7-4b55-b061-f68ffcd2d192 EXECUTE sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071 info()
91edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.364304 [DEBUG] switch_core_session.c:2605 sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071 ZOMBIE EXEC info()
2017-08-03 14:52:53.364304 [DEBUG] mod_xml_radius.c:1012 mod_xml_radius: starting accounting stop
91edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.364304 [DEBUG] switch_core_state_machine.c:938 (sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071) State REPORTING
91edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.364304 [DEBUG] switch_core_state_machine.c:584 (sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071) Running State Change CS_REPORTING (Cur 3 Tot 513)
91edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.364304 [DEBUG] switch_core_state_machine.c:619 (sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071) State Change CS_HANGUP -> CS_REPORTING
91edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.364304 [DEBUG] switch_core_state_machine.c:852 (sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071) State HANGUP going to sleep
91edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.364304 [DEBUG] switch_core_state_machine.c:60 sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071 Standard HANGUP, cause: NORMAL_CLEARING
91edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.364304 [DEBUG] mod_sofia.c:577 Responding to INVITE with: 480
91edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.364304 [DEBUG] mod_sofia.c:438 Channel sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071 hanging up, cause: NORMAL_CLEARING
91edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.364304 [DEBUG] switch_core_state_machine.c:852 (sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071) State HANGUP
91edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.364304 [DEBUG] switch_core_state_machine.c:850 (sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071) Callstate Change RINGING -> HANGUP
91edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.364304 [DEBUG] switch_core_state_machine.c:584 (sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071) Running State Change CS_HANGUP (Cur 3 Tot 513)
91edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.364304 [DEBUG] switch_core_state_machine.c:650 (sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071) State EXECUTE going to sleep
91edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.364304 [NOTICE] switch_core_state_machine.c:387 Hangup sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071 [CS_EXECUTE] [NORMAL_CLEARING]
91edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.364304 [NOTICE] switch_core_state_machine.c:385 sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071 has executed the last dialplan instruction, hanging up.
91edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.364304 [DEBUG] switch_cpp.cpp:1112 sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071 destroy/unlink session from object
2017-08-03 14:52:53.364304 [INFO] switch_cpp.cpp:1365 [failure_handler] last_busy_dialed_extension: nil
91edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.364304 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(originate_disposition, USER_BUSY)
2017-08-03 14:52:53.364304 [INFO] switch_cpp.cpp:1365 [failure_handler] busy_forward: nil
2017-08-03 14:52:53.364304 [INFO] switch_cpp.cpp:1365 [failure_handler] sip_code: nil
2017-08-03 14:52:53.364304 [INFO] switch_cpp.cpp:1365 [failure_handler] hangup_on_call_reject: nil
2017-08-03 14:52:53.364304 [INFO] switch_cpp.cpp:1365 [failure_handler] hangup_on_subscriber_absent: nil
2017-08-03 14:52:53.364304 [INFO] switch_cpp.cpp:1365 [failure_handler] originate_disposition: USER_BUSY
2017-08-03 14:52:53.364304 [INFO] switch_cpp.cpp:1365 [failure_handler] originate_causes: 91edb049-7fc7-4b55-b061-f68ffcd2d192;USER_BUSY
91edb049-7fc7-4b55-b061-f68ffcd2d192 EXECUTE sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071 lua(app.lua failure_handler)
91edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.364304 [DEBUG] switch_core_state_machine.c:328 sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071 Standard EXECUTE
91edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.364304 [DEBUG] mod_sofia.c:198 sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071 SOFIA EXECUTE
91edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.364304 [DEBUG] switch_core_state_machine.c:650 (sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071) State EXECUTE
91edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.364304 [DEBUG] switch_core_state_machine.c:584 (sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071) Running State Change CS_EXECUTE (Cur 3 Tot 513)
91edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.364304 [DEBUG] switch_core_state_machine.c:643 (sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071) State ROUTING going to sleep
91edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.364304 [DEBUG] switch_core_state_machine.c:286 (sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071) State Change CS_ROUTING -> CS_EXECUTE
91edb049-7fc7-4b55-b061-f68ffcd2d192 Dialplan: sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071 Regex (FAIL) [talking clock date] destination_number(USER_BUSY) =~ /^\*9171$/ break=on-false
91edb049-7fc7-4b55-b061-f68ffcd2d192 Dialplan: sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071 parsing [test.root.sip.telezon.ru->talking clock date] continue=true
91edb049-7fc7-4b55-b061-f68ffcd2d192 Dialplan: sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071 Regex (FAIL) [talking clock time] destination_number(USER_BUSY) =~ /^\*9170$/ break=on-false
91edb049-7fc7-4b55-b061-f68ffcd2d192 Dialplan: sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071 parsing [test.root.sip.telezon.ru->talking clock time] continue=true
91edb049-7fc7-4b55-b061-f68ffcd2d192 Dialplan: sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071 Regex (FAIL) [talking clock date and time] destination_number(USER_BUSY) =~ /^\*9172$/ break=on-false
91edb049-7fc7-4b55-b061-f68ffcd2d192 Dialplan: sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071 parsing [test.root.sip.telezon.ru->talking clock date and time] continue=true
91edb049-7fc7-4b55-b061-f68ffcd2d192 Dialplan: sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071 Action lua(app.lua failure_handler)
91edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.364304 [DEBUG] mod_dptools.c:1548 SET sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071 [originate_causes]=[91edb049-7fc7-4b55-b061-f68ffcd2d192;USER_BUSY]
91edb049-7fc7-4b55-b061-f68ffcd2d192 EXECUTE sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071 set(originate_causes=91edb049-7fc7-4b55-b061-f68ffcd2d192;USER_BUSY)
91edb049-7fc7-4b55-b061-f68ffcd2d192 Dialplan: sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071 Action set(originate_causes=${uuid};USER_BUSY) INLINE
91edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.364304 [DEBUG] mod_dptools.c:1548 SET sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071 [originate_disposition]=[USER_BUSY]
91edb049-7fc7-4b55-b061-f68ffcd2d192 EXECUTE sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071 set(originate_disposition=USER_BUSY)
91edb049-7fc7-4b55-b061-f68ffcd2d192 Dialplan: sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071 Action set(originate_disposition=USER_BUSY) INLINE
91edb049-7fc7-4b55-b061-f68ffcd2d192 Dialplan: sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071 Regex (PASS) [user_busy_test] destination_number(USER_BUSY) =~ /USER_BUSY/ break=on-false
91edb049-7fc7-4b55-b061-f68ffcd2d192 Dialplan: sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071 parsing [test.root.sip.telezon.ru->user_busy_test] continue=true
2017-08-03 14:52:53.364304 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f7b140725c0 released.
2017-08-03 14:52:53.364304 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f7b140725c0 Connected.
91edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.364304 [INFO] mod_dialplan_xml.c:637 Processing 3912458888 <3912458888>->USER_BUSY in context test.root.sip.telezon.ru
91edb049-7fc7-4b55-b061-f68ffcd2d192 2017-08-03 14:52:53.364304 [DEBUG] switch_core_state_machine.c:236 sofia/10_248_0_5-5080/3912458888@10.248.0.6:5071 Standard ROUTING