Re: Cisco 8831 not registering

raiwekhoe · ‎05-30-2018

Hi,

I inherited a CME (10.5 on Cisco 2901 router)configuration that already have 8831 installed and working. We got some additional phones including 8831. Those additional phones were added and they are working but the additional 8831 is not registering. Voice register dn and pool 5 is the 8831 that is not working and the Voice register dn and pool 6 belongs to the working 8831. Can someone help me with this.

Product ID is CP-8831-BASE-S-EUV01

voice register dn 5
number 105
pickup-call any-group
pickup-group 10
name Boardrm2
label 105
!
voice register dn 6
number 106
pickup-call any-group
name BOARDROOM
label 106

!
voice register pool 5
busy-trigger-per-button 2
id mac AC44.F212.B57D
type 8831
number 1 dn 5
cor incoming user-prefix default
voice-class codec 1
username board1 password 1234
!
voice register pool 6
busy-trigger-per-button 2
id mac AC44.F212.A5CE
type 8831
number 1 dn 6
cor incoming user-prefix default
voice-class codec 1
username board password board
description BOARDROOM
!

voice register pool-type 8831
description CiscoSIPPhone8831
reference-pooltype 7821
!
voice register pool-type 8861
description Cisco Sip Phone 8861
reference-pooltype 9971
!
voice register global
mode cme
source-address 192.168.5.1 port 5060
max-dn 100
max-pool 20
load 8861 sip88xx.11-0-1-11
timezone 22
external-ring Bellcore-dr3
url authentication http://192.168.5.1/CCMCIP/authenticate.asp
create profile sync 0032562142294958
conference hardware
video
!

R0g22 · ‎05-31-2018

First advise would be to upgrade the IOS to 15.7 so that you are running CME 12.0 and support these phones natively. Currently you are doing a fast-track.

raiwekhoe · ‎05-31-2018

Thanks Nipun... before I attempt to upgrade, I will need to answer why a similar phone, with the same specs, is working with the current version.

R0g22 · ‎05-31-2018

Well we can troubleshoot that's fine. Enable the following and send me the logs -

debug ccsip message
debug ccsip error
debug ccsip non-call
debug voice register event
debug voice register error

raiwekhoe · ‎05-31-2018

Thanks Nipun... see below

LROUTER#sh logging
Syslog logging: enabled (0 messages dropped, 6 messages rate-limited, 117 flushes, 0 overruns, xml disabled, filtering disabled)

No Active Message Discriminator.

No Inactive Message Discriminator.

Console logging: level debugging, 5501 messages logged, xml disabled,
filtering disabled
Monitor logging: level debugging, 0 messages logged, xml disabled,
filtering disabled
Buffer logging: level debugging, 5222 messages logged, xml disabled,
filtering disabled
Exception Logging: size (4096 bytes)
Count and timestamp logging messages: disabled
Persistent logging: disabled

No active filter modules.

Trap logging: level informational, 117 message lines logged
Logging Source-Interface: VRF Name:

Log Buffer (8192 bytes):
: VOICE_REG_POOL: key(106) contact(192.168.5.43:5060) add to contact table
May 31 15:58:48.075: VOICE_REG_POOL: No entry for (106) found in contact tableVOICE_REG_POOL pool_tag(6), dn_tag(6)

May 31 15:58:48.075: VOICE_REG_POOL: key(106) contact(192.168.5.43) added to contact table
May 31 15:58:48.075: VOICE_REG_POOL: key(192.168.5.43) contact(106) add to srst contact table
May 31 15:58:48.075: VOICE_REG_POOL: No entry for (192.168.5.43) found in srst contact table
May 31 15:58:48.075: VOICE_REG_POOL: key(192.168.5.43) contact(106) added to srst contact table
May 31 15:58:48.075: VOICE_REG_POOL pool->tag(6), dn->tag(6), submask(1)

May 31 15:58:48.075: VOICE_REG_POOL: Creating param container for dial-peer 40002.
May 31 15:58:48.075: voice_reg_send_cfwdall_update: cfwdall_update type(8831), DN(106), IP(192.168.5.43), port(5060),transport(1), line(0), CfwdAll Num() vrfid(0)VOICE_REG_POOL pool->tag(6), dn->tag(6), submask(1)
VOICE_REG_POOL pool_tag(6), dn_tag(6)
snr local <0> rs <0> ats <0> mobility <0> dn_tag <6> numlist <0>
update_dialpeer_attributes CME_PARAM_ALL
update_dialpeer_attributes CME_SNR_CALLING_NUMBER_LOCAL
update_dialpeer_attributes CME_SNR_RING_STOP
update_dialpeer_attributes CME_SNR_ANSWER_TOO_SOON
update_dialpeer_attributes CME_SNR_MOBILITY
update_dialpeer_attributes timeout 0 delay 0 snrmobile peerTage <40002>

May 31 15:58:48.079: VOICE_REG_POOL: Created dial-peer entry of type 0
May 31 15:58:48.079: VOICE_REG_POOL: Registration successful for 106, registration id is 47
May 31 15:58:48.079: voice_reg_get_phone_index_from_contact: Trying to match key 106
May 31 15:58:48.079: voice_reg_get_phone_index_from_contact:key MATCHED!!
May 31 15:58:48.079: VOICE REGISTER POOL-6 has registered. Name:SEPAC44F212A5CE IP:192.168.5.43 DeviceType:Phone

May 31 15:58:48.079: VOICE_REG_POOL: Pool[6]: service-control (reset type: 2) message sent to sip:106@192.168.5.43

May 31 15:58:48.079: //-1/xxxxxxxxxxxx/SIP/Error/ccsip_reg_locate_in_pattern_table:
***No entry found in Destination pattern Table
May 31 15:58:48.079: //-1/xxxxxxxxxxxx/SIP/Error/sipSPIGetDialogParamStr:
Invalid parameters
May 31 15:58:48.083: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
REFER sip:106@192.168.5.43:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.5.1:5060;branch=z9hG4bKFA57C
From: <sip:192.168.5.1>;tag=5815B8-2127
To: <sip:106@192.168.5.43>
Call-ID: 56AF50D1-642211E8-82589F0D-4967DB62@192.168.5.1
CSeq: 101 REFER
Max-Forwards: 70
Contact: <sip:192.168.5.1:5060>
User-Agent: Cisco-SIPGateway/IOS-15.4.3.M1
Timestamp: 1527782328
Require: norefersub
Refer-To: cid:577272
Content-ID: <577272>
Content-Type: application/x-cisco-remotecc-request+xml
Referred-By: <sip:192.168.5.1>
Content-Length: 139

<x-cisco-remotecc-request><cfwdallupdate><fwdaddress></fwdaddress><tovoicemail>off</tovoicemail></cfwdallupdate></x-cisco-remotecc-request>
May 31 15:58:48.083: //292/56AEB4A98257/SIP/Msg/ccsipDisplayMsg:
Sent:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.5.43:5060;branch=z9hG4bK12b691ed
From: <sip:106@192.168.5.1>;tag=ac44f212a5ce004c2e6219ec-4d7de074
To: <sip:106@192.168.5.1>;tag=5815B4-D09
Date: Thu, 31 May 2018 15:58:48 GMT
Call-ID: ac44f212-a5ce000f-5b008e09-2fc95953@192.168.5.43
Server: Cisco-SIPGateway/IOS-15.4.3.M1
CSeq: 154 REGISTER
Supported: X-cisco-cme-sis-1.0.0
Supported: X-cisco-srtp-fallback
Contact: <sip:106@192.168.5.43:5060>;expires=600;x-cisco-newreg
Expires: 600
Content-Type: application/x-cisco-remotecc-response+xml
Content-Length: 188

<?xml version="1.0" encoding="UTF-8"?><x-cisco-remotecc-response><response><code>200</code><optionsind><cmeoption><b2bconf/></cmeoption></optionsind></response></x-cisco-remotecc-response>
May 31 15:58:48.083: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
NOTIFY sip:106@192.168.5.43:5060;transport=udp SIP/2.0
Via: SIP/2.0/UDP 192.168.5.1:5060;branch=z9hG4bKFB1614
From: <sip:106@192.168.5.1>;tag=5815B8-268
To: <sip:106@192.168.5.43>
Call-ID: 56AF50D1-642211E8-82599F0D-4967DB62@192.168.5.1
CSeq: 101 NOTIFY
Max-Forwards: 70
Date: Thu, 31 May 2018 15:58:48 GMT
User-Agent: Cisco-SIPGateway/IOS-15.4.3.M1
Event: service-control
Subscription-State: active
Contact: <sip:106@192.168.5.1:5060>
Content-Type: text/plain
Content-Length: 190

action=check-version
RegisterCallId={ac44f212-a5ce000f-5b008e09-2fc95953@192.168.5.43}
ConfigVersionStamp={0032562142294955}
DialplanVersionStamp={}
SoftkeyVersionStamp={0032562142294955}

May 31 15:58:48.579: //0/000000000000/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 200 OK
Via: SIP/2.0/UDP 192.168.5.1:5060;branch=z9hG4bKFB1614
From: <sip:106@192.168.5.1>;tag=5815B8-268
To: <sip:106@192.168.5.43>
Call-ID: 56AF50D1-642211E8-82599F0D-4967DB62@192.168.5.1
Date: Thu, 31 May 2018 15:58:47 GMT
CSeq: 101 NOTIFY
Content-Length: 0

May 31 15:58:48.579: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:
REFER sip:106@192.168.5.43:5060 SIP/2.0
Via: SIP/2.0/UDP 192.168.5.1:5060;branch=z9hG4bKFA57C
From: <sip:192.168.5.1>;tag=5815B8-2127
To: <sip:106@192.168.5.43>
Call-ID: 56AF50D1-642211E8-82589F0D-4967DB62@192.168.5.1
CSeq: 101 REFER
Max-Forwards: 70
Contact: <sip:192.168.5.1:5060>
User-Agent: Cisco-SIPGateway/IOS-15.4.3.M1
Timestamp: 1527782328
Require: norefersub
Refer-To: cid:577272
Content-ID: <577272>
Content-Type: application/x-cisco-remotecc-request+xml
Referred-By: <sip:192.168.5.1>
Content-Length: 139

<x-cisco-remotecc-request><cfwdallupdate><fwdaddress></fwdaddress><tovoicemail>off</tovoicemail></cfwdallupdate></x-cisco-remotecc-request>
May 31 15:58:48.595: //0/000000000000/SIP/Msg/ccsipDisplayMsg:
Received:
SIP/2.0 481 Call Leg/Transaction Does Not Exist
Via: SIP/2.0/UDP 192.168.5.1:5060;branch=z9hG4bKFA57C
From: <sip:192.168.5.1>;tag=5815B8-2127
To: <sip:106@192.168.5.43>
Call-ID: 56AF50D1-642211E8-82589F0D-4967DB62@192.168.5.1
Date: Thu, 31 May 2018 15:58:47 GMT
Warning: 399 Bad Request - 'Subscription Terminated'
CSeq: 101 REFER
Content-Length: 0

May 31 15:58:48.599: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Received:
REFER sip:192.168.5.1 SIP/2.0
Via: SIP/2.0/UDP 192.168.5.43:5060;branch=z9hG4bK0328a80f
From: <sip:ac44f212a5ce@192.168.5.43>;tag=ac44f212a5ce004e6e54b22c-138d38ba
To: <sip:192.168.5.1>
Call-ID: ac44f212-a5ce0005-3d240159-2819fcfa@192.168.5.43
Date: Thu, 31 May 2018 15:58:47 GMT
CSeq: 1000 REFER
User-Agent: Cisco-CP8831G/9.3.2
Expires: 10
Max-Forwards: 70
Contact: <sip:ac44f212a5ce@192.168.5.43:5060>
Require: norefersub
Referred-By: <sip:ac44f212a5ce@192.168.5.43>
Refer-To: cid:416384cd@192.168.5.43
Content-Id: <416384cd@192.168.5.43>
Allow: ACK,BYE,CANCEL,INVITE,NOTIFY,OPTIONS,REFER,REGISTER,UPDATE,SUBSCRIBE
Content-Length: 1295
Content-Type: application/x-cisco-alarm+xml
Content-Disposition: session;handling=required

<?xml version="1.0" encoding="UTF-8"?>
<x-cisco-alarm>
<Alarm Name="LastOutOfServiceInformation">
<ParameterList>
<String name="DeviceName">SEPAC44F212A5CE</String>
<String name="DeviceIPv4Address">192.168.5.25/24</String>
<String name="IPv4DefaultGateway">192.168.5.1</String>
<String name="DeviceIPv6Address"></String>
<String name="IPv6DefaultGateway"></String>
<String name="ModelNumber">CP-8831</String>
<String name="NeighborIPv4Address">0.0.0.0</String>
<String name="NeighborIPv6Address"></String>
<String name="NeighborDeviceID"></String>
<String name="NeighborPortID"></String>
<Enum name="DHCPv4Status">1</Enum>
<Enum name="DHCPv6Status">0</Enum>
<Enum name="TFTPCfgStatus">2</Enum>
<Enum name="DNSStatusUnifiedCM1">0</Enum>
<Enum name="DNSStatusUnifiedCM2">0</Enum>
<Enum name="DNSStatusUnifiedCM3">0</Enum>
<String name="VoiceVLAN">4095</String>
<String name="UnifiedCMIPAddress">192.168.5.1</String>
<String name="LocalPort">0</String>
<String name="TimeStamp">1527782307821</String>
<Enum name="ReasonForOutOfService">16</Enum>
<String name="LastProtocolEventSent">Sent:REGISTER sip:192.168.5.1 SIP/2.0 Cseq:143 REGISTER CallId:ac44f212-a5ce0006-23f408b0-03c527df@192.168.5.25</String>
<String name="LastProtocolEventReceived"></String>
</ParameterList>
</Alarm>
</x-cisco-alarm>
May 31 15:58:48.603: //-1/xxxxxxxxxxxx/SIP/Msg/ccsipDisplayMsg:
Sent:

Ratheesh Kumar · ‎05-31-2018

Hi there
Could you try upgrading the firmware of the phone. Cross match the non-working phone firmware with the working one.

Hope this Helps
Cheers
Rath!

***Please rate helpful posts***

raiwekhoe · ‎05-31-2018

Hi Nipun,

I did a factory reset on the 8831 phone and it started working.

Thanks

R0g22 · ‎05-31-2018

I guess you fixed it before I could check the logs. Great!

Ratheesh Kumar · ‎05-31-2018

Hi there

Just curious to know the current load on the phone. Could you share the firmware version please

Hope this Helps

Cheers
Rath!

***Please rate helpful posts***