dialing park or *97 not working on one single tenant

Status
Not open for further replies.

PJPMontreal

Member
Feb 28, 2020
59
2
8
57
Ok, this is very strange behavior that is happening only in one of my multiples tenants.
When dialing park *5901 or *97 (voicemail) the call drops.
Other options like *88 for paging or dialing direct the extension to access the voicemail works fine as well all other outgoing calls.
Is not related to the devices dialplan or anything because is happening on all extensions even when using softphone or SPA525G.

I insist that this is happening on one single tenant out of many working fine.
Any idea where to start looking????
 

ad5ou

Active Member
Jun 12, 2018
884
197
43
Hard to guess without logs of the failed calls.
Start with checking to see if those dial plans exist in the domain having issues.
 

PJPMontreal

Member
Feb 28, 2020
59
2
8
57
Hard to guess without logs of the failed calls.
Start with checking to see if those dial plans exist in the domain having issues.
Thanks Ad5ou, yes, dial plan compared to other subdomains (tenants) and is exactly the same.
The logs when trying to dial *97 is really long to post it here but I could try...just wanna see if somebody had this issue and maybe knows where else to look for a fix.
 

PJPMontreal

Member
Feb 28, 2020
59
2
8
57
Hard to guess without logs of the failed calls.
Start with checking to see if those dial plans exist in the domain having issues.
Hi ad5ou, here the log when dialing *97 to check the voicemail from extension 190, please help me find the problem why the call drops immediately. Thanks!!!!


280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.880726 [NOTICE] switch_channel.c:1118 New Channel sofia/internal/190@mydomain.com [280e7467-a44c-48f1-b8c4-2d9b4382d56a]

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.880726 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/190@mydomain.com) Running State Change CS_NEW (Cur 1 Tot 1147254)

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.880726 [DEBUG] sofia.c:10255 sofia/internal/190@mydomain.com receiving invite from 184.1160.160.555:5060 version: 1.10.2 -release-14-f7bdd3845a 64bit

2022-03-31 02:06:27.880726 [DEBUG] sofia.c:10349 verifying acl "domains" for ip/port 184.1160.160.555:0.

2022-03-31 02:06:27.880726 [DEBUG] sofia.c:2434 detaching session 280e7467-a44c-48f1-b8c4-2d9b4382d56a

2022-03-31 02:06:27.880726 [WARNING] sofia_reg.c:1793 SIP auth challenge (INVITE) on sofia profile 'internal' for [*97@mydomain.com] from ip 184.1160.160.555

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.880726 [DEBUG] switch_core_state_machine.c:604 (sofia/internal/190@mydomain.com) State NEW

2022-03-31 02:06:27.940749 [DEBUG] sofia.c:2544 Re-attaching to session 280e7467-a44c-48f1-b8c4-2d9b4382d56a

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.940749 [DEBUG] sofia.c:10255 sofia/internal/190@mydomain.com receiving invite from 184.1160.160.555:5060 version: 1.10.2 -release-14-f7bdd3845a 64bit

2022-03-31 02:06:27.940749 [DEBUG] sofia.c:10349 verifying acl "domains" for ip/port 184.1160.160.555:0.

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.960673 [DEBUG] sofia.c:11452 Setting NAT mode based on via received

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.960673 [DEBUG] sofia.c:7301 Channel sofia/internal/190@mydomain.com entering state [received][100]

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.960673 [DEBUG] sofia.c:7311 Remote SDP:

280e7467-a44c-48f1-b8c4-2d9b4382d56a v=0

280e7467-a44c-48f1-b8c4-2d9b4382d56a o=- 1648692387 1 IN IP4 184.1160.160.555

280e7467-a44c-48f1-b8c4-2d9b4382d56a s=ps

280e7467-a44c-48f1-b8c4-2d9b4382d56a c=IN IP4 184.1160.160.555

280e7467-a44c-48f1-b8c4-2d9b4382d56a t=0 0

280e7467-a44c-48f1-b8c4-2d9b4382d56a m=audio 10004 RTP/AVP 105 18 8 0 101

280e7467-a44c-48f1-b8c4-2d9b4382d56a a=rtpmap:105 opus/48000/2

280e7467-a44c-48f1-b8c4-2d9b4382d56a a=fmtp:105 useinbandfec=1

280e7467-a44c-48f1-b8c4-2d9b4382d56a a=rtpmap:18 G729/8000

280e7467-a44c-48f1-b8c4-2d9b4382d56a a=fmtp:18 annexb=no

280e7467-a44c-48f1-b8c4-2d9b4382d56a a=rtpmap:8 PCMA/8000

280e7467-a44c-48f1-b8c4-2d9b4382d56a a=rtpmap:0 PCMU/8000

280e7467-a44c-48f1-b8c4-2d9b4382d56a a=rtpmap:101 telephone-event/8000

280e7467-a44c-48f1-b8c4-2d9b4382d56a a=fmtp:101 0-16

280e7467-a44c-48f1-b8c4-2d9b4382d56a a=mid:audio

280e7467-a44c-48f1-b8c4-2d9b4382d56a

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.960673 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [opus:105:48000:20:0:2]/[G722:9:8000:20:64000:1]

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.960673 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [opus:105:48000:20:0:2]/[PCMU:0:8000:20:64000:1]

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.960673 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [opus:105:48000:20:0:2]/[PCMA:8:8000:20:64000:1]

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.960673 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.960673 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.960673 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.960673 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.960673 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.960673 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.960673 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.960673 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.960673 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.960673 [DEBUG] switch_core_media.c:5647 Audio Codec Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.960673 [DEBUG] switch_core_media.c:5592 Audio Codec Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.960673 [DEBUG] switch_core_media.c:5508 Set telephone-event payload to 101@8000

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.960673 [DEBUG] switch_core_media.c:3837 Set Codec sofia/internal/190@mydomain.com PCMA/8000 20 ms 160 samples 64000 bits 1 channels

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.960673 [DEBUG] switch_core_codec.c:111 sofia/internal/190@mydomain.com Original read codec set to PCMA:8

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.960673 [DEBUG] switch_core_media.c:5851 Set telephone-event payload to 101@8000

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.960673 [DEBUG] switch_core_media.c:5909 sofia/internal/190@mydomain.com Set 2833 dtmf send payload to 101 recv payload to 101

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.960673 [DEBUG] sofia.c:7735 (sofia/internal/190@mydomain.com) State Change CS_NEW -> CS_INIT

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.960673 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/190@mydomain.com) Running State Change CS_INIT (Cur 1 Tot 1147254)

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.960673 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/190@mydomain.com) State INIT

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.960673 [DEBUG] mod_sofia.c:93 sofia/internal/190@mydomain.com SOFIA INIT

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.960673 [DEBUG] switch_core_state_machine.c:40 sofia/internal/190@mydomain.com Standard INIT

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.960673 [DEBUG] switch_core_state_machine.c:48 (sofia/internal/190@mydomain.com) State Change CS_INIT -> CS_ROUTING

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.960673 [DEBUG] switch_core_state_machine.c:628 (sofia/internal/190@mydomain.com) State INIT going to sleep

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.960673 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/190@mydomain.com) Running State Change CS_ROUTING (Cur 1 Tot 1147254)

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.960673 [DEBUG] switch_channel.c:2332 (sofia/internal/190@mydomain.com) Callstate Change DOWN -> RINGING

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.960673 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/190@mydomain.com) State ROUTING

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.960673 [DEBUG] mod_sofia.c:154 sofia/internal/190@mydomain.com SOFIA ROUTING

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.960673 [DEBUG] switch_core_state_machine.c:236 sofia/internal/190@mydomain.com Standard ROUTING

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.960673 [INFO] mod_dialplan_xml.c:637 Processing 190 <190>->*97 in context mydomain.com

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com parsing [mydomain.com->user_exists] continue=true

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com Regex (FAIL) [user_exists] ${loopback_leg}() =~ /^B$/ break=never

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com Regex (PASS) [user_exists] () =~ // break=on-false

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com Action set(user_exists=${user_exists id ${destination_number} ${domain_name}}) INLINE

280e7467-a44c-48f1-b8c4-2d9b4382d56a EXECUTE [depth=0] sofia/internal/190@mydomain.com set(user_exists=false)

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.980666 [DEBUG] mod_dptools.c:1672 SET sofia/internal/190@mydomain.com [user_exists]=[false]

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com Action set(from_user_exists=${user_exists id ${sip_from_user} ${sip_from_host}}) INLINE

280e7467-a44c-48f1-b8c4-2d9b4382d56a EXECUTE [depth=0] sofia/internal/190@mydomain.com set(from_user_exists=true)

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.980666 [DEBUG] mod_dptools.c:1672 SET sofia/internal/190@mydomain.com [from_user_exists]=[true]

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com Regex (FAIL) [user_exists] ${user_exists}(false) =~ /^true$/ break=on-false

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com parsing [mydomain.com->global-variables] continue=true

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com Regex (PASS) [global-variables] () =~ // break=on-false

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com Action set(RFC2822_DATE=${strftime(%a, %d %b %Y %T %z)})

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com Action set(timezone=America/New_York) INLINE

280e7467-a44c-48f1-b8c4-2d9b4382d56a EXECUTE [depth=0] sofia/internal/190@mydomain.com set(timezone=America/New_York)

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.980666 [DEBUG] mod_dptools.c:1672 SET sofia/internal/190@mydomain.com [timezone]=[America/New_York]

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com parsing [mydomain.com->is_loopback] continue=true

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com Regex (FAIL) [is_loopback] ${is_follow_me_loopback}() =~ /true/ break=on-false

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com parsing [mydomain.com->Telnx3305.1d10] continue=false

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com Regex (PASS) [Telnx3305.1d10] ${user_exists}(false) =~ /false/ break=on-false

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com Regex (FAIL) [Telnx3305.1d10] destination_number(*97) =~ /^\+?1?(\d{10})$/ break=on-false

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com parsing [mydomain.com->600] continue=

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com Regex (FAIL) [600] destination_number(*97) =~ /^600$/ break=on-false

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com parsing [mydomain.com->Main Intro] continue=false

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com Regex (FAIL) [Main Intro] destination_number(*97) =~ /^200$/ break=on-false

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com parsing [mydomain.com->Main Intro English] continue=false

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com Regex (FAIL) [Main Intro English] destination_number(*97) =~ /^201$/ break=on-false

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com parsing [mydomain.com->page] continue=false

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com Regex (FAIL) [page] destination_number(*97) =~ /^\*88$/ break=on-false

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com parsing [mydomain.com->page-extension] continue=false

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com Regex (FAIL) [page-extension] destination_number(*97) =~ /^\*8(\d{2,7})$/ break=on-false

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com parsing [mydomain.com->intercept-ext-polycom] continue=false

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com Regex (FAIL) [intercept-ext-polycom] destination_number(*97) =~ /^\*97(\d+)$/ break=on-false

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com parsing [mydomain.com->extension-to-voicemail] continue=false

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com Regex (FAIL) [extension-to-voicemail] ${user_exists}(false) =~ /^true$/ break=on-false

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com parsing [mydomain.com->follow-me-destinations] continue=false

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com Regex (FAIL) [follow-me-destinations] ${user_exists}(false) =~ /^true$/ break=on-false

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com parsing [mydomain.com->call-forward-all] continue=

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com Regex (FAIL) [call-forward-all] ${user_exists}(false) =~ /true/ break=on-false

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com parsing [mydomain.com->call-forward-not-registered] continue=

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com Regex (FAIL) [call-forward-not-registered] ${user_exists}(false) =~ /true/ break=on-false

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com parsing [mydomain.com->talking clock date and time] continue=

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com Regex (FAIL) [talking clock date and time] destination_number(*97) =~ /^\*9172$/ break=on-false

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com parsing [mydomain.com->talking clock time] continue=

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com Regex (FAIL) [talking clock time] destination_number(*97) =~ /^\*9170$/ break=on-false

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com parsing [mydomain.com->talking clock date] continue=

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com Regex (FAIL) [talking clock date] destination_number(*97) =~ /^\*9171$/ break=on-false

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com parsing [mydomain.com->local_extension] continue=true

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com Regex (FAIL) [local_extension] ${user_exists}(false) =~ /true/ break=on-false

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com parsing [mydomain.com->voicemail] continue=false

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com Regex (FAIL) [voicemail] ${user_exists}(false) =~ /true/ break=on-false

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.980666 [DEBUG] switch_core_state_machine.c:287 (sofia/internal/190@mydomain.com) State Change CS_ROUTING -> CS_EXECUTE

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.980666 [DEBUG] switch_core_state_machine.c:644 (sofia/internal/190@mydomain.com) State ROUTING going to sleep

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.980666 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/190@mydomain.com) Running State Change CS_EXECUTE (Cur 1 Tot 1147254)

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.980666 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/190@mydomain.com) State EXECUTE

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.980666 [DEBUG] mod_sofia.c:209 sofia/internal/190@mydomain.com SOFIA EXECUTE

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.980666 [DEBUG] switch_core_state_machine.c:329 sofia/internal/190@mydomain.com Standard EXECUTE

280e7467-a44c-48f1-b8c4-2d9b4382d56a EXECUTE [depth=0] sofia/internal/190@mydomain.com set(RFC2822_DATE=Thu, 31 Mar 2022 02:06:27 +0000)

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.980666 [DEBUG] mod_dptools.c:1672 SET sofia/internal/190@mydomain.com [RFC2822_DATE]=[Thu, 31 Mar 2022 02:06:27 +0000]

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.980666 [NOTICE] switch_core_state_machine.c:386 sofia/internal/190@mydomain.com has executed the last dialplan instruction, hanging up.

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.980666 [NOTICE] switch_core_state_machine.c:388 Hangup sofia/internal/190@mydomain.com [CS_EXECUTE] [NORMAL_CLEARING]

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.980666 [DEBUG] switch_core_state_machine.c:651 (sofia/internal/190@mydomain.com) State EXECUTE going to sleep

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.980666 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/190@mydomain.com) Running State Change CS_HANGUP (Cur 1 Tot 1147254)

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.980666 [DEBUG] switch_core_state_machine.c:848 (sofia/internal/190@mydomain.com) Callstate Change RINGING -> HANGUP

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.980666 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/190@mydomain.com) State HANGUP

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.980666 [DEBUG] mod_sofia.c:453 Channel sofia/internal/190@mydomain.com hanging up, cause: NORMAL_CLEARING

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.980666 [DEBUG] mod_sofia.c:598 Responding to INVITE with: 480

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.980666 [DEBUG] switch_core_state_machine.c:60 sofia/internal/190@mydomain.com Standard HANGUP, cause: NORMAL_CLEARING

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.980666 [DEBUG] switch_core_state_machine.c:850 (sofia/internal/190@mydomain.com) State HANGUP going to sleep

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.980666 [DEBUG] switch_core_state_machine.c:620 (sofia/internal/190@mydomain.com) State Change CS_HANGUP -> CS_REPORTING

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.980666 [DEBUG] switch_core_state_machine.c:585 (sofia/internal/190@mydomain.com) Running State Change CS_REPORTING (Cur 1 Tot 1147254)

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:27.980666 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/190@mydomain.com) State REPORTING

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:28.040679 [DEBUG] switch_core_state_machine.c:174 sofia/internal/190@mydomain.com Standard REPORTING, cause: NORMAL_CLEARING

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:28.040679 [DEBUG] switch_core_state_machine.c:936 (sofia/internal/190@mydomain.com) State REPORTING going to sleep

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:28.040679 [DEBUG] switch_core_state_machine.c:611 (sofia/internal/190@mydomain.com) State Change CS_REPORTING -> CS_DESTROY

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:28.040679 [DEBUG] switch_core_session.c:1726 Session 1147254 (sofia/internal/190@mydomain.com) Locked, Waiting on external entities

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:28.040679 [NOTICE] switch_core_session.c:1744 Session 1147254 (sofia/internal/190@mydomain.com) Ended

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:28.040679 [NOTICE] switch_core_session.c:1748 Close Channel sofia/internal/190@mydomain.com [CS_DESTROY]

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:28.040679 [DEBUG] switch_core_state_machine.c:739 (sofia/internal/190@mydomain.com) Running State Change CS_DESTROY (Cur 0 Tot 1147254)

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:28.040679 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/190@mydomain.com) State DESTROY

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:28.040679 [DEBUG] mod_sofia.c:364 sofia/internal/190@mydomain.com SOFIA DESTROY

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:28.040679 [DEBUG] switch_core_state_machine.c:181 sofia/internal/190@mydomain.com Standard DESTROY

280e7467-a44c-48f1-b8c4-2d9b4382d56a 2022-03-31 02:06:28.040679 [DEBUG] switch_core_state_machine.c:749 (sofia/internal/190@mydomain.com) State DESTROY going to sleep

2022-03-31 02:06:28.960706 [DEBUG] sofia_reg.c:2457 Changing expire time to 172 by request of proxy sip:sip.telnyx.com
 

ad5ou

Active Member
Jun 12, 2018
884
197
43
280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com Regex (FAIL) [extension-to-voicemail] ${user_exists}(false) =~ /^true$/ break=on-false

280e7467-a44c-48f1-b8c4-2d9b4382d56a Dialplan: sofia/internal/190@mydomain.com parsing [mydomain.com->follow-me-destinations] continue=false
extension-to-voicemail should be order 310 in dial plan manager
follow-me-destinations is usually order 500

From your posted logs, there are several dial plans either disabled or missing including *97 and park features.
Here is what I have between the two dial plans your system skipped to.
1648820535884.png
 

PJPMontreal

Member
Feb 28, 2020
59
2
8
57
extension-to-voicemail should be order 310 in dial plan manager
follow-me-destinations is usually order 500

From your posted logs, there are several dial plans either disabled or missing including *97 and park features.
Here is what I have between the two dial plans your system skipped to.
View attachment 2850
This is what I have, Please let me know if you see something wrong
 

Attachments

  • DialPlan.jpg
    DialPlan.jpg
    198.4 KB · Views: 11

PJPMontreal

Member
Feb 28, 2020
59
2
8
57
Im not sure if this problem arrived suddenly in this subdomain, but what I know it is only happening in this subdomain, in all other the dial *97 or parking at *5091 are working fine. The dialplan looks similar to all other subdomain.
 

MKNGRP

Member
Mar 26, 2021
40
0
6
44
Im not sure if this problem arrived suddenly in this subdomain, but what I know it is only happening in this subdomain, in all other the dial *97 or parking at *5091 are working fine. The dialplan looks similar to all other subdomain.
Sometimes when I add a new domain - they for some reason, do not have the default *97 or park dial plan feature. Are you certain that within the *97 feature code - under "Context" - there is one specifically for the tenant you are talking about?
 

PJPMontreal

Member
Feb 28, 2020
59
2
8
57
Sometimes when I add a new domain - they for some reason, do not have the default *97 or park dial plan feature. Are you certain that within the *97 feature code - under "Context" - there is one specifically for the tenant you are talking about?
Thanks, Yes... in my previous comments in this post I included a screenshot of the Dialplan for this specific tenant.
Also included the log when dialing *97 in case people here with more knowledge can take a look and find what is going on.
What else you think could be my problem?
 

PJPMontreal

Member
Feb 28, 2020
59
2
8
57
Did you try deleting those dial plans and then go to upgrade and app defaults
Thanks, Tried that too and nothing changed. Can't dial *97, *98, park+*5901 or *5902..... However page *88 or paging ext *8(ext) works fine.
Mysterious issue I have with this tenant!!!!
 

MKNGRP

Member
Mar 26, 2021
40
0
6
44
Thanks, Tried that too and nothing changed. Can't dial *97, *98, park+*5901 or *5902..... However page *88 or paging ext *8(ext) works fine.
Mysterious issue I have with this tenant!!!!
May I ask what phone model you use to dial these feature codes? Is it a grandstream phone?
 

PJPMontreal

Member
Feb 28, 2020
59
2
8
57
May I ask what phone model you use to dial these feature codes? Is it a grandstream phone?
Cisco SPA525G, Cisco SPA504G and softphone MicroSIP.
Tried on many other tenants with same phones and works fine, I just crated a new tenant and works fine. HAs to be something that had changed in this very specific tenant in which BTW I don't remember having change anything.
Just wondering whether the Log posted above at the beginning of this post showing what happening when dialing *97 doesn't tell you anything about the issue?
Thanks again for taking your time to help on this.
 
Status
Not open for further replies.