0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.522253 [NOTICE] switch_channel.c:1104 New Channel sofia/external/15554321212@96.88.69.88 [0c9c70b8-0345-46e1-a193-67751042f058] 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.522253 [DEBUG] switch_core_state_machine.c:584 (sofia/external/15554321212@96.88.69.88) Running State Change CS_NEW (Cur 1 Tot 488) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.522253 [DEBUG] sofia.c:9815 sofia/external/15554321212@96.88.69.88 receiving invite from 96.88.69.88:9020 version: 1.6.14 -23-e460bf8 64bit 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.522253 [DEBUG] sofia.c:7041 Channel sofia/external/15554321212@96.88.69.88 entering state [received][100] 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.522253 [DEBUG] sofia.c:7051 Remote SDP: 
0c9c70b8-0345-46e1-a193-67751042f058 v=0 
0c9c70b8-0345-46e1-a193-67751042f058 o=Sansay-VSXi 188 1 IN IP4 96.88.69.88 
0c9c70b8-0345-46e1-a193-67751042f058 s=Session Controller 
0c9c70b8-0345-46e1-a193-67751042f058 c=IN IP4 207.112.37.25 
0c9c70b8-0345-46e1-a193-67751042f058 t=0 0 
0c9c70b8-0345-46e1-a193-67751042f058 m=audio 33720 RTP/AVP 0 18 101 
0c9c70b8-0345-46e1-a193-67751042f058 a=rtpmap:0 PCMU/8000 
0c9c70b8-0345-46e1-a193-67751042f058 a=rtpmap:18 G729/8000 
0c9c70b8-0345-46e1-a193-67751042f058 a=fmtp:18 annexb=no 
0c9c70b8-0345-46e1-a193-67751042f058 a=rtpmap:101 telephone-event/8000 
0c9c70b8-0345-46e1-a193-67751042f058 a=fmtp:101 0-15 
0c9c70b8-0345-46e1-a193-67751042f058 a=ptime:20 
0c9c70b8-0345-46e1-a193-67751042f058 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.522253 [DEBUG] switch_core_media.c:4378 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1] 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.522253 [DEBUG] switch_core_media.c:4378 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.522253 [DEBUG] switch_core_media.c:4433 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.522253 [DEBUG] switch_core_media.c:4378 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.522253 [DEBUG] switch_core_media.c:4378 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1] 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.522253 [DEBUG] switch_core_media.c:4378 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1] 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.522253 [DEBUG] switch_core_media.c:4378 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1] 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.522253 [DEBUG] switch_core_media.c:4378 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1] 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.522253 [DEBUG] switch_core_media.c:4378 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1] 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.522253 [DEBUG] switch_core_media.c:4378 Audio Codec Compare [G729:18:8000:20:8000:1]/[GSM:3:8000:20:13200:1] 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.522253 [DEBUG] switch_core_media.c:4378 Audio Codec Compare [G729:18:8000:20:8000:1]/[opus:116:48000:20:0:1] 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.522253 [DEBUG] switch_core_media.c:4294 Set telephone-event payload to 101@8000 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.522253 [DEBUG] switch_core_media.c:3043 Set Codec sofia/external/15554321212@96.88.69.88 PCMU/8000 20 ms 160 samples 64000 bits 1 channels 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.522253 [DEBUG] switch_core_codec.c:111 sofia/external/15554321212@96.88.69.88 Original read codec set to PCMU:0 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.522253 [DEBUG] switch_core_media.c:4637 Set telephone-event payload to 101@8000 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.522253 [DEBUG] switch_core_media.c:4696 sofia/external/15554321212@96.88.69.88 Set 2833 dtmf send payload to 101 recv payload to 101 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.522253 [DEBUG] sofia.c:7464 (sofia/external/15554321212@96.88.69.88) State Change CS_NEW -> CS_INIT 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.522253 [DEBUG] switch_core_state_machine.c:603 (sofia/external/15554321212@96.88.69.88) State NEW 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.522253 [DEBUG] switch_core_state_machine.c:584 (sofia/external/15554321212@96.88.69.88) Running State Change CS_INIT (Cur 1 Tot 488) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.522253 [DEBUG] switch_core_state_machine.c:627 (sofia/external/15554321212@96.88.69.88) State INIT 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.522253 [DEBUG] mod_sofia.c:90 sofia/external/15554321212@96.88.69.88 SOFIA INIT 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.522253 [DEBUG] switch_core_state_machine.c:40 sofia/external/15554321212@96.88.69.88 Standard INIT 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.522253 [DEBUG] switch_core_state_machine.c:48 (sofia/external/15554321212@96.88.69.88) State Change CS_INIT -> CS_ROUTING 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.522253 [DEBUG] switch_core_state_machine.c:627 (sofia/external/15554321212@96.88.69.88) State INIT going to sleep 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.522253 [DEBUG] switch_core_state_machine.c:584 (sofia/external/15554321212@96.88.69.88) Running State Change CS_ROUTING (Cur 1 Tot 488) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.522253 [DEBUG] switch_channel.c:2249 (sofia/external/15554321212@96.88.69.88) Callstate Change DOWN -> RINGING 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.522253 [DEBUG] switch_core_state_machine.c:643 (sofia/external/15554321212@96.88.69.88) State ROUTING 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.522253 [DEBUG] mod_sofia.c:143 sofia/external/15554321212@96.88.69.88 SOFIA ROUTING 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.522253 [DEBUG] switch_core_state_machine.c:236 sofia/external/15554321212@96.88.69.88 Standard ROUTING 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.522253 [INFO] mod_dialplan_xml.c:637 Processing HOROM <5554321212>->15559998585 in context public 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [public->13152840001] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [13152840001] destination_number(15559998585) =~ /^(13152840001)$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [public->19022063688] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [19022063688] destination_number(15559998585) =~ /^(19022063688)$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [public->19028750503] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [19028750503] destination_number(15559998585) =~ /^(19028750503)$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [public->19028750505] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [19028750505] destination_number(15559998585) =~ /^(19028750505)$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [public->19028750804] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [19028750804] destination_number(15559998585) =~ /^(19028750804)$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [public->19028751434] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [19028751434] destination_number(15559998585) =~ /^(19028751434)$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [public->15559998585] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (PASS) [15559998585] destination_number(15559998585) =~ /^(15559998585)$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(call_direction=inbound) INLINE 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 set(call_direction=inbound) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.522253 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [call_direction]=[inbound] 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(domain_uuid=53dfbf50-263f-4208-9540-c2abd247b89b) INLINE 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 set(domain_uuid=53dfbf50-263f-4208-9540-c2abd247b89b) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.522253 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [domain_uuid]=[53dfbf50-263f-4208-9540-c2abd247b89b] 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(domain_name=my.workphone.ca) INLINE 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 set(domain_name=my.workphone.ca) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.522253 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [domain_name]=[my.workphone.ca] 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action transfer(5559998585 XML my.workphone.ca) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.532247 [DEBUG] switch_core_state_machine.c:286 (sofia/external/15554321212@96.88.69.88) State Change CS_ROUTING -> CS_EXECUTE 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.532247 [DEBUG] switch_core_state_machine.c:643 (sofia/external/15554321212@96.88.69.88) State ROUTING going to sleep 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.532247 [DEBUG] switch_core_state_machine.c:584 (sofia/external/15554321212@96.88.69.88) Running State Change CS_EXECUTE (Cur 1 Tot 488) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.532247 [DEBUG] switch_core_state_machine.c:650 (sofia/external/15554321212@96.88.69.88) State EXECUTE 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.532247 [DEBUG] mod_sofia.c:198 sofia/external/15554321212@96.88.69.88 SOFIA EXECUTE 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.532247 [DEBUG] switch_core_state_machine.c:328 sofia/external/15554321212@96.88.69.88 Standard EXECUTE 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 transfer(5559998585 XML my.workphone.ca) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.532247 [DEBUG] switch_ivr.c:2160 (sofia/external/15554321212@96.88.69.88) State Change CS_EXECUTE -> CS_ROUTING 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.532247 [NOTICE] switch_ivr.c:2167 Transfer sofia/external/15554321212@96.88.69.88 to XML[5559998585@my.workphone.ca] 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.532247 [DEBUG] switch_core_state_machine.c:650 (sofia/external/15554321212@96.88.69.88) State EXECUTE going to sleep 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.532247 [DEBUG] switch_core_state_machine.c:584 (sofia/external/15554321212@96.88.69.88) Running State Change CS_ROUTING (Cur 1 Tot 488) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.532247 [DEBUG] switch_core_state_machine.c:643 (sofia/external/15554321212@96.88.69.88) State ROUTING 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.532247 [DEBUG] mod_sofia.c:143 sofia/external/15554321212@96.88.69.88 SOFIA ROUTING 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.532247 [DEBUG] switch_core_state_machine.c:236 sofia/external/15554321212@96.88.69.88 Standard ROUTING 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.532247 [INFO] mod_dialplan_xml.c:637 Processing HOROM <5554321212>->5559998585 in context my.workphone.ca 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->user_exists] continue=true 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (PASS) [user_exists] () =~ // break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 set(user_exists=true) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.542244 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [user_exists]=[true] 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (PASS) [user_exists] ${user_exists}(true) =~ /^true$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(extension_uuid=${user_data ${destination_number}@${domain_name} var extension_uuid}) 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(hold_music=${user_data ${destination_number}@${domain_name} var hold_music}) 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(forward_all_enabled=${user_data ${destination_number}@${domain_name} var forward_all_enabled}) 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(forward_all_destination=${user_data ${destination_number}@${domain_name} var forward_all_destination}) 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(forward_busy_enabled=${user_data ${destination_number}@${domain_name} var forward_busy_enabled}) 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(forward_busy_destination=${user_data ${destination_number}@${domain_name} var forward_busy_destination}) 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(forward_no_answer_enabled=${user_data ${destination_number}@${domain_name} var forward_no_answer_enabled}) 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(forward_no_answer_destination=${user_data ${destination_number}@${domain_name} var forward_no_answer_destination}) 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(forward_user_not_registered_enabled=${user_data ${destination_number}@${domain_name} var forward_user_not_registered_enabled}) 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(forward_user_not_registered_destination=${user_data ${destination_number}@${domain_name} var forward_user_not_registered_destination}) 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(do_not_disturb=${user_data ${destination_number}@${domain_name} var do_not_disturb}) 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(call_timeout=${user_data ${destination_number}@${domain_name} var call_timeout}) 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(missed_call_app=${user_data ${destination_number}@${domain_name} var missed_call_app}) 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(missed_call_data=${user_data ${destination_number}@${domain_name} var missed_call_data}) 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(call_screen_enabled=${user_data ${destination_number}@${domain_name} var call_screen_enabled}) INLINE 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 set(call_screen_enabled=false) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.542244 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [call_screen_enabled]=[false] 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->call-direction] continue=true 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (PASS) [call-direction] ${call_direction}(inbound) =~ /^(inbound|outbound|local)$/ break=never 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->variables] continue=true 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (PASS) [variables] () =~ // break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action export(origination_callee_id_name=${destination_number}) 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(timezone=America/Halifax) INLINE 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 set(timezone=America/Halifax) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.542244 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [timezone]=[America/Halifax] 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->user_record] continue=true 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (PASS) [user_record] () =~ // break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(user_record=${user_data ${destination_number}@${domain_name} var user_record}) INLINE 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 set(user_record=) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.542244 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [user_record]=[UNDEF] 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE 
2017-06-02 20:40:02.552254 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x205f830 Connected. 
2017-06-02 20:40:02.552254 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x205f830 released. 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 set(from_user_exists=false) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.552254 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [from_user_exists]=[false] 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->redial] continue=true 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [redial] destination_number(5559998585) =~ /^(redial|\*870)$/ break=on-true 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (PASS) [redial] () =~ // break=never 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number}) 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->speed_dial] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [speed_dial] destination_number(5559998585) =~ /^\*0(.*)$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->sip_has_crypto] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [sip_has_crypto] ${sip_has_crypto}() =~ /^(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)$/ break=never 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->transfer.10d] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [transfer.10d] ${user_exists}(true) =~ /false/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->VoIP_Innovations_Conversational_Primary_IP.011.9-17d] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [VoIP_Innovations_Conversational_Primary_IP.011.9-17d] ${user_exists}(true) =~ /false/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->VoIP_Innovations_Conversational_Primary_IP.10d] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [VoIP_Innovations_Conversational_Primary_IP.10d] ${user_exists}(true) =~ /false/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->VoIP_Innovations_Conversational_Primary_IP.11d] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [VoIP_Innovations_Conversational_Primary_IP.11d] ${user_exists}(true) =~ /false/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->VoIP_Innovations_Conversational_Primary_IP.1d10] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [VoIP_Innovations_Conversational_Primary_IP.1d10] ${user_exists}(true) =~ /false/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->agent_status] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [agent_status] destination_number(5559998585) =~ /^\*22$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->agent_status_id] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [agent_status_id] destination_number(5559998585) =~ /^\*23$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->group-intercept] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [group-intercept] destination_number(5559998585) =~ /^\*8$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->page-extension] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [page-extension] destination_number(5559998585) =~ /^\*8(\d{2,7})$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->eavesdrop] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [eavesdrop] destination_number(5559998585) =~ /^\*33(\d{2,7})$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->call_privacy] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [call_privacy] destination_number(5559998585) =~ /^\*67(\d+)$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->call_return] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [call_return] destination_number(5559998585) =~ /^\*69$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->extension_queue] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [extension_queue] destination_number(5559998585) =~ /^\*800(.*)$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->intercept-ext] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [intercept-ext] destination_number(5559998585) =~ /^\*\*(\d+)$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->intercept-ext-polycom] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [intercept-ext-polycom] destination_number(5559998585) =~ /^\*97(\d+)$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->dx] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [dx] destination_number(5559998585) =~ /^dx$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->att_xfer] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [att_xfer] destination_number(5559998585) =~ /^att_xfer$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->extension-to-voicemail] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (PASS) [extension-to-voicemail] username(5554321212) =~ /^5554321212$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [extension-to-voicemail] destination_number(5559998585) =~ /^5554321212$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->send_to_voicemail] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [send_to_voicemail] destination_number(5559998585) =~ /^\*99(\d{2,10})$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->vmain] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [vmain] destination_number(5559998585) =~ /^vmain$|^\*4000$|^\*98$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->xfer_vm] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [xfer_vm] destination_number(5559998585) =~ /^xfer_vm$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->is_transfer] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [is_transfer] destination_number(5559998585) =~ /^is_transfer$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->vmain_user] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [vmain_user] destination_number(5559998585) =~ /^\*97$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->cf] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [cf] destination_number(5559998585) =~ /^cf$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->delay_echo] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [delay_echo] destination_number(5559998585) =~ /^\*9195$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->echo] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [echo] destination_number(5559998585) =~ /^\*9196$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->is_zrtp_secure] continue=true 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [is_zrtp_secure] ${zrtp_secure_media_confirmed}() =~ /^true$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 ANTI-Action eval(not_secure) 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->milliwatt] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [milliwatt] destination_number(5559998585) =~ /^\*9197$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->is_secure] continue=true 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [is_secure] ${sip_via_protocol}(udp) =~ /tls/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->tone_stream] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [tone_stream] destination_number(5559998585) =~ /^\*9198$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->hold_music] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [hold_music] destination_number(5559998585) =~ /^\*9664$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->recordings] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [recordings] destination_number(5559998585) =~ /^\*(732)$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->directory] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [directory] destination_number(5559998585) =~ /^\*411$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->wake-up] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [wake-up] destination_number(5559998585) =~ /^\*(925)$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->valet_park] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [valet_park] destination_number(5559998585) =~ /^(park\+)?(\*59[0-9][0-9])$/ break=never 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [valet_park] ${sip_h_Referred-By}() =~ /sip:(.*)@.*/ break=never 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [valet_park] destination_number(5559998585) =~ /^(park\+)?(\*59[0-9][0-9])$/ break=never 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [valet_park] destination_number(5559998585) =~ /^(park\+)?(\*59[0-9][0-9])$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->operator] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [operator] destination_number(5559998585) =~ /^0$|^operator$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->operator-forward] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [operator-forward] destination_number(5559998585) =~ /^\*000$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->do-not-disturb] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [do-not-disturb] destination_number(5559998585) =~ /^\*77$/ break=on-true 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [do-not-disturb] destination_number(5559998585) =~ /^\*78$|\*363$/ break=on-true 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [do-not-disturb] destination_number(5559998585) =~ /^\*79$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->call-forward] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [call-forward] destination_number(5559998585) =~ /^\*72$/ break=on-true 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [call-forward] destination_number(5559998585) =~ /^\*73$/ break=on-true 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [call-forward] destination_number(5559998585) =~ /^\*74$/ break=on-true 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->follow-me] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [follow-me] destination_number(5559998585) =~ /^\*21$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->clear_sip_auto_answer] continue=true 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [clear_sip_auto_answer] ${click_to_call}() =~ /true/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->talking clock date and time] continue=true 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [talking clock date and time] destination_number(5559998585) =~ /^\*9172$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->talking clock time] continue=true 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [talking clock time] destination_number(5559998585) =~ /^\*9170$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->talking clock date] continue=true 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [talking clock date] destination_number(5559998585) =~ /^\*9171$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->call_screen] continue=true 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [call_screen] ${call_screen_enabled}(false) =~ /^true$/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->local_extension] continue=true 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (PASS) [local_extension] ${user_exists}(true) =~ /true/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action export(dialed_extension=${destination_number}) INLINE 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 export(dialed_extension=5559998585) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.552254 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [dialed_extension]=[5559998585] 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action limit(hash ${domain_name} ${destination_number} ${limit_max} ${limit_destination}) 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action export(rtp_secure_media=true) 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [local_extension] ${sip_authorized}() =~ /true/ break=never 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 ANTI-Action set(bind_target=peer) INLINE 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 set(bind_target=peer) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.552254 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [bind_target]=[peer] 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (PASS) [local_extension] () =~ // break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action bind_digit_action(local,*1,exec:execute_extension,dx XML ${context},${bind_target}) 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action bind_digit_action(local,*2,exec:record_session,${recordings_dir}/${domain_name}/archive/${strftime(%Y)}/${strftime(%b)}/${strftime(%d)}/${uuid}.${record_ext},${bind_target}) 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action bind_digit_action(local,*3,exec:execute_extension,cf XML ${context},${bind_target}) 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action bind_digit_action(local,*4,exec:execute_extension,att_xfer XML ${context},${bind_target}) 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action digit_action_set_realm(local) 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (PASS) [local_extension] () =~ // break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(hangup_after_bridge=true) 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(continue_on_fail=true) 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number}) 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid}) 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(called_party_call_group=${user_data(${dialed_extension}@${domain_name} var call_group)}) 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action hash(insert/${domain_name}-last_dial/${called_party_call_group}/${uuid}) 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(api_hangup_hook=lua app.lua hangup) 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action export(domain_name=${context}) 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action bridge(user/${destination_number}@${domain_name}) 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action lua(app.lua failure_handler) 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->voicemail] continue=false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (PASS) [voicemail] ${user_exists}(true) =~ /true/ break=on-false 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action answer() 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action sleep(1000) 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(voicemail_action=save) 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(voicemail_id=${destination_number}) 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(voicemail_profile=default) 
0c9c70b8-0345-46e1-a193-67751042f058 Dialplan: sofia/external/15554321212@96.88.69.88 Action lua(app.lua voicemail) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.552254 [DEBUG] switch_core_state_machine.c:286 (sofia/external/15554321212@96.88.69.88) State Change CS_ROUTING -> CS_EXECUTE 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.552254 [DEBUG] switch_core_state_machine.c:643 (sofia/external/15554321212@96.88.69.88) State ROUTING going to sleep 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.552254 [DEBUG] switch_core_state_machine.c:584 (sofia/external/15554321212@96.88.69.88) Running State Change CS_EXECUTE (Cur 1 Tot 488) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.552254 [DEBUG] switch_core_state_machine.c:650 (sofia/external/15554321212@96.88.69.88) State EXECUTE 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.552254 [DEBUG] mod_sofia.c:198 sofia/external/15554321212@96.88.69.88 SOFIA EXECUTE 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.552254 [DEBUG] switch_core_state_machine.c:328 sofia/external/15554321212@96.88.69.88 Standard EXECUTE 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 set(extension_uuid=c53678f6-62e8-48df-8fba-e7ddd39de7b3) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.562243 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [extension_uuid]=[c53678f6-62e8-48df-8fba-e7ddd39de7b3] 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 set(hold_music=local_stream://default) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.562243 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [hold_music]=[local_stream://default] 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 set(forward_all_enabled=true) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.562243 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [forward_all_enabled]=[true] 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 set(forward_all_destination=+15059687272) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.572243 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [forward_all_destination]=[+15059687272] 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 set(forward_busy_enabled=false) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.572243 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [forward_busy_enabled]=[false] 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 set(forward_busy_destination=) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.572243 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [forward_busy_destination]=[UNDEF] 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 set(forward_no_answer_enabled=false) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.582244 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [forward_no_answer_enabled]=[false] 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 set(forward_no_answer_destination=) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.582244 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [forward_no_answer_destination]=[UNDEF] 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 set(forward_user_not_registered_enabled=false) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.582244 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [forward_user_not_registered_enabled]=[false] 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 set(forward_user_not_registered_destination=) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.592242 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [forward_user_not_registered_destination]=[UNDEF] 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 set(do_not_disturb=false) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.592242 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [do_not_disturb]=[false] 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 set(call_timeout=30) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.592242 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [call_timeout]=[30] 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 set(missed_call_app=) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.592242 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [missed_call_app]=[UNDEF] 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 set(missed_call_data=) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.602242 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [missed_call_data]=[UNDEF] 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 export(origination_callee_id_name=5559998585) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.602242 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [origination_callee_id_name]=[5559998585] 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 set(RFC2822_DATE=Fri, 02 Jun 2017 20:40:02 +0000) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.602242 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [RFC2822_DATE]=[Fri, 02 Jun 2017 20:40:02 +0000] 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 hash(insert/my.workphone.ca-last_dial/5554321212/5559998585) 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 eval(not_secure) 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 limit(hash my.workphone.ca 5559998585 ) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.602242 [DEBUG] switch_limit.c:126 incr called: my.workphone.ca_5559998585 max:-1, interval:0 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.602242 [DEBUG] mod_hash.c:194 Usage for my.workphone.ca_5559998585 is now 1 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 export(rtp_secure_media=true) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.602242 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [rtp_secure_media]=[true] 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 bind_digit_action(local,*1,exec:execute_extension,dx XML my.workphone.ca,peer) 
2017-06-02 20:40:02.602242 [INFO] switch_ivr_async.c:214 Digit parser DPTOOLS: Setting realm to 'local' 
2017-06-02 20:40:02.602242 [DEBUG] switch_ivr_async.c:323 Digit parser DPTOOLS: binding *1/local/0 callback: 0x6d49d7be6c20 data: 0x1f7b4f8 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 bind_digit_action(local,*2,exec:record_session,/var/lib/freeswitch/recordings/my.workphone.ca/archive/2017/Jun/02/0c9c70b8-0345-46e1-a193-67751042f058.wav,peer) 
2017-06-02 20:40:02.602242 [DEBUG] switch_ivr_async.c:323 Digit parser DPTOOLS: binding *2/local/0 callback: 0x6d49d7be6c20 data: 0x1f7b728 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 bind_digit_action(local,*3,exec:execute_extension,cf XML my.workphone.ca,peer) 
2017-06-02 20:40:02.602242 [DEBUG] switch_ivr_async.c:323 Digit parser DPTOOLS: binding *3/local/0 callback: 0x6d49d7be6c20 data: 0x1f7b8c0 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 bind_digit_action(local,*4,exec:execute_extension,att_xfer XML my.workphone.ca,peer) 
2017-06-02 20:40:02.602242 [DEBUG] switch_ivr_async.c:323 Digit parser DPTOOLS: binding *4/local/0 callback: 0x6d49d7be6c20 data: 0x217f1c0 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 digit_action_set_realm(local) 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 set(hangup_after_bridge=true) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.602242 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [hangup_after_bridge]=[true] 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 set(continue_on_fail=true) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.602242 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [continue_on_fail]=[true] 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 hash(insert/my.workphone.ca-call_return/5559998585/5554321212) 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 hash(insert/my.workphone.ca-last_dial_ext/5559998585/0c9c70b8-0345-46e1-a193-67751042f058) 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 set(called_party_call_group=) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.602242 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [called_party_call_group]=[UNDEF] 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 hash(insert/my.workphone.ca-last_dial//0c9c70b8-0345-46e1-a193-67751042f058) 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 set(api_hangup_hook=lua app.lua hangup) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.602242 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [api_hangup_hook]=[lua app.lua hangup] 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 export(domain_name=my.workphone.ca) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.602242 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [domain_name]=[my.workphone.ca] 
0c9c70b8-0345-46e1-a193-67751042f058 EXECUTE sofia/external/15554321212@96.88.69.88 bridge(user/5559998585@my.workphone.ca) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.612241 [DEBUG] switch_channel.c:1250 sofia/external/15554321212@96.88.69.88 EXPORTING[export_vars] [dialed_extension]=[5559998585] to event 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.612241 [DEBUG] switch_channel.c:1250 sofia/external/15554321212@96.88.69.88 EXPORTING[export_vars] [origination_callee_id_name]=[5559998585] to event 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.612241 [DEBUG] switch_channel.c:1250 sofia/external/15554321212@96.88.69.88 EXPORTING[export_vars] [rtp_secure_media]=[true] to event 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.612241 [DEBUG] switch_channel.c:1250 sofia/external/15554321212@96.88.69.88 EXPORTING[export_vars] [domain_name]=[my.workphone.ca] to event 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.612241 [DEBUG] switch_ivr_originate.c:2138 Parsing global variables 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.612241 [DEBUG] switch_channel.c:1250 sofia/external/15554321212@96.88.69.88 EXPORTING[export_vars] [dialed_extension]=[5559998585] to event 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.612241 [DEBUG] switch_channel.c:1250 sofia/external/15554321212@96.88.69.88 EXPORTING[export_vars] [origination_callee_id_name]=[5559998585] to event 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.612241 [DEBUG] switch_channel.c:1250 sofia/external/15554321212@96.88.69.88 EXPORTING[export_vars] [rtp_secure_media]=[true] to event 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.612241 [DEBUG] switch_channel.c:1250 sofia/external/15554321212@96.88.69.88 EXPORTING[export_vars] [domain_name]=[my.workphone.ca] to event 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:02.612241 [DEBUG] switch_ivr_originate.c:2138 Parsing global variables 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:02.612241 [NOTICE] switch_channel.c:1104 New Channel sofia/external/5059687272 [873afbb6-acd8-432e-b6ea-1b739a7e7e9e] 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:02.612241 [DEBUG] mod_sofia.c:4808 (sofia/external/5059687272) State Change CS_NEW -> CS_INIT 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:02.612241 [DEBUG] switch_core_state_machine.c:584 (sofia/external/5059687272) Running State Change CS_INIT (Cur 2 Tot 489) 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:02.612241 [DEBUG] switch_core_state_machine.c:627 (sofia/external/5059687272) State INIT 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:02.612241 [DEBUG] mod_sofia.c:90 sofia/external/5059687272 SOFIA INIT 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:02.612241 [DEBUG] switch_core_media.c:1117 Set Local audio crypto Key [1 AEAD_AES_256_GCM_8 inline:mDQ3pGJiDxb7Vas4ROTVd5RiZQtsvmkWgKK9ZQmNiofXxku5e/DLSieVExU] 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:02.612241 [DEBUG] switch_core_media.c:1117 Set Local video crypto Key [1 AEAD_AES_256_GCM_8 inline:OXBHtbaSSWGOSo8R3Ov+DN7Wt1d4NUFaAgFqjNN0EB1dgnWZkQ+OIx9TX9c] 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:02.612241 [DEBUG] switch_core_media.c:1117 Set Local audio crypto Key [2 AEAD_AES_128_GCM_8 inline:Aq9nmphdN6YiFULHVaVYlDzwhqJurhqBA0rOrw] 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:02.612241 [DEBUG] switch_core_media.c:1117 Set Local video crypto Key [2 AEAD_AES_128_GCM_8 inline:5ZFrW/CKX8pUeA03U/CbTndiUEblbswZrz0UwQ] 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:02.612241 [DEBUG] switch_core_media.c:1117 Set Local audio crypto Key [3 AES_CM_256_HMAC_SHA1_80 inline:mA+63efDsOqJ/H4dXG/0Cklg63QBL5f7BP+NIB+mIQoxF6Imau+3JBKSzf+fcQ] 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:02.612241 [DEBUG] switch_core_media.c:1117 Set Local video crypto Key [3 AES_CM_256_HMAC_SHA1_80 inline:LqoPuEZGwDGidR91S6iPuLEkfLjY3XZVHGgAf0tqsVqTixZEXG+044/lMkY4hw] 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:02.612241 [DEBUG] switch_core_media.c:1117 Set Local audio crypto Key [4 AES_CM_192_HMAC_SHA1_80 inline:RSb4bBURiROdrqjM7y6Wrldsl3G8D5Li0XKcCjxVXrJLEntZzlo] 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:02.612241 [DEBUG] switch_core_media.c:1117 Set Local video crypto Key [4 AES_CM_192_HMAC_SHA1_80 inline:pxVsna3eS7KGffK60waLX3tVpzSZmyymxdoUMH9mgf8FS4LhyJE] 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:02.612241 [DEBUG] switch_core_media.c:1117 Set Local audio crypto Key [5 AES_CM_128_HMAC_SHA1_80 inline:exwPwLlaTMO4+AcQZCz4rKc3x7Yf5kZr6xmRzGSK] 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:02.612241 [DEBUG] switch_core_media.c:1117 Set Local video crypto Key [5 AES_CM_128_HMAC_SHA1_80 inline:hdoPU1O++QW5ackkG9dLZTPcOCaRDZ848r4SPhTz] 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:02.612241 [DEBUG] switch_core_media.c:1117 Set Local audio crypto Key [6 AES_CM_256_HMAC_SHA1_32 inline:TJuHHUQAVisZmpTOMlpKOePg375HGhuuBFtbxADn6W9j3JM67DBLtkpLKK+esQ] 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:02.612241 [DEBUG] switch_core_media.c:1117 Set Local video crypto Key [6 AES_CM_256_HMAC_SHA1_32 inline:Wp3S1dgwVJ9uNWPuKmcL2kLrkaH/CdqmpIwlyzVUZK9SjpIAzwk8eHYsnNIVsg] 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:02.612241 [DEBUG] switch_core_media.c:1117 Set Local audio crypto Key [7 AES_CM_192_HMAC_SHA1_32 inline:q2HaT2/rqHGV/3P4xHMDQQnjjpcseKJweFcEnaeawdXH27diALY] 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:02.612241 [DEBUG] switch_core_media.c:1117 Set Local video crypto Key [7 AES_CM_192_HMAC_SHA1_32 inline:rWNvLQ5VVpxfn4nMtDE81rxAgpMTzlFzPARWkIZy65coEDASazk] 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:02.612241 [DEBUG] switch_core_media.c:1117 Set Local audio crypto Key [8 AES_CM_128_HMAC_SHA1_32 inline:D23aEEz3NPU/MJbn3Ay1MSplaM1OuaqT4JKGNpOV] 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:02.612241 [DEBUG] switch_core_media.c:1117 Set Local video crypto Key [8 AES_CM_128_HMAC_SHA1_32 inline:Fp5011U7lla5jlowG4j/HdI8XO07EFUGMfwzsY6U] 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:02.612241 [DEBUG] switch_core_media.c:1117 Set Local audio crypto Key [9 AES_CM_128_NULL_AUTH inline:CnVIkpEUdaYbh44qy+ZL+nE7ELHJzjzkG/PQYgMF] 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:02.612241 [DEBUG] switch_core_media.c:1117 Set Local video crypto Key [9 AES_CM_128_NULL_AUTH inline:vVt8OiMnoJOLC9vMLHhxZg4cUfShJy9RdKaS0wPu] 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:02.612241 [DEBUG] sofia_glue.c:1283 sofia/external/5059687272 sending invite version: 1.6.14 -23-e460bf8 64bit 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e Local SDP: 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e v=0 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e o=FreeSWITCH 1496410354 1496410355 IN IP4 111.111.111.111 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e s=FreeSWITCH 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e c=IN IP4 111.111.111.111 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e t=0 0 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e m=audio 25648 RTP/SAVP 0 8 3 101 13 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e a=rtpmap:0 PCMU/8000 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e a=rtpmap:8 PCMA/8000 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e a=rtpmap:3 GSM/8000 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e a=rtpmap:101 telephone-event/8000 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e a=fmtp:101 0-16 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e a=crypto:1 AEAD_AES_256_GCM_8 inline:mDQ3pGJiDxb7Vas4ROTVd5RiZQtsvmkWgKK9ZQmNiofXxku5e/DLSieVExU 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e a=crypto:2 AEAD_AES_128_GCM_8 inline:Aq9nmphdN6YiFULHVaVYlDzwhqJurhqBA0rOrw 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e a=crypto:3 AES_CM_256_HMAC_SHA1_80 inline:mA+63efDsOqJ/H4dXG/0Cklg63QBL5f7BP+NIB+mIQoxF6Imau+3JBKSzf+fcQ 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e a=crypto:4 AES_CM_192_HMAC_SHA1_80 inline:RSb4bBURiROdrqjM7y6Wrldsl3G8D5Li0XKcCjxVXrJLEntZzlo 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e a=crypto:5 AES_CM_128_HMAC_SHA1_80 inline:exwPwLlaTMO4+AcQZCz4rKc3x7Yf5kZr6xmRzGSK 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e a=crypto:6 AES_CM_256_HMAC_SHA1_32 inline:TJuHHUQAVisZmpTOMlpKOePg375HGhuuBFtbxADn6W9j3JM67DBLtkpLKK+esQ 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e a=crypto:7 AES_CM_192_HMAC_SHA1_32 inline:q2HaT2/rqHGV/3P4xHMDQQnjjpcseKJweFcEnaeawdXH27diALY 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e a=crypto:8 AES_CM_128_HMAC_SHA1_32 inline:D23aEEz3NPU/MJbn3Ay1MSplaM1OuaqT4JKGNpOV 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e a=crypto:9 AES_CM_128_NULL_AUTH inline:CnVIkpEUdaYbh44qy+ZL+nE7ELHJzjzkG/PQYgMF 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e a=rtpmap:13 CN/8000 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e a=ptime:20 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e a=sendrecv 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:02.612241 [DEBUG] switch_core_state_machine.c:40 sofia/external/5059687272 Standard INIT 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:02.612241 [DEBUG] switch_core_state_machine.c:48 (sofia/external/5059687272) State Change CS_INIT -> CS_ROUTING 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:02.612241 [DEBUG] switch_core_state_machine.c:627 (sofia/external/5059687272) State INIT going to sleep 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:02.612241 [DEBUG] switch_core_state_machine.c:584 (sofia/external/5059687272) Running State Change CS_ROUTING (Cur 2 Tot 489) 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:02.612241 [DEBUG] sofia.c:7041 Channel sofia/external/5059687272 entering state [calling][0] 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:02.612241 [DEBUG] switch_core_state_machine.c:643 (sofia/external/5059687272) State ROUTING 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:02.612241 [DEBUG] mod_sofia.c:143 sofia/external/5059687272 SOFIA ROUTING 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:02.612241 [DEBUG] switch_ivr_originate.c:67 (sofia/external/5059687272) State Change CS_ROUTING -> CS_CONSUME_MEDIA 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:02.612241 [DEBUG] switch_core_state_machine.c:643 (sofia/external/5059687272) State ROUTING going to sleep 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:02.612241 [DEBUG] switch_core_state_machine.c:584 (sofia/external/5059687272) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 489) 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:02.612241 [DEBUG] switch_core_state_machine.c:662 (sofia/external/5059687272) State CONSUME_MEDIA 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:02.612241 [DEBUG] switch_core_state_machine.c:662 (sofia/external/5059687272) State CONSUME_MEDIA going to sleep 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:04.132252 [DEBUG] sofia.c:7041 Channel sofia/external/15554321212@96.88.69.88 entering state [terminated][487] 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:04.132252 [NOTICE] sofia.c:8212 Hangup sofia/external/15554321212@96.88.69.88 [CS_EXECUTE] [ORIGINATOR_CANCEL] 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:04.132252 [DEBUG] mod_hash.c:297 Usage for my.workphone.ca_5559998585 is now 0 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:04.152248 [NOTICE] switch_ivr_originate.c:3601 Hangup sofia/external/5059687272 [CS_CONSUME_MEDIA] [ORIGINATOR_CANCEL] 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:04.152248 [DEBUG] switch_ivr_originate.c:3818 Originate Cancelled by originator termination Cause: 487 [ORIGINATOR_CANCEL] 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:04.152248 [DEBUG] switch_core_state_machine.c:584 (sofia/external/5059687272) Running State Change CS_HANGUP (Cur 2 Tot 489) 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:04.152248 [DEBUG] switch_core_state_machine.c:850 (sofia/external/5059687272) Callstate Change DOWN -> HANGUP 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:04.152248 [DEBUG] switch_core_state_machine.c:852 (sofia/external/5059687272) State HANGUP 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:04.152248 [DEBUG] mod_sofia.c:432 sofia/external/5059687272 Overriding SIP cause 487 with 487 from the other leg 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:04.152248 [DEBUG] mod_sofia.c:438 Channel sofia/external/5059687272 hanging up, cause: ORIGINATOR_CANCEL 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:04.152248 [DEBUG] mod_sofia.c:502 Sending CANCEL to sofia/external/5059687272 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:04.152248 [DEBUG] switch_core_state_machine.c:60 sofia/external/5059687272 Standard HANGUP, cause: ORIGINATOR_CANCEL 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:04.152248 [DEBUG] switch_core_state_machine.c:852 (sofia/external/5059687272) State HANGUP going to sleep 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:04.152248 [DEBUG] switch_core_state_machine.c:619 (sofia/external/5059687272) State Change CS_HANGUP -> CS_REPORTING 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:04.152248 [DEBUG] switch_core_state_machine.c:584 (sofia/external/5059687272) Running State Change CS_REPORTING (Cur 2 Tot 489) 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:04.152248 [DEBUG] switch_core_state_machine.c:938 (sofia/external/5059687272) State REPORTING 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:04.152248 [DEBUG] switch_core_state_machine.c:174 sofia/external/5059687272 Standard REPORTING, cause: ORIGINATOR_CANCEL 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:04.152248 [DEBUG] switch_core_state_machine.c:938 (sofia/external/5059687272) State REPORTING going to sleep 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:04.152248 [NOTICE] switch_ivr_originate.c:2841 Cannot create outgoing channel of type [user] cause: [ORIGINATOR_CANCEL] 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:04.152248 [DEBUG] switch_ivr_originate.c:3829 Originate Resulted in Error Cause: 487 [ORIGINATOR_CANCEL] 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:04.152248 [DEBUG] switch_core_state_machine.c:610 (sofia/external/5059687272) State Change CS_REPORTING -> CS_DESTROY 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:04.152248 [DEBUG] switch_core_session.c:1647 Session 489 (sofia/external/5059687272) Locked, Waiting on external entities 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:04.152248 [NOTICE] switch_core_session.c:1665 Session 489 (sofia/external/5059687272) Ended 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:04.152248 [NOTICE] switch_core_session.c:1669 Close Channel sofia/external/5059687272 [CS_DESTROY] 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:04.152248 [DEBUG] switch_core_state_machine.c:741 (sofia/external/5059687272) Running State Change CS_DESTROY (Cur 1 Tot 489) 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:04.152248 [DEBUG] switch_core_state_machine.c:751 (sofia/external/5059687272) State DESTROY 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:04.152248 [DEBUG] mod_sofia.c:343 sofia/external/5059687272 SOFIA DESTROY 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:04.152248 [DEBUG] switch_core_state_machine.c:181 sofia/external/5059687272 Standard DESTROY 
873afbb6-acd8-432e-b6ea-1b739a7e7e9e 2017-06-02 20:40:04.152248 [DEBUG] switch_core_state_machine.c:751 (sofia/external/5059687272) State DESTROY going to sleep 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:04.152248 [INFO] mod_dptools.c:3409 Originate Failed. Cause: ORIGINATOR_CANCEL 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:04.152248 [DEBUG] switch_core_session.c:2797 sofia/external/15554321212@96.88.69.88 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:04.152248 [DEBUG] switch_core_state_machine.c:650 (sofia/external/15554321212@96.88.69.88) State EXECUTE going to sleep 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:04.152248 [DEBUG] switch_core_state_machine.c:584 (sofia/external/15554321212@96.88.69.88) Running State Change CS_HANGUP (Cur 1 Tot 489) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:04.162245 [DEBUG] switch_core_state_machine.c:850 (sofia/external/15554321212@96.88.69.88) Callstate Change RINGING -> HANGUP 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:04.162245 [DEBUG] switch_core_state_machine.c:852 (sofia/external/15554321212@96.88.69.88) State HANGUP 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:04.162245 [DEBUG] mod_sofia.c:438 Channel sofia/external/15554321212@96.88.69.88 hanging up, cause: ORIGINATOR_CANCEL 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:04.162245 [DEBUG] switch_core_state_machine.c:60 sofia/external/15554321212@96.88.69.88 Standard HANGUP, cause: ORIGINATOR_CANCEL 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:04.162245 [DEBUG] switch_core_state_machine.c:852 (sofia/external/15554321212@96.88.69.88) State HANGUP going to sleep 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:04.162245 [DEBUG] switch_core_state_machine.c:783 Hangup Command with no Session lua(app.lua hangup): 
0c9c70b8-0345-46e1-a193-67751042f058 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:04.162245 [DEBUG] switch_core_state_machine.c:619 (sofia/external/15554321212@96.88.69.88) State Change CS_HANGUP -> CS_REPORTING 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:04.162245 [DEBUG] switch_core_state_machine.c:584 (sofia/external/15554321212@96.88.69.88) Running State Change CS_REPORTING (Cur 1 Tot 489) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:04.162245 [DEBUG] switch_core_state_machine.c:938 (sofia/external/15554321212@96.88.69.88) State REPORTING 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:04.282246 [DEBUG] switch_core_state_machine.c:174 sofia/external/15554321212@96.88.69.88 Standard REPORTING, cause: ORIGINATOR_CANCEL 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:04.282246 [DEBUG] switch_core_state_machine.c:938 (sofia/external/15554321212@96.88.69.88) State REPORTING going to sleep 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:04.282246 [DEBUG] switch_core_state_machine.c:610 (sofia/external/15554321212@96.88.69.88) State Change CS_REPORTING -> CS_DESTROY 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:04.282246 [DEBUG] switch_core_session.c:1647 Session 488 (sofia/external/15554321212@96.88.69.88) Locked, Waiting on external entities 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:04.282246 [NOTICE] switch_core_session.c:1665 Session 488 (sofia/external/15554321212@96.88.69.88) Ended 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:04.282246 [NOTICE] switch_core_session.c:1669 Close Channel sofia/external/15554321212@96.88.69.88 [CS_DESTROY] 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:04.282246 [DEBUG] switch_core_state_machine.c:741 (sofia/external/15554321212@96.88.69.88) Running State Change CS_DESTROY (Cur 0 Tot 489) 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:04.282246 [DEBUG] switch_core_state_machine.c:751 (sofia/external/15554321212@96.88.69.88) State DESTROY 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:04.282246 [DEBUG] mod_sofia.c:343 sofia/external/15554321212@96.88.69.88 SOFIA DESTROY 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:04.282246 [DEBUG] switch_core_state_machine.c:181 sofia/external/15554321212@96.88.69.88 Standard DESTROY 
0c9c70b8-0345-46e1-a193-67751042f058 2017-06-02 20:40:04.282246 [DEBUG] switch_core_state_machine.c:751 (sofia/external/15554321212@96.88.69.88) State DESTROY going to sleep 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.372253 [NOTICE] switch_channel.c:1104 New Channel sofia/external/15554321212@96.88.69.88 [5f780b7d-593b-4524-a6c5-1767d3f6a047] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.372253 [DEBUG] switch_core_state_machine.c:584 (sofia/external/15554321212@96.88.69.88) Running State Change CS_NEW (Cur 1 Tot 490) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.372253 [DEBUG] sofia.c:9815 sofia/external/15554321212@96.88.69.88 receiving invite from 96.88.69.88:9020 version: 1.6.14 -23-e460bf8 64bit 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.372253 [DEBUG] sofia.c:7041 Channel sofia/external/15554321212@96.88.69.88 entering state [received][100] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.372253 [DEBUG] sofia.c:7051 Remote SDP: 
5f780b7d-593b-4524-a6c5-1767d3f6a047 v=0 
5f780b7d-593b-4524-a6c5-1767d3f6a047 o=Sansay-VSXi 188 1 IN IP4 96.88.69.88 
5f780b7d-593b-4524-a6c5-1767d3f6a047 s=Session Controller 
5f780b7d-593b-4524-a6c5-1767d3f6a047 c=IN IP4 207.112.37.25 
5f780b7d-593b-4524-a6c5-1767d3f6a047 t=0 0 
5f780b7d-593b-4524-a6c5-1767d3f6a047 m=audio 36436 RTP/AVP 0 18 101 
5f780b7d-593b-4524-a6c5-1767d3f6a047 a=rtpmap:0 PCMU/8000 
5f780b7d-593b-4524-a6c5-1767d3f6a047 a=rtpmap:18 G729/8000 
5f780b7d-593b-4524-a6c5-1767d3f6a047 a=fmtp:18 annexb=no 
5f780b7d-593b-4524-a6c5-1767d3f6a047 a=rtpmap:101 telephone-event/8000 
5f780b7d-593b-4524-a6c5-1767d3f6a047 a=fmtp:101 0-15 
5f780b7d-593b-4524-a6c5-1767d3f6a047 a=ptime:20 
5f780b7d-593b-4524-a6c5-1767d3f6a047 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.372253 [DEBUG] switch_core_media.c:4378 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.372253 [DEBUG] switch_core_media.c:4378 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.372253 [DEBUG] switch_core_media.c:4433 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.372253 [DEBUG] switch_core_media.c:4378 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.372253 [DEBUG] switch_core_media.c:4378 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.372253 [DEBUG] switch_core_media.c:4378 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[opus:116:48000:20:0:1] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.372253 [DEBUG] switch_core_media.c:4378 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.372253 [DEBUG] switch_core_media.c:4378 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.372253 [DEBUG] switch_core_media.c:4378 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.372253 [DEBUG] switch_core_media.c:4378 Audio Codec Compare [G729:18:8000:20:8000:1]/[GSM:3:8000:20:13200:1] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.372253 [DEBUG] switch_core_media.c:4378 Audio Codec Compare [G729:18:8000:20:8000:1]/[opus:116:48000:20:0:1] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.372253 [DEBUG] switch_core_media.c:4294 Set telephone-event payload to 101@8000 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.372253 [DEBUG] switch_core_media.c:3043 Set Codec sofia/external/15554321212@96.88.69.88 PCMU/8000 20 ms 160 samples 64000 bits 1 channels 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.372253 [DEBUG] switch_core_codec.c:111 sofia/external/15554321212@96.88.69.88 Original read codec set to PCMU:0 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.372253 [DEBUG] switch_core_media.c:4637 Set telephone-event payload to 101@8000 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.372253 [DEBUG] switch_core_media.c:4696 sofia/external/15554321212@96.88.69.88 Set 2833 dtmf send payload to 101 recv payload to 101 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.372253 [DEBUG] sofia.c:7464 (sofia/external/15554321212@96.88.69.88) State Change CS_NEW -> CS_INIT 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.372253 [DEBUG] switch_core_state_machine.c:603 (sofia/external/15554321212@96.88.69.88) State NEW 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.372253 [DEBUG] switch_core_state_machine.c:584 (sofia/external/15554321212@96.88.69.88) Running State Change CS_INIT (Cur 1 Tot 490) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.372253 [DEBUG] switch_core_state_machine.c:627 (sofia/external/15554321212@96.88.69.88) State INIT 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.372253 [DEBUG] mod_sofia.c:90 sofia/external/15554321212@96.88.69.88 SOFIA INIT 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.372253 [DEBUG] switch_core_state_machine.c:40 sofia/external/15554321212@96.88.69.88 Standard INIT 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.372253 [DEBUG] switch_core_state_machine.c:48 (sofia/external/15554321212@96.88.69.88) State Change CS_INIT -> CS_ROUTING 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.372253 [DEBUG] switch_core_state_machine.c:627 (sofia/external/15554321212@96.88.69.88) State INIT going to sleep 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.372253 [DEBUG] switch_core_state_machine.c:584 (sofia/external/15554321212@96.88.69.88) Running State Change CS_ROUTING (Cur 1 Tot 490) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.372253 [DEBUG] switch_channel.c:2249 (sofia/external/15554321212@96.88.69.88) Callstate Change DOWN -> RINGING 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.372253 [DEBUG] switch_core_state_machine.c:643 (sofia/external/15554321212@96.88.69.88) State ROUTING 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.372253 [DEBUG] mod_sofia.c:143 sofia/external/15554321212@96.88.69.88 SOFIA ROUTING 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.372253 [DEBUG] switch_core_state_machine.c:236 sofia/external/15554321212@96.88.69.88 Standard ROUTING 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.372253 [INFO] mod_dialplan_xml.c:637 Processing HOROM <5554321212>->15559998585 in context public 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [public->13152840001] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [13152840001] destination_number(15559998585) =~ /^(13152840001)$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [public->19022063688] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [19022063688] destination_number(15559998585) =~ /^(19022063688)$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [public->19028750503] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [19028750503] destination_number(15559998585) =~ /^(19028750503)$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [public->19028750505] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [19028750505] destination_number(15559998585) =~ /^(19028750505)$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [public->19028750804] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [19028750804] destination_number(15559998585) =~ /^(19028750804)$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [public->19028751434] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [19028751434] destination_number(15559998585) =~ /^(19028751434)$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [public->15559998585] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (PASS) [15559998585] destination_number(15559998585) =~ /^(15559998585)$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(call_direction=inbound) INLINE 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 set(call_direction=inbound) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.382245 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [call_direction]=[inbound] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(domain_uuid=53dfbf50-263f-4208-9540-c2abd247b89b) INLINE 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 set(domain_uuid=53dfbf50-263f-4208-9540-c2abd247b89b) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.382245 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [domain_uuid]=[53dfbf50-263f-4208-9540-c2abd247b89b] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(domain_name=my.workphone.ca) INLINE 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 set(domain_name=my.workphone.ca) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.382245 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [domain_name]=[my.workphone.ca] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action transfer(5559998585 XML my.workphone.ca) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.382245 [DEBUG] switch_core_state_machine.c:286 (sofia/external/15554321212@96.88.69.88) State Change CS_ROUTING -> CS_EXECUTE 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.382245 [DEBUG] switch_core_state_machine.c:643 (sofia/external/15554321212@96.88.69.88) State ROUTING going to sleep 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.382245 [DEBUG] switch_core_state_machine.c:584 (sofia/external/15554321212@96.88.69.88) Running State Change CS_EXECUTE (Cur 1 Tot 490) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.382245 [DEBUG] switch_core_state_machine.c:650 (sofia/external/15554321212@96.88.69.88) State EXECUTE 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.382245 [DEBUG] mod_sofia.c:198 sofia/external/15554321212@96.88.69.88 SOFIA EXECUTE 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.382245 [DEBUG] switch_core_state_machine.c:328 sofia/external/15554321212@96.88.69.88 Standard EXECUTE 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 transfer(5559998585 XML my.workphone.ca) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.382245 [DEBUG] switch_ivr.c:2160 (sofia/external/15554321212@96.88.69.88) State Change CS_EXECUTE -> CS_ROUTING 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.382245 [NOTICE] switch_ivr.c:2167 Transfer sofia/external/15554321212@96.88.69.88 to XML[5559998585@my.workphone.ca] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.382245 [DEBUG] switch_core_state_machine.c:650 (sofia/external/15554321212@96.88.69.88) State EXECUTE going to sleep 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.382245 [DEBUG] switch_core_state_machine.c:584 (sofia/external/15554321212@96.88.69.88) Running State Change CS_ROUTING (Cur 1 Tot 490) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.382245 [DEBUG] switch_core_state_machine.c:643 (sofia/external/15554321212@96.88.69.88) State ROUTING 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.382245 [DEBUG] mod_sofia.c:143 sofia/external/15554321212@96.88.69.88 SOFIA ROUTING 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.382245 [DEBUG] switch_core_state_machine.c:236 sofia/external/15554321212@96.88.69.88 Standard ROUTING 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.382245 [INFO] mod_dialplan_xml.c:637 Processing HOROM <5554321212>->5559998585 in context my.workphone.ca 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->user_exists] continue=true 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (PASS) [user_exists] () =~ // break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 set(user_exists=true) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.392244 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [user_exists]=[true] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (PASS) [user_exists] ${user_exists}(true) =~ /^true$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(extension_uuid=${user_data ${destination_number}@${domain_name} var extension_uuid}) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(hold_music=${user_data ${destination_number}@${domain_name} var hold_music}) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(forward_all_enabled=${user_data ${destination_number}@${domain_name} var forward_all_enabled}) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(forward_all_destination=${user_data ${destination_number}@${domain_name} var forward_all_destination}) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(forward_busy_enabled=${user_data ${destination_number}@${domain_name} var forward_busy_enabled}) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(forward_busy_destination=${user_data ${destination_number}@${domain_name} var forward_busy_destination}) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(forward_no_answer_enabled=${user_data ${destination_number}@${domain_name} var forward_no_answer_enabled}) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(forward_no_answer_destination=${user_data ${destination_number}@${domain_name} var forward_no_answer_destination}) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(forward_user_not_registered_enabled=${user_data ${destination_number}@${domain_name} var forward_user_not_registered_enabled}) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(forward_user_not_registered_destination=${user_data ${destination_number}@${domain_name} var forward_user_not_registered_destination}) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(do_not_disturb=${user_data ${destination_number}@${domain_name} var do_not_disturb}) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(call_timeout=${user_data ${destination_number}@${domain_name} var call_timeout}) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(missed_call_app=${user_data ${destination_number}@${domain_name} var missed_call_app}) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(missed_call_data=${user_data ${destination_number}@${domain_name} var missed_call_data}) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(call_screen_enabled=${user_data ${destination_number}@${domain_name} var call_screen_enabled}) INLINE 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 set(call_screen_enabled=false) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.392244 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [call_screen_enabled]=[false] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->call-direction] continue=true 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (PASS) [call-direction] ${call_direction}(inbound) =~ /^(inbound|outbound|local)$/ break=never 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->variables] continue=true 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (PASS) [variables] () =~ // break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action export(origination_callee_id_name=${destination_number}) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)}) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(timezone=America/Halifax) INLINE 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 set(timezone=America/Halifax) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.392244 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [timezone]=[America/Halifax] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->user_record] continue=true 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (PASS) [user_record] () =~ // break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(user_record=${user_data ${destination_number}@${domain_name} var user_record}) INLINE 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 set(user_record=) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.402243 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [user_record]=[UNDEF] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE 
2017-06-02 20:40:24.402243 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x205f830 Connected. 
2017-06-02 20:40:24.402243 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x205f830 released. 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 set(from_user_exists=false) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.402243 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [from_user_exists]=[false] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [user_record] ${user_record}() =~ /^all$/ break=never 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [user_record] ${user_record}() =~ /^inbound$/ break=never 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [user_record] ${user_record}() =~ /^outbound$/ break=never 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (PASS) [user_record] ${user_exists}(true) =~ /^true$/ break=never 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [user_record] ${user_record}() =~ /^local$/ break=never 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [user_record] ${from_user_record}() =~ /^all$/ break=never 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (PASS) [user_record] ${call_direction}(inbound) =~ /^inbound$/ break=never 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [user_record] ${from_user_record}() =~ /^inbound$/ break=never 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^outbound$/ break=never 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [user_record] ${from_user_record}() =~ /^outbound$/ break=never 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [user_record] ${from_user_exists}(false) =~ /^true$/ break=never 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [user_record] ${call_direction}(inbound) =~ /^local$/ break=never 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [user_record] ${from_user_record}() =~ /^local$/ break=never 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [user_record] ${record_session}() =~ /^true$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->redial] continue=true 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [redial] destination_number(5559998585) =~ /^(redial|\*870)$/ break=on-true 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (PASS) [redial] () =~ // break=never 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action hash(insert/${domain_name}-last_dial/${caller_id_number}/${destination_number}) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->speed_dial] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [speed_dial] destination_number(5559998585) =~ /^\*0(.*)$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->sip_has_crypto] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [sip_has_crypto] ${sip_has_crypto}() =~ /^(AES_CM_128_HMAC_SHA1_32|AES_CM_128_HMAC_SHA1_80)$/ break=never 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->transfer.10d] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [transfer.10d] ${user_exists}(true) =~ /false/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->VoIP_Innovations_Conversational_Primary_IP.011.9-17d] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [VoIP_Innovations_Conversational_Primary_IP.011.9-17d] ${user_exists}(true) =~ /false/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->VoIP_Innovations_Conversational_Primary_IP.10d] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [VoIP_Innovations_Conversational_Primary_IP.10d] ${user_exists}(true) =~ /false/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->VoIP_Innovations_Conversational_Primary_IP.11d] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [VoIP_Innovations_Conversational_Primary_IP.11d] ${user_exists}(true) =~ /false/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->VoIP_Innovations_Conversational_Primary_IP.1d10] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [VoIP_Innovations_Conversational_Primary_IP.1d10] ${user_exists}(true) =~ /false/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->agent_status] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [agent_status] destination_number(5559998585) =~ /^\*22$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->agent_status_id] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [agent_status_id] destination_number(5559998585) =~ /^\*23$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->group-intercept] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [group-intercept] destination_number(5559998585) =~ /^\*8$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->page-extension] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [page-extension] destination_number(5559998585) =~ /^\*8(\d{2,7})$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->eavesdrop] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [eavesdrop] destination_number(5559998585) =~ /^\*33(\d{2,7})$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->call_privacy] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [call_privacy] destination_number(5559998585) =~ /^\*67(\d+)$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->call_return] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [call_return] destination_number(5559998585) =~ /^\*69$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->extension_queue] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [extension_queue] destination_number(5559998585) =~ /^\*800(.*)$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->intercept-ext] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [intercept-ext] destination_number(5559998585) =~ /^\*\*(\d+)$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->intercept-ext-polycom] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [intercept-ext-polycom] destination_number(5559998585) =~ /^\*97(\d+)$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->dx] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [dx] destination_number(5559998585) =~ /^dx$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->att_xfer] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [att_xfer] destination_number(5559998585) =~ /^att_xfer$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->extension-to-voicemail] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (PASS) [extension-to-voicemail] username(5554321212) =~ /^5554321212$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [extension-to-voicemail] destination_number(5559998585) =~ /^5554321212$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->send_to_voicemail] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [send_to_voicemail] destination_number(5559998585) =~ /^\*99(\d{2,10})$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->vmain] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [vmain] destination_number(5559998585) =~ /^vmain$|^\*4000$|^\*98$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->xfer_vm] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [xfer_vm] destination_number(5559998585) =~ /^xfer_vm$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->is_transfer] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [is_transfer] destination_number(5559998585) =~ /^is_transfer$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->vmain_user] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [vmain_user] destination_number(5559998585) =~ /^\*97$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->cf] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [cf] destination_number(5559998585) =~ /^cf$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->delay_echo] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [delay_echo] destination_number(5559998585) =~ /^\*9195$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->echo] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [echo] destination_number(5559998585) =~ /^\*9196$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->is_zrtp_secure] continue=true 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [is_zrtp_secure] ${zrtp_secure_media_confirmed}() =~ /^true$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 ANTI-Action eval(not_secure) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->milliwatt] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [milliwatt] destination_number(5559998585) =~ /^\*9197$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->is_secure] continue=true 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [is_secure] ${sip_via_protocol}(udp) =~ /tls/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->tone_stream] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [tone_stream] destination_number(5559998585) =~ /^\*9198$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->hold_music] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [hold_music] destination_number(5559998585) =~ /^\*9664$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->recordings] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [recordings] destination_number(5559998585) =~ /^\*(732)$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->directory] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [directory] destination_number(5559998585) =~ /^\*411$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->wake-up] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [wake-up] destination_number(5559998585) =~ /^\*(925)$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->valet_park] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [valet_park] destination_number(5559998585) =~ /^(park\+)?(\*59[0-9][0-9])$/ break=never 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [valet_park] ${sip_h_Referred-By}() =~ /sip:(.*)@.*/ break=never 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [valet_park] destination_number(5559998585) =~ /^(park\+)?(\*59[0-9][0-9])$/ break=never 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [valet_park] destination_number(5559998585) =~ /^(park\+)?(\*59[0-9][0-9])$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->operator] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [operator] destination_number(5559998585) =~ /^0$|^operator$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->operator-forward] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [operator-forward] destination_number(5559998585) =~ /^\*000$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->do-not-disturb] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [do-not-disturb] destination_number(5559998585) =~ /^\*77$/ break=on-true 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [do-not-disturb] destination_number(5559998585) =~ /^\*78$|\*363$/ break=on-true 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [do-not-disturb] destination_number(5559998585) =~ /^\*79$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->call-forward] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [call-forward] destination_number(5559998585) =~ /^\*72$/ break=on-true 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [call-forward] destination_number(5559998585) =~ /^\*73$/ break=on-true 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [call-forward] destination_number(5559998585) =~ /^\*74$/ break=on-true 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->follow-me] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [follow-me] destination_number(5559998585) =~ /^\*21$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->clear_sip_auto_answer] continue=true 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [clear_sip_auto_answer] ${click_to_call}() =~ /true/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->talking clock date and time] continue=true 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [talking clock date and time] destination_number(5559998585) =~ /^\*9172$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->talking clock time] continue=true 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [talking clock time] destination_number(5559998585) =~ /^\*9170$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->talking clock date] continue=true 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [talking clock date] destination_number(5559998585) =~ /^\*9171$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->call_screen] continue=true 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [call_screen] ${call_screen_enabled}(false) =~ /^true$/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->local_extension] continue=true 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (PASS) [local_extension] ${user_exists}(true) =~ /true/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action export(dialed_extension=${destination_number}) INLINE 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 export(dialed_extension=5559998585) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.402243 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [dialed_extension]=[5559998585] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action limit(hash ${domain_name} ${destination_number} ${limit_max} ${limit_destination}) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action export(rtp_secure_media=true) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (FAIL) [local_extension] ${sip_authorized}() =~ /true/ break=never 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 ANTI-Action set(bind_target=peer) INLINE 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 set(bind_target=peer) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.402243 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [bind_target]=[peer] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (PASS) [local_extension] () =~ // break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action bind_digit_action(local,*1,exec:execute_extension,dx XML ${context},${bind_target}) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action bind_digit_action(local,*2,exec:record_session,${recordings_dir}/${domain_name}/archive/${strftime(%Y)}/${strftime(%b)}/${strftime(%d)}/${uuid}.${record_ext},${bind_target}) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action bind_digit_action(local,*3,exec:execute_extension,cf XML ${context},${bind_target}) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action bind_digit_action(local,*4,exec:execute_extension,att_xfer XML ${context},${bind_target}) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action digit_action_set_realm(local) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (PASS) [local_extension] () =~ // break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(hangup_after_bridge=true) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(continue_on_fail=true) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action hash(insert/${domain_name}-call_return/${dialed_extension}/${caller_id_number}) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action hash(insert/${domain_name}-last_dial_ext/${dialed_extension}/${uuid}) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(called_party_call_group=${user_data(${dialed_extension}@${domain_name} var call_group)}) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action hash(insert/${domain_name}-last_dial/${called_party_call_group}/${uuid}) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(api_hangup_hook=lua app.lua hangup) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action export(domain_name=${context}) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action bridge(user/${destination_number}@${domain_name}) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action lua(app.lua failure_handler) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 parsing [my.workphone.ca->voicemail] continue=false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Regex (PASS) [voicemail] ${user_exists}(true) =~ /true/ break=on-false 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action answer() 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action sleep(1000) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(voicemail_action=save) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(voicemail_id=${destination_number}) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action set(voicemail_profile=default) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 Dialplan: sofia/external/15554321212@96.88.69.88 Action lua(app.lua voicemail) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.402243 [DEBUG] switch_core_state_machine.c:286 (sofia/external/15554321212@96.88.69.88) State Change CS_ROUTING -> CS_EXECUTE 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.402243 [DEBUG] switch_core_state_machine.c:643 (sofia/external/15554321212@96.88.69.88) State ROUTING going to sleep 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.402243 [DEBUG] switch_core_state_machine.c:584 (sofia/external/15554321212@96.88.69.88) Running State Change CS_EXECUTE (Cur 1 Tot 490) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.402243 [DEBUG] switch_core_state_machine.c:650 (sofia/external/15554321212@96.88.69.88) State EXECUTE 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.402243 [DEBUG] mod_sofia.c:198 sofia/external/15554321212@96.88.69.88 SOFIA EXECUTE 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.402243 [DEBUG] switch_core_state_machine.c:328 sofia/external/15554321212@96.88.69.88 Standard EXECUTE 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 set(extension_uuid=c53678f6-62e8-48df-8fba-e7ddd39de7b3) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.412243 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [extension_uuid]=[c53678f6-62e8-48df-8fba-e7ddd39de7b3] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 set(hold_music=local_stream://default) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.412243 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [hold_music]=[local_stream://default] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 set(forward_all_enabled=true) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.422247 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [forward_all_enabled]=[true] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 set(forward_all_destination=+15059687272) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.422247 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [forward_all_destination]=[+15059687272] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 set(forward_busy_enabled=false) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.422247 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [forward_busy_enabled]=[false] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 set(forward_busy_destination=) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.432243 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [forward_busy_destination]=[UNDEF] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 set(forward_no_answer_enabled=false) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.432243 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [forward_no_answer_enabled]=[false] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 set(forward_no_answer_destination=) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.432243 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [forward_no_answer_destination]=[UNDEF] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 set(forward_user_not_registered_enabled=false) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.432243 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [forward_user_not_registered_enabled]=[false] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 set(forward_user_not_registered_destination=) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.442242 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [forward_user_not_registered_destination]=[UNDEF] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 set(do_not_disturb=false) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.442242 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [do_not_disturb]=[false] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 set(call_timeout=30) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.442242 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [call_timeout]=[30] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 set(missed_call_app=) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.452242 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [missed_call_app]=[UNDEF] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 set(missed_call_data=) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.452242 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [missed_call_data]=[UNDEF] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 export(origination_callee_id_name=5559998585) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.452242 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [origination_callee_id_name]=[5559998585] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 set(RFC2822_DATE=Fri, 02 Jun 2017 20:40:24 +0000) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.452242 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [RFC2822_DATE]=[Fri, 02 Jun 2017 20:40:24 +0000] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 hash(insert/my.workphone.ca-last_dial/5554321212/5559998585) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 eval(not_secure) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 limit(hash my.workphone.ca 5559998585 ) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.452242 [DEBUG] switch_limit.c:126 incr called: my.workphone.ca_5559998585 max:-1, interval:0 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.452242 [DEBUG] mod_hash.c:194 Usage for my.workphone.ca_5559998585 is now 1 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 export(rtp_secure_media=true) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.452242 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [rtp_secure_media]=[true] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 bind_digit_action(local,*1,exec:execute_extension,dx XML my.workphone.ca,peer) 
2017-06-02 20:40:24.452242 [INFO] switch_ivr_async.c:214 Digit parser DPTOOLS: Setting realm to 'local' 
2017-06-02 20:40:24.452242 [DEBUG] switch_ivr_async.c:323 Digit parser DPTOOLS: binding *1/local/0 callback: 0x6d49d7be6c20 data: 0x1f7b4f8 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 bind_digit_action(local,*2,exec:record_session,/var/lib/freeswitch/recordings/my.workphone.ca/archive/2017/Jun/02/5f780b7d-593b-4524-a6c5-1767d3f6a047.wav,peer) 
2017-06-02 20:40:24.452242 [DEBUG] switch_ivr_async.c:323 Digit parser DPTOOLS: binding *2/local/0 callback: 0x6d49d7be6c20 data: 0x1f7b728 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 bind_digit_action(local,*3,exec:execute_extension,cf XML my.workphone.ca,peer) 
2017-06-02 20:40:24.452242 [DEBUG] switch_ivr_async.c:323 Digit parser DPTOOLS: binding *3/local/0 callback: 0x6d49d7be6c20 data: 0x1f7b8c0 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 bind_digit_action(local,*4,exec:execute_extension,att_xfer XML my.workphone.ca,peer) 
2017-06-02 20:40:24.452242 [DEBUG] switch_ivr_async.c:323 Digit parser DPTOOLS: binding *4/local/0 callback: 0x6d49d7be6c20 data: 0x217f1c0 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 digit_action_set_realm(local) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 set(hangup_after_bridge=true) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.452242 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [hangup_after_bridge]=[true] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 set(continue_on_fail=true) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.452242 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [continue_on_fail]=[true] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 hash(insert/my.workphone.ca-call_return/5559998585/5554321212) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 hash(insert/my.workphone.ca-last_dial_ext/5559998585/5f780b7d-593b-4524-a6c5-1767d3f6a047) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 set(called_party_call_group=) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.462242 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [called_party_call_group]=[UNDEF] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 hash(insert/my.workphone.ca-last_dial//5f780b7d-593b-4524-a6c5-1767d3f6a047) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 set(api_hangup_hook=lua app.lua hangup) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.462242 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [api_hangup_hook]=[lua app.lua hangup] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 export(domain_name=my.workphone.ca) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.462242 [DEBUG] switch_channel.c:1296 EXPORT (export_vars) [domain_name]=[my.workphone.ca] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 bridge(user/5559998585@my.workphone.ca) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.462242 [DEBUG] switch_channel.c:1250 sofia/external/15554321212@96.88.69.88 EXPORTING[export_vars] [dialed_extension]=[5559998585] to event 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.462242 [DEBUG] switch_channel.c:1250 sofia/external/15554321212@96.88.69.88 EXPORTING[export_vars] [origination_callee_id_name]=[5559998585] to event 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.462242 [DEBUG] switch_channel.c:1250 sofia/external/15554321212@96.88.69.88 EXPORTING[export_vars] [rtp_secure_media]=[true] to event 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.462242 [DEBUG] switch_channel.c:1250 sofia/external/15554321212@96.88.69.88 EXPORTING[export_vars] [domain_name]=[my.workphone.ca] to event 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.462242 [DEBUG] switch_ivr_originate.c:2138 Parsing global variables 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.462242 [DEBUG] switch_channel.c:1250 sofia/external/15554321212@96.88.69.88 EXPORTING[export_vars] [dialed_extension]=[5559998585] to event 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.462242 [DEBUG] switch_channel.c:1250 sofia/external/15554321212@96.88.69.88 EXPORTING[export_vars] [origination_callee_id_name]=[5559998585] to event 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.462242 [DEBUG] switch_channel.c:1250 sofia/external/15554321212@96.88.69.88 EXPORTING[export_vars] [rtp_secure_media]=[true] to event 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.462242 [DEBUG] switch_channel.c:1250 sofia/external/15554321212@96.88.69.88 EXPORTING[export_vars] [domain_name]=[my.workphone.ca] to event 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:24.462242 [DEBUG] switch_ivr_originate.c:2138 Parsing global variables 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:24.462242 [NOTICE] switch_channel.c:1104 New Channel sofia/external/5059687272 [8d261e38-5336-4cb2-86ae-cc3adda922bc] 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:24.462242 [DEBUG] mod_sofia.c:4808 (sofia/external/5059687272) State Change CS_NEW -> CS_INIT 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:24.462242 [DEBUG] switch_core_state_machine.c:584 (sofia/external/5059687272) Running State Change CS_INIT (Cur 2 Tot 491) 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:24.462242 [DEBUG] switch_core_state_machine.c:627 (sofia/external/5059687272) State INIT 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:24.462242 [DEBUG] mod_sofia.c:90 sofia/external/5059687272 SOFIA INIT 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:24.462242 [DEBUG] switch_core_media.c:1117 Set Local audio crypto Key [1 AEAD_AES_256_GCM_8 inline:cnrJju17yxlZYgnZugO8UAGCaa0cXx5baerfKD49DjHwPpAP2tugcgUYbeE] 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:24.462242 [DEBUG] switch_core_media.c:1117 Set Local video crypto Key [1 AEAD_AES_256_GCM_8 inline:AGUZbaHu0LK8Kpyh0nXps+NnQAd5kvOd9NfdCV2uDSJmZTzWCpt6poijZE8] 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:24.462242 [DEBUG] switch_core_media.c:1117 Set Local audio crypto Key [2 AEAD_AES_128_GCM_8 inline:K/YZfxVPgqaxZw/JJJ1fbRLnv5TeQcrZEUWbLQ] 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:24.462242 [DEBUG] switch_core_media.c:1117 Set Local video crypto Key [2 AEAD_AES_128_GCM_8 inline:PT8Kl1K6yycktX1WPCiTKcvgn572SXxOPAvqkw] 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:24.462242 [DEBUG] switch_core_media.c:1117 Set Local audio crypto Key [3 AES_CM_256_HMAC_SHA1_80 inline:uURCYfja5h7cJo7Bw6R7/gYstjbrwvJ4un2EPlfYV+ehdso5HUB98KnwkCtxIw] 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:24.462242 [DEBUG] switch_core_media.c:1117 Set Local video crypto Key [3 AES_CM_256_HMAC_SHA1_80 inline:y5SNcyJoeD2prWR7qbCADdi3IMu5G7GfEz6Ykab2WM4Gf3cv4odMeh2mu7Q7Wg] 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:24.462242 [DEBUG] switch_core_media.c:1117 Set Local audio crypto Key [4 AES_CM_192_HMAC_SHA1_80 inline:phIAW/y9o/c0SNkfk74heIeKiGprL3jdcKHT72ueqie/ZGZTzqQ] 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:24.462242 [DEBUG] switch_core_media.c:1117 Set Local video crypto Key [4 AES_CM_192_HMAC_SHA1_80 inline:mm4T16quTAHPBujHiBmWFXWHAG0G8Q96P3H8u3hAJ0rQYPdzeZg] 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:24.462242 [DEBUG] switch_core_media.c:1117 Set Local audio crypto Key [5 AES_CM_128_HMAC_SHA1_80 inline:6ouYLUv2xi0oSSREjk6CB0evuc7Xngr44AS52Pia] 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:24.462242 [DEBUG] switch_core_media.c:1117 Set Local video crypto Key [5 AES_CM_128_HMAC_SHA1_80 inline:yGLmDE4PYHAAE9zTm08uyQyDCL0kygSR7+mhOnK/] 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:24.462242 [DEBUG] switch_core_media.c:1117 Set Local audio crypto Key [6 AES_CM_256_HMAC_SHA1_32 inline:EdDAaFCuIRRT1Fv6ePy6wpzjsNnmtOr0CgnnkUwVne0kBnhnURNgpWn4HuV9AA] 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:24.462242 [DEBUG] switch_core_media.c:1117 Set Local video crypto Key [6 AES_CM_256_HMAC_SHA1_32 inline:dV1huVnyNb4y0vdqrwvnseusnOQ6lUjgQCIMhjxC865u5JeE8IMlIVrfLt0HWw] 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:24.462242 [DEBUG] switch_core_media.c:1117 Set Local audio crypto Key [7 AES_CM_192_HMAC_SHA1_32 inline:Dg/fmqQadogq4IcyZq2qQhyceE4cSZ2oSBhHWgTf3MF6JWw5+E8] 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:24.462242 [DEBUG] switch_core_media.c:1117 Set Local video crypto Key [7 AES_CM_192_HMAC_SHA1_32 inline:S8xXAKkPjoQB0B6XdnBWnpv+DtIMrHnaRS0dq8ES+EKglQB8jng] 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:24.462242 [DEBUG] switch_core_media.c:1117 Set Local audio crypto Key [8 AES_CM_128_HMAC_SHA1_32 inline:U4OfN4hFzMcuENkGI0mnprstFBoFc7qyxwFwW4rN] 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:24.462242 [DEBUG] switch_core_media.c:1117 Set Local video crypto Key [8 AES_CM_128_HMAC_SHA1_32 inline:DNfUuQi/875h4WmfUE9IwOTIJe3HiUSZIRW8ZgFw] 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:24.462242 [DEBUG] switch_core_media.c:1117 Set Local audio crypto Key [9 AES_CM_128_NULL_AUTH inline:JZ3OHTMG6nPCZh1iGFSuagXZQjwFKRwneA0xBgKZ] 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:24.462242 [DEBUG] switch_core_media.c:1117 Set Local video crypto Key [9 AES_CM_128_NULL_AUTH inline:56Ij9UYK+nPghSFjgTpyhVec6ZBFtJS+HMezgkrH] 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:24.462242 [DEBUG] sofia_glue.c:1283 sofia/external/5059687272 sending invite version: 1.6.14 -23-e460bf8 64bit 
8d261e38-5336-4cb2-86ae-cc3adda922bc Local SDP: 
8d261e38-5336-4cb2-86ae-cc3adda922bc v=0 
8d261e38-5336-4cb2-86ae-cc3adda922bc o=FreeSWITCH 1496415216 1496415217 IN IP4 111.111.111.111 
8d261e38-5336-4cb2-86ae-cc3adda922bc s=FreeSWITCH 
8d261e38-5336-4cb2-86ae-cc3adda922bc c=IN IP4 111.111.111.111 
8d261e38-5336-4cb2-86ae-cc3adda922bc t=0 0 
8d261e38-5336-4cb2-86ae-cc3adda922bc m=audio 20808 RTP/SAVP 0 8 3 101 13 
8d261e38-5336-4cb2-86ae-cc3adda922bc a=rtpmap:0 PCMU/8000 
8d261e38-5336-4cb2-86ae-cc3adda922bc a=rtpmap:8 PCMA/8000 
8d261e38-5336-4cb2-86ae-cc3adda922bc a=rtpmap:3 GSM/8000 
8d261e38-5336-4cb2-86ae-cc3adda922bc a=rtpmap:101 telephone-event/8000 
8d261e38-5336-4cb2-86ae-cc3adda922bc a=fmtp:101 0-16 
8d261e38-5336-4cb2-86ae-cc3adda922bc a=crypto:1 AEAD_AES_256_GCM_8 inline:cnrJju17yxlZYgnZugO8UAGCaa0cXx5baerfKD49DjHwPpAP2tugcgUYbeE 
8d261e38-5336-4cb2-86ae-cc3adda922bc a=crypto:2 AEAD_AES_128_GCM_8 inline:K/YZfxVPgqaxZw/JJJ1fbRLnv5TeQcrZEUWbLQ 
8d261e38-5336-4cb2-86ae-cc3adda922bc a=crypto:3 AES_CM_256_HMAC_SHA1_80 inline:uURCYfja5h7cJo7Bw6R7/gYstjbrwvJ4un2EPlfYV+ehdso5HUB98KnwkCtxIw 
8d261e38-5336-4cb2-86ae-cc3adda922bc a=crypto:4 AES_CM_192_HMAC_SHA1_80 inline:phIAW/y9o/c0SNkfk74heIeKiGprL3jdcKHT72ueqie/ZGZTzqQ 
8d261e38-5336-4cb2-86ae-cc3adda922bc a=crypto:5 AES_CM_128_HMAC_SHA1_80 inline:6ouYLUv2xi0oSSREjk6CB0evuc7Xngr44AS52Pia 
8d261e38-5336-4cb2-86ae-cc3adda922bc a=crypto:6 AES_CM_256_HMAC_SHA1_32 inline:EdDAaFCuIRRT1Fv6ePy6wpzjsNnmtOr0CgnnkUwVne0kBnhnURNgpWn4HuV9AA 
8d261e38-5336-4cb2-86ae-cc3adda922bc a=crypto:7 AES_CM_192_HMAC_SHA1_32 inline:Dg/fmqQadogq4IcyZq2qQhyceE4cSZ2oSBhHWgTf3MF6JWw5+E8 
8d261e38-5336-4cb2-86ae-cc3adda922bc a=crypto:8 AES_CM_128_HMAC_SHA1_32 inline:U4OfN4hFzMcuENkGI0mnprstFBoFc7qyxwFwW4rN 
8d261e38-5336-4cb2-86ae-cc3adda922bc a=crypto:9 AES_CM_128_NULL_AUTH inline:JZ3OHTMG6nPCZh1iGFSuagXZQjwFKRwneA0xBgKZ 
8d261e38-5336-4cb2-86ae-cc3adda922bc a=rtpmap:13 CN/8000 
8d261e38-5336-4cb2-86ae-cc3adda922bc a=ptime:20 
8d261e38-5336-4cb2-86ae-cc3adda922bc a=sendrecv 
8d261e38-5336-4cb2-86ae-cc3adda922bc 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:24.462242 [DEBUG] switch_core_state_machine.c:40 sofia/external/5059687272 Standard INIT 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:24.462242 [DEBUG] switch_core_state_machine.c:48 (sofia/external/5059687272) State Change CS_INIT -> CS_ROUTING 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:24.462242 [DEBUG] switch_core_state_machine.c:627 (sofia/external/5059687272) State INIT going to sleep 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:24.462242 [DEBUG] switch_core_state_machine.c:584 (sofia/external/5059687272) Running State Change CS_ROUTING (Cur 2 Tot 491) 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:24.462242 [DEBUG] sofia.c:7041 Channel sofia/external/5059687272 entering state [calling][0] 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:24.462242 [DEBUG] switch_core_state_machine.c:643 (sofia/external/5059687272) State ROUTING 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:24.462242 [DEBUG] mod_sofia.c:143 sofia/external/5059687272 SOFIA ROUTING 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:24.462242 [DEBUG] switch_ivr_originate.c:67 (sofia/external/5059687272) State Change CS_ROUTING -> CS_CONSUME_MEDIA 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:24.462242 [DEBUG] switch_core_state_machine.c:643 (sofia/external/5059687272) State ROUTING going to sleep 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:24.462242 [DEBUG] switch_core_state_machine.c:584 (sofia/external/5059687272) Running State Change CS_CONSUME_MEDIA (Cur 2 Tot 491) 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:24.462242 [DEBUG] switch_core_state_machine.c:662 (sofia/external/5059687272) State CONSUME_MEDIA 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:24.462242 [DEBUG] switch_core_state_machine.c:662 (sofia/external/5059687272) State CONSUME_MEDIA going to sleep 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:26.652252 [DEBUG] sofia.c:7041 Channel sofia/external/5059687272 entering state [proceeding][183] 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:26.652252 [DEBUG] sofia.c:7051 Remote SDP: 
8d261e38-5336-4cb2-86ae-cc3adda922bc v=0 
8d261e38-5336-4cb2-86ae-cc3adda922bc o=Sansay-VSXi 188 1 IN IP4 64.136.174.30 
8d261e38-5336-4cb2-86ae-cc3adda922bc s=Session Controller 
8d261e38-5336-4cb2-86ae-cc3adda922bc c=IN IP4 216.221.154.151 
8d261e38-5336-4cb2-86ae-cc3adda922bc t=0 0 
8d261e38-5336-4cb2-86ae-cc3adda922bc m=audio 46070 RTP/AVP 0 101 
8d261e38-5336-4cb2-86ae-cc3adda922bc a=rtpmap:0 PCMU/8000 
8d261e38-5336-4cb2-86ae-cc3adda922bc a=rtpmap:101 telephone-event/8000 
8d261e38-5336-4cb2-86ae-cc3adda922bc a=fmtp:101 0-15 
8d261e38-5336-4cb2-86ae-cc3adda922bc a=ptime:20 
8d261e38-5336-4cb2-86ae-cc3adda922bc 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:26.652252 [DEBUG] switch_core_media.c:4378 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1] 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:26.652252 [DEBUG] switch_core_media.c:4433 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:26.652252 [DEBUG] switch_core_media.c:4378 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1] 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:26.652252 [DEBUG] switch_core_media.c:4378 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[GSM:3:8000:20:13200:1] 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:26.652252 [DEBUG] switch_core_media.c:4294 Set telephone-event payload to 101@8000 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:26.652252 [WARNING] switch_core_media.c:4451 Crypto not negotiated but required. 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:26.652252 [DEBUG] switch_core_media.c:4696 sofia/external/5059687272 Set 2833 dtmf send payload to 101 recv payload to 101 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:26.652252 [NOTICE] sofia.c:7230 Hangup sofia/external/5059687272 [CS_CONSUME_MEDIA] [INCOMPATIBLE_DESTINATION] 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:26.652252 [DEBUG] switch_core_state_machine.c:584 (sofia/external/5059687272) Running State Change CS_HANGUP (Cur 2 Tot 491) 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:26.652252 [DEBUG] switch_core_state_machine.c:850 (sofia/external/5059687272) Callstate Change DOWN -> HANGUP 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:26.652252 [DEBUG] switch_core_state_machine.c:852 (sofia/external/5059687272) State HANGUP 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:26.652252 [DEBUG] mod_sofia.c:438 Channel sofia/external/5059687272 hanging up, cause: INCOMPATIBLE_DESTINATION 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:26.652252 [DEBUG] mod_sofia.c:502 Sending CANCEL to sofia/external/5059687272 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:26.652252 [DEBUG] switch_core_state_machine.c:60 sofia/external/5059687272 Standard HANGUP, cause: INCOMPATIBLE_DESTINATION 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:26.652252 [DEBUG] switch_core_state_machine.c:852 (sofia/external/5059687272) State HANGUP going to sleep 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:26.652252 [DEBUG] switch_core_state_machine.c:619 (sofia/external/5059687272) State Change CS_HANGUP -> CS_REPORTING 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:26.652252 [DEBUG] switch_core_state_machine.c:584 (sofia/external/5059687272) Running State Change CS_REPORTING (Cur 2 Tot 491) 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:26.652252 [DEBUG] switch_core_state_machine.c:938 (sofia/external/5059687272) State REPORTING 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:26.652252 [DEBUG] switch_core_state_machine.c:174 sofia/external/5059687272 Standard REPORTING, cause: INCOMPATIBLE_DESTINATION 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:26.652252 [DEBUG] switch_core_state_machine.c:938 (sofia/external/5059687272) State REPORTING going to sleep 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:26.652252 [DEBUG] switch_core_state_machine.c:610 (sofia/external/5059687272) State Change CS_REPORTING -> CS_DESTROY 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:26.652252 [DEBUG] switch_core_session.c:1647 Session 491 (sofia/external/5059687272) Locked, Waiting on external entities 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:26.672244 [DEBUG] switch_ivr_originate.c:3829 Originate Resulted in Error Cause: 88 [INCOMPATIBLE_DESTINATION] 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:26.672244 [NOTICE] switch_core_session.c:1665 Session 491 (sofia/external/5059687272) Ended 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:26.672244 [NOTICE] switch_core_session.c:1669 Close Channel sofia/external/5059687272 [CS_DESTROY] 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:26.672244 [DEBUG] switch_core_state_machine.c:741 (sofia/external/5059687272) Running State Change CS_DESTROY (Cur 1 Tot 491) 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:26.672244 [DEBUG] switch_core_state_machine.c:751 (sofia/external/5059687272) State DESTROY 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:26.672244 [DEBUG] mod_sofia.c:343 sofia/external/5059687272 SOFIA DESTROY 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:26.672244 [DEBUG] switch_core_state_machine.c:181 sofia/external/5059687272 Standard DESTROY 
8d261e38-5336-4cb2-86ae-cc3adda922bc 2017-06-02 20:40:26.672244 [DEBUG] switch_core_state_machine.c:751 (sofia/external/5059687272) State DESTROY going to sleep 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:26.672244 [NOTICE] switch_ivr_originate.c:2841 Cannot create outgoing channel of type [user] cause: [INCOMPATIBLE_DESTINATION] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:26.672244 [DEBUG] switch_ivr_originate.c:3829 Originate Resulted in Error Cause: 88 [INCOMPATIBLE_DESTINATION] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:26.672244 [INFO] mod_dptools.c:3409 Originate Failed. Cause: INCOMPATIBLE_DESTINATION 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 lua(app.lua failure_handler) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:26.672244 [DEBUG] switch_cpp.cpp:1113 sofia/external/15554321212@96.88.69.88 destroy/unlink session from object 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 answer() 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:26.672244 [DEBUG] switch_core_media.c:6703 AUDIO RTP [sofia/external/15554321212@96.88.69.88] 111.111.111.111 port 18402 -> 207.112.37.25 port 36436 codec: 0 ms: 20 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:26.672244 [DEBUG] switch_rtp.c:3878 Starting timer [soft] 160 bytes per 20ms 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:26.672244 [DEBUG] switch_core_media.c:7009 sofia/external/15554321212@96.88.69.88 Set 2833 dtmf send payload to 101 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:26.672244 [DEBUG] switch_core_media.c:7016 sofia/external/15554321212@96.88.69.88 Set 2833 dtmf receive payload to 101 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:26.672244 [DEBUG] switch_core_media.c:7039 sofia/external/15554321212@96.88.69.88 Set rtp dtmf delay to 40 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:26.672244 [DEBUG] mod_sofia.c:849 Local SDP sofia/external/15554321212@96.88.69.88: 
5f780b7d-593b-4524-a6c5-1767d3f6a047 v=0 
5f780b7d-593b-4524-a6c5-1767d3f6a047 o=FreeSWITCH 1496417624 1496417625 IN IP4 111.111.111.111 
5f780b7d-593b-4524-a6c5-1767d3f6a047 s=FreeSWITCH 
5f780b7d-593b-4524-a6c5-1767d3f6a047 c=IN IP4 111.111.111.111 
5f780b7d-593b-4524-a6c5-1767d3f6a047 t=0 0 
5f780b7d-593b-4524-a6c5-1767d3f6a047 m=audio 18402 RTP/AVP 0 101 
5f780b7d-593b-4524-a6c5-1767d3f6a047 a=rtpmap:0 PCMU/8000 
5f780b7d-593b-4524-a6c5-1767d3f6a047 a=rtpmap:101 telephone-event/8000 
5f780b7d-593b-4524-a6c5-1767d3f6a047 a=fmtp:101 0-16 
5f780b7d-593b-4524-a6c5-1767d3f6a047 a=ptime:20 
5f780b7d-593b-4524-a6c5-1767d3f6a047 a=sendrecv 
5f780b7d-593b-4524-a6c5-1767d3f6a047 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:26.672244 [NOTICE] mod_dptools.c:1309 Channel [sofia/external/15554321212@96.88.69.88] has been answered 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:26.672244 [DEBUG] switch_channel.c:3772 (sofia/external/15554321212@96.88.69.88) Callstate Change RINGING -> ACTIVE 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:26.672244 [DEBUG] sofia.c:7041 Channel sofia/external/15554321212@96.88.69.88 entering state [completed][200] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 sleep(1000) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:26.712243 [DEBUG] sofia.c:7041 Channel sofia/external/15554321212@96.88.69.88 entering state [ready][200] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:26.832255 [DEBUG] switch_rtp.c:6994 Correct audio ip/port confirmed. 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 set(voicemail_action=save) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:27.672261 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [voicemail_action]=[save] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 set(voicemail_id=5559998585) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:27.672261 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [voicemail_id]=[5559998585] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 set(voicemail_profile=default) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:27.672261 [DEBUG] mod_dptools.c:1527 SET sofia/external/15554321212@96.88.69.88 [voicemail_profile]=[default] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 lua(app.lua voicemail) 
2017-06-02 20:40:27.672261 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x205f830 Connected. 
2017-06-02 20:40:27.682244 [NOTICE] switch_cpp.cpp:1366 [voicemail] voicemail_dir: /var/lib/freeswitch/storage/voicemail/default/my.workphone.ca 
2017-06-02 20:40:27.682244 [NOTICE] switch_cpp.cpp:1366 [settings] SQL: SELECT * FROM v_default_settings WHERE default_setting_enabled = 'true' AND default_setting_category is not null AND default_setting_subcategory is not null AND default_setting_name is not null AND default_setting_value is not null ORDER BY default_setting_category, default_setting_subcategory ASC 
2017-06-02 20:40:27.682244 [NOTICE] switch_cpp.cpp:1366 [settings] SQL: SELECT * FROM v_domain_settings WHERE domain_uuid = :domain_uuid AND domain_setting_enabled = 'true' AND domain_setting_category is not null AND domain_setting_subcategory is not null AND domain_setting_name is not null AND domain_setting_value is not null ORDER BY domain_setting_category, domain_setting_subcategory ASC ; params: {"domain_uuid":"53dfbf50-263f-4208-9540-c2abd247b89b"} 
2017-06-02 20:40:27.682244 [NOTICE] switch_cpp.cpp:1366 [voicemail] SQL: SELECT * FROM v_voicemails 
WHERE domain_uuid = :domain_uuid 
AND voicemail_id = :voicemail_id 
AND voicemail_enabled = 'true' ; params:{"domain_uuid":"53dfbf50-263f-4208-9540-c2abd247b89b","voicemail_id":"5559998585"} 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 unbind_meta_app() 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:27.682244 [INFO] switch_ivr_async.c:4065 UnBound A-Leg: ALL 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:27.682244 [DEBUG] switch_cpp.cpp:746 CoreSession::setVariable(playback_terminators, #) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 EXECUTE sofia/external/15554321212@96.88.69.88 playback(silence_stream://200) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:27.692244 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:27.872252 [DEBUG] switch_ivr_play_say.c:1942 done playing file silence_stream://200 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:27.872252 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:29.232261 [DEBUG] switch_ivr_play_say.c:1942 done playing file /usr/share/freeswitch/sounds/en/us/callie/voicemail/vm-person.wav 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:29.232261 [DEBUG] switch_ivr.c:3565 No language specified - Using [en] 
2017-06-02 20:40:29.232261 [DEBUG] freeswitch_lua.cpp:365 DBH handle 0x217b6d0 Connected. 
2017-06-02 20:40:29.232261 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x217b6d0 released. 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:29.242244 [DEBUG] switch_ivr_play_say.c:1498 Codec Activated L16@8000hz 1 channels 20ms 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:29.972261 [NOTICE] sofia.c:1012 Hangup sofia/external/15554321212@96.88.69.88 [CS_EXECUTE] [NORMAL_CLEARING] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:29.972261 [DEBUG] mod_hash.c:297 Usage for my.workphone.ca_5559998585 is now 0 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:29.972261 [DEBUG] switch_ivr_play_say.c:1942 done playing file file_string://digits/5.wav!digits/0.wav!digits/6.wav!digits/3.wav!digits/0.wav!digits/0.wav!digits/7.wav!digits/1.wav!digits/7.wav!digits/0.wav 
2017-06-02 20:40:29.972261 [NOTICE] switch_cpp.cpp:1366 [voicemail] transcribe_enabled: true 
2017-06-02 20:40:29.982840 [NOTICE] switch_cpp.cpp:1366 [voicemail] transcribe_provider: microsoft 
2017-06-02 20:40:29.982840 [NOTICE] switch_cpp.cpp:1366 [voicemail] transcribe_language: en-US 
2017-06-02 20:40:30.362252 [NOTICE] switch_cpp.cpp:1366 [voicemail] CMD: curl -X POST "https://api.cognitive.microsoft.com/sts/v1.0/issueToken" -H "Content-type: application/x-www-form-urlencoded" -H "Content-Length: 0" -H "Ocp-Apim-Subscription-Key: 4fdabe8e06f748fbab42c5f471241061" 
2017-06-02 20:40:30.362252 [NOTICE] switch_cpp.cpp:1366 [voicemail] RESULT: eyJ0eXAiOiJKV1QiLCJhbGciOiJIUzI1NiJ9.eyJzY29wZSI6Imh0dHBzOi8vc3BlZWNoLnBsYXRmb3JtLmJpbmcuY29tIiwic3Vic2NyaXB0aW9uLWlkIjoiOWQ5NTM1OGM4Nzg4NDBjZGJlZjQzNDhiNTIyMzNmNjYiLCJwcm9kdWN0LWlkIjoiQmluZy5TcGVlY2guUHJldmlldyIsImNvZ25pdGl2ZS1zZXJ2aWNlcy1lbmRwb2ludCI6Imh0dHBzOi8vYXBpLmNvZ25pdGl2ZS5taWNyb3NvZnQuY29tL2ludGVybmFsL3YxLjAvIiwiYXp1cmUtcmVzb3VyY2UtaWQiOiIiLCJpc3MiOiJ1cm46bXMuY29nbml0aXZlc2VydmljZXMiLCJhdWQiOiJ1cm46bXMuc3BlZWNoIiwiZXhwIjoxNDk2NDM2MzkwfQ.hhfWeHuXyvd2i5w0ycILPDqjTAvuLQ9xLellI9N7b-0 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:30.432251 [ERR] mod_lua.cpp:203 ...eswitch/scripts/resources/functions/lunajson/decoder.lua:28: parse error at 2: invalid value 
5f780b7d-593b-4524-a6c5-1767d3f6a047 stack traceback: 
5f780b7d-593b-4524-a6c5-1767d3f6a047 [C]: in function error 
5f780b7d-593b-4524-a6c5-1767d3f6a047 ...eswitch/scripts/resources/functions/lunajson/decoder.lua:28: in function decodeerror 
5f780b7d-593b-4524-a6c5-1767d3f6a047 ...eswitch/scripts/resources/functions/lunajson/decoder.lua:35: in function f 
5f780b7d-593b-4524-a6c5-1767d3f6a047 ...eswitch/scripts/resources/functions/lunajson/decoder.lua:348: in function decode 
5f780b7d-593b-4524-a6c5-1767d3f6a047 ...pts/app/voicemail/resources/functions/record_message.lua:68: in function transcribe 
5f780b7d-593b-4524-a6c5-1767d3f6a047 ...pts/app/voicemail/resources/functions/record_message.lua:252: in function record_message 
5f780b7d-593b-4524-a6c5-1767d3f6a047 /usr/share/freeswitch/scripts/app/voicemail/index.lua:354: in main chunk 
5f780b7d-593b-4524-a6c5-1767d3f6a047 /usr/share/freeswitch/scripts/app.lua:48: in main chunk 
2017-06-02 20:40:30.432251 [DEBUG] freeswitch_lua.cpp:382 DBH handle 0x205f830 released. 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:30.432251 [DEBUG] switch_cpp.cpp:1113 sofia/external/15554321212@96.88.69.88 destroy/unlink session from object 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:30.432251 [DEBUG] switch_core_session.c:2797 sofia/external/15554321212@96.88.69.88 skip receive message [APPLICATION_EXEC_COMPLETE] (channel is hungup already) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:30.432251 [DEBUG] switch_core_state_machine.c:650 (sofia/external/15554321212@96.88.69.88) State EXECUTE going to sleep 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:30.432251 [DEBUG] switch_core_state_machine.c:584 (sofia/external/15554321212@96.88.69.88) Running State Change CS_HANGUP (Cur 1 Tot 491) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:30.432251 [DEBUG] switch_core_state_machine.c:850 (sofia/external/15554321212@96.88.69.88) Callstate Change ACTIVE -> HANGUP 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:30.432251 [DEBUG] switch_core_state_machine.c:852 (sofia/external/15554321212@96.88.69.88) State HANGUP 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:30.432251 [DEBUG] mod_sofia.c:438 Channel sofia/external/15554321212@96.88.69.88 hanging up, cause: NORMAL_CLEARING 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:30.432251 [DEBUG] switch_core_state_machine.c:60 sofia/external/15554321212@96.88.69.88 Standard HANGUP, cause: NORMAL_CLEARING 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:30.432251 [DEBUG] switch_core_state_machine.c:852 (sofia/external/15554321212@96.88.69.88) State HANGUP going to sleep 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:30.432251 [DEBUG] switch_core_state_machine.c:783 Hangup Command with no Session lua(app.lua hangup): 
5f780b7d-593b-4524-a6c5-1767d3f6a047 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:30.432251 [DEBUG] switch_core_state_machine.c:619 (sofia/external/15554321212@96.88.69.88) State Change CS_HANGUP -> CS_REPORTING 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:30.432251 [DEBUG] switch_core_state_machine.c:584 (sofia/external/15554321212@96.88.69.88) Running State Change CS_REPORTING (Cur 1 Tot 491) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:30.432251 [DEBUG] switch_core_state_machine.c:938 (sofia/external/15554321212@96.88.69.88) State REPORTING 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:30.532252 [DEBUG] switch_core_state_machine.c:174 sofia/external/15554321212@96.88.69.88 Standard REPORTING, cause: NORMAL_CLEARING 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:30.532252 [DEBUG] switch_core_state_machine.c:938 (sofia/external/15554321212@96.88.69.88) State REPORTING going to sleep 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:30.532252 [DEBUG] switch_core_state_machine.c:610 (sofia/external/15554321212@96.88.69.88) State Change CS_REPORTING -> CS_DESTROY 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:30.532252 [DEBUG] switch_core_session.c:1647 Session 490 (sofia/external/15554321212@96.88.69.88) Locked, Waiting on external entities 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:30.532252 [NOTICE] switch_core_session.c:1665 Session 490 (sofia/external/15554321212@96.88.69.88) Ended 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:30.532252 [NOTICE] switch_core_session.c:1669 Close Channel sofia/external/15554321212@96.88.69.88 [CS_DESTROY] 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:30.532252 [DEBUG] switch_core_state_machine.c:741 (sofia/external/15554321212@96.88.69.88) Running State Change CS_DESTROY (Cur 0 Tot 491) 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:30.532252 [DEBUG] switch_core_state_machine.c:751 (sofia/external/15554321212@96.88.69.88) State DESTROY 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:30.532252 [DEBUG] mod_sofia.c:343 sofia/external/15554321212@96.88.69.88 SOFIA DESTROY 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:30.532252 [DEBUG] switch_core_state_machine.c:181 sofia/external/15554321212@96.88.69.88 Standard DESTROY 
5f780b7d-593b-4524-a6c5-1767d3f6a047 2017-06-02 20:40:30.532252 [DEBUG] switch_core_state_machine.c:751 (sofia/external/15554321212@96.88.69.88) State DESTROY going to sleep