Grandstream DP752 401 Errors

So I’ve have a Grandstream DP752 (DECT) with four handsets that I’ve been using for a few months with no issue along with FreePBX. Went to make a call last night and suddenly I’m getting a “488 Call Release” error on all of the handsets immediately after dialing. I have not made any contiguration changes other than adding a ring group.

I also have an Avaya desk phone which works fine. In addition I can plug the affected extension info a softphone and it works fine. I tried creating a new extension and configuring it on the Grandstream, same problem occurs. I have even done a factory reset on the Grandstream and reconfigured it from scratch to rule out some unseen configuration corruption. This didn’t help either.

I’m totally out of ideas at this point. Below is what’s showing up in my syslog. By the way, I can receive income calls on these phones, it’s only outgoing that fail. Any ideas?

Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]snd_message:SIPStack.cc(6402)->Last Good IP Addr:0.0.0.0
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]snd_message:SIPStack.cc(6425)->Present IP Addr:192.168.1.6
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]snd_message:SIPStack.cc(6720)->(300) ACK sip:[email protected] SIP/2.0  Via: SIP/2.0/TCP 192.168.1.8:5060;branch=z9hG4bK1234015185;rport;alias  From: "
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]Jeffrey M. D" <sip:[email protected]>;tag=46743138  To: <sip:[email protected]>;tag=z9hG4bK1234015185  Call-ID: [email protected]
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]CSeq: 1420 ACK  Content-Length: 0
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]cb_rcv4xx:SIPStack.cc(7706)->SIPStack(0): Received 401 response for transaction 415(INVITE)
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]waitForResponse:SIPTransaction.cc(1025)->Request 415 got status code 401
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]run:SIPStack.cc(1170)->SIPStack(0): Active subscribe dialogs: 1
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]run:SIPStack.cc(1260)->SIPStack(0): Active transactions: 2
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]cb_ict_kill_transaction:SIPStack.cc(6806)->SIPStack(0): Kill ICT transaction 415
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]cb_snd_message:SIPStack.cc(5733)->SIPStack(0): Transaction 416 , state=0
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]snd_message:SIPStack.cc(6402)->Last Good IP Addr:0.0.0.0
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]snd_message:SIPStack.cc(6425)->Present IP Addr:192.168.1.6
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]snd_message:SIPStack.cc(6720)->(1583) INVITE sip:[email protected] SIP/2.0  Via: SIP/2.0/TCP 192.168.1.8:5060;branch=z9hG4bK1729553293;rport;alias  Fro
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]m: "Jeffrey M. D" <sip:[email protected]>;tag=46743138  To: <sip:[email protected]>  Call-ID: [email protected]  CSeq: 1421 INVITE
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3] Contact: "Jeffrey M. D" <sip:[email protected]:5060;transport=tcp>  Authorization: Digest username="1000", realm="asterisk", nonce="1648835690/
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]3d03edd2e169027f475fc2b82be72bea", uri="sip:[email protected]", response="dce39901e341f11de3880b729ec6274e", algorithm=md5, cnonce="07050383", opaque="
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]3effc86321cbcd2f", qop=auth, nc=00000001  Max-Forwards: 70  User-Agent: Grandstream DP752 1.0.17.3  Privacy: none  P-Preferred-Identity: "Jeffrey M. D
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]ominiak" <sip:[email protected]>  Supported: replaces, path, timer, eventlist  Allow: INVITE, ACK, OPTIONS, CANCEL, BYE, SUBSCRIBE, NOTIFY, INFO, REFER
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3], UPDATE  Content-Type: application/sdp  Accept: application/sdp, application/dtmf-relay  Content-Length:   606    v=0  o=1000 8000 8000 IN IP4 192.16
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]8.1.8  s=SIP Call  c=IN IP4 192.168.1.8  t=0 0  m=audio 50000 RTP/SAVP 0 8 18 123 97 2 101  a=sendrecv  a=rtpmap:0 PCMU/8000  a=ptime:20  a=rtpmap:8 P
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]CMA/8000  a=rtpmap:18 G729/8000  a=fmtp:18 annexb=no  a=rtpmap:123 opus/48000/2  a=fmtp:123 maxplaybackrate=16000  a=rtpmap:97 iLBC/8000  a=fmtp:97 mo
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]de=20  a=rtpmap:2 G726-32/8000  a=rtpmap:101 telephone-event/8000  a=fmtp:101 0-16,32-36,54  a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:Bpt/6nzI7y4IJdE
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]RvYX5Vh0xDhYgkSSDa5GpTh07|2^32  a=crypto:2 AES_CM_128_HMAC_SHA1_32 inline:RSTWxA5SjP6BlSNSpuDXoDb00UQL8dUvdUDBHo7e|2^32
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]run:SIPStack.cc(1170)->SIPStack(0): Active subscribe dialogs: 1
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]run:SIPStack.cc(1260)->SIPStack(0): Active transactions: 1
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]receiveMessage:SIPStack.cc(3476)->(708) SIP/2.0 100 Trying  Via: SIP/2.0/TCP 192.168.1.8:5060;rport=46583;received=192.168.1.8;branch=z9hG4bK172955329
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]3;alias  Call-ID: [email protected]  From: "Jeffrey M. D" <sip:[email protected]>;tag=46743138  To: <sip:[email protected]>  CSeq:
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]1421 INVITE  Server: FPBX-16.0.19.3(19.3.0)  Content-Length:  0    SIP/2.0 488 Not Acceptable Here  Via: SIP/2.0/TCP 192.168.1.8:5060;rport=46583;rece
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]ived=192.168.1.8;branch=z9hG4bK1729553293;alias  Call-ID: [email protected]  From: "Jeffrey M. D" <sip:[email protected]>;tag=4674
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]3138  To: <sip:[email protected]>;tag=6a99e4c0-4e82-41e4-919c-cc7b2e764db1  CSeq: 1421 INVITE  Server: FPBX-16.0.19.3(19.3.0)  Content-Length:  0
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]cb_rcv1xx:SIPStack.cc(7241)->SIPStack(0): Received 100 response for transaction 416 (INVITE)
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]waitForResponse:SIPTransaction.cc(1025)->Request 416 got status code 100
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]snd_message:SIPStack.cc(6402)->Last Good IP Addr:0.0.0.0
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]snd_message:SIPStack.cc(6425)->Present IP Addr:192.168.1.6
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]snd_message:SIPStack.cc(6720)->(319) ACK sip:[email protected] SIP/2.0  Via: SIP/2.0/TCP 192.168.1.8:5060;branch=z9hG4bK1729553293;rport;alias  From: "
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]Jeffrey M. D" <sip:[email protected]>;tag=46743138  To: <sip:[email protected]>;tag=6a99e4c0-4e82-41e4-919c-cc7b2e764db1  Call-ID: 1099447323-506
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3][email protected]  CSeq: 1421 ACK  Content-Length: 0
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]cb_rcv4xx:SIPStack.cc(7706)->SIPStack(0): Received 488 response for transaction 416(INVITE)
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]waitForResponse:SIPTransaction.cc(1025)->Request 416 got status code 488
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]Event:Event.cc(1093)->========================================
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]Event:Event.cc(1094)->Event: CALL_FAILED, Port: 0, Ch: 0
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]Event:Event.cc(1095)->========================================
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]~RTP:RTP.cc(120)->RTP::~RTP, destruct 0xe39b68
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]closeSocket:RTP.cc(388)->RTP::closeSocket(), Closing socket: 62, local RTP port: 50000
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]closeSocket:RTP.cc(406)->RTP::closeSocket(), Closing socket: 63, local RTCP port: 50001
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]run:Event.cc(1036)->Dispatching event 228 (CALL_FAILED) on port 0:0
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]processEvent:CallControl.cc(1416)->CTRL: processEvent: CALL_FAILED [0:0] CALL_DIALED [0:1] CALL_IDLE.
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]processCallFailed:CallControl.cc(5259)->on port 0:0, status = CALL_DIALED/CALL_IDLE stCode:488 canConf:1
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]setStatus:CallControl.h(444)->CTRL: setStatus: [0:0] CALL_DIALED -> CALL_ENDING.
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]map_to_dsp_port_chan:GSDSP_pnx8181.cc(1284)->GSDSP::map_to_dsp_port_chan: from port_app = 0 --> port_dsp(CMBS Channel ID) = 0
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]stopRTP:GSDSP_pnx8181.cc(1013)->GSDSP::stop RTP on DSP port 0:0
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3] LIBGSDSP: dua_disconnect_tdm ( 0 )
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3] LIBGSDSP: locking dua_mutex in dua_disconnect_tdm : 2226
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3] LIBGSDSP: locked dua_mutex in dua_disconnect_tdm : 2228
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3] LIBGSDSP: dua_disconnect_tdm done
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3] LIBGSDSP: unlocking dua_mutex in dua_disconnect_tdm : 2259
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3] LIBGSDSP: unlocked dua_mutex in dua_disconnect_tdm : 2261
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]stopRTP:GSDSP_pnx8181.cc(1025)->GSDSP::RTP stopped on DSP port 0:0
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]processEvent:Call.cc(1405)->Call(16), CALL_FAILED, port 0:0, evtPort 0:0, inTransfer:0
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]find_active_cmbs_channel_by_app:Dect.h(2363)->DECT: [RII:030303] find_active_cmbs_channel_by_app: HS1 Ch0 Found cmbs_channel for app_port 0, app_ch 0.
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]cmbsSendChannelStop:gs_cmbs_callcontrol.cc(2281)->CMBS: cmbsSendChannelStop: WARNING!!!: HS1 Ch0 CMBS audio channel already STOPPED (tdm 0) Active cha
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]nnels = 1.
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]~Call:Call.cc(301)->Deleting Call object 16 port 0:0, callCount=0
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]map_to_dsp_port_chan:GSDSP_pnx8181.cc(1284)->GSDSP::map_to_dsp_port_chan: from port_app = 0 --> port_dsp(CMBS Channel ID) = 0
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]cmbsSendChannelStop:gs_cmbs_callcontrol.cc(2289)->CMBS: cmbsSendChannelStop: WARNING!!!: HS1 Ch0 DSP port was set to 0:0, clean up.
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]setCmbsChannelStatus:Dect.cc(5932)->Dect::setCmbsChannelStatus, handset 1 cmbs_ch 0 status: 10
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]setCmbsChannelStatus:Dect.cc(5961)->DECT: [RII:030303] setCmbsChannelStatus: HS1 Ch0 status change 3 ---> 10.
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]getHandsetActiveInstances:Dect.h(3271)->DECT: [RII:030303] getHandsetActiveInstances: HS1 has 1 active instances [0x00000010,  0x00000000].
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]updateHandsetStatus:Dect.cc(6154)->DECT: [RII:030303] updateHandsetStatus: HS1 status change 3 ---> 10.
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]run:SIPStack.cc(1170)->SIPStack(0): Active subscribe dialogs: 1
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]run:SIPStack.cc(1260)->SIPStack(0): Active transactions: 1
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]cb_ict_kill_transaction:SIPStack.cc(6806)->SIPStack(0): Kill ICT transaction 416
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]run:SIPStack.cc(1170)->SIPStack(0): Active subscribe dialogs: 1
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]dect_update_led_status:Dect.cc(6234)->DECT: [RII:030303] dect_update_led_status: LED status 1 --> 1.
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]gs_cmbs_set_calls_running:gs_cmbs.cc(265)->CMBS: gs_cmbs_set_calls_running: CMBS concurrent calls 1 -> 0.
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]cmbsSendCallRelease:gs_cmbs_callcontrol.cc(2486)->CMBS: cmbsSendCallRelease: HS1 Ch0.
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]processCallComplete:Dect.cc(1833)->DECT: [RII:030303] processCallComplete: HS1 Ch0 Cleanup, default.
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]find_active_cmbs_channel_by_instance:Dect.cc(6345)->DECT: [RII:030303] find_active_cmbs_channel_by_instance: Found active cmbs channel for instance 0x
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]00000010, (HS1 CH0).
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]cmbsOnCallState:gs_cmbs_callcontrol.cc(1149)->CMBS: cmbsOnCallState: HS1 Ch0 CMBS CALL state update.
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]find_active_cmbs_channel_by_instance:Dect.cc(6345)->DECT: [RII:030303] find_active_cmbs_channel_by_instance: Found active cmbs channel for instance 0x
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]00000010, (HS1 CH0).
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]Event:Event.cc(1093)->========================================
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]Event:Event.cc(1094)->Event: PHONE_ON_HOOK, Port: 0, Ch: 0
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]Event:Event.cc(1095)->========================================
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]release_cmbs_channel:Dect.cc(6883)->Dect::release_cmbs_channel, handset 1 ch 0
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]release_cmbs_channel:Dect.cc(6884)->DECT: [RII:030303] release_cmbs_channel: Running on HS1 Ch 0.
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]dect_debug_handset_info:Dect.cc(11064)->DECT: [RII:030303] dect_debug_handset_info: .
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]setCmbsChannelStatus:Dect.cc(5932)->Dect::setCmbsChannelStatus, handset 1 cmbs_ch 0 status: 0
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]setCmbsChannelStatus:Dect.cc(5961)->DECT: [RII:030303] setCmbsChannelStatus: HS1 Ch0 status change 10 ---> 0.
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]unregisterEventListener:Event.cc(911)->listener Call
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]getHandsetActiveInstances:Dect.h(3271)->DECT: [RII:030303] getHandsetActiveInstances: HS1 has 1 active instances [0x00000010,  0x00000000].
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]updateHandsetStatus:Dect.cc(6154)->DECT: [RII:030303] updateHandsetStatus: HS1 status change 10 ---> 0.
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]dect_update_led_status:Dect.cc(6234)->DECT: [RII:030303] dect_update_led_status: LED status 1 --> 0.
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]dect_log_call:Dect.cc(12529)->DECT: [RII:030303] dect_log_call: No dialed network name logged!.
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]dect_log_call:Dect.cc(12630)->DECT: [RII:030303] CALL_LOG: dect_log_call: Adding Call Entry (mask: 0x00000400) on HS 1, Line ID 0.
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]clearChannelInstance:Dect.h(2333)->DECT: [RII:030303] clearChannelInstance: Cleanup HS1 Ch0 instance 0x00000010.
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]Event:Event.cc(1080)->========================================
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]Event:Event.cc(1081)->Event: DECT_MM_UPDATE_HS_STATUS
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]Event:Event.cc(1082)->========================================
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]Event:Event.cc(1080)->========================================
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]Event:Event.cc(1081)->Event: DECT_RESTART_PTT
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]Event:Event.cc(1082)->========================================
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]run:Event.cc(1036)->Dispatching event 4 (PHONE_ON_HOOK) on port 0:0
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]processEvent:CallControl.cc(1416)->CTRL: processEvent: PHONE_ON_HOOK [0:0] CALL_ENDING [0:1] CALL_IDLE.
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]processPhoneOnHook:CallControl.cc(2810)->on port 0:0, status = CALL_ENDING/CALL_IDLE
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]processPhoneOnHook:CallControl.cc(2973)->on port 0: och1 /ch0, status 0/9
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]setStatus:CallControl.h(444)->CTRL: setStatus: [0:0] CALL_ENDING -> CALL_IDLE.
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]run:Event.cc(1036)->Dispatching event 326 (DECT_MM_UPDATE_HS_STATUS) on port -1:-1
Apr  1 12:54:50 DP752 [C0: 74:AD:50:0B:1D] [1.0.17.3]run:Event.cc(1036)->Dispatching event 317 (DECT_RESTART_PTT) on port -1:-1

Finally figured it out, had to change “Callee codec negotiation priority” on the DP752 from “Remote” to “Local”. For some unknown reason it’s no longer able to negotiate a compatible codec on it’s own with Asterisk. Why? I have no clue.

I did upgrade the firmware on the Grandstream, but that was several weeks ago. I has been working fine until yesterday. :face_with_spiral_eyes:

What codecs are acceptable by your PBX ? generally SIP will figure out the first mutually acceptable one to use for any ensuing SDP connection

I have ulaw, alaw, g729 and g722 enabled (and in that order), and normally ulaw is negotiated.

Disable all but ulaw everywhere

This topic was automatically closed 7 days after the last reply. New replies are no longer allowed.