cancel
Showing results for 
Search instead for 
Did you mean: 
cancel
2680
Views
15
Helpful
8
Replies

Cisco 7811 randomly going off hook

mvarazlic
Level 1
Level 1

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.

 

 

8 Replies 8

mvarazlic
Level 1
Level 1

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

mvarazlic
Level 1
Level 1

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

Jaime Valencia
Cisco Employee
Cisco Employee

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.

HTH

java

if this helps, please rate

Thanks for the reply.  What do you mean by "FW"?

 

Thanks again!

Melissa

firmware

HTH

java

if this helps, please rate

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

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!

I think I spoke too soon.  The phone started doing it again.  Sorry that I just saw your message.