02-18-2022 08:13 AM
Callmanager 11.5
phone firmware: sip78xx.12-8-1-0101-482
The phone will randomly go off hook and act as though it is waiting for numbers to be dialed. An it isn't activating the speakerphone. It is activating the handset while it is in the cradle. The handset will not work for a few minutes after this happens. Seems like it may be a physical issue with the phone but I would like to check to see if anyone else has seen this.
02-18-2022 08:43 AM
Here is some of the core file from the phone webpage. I have just started digging through it:
3875 NOT Feb 18 09:55:17.455547 (1830-2097) JAVA-SIPCC-SIP_CC_PROV: ccappUpdateSessionData: CCAPP -> GSM: Setting Privacy to FULL. eventID= 4
3876 NOT Feb 18 09:55:17.453013 (1856-1856) metmand-update_sessionid: Received NULL or Invalid Type
3877 NOT Feb 18 09:55:17.458812 (1830-2097) JAVA-SIPCC-SIP_CC_PROV: ccsnap_gen_callEvent: CallEvent=CREATED, CallState=OFFHOOK
3878 NOT Feb 18 09:55:17.474103 (1830-2098) JAVA-SIPCC-SIP_MSG_RECV: ccsip_dump_recv_msg_info: <10.X.X.X:0 >:200 OK
3879 NOT Feb 18 09:55:17.477369 (1830-2100) JAVA-SIPCC-MED_API: lsm_util_start_tone: Enter, line=1, call_id=1.
3880 ERR Feb 18 09:55:17.492476 (1817-1817) ms-ACOUSTINTER.aecSetAfeGainParams NULL AFE REG setting pointer !!!
3881 NOT Feb 18 09:55:17.545612 (1830-2100) JAVA-SIPCC-FSM: 1/1, fsm_change_state: DEF: IDLE -> COLLECTING_INFO
3882 NOT Feb 18 09:55:17.545856 (1830-2100) JAVA-SIPCC-DCSM: dcsm_update_gsm_state: 1 : DCSM_READY --> DCSM_READY
3883 NOT Feb 18 09:55:17.545978 (1830-2100) JAVA-SIPCC-GSM: 1/1, sm_process_event: DEF :(IDLE:OFFHOOK )
3884 NOT Feb 18 09:55:17.560354 (1856-1856) metmand-update_sessionid: Local UUID:
3885 NOT Feb 18 09:55:17.560537 (1856-1856) metmand-update_sessionid: Remote UUID:
3886 NOT Feb 18 09:55:17.560750 (1856-1856) metmand-update_sessionid: Received NULL or Invalid Type
3887 NOT Feb 18 09:55:17.560842 (1856-1856) metmand-update_sessionid: Local UUID:
3888 NOT Feb 18 09:55:17.560933 (1856-1856) metmand-update_sessionid: Remote UUID:
3889 NOT Feb 18 09:55:17.795452 (1830-2076) JAVA-callagent MQThread|cip.callagent.Line:? - callState=OFFHOOK
3890 NOT Feb 18 09:55:17.997161 (1830-2097) JAVA-SIPCC-SIP_CC_PROV: ccappUpdateSessionData: CCAPP -> GSM: Call_STATE:. state=21, privacy=0, attr=0, cause=5, instance=0
3891 NOT Feb 18 09:55:17.998107 (1830-2097) JAVA-SIPCC-SIP_CC_PROV: ccsnap_gen_callEvent: CallEvent=STATE, CallState=WAITINGFORDIGITS
3892 NOT Feb 18 09:55:18.008576 (1830-2076) JAVA-callagent MQThread|cip.callagent.Line:attention: set reviseendCall - theCall.reviseendCall=true
3893 ERR Feb 18 09:55:18.027102 (1830-2062) JAVA-System P7-display MQThread - ### reset call list, newSize=1
3894 NOT Feb 18 09:55:18.042850 (1830-2076) JAVA-callagent MQThread|cip.callagent.Line:? - setActiveCall - enter
3895 NOT Feb 18 09:55:18.072119 (1830-2076) JAVA-callagent MQThread|cip.callagent.Call:activate - callhandle=cip.callagent.CallNumberHandle@1
3896 NOT Feb 18 09:55:18.076361 (1830-2080) JAVA-configmgr MQThread|ConfigManager:? - --->ConfigManager PropertyChanged: device.callagent.callcount
3897 NOT Feb 18 09:55:18.076880 (1830-2080) JAVA-configmgr MQThread|ConfigManager:? - <---ConfigManager PropertyChanged: device.callagent.callcount
3898 NOT Feb 18 09:55:18.078101 (1830-2080) JAVA-configmgr MQThread|cip.cfg.Config: - [propertyChanged()] propName:"device.callagent.callcount"
3899 NOT Feb 18 09:55:18.083381 (1830-2062) JAVA-System P7-display MQThread|cip.sipcc.SipCcAdapter: - propertyChanged(): device.callagent.callcount
3900 NOT Feb 18 09:55:18.087501 (1830-2080) JAVA-configmgr MQThread|ConfigManager:? - --->ConfigManager PropertyChanged: device.callagent.callcount
3901 NOT Feb 18 09:55:18.088295 (1830-2080) JAVA-configmgr MQThread|ConfigManager:? - <---ConfigManager PropertyChanged: device.callagent.callcount
3902 NOT Feb 18 09:55:18.095162 (1830-2062) JAVA-System P7-display MQThread|cip.sipcc.SipCcAdapter: - propertyChanged(): device.callagent.callcount
3903 NOT Feb 18 09:55:18.098275 (1830-2080) JAVA-configmgr MQThread|cip.cfg.Config: - [propertyChanged()] propName:"device.callagent.callcount"
3904 NOT Feb 18 09:55:18.098794 (1830-2076) JAVA-callagent MQThread|cip.callagent.Line:? - prevLineState=INACTIVE, lineState=OFFHOOK
3905 NOT Feb 18 09:55:18.106394 (1830-2075) JAVA-mediacontrolui MQThread|cip.msui.MediaControl:callState - The CallControlState isOFFHOOK
3906 NOT Feb 18 09:55:18.107462 (1830-2075) JAVA-mediacontrolui MQThread|cip.msui.MediaControl$3:execute - [TR] executing the state-event handlerFunction=NoAudioSessionStartedFunc
3907 NOT Feb 18 09:55:18.164352 sh:
3908 NOT Feb 18 09:55:18.164871 ioctl_test: not found
3909 NOT Feb 18 09:55:18.173142 (1830-2075) JAVA-mediacontrolui MQThread|cip.msui.MediaControl:? - ignore updating local volume 7 for remote config, audioPath=AUDIO_PATH_HANDSET_OUT
3910 NOT Feb 18 09:55:18.202380 (1830-2088) JAVA-System P9-sidecar MQThread|cip.sidecar.Sidecar:In the PropertyChanged - PHONE_IDLE_PROPERTY_NAME and it is false
3911 NOT Feb 18 09:55:18.206073 (1830-2076) JAVA-callagent MQThread|cip.callagent.Call:subscribe - callhandle=cip.callagent.CallNumberHandle@1
3912 NOT Feb 18 09:55:18.206500 (1830-2076) JAVA-callagent MQThread|cip.callagent.Line:? - setActiveCall - enter
3913 NOT Feb 18 09:55:18.207111 (1830-2076) JAVA-callagent MQThread|cip.callagent.Call:activate - callhandle=cip.callagent.CallNumberHandle@1
3914 NOT Feb 18 09:55:18.208148 (1830-2076) JAVA-callagent MQThread|cip.callagent.Line:? - callState=OFFHOOK
3915 NOT Feb 18 09:55:18.209034 (1830-2076) JAVA-callagent MQThread|cip.callagent.Line:? - setActiveCall - enter
3916 NOT Feb 18 09:55:18.211323 (1830-2076) JAVA-callagent MQThread|cip.callagent.Call:activate - callhandle=cip.callagent.CallNumberHandle@1
3917 NOT Feb 18 09:55:18.262658 (1830-2080) JAVA-configmgr MQThread|ConfigManager:? - --->ConfigManager PropertyChanged: device.callagent.callcount
3918 NOT Feb 18 09:55:18.263207 (1830-2080) JAVA-configmgr MQThread|ConfigManager:? - <---ConfigManager PropertyChanged: device.callagent.callcount
3919 NOT Feb 18 09:55:18.263604 (1830-2080) JAVA-configmgr MQThread|cip.cfg.Config: - [propertyChanged()] propName:"device.callagent.callcount"
3920 NOT Feb 18 09:55:18.271234 (1830-2076) JAVA-callagent MQThread|cip.callagent.Line:? - prevLineState=OFFHOOK, lineState=OFFHOOK
3921 NOT Feb 18 09:55:18.272211 (1830-2075) JAVA-mediacontrolui MQThread|cip.msui.MediaControl:callState - The CallControlState isOFFHOOK
3922 NOT Feb 18 09:55:18.272882 (1830-2075) JAVA-mediacontrolui MQThread|cip.msui.MediaControl$3:execute - [TR] executing the state-event handlerFunction=ActiveHandsetSessionStartedFunc
3923 NOT Feb 18 09:55:18.402044 (1830-2062) JAVA-System P7-display MQThread|cip.sipcc.SipCcAdapter: - propertyChanged(): device.callagent.callcount
02-18-2022 09:04 AM
Here is what I found in the phone logs. It repeats these steps:
3875 NOT Feb 18 09:55:17.455547 (1830-2097) JAVA-SIPCC-SIP_CC_PROV: ccappUpdateSessionData: CCAPP -> GSM: Setting Privacy to FULL. eventID= 4
3876 NOT Feb 18 09:55:17.453013 (1856-1856) metmand-update_sessionid: Received NULL or Invalid Type
3877 NOT Feb 18 09:55:17.458812 (1830-2097) JAVA-SIPCC-SIP_CC_PROV: ccsnap_gen_callEvent: CallEvent=CREATED, CallState=OFFHOOK
3878 NOT Feb 18 09:55:17.474103 (1830-2098) JAVA-SIPCC-SIP_MSG_RECV: ccsip_dump_recv_msg_info: <10.X.X.X:0 >:200 OK
3879 NOT Feb 18 09:55:17.477369 (1830-2100) JAVA-SIPCC-MED_API: lsm_util_start_tone: Enter, line=1, call_id=1.
3880 ERR Feb 18 09:55:17.492476 (1817-1817) ms-ACOUSTINTER.aecSetAfeGainParams NULL AFE REG setting pointer !!!
3881 NOT Feb 18 09:55:17.545612 (1830-2100) JAVA-SIPCC-FSM: 1/1, fsm_change_state: DEF: IDLE -> COLLECTING_INFO
3882 NOT Feb 18 09:55:17.545856 (1830-2100) JAVA-SIPCC-DCSM: dcsm_update_gsm_state: 1 : DCSM_READY --> DCSM_READY
3883 NOT Feb 18 09:55:17.545978 (1830-2100) JAVA-SIPCC-GSM: 1/1, sm_process_event: DEF :(IDLE:OFFHOOK )
3884 NOT Feb 18 09:55:17.560354 (1856-1856) metmand-update_sessionid: Local UUID:
3885 NOT Feb 18 09:55:17.560537 (1856-1856) metmand-update_sessionid: Remote UUID:
3886 NOT Feb 18 09:55:17.560750 (1856-1856) metmand-update_sessionid: Received NULL or Invalid Type
3887 NOT Feb 18 09:55:17.560842 (1856-1856) metmand-update_sessionid: Local UUID:
3888 NOT Feb 18 09:55:17.560933 (1856-1856) metmand-update_sessionid: Remote UUID:
3889 NOT Feb 18 09:55:17.795452 (1830-2076) JAVA-callagent MQThread|cip.callagent.Line:? - callState=OFFHOOK
3890 NOT Feb 18 09:55:17.997161 (1830-2097) JAVA-SIPCC-SIP_CC_PROV: ccappUpdateSessionData: CCAPP -> GSM: Call_STATE:. state=21, privacy=0, attr=0, cause=5, instance=0
3891 NOT Feb 18 09:55:17.998107 (1830-2097) JAVA-SIPCC-SIP_CC_PROV: ccsnap_gen_callEvent: CallEvent=STATE, CallState=WAITINGFORDIGITS
3892 NOT Feb 18 09:55:18.008576 (1830-2076) JAVA-callagent MQThread|cip.callagent.Line:attention: set reviseendCall - theCall.reviseendCall=true
3893 ERR Feb 18 09:55:18.027102 (1830-2062) JAVA-System P7-display MQThread - ### reset call list, newSize=1
3894 NOT Feb 18 09:55:18.042850 (1830-2076) JAVA-callagent MQThread|cip.callagent.Line:? - setActiveCall - enter
3895 NOT Feb 18 09:55:18.072119 (1830-2076) JAVA-callagent MQThread|cip.callagent.Call:activate - callhandle=cip.callagent.CallNumberHandle@1
3896 NOT Feb 18 09:55:18.076361 (1830-2080) JAVA-configmgr MQThread|ConfigManager:? - --->ConfigManager PropertyChanged: device.callagent.callcount
3897 NOT Feb 18 09:55:18.076880 (1830-2080) JAVA-configmgr MQThread|ConfigManager:? - <---ConfigManager PropertyChanged: device.callagent.callcount
3898 NOT Feb 18 09:55:18.078101 (1830-2080) JAVA-configmgr MQThread|cip.cfg.Config: - [propertyChanged()] propName:"device.callagent.callcount"
3899 NOT Feb 18 09:55:18.083381 (1830-2062) JAVA-System P7-display MQThread|cip.sipcc.SipCcAdapter: - propertyChanged(): device.callagent.callcount
3900 NOT Feb 18 09:55:18.087501 (1830-2080) JAVA-configmgr MQThread|ConfigManager:? - --->ConfigManager PropertyChanged: device.callagent.callcount
3901 NOT Feb 18 09:55:18.088295 (1830-2080) JAVA-configmgr MQThread|ConfigManager:? - <---ConfigManager PropertyChanged: device.callagent.callcount
3902 NOT Feb 18 09:55:18.095162 (1830-2062) JAVA-System P7-display MQThread|cip.sipcc.SipCcAdapter: - propertyChanged(): device.callagent.callcount
3903 NOT Feb 18 09:55:18.098275 (1830-2080) JAVA-configmgr MQThread|cip.cfg.Config: - [propertyChanged()] propName:"device.callagent.callcount"
3904 NOT Feb 18 09:55:18.098794 (1830-2076) JAVA-callagent MQThread|cip.callagent.Line:? - prevLineState=INACTIVE, lineState=OFFHOOK
3905 NOT Feb 18 09:55:18.106394 (1830-2075) JAVA-mediacontrolui MQThread|cip.msui.MediaControl:callState - The CallControlState isOFFHOOK
3906 NOT Feb 18 09:55:18.107462 (1830-2075) JAVA-mediacontrolui MQThread|cip.msui.MediaControl$3:execute - [TR] executing the state-event handlerFunction=NoAudioSessionStartedFunc
3907 NOT Feb 18 09:55:18.164352 sh:
3908 NOT Feb 18 09:55:18.164871 ioctl_test: not found
3909 NOT Feb 18 09:55:18.173142 (1830-2075) JAVA-mediacontrolui MQThread|cip.msui.MediaControl:? - ignore updating local volume 7 for remote config, audioPath=AUDIO_PATH_HANDSET_OUT
3910 NOT Feb 18 09:55:18.202380 (1830-2088) JAVA-System P9-sidecar MQThread|cip.sidecar.Sidecar:In the PropertyChanged - PHONE_IDLE_PROPERTY_NAME and it is false
3911 NOT Feb 18 09:55:18.206073 (1830-2076) JAVA-callagent MQThread|cip.callagent.Call:subscribe - callhandle=cip.callagent.CallNumberHandle@1
3912 NOT Feb 18 09:55:18.206500 (1830-2076) JAVA-callagent MQThread|cip.callagent.Line:? - setActiveCall - enter
3913 NOT Feb 18 09:55:18.207111 (1830-2076) JAVA-callagent MQThread|cip.callagent.Call:activate - callhandle=cip.callagent.CallNumberHandle@1
3914 NOT Feb 18 09:55:18.208148 (1830-2076) JAVA-callagent MQThread|cip.callagent.Line:? - callState=OFFHOOK
3915 NOT Feb 18 09:55:18.209034 (1830-2076) JAVA-callagent MQThread|cip.callagent.Line:? - setActiveCall - enter
3916 NOT Feb 18 09:55:18.211323 (1830-2076) JAVA-callagent MQThread|cip.callagent.Call:activate - callhandle=cip.callagent.CallNumberHandle@1
3917 NOT Feb 18 09:55:18.262658 (1830-2080) JAVA-configmgr MQThread|ConfigManager:? - --->ConfigManager PropertyChanged: device.callagent.callcount
3918 NOT Feb 18 09:55:18.263207 (1830-2080) JAVA-configmgr MQThread|ConfigManager:? - <---ConfigManager PropertyChanged: device.callagent.callcount
3919 NOT Feb 18 09:55:18.263604 (1830-2080) JAVA-configmgr MQThread|cip.cfg.Config: - [propertyChanged()] propName:"device.callagent.callcount"
3920 NOT Feb 18 09:55:18.271234 (1830-2076) JAVA-callagent MQThread|cip.callagent.Line:? - prevLineState=OFFHOOK, lineState=OFFHOOK
3921 NOT Feb 18 09:55:18.272211 (1830-2075) JAVA-mediacontrolui MQThread|cip.msui.MediaControl:callState - The CallControlState isOFFHOOK
3922 NOT Feb 18 09:55:18.272882 (1830-2075) JAVA-mediacontrolui MQThread|cip.msui.MediaControl$3:execute - [TR] executing the state-event handlerFunction=ActiveHandsetSessionStartedFunc
3923 NOT Feb 18 09:55:18.402044 (1830-2062) JAVA-System P7-display MQThread|cip.sipcc.SipCcAdapter: - propertyChanged(): device.callagent.callcount
02-18-2022 09:26 AM
By the description it does sound like the issue is with the switch in the cradle, you can try different FW to rule out that's the issue.
02-18-2022 09:39 AM
Thanks for the reply. What do you mean by "FW"?
Thanks again!
Melissa
02-18-2022 09:51 AM
firmware
02-18-2022 09:41 AM
Nevermind: Firmware. I did. I updated it. It does seem to be a java issue. I have tried to post the phone messages but they don't seem to post.
3876 NOT Feb 18 09:55:17.453013 (1856-1856) metmand-update_sessionid: Received NULL or Invalid Type
3877 NOT Feb 18 09:55:17.458812 (1830-2097) JAVA-SIPCC-SIP_CC_PROV: ccsnap_gen_callEvent: CallEvent=CREATED, CallState=OFFHOOK
3878 NOT Feb 18 09:55:17.474103 (1830-2098) JAVA-SIPCC-SIP_MSG_RECV: ccsip_dump_recv_msg_info: <10.X.X.X:0 >:200 OK
3879 NOT Feb 18 09:55:17.477369 (1830-2100) JAVA-SIPCC-MED_API: lsm_util_start_tone: Enter, line=1, call_id=1.
3880 ERR Feb 18 09:55:17.492476 (1817-1817) ms-ACOUSTINTER.aecSetAfeGainParams NULL AFE REG setting pointer !!!
3881 NOT Feb 18 09:55:17.545612 (1830-2100) JAVA-SIPCC-FSM: 1/1, fsm_change_state: DEF: IDLE -> COLLECTING_INFO
3882 NOT Feb 18 09:55:17.545856 (1830-2100) JAVA-SIPCC-DCSM: dcsm_update_gsm_state: 1 : DCSM_READY --> DCSM_READY
3883 NOT Feb 18 09:55:17.545978 (1830-2100) JAVA-SIPCC-GSM: 1/1, sm_process_event: DEF :(IDLE:OFFHOOK )
3884 NOT Feb 18 09:55:17.560354 (1856-1856) metmand-update_sessionid: Local UUID:
3885 NOT Feb 18 09:55:17.560537 (1856-1856) metmand-update_sessionid: Remote UUID:
3886 NOT Feb 18 09:55:17.560750 (1856-1856) metmand-update_sessionid: Received NULL or Invalid Type
3887 NOT Feb 18 09:55:17.560842 (1856-1856) metmand-update_sessionid: Local UUID:
3888 NOT Feb 18 09:55:17.560933 (1856-1856) metmand-update_sessionid: Remote UUID:
3889 NOT Feb 18 09:55:17.795452 (1830-2076) JAVA-callagent MQThread|cip.callagent.Line:? - callState=OFFHOOK
3890 NOT Feb 18 09:55:17.997161 (1830-2097) JAVA-SIPCC-SIP_CC_PROV: ccappUpdateSessionData: CCAPP -> GSM: Call_STATE:. state=21, privacy=0, attr=0, cause=5, instance=0
3891 NOT Feb 18 09:55:17.998107 (1830-2097) JAVA-SIPCC-SIP_CC_PROV: ccsnap_gen_callEvent: CallEvent=STATE, CallState=WAITINGFORDIGITS
3892 NOT Feb 18 09:55:18.008576 (1830-2076) JAVA-callagent MQThread|cip.callagent.Line:attention: set reviseendCall - theCall.reviseendCall=true
3893 ERR Feb 18 09:55:18.027102 (1830-2062) JAVA-System P7-display MQThread - ### reset call list, newSize=1
3894 NOT Feb 18 09:55:18.042850 (1830-2076) JAVA-callagent MQThread|cip.callagent.Line:? - setActiveCall - enter
3895 NOT Feb 18 09:55:18.072119 (1830-2076) JAVA-callagent MQThread|cip.callagent.Call:activate - callhandle=cip.callagent.CallNumberHandle@1
3896 NOT Feb 18 09:55:18.076361 (1830-2080) JAVA-configmgr MQThread|ConfigManager:? - --->ConfigManager PropertyChanged: device.callagent.callcount
3897 NOT Feb 18 09:55:18.076880 (1830-2080) JAVA-configmgr MQThread|ConfigManager:? - <---ConfigManager PropertyChanged: device.callagent.callcount
3898 NOT Feb 18 09:55:18.078101 (1830-2080) JAVA-configmgr MQThread|cip.cfg.Config: - [propertyChanged()] propName:"device.callagent.callcount"
3899 NOT Feb 18 09:55:18.083381 (1830-2062) JAVA-System P7-display MQThread|cip.sipcc.SipCcAdapter: - propertyChanged(): device.callagent.callcount
3900 NOT Feb 18 09:55:18.087501 (1830-2080) JAVA-configmgr MQThread|ConfigManager:? - --->ConfigManager PropertyChanged: device.callagent.callcount
3901 NOT Feb 18 09:55:18.088295 (1830-2080) JAVA-configmgr MQThread|ConfigManager:? - <---ConfigManager PropertyChanged: device.callagent.callcount
3902 NOT Feb 18 09:55:18.095162 (1830-2062) JAVA-System P7-display MQThread|cip.sipcc.SipCcAdapter: - propertyChanged(): device.callagent.callcount
3903 NOT Feb 18 09:55:18.098275 (1830-2080) JAVA-configmgr MQThread|cip.cfg.Config: - [propertyChanged()] propName:"device.callagent.callcount"
3904 NOT Feb 18 09:55:18.098794 (1830-2076) JAVA-callagent MQThread|cip.callagent.Line:? - prevLineState=INACTIVE, lineState=OFFHOOK
3905 NOT Feb 18 09:55:18.106394 (1830-2075) JAVA-mediacontrolui MQThread|cip.msui.MediaControl:callState - The CallControlState isOFFHOOK
3906 NOT Feb 18 09:55:18.107462 (1830-2075) JAVA-mediacontrolui MQThread|cip.msui.MediaControl$3:execute - [TR] executing the state-event handlerFunction=NoAudioSessionStartedFunc
04-09-2024 10:46 PM
Hi mvarazlic, we have a whole bunch of phones on the same firmware mentioned that have suddenly started having similar offhook issues, and only able to get audio when the speaker button is pressed. Curious as to which firmware version you updated to that helped resolve? Thanks!
06-26-2024 08:49 AM
I think I spoke too soon. The phone started doing it again. Sorry that I just saw your message.
Discover and save your favorite ideas. Come back to expert answers, step-by-step guides, recent topics, and more.
New here? Get started with these tips. How to use Community New member guide