Getting 7916 to work on 7965

[OliverDarvall]

Hi Guys,

I have the following:

- Call Manager - Version 7.1.5

- 7965G - Firmware 9-3-1

- 7916 - Firmware 1-0-4-2

- SCCP based

I can not get the 7916 module to work, it only shows the Cisco logo and all lights are amber. I have Googled myself to a standstill and now need some help please.

I have tried various combinations of settings. I have enabled the 12 and/or 24 button module on the 7965G settings, I have added/removed the  Module Load Name for the 7916, I have upgraded/downgraded the firmware versions, I have factory reset the 7965 almost to death, I have deleted/recreated the phone configuration, etc etc etc ...

The one very weird thing that I have noticed is that when I apply the Config to the Phone, that not all settings as I have set them in CM, seems to "transfer" to the phone. For instance, even though I enable SSH, I can not SSH into the phone. And I set the Web Access to HTTPS only, but can still access the standard HTTP interface. There are other settings also that does not "transfer" ...

Also, when I Apply the Config and I check the Status on the phone itself, the module never shows as permanently states "Not Configured" and the module firmware is blank.

Any ideas and help please ?!

[Carlo Poggiarelli]

Hi Oliver

The other step I can suggest you is to downgrade 7965 firmware to an earlier version a see if it makes the difference.

Regards

Carlo

[OliverDarvall]

I downgraded the firmware to 8-3-5 on the 7965, and to 1-0-3 on the 7916, but no luck yet. The phone console logs look slightly different and at least there is no TVS error anymore, but I still have a CTL failure of sorts, which I hope will solve my problem. I also tried deleting the CTL/ITL (as you will see in the logs to follow), but still nothing. This is the logs after the CTL delete :

WRN 07:32:52.547654 JVM: Startup Module Loader|cip.mmgr.ds:? - [MediaMgrSM]: Unhandled Event, State = StateOnHook Event = EventServicesTxStop
NOT 07:32:52.553697 tftpClient: request server 0 ---> 192.168.1.15 
NOT 07:32:52.569036 tftpClient: request server 1 --->  
NOT 07:33:02.031964 SECD: deleteCTL: starting CTL delete
WRN 07:33:02.048460 SECD: ctlFactoryReset(): CTL file deleted, table cleared
WRN 07:33:02.049103 SECD: WARN:deleteCTL: ** CTL deleted ** 
WRN 07:33:02.050190 SECD: WARN:resetSecMode: ** WARN ** resetting sec mode to NONE (non-secure)
NOT 07:33:02.052553 SECD: clearCapfList: CAPF table cleared
NOT 07:33:02.053196 SECD: clearSRSTList: cleared all SRST entries
WRN 07:33:02.571485 JVM: Startup Module Loader|cip.sccp.cj:? - Read exception: java.io.EOFException 
 Close(d) Connection ...
ERR 07:33:02.576566 JVM: 07:33:02|java.io.EOFException
     at java.io.DataInputStream.readInt()I(Unknown Source)
     at cip.io.i.readInt()I(Unknown Source)
     at cip.sccp.au.a()Lcip/sccp/bq;(Unknown Source)
     at cip.sccp.cj.e()V(Unknown Source)
     at cip.sys.l.run()V(Unknown Source)
     at java.lang.Thread.startup(Z)V(Unknown Source)
NOT 07:33:02.639526 DHCP: Restart - delay = 0 
NOT 07:33:02.666513 DHCP:  dhcpSendReq: status 0x12300000 
NOT 07:33:02.667124 DHCP: Sending Request... 
NOT 07:33:02.669782 DHCP: ACK received 
NOT 07:33:02.670846 DHCP: Succeeded 
NOT 07:33:02.685173 DHCP: IP Address -- 192.168.1.102 
NOT 07:33:02.685758 DHCP: Subnet Mask - 255.255.255.0 
NOT 07:33:02.686248 DHCP: Default Gwy - 192.168.1.80 
NOT 07:33:02.691075 PAE: SIGIPCFG received...
NOT 07:33:02.692699 ESP: send ADMIN, logging = 1, shell = 0, ipconfig = 1 
WRN 07:33:02.715534 SECD: WARN:getCTLInfo: ** phone has no CTL
WRN 07:33:02.720202 SECD: WARN:getCTLInfo: ** phone has no CTL
NOT 07:33:02.727334 CDP-D: catchipcfg getdhcpinfo IP: c0a80166  Chng:1
NOT 07:33:02.736157 tftpClient: request server 0 ---> 192.168.1.15 
NOT 07:33:02.751883 tftpClient: request server 1 --->  
NOT 07:33:02.764274 tftpClient: request server 0 ---> 192.168.1.15 
NOT 07:33:02.766370 ESP: server 0 = 192.168.1.15 
NOT 07:33:02.778788 tftpClient: request server 1 --->  
NOT 07:33:02.780984 ESP: server 1 =  
NOT 07:33:02.819387 SECD: updateCTL: starting CTL update
NOT 07:33:02.821473 SECD: ctlRequestFile: Socket 7 connected to /usr/tmp/tftpClientSock 
NOT 07:33:02.822308 SECD: ctlRequestFile: Request CTLSEPB4E9B08CE56D.tlv 
NOT 07:33:02.823468 tftpClient: tftp request rcv'd from /usr/tmp/ctlSock, srcFile = CTLSEPB4E9B08CE56D.tlv, dstFile = /usr/tmp/CTLFile.tlv 
NOT 07:33:02.824936 tftpClient: auth server - tftpList[0] = 192.168.1.15 
NOT 07:33:02.825511 tftpClient: look up server - 0 
WRN 07:33:02.827319 SECD: WARN:lookupCTL: CTL update in progress, no old CTL, assume TFTP 192.168.1.15 NONSECURE
NOT 07:33:02.829651 tftpClient: secVal = 0xa 
NOT 07:33:02.833166 tftpClient: 192.168.1.15 is a NONsecure server 
NOT 07:33:02.833745 tftpClient: temp retval = SRVR_NONSECURE, keep looking 
NOT 07:33:02.834220 tftpClient: retval = 10 
NOT 07:33:02.834689 tftpClient: Non secure file requested  
NOT 07:33:02.842084 TFTP: [25]:Requesting CTLSEPB4E9B08CE56D.tlv from 192.168.1.15 
NOT 07:33:02.850964 TFTP: [25]:Finished --> rcvd 4952 bytes 
NOT 07:33:02.854126 SECD: ctlRequestFile: tftp Status 0 rcv'd 
NOT 07:33:02.857011 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15
NOT 07:33:02.857645 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)
NOT 07:33:02.858194 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)
NOT 07:33:02.858906 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15
NOT 07:33:02.859490 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)
NOT 07:33:02.860056 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)
NOT 07:33:02.890260 SECD: validate_file_envelope: File sign verify SUCCESS; header length <304>
NOT 07:33:02.907404 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15
NOT 07:33:02.907945 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)
NOT 07:33:02.908480 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)
NOT 07:33:02.909197 SECD: ctlUpdateFromFile: CTL parse to table: CTL_SUCCESS
NOT 07:33:02.909725 SECD: ctlUpdateFromFile: Updating master CTL table
NOT 07:33:02.912370 SECD: updateCTL: finished CTL update
NOT 07:33:02.913015 SECD: updateCTL: ** got updated CTL **
NOT 07:33:02.913572 SECD: updateCTL: phone had no CTL, now got one!
NOT 07:33:02.914113 SECD: updateCTL: changing security mode to default AUTH
NOT 07:33:02.914702 SECD: setSecMode: sec mode set to AUTH (was NONE)
NOT 07:33:02.972184 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15
NOT 07:33:02.972812 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)
NOT 07:33:02.973380 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)
NOT 07:33:02.996690 tftpClient: tftp request rcv'd from /usr/tmp/tftp, srcFile = SEPB4E9B08CE56D.cnf.xml, dstFile = /usr/cache/SEPB4E9B08CE56D.cnf.xml 
NOT 07:33:03.003839 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15
NOT 07:33:03.004470 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)
NOT 07:33:03.005011 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)
NOT 07:33:03.012569 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15
NOT 07:33:03.013205 SECD: parseHdr(): hdr ver 1.2 (knows upto 2.0)
NOT 07:33:03.013804 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)
NOT 07:33:03.021856 tftpClient: auth server - tftpList[0] = 192.168.1.15 
NOT 07:33:03.022440 tftpClient: look up server - 0 
NOT 07:33:03.024320 SECD: lookupCTL: TFTP 192.168.1.15 in CTL, MUST get sgn files
NOT 07:33:03.026523 tftpClient: secVal = 0x9 
NOT 07:33:03.027092 tftpClient: 192.168.1.15 is a secure server 
NOT 07:33:03.027584 tftpClient: retval = SRVR_SECURE 
NOT 07:33:03.028077 tftpClient: Secure file requested 
NOT 07:33:03.028605 tftpClient: authenticated file approved - add .sgn -- SEPB4E9B08CE56D.cnf.xml.sgn  
NOT 07:33:03.037005 TFTP: [24]:Requesting SEPB4E9B08CE56D.cnf.xml.sgn from 192.168.1.15 
NOT 07:33:03.048447 TFTP: [24]:Finished --> rcvd 7510 bytes 
NOT 07:33:03.052805 SECD: verifyFile: sgn-verify , 'name'[SEPB4E9B08CE56D.cnf.xml.sgn]
NOT 07:33:03.054625 SECD: parseHdr(): start of pad ('T' 0x0d) at TLV 15
NOT 07:33:03.055207 SECD: parseHdr(): skipping 3 trail bytes (pad and/or unknown TLVs)
ERR 07:33:03.055953 SECD: EROR:verifyFile: sgn verify file failed , errclass 8, errcode 19 (signer not in CTL)
ERR 07:33:03.056959 SECD: EROR:verifyFile: verify FAILED, 
NOT 07:33:03.059429 tftpClient: authorize file = 13, isEncr = 0 
WRN 07:33:03.066294 SECD: WARN:cancelCapfOp: CAPF not in use, user cancel ignored
NOT 07:33:03.066945 SECD: clearCapfList: CAPF table cleared
ERR 07:33:03.069586 JVM: GETHOSTBYNAME: Empty Parameter
NOT 07:33:03.072258 SECD: clearCapfList: CAPF table cleared
NOT 07:33:03.072916 SECD: addCapfEntry: adding new CAPF entry from CTL:
NOT 07:33:03.073475 SECD:    CTL  tbl idx : 2
NOT 07:33:03.074002 SECD:    CAPF tbl idx : 0
NOT 07:33:03.074512 SECD:        ip addr      : 192.168.1.15
NOT 07:33:03.075015 SECD:        dns name     : TWIGGY
NOT 07:33:03.075536 SECD:        port         : 3804 (default)
NOT 07:33:03.076058 SECD:        priority     : 0 
NOT 07:33:03.090417 SECD: setSecMode: sec mode set to NONE (was AUTH)
NOT 07:33:03.164050 JVM: Startup Module Loader|cip.cfg.r:? - DELETE ConfigFile:(scratchpad/SEPB4E9B08CE56D.cnf.xml)WAS UNSUCCESSFUL

[Carlo Poggiarelli]

Hi Oliver.

Does the 7965G register to CUCM?

Are you able to try if 7916 module works on other phone model if you have one?

Let me know

Regards

Carlo

[OliverDarvall]

Jip, 7965 registers fine. This is the only sidecar capable phone we have.

[Carlo Poggiarelli]

Did you remember to change load name of 7916 module on IP phone configuration page?

Now should be B016-1-0-3.

Carlo

[Carlo Poggiarelli]

Hi Oliver.

Any news from the sidecar?

Carlo

[OliverDarvall]

No progress at all, sigh ! I noticed this morning though that my CTL and the Certificates loaded on the server are out of sync. So I am going to try and resync everything using the CTL Client, but of course for some or other weird reason the CTL Client does not want to install on my PC or Server. The fun just continues ...

[OliverDarvall]

Right, so if it is raining/snowing/hurricaning where you are, it is because I got it working !

I battled to get the CTL Client and eTokens working, but eventually got them working in a clean XP installation on a Virtualbox. I then Updated the CTL file on the server and deleted the CTL on the phone. After the phone reset itself the phone and sidecar started working immediately.

[Marwan Urabi]

Hi Oliver ,

I have same problem , I try from couple of days to make it work , but no luck , I have CUCME 8.6 on Cisco 2811 ,

the 7916 100% work correctly also the 7965 phone work and register normally , can you inform me any idea to

make it work, I have 2nd Cisco 7965 and 7916 and work correctly .

Best Regards

[OliverDarvall]

Marwan, my problem was a mismatch in the security certificates on the server. I used the CTL client to recreate and resync the certificates and then reset the phones, all came right after that. Have a look at some of my posts above with some more details.

[Marwan Urabi]

Hi,

Thank you for your reply ,

I check the CTL it's not configure at my system , I use CME on Cisco 2811 , not CUCM , I also try erase the config from the phone and see the CTL information on phone it's not config , I also try change the phone 3 deferent Firmware no luck to get it work .

Best Regards