Intermittent Voicemail Issue - 5 secs

Status
Not open for further replies.
R

Robert Birch

Member
Mar 16, 2017
111
4
18
52
I am having an intermittent issue with voicemail.

Every now and then, when someone tries to leave a voicemail, it hangs up after 5 seconds. The resultant voicemail is emailed to the extension owner, but contains no audio.

Looking at the CDR for the call, it looks like everything is correct.

I am including the information from the log for the call. I have changed out the unique IPs/numbers from the log.

Thanks for the help,
Robert Birch


Code: 
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.011781 [NOTICE] switch_channel.c:1104 New Channel sofia/external/<Incoming Number>@<Gateway IP> [7cdb1fcc-e9ef-4629-9a86-7e15a783f97a]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.011781 [DEBUG] switch_core_state_machine.c:584 (sofia/external/<Incoming Number>@<Gateway IP>) Running State Change CS_NEW (Cur 5 Tot 140461)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.011781 [DEBUG] sofia.c:9873 sofia/external/<Incoming Number>@<Gateway IP> receiving invite from <Gateway IP>:5060 version: 1.6.19 -36-7a77e0b 64bit
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.011781 [DEBUG] sofia.c:7084 Channel sofia/external/<Incoming Number>@<Gateway IP> entering state [received][100]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.011781 [DEBUG] sofia.c:7094 Remote SDP:
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a v=0
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a o=les.net 994487267 994487267 IN IP4 <Gateway IP>
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a s=LES.NET VoIP
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a c=IN IP4 <Gateway IP>
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a t=0 0
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a m=audio 16206 RTP/AVP 0 101
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a a=rtpmap:0 PCMU/8000
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a a=rtpmap:101 telephone-event/8000
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a a=fmtp:101 0-16
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a a=ptime:20
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a a=maxptime:150
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.011781 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.011781 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.011781 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.011781 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.011781 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.011781 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.011781 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.011781 [DEBUG] switch_core_media.c:3061 Set Codec sofia/external/<Incoming Number>@<Gateway IP> PCMU/8000 20 ms 160 samples 64000 bits 1 channels
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.011781 [DEBUG] switch_core_codec.c:111 sofia/external/<Incoming Number>@<Gateway IP> Original read codec set to PCMU:0
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.011781 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.011781 [DEBUG] switch_core_media.c:4767 sofia/external/<Incoming Number>@<Gateway IP> Set 2833 dtmf send payload to 101 recv payload to 101
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.011781 [DEBUG] sofia.c:7507 (sofia/external/<Incoming Number>@<Gateway IP>) State Change CS_NEW -> CS_INIT
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.011781 [DEBUG] switch_core_state_machine.c:603 (sofia/external/<Incoming Number>@<Gateway IP>) State NEW
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.011781 [DEBUG] switch_core_state_machine.c:584 (sofia/external/<Incoming Number>@<Gateway IP>) Running State Change CS_INIT (Cur 5 Tot 140461)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.011781 [DEBUG] switch_core_state_machine.c:627 (sofia/external/<Incoming Number>@<Gateway IP>) State INIT
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.011781 [DEBUG] mod_sofia.c:90 sofia/external/<Incoming Number>@<Gateway IP> SOFIA INIT
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.011781 [DEBUG] switch_core_state_machine.c:40 sofia/external/<Incoming Number>@<Gateway IP> Standard INIT
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.011781 [DEBUG] switch_core_state_machine.c:48 (sofia/external/<Incoming Number>@<Gateway IP>) State Change CS_INIT -> CS_ROUTING
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.011781 [DEBUG] switch_core_state_machine.c:627 (sofia/external/<Incoming Number>@<Gateway IP>) State INIT going to sleep
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.011781 [DEBUG] switch_core_state_machine.c:584 (sofia/external/<Incoming Number>@<Gateway IP>) Running State Change CS_ROUTING (Cur 5 Tot 140461)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.011781 [DEBUG] switch_channel.c:2249 (sofia/external/<Incoming Number>@<Gateway IP>) Callstate Change DOWN -> RINGING
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.011781 [DEBUG] switch_core_state_machine.c:643 (sofia/external/<Incoming Number>@<Gateway IP>) State ROUTING
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.011781 [DEBUG] mod_sofia.c:143 sofia/external/<Incoming Number>@<Gateway IP> SOFIA ROUTING
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.011781 [DEBUG] switch_core_state_machine.c:236 sofia/external/<Incoming Number>@<Gateway IP> Standard ROUTING
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.011781 [INFO] mod_dialplan_xml.c:637 Processing <<Incoming Number>>-><DID Number> in context public
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [public-><DID Number>] continue=false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (PASS) [<DID Number>] destination_number(<DID Number>) =~ /^(<DID Number>)$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action set(call_direction=inbound) INLINE
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> set(call_direction=inbound)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.021765 [DEBUG] mod_dptools.c:1548 SET sofia/external/<Incoming Number>@<Gateway IP> [call_direction]=[inbound]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action set(domain_uuid=dd696592-c948-43af-a6db-78ecf0aa13d2) INLINE
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> set(domain_uuid=dd696592-c948-43af-a6db-78ecf0aa13d2)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.021765 [DEBUG] mod_dptools.c:1548 SET sofia/external/<Incoming Number>@<Gateway IP> [domain_uuid]=[dd696592-c948-43af-a6db-78ecf0aa13d2]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action set(domain_name=<VOIP Server>) INLINE
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> set(domain_name=<VOIP Server>)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.021765 [DEBUG] mod_dptools.c:1548 SET sofia/external/<Incoming Number>@<Gateway IP> [domain_name]=[<VOIP Server>]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action transfer(306 XML <VOIP Server>)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.021765 [DEBUG] switch_core_state_machine.c:286 (sofia/external/<Incoming Number>@<Gateway IP>) State Change CS_ROUTING -> CS_EXECUTE
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.021765 [DEBUG] switch_core_state_machine.c:643 (sofia/external/<Incoming Number>@<Gateway IP>) State ROUTING going to sleep
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.021765 [DEBUG] switch_core_state_machine.c:584 (sofia/external/<Incoming Number>@<Gateway IP>) Running State Change CS_EXECUTE (Cur 5 Tot 140461)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.021765 [DEBUG] switch_core_state_machine.c:650 (sofia/external/<Incoming Number>@<Gateway IP>) State EXECUTE
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.021765 [DEBUG] mod_sofia.c:198 sofia/external/<Incoming Number>@<Gateway IP> SOFIA EXECUTE
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.021765 [DEBUG] switch_core_state_machine.c:328 sofia/external/<Incoming Number>@<Gateway IP> Standard EXECUTE
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> transfer(306 XML <VOIP Server>)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.021765 [DEBUG] switch_ivr.c:2165 (sofia/external/<Incoming Number>@<Gateway IP>) State Change CS_EXECUTE -> CS_ROUTING
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.021765 [NOTICE] switch_ivr.c:2172 Transfer sofia/external/<Incoming Number>@<Gateway IP> to XML[306@<VOIP Server>]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.021765 [DEBUG] switch_core_state_machine.c:650 (sofia/external/<Incoming Number>@<Gateway IP>) State EXECUTE going to sleep
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.021765 [DEBUG] switch_core_state_machine.c:584 (sofia/external/<Incoming Number>@<Gateway IP>) Running State Change CS_ROUTING (Cur 5 Tot 140461)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.021765 [DEBUG] switch_core_state_machine.c:643 (sofia/external/<Incoming Number>@<Gateway IP>) State ROUTING
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.021765 [DEBUG] mod_sofia.c:143 sofia/external/<Incoming Number>@<Gateway IP> SOFIA ROUTING
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.021765 [DEBUG] switch_core_state_machine.c:236 sofia/external/<Incoming Number>@<Gateway IP> Standard ROUTING
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.021765 [INFO] mod_dialplan_xml.c:637 Processing Lonnie Atchison <<Incoming Number>>->306 in context <VOIP Server>
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->user_exists] continue=true
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (PASS) [user_exists] () =~ // break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> set(user_exists=true)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.031765 [DEBUG] mod_dptools.c:1548 SET sofia/external/<Incoming Number>@<Gateway IP> [user_exists]=[true]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (PASS) [user_exists] ${user_exists}(true) =~ /^true$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action set(extension_uuid=${user_data ${destination_number}@${domain_name} var extension_uuid})
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action set(hold_music=${user_data ${destination_number}@${domain_name} var hold_music})
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action set(forward_all_enabled=${user_data ${destination_number}@${domain_name} var forward_all_enabled})
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action set(forward_all_destination=${user_data ${destination_number}@${domain_name} var forward_all_destination})
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action set(forward_busy_enabled=${user_data ${destination_number}@${domain_name} var forward_busy_enabled})
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action set(forward_busy_destination=${user_data ${destination_number}@${domain_name} var forward_busy_destination})
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action set(forward_no_answer_enabled=${user_data ${destination_number}@${domain_name} var forward_no_answer_enabled})
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action set(forward_no_answer_destination=${user_data ${destination_number}@${domain_name} var forward_no_answer_destination})
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action set(forward_user_not_registered_enabled=${user_data ${destination_number}@${domain_name} var forward_user_not_registered_enabled})
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action set(forward_user_not_registered_destination=${user_data ${destination_number}@${domain_name} var forward_user_not_registered_destination})
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action set(do_not_disturb=${user_data ${destination_number}@${domain_name} var do_not_disturb})
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action set(call_timeout=${user_data ${destination_number}@${domain_name} var call_timeout})
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action set(missed_call_app=${user_data ${destination_number}@${domain_name} var missed_call_app})
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action set(missed_call_data=${user_data ${destination_number}@${domain_name} var missed_call_data})
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action set(call_screen_enabled=${user_data ${destination_number}@${domain_name} var call_screen_enabled}) INLINE
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> set(call_screen_enabled=false)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.031765 [DEBUG] mod_dptools.c:1548 SET sofia/external/<Incoming Number>@<Gateway IP> [call_screen_enabled]=[false]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->call-direction] continue=true
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (PASS) [call-direction] ${call_direction}(inbound) =~ /^(inbound|outbound|local)$/ break=never
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->variables] continue=true
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (PASS) [variables] () =~ // break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action export(origination_callee_id_name=${destination_number})
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->user_record] continue=true
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (PASS) [user_record] () =~ // break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action set(user_record=${user_data ${destination_number}@${domain_name} var user_record}) INLINE
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> set(user_record=all)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.031765 [DEBUG] mod_dptools.c:1548 SET sofia/external/<Incoming Number>@<Gateway IP> [user_record]=[all]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> set(from_user_exists=false)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.051764 [DEBUG] mod_dptools.c:1548 SET sofia/external/<Incoming Number>@<Gateway IP> [from_user_exists]=[false]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (PASS) [user_record] ${user_record}(all) =~ /^all$/ break=never
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action set(record_session=true) INLINE
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> set(record_session=true)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.051764 [DEBUG] mod_dptools.c:1548 SET sofia/external/<Incoming Number>@<Gateway IP> [record_session]=[true]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [user_record] ${user_record}(all) =~ /^inbound$/ break=never
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [user_record] ${user_record}(all) =~ /^outbound$/ break=never
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [user_record] ${user_record}(all) =~ /^local$/ break=never
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (PASS) [user_record] ${record_session}(true) =~ /^true$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action export(nolocal:api_on_answer=uuid_record ${uuid} start ${recordings_dir}/${domain_name}/archive/${strftime(%Y)}/${strftime(%b)}/${strftime(%d)}/${uuid}.${record_ext})
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->redial] continue=true
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [redial] destination_number(306) =~ /^(redial|\*870)$/ break=on-true
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (PASS) [redial] () =~ // break=never
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->speed_dial] continue=false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [speed_dial] destination_number(306) =~ /^\*0(.*)$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->Lesnet_Main_Line.1d10] continue=false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [Lesnet_Main_Line.1d10] destination_number(306) =~ /^\+?1?(\d{10})$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->Lesnet_Main_Line.911] continue=false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [Lesnet_Main_Line.911] destination_number(306) =~ /^(911)$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->agent_status] continue=false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [agent_status] destination_number(306) =~ /^\*22$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->agent_status_id] continue=false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [agent_status_id] destination_number(306) =~ /^\*23$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->group-intercept] continue=false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [group-intercept] destination_number(306) =~ /^\*8$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->page-extension] continue=false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [page-extension] destination_number(306) =~ /^\*8(\d{2,7})$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->eavesdrop] continue=false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [eavesdrop] destination_number(306) =~ /^\*33(\d{2,7})$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->call_privacy] continue=false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [call_privacy] destination_number(306) =~ /^\*67(\d+)$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->call_return] continue=false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [call_return] destination_number(306) =~ /^\*69$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->extension_queue] continue=false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [extension_queue] destination_number(306) =~ /^\*800(.*)$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->intercept-ext] continue=false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [intercept-ext] destination_number(306) =~ /^\*\*(\d+)$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->intercept-ext-polycom] continue=false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [intercept-ext-polycom] destination_number(306) =~ /^\*97(\d+)$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->dx] continue=false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [dx] destination_number(306) =~ /^dx$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->att_xfer] continue=false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [att_xfer] destination_number(306) =~ /^att_xfer$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->extension-to-voicemail] continue=false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (PASS) [extension-to-voicemail] username(<Incoming Number>) =~ /^<Incoming Number>$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [extension-to-voicemail] destination_number(306) =~ /^<Incoming Number>$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->send_to_voicemail] continue=false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [send_to_voicemail] destination_number(306) =~ /^\*99(\d{2,10})$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->vmain] continue=false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [vmain] destination_number(306) =~ /^vmain$|^\*4000$|^\*98$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->xfer_vm] continue=false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [xfer_vm] destination_number(306) =~ /^xfer_vm$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->is_transfer] continue=false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [is_transfer] destination_number(306) =~ /^is_transfer$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->vmain_user] continue=false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [vmain_user] destination_number(306) =~ /^\*97$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->MainIVR] continue=
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [MainIVR] destination_number(306) =~ /^1000$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->SchuettRingGroup] continue=
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [SchuettRingGroup] destination_number(306) =~ /^2000$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->cf] continue=false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [cf] destination_number(306) =~ /^cf$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->delay_echo] continue=false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [delay_echo] destination_number(306) =~ /^\*9195$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->echo] continue=false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [echo] destination_number(306) =~ /^\*9196$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->is_zrtp_secure] continue=true
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [is_zrtp_secure] ${zrtp_secure_media_confirmed}() =~ /^true$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> ANTI-Action eval(not_secure)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->milliwatt] continue=false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [milliwatt] destination_number(306) =~ /^\*9197$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->is_secure] continue=true
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [is_secure] ${sip_via_protocol}(udp) =~ /tls/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->tone_stream] continue=false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [tone_stream] destination_number(306) =~ /^\*9198$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->hold_music] continue=false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [hold_music] destination_number(306) =~ /^\*9664$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->recordings] continue=false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [recordings] destination_number(306) =~ /^\*(732)$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->directory] continue=false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [directory] destination_number(306) =~ /^\*411$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->wake-up] continue=false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [wake-up] destination_number(306) =~ /^\*(925)$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->valet_park] continue=false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [valet_park] destination_number(306) =~ /^(park\+)?(\*59[0-9][0-9])$/ break=never
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [valet_park] ${sip_h_Referred-By}() =~ /sip:(.*)@.*/ break=never
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [valet_park] destination_number(306) =~ /^(park\+)?(\*59[0-9][0-9])$/ break=never
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [valet_park] destination_number(306) =~ /^(park\+)?(\*59[0-9][0-9])$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->operator] continue=false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [operator] destination_number(306) =~ /^0$|^operator$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->operator-forward] continue=false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [operator-forward] destination_number(306) =~ /^\*000$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->do-not-disturb] continue=false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [do-not-disturb] destination_number(306) =~ /^\*77$/ break=on-true
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [do-not-disturb] destination_number(306) =~ /^\*78$|\*363$/ break=on-true
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [do-not-disturb] destination_number(306) =~ /^\*79$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->call-forward] continue=false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [call-forward] destination_number(306) =~ /^\*72$/ break=on-true
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [call-forward] destination_number(306) =~ /^\*73$/ break=on-true
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [call-forward] destination_number(306) =~ /^\*74$/ break=on-true
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->call forward all] continue=false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (PASS) [call forward all] ${user_exists}(true) =~ /^true/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [call forward all] ${forward_all_enabled}() =~ /^true/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->follow-me] continue=false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [follow-me] destination_number(306) =~ /^\*21$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->clear_sip_auto_answer] continue=true
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [clear_sip_auto_answer] ${click_to_call}() =~ /true/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->talking clock date and time] continue=true
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [talking clock date and time] destination_number(306) =~ /^\*9172$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->talking clock time] continue=true
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [talking clock time] destination_number(306) =~ /^\*9170$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->talking clock date] continue=true
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [talking clock date] destination_number(306) =~ /^\*9171$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->call_screen] continue=true
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [call_screen] ${call_screen_enabled}(false) =~ /^true$/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->local_extension] continue=true
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (PASS) [local_extension] ${user_exists}(true) =~ /true/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action export(dialed_extension=${destination_number}) INLINE
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> export(dialed_extension=306)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.051764 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [dialed_extension]=[306]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action limit(hash ${domain_name} ${destination_number} ${limit_max} ${limit_destination})
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (FAIL) [local_extension] ${sip_authorized}() =~ /true/ break=never
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> ANTI-Action set(bind_target=peer) INLINE
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> set(bind_target=peer)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.051764 [DEBUG] mod_dptools.c:1548 SET sofia/external/<Incoming Number>@<Gateway IP> [bind_target]=[peer]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (PASS) [local_extension] () =~ // break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action bind_digit_action(local,*1,exec:execute_extension,dx XML ${context},${bind_target})
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action bind_digit_action(local,*2,exec:record_session,${recordings_dir}/${domain_name}/archive/${strftime(%Y)}/${strftime(%b)}/${strftime(%d)}/${uuid}.${record_ext},${bind_target})
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action bind_digit_action(local,*3,exec:execute_extension,cf XML ${context},${bind_target})
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action bind_digit_action(local,*4,exec:execute_extension,att_xfer XML ${context},${bind_target})
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action digit_action_set_realm(local)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (PASS) [local_extension] () =~ // break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action set(hangup_after_bridge=true)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action set(continue_on_fail=true)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number})
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid})
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action set(called_party_call_group=${user_data(${dialed_extension}@${domain_name} var call_group)})
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action hash(insert/${domain_name}-last_dial/${called_party_call_group}/${uuid})
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action set(api_hangup_hook=lua app.lua hangup)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action export(domain_name=${context})
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action bridge(user/${destination_number}@${domain_name})
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action lua(app.lua failure_handler)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> parsing [<VOIP Server>->voicemail] continue=false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Regex (PASS) [voicemail] ${user_exists}(true) =~ /true/ break=on-false
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action answer()
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action sleep(1000)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action set(voicemail_action=save)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action set(voicemail_id=${destination_number})
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action set(voicemail_profile=default)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a Dialplan: sofia/external/<Incoming Number>@<Gateway IP> Action lua(app.lua voicemail)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.051764 [DEBUG] switch_core_state_machine.c:286 (sofia/external/<Incoming Number>@<Gateway IP>) State Change CS_ROUTING -> CS_EXECUTE
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.051764 [DEBUG] switch_core_state_machine.c:643 (sofia/external/<Incoming Number>@<Gateway IP>) State ROUTING going to sleep
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.051764 [DEBUG] switch_core_state_machine.c:584 (sofia/external/<Incoming Number>@<Gateway IP>) Running State Change CS_EXECUTE (Cur 5 Tot 140461)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.051764 [DEBUG] switch_core_state_machine.c:650 (sofia/external/<Incoming Number>@<Gateway IP>) State EXECUTE
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.051764 [DEBUG] mod_sofia.c:198 sofia/external/<Incoming Number>@<Gateway IP> SOFIA EXECUTE
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.051764 [DEBUG] switch_core_state_machine.c:328 sofia/external/<Incoming Number>@<Gateway IP> Standard EXECUTE
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> set(extension_uuid=3412f881-693b-4dda-b91f-aa362c7dd429)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.061772 [DEBUG] mod_dptools.c:1548 SET sofia/external/<Incoming Number>@<Gateway IP> [extension_uuid]=[3412f881-693b-4dda-b91f-aa362c7dd429]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> set(hold_music=local_stream://default)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.061772 [DEBUG] mod_dptools.c:1548 SET sofia/external/<Incoming Number>@<Gateway IP> [hold_music]=[local_stream://default]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> set(forward_all_enabled=false)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.061772 [DEBUG] mod_dptools.c:1548 SET sofia/external/<Incoming Number>@<Gateway IP> [forward_all_enabled]=[false]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> set(forward_all_destination=)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.071758 [DEBUG] mod_dptools.c:1548 SET sofia/external/<Incoming Number>@<Gateway IP> [forward_all_destination]=[UNDEF]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> set(forward_busy_enabled=)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.071758 [DEBUG] mod_dptools.c:1548 SET sofia/external/<Incoming Number>@<Gateway IP> [forward_busy_enabled]=[UNDEF]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> set(forward_busy_destination=)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.071758 [DEBUG] mod_dptools.c:1548 SET sofia/external/<Incoming Number>@<Gateway IP> [forward_busy_destination]=[UNDEF]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> set(forward_no_answer_enabled=)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.081763 [DEBUG] mod_dptools.c:1548 SET sofia/external/<Incoming Number>@<Gateway IP> [forward_no_answer_enabled]=[UNDEF]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> set(forward_no_answer_destination=)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.081763 [DEBUG] mod_dptools.c:1548 SET sofia/external/<Incoming Number>@<Gateway IP> [forward_no_answer_destination]=[UNDEF]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> set(forward_user_not_registered_enabled=)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.081763 [DEBUG] mod_dptools.c:1548 SET sofia/external/<Incoming Number>@<Gateway IP> [forward_user_not_registered_enabled]=[UNDEF]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> set(forward_user_not_registered_destination=)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.081763 [DEBUG] mod_dptools.c:1548 SET sofia/external/<Incoming Number>@<Gateway IP> [forward_user_not_registered_destination]=[UNDEF]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> set(do_not_disturb=false)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.091763 [DEBUG] mod_dptools.c:1548 SET sofia/external/<Incoming Number>@<Gateway IP> [do_not_disturb]=[false]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> set(call_timeout=30)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.091763 [DEBUG] mod_dptools.c:1548 SET sofia/external/<Incoming Number>@<Gateway IP> [call_timeout]=[30]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> set(missed_call_app=)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.091763 [DEBUG] mod_dptools.c:1548 SET sofia/external/<Incoming Number>@<Gateway IP> [missed_call_app]=[UNDEF]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> set(missed_call_data=)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.101760 [DEBUG] mod_dptools.c:1548 SET sofia/external/<Incoming Number>@<Gateway IP> [missed_call_data]=[UNDEF]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> export(origination_callee_id_name=306)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.101760 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [origination_callee_id_name]=[306]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> set(RFC2822_DATE=Mon, 11 Feb 2019 12:34:22 -0700)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.101760 [DEBUG] mod_dptools.c:1548 SET sofia/external/<Incoming Number>@<Gateway IP> [RFC2822_DATE]=[Mon, 11 Feb 2019 12:34:22 -0700]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> export(nolocal:api_on_answer=uuid_record 7cdb1fcc-e9ef-4629-9a86-7e15a783f97a start /var/lib/freeswitch/recordings/<VOIP Server>/archive/2019/Feb/11/7cdb1fcc-e9ef-4629-9a86-7e15a783f97a.wav)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.101760 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) (REMOTE ONLY) [api_on_answer]=[uuid_record 7cdb1fcc-e9ef-4629-9a86-7e15a783f97a start /var/lib/freeswitch/recordings/<VOIP Server>/archive/2019/Feb/11/7cdb1fcc-e9ef-4629-9a86-7e15a783f97a.wav]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> hash(insert/<VOIP Server>-last_dial/<Incoming Number>/306)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> eval(not_secure)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> limit(hash <VOIP Server> 306  )
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.101760 [DEBUG] switch_limit.c:126 incr called: <VOIP Server>_306 max:-1, interval:0
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.101760 [DEBUG] mod_hash.c:194 Usage for <VOIP Server>_306 is now 1
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> bind_digit_action(local,*1,exec:execute_extension,dx XML <VOIP Server>,peer)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> bind_digit_action(local,*2,exec:record_session,/var/lib/freeswitch/recordings/<VOIP Server>/archive/2019/Feb/11/7cdb1fcc-e9ef-4629-9a86-7e15a783f97a.wav,peer)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> bind_digit_action(local,*3,exec:execute_extension,cf XML <VOIP Server>,peer)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> bind_digit_action(local,*4,exec:execute_extension,att_xfer XML <VOIP Server>,peer)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> digit_action_set_realm(local)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> set(hangup_after_bridge=true)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.101760 [DEBUG] mod_dptools.c:1548 SET sofia/external/<Incoming Number>@<Gateway IP> [hangup_after_bridge]=[true]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> set(continue_on_fail=true)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.101760 [DEBUG] mod_dptools.c:1548 SET sofia/external/<Incoming Number>@<Gateway IP> [continue_on_fail]=[true]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> hash(insert/<VOIP Server>-call_return/306/<Incoming Number>)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> hash(insert/<VOIP Server>-last_dial_ext/306/7cdb1fcc-e9ef-4629-9a86-7e15a783f97a)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> set(called_party_call_group=)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.101760 [DEBUG] mod_dptools.c:1548 SET sofia/external/<Incoming Number>@<Gateway IP> [called_party_call_group]=[UNDEF]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> hash(insert/<VOIP Server>-last_dial//7cdb1fcc-e9ef-4629-9a86-7e15a783f97a)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> set(api_hangup_hook=lua app.lua hangup)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.101760 [DEBUG] mod_dptools.c:1548 SET sofia/external/<Incoming Number>@<Gateway IP> [api_hangup_hook]=[lua app.lua hangup]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> export(domain_name=<VOIP Server>)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.111756 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [domain_name]=[<VOIP Server>]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> bridge(user/306@<VOIP Server>)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.111756 [DEBUG] switch_channel.c:1250 sofia/external/<Incoming Number>@<Gateway IP> EXPORTING[export_vars] [dialed_extension]=[306] to event
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.111756 [DEBUG] switch_channel.c:1250 sofia/external/<Incoming Number>@<Gateway IP> EXPORTING[export_vars] [origination_callee_id_name]=[306] to event
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.111756 [DEBUG] switch_channel.c:1250 sofia/external/<Incoming Number>@<Gateway IP> EXPORTING[export_vars] [api_on_answer]=[uuid_record 7cdb1fcc-e9ef-4629-9a86-7e15a783f97a start /var/lib/freeswitch/recordings/<VOIP Server>/archive/2019/Feb/11/7cdb1fcc-e9ef-4629-9a86-7e15a783f97a.wav] to event
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.111756 [DEBUG] switch_channel.c:1250 sofia/external/<Incoming Number>@<Gateway IP> EXPORTING[export_vars] [domain_name]=[<VOIP Server>] to event
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.111756 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.111756 [DEBUG] switch_channel.c:1250 sofia/external/<Incoming Number>@<Gateway IP> EXPORTING[export_vars] [dialed_extension]=[306] to event
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.111756 [DEBUG] switch_channel.c:1250 sofia/external/<Incoming Number>@<Gateway IP> EXPORTING[export_vars] [origination_callee_id_name]=[306] to event
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.111756 [DEBUG] switch_channel.c:1250 sofia/external/<Incoming Number>@<Gateway IP> EXPORTING[export_vars] [api_on_answer]=[uuid_record 7cdb1fcc-e9ef-4629-9a86-7e15a783f97a start /var/lib/freeswitch/recordings/<VOIP Server>/archive/2019/Feb/11/7cdb1fcc-e9ef-4629-9a86-7e15a783f97a.wav] to event
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.111756 [DEBUG] switch_channel.c:1250 sofia/external/<Incoming Number>@<Gateway IP> EXPORTING[export_vars] [domain_name]=[<VOIP Server>] to event
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.111756 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.291759 [INFO] switch_ivr_originate.c:1215 Sending early media
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.291759 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/external/<Incoming Number>@<Gateway IP>] 172.31.13.8 port 17710 -> <Gateway IP> port 16206 codec: 0 ms: 20
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.291759 [DEBUG] switch_rtp.c:4111 Starting timer [soft] 160 bytes per 20ms
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.291759 [DEBUG] switch_core_media.c:7179 sofia/external/<Incoming Number>@<Gateway IP> Set 2833 dtmf send payload to 101
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.291759 [DEBUG] switch_core_media.c:7186 sofia/external/<Incoming Number>@<Gateway IP> Set 2833 dtmf receive payload to 101
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.291759 [DEBUG] switch_core_media.c:7209 sofia/external/<Incoming Number>@<Gateway IP> Set rtp dtmf delay to 40
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.291759 [DEBUG] mod_sofia.c:2364 Ring SDP:
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a v=0
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a o=FreeSWITCH 1549895952 1549895953 IN IP4 <VOIP Server IP>
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a s=FreeSWITCH
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a c=IN IP4 <VOIP Server IP>
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a t=0 0
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a m=audio 17710 RTP/AVP 0 101
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a a=rtpmap:0 PCMU/8000
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a a=rtpmap:101 telephone-event/8000
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a a=fmtp:101 0-16
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a a=ptime:20
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a a=sendrecv
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.291759 [NOTICE] mod_sofia.c:2367 Pre-Answer sofia/external/<Incoming Number>@<Gateway IP>!
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.291759 [DEBUG] switch_channel.c:3474 (sofia/external/<Incoming Number>@<Gateway IP>) Callstate Change RINGING -> EARLY
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.291759 [DEBUG] switch_ivr_originate.c:1273 Raw Codec Activation Success L16@8000hz 1 channel 20ms
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.291759 [DEBUG] switch_core_codec.c:223 sofia/external/<Incoming Number>@<Gateway IP> Push codec L16:100
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.291759 [DEBUG] switch_ivr_originate.c:1342 Play Ringback Tone [%(2000,4000,440,480)]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.321755 [DEBUG] sofia.c:7084 Channel sofia/external/<Incoming Number>@<Gateway IP> entering state [early][183]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:22.411755 [DEBUG] switch_rtp.c:7271 Correct audio ip/port confirmed.
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:52.011784 [DEBUG] switch_core_codec.c:248 sofia/external/<Incoming Number>@<Gateway IP> Restore previous codec PCMU:0.
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:52.011784 [NOTICE] switch_ivr_originate.c:2851 Cannot create outgoing channel of type [user] cause: [NO_ANSWER]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:52.011784 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 19 [NO_ANSWER]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:52.021760 [INFO] mod_dptools.c:3436 Originate Failed.  Cause: NO_ANSWER
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> lua(app.lua failure_handler)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:52.021760 [DEBUG] switch_cpp.cpp:1112 sofia/external/<Incoming Number>@<Gateway IP> destroy/unlink session from object
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> answer()
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:52.021760 [DEBUG] switch_core_media.c:6861 Audio params are unchanged for sofia/external/<Incoming Number>@<Gateway IP>.
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:52.021760 [DEBUG] mod_sofia.c:850 Local SDP sofia/external/<Incoming Number>@<Gateway IP>:
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a v=0
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a o=FreeSWITCH 1549895952 1549895954 IN IP4 <VOIP Server IP>
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a s=FreeSWITCH
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a c=IN IP4 <VOIP Server IP>
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a t=0 0
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a m=audio 17710 RTP/AVP 0 101
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a a=rtpmap:0 PCMU/8000
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a a=rtpmap:101 telephone-event/8000
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a a=fmtp:101 0-16
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a a=ptime:20
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a a=sendrecv
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:52.021760 [NOTICE] mod_dptools.c:1312 Channel [sofia/external/<Incoming Number>@<Gateway IP>] has been answered
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:52.021760 [DEBUG] switch_channel.c:3773 (sofia/external/<Incoming Number>@<Gateway IP>) Callstate Change EARLY -> ACTIVE
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:52.021760 [DEBUG] sofia.c:7084 Channel sofia/external/<Incoming Number>@<Gateway IP> entering state [completed][200]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> sleep(1000)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:52.071788 [DEBUG] sofia.c:7084 Channel sofia/external/<Incoming Number>@<Gateway IP> entering state [ready][200]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:52.111761 [DEBUG] switch_rtp.c:7271 Correct audio ip/port confirmed.
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> set(voicemail_action=save)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:53.031788 [DEBUG] mod_dptools.c:1548 SET sofia/external/<Incoming Number>@<Gateway IP> [voicemail_action]=[save]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> set(voicemail_id=306)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:53.031788 [DEBUG] mod_dptools.c:1548 SET sofia/external/<Incoming Number>@<Gateway IP> [voicemail_id]=[306]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> set(voicemail_profile=default)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:53.031788 [DEBUG] mod_dptools.c:1548 SET sofia/external/<Incoming Number>@<Gateway IP> [voicemail_profile]=[default]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> lua(app.lua voicemail)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> unbind_meta_app()
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:53.041767 [INFO] switch_ivr_async.c:4072 UnBound A-Leg: ALL
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:53.041767 [DEBUG] switch_cpp.cpp:745 CoreSession::setVariable(playback_terminators, #)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a EXECUTE sofia/external/<Incoming Number>@<Gateway IP> playback(silence_stream://200)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:53.051767 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:53.231767 [DEBUG] switch_ivr_play_say.c:1942 done playing file silence_stream://200
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:34:53.231767 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:04.191758 [DEBUG] switch_ivr_play_say.c:1942 done playing file /var/lib/freeswitch/storage/voicemail/default/<VOIP Server>/306/greeting_1.wav
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:04.291794 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:08.891786 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/share/freeswitch/sounds/en/us/callie/voicemail/vm-record_message.wav
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:08.991807 [DEBUG] switch_cpp.cpp:895 getDigits dtmf_buf:
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:09.991810 [DEBUG] switch_cpp.cpp:895 getDigits dtmf_buf:
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:09.991810 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:10.991784 [DEBUG] switch_ivr_play_say.c:1942 done playing file tone_stream://L=1;%(1000, 0, 640)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:11.111793 [DEBUG] switch_cpp.cpp:895 getDigits dtmf_buf:
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:11.123426 [DEBUG] switch_ivr_play_say.c:560 Raw Codec Activated, ready to waste resources!
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:11.123426 [DEBUG] switch_ivr_play_say.c:674 Raw Codec Activated
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:11.123426 [DEBUG] switch_core_codec.c:223 sofia/external/<Incoming Number>@<Gateway IP> Push codec L16:100
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:16.111786 [DEBUG] switch_core_codec.c:248 sofia/external/<Incoming Number>@<Gateway IP> Restore previous codec PCMU:0.
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:16.111786 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:17.331779 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/share/freeswitch/sounds/en/us/callie/voicemail/vm-listen_to_recording.wav
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:17.331779 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:17.731785 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/share/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:17.731785 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:18.191765 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/share/freeswitch/sounds/en/us/callie/digits/1.wav
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:18.311783 [DEBUG] switch_cpp.cpp:895 getDigits dtmf_buf:
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:18.311783 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:19.451762 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/share/freeswitch/sounds/en/us/callie/voicemail/vm-save_recording.wav
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:19.451762 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:19.851796 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/share/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:19.851796 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:20.251794 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/share/freeswitch/sounds/en/us/callie/digits/2.wav
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:20.351794 [DEBUG] switch_cpp.cpp:895 getDigits dtmf_buf:
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:20.351794 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:21.231776 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/share/freeswitch/sounds/en/us/callie/voicemail/vm-rerecord.wav
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:21.231776 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:21.631769 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/share/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:21.631769 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:22.031799 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/share/freeswitch/sounds/en/us/callie/digits/3.wav
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:25.051778 [DEBUG] switch_cpp.cpp:895 getDigits dtmf_buf:
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:25.051778 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:26.271769 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/share/freeswitch/sounds/en/us/callie/voicemail/vm-listen_to_recording.wav
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:26.271769 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:26.671758 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/share/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:26.671758 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:27.131801 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/share/freeswitch/sounds/en/us/callie/digits/1.wav
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:27.251789 [DEBUG] switch_cpp.cpp:895 getDigits dtmf_buf:
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:27.251789 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:27.351770 [DEBUG] switch_rtp.c:7497 RTP RECV DTMF 3:1680
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:27.351770 [INFO] switch_channel.c:515 RECV DTMF 3:1680
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:27.351770 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/share/freeswitch/sounds/en/us/callie/voicemail/vm-save_recording.wav
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:27.361765 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:31.951788 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/share/freeswitch/sounds/en/us/callie/voicemail/vm-record_message.wav
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:32.071778 [DEBUG] switch_cpp.cpp:895 getDigits dtmf_buf:
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:33.071794 [DEBUG] switch_cpp.cpp:895 getDigits dtmf_buf:
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:33.071794 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:34.071785 [DEBUG] switch_ivr_play_say.c:1942 done playing file tone_stream://L=1;%(1000, 0, 640)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:34.171805 [DEBUG] switch_cpp.cpp:895 getDigits dtmf_buf:
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:34.184999 [DEBUG] switch_ivr_play_say.c:560 Raw Codec Activated, ready to waste resources!
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:34.184999 [DEBUG] switch_ivr_play_say.c:674 Raw Codec Activated
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:34.184999 [DEBUG] switch_core_codec.c:223 sofia/external/<Incoming Number>@<Gateway IP> Push codec L16:100
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:39.171816 [DEBUG] switch_core_codec.c:248 sofia/external/<Incoming Number>@<Gateway IP> Restore previous codec PCMU:0.
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:39.171816 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:40.391810 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/share/freeswitch/sounds/en/us/callie/voicemail/vm-listen_to_recording.wav
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:40.391810 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:40.791809 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/share/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:40.791809 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:41.251781 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/share/freeswitch/sounds/en/us/callie/digits/1.wav
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:41.351782 [DEBUG] switch_cpp.cpp:895 getDigits dtmf_buf:
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:41.351782 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:42.491801 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/share/freeswitch/sounds/en/us/callie/voicemail/vm-save_recording.wav
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:42.491801 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:42.891787 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/share/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:42.891787 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:43.291781 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/share/freeswitch/sounds/en/us/callie/digits/2.wav
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:43.391878 [DEBUG] switch_cpp.cpp:895 getDigits dtmf_buf:
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:43.391878 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:44.271787 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/share/freeswitch/sounds/en/us/callie/voicemail/vm-rerecord.wav
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:44.271787 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:44.671780 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/share/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:44.671780 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:45.071785 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/share/freeswitch/sounds/en/us/callie/digits/3.wav
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:48.091760 [DEBUG] switch_cpp.cpp:895 getDigits dtmf_buf:
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:48.091760 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:48.471762 [DEBUG] switch_rtp.c:7497 RTP RECV DTMF 3:1680
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:48.471762 [INFO] switch_channel.c:515 RECV DTMF 3:1680
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:48.471762 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/share/freeswitch/sounds/en/us/callie/voicemail/vm-listen_to_recording.wav
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:48.481762 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:53.071766 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/share/freeswitch/sounds/en/us/callie/voicemail/vm-record_message.wav
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:53.191781 [DEBUG] switch_cpp.cpp:895 getDigits dtmf_buf:
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:54.191795 [DEBUG] switch_cpp.cpp:895 getDigits dtmf_buf:
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:54.191795 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:55.191810 [DEBUG] switch_ivr_play_say.c:1942 done playing file tone_stream://L=1;%(1000, 0, 640)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:55.311795 [DEBUG] switch_cpp.cpp:895 getDigits dtmf_buf:
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:55.324809 [DEBUG] switch_ivr_play_say.c:560 Raw Codec Activated, ready to waste resources!
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:55.324809 [DEBUG] switch_ivr_play_say.c:674 Raw Codec Activated
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:35:55.324809 [DEBUG] switch_core_codec.c:223 sofia/external/<Incoming Number>@<Gateway IP> Push codec L16:100
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:36:00.311778 [DEBUG] switch_core_codec.c:248 sofia/external/<Incoming Number>@<Gateway IP> Restore previous codec PCMU:0.
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:36:00.311778 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:36:01.531779 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/share/freeswitch/sounds/en/us/callie/voicemail/vm-listen_to_recording.wav
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:36:01.531779 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:36:01.651784 [NOTICE] sofia.c:1012 Hangup sofia/external/<Incoming Number>@<Gateway IP> [CS_EXECUTE] [NORMAL_CLEARING]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:36:01.651784 [DEBUG] mod_hash.c:297 Usage for <VOIP Server>_306 is now 0
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:36:01.651784 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/share/freeswitch/sounds/en/us/callie/voicemail/vm-press.wav
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:36:02.891765 [DEBUG] switch_cpp.cpp:1112 sofia/external/<Incoming Number>@<Gateway IP> destroy/unlink session from object
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:36:02.891765 [DEBUG] switch_core_session.c:2815 sofia/external/<Incoming Number>@<Gateway IP> skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:36:02.891765 [DEBUG] switch_core_state_machine.c:650 (sofia/external/<Incoming Number>@<Gateway IP>) State EXECUTE going to sleep
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:36:02.891765 [DEBUG] switch_core_state_machine.c:584 (sofia/external/<Incoming Number>@<Gateway IP>) Running State Change CS_HANGUP (Cur 7 Tot 140464)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:36:02.891765 [DEBUG] switch_core_state_machine.c:850 (sofia/external/<Incoming Number>@<Gateway IP>) Callstate Change ACTIVE -> HANGUP
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:36:02.891765 [DEBUG] switch_core_state_machine.c:852 (sofia/external/<Incoming Number>@<Gateway IP>) State HANGUP
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:36:02.891765 [DEBUG] mod_sofia.c:438 Channel sofia/external/<Incoming Number>@<Gateway IP> hanging up, cause: NORMAL_CLEARING
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:36:02.891765 [DEBUG] switch_core_state_machine.c:60 sofia/external/<Incoming Number>@<Gateway IP> Standard HANGUP, cause: NORMAL_CLEARING
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:36:02.891765 [DEBUG] switch_core_state_machine.c:852 (sofia/external/<Incoming Number>@<Gateway IP>) State HANGUP going to sleep
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:36:02.891765 [DEBUG] switch_core_state_machine.c:783 Hangup Command with no Session lua(app.lua hangup):
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:36:02.891765 [DEBUG] switch_core_state_machine.c:619 (sofia/external/<Incoming Number>@<Gateway IP>) State Change CS_HANGUP -> CS_REPORTING
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:36:02.891765 [DEBUG] switch_core_state_machine.c:584 (sofia/external/<Incoming Number>@<Gateway IP>) Running State Change CS_REPORTING (Cur 7 Tot 140464)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:36:02.891765 [DEBUG] switch_core_state_machine.c:938 (sofia/external/<Incoming Number>@<Gateway IP>) State REPORTING
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:36:02.921768 [DEBUG] switch_core_state_machine.c:174 sofia/external/<Incoming Number>@<Gateway IP> Standard REPORTING, cause: NORMAL_CLEARING
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:36:02.921768 [DEBUG] switch_core_state_machine.c:938 (sofia/external/<Incoming Number>@<Gateway IP>) State REPORTING going to sleep
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:36:02.921768 [DEBUG] switch_core_state_machine.c:610 (sofia/external/<Incoming Number>@<Gateway IP>) State Change CS_REPORTING -> CS_DESTROY
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:36:02.921768 [DEBUG] switch_core_session.c:1665 Session 140461 (sofia/external/<Incoming Number>@<Gateway IP>) Locked, Waiting on external entities
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:36:02.921768 [NOTICE] switch_core_session.c:1683 Session 140461 (sofia/external/<Incoming Number>@<Gateway IP>) Ended
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:36:02.921768 [NOTICE] switch_core_session.c:1687 Close Channel sofia/external/<Incoming Number>@<Gateway IP> [CS_DESTROY]
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:36:02.921768 [DEBUG] switch_core_state_machine.c:741 (sofia/external/<Incoming Number>@<Gateway IP>) Running State Change CS_DESTROY (Cur 6 Tot 140464)
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:36:02.921768 [DEBUG] switch_core_state_machine.c:751 (sofia/external/<Incoming Number>@<Gateway IP>) State DESTROY
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:36:02.921768 [DEBUG] mod_sofia.c:343 sofia/external/<Incoming Number>@<Gateway IP> SOFIA DESTROY
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:36:02.921768 [DEBUG] switch_core_state_machine.c:181 sofia/external/<Incoming Number>@<Gateway IP> Standard DESTROY
7cdb1fcc-e9ef-4629-9a86-7e15a783f97a 2019-02-11 12:36:02.921768 [DEBUG] switch_core_state_machine.c:751 (sofia/external/<Incoming Number>@<Gateway IP>) State DESTROY going to sleep
 
DigitalDaz

DigitalDaz

Administrator
Staff member
Sep 29, 2016
3,043
565
113
Are you sure they are not just choosing to hang up rather than leaving a voicemail and this could be what you are seeing?
 
R

Robert Birch

Member
Mar 16, 2017
111
4
18
52
I was wondering that, but from what I am told, the caller will be leaving a message and talking when it decides that the message is finished.

It is weird as I have probably 100 extensions from multiple domains on this server, and this is the only domain where this is happening.

Since it is intermittent. it is difficult to determine whee the issue lies.

Thanks
 
B

Bernie Reynoso

New Member
Nov 10, 2017
6
0
1
48
Hi Robert. Did you figure it out? I can also sometimes see VMs from 4-6 seconds with 0 Bytes causing the user to think they can't play them since it acts like the VM is in a loop and they can't retrieve the messages. I was able to replicate it my calling the VM on mute and hanging up after 4 seconds. Just wondering if you figured out if that is the case or somehow data/recording is getting lost. Thx
 
R

Robert Birch

Member
Mar 16, 2017
111
4
18
52
Sorry, I was never ever to figure out what caused it, but haven't had it happen since. It could have been related to version. The client was originally on 4.4.6 server, but we have moved them to a 4.5.24 server. Not 100% sure that fixed it though. It was happening very randomly.
 
D

djacob

Member
Oct 31, 2016
43
8
8
50
Bensalem PA , USA
Robert Birch said:
Sorry, I was never ever to figure out what caused it, but haven't had it happen since. It could have been related to version. The client was originally on 4.4.6 server, but we have moved them to a 4.5.24 server. Not 100% sure that fixed it though. It was happening very randomly.
Click to expand...
Thanks for the response, i am looking into updating now also.

Thanks
 
Status
Not open for further replies.
Top Bottom