*97 not working

Status
Not open for further replies.
T

tdcockers

Member
Mar 9, 2018
44
0
6
44
Hi all... I've got the same issue on a couple of different instances of FusionPBX, at some point I did an update and *97 stopped working for message bank on Yealink phones (I haven't got any other phones to test on at the moment).

It seems like it connects then hangs up within a second or so - there is just silence for the whole time. Call log as follows... is there any clues as to what is triggering the hangup?

*98 is working as normal, message bank for each extension is available by using *98 then entering the extension number, but the direct option seems to the be only thing that is broken.

Thanks

Code: 
98d1f947-9c69-4e2a-b9bc-799231035ece Dialplan: sofia/internal/101@mypbx.com:5060 Regex (PASS) [vmain_user] destination_number(*97) =~ /^\*97$/ break=on-false
98d1f947-9c69-4e2a-b9bc-799231035ece Dialplan: sofia/internal/101@mypbx.com:5060 Action answer()
98d1f947-9c69-4e2a-b9bc-799231035ece Dialplan: sofia/internal/101@mypbx.com:5060 Action sleep(1000)
98d1f947-9c69-4e2a-b9bc-799231035ece Dialplan: sofia/internal/101@mypbx.com:5060 Action set(voicemail_action=check)
98d1f947-9c69-4e2a-b9bc-799231035ece Dialplan: sofia/internal/101@mypbx.com:5060 Action set(voicemail_id=${sip_from_user})
98d1f947-9c69-4e2a-b9bc-799231035ece Dialplan: sofia/internal/101@mypbx.com:5060 Action set(voicemail_profile=default)
98d1f947-9c69-4e2a-b9bc-799231035ece Dialplan: sofia/internal/101@mypbx.com:5060 Action lua(app.lua voicemail)
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:14.809580 97.20% [DEBUG] switch_core_state_machine.c:281 (sofia/internal/101@mypbx.com:5060) State Change CS_ROUTING -> CS_EXECUTE
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:14.809580 97.20% [DEBUG] switch_core_state_machine.c:640 (sofia/internal/101@mypbx.com:5060) State ROUTING going to sleep
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:14.809580 97.20% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/101@mypbx.com:5060) Running State Change CS_EXECUTE (Cur 1 Tot 5896)
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:14.809580 97.20% [DEBUG] switch_core_state_machine.c:647 (sofia/internal/101@mypbx.com:5060) State EXECUTE
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:14.809580 97.20% [DEBUG] mod_sofia.c:213 sofia/internal/101@mypbx.com:5060 SOFIA EXECUTE
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:14.809580 97.20% [DEBUG] switch_core_state_machine.c:323 sofia/internal/101@mypbx.com:5060 Standard EXECUTE
98d1f947-9c69-4e2a-b9bc-799231035ece EXECUTE [depth=0] sofia/internal/101@mypbx.com:5060 set(caller_id_number=1001)
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:14.809580 97.20% [DEBUG] mod_dptools.c:1685 SET sofia/internal/101@mypbx.com:5060 [caller_id_number]=[1001]
98d1f947-9c69-4e2a-b9bc-799231035ece EXECUTE [depth=0] sofia/internal/101@mypbx.com:5060 set(RFC2822_DATE=Fri, 12 Jan 2024 08:02:14 +1000)
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:14.809580 97.20% [DEBUG] mod_dptools.c:1685 SET sofia/internal/101@mypbx.com:5060 [RFC2822_DATE]=[Fri, 12 Jan 2024 08:02:14 +1000]
98d1f947-9c69-4e2a-b9bc-799231035ece EXECUTE [depth=0] sofia/internal/101@mypbx.com:5060 export(origination_callee_id_name=*97)
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:14.809580 97.20% [DEBUG] switch_channel.c:1315 EXPORT (export_vars) [origination_callee_id_name]=[*97]
98d1f947-9c69-4e2a-b9bc-799231035ece EXECUTE [depth=0] sofia/internal/101@mypbx.com:5060 set(RFC2822_DATE=Fri, 12 Jan 2024 08:02:14 +1000)
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:14.809580 97.20% [DEBUG] mod_dptools.c:1685 SET sofia/internal/101@mypbx.com:5060 [RFC2822_DATE]=[Fri, 12 Jan 2024 08:02:14 +1000]
98d1f947-9c69-4e2a-b9bc-799231035ece EXECUTE [depth=0] sofia/internal/101@mypbx.com:5060 hash(insert/mypbx.com-last_dial/1001/*97)
98d1f947-9c69-4e2a-b9bc-799231035ece EXECUTE [depth=0] sofia/internal/101@mypbx.com:5060 answer()
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:14.809580 97.20% [DEBUG] switch_core_media.c:8777 AUDIO RTP [sofia/internal/101@mypbx.com:5060] x.x.x.x port 32492 -> 10.70.30.81 port 12054 codec: 8 ms: 20
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:14.809580 97.20% [DEBUG] switch_rtp.c:4619 Starting timer [soft] 160 bytes per 20ms
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:14.809580 97.20% [DEBUG] switch_core_media.c:9089 sofia/internal/101@mypbx.com:5060 Set 2833 dtmf send payload to 101
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:14.809580 97.20% [DEBUG] switch_core_media.c:9096 sofia/internal/101@mypbx.com:5060 Set 2833 dtmf receive payload to 101
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:14.809580 97.20% [DEBUG] switch_core_media.c:9119 sofia/internal/101@mypbx.com:5060 Set rtp dtmf delay to 40
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:14.809580 97.20% [DEBUG] mod_sofia.c:913 Local SDP sofia/internal/101@mypbx.com:5060:
98d1f947-9c69-4e2a-b9bc-799231035ece v=0
98d1f947-9c69-4e2a-b9bc-799231035ece o=FreeSWITCH 1704978042 1704978043 IN IP4 x.x.x.x
98d1f947-9c69-4e2a-b9bc-799231035ece s=FreeSWITCH
98d1f947-9c69-4e2a-b9bc-799231035ece c=IN IP4 x.x.x.x
98d1f947-9c69-4e2a-b9bc-799231035ece t=0 0
98d1f947-9c69-4e2a-b9bc-799231035ece m=audio 32492 RTP/AVP 8 101
98d1f947-9c69-4e2a-b9bc-799231035ece a=rtpmap:8 PCMA/8000
98d1f947-9c69-4e2a-b9bc-799231035ece a=rtpmap:101 telephone-event/8000
98d1f947-9c69-4e2a-b9bc-799231035ece a=fmtp:101 0-15
98d1f947-9c69-4e2a-b9bc-799231035ece a=ptime:20
98d1f947-9c69-4e2a-b9bc-799231035ece a=sendrecv
98d1f947-9c69-4e2a-b9bc-799231035ece
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:14.809580 97.20% [DEBUG] sofia.c:7499 Channel sofia/internal/101@mypbx.com:5060 entering state [completed][200]
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:14.809580 97.20% [NOTICE] mod_dptools.c:1419 Channel [sofia/internal/101@mypbx.com:5060] has been answered
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:14.829583 97.20% [DEBUG] switch_channel.c:3950 (sofia/internal/101@mypbx.com:5060) Callstate Change RINGING -> ACTIVE
98d1f947-9c69-4e2a-b9bc-799231035ece EXECUTE [depth=0] sofia/internal/101@mypbx.com:5060 sleep(1000)
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:14.869583 97.20% [DEBUG] sofia.c:7499 Channel sofia/internal/101@mypbx.com:5060 entering state [ready][200]
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:15.129643 97.20% [INFO] switch_rtp.c:7894 Auto Changing audio port from 10.70.30.81:12054 to x.x.x.x:12054
98d1f947-9c69-4e2a-b9bc-799231035ece EXECUTE [depth=0] sofia/internal/101@mypbx.com:5060 set(voicemail_action=check)
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:15.829616 96.67% [DEBUG] mod_dptools.c:1685 SET sofia/internal/101@mypbx.com:5060 [voicemail_action]=[check]
98d1f947-9c69-4e2a-b9bc-799231035ece EXECUTE [depth=0] sofia/internal/101@mypbx.com:5060 set(voicemail_id=S1001)
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:15.829616 96.67% [DEBUG] mod_dptools.c:1685 SET sofia/internal/101@mypbx.com:5060 [voicemail_id]=[S1001]
98d1f947-9c69-4e2a-b9bc-799231035ece EXECUTE [depth=0] sofia/internal/101@mypbx.com:5060 set(voicemail_profile=default)
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:15.829616 96.67% [DEBUG] mod_dptools.c:1685 SET sofia/internal/101@mypbx.com:5060 [voicemail_profile]=[default]
98d1f947-9c69-4e2a-b9bc-799231035ece EXECUTE [depth=0] sofia/internal/101@mypbx.com:5060 lua(app.lua voicemail)
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:15.849597 96.67% [DEBUG] switch_cpp.cpp:773 CoreSession::setVariable(playback_terminators, #)
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:15.869581 96.67% [DEBUG] switch_cpp.cpp:749 CoreSession::hangup
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:15.869581 96.67% [NOTICE] switch_cpp.cpp:751 Hangup sofia/internal/101@mypbx.com:5060 [CS_EXECUTE] [NORMAL_CLEARING]
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:15.869581 96.67% [DEBUG] switch_cpp.cpp:1209 sofia/internal/101@mypbx.com:5060 destroy/unlink session from object
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:15.869581 96.67% [DEBUG] switch_core_session.c:2983 sofia/internal/101@mypbx.com:5060 skip receive message [PHONE_EVENT] (channel is hungup already)
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:15.869581 96.67% [DEBUG] switch_core_state_machine.c:647 (sofia/internal/101@mypbx.com:5060) State EXECUTE going to sleep
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:15.869581 96.67% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/101@mypbx.com:5060) Running State Change CS_HANGUP (Cur 1 Tot 5896)
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:15.869581 96.67% [DEBUG] switch_core_state_machine.c:844 (sofia/internal/101@mypbx.com:5060) Callstate Change ACTIVE -> HANGUP
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:15.869581 96.67% [DEBUG] switch_core_state_machine.c:846 (sofia/internal/101@mypbx.com:5060) State HANGUP
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:15.869581 96.67% [DEBUG] mod_sofia.c:468 Channel sofia/internal/101@mypbx.com:5060 hanging up, cause: NORMAL_CLEARING
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:15.869581 96.67% [DEBUG] mod_sofia.c:522 Sending BYE to sofia/internal/101@mypbx.com:5060
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:15.869581 96.67% [DEBUG] switch_core_state_machine.c:59 sofia/internal/101@mypbx.com:5060 Standard HANGUP, cause: NORMAL_CLEARING
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:15.869581 96.67% [DEBUG] switch_core_state_machine.c:846 (sofia/internal/101@mypbx.com:5060) State HANGUP going to sleep
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:15.869581 96.67% [DEBUG] switch_core_state_machine.c:616 (sofia/internal/101@mypbx.com:5060) State Change CS_HANGUP -> CS_REPORTING
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:15.869581 96.67% [DEBUG] switch_core_state_machine.c:581 (sofia/internal/101@mypbx.com:5060) Running State Change CS_REPORTING (Cur 1 Tot 5896)
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:15.869581 96.67% [DEBUG] switch_core_state_machine.c:932 (sofia/internal/101@mypbx.com:5060) State REPORTING
2024-01-12 08:02:15.869581 96.67% [ERR] mod_xml_cdr.c:394 Got error [404] posting to web server [http://127.0.0.1/app/xml_cdr/v_xml_cdr_import.php]
2024-01-12 08:02:15.869581 96.67% [ERR] mod_xml_cdr.c:401 Retry will be with url [http://127.0.0.1/app/xml_cdr/v_xml_cdr_import.php]
2024-01-12 08:02:15.869581 96.67% [ERR] mod_xml_cdr.c:417 Unable to post to web server, writing to file
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:15.869581 96.67% [DEBUG] switch_core_state_machine.c:168 sofia/internal/101@mypbx.com:5060 Standard REPORTING, cause: NORMAL_CLEARING
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:15.869581 96.67% [DEBUG] switch_core_state_machine.c:932 (sofia/internal/101@mypbx.com:5060) State REPORTING going to sleep
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:15.869581 96.67% [DEBUG] switch_core_state_machine.c:607 (sofia/internal/101@mypbx.com:5060) State Change CS_REPORTING -> CS_DESTROY
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:15.869581 96.67% [DEBUG] switch_core_session.c:1753 Session 5896 (sofia/internal/101@mypbx.com:5060) Locked, Waiting on external entities
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:15.869581 96.67% [NOTICE] switch_core_session.c:1771 Session 5896 (sofia/internal/101@mypbx.com:5060) Ended
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:15.869581 96.67% [NOTICE] switch_core_session.c:1775 Close Channel sofia/internal/101@mypbx.com:5060 [CS_DESTROY]
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:15.869581 96.67% [DEBUG] switch_core_state_machine.c:735 (sofia/internal/101@mypbx.com:5060) Running State Change CS_DESTROY (Cur 0 Tot 5896)
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:15.869581 96.67% [DEBUG] switch_core_state_machine.c:745 (sofia/internal/101@mypbx.com:5060) State DESTROY
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:15.869581 96.67% [DEBUG] mod_sofia.c:379 sofia/internal/101@mypbx.com:5060 SOFIA DESTROY
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:15.869581 96.67% [DEBUG] switch_core_state_machine.c:175 sofia/internal/101@mypbx.com:5060 Standard DESTROY
98d1f947-9c69-4e2a-b9bc-799231035ece 2024-01-12 08:02:15.869581 96.67% [DEBUG] switch_core_state_machine.c:745 (sofia/internal/101@mypbx.com:5060) State DESTROY going to sleep
 
T

tdcockers

Member
Mar 9, 2018
44
0
6
44
Wondering if this might have something to do with voice packs, I've just been told that when you dial *98 and then your extension, the menu is a bit flaky, instead of saying 'press 1 for something, press 2 for something' it is not reading out the numbers, just silence eg 'press ... for something, press ... for something'.

Maybe a file permissions error? How would I check that?
 
Status
Not open for further replies.
Top Bottom