SOLVED Call dies when using a withheld number.

Status
Not open for further replies.

deltukru

New Member
Sep 19, 2018
15
0
1
58
Hello,

In dialplan include general settings.does this take into account withheld number?
condition destination_number ^0(.*)$
I'm using "Follow Me" to handle call routing when it hits the extension, but it dies when using a withheld number.

Withheld number log
2018-09-25 14:09:30.982688 [NOTICE] switch_channel.c:1104 New Channel sofia/external/anonymous@anonymous.invalid [f8ceb442-2be0-4b67-8d29-da8ee4b19981]
2018-09-25 14:09:30.982688 [DEBUG] switch_core_state_machine.c:584 (sofia/external/anonymous@anonymous.invalid) Running State Change CS_NEW (Cur 1 Tot 1476)
2018-09-25 14:09:30.982688 [DEBUG] sofia.c:9873 sofia/external/anonymous@anonymous.invalid receiving invite from 185.63.140.77:5060 version: 1.6.19 -36-7a77e0b 64bit
2018-09-25 14:09:30.982688 [DEBUG] sofia.c:7084 Channel sofia/external/anonymous@anonymous.invalid entering state [received][100]
2018-09-25 14:09:30.982688 [DEBUG] sofia.c:7094 Remote SDP:
v=0
o=FreeSWITCH 1537867784 1537867785 IN IP4 185.63.140.3
s=FreeSWITCH
c=IN IP4 185.63.140.3
t=0 0
m=audio 16786 RTP/AVP 9 8 0 101 13
a=rtpmap:9 G722/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:13 CN/8000
a=rtcp-mux
a=rtcp:16786 IN IP4 185.63.140.3
a=ptime:20

2018-09-25 14:09:30.982688 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2018-09-25 14:09:30.982688 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
2018-09-25 14:09:30.982688 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2018-09-25 14:09:30.982688 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2018-09-25 14:09:30.982688 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
2018-09-25 14:09:30.982688 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2018-09-25 14:09:30.982688 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2018-09-25 14:09:30.982688 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2018-09-25 14:09:30.982688 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2018-09-25 14:09:30.982688 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
2018-09-25 14:09:30.982688 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2018-09-25 14:09:30.982688 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2018-09-25 14:09:30.982688 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2018-09-25 14:09:30.982688 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2018-09-25 14:09:30.982688 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
2018-09-25 14:09:30.982688 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
2018-09-25 14:09:30.982688 [DEBUG] switch_core_media.c:3061 Set Codec sofia/external/anonymous@anonymous.invalid G722/8000 20 ms 160 samples 64000 bits 1 channels
2018-09-25 14:09:30.982688 [DEBUG] switch_core_codec.c:111 sofia/external/anonymous@anonymous.invalid Original read codec set to G722:9
2018-09-25 14:09:30.982688 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
2018-09-25 14:09:30.982688 [DEBUG] switch_core_media.c:4767 sofia/external/anonymous@anonymous.invalid Set 2833 dtmf send payload to 101 recv payload to 101
2018-09-25 14:09:30.982688 [DEBUG] sofia.c:7507 (sofia/external/anonymous@anonymous.invalid) State Change CS_NEW -> CS_INIT
2018-09-25 14:09:30.982688 [DEBUG] switch_core_state_machine.c:603 (sofia/external/anonymous@anonymous.invalid) State NEW
2018-09-25 14:09:30.982688 [DEBUG] switch_core_state_machine.c:584 (sofia/external/anonymous@anonymous.invalid) Running State Change CS_INIT (Cur 1 Tot 1476)
2018-09-25 14:09:30.982688 [DEBUG] switch_core_state_machine.c:627 (sofia/external/anonymous@anonymous.invalid) State INIT
2018-09-25 14:09:30.982688 [DEBUG] mod_sofia.c:90 sofia/external/anonymous@anonymous.invalid SOFIA INIT
2018-09-25 14:09:30.982688 [DEBUG] switch_core_state_machine.c:40 sofia/external/anonymous@anonymous.invalid Standard INIT
2018-09-25 14:09:30.982688 [DEBUG] switch_core_state_machine.c:48 (sofia/external/anonymous@anonymous.invalid) State Change CS_INIT -> CS_ROUTING
2018-09-25 14:09:30.982688 [DEBUG] switch_core_state_machine.c:627 (sofia/external/anonymous@anonymous.invalid) State INIT going to sleep
2018-09-25 14:09:30.982688 [DEBUG] switch_core_state_machine.c:584 (sofia/external/anonymous@anonymous.invalid) Running State Change CS_ROUTING (Cur 1 Tot 1476)
2018-09-25 14:09:30.982688 [DEBUG] switch_channel.c:2249 (sofia/external/anonymous@anonymous.invalid) Callstate Change DOWN -> RINGING
2018-09-25 14:09:30.982688 [DEBUG] switch_core_state_machine.c:643 (sofia/external/anonymous@anonymous.invalid) State ROUTING
2018-09-25 14:09:30.982688 [DEBUG] mod_sofia.c:143 sofia/external/anonymous@anonymous.invalid SOFIA ROUTING
2018-09-25 14:09:30.982688 [DEBUG] switch_core_state_machine.c:236 sofia/external/anonymous@anonymous.invalid Standard ROUTING
2018-09-25 14:09:30.982688 [INFO] mod_dialplan_xml.c:637 Processing Anonymous <anonymous>->442081991111 in context public
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [public->442071691000] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [442071691000] destination_number(442081991111) =~ /^(442071691000)$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [public->442071697134] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [442071697134] destination_number(442081991111) =~ /^(442071697134)$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [public->442081991111] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (PASS) [442081991111] destination_number(442081991111) =~ /^(442081991111)$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(call_direction=inbound) INLINE
EXECUTE sofia/external/anonymous@anonymous.invalid set(call_direction=inbound)
2018-09-25 14:09:31.002802 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [call_direction]=[inbound]
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(domain_uuid=2c9c2dc9-07b3-4849-85c1-513f7d2db366) INLINE
EXECUTE sofia/external/anonymous@anonymous.invalid set(domain_uuid=2c9c2dc9-07b3-4849-85c1-513f7d2db366)
2018-09-25 14:09:31.002802 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [domain_uuid]=[2c9c2dc9-07b3-4849-85c1-513f7d2db366]
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(domain_name=pbx.numbercard.co.uk) INLINE
EXECUTE sofia/external/anonymous@anonymous.invalid set(domain_name=pbx.numbercard.co.uk)
2018-09-25 14:09:31.002802 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [domain_name]=[pbx.numbercard.co.uk]
Dialplan: sofia/external/anonymous@anonymous.invalid Action transfer(20000 XML pbx.numbercard.co.uk)
2018-09-25 14:09:31.002802 [DEBUG] switch_core_state_machine.c:286 (sofia/external/anonymous@anonymous.invalid) State Change CS_ROUTING -> CS_EXECUTE
2018-09-25 14:09:31.002802 [DEBUG] switch_core_state_machine.c:643 (sofia/external/anonymous@anonymous.invalid) State ROUTING going to sleep
2018-09-25 14:09:31.002802 [DEBUG] switch_core_state_machine.c:584 (sofia/external/anonymous@anonymous.invalid) Running State Change CS_EXECUTE (Cur 1 Tot 1476)
2018-09-25 14:09:31.002802 [DEBUG] switch_core_state_machine.c:650 (sofia/external/anonymous@anonymous.invalid) State EXECUTE
2018-09-25 14:09:31.002802 [DEBUG] mod_sofia.c:198 sofia/external/anonymous@anonymous.invalid SOFIA EXECUTE
2018-09-25 14:09:31.002802 [DEBUG] switch_core_state_machine.c:328 sofia/external/anonymous@anonymous.invalid Standard EXECUTE
EXECUTE sofia/external/anonymous@anonymous.invalid transfer(20000 XML pbx.numbercard.co.uk)
2018-09-25 14:09:31.002802 [DEBUG] switch_ivr.c:2165 (sofia/external/anonymous@anonymous.invalid) State Change CS_EXECUTE -> CS_ROUTING
2018-09-25 14:09:31.002802 [NOTICE] switch_ivr.c:2172 Transfer sofia/external/anonymous@anonymous.invalid to XML[20000@pbx.numbercard.co.uk]
2018-09-25 14:09:31.002802 [DEBUG] switch_core_state_machine.c:650 (sofia/external/anonymous@anonymous.invalid) State EXECUTE going to sleep
2018-09-25 14:09:31.002802 [DEBUG] switch_core_state_machine.c:584 (sofia/external/anonymous@anonymous.invalid) Running State Change CS_ROUTING (Cur 1 Tot 1476)
2018-09-25 14:09:31.002802 [DEBUG] switch_core_state_machine.c:643 (sofia/external/anonymous@anonymous.invalid) State ROUTING
2018-09-25 14:09:31.002802 [DEBUG] mod_sofia.c:143 sofia/external/anonymous@anonymous.invalid SOFIA ROUTING
2018-09-25 14:09:31.002802 [DEBUG] switch_core_state_machine.c:236 sofia/external/anonymous@anonymous.invalid Standard ROUTING
2018-09-25 14:09:31.002802 [INFO] mod_dialplan_xml.c:637 Processing Anonymous <anonymous>->20000 in context pbx.numbercard.co.uk
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->user_exists] continue=true
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (PASS) [user_exists] () =~ // break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
2018-09-25 14:09:31.062669 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f40ac4a3660 Connected.
2018-09-25 14:09:31.082693 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f40ac4a3660 released.
EXECUTE sofia/external/anonymous@anonymous.invalid set(user_exists=false)
2018-09-25 14:09:31.082693 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [user_exists]=[false]
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_exists] ${user_exists}(false) =~ /^true$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->call-direction] continue=true
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (PASS) [call-direction] ${call_direction}(inbound) =~ /^(inbound|outbound|local)$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->variables] continue=true
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (PASS) [variables] () =~ // break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid Action export(origination_callee_id_name=${destination_number})
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->user_record] continue=true
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (PASS) [user_record] () =~ // break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(user_record=${user_data ${destination_number}@${domain_name} var user_record}) INLINE
2018-09-25 14:09:31.082693 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f40ac4a3660 Connected.
2018-09-25 14:09:31.082693 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f40ac4a3660 released.
2018-09-25 14:09:31.102739 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f40ac4a3660 Connected.
2018-09-25 14:09:31.102739 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f40ac4a3660 released.
EXECUTE sofia/external/anonymous@anonymous.invalid set(user_record=)
2018-09-25 14:09:31.102739 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [user_record]=[UNDEF]
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE
2018-09-25 14:09:31.102739 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f40ac4a3660 Connected.
2018-09-25 14:09:31.102739 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f40ac4a3660 released.
EXECUTE sofia/external/anonymous@anonymous.invalid set(from_user_exists=false)
2018-09-25 14:09:31.102739 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [from_user_exists]=[false]
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->redial] continue=true
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [redial] destination_number(20000) =~ /^(redial|\*870)$/ break=on-true
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (PASS) [redial] () =~ // break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->speed_dial] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [speed_dial] destination_number(20000) =~ /^\*0(.*)$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->No-Reg-simwood.00] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [No-Reg-simwood.00] destination_number(20000) =~ /^00(.*)$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->No-Reg-simwood.0] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [No-Reg-simwood.0] destination_number(20000) =~ /^0(.*)$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->agent_status] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [agent_status] destination_number(20000) =~ /^\*22$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->agent_status_id] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [agent_status_id] destination_number(20000) =~ /^\*23$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->group-intercept] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [group-intercept] destination_number(20000) =~ /^\*8$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->page-extension] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [page-extension] destination_number(20000) =~ /^\*8(\d{2,7})$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->eavesdrop] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [eavesdrop] destination_number(20000) =~ /^\*33(\d{2,7})$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->call_privacy] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [call_privacy] destination_number(20000) =~ /^\*67(\d+)$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->call_return] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [call_return] destination_number(20000) =~ /^\*69$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->extension_queue] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [extension_queue] destination_number(20000) =~ /^\*800(.*)$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->intercept-ext] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [intercept-ext] destination_number(20000) =~ /^\*\*(\d+)$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->intercept-ext-polycom] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [intercept-ext-polycom] destination_number(20000) =~ /^\*97(\d+)$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->dx] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [dx] destination_number(20000) =~ /^dx$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->att_xfer] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [att_xfer] destination_number(20000) =~ /^att_xfer$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->extension-to-voicemail] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [extension-to-voicemail] ${user_exists}(false) =~ /^true$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->send_to_voicemail] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [send_to_voicemail] destination_number(20000) =~ /^\*99(\d{2,10})$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->vmain] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [vmain] destination_number(20000) =~ /^vmain$|^\*4000$|^\*98$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->xfer_vm] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [xfer_vm] destination_number(20000) =~ /^xfer_vm$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->is_transfer] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [is_transfer] destination_number(20000) =~ /^is_transfer$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->vmain_user] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [vmain_user] destination_number(20000) =~ /^\*97$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->IVRmorning] continue=
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (PASS) [IVRmorning] destination_number(20000) =~ /^20000$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid Action answer()
Dialplan: sofia/external/anonymous@anonymous.invalid Action sleep(1000)
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(hangup_after_bridge=true)
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(ringback=local_stream://default)
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(transfer_ringback=local_stream://default)
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(ivr_menu_uuid=a9b7a5d3-aa3b-40ae-882f-a3daaa4f9378)
Dialplan: sofia/external/anonymous@anonymous.invalid Action ivr(a9b7a5d3-aa3b-40ae-882f-a3daaa4f9378)
Dialplan: sofia/external/anonymous@anonymous.invalid Action lua(streamfile.lua pleaseHold.wav)
2018-09-25 14:09:31.102739 [DEBUG] switch_core_state_machine.c:286 (sofia/external/anonymous@anonymous.invalid) State Change CS_ROUTING -> CS_EXECUTE
2018-09-25 14:09:31.102739 [DEBUG] switch_core_state_machine.c:643 (sofia/external/anonymous@anonymous.invalid) State ROUTING going to sleep
2018-09-25 14:09:31.102739 [DEBUG] switch_core_state_machine.c:584 (sofia/external/anonymous@anonymous.invalid) Running State Change CS_EXECUTE (Cur 1 Tot 1476)
2018-09-25 14:09:31.102739 [DEBUG] switch_core_state_machine.c:650 (sofia/external/anonymous@anonymous.invalid) State EXECUTE
2018-09-25 14:09:31.102739 [DEBUG] mod_sofia.c:198 sofia/external/anonymous@anonymous.invalid SOFIA EXECUTE
2018-09-25 14:09:31.102739 [DEBUG] switch_core_state_machine.c:328 sofia/external/anonymous@anonymous.invalid Standard EXECUTE
EXECUTE sofia/external/anonymous@anonymous.invalid export(origination_callee_id_name=20000)
2018-09-25 14:09:31.102739 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [origination_callee_id_name]=[20000]
EXECUTE sofia/external/anonymous@anonymous.invalid set(RFC2822_DATE=Tue, 25 Sep 2018 14:09:31 +0000)
2018-09-25 14:09:31.102739 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [RFC2822_DATE]=[Tue, 25 Sep 2018 14:09:31 +0000]
EXECUTE sofia/external/anonymous@anonymous.invalid hash(insert/pbx.numbercard.co.uk-last_dial/anonymous/20000)
EXECUTE sofia/external/anonymous@anonymous.invalid answer()
2018-09-25 14:09:31.102739 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/external/anonymous@anonymous.invalid] 46.101.82.189 port 25240 -> 185.63.140.3 port 16786 codec: 9 ms: 20
2018-09-25 14:09:31.102739 [DEBUG] switch_rtp.c:4111 Starting timer [soft] 160 bytes per 20ms
2018-09-25 14:09:31.102739 [DEBUG] switch_core_media.c:7098 Activating RTCP PORT 16786
2018-09-25 14:09:31.102739 [DEBUG] switch_rtp.c:4507 RTCP send rate is: 1000 and packet rate is: 20000 Remote Port: 16786
2018-09-25 14:09:31.102739 [DEBUG] switch_core_media.c:7179 sofia/external/anonymous@anonymous.invalid Set 2833 dtmf send payload to 101
2018-09-25 14:09:31.102739 [DEBUG] switch_core_media.c:7186 sofia/external/anonymous@anonymous.invalid Set 2833 dtmf receive payload to 101
2018-09-25 14:09:31.102739 [DEBUG] switch_core_media.c:7209 sofia/external/anonymous@anonymous.invalid Set rtp dtmf delay to 40
2018-09-25 14:09:31.102739 [DEBUG] switch_core_media.c:7215 Set comfort noise payload to 13
2018-09-25 14:09:31.102739 [DEBUG] mod_sofia.c:850 Local SDP sofia/external/anonymous@anonymous.invalid:
v=0
o=FreeSWITCH 1537859331 1537859332 IN IP4 46.101.82.189
s=FreeSWITCH
c=IN IP4 46.101.82.189
t=0 0
m=audio 25240 RTP/AVP 9 101 13
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:13 CN/8000
a=ptime:20
a=sendrecv
a=rtcp-mux
a=rtcp:25240 IN IP4 46.101.82.189

2018-09-25 14:09:31.102739 [DEBUG] sofia.c:7084 Channel sofia/external/anonymous@anonymous.invalid entering state [completed][200]
2018-09-25 14:09:31.102739 [NOTICE] mod_dptools.c:1312 Channel [sofia/external/anonymous@anonymous.invalid] has been answered
2018-09-25 14:09:31.102739 [DEBUG] switch_channel.c:3773 (sofia/external/anonymous@anonymous.invalid) Callstate Change RINGING -> ACTIVE
EXECUTE sofia/external/anonymous@anonymous.invalid sleep(1000)
2018-09-25 14:09:31.122807 [DEBUG] sofia.c:7084 Channel sofia/external/anonymous@anonymous.invalid entering state [ready][200]
2018-09-25 14:09:31.242701 [DEBUG] switch_rtp.c:1887 rtcp_stats_init: audio ssrc[2438347594] base_seq[48522]
2018-09-25 14:09:31.242701 [DEBUG] switch_rtp.c:7271 Correct audio ip/port confirmed.
EXECUTE sofia/external/anonymous@anonymous.invalid set(hangup_after_bridge=true)
2018-09-25 14:09:32.122733 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [hangup_after_bridge]=[true]
EXECUTE sofia/external/anonymous@anonymous.invalid set(ringback=local_stream://default)
2018-09-25 14:09:32.122733 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [ringback]=[local_stream://default]
EXECUTE sofia/external/anonymous@anonymous.invalid set(transfer_ringback=local_stream://default)
2018-09-25 14:09:32.122733 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [transfer_ringback]=[local_stream://default]
EXECUTE sofia/external/anonymous@anonymous.invalid set(ivr_menu_uuid=a9b7a5d3-aa3b-40ae-882f-a3daaa4f9378)
2018-09-25 14:09:32.122733 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [ivr_menu_uuid]=[a9b7a5d3-aa3b-40ae-882f-a3daaa4f9378]
EXECUTE sofia/external/anonymous@anonymous.invalid ivr(a9b7a5d3-aa3b-40ae-882f-a3daaa4f9378)
2018-09-25 14:09:32.122733 [DEBUG] switch_ivr_menu.c:743 switch_ivr_menu_stack_xml_add binding 'menu-exit'
2018-09-25 14:09:32.122733 [DEBUG] switch_ivr_menu.c:743 switch_ivr_menu_stack_xml_add binding 'menu-sub'
2018-09-25 14:09:32.122733 [DEBUG] switch_ivr_menu.c:743 switch_ivr_menu_stack_xml_add binding 'menu-exec-app'
2018-09-25 14:09:32.122733 [DEBUG] switch_ivr_menu.c:743 switch_ivr_menu_stack_xml_add binding 'menu-play-sound'
2018-09-25 14:09:32.122733 [DEBUG] switch_ivr_menu.c:743 switch_ivr_menu_stack_xml_add binding 'menu-back'
2018-09-25 14:09:32.122733 [DEBUG] switch_ivr_menu.c:743 switch_ivr_menu_stack_xml_add binding 'menu-top'
2018-09-25 14:09:32.122733 [DEBUG] switch_ivr_menu.c:880 building menu 'a9b7a5d3-aa3b-40ae-882f-a3daaa4f9378'
2018-09-25 14:09:32.122733 [DEBUG] switch_ivr_menu.c:964 binding menu action 'menu-exec-app' to '1'
2018-09-25 14:09:32.122733 [DEBUG] switch_ivr_menu.c:964 binding menu action 'menu-exec-app' to '2'
2018-09-25 14:09:32.122733 [DEBUG] switch_ivr_menu.c:964 binding menu action 'menu-exec-app' to '3'
2018-09-25 14:09:32.122733 [DEBUG] switch_ivr_menu.c:483 Executing IVR menu a9b7a5d3-aa3b-40ae-882f-a3daaa4f9378
2018-09-25 14:09:32.122733 [DEBUG] switch_core_file.c:342 File /var/lib/freeswitch/recordings/pbx.numbercard.co.uk/goodAfternoon.wav sample rate 44100 doesn't match requested rate 16000
2018-09-25 14:09:32.122733 [WARNING] switch_core_file.c:360 File has 2 channels, muxing to 1 channel will occur.
2018-09-25 14:09:32.122733 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@16000hz 1 channels 20ms
2018-09-25 14:09:35.362729 [DEBUG] switch_rtp.c:7497 RTP RECV DTMF 1:960
2018-09-25 14:09:35.362729 [INFO] switch_channel.c:515 RECV DTMF 1:960
2018-09-25 14:09:35.362729 [DEBUG] switch_ivr_play_say.c:1942 done playing file /var/lib/freeswitch/recordings/pbx.numbercard.co.uk/goodAfternoon.wav
2018-09-25 14:09:35.362729 [DEBUG] switch_ivr_menu.c:377 waiting for 4/5 digits t/o 2000
2018-09-25 14:09:37.382709 [DEBUG] switch_ivr_menu.c:424 digits '1'
2018-09-25 14:09:37.382709 [DEBUG] switch_ivr_menu.c:577 IVR action on menu 'a9b7a5d3-aa3b-40ae-882f-a3daaa4f9378' matched '1' param 'transfer 2008 XML pbx.numbercard.co.uk'
2018-09-25 14:09:37.382709 [DEBUG] switch_ivr_menu.c:581 switch_ivr_menu_execute todo=[2]
EXECUTE sofia/external/anonymous@anonymous.invalid transfer(2008 XML pbx.numbercard.co.uk)
2018-09-25 14:09:37.382709 [DEBUG] switch_ivr.c:2165 (sofia/external/anonymous@anonymous.invalid) State Change CS_EXECUTE -> CS_ROUTING
2018-09-25 14:09:37.382709 [NOTICE] switch_ivr.c:2172 Transfer sofia/external/anonymous@anonymous.invalid to XML[2008@pbx.numbercard.co.uk]
2018-09-25 14:09:37.382709 [DEBUG] switch_ivr_menu.c:673 exit-sound '(null)'
2018-09-25 14:09:37.382709 [DEBUG] switch_core_state_machine.c:650 (sofia/external/anonymous@anonymous.invalid) State EXECUTE going to sleep
2018-09-25 14:09:37.382709 [DEBUG] switch_core_state_machine.c:584 (sofia/external/anonymous@anonymous.invalid) Running State Change CS_ROUTING (Cur 1 Tot 1476)
2018-09-25 14:09:37.382709 [DEBUG] switch_channel.c:2249 (sofia/external/anonymous@anonymous.invalid) Callstate Change ACTIVE -> RINGING
2018-09-25 14:09:37.382709 [DEBUG] switch_core_state_machine.c:643 (sofia/external/anonymous@anonymous.invalid) State ROUTING
2018-09-25 14:09:37.382709 [DEBUG] mod_sofia.c:143 sofia/external/anonymous@anonymous.invalid SOFIA ROUTING
2018-09-25 14:09:37.382709 [DEBUG] switch_core_state_machine.c:236 sofia/external/anonymous@anonymous.invalid Standard ROUTING
2018-09-25 14:09:37.382709 [INFO] mod_dialplan_xml.c:637 Processing Anonymous <anonymous>->2008 in context pbx.numbercard.co.uk
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->user_exists] continue=true
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (PASS) [user_exists] () =~ // break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
2018-09-25 14:09:37.402727 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f40ac4a3660 Connected.
2018-09-25 14:09:37.402727 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f40ac4a3660 released.
EXECUTE sofia/external/anonymous@anonymous.invalid set(user_exists=true)
2018-09-25 14:09:37.402727 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [user_exists]=[true]
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (PASS) [user_exists] ${user_exists}(true) =~ /^true$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(extension_uuid=${user_data ${destination_number}@${domain_name} var extension_uuid}) INLINE
EXECUTE sofia/external/anonymous@anonymous.invalid set(extension_uuid=7751cac5-ad77-449e-b641-b606bc0f027c)
2018-09-25 14:09:37.402727 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [extension_uuid]=[7751cac5-ad77-449e-b641-b606bc0f027c]
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(hold_music=${user_data ${destination_number}@${domain_name} var hold_music}) INLINE
EXECUTE sofia/external/anonymous@anonymous.invalid set(hold_music=local_stream://default)
2018-09-25 14:09:37.402727 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [hold_music]=[local_stream://default]
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(forward_all_enabled=${user_data ${destination_number}@${domain_name} var forward_all_enabled}) INLINE
EXECUTE sofia/external/anonymous@anonymous.invalid set(forward_all_enabled=true)
2018-09-25 14:09:37.422683 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [forward_all_enabled]=[true]
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(forward_all_destination=${user_data ${destination_number}@${domain_name} var forward_all_destination}) INLINE
EXECUTE sofia/external/anonymous@anonymous.invalid set(forward_all_destination=07508901164)
2018-09-25 14:09:37.422683 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [forward_all_destination]=[07508901164]
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(forward_busy_enabled=${user_data ${destination_number}@${domain_name} var forward_busy_enabled}) INLINE
EXECUTE sofia/external/anonymous@anonymous.invalid set(forward_busy_enabled=false)
2018-09-25 14:09:37.422683 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [forward_busy_enabled]=[false]
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(forward_busy_destination=${user_data ${destination_number}@${domain_name} var forward_busy_destination}) INLINE
EXECUTE sofia/external/anonymous@anonymous.invalid set(forward_busy_destination=02085199022)
2018-09-25 14:09:37.422683 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [forward_busy_destination]=[02085199022]
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(forward_no_answer_enabled=${user_data ${destination_number}@${domain_name} var forward_no_answer_enabled}) INLINE
EXECUTE sofia/external/anonymous@anonymous.invalid set(forward_no_answer_enabled=false)
2018-09-25 14:09:37.422683 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [forward_no_answer_enabled]=[false]
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(forward_no_answer_destination=${user_data ${destination_number}@${domain_name} var forward_no_answer_destination}) INLINE
EXECUTE sofia/external/anonymous@anonymous.invalid set(forward_no_answer_destination=02085199022)
2018-09-25 14:09:37.442681 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [forward_no_answer_destination]=[02085199022]
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(forward_user_not_registered_enabled=${user_data ${destination_number}@${domain_name} var forward_user_not_registered_enabled}) INLINE
EXECUTE sofia/external/anonymous@anonymous.invalid set(forward_user_not_registered_enabled=false)
2018-09-25 14:09:37.442681 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [forward_user_not_registered_enabled]=[false]
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(forward_user_not_registered_destination=${user_data ${destination_number}@${domain_name} var forward_user_not_registered_destination}) INLINE
EXECUTE sofia/external/anonymous@anonymous.invalid set(forward_user_not_registered_destination=02085199022)
2018-09-25 14:09:37.442681 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [forward_user_not_registered_destination]=[02085199022]
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(do_not_disturb=${user_data ${destination_number}@${domain_name} var do_not_disturb}) INLINE
EXECUTE sofia/external/anonymous@anonymous.invalid set(do_not_disturb=false)
2018-09-25 14:09:37.442681 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [do_not_disturb]=[false]
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(call_timeout=${user_data ${destination_number}@${domain_name} var call_timeout}) INLINE
EXECUTE sofia/external/anonymous@anonymous.invalid set(call_timeout=30)
2018-09-25 14:09:37.442681 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [call_timeout]=[30]
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(missed_call_app=${user_data ${destination_number}@${domain_name} var missed_call_app}) INLINE
EXECUTE sofia/external/anonymous@anonymous.invalid set(missed_call_app=)
2018-09-25 14:09:37.462690 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [missed_call_app]=[UNDEF]
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(missed_call_data=${user_data ${destination_number}@${domain_name} var missed_call_data}) INLINE
EXECUTE sofia/external/anonymous@anonymous.invalid set(missed_call_data=)
2018-09-25 14:09:37.462690 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [missed_call_data]=[UNDEF]
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(toll_allow=${user_data ${destination_number}@${domain_name} var toll_allow}) INLINE
EXECUTE sofia/external/anonymous@anonymous.invalid set(toll_allow=)
2018-09-25 14:09:37.462690 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [toll_allow]=[UNDEF]
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(call_screen_enabled=${user_data ${destination_number}@${domain_name} var call_screen_enabled}) INLINE
EXECUTE sofia/external/anonymous@anonymous.invalid set(call_screen_enabled=false)
2018-09-25 14:09:37.462690 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [call_screen_enabled]=[false]
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->call-direction] continue=true
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (PASS) [call-direction] ${call_direction}(inbound) =~ /^(inbound|outbound|local)$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->variables] continue=true
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (PASS) [variables] () =~ // break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid Action export(origination_callee_id_name=${destination_number})
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->user_record] continue=true
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (PASS) [user_record] () =~ // break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(user_record=${user_data ${destination_number}@${domain_name} var user_record}) INLINE
EXECUTE sofia/external/anonymous@anonymous.invalid set(user_record=)
2018-09-25 14:09:37.462690 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [user_record]=[UNDEF]
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE
2018-09-25 14:09:37.462690 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f40ac4a3660 Connected.
2018-09-25 14:09:37.482809 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f40ac4a3660 released.
EXECUTE sofia/external/anonymous@anonymous.invalid set(from_user_exists=false)
2018-09-25 14:09:37.482809 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [from_user_exists]=[false]
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->redial] continue=true
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [redial] destination_number(2008) =~ /^(redial|\*870)$/ break=on-true
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (PASS) [redial] () =~ // break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->speed_dial] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [speed_dial] destination_number(2008) =~ /^\*0(.*)$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->No-Reg-simwood.00] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [No-Reg-simwood.00] destination_number(2008) =~ /^00(.*)$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->No-Reg-simwood.0] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [No-Reg-simwood.0] destination_number(2008) =~ /^0(.*)$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->agent_status] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [agent_status] destination_number(2008) =~ /^\*22$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->agent_status_id] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [agent_status_id] destination_number(2008) =~ /^\*23$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->group-intercept] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [group-intercept] destination_number(2008) =~ /^\*8$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->page-extension] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [page-extension] destination_number(2008) =~ /^\*8(\d{2,7})$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->eavesdrop] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [eavesdrop] destination_number(2008) =~ /^\*33(\d{2,7})$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->call_privacy] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [call_privacy] destination_number(2008) =~ /^\*67(\d+)$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->call_return] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [call_return] destination_number(2008) =~ /^\*69$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->extension_queue] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [extension_queue] destination_number(2008) =~ /^\*800(.*)$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->intercept-ext] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [intercept-ext] destination_number(2008) =~ /^\*\*(\d+)$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->intercept-ext-polycom] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [intercept-ext-polycom] destination_number(2008) =~ /^\*97(\d+)$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->dx] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [dx] destination_number(2008) =~ /^dx$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->att_xfer] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [att_xfer] destination_number(2008) =~ /^att_xfer$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->extension-to-voicemail] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (PASS) [extension-to-voicemail] ${user_exists}(true) =~ /^true$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (PASS) [extension-to-voicemail] username(anonymous) =~ /^anonymous$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [extension-to-voicemail] destination_number(2008) =~ /^anonymous$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->send_to_voicemail] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [send_to_voicemail] destination_number(2008) =~ /^\*99(\d{2,10})$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->vmain] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [vmain] destination_number(2008) =~ /^vmain$|^\*4000$|^\*98$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->xfer_vm] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [xfer_vm] destination_number(2008) =~ /^xfer_vm$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->is_transfer] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [is_transfer] destination_number(2008) =~ /^is_transfer$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->vmain_user] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [vmain_user] destination_number(2008) =~ /^\*97$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->IVRmorning] continue=
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [IVRmorning] destination_number(2008) =~ /^20000$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->cf] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [cf] destination_number(2008) =~ /^cf$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->delay_echo] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [delay_echo] destination_number(2008) =~ /^\*9195$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->echo] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [echo] destination_number(2008) =~ /^\*9196$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->is_zrtp_secure] continue=true
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [is_zrtp_secure] ${zrtp_secure_media_confirmed}() =~ /^true$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid ANTI-Action eval(not_secure)
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->milliwatt] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [milliwatt] destination_number(2008) =~ /^\*9197$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->is_secure] continue=true
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [is_secure] ${sip_via_protocol}(udp) =~ /tls/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->tone_stream] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [tone_stream] destination_number(2008) =~ /^\*9198$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->hold_music] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [hold_music] destination_number(2008) =~ /^\*9664$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->recordings] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [recordings] destination_number(2008) =~ /^\*(732)$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->directory] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [directory] destination_number(2008) =~ /^\*411$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->wake-up] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [wake-up] destination_number(2008) =~ /^\*(925)$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->valet_park] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [valet_park] destination_number(2008) =~ /^(park\+)?(\*59[0-9][0-9])$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [valet_park] ${sip_h_Referred-By}() =~ /sip:(.*)@.*/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [valet_park] destination_number(2008) =~ /^(park\+)?(\*59[0-9][0-9])$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [valet_park] destination_number(2008) =~ /^(park\+)?(\*59[0-9][0-9])$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->operator] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [operator] destination_number(2008) =~ /^0$|^operator$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->operator-forward] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [operator-forward] destination_number(2008) =~ /^\*000$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->do-not-disturb] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [do-not-disturb] destination_number(2008) =~ /^\*77$/ break=on-true
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [do-not-disturb] destination_number(2008) =~ /^\*78$|\*363$/ break=on-true
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [do-not-disturb] destination_number(2008) =~ /^\*79$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->call-forward] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [call-forward] destination_number(2008) =~ /^\*72$/ break=on-true
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [call-forward] destination_number(2008) =~ /^\*73$/ break=on-true
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [call-forward] destination_number(2008) =~ /^\*74$/ break=on-true
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->call forward all] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (PASS) [call forward all] ${user_exists}(true) =~ /^true/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (PASS) [call forward all] ${forward_all_enabled}(true) =~ /^true/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid Action transfer(${forward_all_destination} XML ${domain_name})
2018-09-25 14:09:37.482809 [DEBUG] switch_core_state_machine.c:286 (sofia/external/anonymous@anonymous.invalid) State Change CS_ROUTING -> CS_EXECUTE
2018-09-25 14:09:37.482809 [DEBUG] switch_core_state_machine.c:643 (sofia/external/anonymous@anonymous.invalid) State ROUTING going to sleep
2018-09-25 14:09:37.482809 [DEBUG] switch_core_state_machine.c:584 (sofia/external/anonymous@anonymous.invalid) Running State Change CS_EXECUTE (Cur 1 Tot 1476)
2018-09-25 14:09:37.482809 [DEBUG] switch_channel.c:2251 (sofia/external/anonymous@anonymous.invalid) Callstate Change RINGING -> ACTIVE
2018-09-25 14:09:37.482809 [DEBUG] switch_core_state_machine.c:650 (sofia/external/anonymous@anonymous.invalid) State EXECUTE
2018-09-25 14:09:37.482809 [DEBUG] mod_sofia.c:198 sofia/external/anonymous@anonymous.invalid SOFIA EXECUTE
2018-09-25 14:09:37.482809 [DEBUG] switch_core_state_machine.c:328 sofia/external/anonymous@anonymous.invalid Standard EXECUTE
EXECUTE sofia/external/anonymous@anonymous.invalid export(origination_callee_id_name=2008)
2018-09-25 14:09:37.482809 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [origination_callee_id_name]=[2008]
EXECUTE sofia/external/anonymous@anonymous.invalid set(RFC2822_DATE=Tue, 25 Sep 2018 14:09:37 +0000)
2018-09-25 14:09:37.482809 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [RFC2822_DATE]=[Tue, 25 Sep 2018 14:09:37 +0000]
EXECUTE sofia/external/anonymous@anonymous.invalid hash(insert/pbx.numbercard.co.uk-last_dial/anonymous/2008)
EXECUTE sofia/external/anonymous@anonymous.invalid eval(not_secure)
EXECUTE sofia/external/anonymous@anonymous.invalid transfer(07508901164 XML pbx.numbercard.co.uk)
2018-09-25 14:09:37.482809 [DEBUG] switch_ivr.c:2165 (sofia/external/anonymous@anonymous.invalid) State Change CS_EXECUTE -> CS_ROUTING
2018-09-25 14:09:37.482809 [NOTICE] switch_ivr.c:2172 Transfer sofia/external/anonymous@anonymous.invalid to XML[07508901164@pbx.numbercard.co.uk]
2018-09-25 14:09:37.482809 [DEBUG] switch_core_state_machine.c:650 (sofia/external/anonymous@anonymous.invalid) State EXECUTE going to sleep
2018-09-25 14:09:37.482809 [DEBUG] switch_core_state_machine.c:584 (sofia/external/anonymous@anonymous.invalid) Running State Change CS_ROUTING (Cur 1 Tot 1476)
2018-09-25 14:09:37.482809 [DEBUG] switch_channel.c:2249 (sofia/external/anonymous@anonymous.invalid) Callstate Change ACTIVE -> RINGING
2018-09-25 14:09:37.482809 [DEBUG] switch_core_state_machine.c:643 (sofia/external/anonymous@anonymous.invalid) State ROUTING
2018-09-25 14:09:37.482809 [DEBUG] mod_sofia.c:143 sofia/external/anonymous@anonymous.invalid SOFIA ROUTING
2018-09-25 14:09:37.482809 [DEBUG] switch_core_state_machine.c:236 sofia/external/anonymous@anonymous.invalid Standard ROUTING
2018-09-25 14:09:37.482809 [INFO] mod_dialplan_xml.c:637 Processing Anonymous <anonymous>->07508901164 in context pbx.numbercard.co.uk
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->user_exists] continue=true
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (PASS) [user_exists] () =~ // break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
2018-09-25 14:09:37.482809 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f40ac4a3660 Connected.
2018-09-25 14:09:37.482809 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f40ac4a3660 released.
EXECUTE sofia/external/anonymous@anonymous.invalid set(user_exists=false)
2018-09-25 14:09:37.482809 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [user_exists]=[false]
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_exists] ${user_exists}(false) =~ /^true$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->call-direction] continue=true
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (PASS) [call-direction] ${call_direction}(inbound) =~ /^(inbound|outbound|local)$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->variables] continue=true
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (PASS) [variables] () =~ // break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid Action export(origination_callee_id_name=${destination_number})
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->user_record] continue=true
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (PASS) [user_record] () =~ // break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(user_record=${user_data ${destination_number}@${domain_name} var user_record}) INLINE
2018-09-25 14:09:37.482809 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f40ac4a3660 Connected.
2018-09-25 14:09:37.502672 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f40ac4a3660 released.
2018-09-25 14:09:37.502672 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f40ac4a3660 Connected.
2018-09-25 14:09:37.502672 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f40ac4a3660 released.
EXECUTE sofia/external/anonymous@anonymous.invalid set(user_record=)
2018-09-25 14:09:37.502672 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [user_record]=[UNDEF]
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE
2018-09-25 14:09:37.502672 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f40ac4a3660 Connected.
2018-09-25 14:09:37.502672 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f40ac4a3660 released.
EXECUTE sofia/external/anonymous@anonymous.invalid set(from_user_exists=false)
2018-09-25 14:09:37.502672 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [from_user_exists]=[false]
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->redial] continue=true
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [redial] destination_number(07508901164) =~ /^(redial|\*870)$/ break=on-true
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (PASS) [redial] () =~ // break=never
Dialplan: sofia/external/anonymous@anonymous.invalid Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->speed_dial] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [speed_dial] destination_number(07508901164) =~ /^\*0(.*)$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->No-Reg-simwood.00] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (FAIL) [No-Reg-simwood.00] destination_number(07508901164) =~ /^00(.*)$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid parsing [pbx.numbercard.co.uk->No-Reg-simwood.0] continue=false
Dialplan: sofia/external/anonymous@anonymous.invalid Regex (PASS) [No-Reg-simwood.0] destination_number(07508901164) =~ /^0(.*)$/ break=on-false
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(sip_h_X-accountcode=${accountcode})
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(call_direction=outbound)
Dialplan: sofia/external/anonymous@anonymous.invalid Action unset(call_timeout)
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(hangup_after_bridge=true)
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(effective_caller_id_name=${outbound_caller_id_name})
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(effective_caller_id_number=${outbound_caller_id_number})
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(inherit_codec=true)
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(ignore_display_updates=true)
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(callee_id_number=7508901164)
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(continue_on_fail=true)
Dialplan: sofia/external/anonymous@anonymous.invalid Action set(outbound_prefix=0) INLINE
EXECUTE sofia/external/anonymous@anonymous.invalid set(outbound_prefix=0)
2018-09-25 14:09:37.502672 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [outbound_prefix]=[0]
Dialplan: sofia/external/anonymous@anonymous.invalid Action bridge(sofia/gateway/e5b92236-17ad-48c2-a0fe-6180da39ab31/447508901164)
2018-09-25 14:09:37.502672 [DEBUG] switch_core_state_machine.c:286 (sofia/external/anonymous@anonymous.invalid) State Change CS_ROUTING -> CS_EXECUTE
2018-09-25 14:09:37.502672 [DEBUG] switch_core_state_machine.c:643 (sofia/external/anonymous@anonymous.invalid) State ROUTING going to sleep
2018-09-25 14:09:37.502672 [DEBUG] switch_core_state_machine.c:584 (sofia/external/anonymous@anonymous.invalid) Running State Change CS_EXECUTE (Cur 1 Tot 1476)
2018-09-25 14:09:37.502672 [DEBUG] switch_channel.c:2251 (sofia/external/anonymous@anonymous.invalid) Callstate Change RINGING -> ACTIVE
2018-09-25 14:09:37.502672 [DEBUG] switch_core_state_machine.c:650 (sofia/external/anonymous@anonymous.invalid) State EXECUTE
2018-09-25 14:09:37.502672 [DEBUG] mod_sofia.c:198 sofia/external/anonymous@anonymous.invalid SOFIA EXECUTE
2018-09-25 14:09:37.502672 [DEBUG] switch_core_state_machine.c:328 sofia/external/anonymous@anonymous.invalid Standard EXECUTE
EXECUTE sofia/external/anonymous@anonymous.invalid export(origination_callee_id_name=07508901164)
2018-09-25 14:09:37.502672 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [origination_callee_id_name]=[07508901164]
EXECUTE sofia/external/anonymous@anonymous.invalid set(RFC2822_DATE=Tue, 25 Sep 2018 14:09:37 +0000)
2018-09-25 14:09:37.502672 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [RFC2822_DATE]=[Tue, 25 Sep 2018 14:09:37 +0000]
EXECUTE sofia/external/anonymous@anonymous.invalid hash(insert/pbx.numbercard.co.uk-last_dial/anonymous/07508901164)
EXECUTE sofia/external/anonymous@anonymous.invalid set(sip_h_X-accountcode=)
2018-09-25 14:09:37.502672 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [sip_h_X-accountcode]=[UNDEF]
EXECUTE sofia/external/anonymous@anonymous.invalid set(call_direction=outbound)
2018-09-25 14:09:37.502672 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [call_direction]=[outbound]
EXECUTE sofia/external/anonymous@anonymous.invalid unset(call_timeout)
2018-09-25 14:09:37.502672 [DEBUG] mod_dptools.c:1693 UNSET [call_timeout]
EXECUTE sofia/external/anonymous@anonymous.invalid set(hangup_after_bridge=true)
2018-09-25 14:09:37.502672 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [hangup_after_bridge]=[true]
EXECUTE sofia/external/anonymous@anonymous.invalid set(effective_caller_id_name=)
2018-09-25 14:09:37.502672 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [effective_caller_id_name]=[UNDEF]
EXECUTE sofia/external/anonymous@anonymous.invalid set(effective_caller_id_number=)
2018-09-25 14:09:37.522907 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [effective_caller_id_number]=[UNDEF]
EXECUTE sofia/external/anonymous@anonymous.invalid set(inherit_codec=true)
2018-09-25 14:09:37.522907 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [inherit_codec]=[true]
EXECUTE sofia/external/anonymous@anonymous.invalid set(ignore_display_updates=true)
2018-09-25 14:09:37.522907 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [ignore_display_updates]=[true]
EXECUTE sofia/external/anonymous@anonymous.invalid set(callee_id_number=7508901164)
2018-09-25 14:09:37.522907 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [callee_id_number]=[7508901164]
EXECUTE sofia/external/anonymous@anonymous.invalid set(continue_on_fail=true)
2018-09-25 14:09:37.522907 [DEBUG] mod_dptools.c:1548 SET sofia/external/anonymous@anonymous.invalid [continue_on_fail]=[true]
EXECUTE sofia/external/anonymous@anonymous.invalid bridge(sofia/gateway/e5b92236-17ad-48c2-a0fe-6180da39ab31/447508901164)
2018-09-25 14:09:37.522907 [DEBUG] switch_channel.c:1823 (sofia/external/anonymous@anonymous.invalid) Callstate Change ACTIVE -> RING_WAIT
2018-09-25 14:09:37.522907 [DEBUG] switch_channel.c:1250 sofia/external/anonymous@anonymous.invalid EXPORTING[export_vars] [origination_callee_id_name]=[07508901164] to event
2018-09-25 14:09:37.522907 [DEBUG] switch_channel.c:1250 sofia/external/anonymous@anonymous.invalid EXPORTING[export_vars] [origination_callee_id_name]=[07508901164] to event
2018-09-25 14:09:37.522907 [DEBUG] switch_channel.c:1250 sofia/external/anonymous@anonymous.invalid EXPORTING[export_vars] [origination_callee_id_name]=[07508901164] to event
2018-09-25 14:09:37.522907 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
2018-09-25 14:09:37.522907 [NOTICE] switch_channel.c:1104 New Channel sofia/external/447508901164 [51b6f200-4ce2-4357-a4b5-776d5aa5b35a]
2018-09-25 14:09:37.522907 [DEBUG] mod_sofia.c:4803 sofia/external/447508901164 setting variable [sip_cid_type]=[pid]
2018-09-25 14:09:37.522907 [DEBUG] mod_sofia.c:4819 (sofia/external/447508901164) State Change CS_NEW -> CS_INIT
2018-09-25 14:09:37.522907 [DEBUG] switch_core_state_machine.c:584 (sofia/external/447508901164) Running State Change CS_INIT (Cur 2 Tot 1477)
2018-09-25 14:09:37.522907 [DEBUG] switch_core_state_machine.c:627 (sofia/external/447508901164) State INIT
2018-09-25 14:09:37.522907 [DEBUG] mod_sofia.c:90 sofia/external/447508901164 SOFIA INIT
2018-09-25 14:09:37.522907 [DEBUG] sofia_glue.c:1295 sofia/external/447508901164 sending invite version: 1.6.19 -36-7a77e0b 64bit
Local SDP:
v=0
o=FreeSWITCH 1537866563 1537866564 IN IP4 46.101.82.189
s=FreeSWITCH
c=IN IP4 46.101.82.189
t=0 0
m=audio 18014 RTP/AVP 9 0 8 3 101 13
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:13 CN/8000
a=ptime:20
a=sendrecv

2018-09-25 14:09:37.522907 [DEBUG] switch_core_state_machine.c:40 sofia/external/447508901164 Standard INIT
2018-09-25 14:09:37.522907 [DEBUG] switch_core_state_machine.c:48 (sofia/external/447508901164) State Change CS_INIT -> CS_ROUTING
2018-09-25 14:09:37.522907 [DEBUG] switch_core_state_machine.c:627 (sofia/external/447508901164) State INIT going to sleep
2018-09-25 14:09:37.522907 [DEBUG] switch_core_state_machine.c:584 (sofia/external/447508901164) Running State Change CS_ROUTING (Cur 2 Tot 1477)
2018-09-25 14:09:37.522907 [DEBUG] sofia.c:7084 Channel sofia/external/447508901164 entering state [calling][0]
2018-09-25 14:09:37.522907 [DEBUG] switch_core_state_machine.c:643 (sofia/external/447508901164) State ROUTING
2018-09-25 14:09:37.522907 [DEBUG] mod_sofia.c:143 sofia/external/447508901164 SOFIA ROUTING
2018-09-25 14:09:37.522907 [DEBUG] switch_ivr_originate.c:67 (sofia/external/447508901164) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2018-09-25 14:09:37.522907 [DEBUG] switch_core_state_machine.c:643 (sofia/external/447508901164) State ROUTING going to sleep
2018-09-25 14:09:37.522907 [DEBUG] switch_core_state_machine.c:584 (sofia/external/447508901164) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 1477)
2018-09-25 14:09:37.522907 [DEBUG] switch_core_state_machine.c:662 (sofia/external/447508901164) State CONSUME_MEDIA
2018-09-25 14:09:37.522907 [DEBUG] switch_core_state_machine.c:662 (sofia/external/447508901164) State CONSUME_MEDIA going to sleep
2018-09-25 14:09:37.662669 [DEBUG] sofia.c:6257 Remote Reason: 88
2018-09-25 14:09:37.662669 [DEBUG] sofia.c:7084 Channel sofia/external/447508901164 entering state [terminated][488]
2018-09-25 14:09:37.662669 [NOTICE] sofia.c:8273 Hangup sofia/external/447508901164 [CS_CONSUME_MEDIA] [INCOMPATIBLE_DESTINATION]
2018-09-25 14:09:37.662669 [DEBUG] switch_core_state_machine.c:584 (sofia/external/447508901164) Running State Change CS_HANGUP (Cur 2 Tot 1477)
2018-09-25 14:09:37.662669 [DEBUG] switch_core_state_machine.c:850 (sofia/external/447508901164) Callstate Change DOWN -> HANGUP
2018-09-25 14:09:37.662669 [DEBUG] switch_core_state_machine.c:852 (sofia/external/447508901164) State HANGUP
2018-09-25 14:09:37.662669 [DEBUG] mod_sofia.c:438 Channel sofia/external/447508901164 hanging up, cause: INCOMPATIBLE_DESTINATION
2018-09-25 14:09:37.662669 [DEBUG] switch_core_state_machine.c:60 sofia/external/447508901164 Standard HANGUP, cause: INCOMPATIBLE_DESTINATION
2018-09-25 14:09:37.662669 [DEBUG] switch_core_state_machine.c:852 (sofia/external/447508901164) State HANGUP going to sleep
2018-09-25 14:09:37.662669 [DEBUG] switch_core_state_machine.c:619 (sofia/external/447508901164) State Change CS_HANGUP -> CS_REPORTING
2018-09-25 14:09:37.662669 [DEBUG] switch_core_state_machine.c:584 (sofia/external/447508901164) Running State Change CS_REPORTING (Cur 2 Tot 1477)
2018-09-25 14:09:37.662669 [DEBUG] switch_core_state_machine.c:938 (sofia/external/447508901164) State REPORTING
2018-09-25 14:09:37.662669 [DEBUG] switch_core_state_machine.c:174 sofia/external/447508901164 Standard REPORTING, cause: INCOMPATIBLE_DESTINATION
2018-09-25 14:09:37.662669 [DEBUG] switch_core_state_machine.c:938 (sofia/external/447508901164) State REPORTING going to sleep
2018-09-25 14:09:37.662669 [DEBUG] switch_core_state_machine.c:610 (sofia/external/447508901164) State Change CS_REPORTING -> CS_DESTROY
2018-09-25 14:09:37.662669 [DEBUG] switch_core_session.c:1665 Session 1477 (sofia/external/447508901164) Locked, Waiting on external entities
2018-09-25 14:09:37.662669 [DEBUG] switch_ivr_originate.c:3848 Originate Resulted in Error Cause: 88 [INCOMPATIBLE_DESTINATION]
2018-09-25 14:09:37.662669 [DEBUG] switch_channel.c:2047 (sofia/external/anonymous@anonymous.invalid) Callstate Change RING_WAIT -> ACTIVE
2018-09-25 14:09:37.662669 [INFO] mod_dptools.c:3436 Originate Failed. Cause: INCOMPATIBLE_DESTINATION
2018-09-25 14:09:37.662669 [NOTICE] switch_core_state_machine.c:385 sofia/external/anonymous@anonymous.invalid has executed the last dialplan instruction, hanging up.
2018-09-25 14:09:37.662669 [NOTICE] switch_core_state_machine.c:387 Hangup sofia/external/anonymous@anonymous.invalid [CS_EXECUTE] [NORMAL_CLEARING]
2018-09-25 14:09:37.662669 [DEBUG] switch_core_state_machine.c:650 (sofia/external/anonymous@anonymous.invalid) State EXECUTE going to sleep
2018-09-25 14:09:37.662669 [DEBUG] switch_core_state_machine.c:584 (sofia/external/anonymous@anonymous.invalid) Running State Change CS_HANGUP (Cur 2 Tot 1477)
2018-09-25 14:09:37.662669 [DEBUG] switch_core_state_machine.c:850 (sofia/external/anonymous@anonymous.invalid) Callstate Change ACTIVE -> HANGUP
2018-09-25 14:09:37.662669 [DEBUG] switch_core_state_machine.c:852 (sofia/external/anonymous@anonymous.invalid) State HANGUP
2018-09-25 14:09:37.662669 [DEBUG] mod_sofia.c:432 sofia/external/anonymous@anonymous.invalid Overriding SIP cause 480 with 488 from the other leg
2018-09-25 14:09:37.662669 [DEBUG] mod_sofia.c:438 Channel sofia/external/anonymous@anonymous.invalid hanging up, cause: NORMAL_CLEARING
2018-09-25 14:09:37.662669 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/external/anonymous@anonymous.invalid
2018-09-25 14:09:37.662669 [DEBUG] switch_core_state_machine.c:60 sofia/external/anonymous@anonymous.invalid Standard HANGUP, cause: NORMAL_CLEARING
2018-09-25 14:09:37.662669 [DEBUG] switch_core_state_machine.c:852 (sofia/external/anonymous@anonymous.invalid) State HANGUP going to sleep
2018-09-25 14:09:37.662669 [DEBUG] switch_core_state_machine.c:619 (sofia/external/anonymous@anonymous.invalid) State Change CS_HANGUP -> CS_REPORTING
2018-09-25 14:09:37.662669 [DEBUG] switch_core_state_machine.c:584 (sofia/external/anonymous@anonymous.invalid) Running State Change CS_REPORTING (Cur 2 Tot 1477)
2018-09-25 14:09:37.662669 [DEBUG] switch_core_state_machine.c:938 (sofia/external/anonymous@anonymous.invalid) State REPORTING
2018-09-25 14:09:37.662669 [NOTICE] switch_core_session.c:1683 Session 1477 (sofia/external/447508901164) Ended
2018-09-25 14:09:37.662669 [NOTICE] switch_core_session.c:1687 Close Channel sofia/external/447508901164 [CS_DESTROY]
2018-09-25 14:09:37.662669 [DEBUG] switch_core_state_machine.c:741 (sofia/external/447508901164) Running State Change CS_DESTROY (Cur 1 Tot 1477)
2018-09-25 14:09:37.662669 [DEBUG] switch_core_state_machine.c:751 (sofia/external/447508901164) State DESTROY
2018-09-25 14:09:37.662669 [DEBUG] mod_sofia.c:343 sofia/external/447508901164 SOFIA DESTROY
2018-09-25 14:09:37.662669 [DEBUG] switch_core_state_machine.c:181 sofia/external/447508901164 Standard DESTROY
2018-09-25 14:09:37.662669 [DEBUG] switch_core_state_machine.c:751 (sofia/external/447508901164) State DESTROY going to sleep
2018-09-25 14:09:37.842673 [DEBUG] switch_core_state_machine.c:174 sofia/external/anonymous@anonymous.invalid Standard REPORTING, cause: NORMAL_CLEARING
2018-09-25 14:09:37.842673 [DEBUG] switch_core_state_machine.c:938 (sofia/external/anonymous@anonymous.invalid) State REPORTING going to sleep
2018-09-25 14:09:37.842673 [DEBUG] switch_core_state_machine.c:610 (sofia/external/anonymous@anonymous.invalid) State Change CS_REPORTING -> CS_DESTROY
2018-09-25 14:09:37.842673 [DEBUG] switch_core_session.c:1665 Session 1476 (sofia/external/anonymous@anonymous.invalid) Locked, Waiting on external entities
2018-09-25 14:09:37.842673 [NOTICE] switch_core_session.c:1683 Session 1476 (sofia/external/anonymous@anonymous.invalid) Ended
2018-09-25 14:09:37.842673 [NOTICE] switch_core_session.c:1687 Close Channel sofia/external/anonymous@anonymous.invalid [CS_DESTROY]
2018-09-25 14:09:37.842673 [DEBUG] switch_core_state_machine.c:741 (sofia/external/anonymous@anonymous.invalid) Running State Change CS_DESTROY (Cur 0 Tot 1477)
2018-09-25 14:09:37.842673 [DEBUG] switch_core_state_machine.c:751 (sofia/external/anonymous@anonymous.invalid) State DESTROY
2018-09-25 14:09:37.842673 [DEBUG] mod_sofia.c:343 sofia/external/anonymous@anonymous.invalid SOFIA DESTROY
2018-09-25 14:09:37.842673 [DEBUG] switch_core_state_machine.c:181 sofia/external/anonymous@anonymous.invalid Standard DESTROY
2018-09-25 14:09:37.842673 [DEBUG] switch_core_state_machine.c:751 (sofia/external/anonymous@anonymous.invalid) State DESTROY going to sleep

Displayed number log
2018-09-25 14:14:39.202710 [NOTICE] switch_channel.c:1104 New Channel sofia/external/02085199022@178.22.139.5 [5a406c6f-3df9-41e1-b137-16a18774fd50]
2018-09-25 14:14:39.202710 [DEBUG] switch_core_state_machine.c:584 (sofia/external/02085199022@178.22.139.5) Running State Change CS_NEW (Cur 1 Tot 1481)
2018-09-25 14:14:39.202710 [DEBUG] sofia.c:9873 sofia/external/02085199022@178.22.139.5 receiving invite from 178.22.139.77:5060 version: 1.6.19 -36-7a77e0b 64bit
2018-09-25 14:14:39.202710 [DEBUG] sofia.c:7084 Channel sofia/external/02085199022@178.22.139.5 entering state [received][100]
2018-09-25 14:14:39.202710 [DEBUG] sofia.c:7094 Remote SDP:
v=0
o=FreeSWITCH 1537853774 1537853775 IN IP4 178.22.139.5
s=FreeSWITCH
c=IN IP4 178.22.139.5
t=0 0
m=audio 31104 RTP/AVP 9 8 0 101 13
a=rtpmap:9 G722/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:13 CN/8000
a=rtcp-mux
a=rtcp:31104 IN IP4 178.22.139.5
a=ptime:20

2018-09-25 14:14:39.202710 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2018-09-25 14:14:39.202710 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
2018-09-25 14:14:39.202710 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2018-09-25 14:14:39.202710 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2018-09-25 14:14:39.202710 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
2018-09-25 14:14:39.202710 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2018-09-25 14:14:39.202710 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2018-09-25 14:14:39.202710 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2018-09-25 14:14:39.202710 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
2018-09-25 14:14:39.202710 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
2018-09-25 14:14:39.202710 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2018-09-25 14:14:39.202710 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2018-09-25 14:14:39.202710 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
2018-09-25 14:14:39.202710 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2018-09-25 14:14:39.202710 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
2018-09-25 14:14:39.202710 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
2018-09-25 14:14:39.222895 [DEBUG] switch_core_media.c:3061 Set Codec sofia/external/02085199022@178.22.139.5 G722/8000 20 ms 160 samples 64000 bits 1 channels
2018-09-25 14:14:39.222895 [DEBUG] switch_core_codec.c:111 sofia/external/02085199022@178.22.139.5 Original read codec set to G722:9
2018-09-25 14:14:39.222895 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
2018-09-25 14:14:39.222895 [DEBUG] switch_core_media.c:4767 sofia/external/02085199022@178.22.139.5 Set 2833 dtmf send payload to 101 recv payload to 101
2018-09-25 14:14:39.222895 [DEBUG] sofia.c:7507 (sofia/external/02085199022@178.22.139.5) State Change CS_NEW -> CS_INIT
2018-09-25 14:14:39.222895 [DEBUG] switch_core_state_machine.c:603 (sofia/external/02085199022@178.22.139.5) State NEW
2018-09-25 14:14:39.222895 [DEBUG] switch_core_state_machine.c:584 (sofia/external/02085199022@178.22.139.5) Running State Change CS_INIT (Cur 1 Tot 1481)
2018-09-25 14:14:39.222895 [DEBUG] switch_core_state_machine.c:627 (sofia/external/02085199022@178.22.139.5) State INIT
2018-09-25 14:14:39.222895 [DEBUG] mod_sofia.c:90 sofia/external/02085199022@178.22.139.5 SOFIA INIT
2018-09-25 14:14:39.222895 [DEBUG] switch_core_state_machine.c:40 sofia/external/02085199022@178.22.139.5 Standard INIT
2018-09-25 14:14:39.222895 [DEBUG] switch_core_state_machine.c:48 (sofia/external/02085199022@178.22.139.5) State Change CS_INIT -> CS_ROUTING
2018-09-25 14:14:39.222895 [DEBUG] switch_core_state_machine.c:627 (sofia/external/02085199022@178.22.139.5) State INIT going to sleep
2018-09-25 14:14:39.222895 [DEBUG] switch_core_state_machine.c:584 (sofia/external/02085199022@178.22.139.5) Running State Change CS_ROUTING (Cur 1 Tot 1481)
2018-09-25 14:14:39.222895 [DEBUG] switch_channel.c:2249 (sofia/external/02085199022@178.22.139.5) Callstate Change DOWN -> RINGING
2018-09-25 14:14:39.222895 [DEBUG] switch_core_state_machine.c:643 (sofia/external/02085199022@178.22.139.5) State ROUTING
2018-09-25 14:14:39.222895 [DEBUG] mod_sofia.c:143 sofia/external/02085199022@178.22.139.5 SOFIA ROUTING
2018-09-25 14:14:39.222895 [DEBUG] switch_core_state_machine.c:236 sofia/external/02085199022@178.22.139.5 Standard ROUTING
2018-09-25 14:14:39.222895 [INFO] mod_dialplan_xml.c:637 Processing 02085199022 <02085199022>->442081991111 in context public
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [public->442071691000] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [442071691000] destination_number(442081991111) =~ /^(442071691000)$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [public->442071697134] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [442071697134] destination_number(442081991111) =~ /^(442071697134)$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [public->442081991111] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (PASS) [442081991111] destination_number(442081991111) =~ /^(442081991111)$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(call_direction=inbound) INLINE
EXECUTE sofia/external/02085199022@178.22.139.5 set(call_direction=inbound)
2018-09-25 14:14:39.222895 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [call_direction]=[inbound]
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(domain_uuid=2c9c2dc9-07b3-4849-85c1-513f7d2db366) INLINE
EXECUTE sofia/external/02085199022@178.22.139.5 set(domain_uuid=2c9c2dc9-07b3-4849-85c1-513f7d2db366)
2018-09-25 14:14:39.222895 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [domain_uuid]=[2c9c2dc9-07b3-4849-85c1-513f7d2db366]
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(domain_name=pbx.numbercard.co.uk) INLINE
EXECUTE sofia/external/02085199022@178.22.139.5 set(domain_name=pbx.numbercard.co.uk)
2018-09-25 14:14:39.222895 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [domain_name]=[pbx.numbercard.co.uk]
Dialplan: sofia/external/02085199022@178.22.139.5 Action transfer(20000 XML pbx.numbercard.co.uk)
2018-09-25 14:14:39.222895 [DEBUG] switch_core_state_machine.c:286 (sofia/external/02085199022@178.22.139.5) State Change CS_ROUTING -> CS_EXECUTE
2018-09-25 14:14:39.222895 [DEBUG] switch_core_state_machine.c:643 (sofia/external/02085199022@178.22.139.5) State ROUTING going to sleep
2018-09-25 14:14:39.222895 [DEBUG] switch_core_state_machine.c:584 (sofia/external/02085199022@178.22.139.5) Running State Change CS_EXECUTE (Cur 1 Tot 1481)
2018-09-25 14:14:39.222895 [DEBUG] switch_core_state_machine.c:650 (sofia/external/02085199022@178.22.139.5) State EXECUTE
2018-09-25 14:14:39.222895 [DEBUG] mod_sofia.c:198 sofia/external/02085199022@178.22.139.5 SOFIA EXECUTE
2018-09-25 14:14:39.222895 [DEBUG] switch_core_state_machine.c:328 sofia/external/02085199022@178.22.139.5 Standard EXECUTE
EXECUTE sofia/external/02085199022@178.22.139.5 transfer(20000 XML pbx.numbercard.co.uk)
2018-09-25 14:14:39.222895 [DEBUG] switch_ivr.c:2165 (sofia/external/02085199022@178.22.139.5) State Change CS_EXECUTE -> CS_ROUTING
2018-09-25 14:14:39.222895 [NOTICE] switch_ivr.c:2172 Transfer sofia/external/02085199022@178.22.139.5 to XML[20000@pbx.numbercard.co.uk]
2018-09-25 14:14:39.222895 [DEBUG] switch_core_state_machine.c:650 (sofia/external/02085199022@178.22.139.5) State EXECUTE going to sleep
2018-09-25 14:14:39.222895 [DEBUG] switch_core_state_machine.c:584 (sofia/external/02085199022@178.22.139.5) Running State Change CS_ROUTING (Cur 1 Tot 1481)
2018-09-25 14:14:39.222895 [DEBUG] switch_core_state_machine.c:643 (sofia/external/02085199022@178.22.139.5) State ROUTING
2018-09-25 14:14:39.222895 [DEBUG] mod_sofia.c:143 sofia/external/02085199022@178.22.139.5 SOFIA ROUTING
2018-09-25 14:14:39.222895 [DEBUG] switch_core_state_machine.c:236 sofia/external/02085199022@178.22.139.5 Standard ROUTING
2018-09-25 14:14:39.222895 [INFO] mod_dialplan_xml.c:637 Processing 02085199022 <02085199022>->20000 in context pbx.numbercard.co.uk
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->user_exists] continue=true
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (PASS) [user_exists] () =~ // break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
2018-09-25 14:14:39.282757 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f40ac3d4880 Connected.
2018-09-25 14:14:39.302682 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f40ac3d4880 released.
EXECUTE sofia/external/02085199022@178.22.139.5 set(user_exists=false)
2018-09-25 14:14:39.302682 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [user_exists]=[false]
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_exists] ${user_exists}(false) =~ /^true$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->call-direction] continue=true
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (PASS) [call-direction] ${call_direction}(inbound) =~ /^(inbound|outbound|local)$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->variables] continue=true
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (PASS) [variables] () =~ // break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 Action export(origination_callee_id_name=${destination_number})
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->user_record] continue=true
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (PASS) [user_record] () =~ // break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(user_record=${user_data ${destination_number}@${domain_name} var user_record}) INLINE
2018-09-25 14:14:39.302682 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f40ac3d4880 Connected.
2018-09-25 14:14:39.322892 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f40ac3d4880 released.
2018-09-25 14:14:39.322892 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f40ac3d4880 Connected.
2018-09-25 14:14:39.322892 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f40ac3d4880 released.
EXECUTE sofia/external/02085199022@178.22.139.5 set(user_record=)
2018-09-25 14:14:39.322892 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [user_record]=[UNDEF]
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE
2018-09-25 14:14:39.342663 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f40ac3d4880 Connected.
2018-09-25 14:14:39.342663 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f40ac3d4880 released.
EXECUTE sofia/external/02085199022@178.22.139.5 set(from_user_exists=false)
2018-09-25 14:14:39.342663 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [from_user_exists]=[false]
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->redial] continue=true
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [redial] destination_number(20000) =~ /^(redial|\*870)$/ break=on-true
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (PASS) [redial] () =~ // break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->speed_dial] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [speed_dial] destination_number(20000) =~ /^\*0(.*)$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->No-Reg-simwood.00] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [No-Reg-simwood.00] destination_number(20000) =~ /^00(.*)$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->No-Reg-simwood.0] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [No-Reg-simwood.0] destination_number(20000) =~ /^0(.*)$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->agent_status] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [agent_status] destination_number(20000) =~ /^\*22$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->agent_status_id] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [agent_status_id] destination_number(20000) =~ /^\*23$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->group-intercept] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [group-intercept] destination_number(20000) =~ /^\*8$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->page-extension] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [page-extension] destination_number(20000) =~ /^\*8(\d{2,7})$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->eavesdrop] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [eavesdrop] destination_number(20000) =~ /^\*33(\d{2,7})$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->call_privacy] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [call_privacy] destination_number(20000) =~ /^\*67(\d+)$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->call_return] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [call_return] destination_number(20000) =~ /^\*69$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->extension_queue] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [extension_queue] destination_number(20000) =~ /^\*800(.*)$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->intercept-ext] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [intercept-ext] destination_number(20000) =~ /^\*\*(\d+)$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->intercept-ext-polycom] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [intercept-ext-polycom] destination_number(20000) =~ /^\*97(\d+)$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->dx] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [dx] destination_number(20000) =~ /^dx$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->att_xfer] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [att_xfer] destination_number(20000) =~ /^att_xfer$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->extension-to-voicemail] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [extension-to-voicemail] ${user_exists}(false) =~ /^true$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->send_to_voicemail] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [send_to_voicemail] destination_number(20000) =~ /^\*99(\d{2,10})$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->vmain] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [vmain] destination_number(20000) =~ /^vmain$|^\*4000$|^\*98$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->xfer_vm] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [xfer_vm] destination_number(20000) =~ /^xfer_vm$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->is_transfer] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [is_transfer] destination_number(20000) =~ /^is_transfer$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->vmain_user] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [vmain_user] destination_number(20000) =~ /^\*97$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->IVRmorning] continue=
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (PASS) [IVRmorning] destination_number(20000) =~ /^20000$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 Action answer()
Dialplan: sofia/external/02085199022@178.22.139.5 Action sleep(1000)
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(hangup_after_bridge=true)
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(ringback=local_stream://default)
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(transfer_ringback=local_stream://default)
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(ivr_menu_uuid=a9b7a5d3-aa3b-40ae-882f-a3daaa4f9378)
Dialplan: sofia/external/02085199022@178.22.139.5 Action ivr(a9b7a5d3-aa3b-40ae-882f-a3daaa4f9378)
Dialplan: sofia/external/02085199022@178.22.139.5 Action lua(streamfile.lua pleaseHold.wav)
2018-09-25 14:14:39.342663 [DEBUG] switch_core_state_machine.c:286 (sofia/external/02085199022@178.22.139.5) State Change CS_ROUTING -> CS_EXECUTE
2018-09-25 14:14:39.342663 [DEBUG] switch_core_state_machine.c:643 (sofia/external/02085199022@178.22.139.5) State ROUTING going to sleep
2018-09-25 14:14:39.342663 [DEBUG] switch_core_state_machine.c:584 (sofia/external/02085199022@178.22.139.5) Running State Change CS_EXECUTE (Cur 1 Tot 1481)
2018-09-25 14:14:39.342663 [DEBUG] switch_core_state_machine.c:650 (sofia/external/02085199022@178.22.139.5) State EXECUTE
2018-09-25 14:14:39.342663 [DEBUG] mod_sofia.c:198 sofia/external/02085199022@178.22.139.5 SOFIA EXECUTE
2018-09-25 14:14:39.342663 [DEBUG] switch_core_state_machine.c:328 sofia/external/02085199022@178.22.139.5 Standard EXECUTE
EXECUTE sofia/external/02085199022@178.22.139.5 export(origination_callee_id_name=20000)
2018-09-25 14:14:39.342663 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [origination_callee_id_name]=[20000]
EXECUTE sofia/external/02085199022@178.22.139.5 set(RFC2822_DATE=Tue, 25 Sep 2018 14:14:39 +0000)
2018-09-25 14:14:39.342663 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [RFC2822_DATE]=[Tue, 25 Sep 2018 14:14:39 +0000]
EXECUTE sofia/external/02085199022@178.22.139.5 hash(insert/pbx.numbercard.co.uk-last_dial/02085199022/20000)
EXECUTE sofia/external/02085199022@178.22.139.5 answer()
2018-09-25 14:14:39.342663 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/external/02085199022@178.22.139.5] 46.101.82.189 port 17874 -> 178.22.139.5 port 31104 codec: 9 ms: 20
2018-09-25 14:14:39.342663 [DEBUG] switch_rtp.c:4111 Starting timer [soft] 160 bytes per 20ms
2018-09-25 14:14:39.342663 [DEBUG] switch_core_media.c:7098 Activating RTCP PORT 31104
2018-09-25 14:14:39.342663 [DEBUG] switch_rtp.c:4507 RTCP send rate is: 1000 and packet rate is: 20000 Remote Port: 31104
2018-09-25 14:14:39.342663 [DEBUG] switch_core_media.c:7179 sofia/external/02085199022@178.22.139.5 Set 2833 dtmf send payload to 101
2018-09-25 14:14:39.342663 [DEBUG] switch_core_media.c:7186 sofia/external/02085199022@178.22.139.5 Set 2833 dtmf receive payload to 101
2018-09-25 14:14:39.342663 [DEBUG] switch_core_media.c:7209 sofia/external/02085199022@178.22.139.5 Set rtp dtmf delay to 40
2018-09-25 14:14:39.342663 [DEBUG] switch_core_media.c:7215 Set comfort noise payload to 13
2018-09-25 14:14:39.342663 [DEBUG] mod_sofia.c:850 Local SDP sofia/external/02085199022@178.22.139.5:
v=0
o=FreeSWITCH 1537867005 1537867006 IN IP4 46.101.82.189
s=FreeSWITCH
c=IN IP4 46.101.82.189
t=0 0
m=audio 17874 RTP/AVP 9 101 13
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:13 CN/8000
a=ptime:20
a=sendrecv
a=rtcp-mux
a=rtcp:17874 IN IP4 46.101.82.189

2018-09-25 14:14:39.342663 [DEBUG] sofia.c:7084 Channel sofia/external/02085199022@178.22.139.5 entering state [completed][200]
2018-09-25 14:14:39.342663 [NOTICE] mod_dptools.c:1312 Channel [sofia/external/02085199022@178.22.139.5] has been answered
2018-09-25 14:14:39.342663 [DEBUG] switch_channel.c:3773 (sofia/external/02085199022@178.22.139.5) Callstate Change RINGING -> ACTIVE
EXECUTE sofia/external/02085199022@178.22.139.5 sleep(1000)
2018-09-25 14:14:39.362722 [DEBUG] sofia.c:7084 Channel sofia/external/02085199022@178.22.139.5 entering state [ready][200]
2018-09-25 14:14:39.482822 [DEBUG] switch_rtp.c:1887 rtcp_stats_init: audio ssrc[3551470558] base_seq[53008]
2018-09-25 14:14:39.502709 [DEBUG] switch_rtp.c:7271 Correct audio ip/port confirmed.
EXECUTE sofia/external/02085199022@178.22.139.5 set(hangup_after_bridge=true)
2018-09-25 14:14:40.342705 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [hangup_after_bridge]=[true]
EXECUTE sofia/external/02085199022@178.22.139.5 set(ringback=local_stream://default)
2018-09-25 14:14:40.342705 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [ringback]=[local_stream://default]
EXECUTE sofia/external/02085199022@178.22.139.5 set(transfer_ringback=local_stream://default)
2018-09-25 14:14:40.342705 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [transfer_ringback]=[local_stream://default]
EXECUTE sofia/external/02085199022@178.22.139.5 set(ivr_menu_uuid=a9b7a5d3-aa3b-40ae-882f-a3daaa4f9378)
2018-09-25 14:14:40.342705 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [ivr_menu_uuid]=[a9b7a5d3-aa3b-40ae-882f-a3daaa4f9378]
EXECUTE sofia/external/02085199022@178.22.139.5 ivr(a9b7a5d3-aa3b-40ae-882f-a3daaa4f9378)
2018-09-25 14:14:40.342705 [DEBUG] switch_ivr_menu.c:743 switch_ivr_menu_stack_xml_add binding 'menu-exit'
2018-09-25 14:14:40.342705 [DEBUG] switch_ivr_menu.c:743 switch_ivr_menu_stack_xml_add binding 'menu-sub'
2018-09-25 14:14:40.342705 [DEBUG] switch_ivr_menu.c:743 switch_ivr_menu_stack_xml_add binding 'menu-exec-app'
2018-09-25 14:14:40.342705 [DEBUG] switch_ivr_menu.c:743 switch_ivr_menu_stack_xml_add binding 'menu-play-sound'
2018-09-25 14:14:40.342705 [DEBUG] switch_ivr_menu.c:743 switch_ivr_menu_stack_xml_add binding 'menu-back'
2018-09-25 14:14:40.342705 [DEBUG] switch_ivr_menu.c:743 switch_ivr_menu_stack_xml_add binding 'menu-top'
2018-09-25 14:14:40.342705 [DEBUG] switch_ivr_menu.c:880 building menu 'a9b7a5d3-aa3b-40ae-882f-a3daaa4f9378'
2018-09-25 14:14:40.342705 [DEBUG] switch_ivr_menu.c:964 binding menu action 'menu-exec-app' to '1'
2018-09-25 14:14:40.342705 [DEBUG] switch_ivr_menu.c:964 binding menu action 'menu-exec-app' to '2'
2018-09-25 14:14:40.342705 [DEBUG] switch_ivr_menu.c:964 binding menu action 'menu-exec-app' to '3'
2018-09-25 14:14:40.342705 [DEBUG] switch_ivr_menu.c:483 Executing IVR menu a9b7a5d3-aa3b-40ae-882f-a3daaa4f9378
2018-09-25 14:14:40.342705 [DEBUG] switch_core_file.c:342 File /var/lib/freeswitch/recordings/pbx.numbercard.co.uk/goodAfternoon.wav sample rate 44100 doesn't match requested rate 16000
2018-09-25 14:14:40.342705 [WARNING] switch_core_file.c:360 File has 2 channels, muxing to 1 channel will occur.
2018-09-25 14:14:40.342705 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@16000hz 1 channels 20ms
2018-09-25 14:14:42.842712 [DEBUG] switch_rtp.c:7497 RTP RECV DTMF 1:800
2018-09-25 14:14:42.842712 [INFO] switch_channel.c:515 RECV DTMF 1:800
2018-09-25 14:14:42.842712 [DEBUG] switch_ivr_play_say.c:1942 done playing file /var/lib/freeswitch/recordings/pbx.numbercard.co.uk/goodAfternoon.wav
2018-09-25 14:14:42.842712 [DEBUG] switch_ivr_menu.c:377 waiting for 4/5 digits t/o 2000
2018-09-25 14:14:44.862731 [DEBUG] switch_ivr_menu.c:424 digits '1'
2018-09-25 14:14:44.862731 [DEBUG] switch_ivr_menu.c:577 IVR action on menu 'a9b7a5d3-aa3b-40ae-882f-a3daaa4f9378' matched '1' param 'transfer 2008 XML pbx.numbercard.co.uk'
2018-09-25 14:14:44.862731 [DEBUG] switch_ivr_menu.c:581 switch_ivr_menu_execute todo=[2]
EXECUTE sofia/external/02085199022@178.22.139.5 transfer(2008 XML pbx.numbercard.co.uk)
2018-09-25 14:14:44.862731 [DEBUG] switch_ivr.c:2165 (sofia/external/02085199022@178.22.139.5) State Change CS_EXECUTE -> CS_ROUTING
2018-09-25 14:14:44.862731 [NOTICE] switch_ivr.c:2172 Transfer sofia/external/02085199022@178.22.139.5 to XML[2008@pbx.numbercard.co.uk]
2018-09-25 14:14:44.862731 [DEBUG] switch_ivr_menu.c:673 exit-sound '(null)'
2018-09-25 14:14:44.862731 [DEBUG] switch_core_state_machine.c:650 (sofia/external/02085199022@178.22.139.5) State EXECUTE going to sleep
2018-09-25 14:14:44.862731 [DEBUG] switch_core_state_machine.c:584 (sofia/external/02085199022@178.22.139.5) Running State Change CS_ROUTING (Cur 1 Tot 1481)
2018-09-25 14:14:44.862731 [DEBUG] switch_channel.c:2249 (sofia/external/02085199022@178.22.139.5) Callstate Change ACTIVE -> RINGING
2018-09-25 14:14:44.862731 [DEBUG] switch_core_state_machine.c:643 (sofia/external/02085199022@178.22.139.5) State ROUTING
2018-09-25 14:14:44.862731 [DEBUG] mod_sofia.c:143 sofia/external/02085199022@178.22.139.5 SOFIA ROUTING
2018-09-25 14:14:44.862731 [DEBUG] switch_core_state_machine.c:236 sofia/external/02085199022@178.22.139.5 Standard ROUTING
2018-09-25 14:14:44.862731 [INFO] mod_dialplan_xml.c:637 Processing 02085199022 <02085199022>->2008 in context pbx.numbercard.co.uk
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->user_exists] continue=true
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (PASS) [user_exists] () =~ // break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
EXECUTE sofia/external/02085199022@178.22.139.5 set(user_exists=true)
2018-09-25 14:14:44.862731 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [user_exists]=[true]
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (PASS) [user_exists] ${user_exists}(true) =~ /^true$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(extension_uuid=${user_data ${destination_number}@${domain_name} var extension_uuid}) INLINE
EXECUTE sofia/external/02085199022@178.22.139.5 set(extension_uuid=7751cac5-ad77-449e-b641-b606bc0f027c)
2018-09-25 14:14:44.882677 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [extension_uuid]=[7751cac5-ad77-449e-b641-b606bc0f027c]
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(hold_music=${user_data ${destination_number}@${domain_name} var hold_music}) INLINE
EXECUTE sofia/external/02085199022@178.22.139.5 set(hold_music=local_stream://default)
2018-09-25 14:14:44.882677 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [hold_music]=[local_stream://default]
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(forward_all_enabled=${user_data ${destination_number}@${domain_name} var forward_all_enabled}) INLINE
EXECUTE sofia/external/02085199022@178.22.139.5 set(forward_all_enabled=true)
2018-09-25 14:14:44.882677 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [forward_all_enabled]=[true]
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(forward_all_destination=${user_data ${destination_number}@${domain_name} var forward_all_destination}) INLINE
EXECUTE sofia/external/02085199022@178.22.139.5 set(forward_all_destination=07508901164)
2018-09-25 14:14:44.882677 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [forward_all_destination]=[07508901164]
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(forward_busy_enabled=${user_data ${destination_number}@${domain_name} var forward_busy_enabled}) INLINE
EXECUTE sofia/external/02085199022@178.22.139.5 set(forward_busy_enabled=false)
2018-09-25 14:14:44.902760 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [forward_busy_enabled]=[false]
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(forward_busy_destination=${user_data ${destination_number}@${domain_name} var forward_busy_destination}) INLINE
EXECUTE sofia/external/02085199022@178.22.139.5 set(forward_busy_destination=02085199022)
2018-09-25 14:14:44.902760 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [forward_busy_destination]=[02085199022]
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(forward_no_answer_enabled=${user_data ${destination_number}@${domain_name} var forward_no_answer_enabled}) INLINE
EXECUTE sofia/external/02085199022@178.22.139.5 set(forward_no_answer_enabled=false)
2018-09-25 14:14:44.902760 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [forward_no_answer_enabled]=[false]
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(forward_no_answer_destination=${user_data ${destination_number}@${domain_name} var forward_no_answer_destination}) INLINE
EXECUTE sofia/external/02085199022@178.22.139.5 set(forward_no_answer_destination=02085199022)
2018-09-25 14:14:44.902760 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [forward_no_answer_destination]=[02085199022]
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(forward_user_not_registered_enabled=${user_data ${destination_number}@${domain_name} var forward_user_not_registered_enabled}) INLINE
EXECUTE sofia/external/02085199022@178.22.139.5 set(forward_user_not_registered_enabled=false)
2018-09-25 14:14:44.902760 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [forward_user_not_registered_enabled]=[false]
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(forward_user_not_registered_destination=${user_data ${destination_number}@${domain_name} var forward_user_not_registered_destination}) INLINE
EXECUTE sofia/external/02085199022@178.22.139.5 set(forward_user_not_registered_destination=02085199022)
2018-09-25 14:14:44.902760 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [forward_user_not_registered_destination]=[02085199022]
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(do_not_disturb=${user_data ${destination_number}@${domain_name} var do_not_disturb}) INLINE
EXECUTE sofia/external/02085199022@178.22.139.5 set(do_not_disturb=false)
2018-09-25 14:14:44.922768 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [do_not_disturb]=[false]
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(call_timeout=${user_data ${destination_number}@${domain_name} var call_timeout}) INLINE
EXECUTE sofia/external/02085199022@178.22.139.5 set(call_timeout=30)
2018-09-25 14:14:44.922768 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [call_timeout]=[30]
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(missed_call_app=${user_data ${destination_number}@${domain_name} var missed_call_app}) INLINE
EXECUTE sofia/external/02085199022@178.22.139.5 set(missed_call_app=)
2018-09-25 14:14:44.922768 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [missed_call_app]=[UNDEF]
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(missed_call_data=${user_data ${destination_number}@${domain_name} var missed_call_data}) INLINE
EXECUTE sofia/external/02085199022@178.22.139.5 set(missed_call_data=)
2018-09-25 14:14:44.922768 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [missed_call_data]=[UNDEF]
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(toll_allow=${user_data ${destination_number}@${domain_name} var toll_allow}) INLINE
EXECUTE sofia/external/02085199022@178.22.139.5 set(toll_allow=)
2018-09-25 14:14:44.922768 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [toll_allow]=[UNDEF]
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(call_screen_enabled=${user_data ${destination_number}@${domain_name} var call_screen_enabled}) INLINE
EXECUTE sofia/external/02085199022@178.22.139.5 set(call_screen_enabled=false)
2018-09-25 14:14:44.942697 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [call_screen_enabled]=[false]
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->call-direction] continue=true
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (PASS) [call-direction] ${call_direction}(inbound) =~ /^(inbound|outbound|local)$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->variables] continue=true
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (PASS) [variables] () =~ // break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 Action export(origination_callee_id_name=${destination_number})
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->user_record] continue=true
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (PASS) [user_record] () =~ // break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(user_record=${user_data ${destination_number}@${domain_name} var user_record}) INLINE
EXECUTE sofia/external/02085199022@178.22.139.5 set(user_record=)
2018-09-25 14:14:44.942697 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [user_record]=[UNDEF]
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE
2018-09-25 14:14:44.942697 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f40ac3d4880 Connected.
2018-09-25 14:14:44.942697 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f40ac3d4880 released.
EXECUTE sofia/external/02085199022@178.22.139.5 set(from_user_exists=false)
2018-09-25 14:14:44.942697 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [from_user_exists]=[false]
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->redial] continue=true
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [redial] destination_number(2008) =~ /^(redial|\*870)$/ break=on-true
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (PASS) [redial] () =~ // break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->speed_dial] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [speed_dial] destination_number(2008) =~ /^\*0(.*)$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->No-Reg-simwood.00] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [No-Reg-simwood.00] destination_number(2008) =~ /^00(.*)$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->No-Reg-simwood.0] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [No-Reg-simwood.0] destination_number(2008) =~ /^0(.*)$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->agent_status] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [agent_status] destination_number(2008) =~ /^\*22$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->agent_status_id] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [agent_status_id] destination_number(2008) =~ /^\*23$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->group-intercept] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [group-intercept] destination_number(2008) =~ /^\*8$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->page-extension] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [page-extension] destination_number(2008) =~ /^\*8(\d{2,7})$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->eavesdrop] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [eavesdrop] destination_number(2008) =~ /^\*33(\d{2,7})$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->call_privacy] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [call_privacy] destination_number(2008) =~ /^\*67(\d+)$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->call_return] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [call_return] destination_number(2008) =~ /^\*69$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->extension_queue] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [extension_queue] destination_number(2008) =~ /^\*800(.*)$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->intercept-ext] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [intercept-ext] destination_number(2008) =~ /^\*\*(\d+)$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->intercept-ext-polycom] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [intercept-ext-polycom] destination_number(2008) =~ /^\*97(\d+)$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->dx] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [dx] destination_number(2008) =~ /^dx$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->att_xfer] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [att_xfer] destination_number(2008) =~ /^att_xfer$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->extension-to-voicemail] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (PASS) [extension-to-voicemail] ${user_exists}(true) =~ /^true$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (PASS) [extension-to-voicemail] username(02085199022) =~ /^02085199022$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [extension-to-voicemail] destination_number(2008) =~ /^02085199022$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->send_to_voicemail] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [send_to_voicemail] destination_number(2008) =~ /^\*99(\d{2,10})$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->vmain] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [vmain] destination_number(2008) =~ /^vmain$|^\*4000$|^\*98$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->xfer_vm] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [xfer_vm] destination_number(2008) =~ /^xfer_vm$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->is_transfer] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [is_transfer] destination_number(2008) =~ /^is_transfer$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->vmain_user] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [vmain_user] destination_number(2008) =~ /^\*97$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->IVRmorning] continue=
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [IVRmorning] destination_number(2008) =~ /^20000$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->cf] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [cf] destination_number(2008) =~ /^cf$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->delay_echo] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [delay_echo] destination_number(2008) =~ /^\*9195$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->echo] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [echo] destination_number(2008) =~ /^\*9196$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->is_zrtp_secure] continue=true
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [is_zrtp_secure] ${zrtp_secure_media_confirmed}() =~ /^true$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 ANTI-Action eval(not_secure)
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->milliwatt] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [milliwatt] destination_number(2008) =~ /^\*9197$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->is_secure] continue=true
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [is_secure] ${sip_via_protocol}(udp) =~ /tls/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->tone_stream] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [tone_stream] destination_number(2008) =~ /^\*9198$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->hold_music] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [hold_music] destination_number(2008) =~ /^\*9664$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->recordings] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [recordings] destination_number(2008) =~ /^\*(732)$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->directory] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [directory] destination_number(2008) =~ /^\*411$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->wake-up] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [wake-up] destination_number(2008) =~ /^\*(925)$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->valet_park] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [valet_park] destination_number(2008) =~ /^(park\+)?(\*59[0-9][0-9])$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [valet_park] ${sip_h_Referred-By}() =~ /sip:(.*)@.*/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [valet_park] destination_number(2008) =~ /^(park\+)?(\*59[0-9][0-9])$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [valet_park] destination_number(2008) =~ /^(park\+)?(\*59[0-9][0-9])$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->operator] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [operator] destination_number(2008) =~ /^0$|^operator$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->operator-forward] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [operator-forward] destination_number(2008) =~ /^\*000$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->do-not-disturb] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [do-not-disturb] destination_number(2008) =~ /^\*77$/ break=on-true
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [do-not-disturb] destination_number(2008) =~ /^\*78$|\*363$/ break=on-true
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [do-not-disturb] destination_number(2008) =~ /^\*79$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->call-forward] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [call-forward] destination_number(2008) =~ /^\*72$/ break=on-true
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [call-forward] destination_number(2008) =~ /^\*73$/ break=on-true
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [call-forward] destination_number(2008) =~ /^\*74$/ break=on-true
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->call forward all] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (PASS) [call forward all] ${user_exists}(true) =~ /^true/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (PASS) [call forward all] ${forward_all_enabled}(true) =~ /^true/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 Action transfer(${forward_all_destination} XML ${domain_name})
2018-09-25 14:14:44.942697 [DEBUG] switch_core_state_machine.c:286 (sofia/external/02085199022@178.22.139.5) State Change CS_ROUTING -> CS_EXECUTE
2018-09-25 14:14:44.942697 [DEBUG] switch_core_state_machine.c:643 (sofia/external/02085199022@178.22.139.5) State ROUTING going to sleep
2018-09-25 14:14:44.942697 [DEBUG] switch_core_state_machine.c:584 (sofia/external/02085199022@178.22.139.5) Running State Change CS_EXECUTE (Cur 1 Tot 1481)
2018-09-25 14:14:44.942697 [DEBUG] switch_channel.c:2251 (sofia/external/02085199022@178.22.139.5) Callstate Change RINGING -> ACTIVE
2018-09-25 14:14:44.942697 [DEBUG] switch_core_state_machine.c:650 (sofia/external/02085199022@178.22.139.5) State EXECUTE
2018-09-25 14:14:44.942697 [DEBUG] mod_sofia.c:198 sofia/external/02085199022@178.22.139.5 SOFIA EXECUTE
2018-09-25 14:14:44.942697 [DEBUG] switch_core_state_machine.c:328 sofia/external/02085199022@178.22.139.5 Standard EXECUTE
EXECUTE sofia/external/02085199022@178.22.139.5 export(origination_callee_id_name=2008)
2018-09-25 14:14:44.942697 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [origination_callee_id_name]=[2008]
EXECUTE sofia/external/02085199022@178.22.139.5 set(RFC2822_DATE=Tue, 25 Sep 2018 14:14:44 +0000)
2018-09-25 14:14:44.942697 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [RFC2822_DATE]=[Tue, 25 Sep 2018 14:14:44 +0000]
EXECUTE sofia/external/02085199022@178.22.139.5 hash(insert/pbx.numbercard.co.uk-last_dial/02085199022/2008)
EXECUTE sofia/external/02085199022@178.22.139.5 eval(not_secure)
EXECUTE sofia/external/02085199022@178.22.139.5 transfer(07508901164 XML pbx.numbercard.co.uk)
2018-09-25 14:14:44.942697 [DEBUG] switch_ivr.c:2165 (sofia/external/02085199022@178.22.139.5) State Change CS_EXECUTE -> CS_ROUTING
2018-09-25 14:14:44.942697 [NOTICE] switch_ivr.c:2172 Transfer sofia/external/02085199022@178.22.139.5 to XML[07508901164@pbx.numbercard.co.uk]
2018-09-25 14:14:44.942697 [DEBUG] switch_core_state_machine.c:650 (sofia/external/02085199022@178.22.139.5) State EXECUTE going to sleep
2018-09-25 14:14:44.942697 [DEBUG] switch_core_state_machine.c:584 (sofia/external/02085199022@178.22.139.5) Running State Change CS_ROUTING (Cur 1 Tot 1481)
2018-09-25 14:14:44.942697 [DEBUG] switch_channel.c:2249 (sofia/external/02085199022@178.22.139.5) Callstate Change ACTIVE -> RINGING
2018-09-25 14:14:44.942697 [DEBUG] switch_core_state_machine.c:643 (sofia/external/02085199022@178.22.139.5) State ROUTING
2018-09-25 14:14:44.942697 [DEBUG] mod_sofia.c:143 sofia/external/02085199022@178.22.139.5 SOFIA ROUTING
2018-09-25 14:14:44.942697 [DEBUG] switch_core_state_machine.c:236 sofia/external/02085199022@178.22.139.5 Standard ROUTING
2018-09-25 14:14:44.942697 [INFO] mod_dialplan_xml.c:637 Processing 02085199022 <02085199022>->07508901164 in context pbx.numbercard.co.uk
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->user_exists] continue=true
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (PASS) [user_exists] () =~ // break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE
2018-09-25 14:14:44.962812 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f40ac3d4880 Connected.
2018-09-25 14:14:44.962812 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f40ac3d4880 released.
EXECUTE sofia/external/02085199022@178.22.139.5 set(user_exists=false)
2018-09-25 14:14:44.962812 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [user_exists]=[false]
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_exists] ${user_exists}(false) =~ /^true$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->call-direction] continue=true
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (PASS) [call-direction] ${call_direction}(inbound) =~ /^(inbound|outbound|local)$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->variables] continue=true
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (PASS) [variables] () =~ // break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 Action export(origination_callee_id_name=${destination_number})
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->user_record] continue=true
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (PASS) [user_record] () =~ // break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(user_record=${user_data ${destination_number}@${domain_name} var user_record}) INLINE
2018-09-25 14:14:44.962812 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f40ac3d4880 Connected.
2018-09-25 14:14:44.962812 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f40ac3d4880 released.
2018-09-25 14:14:44.962812 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f40ac3d4880 Connected.
2018-09-25 14:14:44.962812 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f40ac3d4880 released.
EXECUTE sofia/external/02085199022@178.22.139.5 set(user_record=)
2018-09-25 14:14:44.962812 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [user_record]=[UNDEF]
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE
2018-09-25 14:14:44.962812 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x7f40ac3d4880 Connected.
2018-09-25 14:14:44.982708 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x7f40ac3d4880 released.
EXECUTE sofia/external/02085199022@178.22.139.5 set(from_user_exists=false)
2018-09-25 14:14:44.982708 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [from_user_exists]=[false]
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->redial] continue=true
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [redial] destination_number(07508901164) =~ /^(redial|\*870)$/ break=on-true
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (PASS) [redial] () =~ // break=never
Dialplan: sofia/external/02085199022@178.22.139.5 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number})
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->speed_dial] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [speed_dial] destination_number(07508901164) =~ /^\*0(.*)$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->No-Reg-simwood.00] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (FAIL) [No-Reg-simwood.00] destination_number(07508901164) =~ /^00(.*)$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 parsing [pbx.numbercard.co.uk->No-Reg-simwood.0] continue=false
Dialplan: sofia/external/02085199022@178.22.139.5 Regex (PASS) [No-Reg-simwood.0] destination_number(07508901164) =~ /^0(.*)$/ break=on-false
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(sip_h_X-accountcode=${accountcode})
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(call_direction=outbound)
Dialplan: sofia/external/02085199022@178.22.139.5 Action unset(call_timeout)
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(hangup_after_bridge=true)
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(effective_caller_id_name=${outbound_caller_id_name})
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(effective_caller_id_number=${outbound_caller_id_number})
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(inherit_codec=true)
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(ignore_display_updates=true)
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(callee_id_number=7508901164)
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(continue_on_fail=true)
Dialplan: sofia/external/02085199022@178.22.139.5 Action set(outbound_prefix=0) INLINE
EXECUTE sofia/external/02085199022@178.22.139.5 set(outbound_prefix=0)
2018-09-25 14:14:44.982708 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [outbound_prefix]=[0]
Dialplan: sofia/external/02085199022@178.22.139.5 Action bridge(sofia/gateway/e5b92236-17ad-48c2-a0fe-6180da39ab31/447508901164)
2018-09-25 14:14:44.982708 [DEBUG] switch_core_state_machine.c:286 (sofia/external/02085199022@178.22.139.5) State Change CS_ROUTING -> CS_EXECUTE
2018-09-25 14:14:44.982708 [DEBUG] switch_core_state_machine.c:643 (sofia/external/02085199022@178.22.139.5) State ROUTING going to sleep
2018-09-25 14:14:44.982708 [DEBUG] switch_core_state_machine.c:584 (sofia/external/02085199022@178.22.139.5) Running State Change CS_EXECUTE (Cur 1 Tot 1481)
2018-09-25 14:14:44.982708 [DEBUG] switch_channel.c:2251 (sofia/external/02085199022@178.22.139.5) Callstate Change RINGING -> ACTIVE
2018-09-25 14:14:44.982708 [DEBUG] switch_core_state_machine.c:650 (sofia/external/02085199022@178.22.139.5) State EXECUTE
2018-09-25 14:14:44.982708 [DEBUG] mod_sofia.c:198 sofia/external/02085199022@178.22.139.5 SOFIA EXECUTE
2018-09-25 14:14:44.982708 [DEBUG] switch_core_state_machine.c:328 sofia/external/02085199022@178.22.139.5 Standard EXECUTE
EXECUTE sofia/external/02085199022@178.22.139.5 export(origination_callee_id_name=07508901164)
2018-09-25 14:14:44.982708 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [origination_callee_id_name]=[07508901164]
EXECUTE sofia/external/02085199022@178.22.139.5 set(RFC2822_DATE=Tue, 25 Sep 2018 14:14:44 +0000)
2018-09-25 14:14:44.982708 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [RFC2822_DATE]=[Tue, 25 Sep 2018 14:14:44 +0000]
EXECUTE sofia/external/02085199022@178.22.139.5 hash(insert/pbx.numbercard.co.uk-last_dial/02085199022/07508901164)
EXECUTE sofia/external/02085199022@178.22.139.5 set(sip_h_X-accountcode=)
2018-09-25 14:14:44.982708 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [sip_h_X-accountcode]=[UNDEF]
EXECUTE sofia/external/02085199022@178.22.139.5 set(call_direction=outbound)
2018-09-25 14:14:44.982708 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [call_direction]=[outbound]
EXECUTE sofia/external/02085199022@178.22.139.5 unset(call_timeout)
2018-09-25 14:14:44.982708 [DEBUG] mod_dptools.c:1693 UNSET [call_timeout]
EXECUTE sofia/external/02085199022@178.22.139.5 set(hangup_after_bridge=true)
2018-09-25 14:14:44.982708 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [hangup_after_bridge]=[true]
EXECUTE sofia/external/02085199022@178.22.139.5 set(effective_caller_id_name=)
2018-09-25 14:14:44.982708 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [effective_caller_id_name]=[UNDEF]
EXECUTE sofia/external/02085199022@178.22.139.5 set(effective_caller_id_number=)
2018-09-25 14:14:44.982708 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [effective_caller_id_number]=[UNDEF]
EXECUTE sofia/external/02085199022@178.22.139.5 set(inherit_codec=true)
2018-09-25 14:14:44.982708 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [inherit_codec]=[true]
EXECUTE sofia/external/02085199022@178.22.139.5 set(ignore_display_updates=true)
2018-09-25 14:14:44.982708 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [ignore_display_updates]=[true]
EXECUTE sofia/external/02085199022@178.22.139.5 set(callee_id_number=7508901164)
2018-09-25 14:14:44.982708 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [callee_id_number]=[7508901164]
EXECUTE sofia/external/02085199022@178.22.139.5 set(continue_on_fail=true)
2018-09-25 14:14:44.982708 [DEBUG] mod_dptools.c:1548 SET sofia/external/02085199022@178.22.139.5 [continue_on_fail]=[true]
EXECUTE sofia/external/02085199022@178.22.139.5 bridge(sofia/gateway/e5b92236-17ad-48c2-a0fe-6180da39ab31/447508901164)
2018-09-25 14:14:44.982708 [DEBUG] switch_channel.c:1823 (sofia/external/02085199022@178.22.139.5) Callstate Change ACTIVE -> RING_WAIT
2018-09-25 14:14:44.982708 [DEBUG] switch_channel.c:1250 sofia/external/02085199022@178.22.139.5 EXPORTING[export_vars] [origination_callee_id_name]=[07508901164] to event
2018-09-25 14:14:44.982708 [DEBUG] switch_channel.c:1250 sofia/external/02085199022@178.22.139.5 EXPORTING[export_vars] [origination_callee_id_name]=[07508901164] to event
2018-09-25 14:14:44.982708 [DEBUG] switch_channel.c:1250 sofia/external/02085199022@178.22.139.5 EXPORTING[export_vars] [origination_callee_id_name]=[07508901164] to event
2018-09-25 14:14:44.982708 [DEBUG] switch_ivr_originate.c:2142 Parsing global variables
2018-09-25 14:14:44.982708 [NOTICE] switch_channel.c:1104 New Channel sofia/external/447508901164 [46049759-e181-4fd4-9257-90c3ff5da477]
2018-09-25 14:14:44.982708 [DEBUG] mod_sofia.c:4803 sofia/external/447508901164 setting variable [sip_cid_type]=[pid]
2018-09-25 14:14:44.982708 [DEBUG] mod_sofia.c:4819 (sofia/external/447508901164) State Change CS_NEW -> CS_INIT
2018-09-25 14:14:44.982708 [DEBUG] switch_core_state_machine.c:584 (sofia/external/447508901164) Running State Change CS_INIT (Cur 2 Tot 1482)
2018-09-25 14:14:44.982708 [DEBUG] switch_core_state_machine.c:627 (sofia/external/447508901164) State INIT
2018-09-25 14:14:44.982708 [DEBUG] mod_sofia.c:90 sofia/external/447508901164 SOFIA INIT
2018-09-25 14:14:44.982708 [DEBUG] sofia_glue.c:1295 sofia/external/447508901164 sending invite version: 1.6.19 -36-7a77e0b 64bit
Local SDP:
v=0
o=FreeSWITCH 1537858062 1537858063 IN IP4 46.101.82.189
s=FreeSWITCH
c=IN IP4 46.101.82.189
t=0 0
m=audio 26822 RTP/AVP 9 0 8 3 101 13
a=rtpmap:9 G722/8000
a=rtpmap:0 PCMU/8000
a=rtpmap:8 PCMA/8000
a=rtpmap:3 GSM/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:13 CN/8000
a=ptime:20
a=sendrecv

2018-09-25 14:14:44.982708 [DEBUG] switch_core_state_machine.c:40 sofia/external/447508901164 Standard INIT
2018-09-25 14:14:44.982708 [DEBUG] switch_core_state_machine.c:48 (sofia/external/447508901164) State Change CS_INIT -> CS_ROUTING
2018-09-25 14:14:44.982708 [DEBUG] switch_core_state_machine.c:627 (sofia/external/447508901164) State INIT going to sleep
2018-09-25 14:14:44.982708 [DEBUG] switch_core_state_machine.c:584 (sofia/external/447508901164) Running State Change CS_ROUTING (Cur 2 Tot 1482)
2018-09-25 14:14:44.982708 [DEBUG] sofia.c:7084 Channel sofia/external/447508901164 entering state [calling][0]
2018-09-25 14:14:44.982708 [DEBUG] switch_core_state_machine.c:643 (sofia/external/447508901164) State ROUTING
2018-09-25 14:14:44.982708 [DEBUG] mod_sofia.c:143 sofia/external/447508901164 SOFIA ROUTING
2018-09-25 14:14:44.982708 [DEBUG] switch_ivr_originate.c:67 (sofia/external/447508901164) State Change CS_ROUTING -> CS_CONSUME_MEDIA
2018-09-25 14:14:44.982708 [DEBUG] switch_core_state_machine.c:643 (sofia/external/447508901164) State ROUTING going to sleep
2018-09-25 14:14:44.982708 [DEBUG] switch_core_state_machine.c:584 (sofia/external/447508901164) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 1482)
2018-09-25 14:14:44.982708 [DEBUG] switch_core_state_machine.c:662 (sofia/external/447508901164) State CONSUME_MEDIA
2018-09-25 14:14:44.982708 [DEBUG] switch_core_state_machine.c:662 (sofia/external/447508901164) State CONSUME_MEDIA going to sleep
2018-09-25 14:14:46.502686 [INFO] sofia.c:1279 sofia/external/447508901164 Update Callee ID to "447508901164" <447508901164>
2018-09-25 14:14:46.502686 [DEBUG] sofia.c:7084 Channel sofia/external/447508901164 entering state [proceeding][180]
2018-09-25 14:14:46.502686 [NOTICE] sofia.c:7192 Ring-Ready sofia/external/447508901164!
2018-09-25 14:14:46.502686 [DEBUG] switch_channel.c:3346 (sofia/external/447508901164) Callstate Change DOWN -> RINGING
2018-09-25 14:14:46.542712 [DEBUG] switch_ivr_originate.c:1273 Raw Codec Activation Success L16@16000hz 1 channel 20ms
2018-09-25 14:14:46.542712 [DEBUG] switch_core_codec.c:223 sofia/external/02085199022@178.22.139.5 Push codec L16:100
2018-09-25 14:14:46.542712 [DEBUG] switch_ivr_originate.c:1305 Play Ringback File [local_stream://default]
2018-09-25 14:14:46.542712 [DEBUG] mod_local_stream.c:871 Opening Stream [default/16000] 16000hz
2018-09-25 14:14:52.262682 [DEBUG] sofia.c:7084 Channel sofia/external/447508901164 entering state [proceeding][183]
2018-09-25 14:14:52.262682 [DEBUG] sofia.c:7094 Remote SDP:
v=0
o=FreeSWITCH 1537859628 1537859629 IN IP4 178.22.139.6
s=FreeSWITCH
c=IN IP4 178.22.139.6
t=0 0
m=audio 25264 RTP/AVP 9 101 13
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:13 CN/8000
a=ptime:20
a=rtcp:25265 IN IP4 178.22.139.6

2018-09-25 14:14:52.262682 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2018-09-25 14:14:52.262682 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
2018-09-25 14:14:52.262682 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2018-09-25 14:14:52.262682 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2018-09-25 14:14:52.262682 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
2018-09-25 14:14:52.262682 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
2018-09-25 14:14:52.262682 [DEBUG] switch_core_media.c:3061 Set Codec sofia/external/447508901164 G722/8000 20 ms 160 samples 64000 bits 1 channels
2018-09-25 14:14:52.262682 [DEBUG] switch_core_codec.c:111 sofia/external/447508901164 Original read codec set to G722:9
2018-09-25 14:14:52.262682 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
2018-09-25 14:14:52.262682 [DEBUG] switch_core_media.c:4767 sofia/external/447508901164 Set 2833 dtmf send payload to 101 recv payload to 101
2018-09-25 14:14:52.262682 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/external/447508901164] 46.101.82.189 port 26822 -> 178.22.139.6 port 25264 codec: 9 ms: 20
2018-09-25 14:14:52.262682 [DEBUG] switch_rtp.c:4111 Starting timer [soft] 160 bytes per 20ms
2018-09-25 14:14:52.262682 [DEBUG] switch_core_media.c:7098 Activating RTCP PORT 25265
2018-09-25 14:14:52.262682 [DEBUG] switch_rtp.c:4507 RTCP send rate is: 1000 and packet rate is: 20000 Remote Port: 25265
2018-09-25 14:14:52.262682 [DEBUG] switch_rtp.c:2567 Setting RTCP remote addr to 178.22.139.6:25265 2
2018-09-25 14:14:52.262682 [DEBUG] switch_core_media.c:7179 sofia/external/447508901164 Set 2833 dtmf send payload to 101
2018-09-25 14:14:52.262682 [DEBUG] switch_core_media.c:7186 sofia/external/447508901164 Set 2833 dtmf receive payload to 101
2018-09-25 14:14:52.262682 [DEBUG] switch_core_media.c:7209 sofia/external/447508901164 Set rtp dtmf delay to 40
2018-09-25 14:14:52.262682 [DEBUG] switch_core_media.c:7215 Set comfort noise payload to 13
2018-09-25 14:14:52.262682 [NOTICE] sofia_media.c:92 Pre-Answer sofia/external/447508901164!
2018-09-25 14:14:52.262682 [DEBUG] switch_channel.c:3474 (sofia/external/447508901164) Callstate Change RINGING -> EARLY
2018-09-25 14:14:52.282664 [DEBUG] switch_core_codec.c:248 sofia/external/02085199022@178.22.139.5 Restore previous codec G722:9.
2018-09-25 14:14:52.282664 [DEBUG] switch_ivr_originate.c:3705 Originate Resulted in Success: [sofia/external/447508901164]
2018-09-25 14:14:52.282664 [DEBUG] switch_channel.c:2047 (sofia/external/02085199022@178.22.139.5) Callstate Change RING_WAIT -> ACTIVE
2018-09-25 14:14:52.282664 [DEBUG] switch_ivr_bridge.c:1614 (sofia/external/447508901164) State Change CS_CONSUME_MEDIA -> CS_EXCHANGE_MEDIA
2018-09-25 14:14:52.282664 [DEBUG] switch_core_state_machine.c:584 (sofia/external/447508901164) Running State Change CS_EXCHANGE_MEDIA (Cur 2 Tot 1482)
2018-09-25 14:14:52.282664 [DEBUG] switch_core_state_machine.c:653 (sofia/external/447508901164) State EXCHANGE_MEDIA
2018-09-25 14:14:52.282664 [DEBUG] mod_sofia.c:631 SOFIA EXCHANGE_MEDIA
2018-09-25 14:14:52.342736 [DEBUG] switch_rtp.c:1887 rtcp_stats_init: audio ssrc[2178002500] base_seq[32725]
2018-09-25 14:14:52.362676 [DEBUG] switch_rtp.c:7271 Correct audio ip/port confirmed.
2018-09-25 14:14:55.222748 [DEBUG] sofia.c:7084 Channel sofia/external/447508901164 entering state [proceeding][180]
2018-09-25 14:15:00.922703 [DEBUG] sofia.c:7084 Channel sofia/external/447508901164 entering state [proceeding][183]
2018-09-25 14:15:00.922703 [DEBUG] sofia.c:7094 Remote SDP:
v=0
o=FreeSWITCH 1537859404 1537859405 IN IP4 178.22.139.5
s=FreeSWITCH
c=IN IP4 178.22.139.5
t=0 0
m=audio 25496 RTP/AVP 9 101 13
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:13 CN/8000
a=ptime:20
a=rtcp:25497 IN IP4 178.22.139.5

2018-09-25 14:15:00.922703 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2018-09-25 14:15:00.922703 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
2018-09-25 14:15:00.922703 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2018-09-25 14:15:00.922703 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2018-09-25 14:15:00.922703 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
2018-09-25 14:15:00.922703 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
2018-09-25 14:15:00.922703 [DEBUG] switch_core_media.c:4373 Set comfort noise payload to 13
2018-09-25 14:15:00.922703 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
2018-09-25 14:15:00.922703 [DEBUG] switch_core_media.c:4720 Set comfort noise payload to 13@8000
2018-09-25 14:15:00.922703 [DEBUG] switch_core_media.c:4767 sofia/external/447508901164 Set 2833 dtmf send payload to 101 recv payload to 101
2018-09-25 14:15:00.922703 [DEBUG] switch_core_media.c:6867 Audio params changed for sofia/external/447508901164 from 178.22.139.6:25264 to 178.22.139.5:25496
2018-09-25 14:15:00.922703 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/external/447508901164] 46.101.82.189 port 26822 -> 178.22.139.5 port 25496 codec: 9 ms: 20
2018-09-25 14:15:00.922703 [DEBUG] switch_core_media.c:6907 AUDIO RTP CHANGING DEST TO: [178.22.139.5:25496]
2018-09-25 14:15:01.042710 [DEBUG] switch_rtp.c:1887 rtcp_stats_init: audio ssrc[1210004253] base_seq[22268]
2018-09-25 14:15:01.082713 [DEBUG] switch_rtp.c:7271 Correct audio ip/port confirmed.
2018-09-25 14:15:03.542668 [DEBUG] sofia.c:7084 Channel sofia/external/447508901164 entering state [proceeding][180]
2018-09-25 14:15:09.342691 [DEBUG] sofia.c:7084 Channel sofia/external/447508901164 entering state [proceeding][183]
2018-09-25 14:15:09.342691 [DEBUG] sofia.c:7094 Remote SDP:
v=0
o=FreeSWITCH 1537854257 1537854258 IN IP4 185.63.140.2
s=FreeSWITCH
c=IN IP4 185.63.140.2
t=0 0
m=audio 30652 RTP/AVP 9 101 13
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:13 CN/8000
a=ptime:20
a=rtcp:30653 IN IP4 185.63.140.2

2018-09-25 14:15:09.342691 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2018-09-25 14:15:09.342691 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
2018-09-25 14:15:09.342691 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2018-09-25 14:15:09.342691 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2018-09-25 14:15:09.342691 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
2018-09-25 14:15:09.342691 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
2018-09-25 14:15:09.342691 [DEBUG] switch_core_media.c:4373 Set comfort noise payload to 13
2018-09-25 14:15:09.342691 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
2018-09-25 14:15:09.342691 [DEBUG] switch_core_media.c:4720 Set comfort noise payload to 13@8000
2018-09-25 14:15:09.342691 [DEBUG] switch_core_media.c:4767 sofia/external/447508901164 Set 2833 dtmf send payload to 101 recv payload to 101
2018-09-25 14:15:09.342691 [DEBUG] switch_core_media.c:6867 Audio params changed for sofia/external/447508901164 from 178.22.139.5:25496 to 185.63.140.2:30652
2018-09-25 14:15:09.342691 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/external/447508901164] 46.101.82.189 port 26822 -> 185.63.140.2 port 30652 codec: 9 ms: 20
2018-09-25 14:15:09.342691 [DEBUG] switch_core_media.c:6907 AUDIO RTP CHANGING DEST TO: [185.63.140.2:30652]
2018-09-25 14:15:09.442721 [DEBUG] switch_rtp.c:1887 rtcp_stats_init: audio ssrc[3929388373] base_seq[5663]
2018-09-25 14:15:09.482693 [DEBUG] switch_rtp.c:7271 Correct audio ip/port confirmed.
2018-09-25 14:15:11.982707 [DEBUG] sofia.c:7084 Channel sofia/external/447508901164 entering state [proceeding][180]
2018-09-25 14:15:17.822704 [DEBUG] sofia.c:7084 Channel sofia/external/447508901164 entering state [proceeding][183]
2018-09-25 14:15:17.822704 [DEBUG] sofia.c:7094 Remote SDP:
v=0
o=FreeSWITCH 1537860905 1537860906 IN IP4 185.63.140.3
s=FreeSWITCH
c=IN IP4 185.63.140.3
t=0 0
m=audio 24012 RTP/AVP 9 101 13
a=rtpmap:9 G722/8000
a=rtpmap:101 telephone-event/8000
a=fmtp:101 0-16
a=rtpmap:13 CN/8000
a=ptime:20
a=rtcp:24013 IN IP4 185.63.140.3

2018-09-25 14:15:17.822704 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
2018-09-25 14:15:17.822704 [DEBUG] switch_core_media.c:4504 Audio Codec Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
2018-09-25 14:15:17.822704 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
2018-09-25 14:15:17.822704 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
2018-09-25 14:15:17.822704 [DEBUG] switch_core_media.c:4449 Audio Codec Compare [G722:9:8000:20:64000:1]/[GSM:3:8000:20:13200:1]
2018-09-25 14:15:17.822704 [DEBUG] switch_core_media.c:4365 Set telephone-event payload to 101@8000
2018-09-25 14:15:17.822704 [DEBUG] switch_core_media.c:4373 Set comfort noise payload to 13
2018-09-25 14:15:17.822704 [DEBUG] switch_core_media.c:4708 Set telephone-event payload to 101@8000
2018-09-25 14:15:17.822704 [DEBUG] switch_core_media.c:4720 Set comfort noise payload to 13@8000
2018-09-25 14:15:17.822704 [DEBUG] switch_core_media.c:4767 sofia/external/447508901164 Set 2833 dtmf send payload to 101 recv payload to 101
2018-09-25 14:15:17.822704 [DEBUG] switch_core_media.c:6867 Audio params changed for sofia/external/447508901164 from 185.63.140.2:30652 to 185.63.140.3:24012
2018-09-25 14:15:17.822704 [DEBUG] switch_core_media.c:6878 AUDIO RTP [sofia/external/447508901164] 46.101.82.189 port 26822 -> 185.63.140.3 port 24012 codec: 9 ms: 20
2018-09-25 14:15:17.822704 [DEBUG] switch_core_media.c:6907 AUDIO RTP CHANGING DEST TO: [185.63.140.3:24012]
2018-09-25 14:15:17.942717 [DEBUG] switch_rtp.c:1887 rtcp_stats_init: audio ssrc[4069579342] base_seq[2533]
2018-09-25 14:15:17.982714 [DEBUG] switch_rtp.c:7271 Correct audio ip/port confirmed.
2018-09-25 14:15:18.942693 [DEBUG] sofia.c:6257 Remote Reason: 31
2018-09-25 14:15:18.942693 [DEBUG] sofia.c:7084 Channel sofia/external/447508901164 entering state [terminated][480]
2018-09-25 14:15:18.942693 [NOTICE] sofia.c:8273 Hangup sofia/external/447508901164 [CS_EXCHANGE_MEDIA] [NORMAL_UNSPECIFIED]
2018-09-25 14:15:18.942693 [DEBUG] switch_ivr_bridge.c:712 sofia/external/447508901164 ending bridge by request from read function
2018-09-25 14:15:18.942693 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/external/447508901164]
2018-09-25 14:15:18.942693 [DEBUG] switch_core_state_machine.c:653 (sofia/external/447508901164) State EXCHANGE_MEDIA going to sleep
2018-09-25 14:15:18.942693 [DEBUG] switch_core_state_machine.c:584 (sofia/external/447508901164) Running State Change CS_HANGUP (Cur 2 Tot 1482)
2018-09-25 14:15:18.942693 [DEBUG] switch_core_state_machine.c:850 (sofia/external/447508901164) Callstate Change EARLY -> HANGUP
2018-09-25 14:15:18.942693 [DEBUG] switch_core_state_machine.c:852 (sofia/external/447508901164) State HANGUP
2018-09-25 14:15:18.942693 [DEBUG] mod_sofia.c:438 Channel sofia/external/447508901164 hanging up, cause: NORMAL_UNSPECIFIED
2018-09-25 14:15:18.942693 [DEBUG] switch_core_state_machine.c:60 sofia/external/447508901164 Standard HANGUP, cause: NORMAL_UNSPECIFIED
2018-09-25 14:15:18.942693 [DEBUG] switch_core_state_machine.c:852 (sofia/external/447508901164) State HANGUP going to sleep
2018-09-25 14:15:18.942693 [DEBUG] switch_core_state_machine.c:619 (sofia/external/447508901164) State Change CS_HANGUP -> CS_REPORTING
2018-09-25 14:15:18.942693 [DEBUG] switch_core_state_machine.c:584 (sofia/external/447508901164) Running State Change CS_REPORTING (Cur 2 Tot 1482)
2018-09-25 14:15:18.942693 [DEBUG] switch_core_state_machine.c:938 (sofia/external/447508901164) State REPORTING
2018-09-25 14:15:18.942693 [DEBUG] switch_core_state_machine.c:174 sofia/external/447508901164 Standard REPORTING, cause: NORMAL_UNSPECIFIED
2018-09-25 14:15:18.942693 [DEBUG] switch_core_state_machine.c:938 (sofia/external/447508901164) State REPORTING going to sleep
2018-09-25 14:15:18.942693 [DEBUG] switch_core_state_machine.c:610 (sofia/external/447508901164) State Change CS_REPORTING -> CS_DESTROY
2018-09-25 14:15:18.942693 [DEBUG] switch_core_session.c:1665 Session 1482 (sofia/external/447508901164) Locked, Waiting on external entities
2018-09-25 14:15:18.962758 [DEBUG] switch_ivr_bridge.c:706 sofia/external/447508901164 ending bridge by request from write function
2018-09-25 14:15:18.962758 [DEBUG] switch_ivr_bridge.c:787 BRIDGE THREAD DONE [sofia/external/02085199022@178.22.139.5]
2018-09-25 14:15:18.962758 [NOTICE] switch_core_state_machine.c:385 sofia/external/02085199022@178.22.139.5 has executed the last dialplan instruction, hanging up.
2018-09-25 14:15:18.962758 [NOTICE] switch_core_state_machine.c:387 Hangup sofia/external/02085199022@178.22.139.5 [CS_EXECUTE] [NORMAL_CLEARING]
2018-09-25 14:15:18.962758 [DEBUG] switch_core_state_machine.c:650 (sofia/external/02085199022@178.22.139.5) State EXECUTE going to sleep
2018-09-25 14:15:18.962758 [DEBUG] switch_core_state_machine.c:584 (sofia/external/02085199022@178.22.139.5) Running State Change CS_HANGUP (Cur 2 Tot 1482)
2018-09-25 14:15:18.962758 [DEBUG] switch_core_state_machine.c:850 (sofia/external/02085199022@178.22.139.5) Callstate Change ACTIVE -> HANGUP
2018-09-25 14:15:18.962758 [DEBUG] switch_core_state_machine.c:852 (sofia/external/02085199022@178.22.139.5) State HANGUP
2018-09-25 14:15:18.962758 [DEBUG] mod_sofia.c:432 sofia/external/02085199022@178.22.139.5 Overriding SIP cause 480 with 480 from the other leg
2018-09-25 14:15:18.962758 [DEBUG] mod_sofia.c:438 Channel sofia/external/02085199022@178.22.139.5 hanging up, cause: NORMAL_CLEARING
2018-09-25 14:15:18.962758 [DEBUG] mod_sofia.c:491 Sending BYE to sofia/external/02085199022@178.22.139.5
2018-09-25 14:15:18.962758 [DEBUG] switch_core_state_machine.c:60 sofia/external/02085199022@178.22.139.5 Standard HANGUP, cause: NORMAL_CLEARING
2018-09-25 14:15:18.962758 [DEBUG] switch_core_state_machine.c:852 (sofia/external/02085199022@178.22.139.5) State HANGUP going to sleep
2018-09-25 14:15:18.962758 [DEBUG] switch_core_state_machine.c:619 (sofia/external/02085199022@178.22.139.5) State Change CS_HANGUP -> CS_REPORTING
2018-09-25 14:15:18.962758 [DEBUG] switch_core_state_machine.c:584 (sofia/external/02085199022@178.22.139.5) Running State Change CS_REPORTING (Cur 2 Tot 1482)
2018-09-25 14:15:18.962758 [DEBUG] switch_core_state_machine.c:938 (sofia/external/02085199022@178.22.139.5) State REPORTING
2018-09-25 14:15:18.962758 [NOTICE] switch_core_session.c:1683 Session 1482 (sofia/external/447508901164) Ended
2018-09-25 14:15:18.962758 [NOTICE] switch_core_session.c:1687 Close Channel sofia/external/447508901164 [CS_DESTROY]
2018-09-25 14:15:18.962758 [DEBUG] switch_core_state_machine.c:741 (sofia/external/447508901164) Running State Change CS_DESTROY (Cur 1 Tot 1482)
2018-09-25 14:15:18.962758 [DEBUG] switch_core_state_machine.c:751 (sofia/external/447508901164) State DESTROY
2018-09-25 14:15:18.962758 [DEBUG] mod_sofia.c:343 sofia/external/447508901164 SOFIA DESTROY
2018-09-25 14:15:18.962758 [DEBUG] switch_core_state_machine.c:181 sofia/external/447508901164 Standard DESTROY
2018-09-25 14:15:18.962758 [DEBUG] switch_core_state_machine.c:751 (sofia/external/447508901164) State DESTROY going to sleep
2018-09-25 14:15:19.022789 [DEBUG] switch_core_state_machine.c:174 sofia/external/02085199022@178.22.139.5 Standard REPORTING, cause: NORMAL_CLEARING
2018-09-25 14:15:19.022789 [DEBUG] switch_core_state_machine.c:938 (sofia/external/02085199022@178.22.139.5) State REPORTING going to sleep
2018-09-25 14:15:19.022789 [DEBUG] switch_core_state_machine.c:610 (sofia/external/02085199022@178.22.139.5) State Change CS_REPORTING -> CS_DESTROY
2018-09-25 14:15:19.022789 [DEBUG] switch_core_session.c:1665 Session 1481 (sofia/external/02085199022@178.22.139.5) Locked, Waiting on external entities
2018-09-25 14:15:19.022789 [NOTICE] switch_core_session.c:1683 Session 1481 (sofia/external/02085199022@178.22.139.5) Ended
2018-09-25 14:15:19.022789 [NOTICE] switch_core_session.c:1687 Close Channel sofia/external/02085199022@178.22.139.5 [CS_DESTROY]
2018-09-25 14:15:19.022789 [DEBUG] switch_core_state_machine.c:741 (sofia/external/02085199022@178.22.139.5) Running State Change CS_DESTROY (Cur 0 Tot 1482)
2018-09-25 14:15:19.022789 [DEBUG] switch_core_state_machine.c:751 (sofia/external/02085199022@178.22.139.5) State DESTROY
2018-09-25 14:15:19.022789 [DEBUG] mod_sofia.c:343 sofia/external/02085199022@178.22.139.5 SOFIA DESTROY
2018-09-25 14:15:19.022789 [DEBUG] switch_core_state_machine.c:181 sofia/external/02085199022@178.22.139.5 Standard DESTROY
2018-09-25 14:15:19.022789 [DEBUG] switch_core_state_machine.c:751 (sofia/external/02085199022@178.22.139.5) State DESTROY going to sleep
 

DigitalDaz

Administrator
Staff member
Sep 29, 2016
3,070
577
113
That's the carrier dumping it as there is no valid caller ID, there should still be a p-asserted id with a valid CLID in it.
 

deltukru

New Member
Sep 19, 2018
15
0
1
58
Something to with this Reason: Q.850;cause=88;text="INCOMPATIBLE_DESTINATION"
Content-Length: 0
X-Warning: Invalid CLI
X-Reason: Invalid CLI
 

DigitalDaz

Administrator
Staff member
Sep 29, 2016
3,070
577
113
No, nothing in FusionPBX currently without jigging around code, I have an agreement with carrier to allow it for forwarding.
 

DigitalDaz

Administrator
Staff member
Sep 29, 2016
3,070
577
113
There is a setting within the PBX to force one of your DIDs as caller ID, thats about it though.
 

deltukru

New Member
Sep 19, 2018
15
0
1
58

As I've with Simwood I'll talk to support to get sorted temporally, though I pulled this off my current Freeswitch that handles withheld go to Simwood, do you this is the right direction to go?

<!-- UK CLIs -->
<condition field="caller_id_number" expression="^44([0-9]+)" break="on-true">
<action inline="true" application="set" data="cli_e164=44$1"/>
</condition>
<condition field="caller_id_number" expression="^0044([0-9]+)" break="on-true">
<action inline="true" application="set" data="cli_e164=44$1"/>
</condition>
<condition field="caller_id_number" expression="^\+44([0-9]+)" break="on-true">
<action inline="true" application="set" data="cli_e164=44$1"/>
</condition>


<!-- Blank CLIs -->
<condition field="caller_id_number" expression="^$" break="on-true">
<action inline="true" application="set" data="cli_e164="/>
</condition>
<condition field="caller_id_number" expression="^(anonymous|none|unknown|restricted|)$" break="on-true">
<action inline="true" application="set" data="cli_e164="/>
</condition>
</extension>

<extension name="Local_Extension">
<condition field="destination_number" expression="^(44[0-9]{10})$">
<action application="lua" data="xoverx.lua" />
</condition>
</extension>
 

DigitalDaz

Administrator
Staff member
Sep 29, 2016
3,070
577
113
No, there is no difference there, this is a new OFcom thing that Simwood are implementing NOW but comes into UK law on October the first. A valid UK caller ID must be sent.
 
  • Like
Reactions: deltukru

deltukru

New Member
Sep 19, 2018
15
0
1
58
This is now solved all be it a manual solution, but i'll be looking to integrate via API later on.
1537964490841.png
 

bcmike

Active Member
Jun 7, 2018
337
58
28
54
Funny story,

Opened a big ticket at Simwood with lots of verbage about my testing methodology, etc, insisted they had an issue.

Looked at the pcap and noticed INVALID_CLI.

Went back and realized I didn't have my outbound CID field set on my testing extension. Found this thread after the fact.

I had to go back and eat crow in my ticket ;) Lesson learned
 
  • Like
Reactions: JamesBorne
Status
Not open for further replies.