[cisco-voip] 7942G phone Registration problems

Johnny.Crothers at datacraft.co.nz Johnny.Crothers at datacraft.co.nz
Mon Mar 21 18:59:46 EDT 2011 

Hi everyone,

 

Got a little problem with 7942G phones – these are all new phones to a new CUCM 8.5 cluster, 7940’s have been configured and work fine, 7942G’s however do not, they bounce their registration between the primary and secondary CUCM servers and never actually settle down. I’ve increased the StationKeepAlive to 60 seconds from 30 to see if this helps, but no joy.

 

I’ve got below some detailed traces but there doesn’t seem to be anything obvious except for a “Socket Broken” However apart from just being a network issue I cant see anything else, and no sniffing traces as of yet, will look at that shortly.

 

Any one seen this before? Also, I’m seeing between the registrations “Rejected” on the CUCM, however the device type is correct, its configured for SCCP and the status messages are showing that the phones have correctly found their configuration file “SEPxxxxxxxxxxxx”.

 

Any help would be cool!!

 

10:54:15.130 |Processing StationReg. regCount: 1 DeviceName=SEP8CB64F57D879, TCPPid = [2.100.9.13295], IPAddr=172.16.15.124, Port=50608, Device Controller=[2,51,11739]|2,100,50,1.115445^172.16.15.124^SEP8CB64F57D879

10:54:15.130 |StationD:    (0011739) wait_register_StationRegister - Check MAC address & protocolVer & Call Limit (mac=, protocolVer=0x85720014, limit=200)|2,100,50,1.115445^172.16.15.124^SEP8CB64F57D879

10:54:15.131 |StationD:    (0011739) ClusterSecurityMode = (0) DeviceSecurityMode = (1)|2,100,50,1.115445^172.16.15.124^SEP8CB64F57D879

10:54:15.131 |Protocol match: SCCP|2,100,50,1.115445^172.16.15.124^SEP8CB64F57D879

10:54:15.131 |StationD:    (0011739) DND settings from TSP:  status=0, option=0, ringSetting=5|2,100,50,1.115445^172.16.15.124^SEP8CB64F57D879

10:54:15.131 |SMDMSharedData::findAliasRegInfo - AliasName = bed84000-d7cc-9e85-a2a1-e700d9d8cf62 not in AliasInfo hashmap|2,100,50,1.115445^172.16.15.124^SEP8CB64F57D879

10:54:15.131 |DeviceManager::star_DmPidReq - RequestedName=bed84000-d7cc-9e85-a2a1-e700d9d8cf62 LookupName=bed84000-d7cc-9e85-a2a1-e700d9d8cf62|2,100,50,1.115445^172.16.15.124^SEP8CB64F57D879

10:54:15.131 |SMDMSharedData::findRemoteDeviceAny - Key=bed84000-d7cc-9e85-a2a1-e700d9d8cf62 not in RemoteDeviceInfo hashmap|2,100,50,1.115445^172.16.15.124^SEP8CB64F57D879

10:54:15.131 |LineManager::getLineControlStatIndex - will use id = 23|2,100,50,1.115445^172.16.15.124^SEP8CB64F57D879

10:54:15.131 |StationD:    (0011739) Device SEP8CB64F57D879, --INFO-- line_register_LineRegisterRes registering line=1 and maxline=1|2,100,50,1.115445^172.16.15.124^SEP8CB64F57D879

10:54:15.131 |<MN::Directory Number><MV::533826>|2,100,50,1.115445^*^SEP8CB64F57D879

10:54:15.131 |StationD:    (0011739) sendCapabilitiesReq Send StationOutputRegisterAck|2,100,50,1.115445^172.16.15.124^SEP8CB64F57D879

10:54:15.131 |StationD:    (0011739) RegisterAck keepAliveInterval=30 dateTemplate='D/M/YA' secondaryKeepAliveInterval=60.|2,100,50,1.115445^172.16.15.124^SEP8CB64F57D879

10:54:15.131 |StationD:    (0011739) Send StationOutputCapabilitiesReq in sendCapabilitiesReq|2,100,50,1.115445^172.16.15.124^SEP8CB64F57D879

10:54:15.131 |StationD:    (0011739) CapabilitiesReq.|2,100,50,1.115445^172.16.15.124^SEP8CB64F57D879

10:54:15.131 |DeviceManager:star_DeviceStart Name=SEP8CB64F57D879 Key=61c50ebf-a298-f973-47f3-b5e46eede920 RegisterDevice=49 DualModeFlag=0 Propapate=1|2,100,50,1.115445^172.16.15.124^SEP8CB64F57D879

10:54:15.131 |mMobileDevice = 0|2,100,50,1.115445^172.16.15.124^SEP8CB64F57D879

10:54:15.131 |DeviceManager:star_DeviceStart The propogation is set to true |2,100,50,1.115445^172.16.15.124^SEP8CB64F57D879

10:54:15.131 |SMDMSharedData::processPendingSub - cepn = 61c50ebf-a298-f973-47f3-b5e46eede920, devicePid=(2,51,11739), localdevice=1|2,100,50,1.115445^172.16.15.124^SEP8CB64F57D879

10:54:15.131 |DeviceManager:star_DeviceStart Name=SEP8CB64F57D879 Key=61c50ebf-a298-f973-47f3-b5e46eede920 OUT|2,100,50,1.115445^172.16.15.124^SEP8CB64F57D879

10:54:15.155 |StationInit: (0011739) InboundStim - Undefined Stimulus Device Message - MessageID: 0x2|2,100,50,1.115446^172.16.15.124^SEP8CB64F57D879

10:54:15.156 |StationInit: (0011739) InboundStim - StationButtonTemplateReqMessageID: Msg Size(received, defined) = 8, 4|2,100,50,1.115447^172.16.15.124^SEP8CB64F57D879

10:54:15.156 |StationInit: (0011739) ButtonTemplateReq.|2,100,50,1.115447^172.16.15.124^SEP8CB64F57D879

10:54:15.157 |StationInit: (0011739) SoftKeyTemplateReq.|2,100,50,1.115448^172.16.15.124^SEP8CB64F57D879

10:54:15.158 |StationInit: (0011739) SoftKeySetReq.|2,100,50,1.115449^172.16.15.124^SEP8CB64F57D879

10:54:15.159 |StationInit: (0011739) ConfigStatReq.|2,100,50,1.115450^172.16.15.124^SEP8CB64F57D879

10:54:15.165 |StationInit: (0011739) InboundStim - StationCapabilitiesResMessageID: Msg Size(received, defined) = 152, 296|2,100,50,1.115451^172.16.15.124^SEP8CB64F57D879

10:54:15.165 |StationInit: (0011739) CapabilitiesRes capCount=9 caps= 257(1) |2,100,50,1.115451^172.16.15.124^SEP8CB64F57D879

10:54:15.165 |StationD:    (0011739) Create Stats for Station device: CHC_CLC Logged Out Phone failed. ErrCode= -1, statIndex= 6|2,100,50,1.115451^172.16.15.124^SEP8CB64F57D879

10:54:15.165 |StationD:    (0011739) ApplicationID: 11739, DataLength: 16, LineInstance: 0,                                              CallReference: 0, tcp Length = 60|2,100,50,1.115451^172.16.15.124^SEP8CB64F57D879

10:54:15.165 |StationD:    (0011739) ButtonTemplate buttonTemplate= 0|2,100,50,1.115447^172.16.15.124^SEP8CB64F57D879

10:54:15.166 |StationD:    (0011739) SoftKeyTemplateRes softKeyTemplate=?., Protocol Ver =85720014|2,100,50,1.115448^172.16.15.124^SEP8CB64F57D879

10:54:15.166 |StationD:    (0011739) 0-th The Softkey Template from DB to Phone: OutputStationSoftKeyTemplate: Offset=0, Count=20,totalCount=20, Label(or Tag) & Event:, €X (33)|2,100,50,1.115448^172.16.15.124^SEP8CB64F57D879

10:54:15.166 |StationD:    (0011739) SoftKeySetRes softKeySets=?.|2,100,50,1.115449^172.16.15.124^SEP8CB64F57D879

10:54:15.166 |StationD:    (0011739) 0-th SoftkeySet: star_StationOutputSoftKeySetRes: Offset = 0, softKeySetCount = 12, totalCount = 12|2,100,50,1.115449^172.16.15.124^SEP8CB64F57D879

10:54:15.166 |StationD:    (0011739) SoftkeySets[0]: ,0 (0)|2,100,50,1.115449^172.16.15.124^SEP8CB64F57D879

10:54:15.166 |StationD:    (0011739) SoftkeySets[1]: ,0 (0)|2,100,50,1.115449^172.16.15.124^SEP8CB64F57D879

10:54:15.166 |StationD:    (0011739) SoftkeySets[2]: ,0 (0)|2,100,50,1.115449^172.16.15.124^SEP8CB64F57D879

10:54:15.166 |StationD:    (0011739) SoftkeySets[3]: ,0 (0)|2,100,50,1.115449^172.16.15.124^SEP8CB64F57D879

10:54:15.166 |StationD:    (0011739) SoftkeySets[4]: ,0 (0)|2,100,50,1.115449^172.16.15.124^SEP8CB64F57D879

10:54:15.166 |StationD:    (0011739) SoftkeySets[5]: ,0 (0)|2,100,50,1.115449^172.16.15.124^SEP8CB64F57D879

10:54:15.166 |StationD:    (0011739) SoftkeySets[6]: ,0 (0)|2,100,50,1.115449^172.16.15.124^SEP8CB64F57D879

10:54:15.166 |StationD:    (0011739) SoftkeySets[7]: ,0 (0)|2,100,50,1.115449^172.16.15.124^SEP8CB64F57D879

10:54:15.166 |StationD:    (0011739) SoftkeySets[8]: ,0 (0)|2,100,50,1.115449^172.16.15.124^SEP8CB64F57D879

10:54:15.166 |StationD:    (0011739) SoftkeySets[9]: ,0 (0)|2,100,50,1.115449^172.16.15.124^SEP8CB64F57D879

10:54:15.166 |StationD:    (0011739) SoftkeySets[10]: ,0 (0)|2,100,50,1.115449^172.16.15.124^SEP8CB64F57D879

10:54:15.166 |StationD:    (0011739) SoftkeySets[11]: ,0 (0)|2,100,50,1.115449^172.16.15.124^SEP8CB64F57D879

10:54:15.166 |StationD:    (0011739) - Send StationOutputConfigStat in restart0_StationConfigStatReq|2,100,50,1.115450^172.16.15.124^SEP8CB64F57D879

10:54:15.166 |StationD:    (0011739)   TCPPid = [2.100.9.13295] ConfigStatMessage sid=? userName='' serverName='PTWLGSUB1' numberOfLines=1 numberOfSpeedDials=0.|2,100,50,1.115450^172.16.15.124^SEP8CB64F57D879

10:54:15.166 |StationD:    (0011739) SelectSoftKeys instance=0 reference=0 softKeySetIndex=0 validKeyMask=ffffffff.|2,100,50,1.115449^172.16.15.124^SEP8CB64F57D879

10:54:15.166 |StationD:    (0011739) DisplayPromptStatus timeOut=0 Status='€ ' content='Your current options' line=0 CI=0 ver=85720014.|2,100,50,1.115449^172.16.15.124^SEP8CB64F57D879

10:54:15.166 |LineControl(10643) - SEP8CB64F57D879 registered, CEPN=61c50ebf-a298-f973-47f3-b5e46eede920, deviceType=1|2,100,50,1.115451^172.16.15.124^SEP8CB64F57D879

10:54:15.166 |No associated device or user.|2,100,50,1.115451^172.16.15.124^SEP8CB64F57D879

10:54:15.166 |LineControl::sendSNFNotifyIndForPresenceWithAlerting mPrecenceWithAlertingChangeNotifySubscribed=0, calllist#=0|2,100,50,1.115451^172.16.15.124^SEP8CB64F57D879

10:54:15.166 |DeviceManager:star_DeviceStart Name=533826:6167566a-505f-d0e8-8524-6e6014be7714 Key=bed84000-d7cc-9e85-a2a1-e700d9d8cf62 RegisterDevice=50 DualModeFlag=0 Propapate=1|2,100,50,1.115451^172.16.15.124^SEP8CB64F57D879

10:54:15.166 |mMobileDevice = 0|2,100,50,1.115451^172.16.15.124^SEP8CB64F57D879

10:54:15.166 |DeviceManager:star_DeviceStart The propogation is set to true |2,100,50,1.115451^172.16.15.124^SEP8CB64F57D879

10:54:15.166 |SMDMSharedData::findRemoteDeviceList - Key=bed84000-d7cc-9e85-a2a1-e700d9d8cf62 not in RemoteDeviceInfo hashmap|2,100,50,1.115451^172.16.15.124^SEP8CB64F57D879

10:54:15.166 |SMDMSharedData::processPendingSub - cepn = bed84000-d7cc-9e85-a2a1-e700d9d8cf62, devicePid=(2,157,10643), localdevice=1|2,100,50,1.115451^172.16.15.124^SEP8CB64F57D879

10:54:15.166 |DeviceManager:star_DeviceStart Name=533826:6167566a-505f-d0e8-8524-6e6014be7714 Key=bed84000-d7cc-9e85-a2a1-e700d9d8cf62 OUT|2,100,50,1.115451^172.16.15.124^SEP8CB64F57D879

10:54:15.194 |EndPointUnregistered - An endpoint has unregistered Device name:SEP8CB64F57D879 Device IP address:172.16.15.124 Protocol:SCCP Device type:434 Device description:CHC_CLC Logged Out Phone Reason Code:6 IPAddressAttributes:3 App ID:Cisco CallManager Cluster ID:PT8.5Cluster Node ID:PTWLGSUB1|AlarmSEP8CB64F57D879^*^SEP8CB64F57D879

10:54:15.193 |StationInit:   TCPPid = [2.100.9.13295]Socket Broken. DeviceName=SEP8CB64F57D879,IPAddr=172.16.15.124, Port=0xc5b0, Device Controller=[2,51,11739]|2,100,50,1.115449^172.16.15.124^SEP8CB64F57D879

10:54:15.193 |Closing Station connection DeviceName=SEP8CB64F57D879, TCPPid = [2.100.9.13295], IPAddr=172.16.15.124, Port=50608, Device Controller=[2,51,11739]|2,100,50,1.115449^172.16.15.124^SEP8CB64F57D879

10:54:15.193 |StationInit:   Table Statistics|2,100,50,1.115449^172.16.15.124^SEP8CB64F57D879

10:54:15.193 |    mStationHandleInfo=35,   mDeviceNameToTcpPid=31|2,100,50,1.115449^172.16.15.124^SEP8CB64F57D879

10:54:15.194 |StationD:    (0011739) Delete Stats Instance for Station failed - ErrCode= -1, statIndex= 6|2,100,50,1.115449^172.16.15.124^SEP8CB64F57D879

10:54:15.194 |LineControl::sendSNFNotifyIndForPresenceWithAlerting mPrecenceWithAlertingChangeNotifySubscribed=0, calllist#=0|2,100,50,1.115449^172.16.15.124^SEP8CB64F57D879

10:54:15.194 |LineControl - Delete index =23|2,100,50,1.115449^172.16.15.124^SEP8CB64F57D879

10:54:15.194 |LineManager::star_LineUnregisterInd - release id = 23|2,100,50,1.115449^172.16.15.124^SEP8CB64F57D879

10:54:15.194 |DeviceManager:star_DeviceStop Name=SEP8CB64F57D879 Key=61c50ebf-a298-f973-47f3-b5e46eede920 RegisterDevice=50 DualModeFlag=0|2,100,50,1.115449^172.16.15.124^SEP8CB64F57D879

10:54:15.194 |SMDMSharedData::findLocalDevice - Name=SEP8CB64F57D879 Key=61c50ebf-a298-f973-47f3-b5e46eede920 isActvie=1 Pid=(2,51,11739) found|2,100,50,1.115449^172.16.15.124^SEP8CB64F57D879

10:54:15.194 |SMDMSharedData::notifyUnRegisterAndDeleteSubscribeeState cepn[61c50ebf-a298-f973-47f3-b5e46eede920]|2,100,50,1.115449^172.16.15.124^SEP8CB64F57D879

10:54:15.194 |SMDMSharedData::findRemoteDeviceAny - Key=61c50ebf-a298-f973-47f3-b5e46eede920 not in RemoteDeviceInfo hashmap|2,100,50,1.115449^172.16.15.124^SEP8CB64F57D879

10:54:15.194 |SMDMSharedData::notifyUnRegisterAndDeleteSubscribeeState not found |2,100,50,1.115449^172.16.15.124^SEP8CB64F57D879

10:54:15.194 |PendingResetHelper::getCurrTimerId - timerId[0]|2,100,50,1.115449^172.16.15.124^SEP8CB64F57D879

10:54:15.194 |DeviceManager:star_DeviceStop Name=533826:6167566a-505f-d0e8-8524-6e6014be7714 Key=bed84000-d7cc-9e85-a2a1-e700d9d8cf62 RegisterDevice=49 DualModeFlag=0|2,100,50,1.115449^172.16.15.124^SEP8CB64F57D879

10:54:15.194 |SMDMSharedData::findLocalDevice - Name=533826:6167566a-505f-d0e8-8524-6e6014be7714 Key=bed84000-d7cc-9e85-a2a1-e700d9d8cf62 isActvie=1 Pid=(2,157,10643) found|2,100,50,1.115449^172.16.15.124^SEP8CB64F57D879

10:54:15.194 |SMDMSharedData::notifyUnRegisterAndDeleteSubscribeeState cepn[bed84000-d7cc-9e85-a2a1-e700d9d8cf62]|2,100,50,1.115449^172.16.15.124^SEP8CB64F57D879

10:54:15.194 |SMDMSharedData::findRemoteDeviceAny - Key=bed84000-d7cc-9e85-a2a1-e700d9d8cf62 not in RemoteDeviceInfo hashmap|2,100,50,1.115449^172.16.15.124^SEP8CB64F57D879

10:54:15.194 |SMDMSharedData::notifyUnRegisterAndDeleteSubscribeeState not found |2,100,50,1.115449^172.16.15.124^SEP8CB64F57D879

 

Kind Regards,

 

Johnny

 



*****************************************************************************
This email and all contents are subject to the following disclaimer:

http://www.datacraft-asia.com/disclaimer
*****************************************************************************
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://puck.nether.net/pipermail/cisco-voip/attachments/20110322/ce7c33e3/attachment.html>

More information about the cisco-voip mailing list