07-29-2022 09:06 AM
Followed the (quite horrible) upgrade documentation to the letter, but upgraded system will not function. Cannot figure out why.
HA Replication is working fine. But no matter what I have tried, the system will not come up and register with CUCM.
I removed and replaced the TSP, but that didn't change anything.
Interestingl, I see this in the logs (below). It seems that something is going wrong with either encryption of something or a credential is wrong. However, the connection to the CUCM server tests good via the interface. TSP was reinstalled with the correct credentials. What am I missing here?
29/07/2022 11:55:38.894 "T:21" "I" [6871]:SVM: EnqueueInboundMessage(): RemoteID:10.0.3.41:61117; PROCESS:CTI_REGISTRATION
29/07/2022 11:55:38.894 "T:21" "V" [6871]:SVM: EnqueueInboundMessage(): QUEUE_COUNT:0;
29/07/2022 11:55:38.894 "T:21" "D" [-2]:HandleSocketSvrCustomDataReceived():Process:CTI_REGISTRATION;Status:esRequest;Trans:1;
29/07/2022 11:55:38.895 "T:10" "D" [6871]:SVM: PROCESSQUEUE_WORK:DEQUEUE:TYPE:CTI_REGISTRATION;REMOTEIP:10.0.3.41:61117;QUEUE_COUNT:1
29/07/2022 11:55:38.895 "T:10" "D" [6871]:SVM: ProcessRegistrationRequest(GINVID:6871):Connection 10.0.3.41:61117]
29/07/2022 11:55:38.895 "T:10" "D" [6871]:SVM: ProcessRegistrationOpenRequest():username: ----, password: ----
29/07/2022 11:55:38.895 "T:10" "D" [-11]:CON: CRYPT CryptoComputeKEK.In,passLen:32,saltLen:32,ic:16367,hexFlag:1
29/07/2022 11:55:38.933 "T:10" "D" [-11]:CON: CRYPT HashController.Hash_PBKDF2.PKCS5_PBKDF2_HMAC()=1
29/07/2022 11:55:38.933 "T:10" "D" [-11]:CON: CRYPT HashController.Compute_KEK.malloc(33) = 0x1d5b53c0
29/07/2022 11:55:38.933 "T:10" "D" [-11]:CON: CRYPT CryptoComputeKEK.malloc(65),pDst=0x1d69d5e0
29/07/2022 11:55:38.934 "T:10" "D" [-11]:CON: CRYPT CryptoConversion::ToHexCryptoAPI.CryptBinaryToStringA.nLenOut=64,ret=1
29/07/2022 11:55:38.934 "T:10" "D" [-11]:CON: CRYPT CryptoEnvironment.CryptoComputeKEK.keyEncryptionKey.Free:0x1d5b53c0
29/07/2022 11:55:38.934 "T:10" "D" [-11]:CON: CRYPT CryptoComputeKEK.Out
29/07/2022 11:55:38.934 "T:10" "D" [-11]:CON: CRYPT CryptoFreeBlock:0x1d69d5e0,size:64
29/07/2022 11:55:38.934 "T:10" "D" [-11]:CON: ConstructingValues:,ComputeKEK()=CRYPTO_SUCCESS
29/07/2022 11:55:38.934 "T:10" "D" [-11]:CON: CRYPT ReadKeyFromFileAsDecrypted.In,KEK_len:64
29/07/2022 11:55:38.934 "T:10" "D" [-11]:CON: CRYPT AESController.ReadFromFile, File Opened Successfully
29/07/2022 11:55:38.934 "T:10" "D" [-11]:CON: CRYPT AESController.ReadFromFile.malloc(109) = 0x1af6aed0
29/07/2022 11:55:38.934 "T:10" "D" [-11]:CON: CRYPT AESController.ReadFromFile():1,bytesRead:108
29/07/2022 11:55:38.935 "T:10" "D" [-11]:CON: CRYPT AESController.ReadFromFile, File Closed Successfully
29/07/2022 11:55:38.935 "T:10" "D" [-11]:CON: CRYPT CryptoConversion::FromBase64Crypto.CryptStringToBinaryA.nLenOut=32,ret=1
29/07/2022 11:55:38.935 "T:10" "D" [-11]:CON: CRYPT CryptoConversion::FromBase64Crypto.CryptStringToBinaryA.nLenOut=16,ret=1
29/07/2022 11:55:38.935 "T:10" "D" [-11]:CON: CRYPT CryptoConversion::FromBase64Crypto.CryptBinaryToStringA.nLenOut=151
29/07/2022 11:55:38.935 "T:10" "D" [-11]:CON: CRYPT CryptoConversion::FromBase64Crypto.malloc(152), pDst=0x1af14f20
29/07/2022 11:55:38.935 "T:10" "D" [-11]:CON: CRYPT AESController.DecryptData_AES.In,ciphertext_len =80,cipher_type=3
29/07/2022 11:55:38.935 "T:10" "D" [-11]:CON: CRYPT AESController.DecryptData_AES.EVP_DecryptInit_ex()=1
29/07/2022 11:55:38.935 "T:10" "D" [-11]:CON: CRYPT AESController.DecryptData_AES.outByteCiphertext.malloc(80) = 0x1b068c70
29/07/2022 11:55:38.935 "T:10" "D" [-11]:CON: CRYPT AESController.DecryptData_AES.EVP_DecryptUpdate()=1
29/07/2022 11:55:38.935 "T:10" "D" [-11]:CON: CRYPT AESController.DecryptData_AES.EVP_DecryptFinal_ex()=1
29/07/2022 11:55:38.935 "T:10" "D" [-11]:CON: CRYPT AESController.DecryptData_AES.Out
29/07/2022 11:55:38.935 "T:10" "D" [-11]:CON: CRYPT CryptoEnvironment.ReadKeyFromFileAsDecrypted.AESEncrypedData.Free:0x1af6aed0
29/07/2022 11:55:38.935 "T:10" "D" [-11]:CON: CRYPT CryptoEnvironment.ReadKeyFromFileAsDecrypted.base64Data.Free:0x1af14f20
29/07/2022 11:55:38.935 "T:10" "D" [-11]:CON: CRYPT ReadKeyFromFileAsDecrypted.Out
29/07/2022 11:55:38.935 "T:10" "D" [-11]:CON: CRYPT CryptoFreeBlock:0x1b068c70,size:64
29/07/2022 11:55:38.935 "T:10" "D" [-11]:CON: ReadKeyFromFile:,ReadKeyFromFile()=CRYPTO_SUCCESS
29/07/2022 11:55:38.935 "T:10" "D" [-11]:CON: CRYPT CryptoAES_DecryptData.In,ciphertext_len:44,key_len:64,iv_len:32
29/07/2022 11:55:38.935 "T:10" "D" [-11]:CON: CRYPT CryptoConversion::FromBase64Crypto.CryptStringToBinaryA.nLenOut=32,ret=1
29/07/2022 11:55:38.935 "T:10" "D" [-11]:CON: CRYPT CryptoConversion::FromBase64Crypto.CryptStringToBinaryA.nLenOut=16,ret=1
29/07/2022 11:55:38.935 "T:10" "D" [-11]:CON: CRYPT CryptoConversion::FromBase64Crypto.CryptBinaryToStringA.nLenOut=63
29/07/2022 11:55:38.935 "T:10" "D" [-11]:CON: CRYPT CryptoConversion::FromBase64Crypto.malloc(64), pDst=0x1d69dbd0
29/07/2022 11:55:38.935 "T:10" "D" [-11]:CON: CRYPT AESController.DecryptData_AES.In,ciphertext_len =32,cipher_type=3
29/07/2022 11:55:38.935 "T:10" "D" [-11]:CON: CRYPT AESController.DecryptData_AES.EVP_DecryptInit_ex()=1
29/07/2022 11:55:38.935 "T:10" "D" [-11]:CON: CRYPT AESController.DecryptData_AES.outByteCiphertext.malloc(32) = 0x1d5b4f10
29/07/2022 11:55:38.935 "T:10" "D" [-11]:CON: CRYPT AESController.DecryptData_AES.EVP_DecryptUpdate()=1
29/07/2022 11:55:38.935 "T:10" "D" [-11]:CON: CRYPT AESController.DecryptData_AES.EVP_DecryptFinal_ex()=0
29/07/2022 11:55:38.935 "T:10" "D" [-11]:CON: CRYPT AESController.DecryptData_AES.EVP_DecryptFinal_ex.ERR_get_error:101077092,ERR_error_string:error:06065064:digital envelope routines:EVP_DecryptFinal_ex:bad decrypt
29/07/2022 11:55:38.935 "T:10" "D" [-11]:CON: CRYPT AESController.DecryptData_AES.EVP_DecryptFinal_ex.translateErrorCode:-25
29/07/2022 11:55:38.936 "T:10" "D" [-11]:CON: CRYPT AESController.DecryptData_AES.outPlaintext.free(32) = 0x1d5b4f10
29/07/2022 11:55:38.936 "T:10" "D" [-11]:CON: CRYPT CryptoEnvironment.CryptoAES_DecryptData.AESEncrypedData.Free:0x1d69dbd0
29/07/2022 11:55:38.936 "T:10" "D" [-11]:CON: CRYPT CryptoAES_DecryptData.Out
29/07/2022 11:55:38.936 "T:10" "D" [-11]:CON: AES_DecryptData:,AES_DecryptData=CRYPTO_INVALID_KEY_OR_IV
29/07/2022 11:55:38.936 "T:10" "W" [6871]:SendFailureMessage(CAUSECODE:invalidCredentials;REMOTEIP:10.0.3.41:61117;)
29/07/2022 11:55:38.936 "T:10" "I" [1]:EnqueueServerMessage:Count:0, EventProcess:CTI_FAILURE, EventStatus:CTI_FAILURE
29/07/2022 11:55:38.936 "T:10" "I" [6871]:SendServerXmlMessage(PROCESS:CTI_FAILURE;STATUS:esReply;INVID:6871;RIP:10.0.3.41:61117;TansID:1):WIN:<CTIServer event="Event.Failure"><Cause code="83">invalidCredentials</Cause><Error>Invalid username and/or password</Error></CTIServer>
29/07/2022 11:55:38.936 "T:10" "W" [6871]:SVM: ProcessRegistrationOpenRequest():Invalid credentials
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