Got it

TE Desktop - Issues with SIP registration via TLS

Latest reply: Jan 3, 2019 07:03:57 1026 3 0 0 0
Issue description:


Customer encounters an issue when he is registering new TE Desktop Participants. He has created some new unmanageable participants on the SMC configured them the same as other pre-existing devices but when the clients try to connect, they receive an error about being unable to connect to the server. Customer has been able to test it on his personal computer and can login wihtout a problem on both internal and external addresses.  He is using TLS as a transport protocol and port 5061. Both PC used for testing were connected internally and used the same account and worked fine however he still has a problem with a client connecting from the internet.

Handling process;

In order to troubleshoot and address the issue we have  requested the firmware version of the SMC and of the TE Desktop client. Customer provided the following information:


SMC Version Huawei SMC2.0 V500R002C00SPCk00

TE Desktop: V6.6.1.117

In order to attempt to replicate the issue encountered with user 80058 we have uninstalled the TE Desktop; deleted all the files from the installation path and reinstalled it again. After this operation the issue has been reproduced and received the server error failure.


After we have skipped login and then log our and log in again with the username 80058 using the exact same settings SIP registration has been performed succesfully. We have checked the status on SMC and the participant was registered.


To further investigate while the issue could be reproduced we have gathered the following information:


 - Gathered the signaling diagnostics on the SC

 - Gathered the TE Desktop logs

 - Gathered the device running logs on the SC

 - Gathered the wireshark trace made on the laptop/PC where the TE Desktop is installed


We have analyzed the gathered logs from the TE Desktop and the SC along with our upper level of support and have found that the firmware version of V5R2 of SC does not support STG data.

 

First the TE Desktop will check the network module if it is Media X or SMC; in our case as it can be seen below it is SMC. Then it will get the STG data from SC.

 

On the SC signaling diagnostics we filtered by port 443 and could not find any traffic.

 

From the network capture performed from the PC that had the TE Desktop installed; the SC hangs up the session with a RST message because the version does not support the STG data (version mismatch):


TE Desktop - Issues with SIP registration via TLS-2711011-1


[2018-07-04 08:37.659][P:8372/T:11060][Inf][uportal_right.cpp:2391 UPortalRightAuthorize]Start Authorize server: 80.194.85.72, type: 2

[2018-07-04 08:37.659][P:8372/T:11060][Dbg][uportal_right.cpp:853 SetAuthParam]Password Authorize

[2018-07-04 08:37.659][P:8372/T:11060][Inf][uportal_right.cpp:2409 UPortalRightAuthorize]TSP_NETB_DnsCleanBuffer

[2018-07-04 08:37.659][P:8372/T:11060][Inf][uportal_right.cpp:2559 SMCAuthorize]start smc authorize server:80.194.85.72, port:443, proxyServer:80.194.85.72, port:443, userId:8

[2018-07-04 08:37.659][P:8372/T:11060][Dbg][uportal_dns.cpp:360 CheckIsDomain]Not Domain, IPv4: 1213579856

[2018-07-04 08:37.659][P:8372/T:11060][Inf][login_data.cpp:1044 LoginDataCreateTPKTBody]create TPKT usCmdId:0x10c usBodyLen:35

[2018-07-04 08:37.192][P:8372/T:11060][Inf][login_common.cpp:150 GetErrnoFromHttpRetExt]GetErrnoFromHttpRetExt StatusCode:408

 

 

We believe that there is a mismatch between the version of TE Desktop and the SC and as such we have the below proposition:

 

-          Since by upgrading the whole VC infrastructure implies a very high workload at the moment the best solution would be to attempt to try to find out the root cause of why does the TE desktop does not register at first and only after performing the below actions is working:

 

Basically we tried to log in and it failed with a server error at first. Then log in as a guest (skip log in) and collected the logs and after we logged out and connected again and we have managed to register successfully.



Root Cause:


After we have advised with our upper level of support we have been advised that this issue is caused by the TE Desktop that when attempting to register for the first time it checks the network mode set if it is Media X or SMC and first in priority is Media X.

 

When you skip the login it no longer checks the MediaX network mode and inspects the SMC network mode and it connects without any issues.


Below is the log extracted from the TE Desktop where we first attempted to register.


2018-07-04 08:37.538( 3848)[INFO]{uc::model::login::CLogin::LoginAuthOnMediaX} ENTER 

2018-07-04 08:37.538( 3848)[INFO]{uc::model::login::CLogin::setTLSParam} ENTER 

2018-07-04 08:37.538( 3848)[INFO]{uc::model::login::CLogin::setTLSParam} strCertPath = ./\cert\root_cert_huawei.pem

2018-07-04 08:37.538( 3848)[INFO]{uc::model::login::CLogin::setTLSParam} LEAVE 

2018-07-04 08:37.538( 3848)[INFO]{uc::model::login::CLogin::requestMediaXVersion} ENTER 

2018-07-04 08:37.538( 3848)[INFO]{uc::model::login::CLogin::requestMediaXVersion} LEAVE 

2018-07-04 08:37.538( 3848)[INFO]{uc::model::login::CLogin::LoginAuthOnMediaX} request mediax version result :1

2018-07-04 08:37.538( 3848)[INFO]{uc::model::login::CLogin::LoginAuthOnMediaX} LEAVE 

2018-07-04 08:37.538( 3848)[INFO]{uc::model::UCClientImpl::SignIn} LEAVE 

2018-07-04 08:37.878( 6432)[INFO]{uc::model::conversation::loginGetVersionOnMediaxEnvResultMI} ENTER 

2018-07-04 08:37.878( 6432)[ERROR]{uc::model::conversation::loginGetVersionOnMediaxEnvResultMI} get version on mediax failed , ulParam1 = 8, user default version = V6R6C00

2018-07-04 08:37.878( 6432)[INFO]{uc::model::login::CLogin::SetMediaXVersion} MediaX version is V6R6C00

2018-07-04 08:37.878( 6432)[INFO]{uc::model::login::CLogin::requestMediaxLoginAuth} ENTER 

2018-07-04 08:37.878( 6432)[INFO]{uc::model::login::CLogin::GetLoginParamOnMediaXEnvironment} ENTER 

2018-07-04 08:37.878( 6432)[INFO]{uc::model::PasswordWrapper::GetPwd} KM_GetAesKey, ret=0

2018-07-04 08:37.878( 6432)[INFO]{uc::model::PasswordWrapper::GetPwd} cryptlen = 69 iResult = 0

2018-07-04 08:37.878( 6432)[INFO]{uc::model::PasswordUtil::DecPwd} ENTER 

2018-07-04 08:37.878( 6432)[INFO]{uc::model::PasswordUtil::DecPwd} KM_GetAesKey, ret=0

2018-07-04 08:37.878( 6432)[INFO]{uc::model::PasswordUtil::DecPwd} LEAVE 

2018-07-04 08:37.878( 6432)[INFO]{uc::model::login::CLogin::GetLoginParamOnMediaXEnvironment} LEAVE 

2018-07-04 08:37.878( 6432)[INFO]{uc::model::UCClientImpl::SetMediaXTokenTypeInfo} ENTER 

2018-07-04 08:37.878( 6432)[INFO]{uc::model::UCClientImpl::SetMediaXTokenTypeInfo} The success token request id(1)'s operation is 0

2018-07-04 08:37.878( 6432)[INFO]{uc::model::UCClientImpl::SetMediaXTokenTypeInfo} LEAVE 

2018-07-04 08:37.878( 6432)[INFO]{uc::model::login::CLogin::requestMediaxLoginAuth} The success token request id(1)'s operation is login operate 

2018-07-04 08:37.878( 6432)[INFO]{uc::model::login::CLogin::requestMediaxLoginAuth} LEAVE 

2018-07-04 08:37.878( 6432)[INFO]{uc::model::login::CLogin::SetMediaXVersion} requestMediaxLoginAuth, ret = 1

2018-07-04 08:37.879( 6432)[INFO]{uc::model::conversation::loginGetVersionOnMediaxEnvResultMI} LEAVE 

2018-07-04 08:37.380( 6432)[INFO]{uc::model::conversation::loginGetParamOnMediaxEnvResultMI} result->8, request id 1

2018-07-04 08:37.380( 6432)[INFO]{uc::model::UCClientImpl::handleGetLoginParamResultOnMediaXEnvNotify} ENTER 

2018-07-04 08:37.380( 6432)[INFO]{uc::model::UCClientImpl::handleMediaXLoginOperateParamResult} ENTER 

2018-07-04 08:37.380( 6432)[INFO]{uc::model::UCClientImpl::handleMediaXLoginOperateParamResult} The login request id(1) is matched with 1, isDetecting = 0

2018-07-04 08:37.380( 6432)[INFO]{uc::model::UCClientImpl::handleLoginMediaXFail} Login failed, because get mediax login params failed.

2018-07-04 08:37.380( 6432)[INFO]{uc::model::UCClientImpl::SignIn} ENTER 

2018-07-04 08:37.397( 6432)[INFO]{CConfigSetting::RefreshDefaultRootCert} TLSCerType = HUAWEI, refresh

2018-07-04 08:37.404( 6432)[INFO]{uc::model::conference::ConferenceMgrImpl::ClearPersonalVMR} ENTER 

2018-07-04 08:37.404( 6432)[INFO]{uc::model::conference::ConferenceMgrImpl::ClearPersonalVMR} LEAVE 

2018-07-04 08:37.404( 6432)[INFO]{uc::model::login::CLogin::configHttpProxyInfo} The http proxy is disabled. Set proxy = 0

2018-07-04 08:37.404( 6432)[INFO]{uc::model::conference::ConferenceMgrImpl::configHttpProxyInfo} The http proxy is disabled. set proxy  = 0

2018-07-04 08:37.404( 6432)[INFO]{uc::model::login::CLogin::GetLoginParamOnSMCEnvironment} ENTER 

2018-07-04 08:37.404( 6432)[INFO]{uc::model::login::CLogin::SetSipDomainParam} TE Desktop deal siprui:

2018-07-04 08:37.404( 6432)[INFO]{uc::model::conference::ConferenceMgrImpl::GetAuthInfo} ENTER 

2018-07-04 08:37.404( 6432)[INFO]{uc::model::PasswordWrapper::GetPwd} KM_GetAesKey, ret=0

2018-07-04 08:37.404( 6432)[INFO]{uc::model::PasswordWrapper::GetPwd} cryptlen = 69 iResult = 0

2018-07-04 08:37.404( 6432)[INFO]{uc::model::conference::ConferenceMgrImpl::GetAuthInfo} KM_GetAesKey, ret=0

2018-07-04 08:37.404( 6432)[INFO]{uc::model::conference::ConferenceMgrImpl::GetAuthInfo} [ConferenceMgr]Create Conf account = ******

2018-07-04 08:37.404( 6432)[INFO]{uc::model::conference::ConferenceMgrImpl::GetAuthInfo} LEAVE 

2018-07-04 08:37.405( 6432)[INFO]{uc::model::login::CLogin::GetLoginParamOnSMCEnvironment} Set http proxy address : 10.0.7.3, server address : 10.0.7.3, account : ******

2018-07-04 08:37.405( 6432)[INFO]{uc::model::login::CLogin::setTLSParam} ENTER 

2018-07-04 08:37.405( 6432)[INFO]{uc::model::login::CLogin::setTLSParam} strCertPath = ./\cert\root_cert_huawei.pem

2018-07-04 08:37.405( 6432)[INFO]{uc::model::login::CLogin::setTLSParam} LEAVE 

2018-07-04 08:37.405( 6432)[INFO]{uc::model::login::CLogin::GetLoginParamOnSMCEnvironment} LEAVE 

2018-07-04 08:37.405( 6432)[INFO]{uc::model::UCClientImpl::SignIn} The STG request(id=1)' is success.

2018-07-04 08:37.405( 6432)[INFO]{uc::model::UCClientImpl::SignIn} LEAVE 

2018-07-04 08:37.405( 6432)[INFO]{uc::model::UCClientImpl::handleMediaXLoginOperateParamResult} LEAVE 

2018-07-04 08:37.405( 6432)[INFO]{uc::model::UCClientImpl::handleGetLoginParamResultOnMediaXEnvNotify} mediax token operate is : 0, bResult is : 0

2018-07-04 08:37.405( 6432)[INFO]{uc::model::UCClientImpl::handleGetLoginParamResultOnMediaXEnvNotify} LEAVE 

2018-07-04 08:37.906( 6432)[INFO]{uc::model::conversation::loginGetParamOnSMCEnvResultMI} result->8, request id 1

2018-07-04 08:37.906( 6432)[INFO]{uc::model::UCClientImpl::handleGetLoginParamResultOnSMCEnvNotify} ENTER 

2018-07-04 08:37.906( 6432)[INFO]{uc::model::UCClientImpl::handleGetLoginParamResultOnSMCEnvNotify} _item.param_ = <GetLoginParamOnSMCEnvMsg>

    <result>8</result>

    <requestID>1</requestID>

</GetLoginParamOnSMCEnvMsg>

2018-07-04 08:37.906( 6432)[INFO]{uc::model::UCClientImpl::handleGetLoginParamResultOnSMCEnvNotify} GetLoginParam Result is 8,UI select NetWorkType is 1, (1 is SMC NetWork), isDetecting = 1

2018-07-04 08:37.906( 6432)[INFO]{uc::model::UCClientImpl::handleGetLoginParamResultOnSMCEnvNotify} The login request id(1) match with 1

2018-07-04 08:37.906( 6432)[INFO]{uc::model::UCClientImpl::handleGetLoginParamResultOnSMCEnvNotify} LEAVE 

2018-07-04 08:37.906( 6432)[INFO]{uc::model::conversation::sipRegisterResultMI} ENTER 

2018-07-04 08:37.906( 6432)[INFO]{uc::model::login::CLogin::sipRegisterResult} RegState = 0(3 is REGISTERED),ServerName =  state:0  ulReasonCode: 11085

2018-07-04 08:37.906( 6432)[INFO]{uc::model::cwmp::CwmpMgrImpl::StopTR069Service} ENTER 

2018-07-04 08:37.906( 6432)[INFO]{uc::model::cwmp::CwmpMgrImpl::StopTR069Service} Cwmp service stop

2018-07-04 08:37.906( 6432)[ERROR]{uc::model::cwmp::CwmpMgrImpl::CwmpDebugOutCallback} [CWMP][ERROR][File:cwmp_schedule.c,Line:4795][ERROR] SCHEDULE_StopHttpSvr TSP_HTTP_ServerStop 1979711490

2018-07-04 08:37.906( 6432)[INFO]{uc::model::cwmp::CwmpMgrImpl::CwmpHttpsServerLogCallback} [HTTP SERVER]TSP_HTTP_ServerDestroy isValidServerInstance  is invalid

2018-07-04 08:37.906( 6432)[ERROR]{uc::model::cwmp::CwmpMgrImpl::CwmpDebugOutCallback} [CWMP][ERROR][File:cwmp_schedule.c,Line:4807][ERROR] SCHEDULE_StopHttpSvr TSP_HTTP_ServerDestroy 1979711490

2018-07-04 08:37.106( 6432)[INFO]{uc::model::cwmp::CwmpMgrImpl::StopTR069Service} Http server stop

2018-07-04 08:37.106( 6432)[INFO]{uc::model::cwmp::CwmpMgrImpl::StopTR069Service} LEAVE 

2018-07-04 08:37.106( 6432)[INFO]{uc::model::login::CLogin::sipRegisterResult} tup call back register failed, error code: 11085

2018-07-04 08:37.106( 6432)[INFO]{uc::model::conversation::sipRegisterResultMI} LEAVE 

2018-07-04 08:37.106( 6432)[INFO]{uc::model::UCClientImpl::handleSignInNotify} ENTER 

2018-07-04 08:37.106( 6432)[INFO]{uc::model::UCClientImpl::handleSignInNotify} _item.param_ = <SignInNotifyMsg>

    <result>-4</result>

</SignInNotifyMsg>

2018-07-04 08:37.106( 6432)[INFO]{uc::model::UCClientImpl::handleSignInNotify} emit signin_sip_failed to UI

2018-07-04 08:37.106( 6432)[INFO]{uc::model::UCClientImpl::notifyUISignedFailed} SignInNotify_.emit: Client_Invalid, 0

2018-07-04 08:37.106( 6432)[INFO]{uc::model::UCClientImpl::notifyUISignedFailed} notifyUISignedFailed selfStatus Changed To offline!

2018-07-04 08:37.106( 6432)[INFO]{uc::model::UCClientImpl::notifyUISignedFailed} SignInNotify_.emit: Client_SignedFailed, register_failed

2018-07-04 08:37.106( 6432)[INFO]{uc::model::UCClientImpl::notifyUISignedFailed} register_failed

2018-07-04 08:37.106( 6432)[INFO]{uc::model::UCClientImpl::handleSignInNotify} LEAVE 



Below is the log information extracted after we have skipped login and tried to log in again.


2018-07-04 08:37.092( 6800)[INFO]{uc::model::login::CLogin::GetLoginParamOnSMCEnvironment} ENTER 

2018-07-04 08:37.092( 6800)[INFO]{uc::model::login::CLogin::SetSipDomainParam} TE Desktop deal siprui:

2018-07-04 08:37.092( 6800)[INFO]{uc::model::conference::ConferenceMgrImpl::GetAuthInfo} ENTER 

2018-07-04 08:37.092( 6800)[INFO]{uc::model::PasswordWrapper::GetPwd} KM_GetAesKey, ret=0

2018-07-04 08:37.093( 6800)[INFO]{uc::model::PasswordWrapper::GetPwd} cryptlen = 69 iResult = 0

2018-07-04 08:37.093( 6800)[INFO]{uc::model::conference::ConferenceMgrImpl::GetAuthInfo} KM_GetAesKey, ret=0

2018-07-04 08:37.093( 6800)[INFO]{uc::model::conference::ConferenceMgrImpl::GetAuthInfo} [ConferenceMgr]Create Conf account = ******

2018-07-04 08:37.093( 6800)[INFO]{uc::model::conference::ConferenceMgrImpl::GetAuthInfo} LEAVE 

2018-07-04 08:37.093( 6800)[INFO]{uc::model::login::CLogin::setTLSParam} ENTER 

2018-07-04 08:37.093( 6800)[INFO]{uc::model::login::CLogin::setTLSParam} strCertPath = ./\cert\root_cert_huawei.pem

2018-07-04 08:37.093( 6800)[INFO]{uc::model::login::CLogin::setTLSParam} LEAVE 

2018-07-04 08:37.093( 6800)[INFO]{uc::model::login::CLogin::GetLoginParamOnSMCEnvironment} LEAVE 

2018-07-04 08:37.093( 6800)[INFO]{uc::model::UCClientImpl::SignIn} The STG request(id=2)' is success.

2018-07-04 08:37.093( 6800)[INFO]{uc::model::UCClientImpl::SignIn} LEAVE 

2018-07-04 08:37.152( 7972)[INFO]{uc::model::conversation::loginGetParamOnSMCEnvResultMI} result->8, request id 2

2018-07-04 08:37.152( 7972)[INFO]{uc::model::UCClientImpl::handleGetLoginParamResultOnSMCEnvNotify} ENTER 

2018-07-04 08:37.152( 7972)[INFO]{uc::model::UCClientImpl::handleGetLoginParamResultOnSMCEnvNotify} _item.param_ = <GetLoginParamOnSMCEnvMsg>

    <result>8</result>

    <requestID>2</requestID>

</GetLoginParamOnSMCEnvMsg>

2018-07-04 08:37.152( 7972)[INFO]{uc::model::UCClientImpl::handleGetLoginParamResultOnSMCEnvNotify} GetLoginParam Result is 8,UI select NetWorkType is 1, (1 is SMC NetWork), isDetecting = 0

2018-07-04 08:37.152( 7972)[INFO]{uc::model::UCClientImpl::handleGetLoginParamResultOnSMCEnvNotify} The login request id(2) match with 2

2018-07-04 08:37.152( 7972)[INFO]{uc::model::UCClientImpl::SignIn} ENTER 

2018-07-04 08:37.159( 7972)[INFO]{CConfigSetting::RefreshDefaultRootCert} TLSCerType = HUAWEI, refresh

2018-07-04 08:37.166( 7972)[INFO]{uc::model::conference::ConferenceMgrImpl::ClearPersonalVMR} ENTER 

2018-07-04 08:37.166( 7972)[INFO]{uc::model::conference::ConferenceMgrImpl::ClearPersonalVMR} LEAVE 

2018-07-04 08:37.166( 7972)[INFO]{uc::model::login::CLogin::configHttpProxyInfo} The http proxy is disabled. Set proxy = 0

2018-07-04 08:37.166( 7972)[INFO]{uc::model::conference::ConferenceMgrImpl::configHttpProxyInfo} The http proxy is disabled. set proxy  = 0

2018-07-04 08:37.166( 7972)[INFO]{uc::model::login::CLogin::LoginAuthOnMediaX} ENTER 

2018-07-04 08:37.166( 7972)[INFO]{uc::model::login::CLogin::setTLSParam} ENTER 

2018-07-04 08:37.166( 7972)[INFO]{uc::model::login::CLogin::setTLSParam} strCertPath = ./\cert\root_cert_huawei.pem

2018-07-04 08:37.167( 7972)[INFO]{uc::model::login::CLogin::setTLSParam} LEAVE 

2018-07-04 08:37.167( 7972)[INFO]{uc::model::login::CLogin::requestMediaxLoginAuth} ENTER 

2018-07-04 08:37.167( 7972)[INFO]{uc::model::login::CLogin::GetLoginParamOnMediaXEnvironment} ENTER 

2018-07-04 08:37.167( 7972)[INFO]{uc::model::PasswordWrapper::GetPwd} KM_GetAesKey, ret=0

2018-07-04 08:37.167( 7972)[INFO]{uc::model::PasswordWrapper::GetPwd} cryptlen = 69 iResult = 0

2018-07-04 08:37.167( 7972)[INFO]{uc::model::PasswordUtil::DecPwd} ENTER 

2018-07-04 08:37.167( 7972)[INFO]{uc::model::PasswordUtil::DecPwd} KM_GetAesKey, ret=0

2018-07-04 08:37.167( 7972)[INFO]{uc::model::PasswordUtil::DecPwd} LEAVE 

2018-07-04 08:37.167( 7972)[INFO]{uc::model::login::CLogin::GetLoginParamOnMediaXEnvironment} LEAVE 

2018-07-04 08:37.167( 7972)[INFO]{uc::model::UCClientImpl::SetMediaXTokenTypeInfo} ENTER 

2018-07-04 08:37.167( 7972)[INFO]{uc::model::UCClientImpl::SetMediaXTokenTypeInfo} The success token request id(2)'s operation is 0

2018-07-04 08:37.167( 7972)[INFO]{uc::model::UCClientImpl::SetMediaXTokenTypeInfo} LEAVE 

2018-07-04 08:37.167( 7972)[INFO]{uc::model::login::CLogin::requestMediaxLoginAuth} The success token request id(2)'s operation is login operate 

2018-07-04 08:37.167( 7972)[INFO]{uc::model::login::CLogin::requestMediaxLoginAuth} LEAVE 

2018-07-04 08:37.167( 7972)[INFO]{uc::model::login::CLogin::LoginAuthOnMediaX} LEAVE 

2018-07-04 08:37.167( 7972)[INFO]{uc::model::UCClientImpl::SignIn} LEAVE 

2018-07-04 08:37.167( 7972)[INFO]{uc::model::UCClientImpl::handleGetLoginParamResultOnSMCEnvNotify} LEAVE 

2018-07-04 08:37.168( 7972)[INFO]{uc::model::conversation::loginGetParamOnMediaxEnvResultMI} result->8, request id 2

2018-07-04 08:37.168( 7972)[INFO]{uc::model::UCClientImpl::handleGetLoginParamResultOnMediaXEnvNotify} ENTER 

2018-07-04 08:37.168( 7972)[INFO]{uc::model::UCClientImpl::handleMediaXLoginOperateParamResult} ENTER 

2018-07-04 08:37.168( 7972)[INFO]{uc::model::UCClientImpl::handleMediaXLoginOperateParamResult} The login request id(2) is matched with 2, isDetecting = 1

2018-07-04 08:37.168( 7972)[INFO]{uc::model::UCClientImpl::handleLoginMediaXFail} Login failed, because get mediax login params failed.

2018-07-04 08:37.168( 7972)[INFO]{uc::model::login::CLogin::login} ENTER 

2018-07-04 08:37.168( 7972)[INFO]{uc::model::login::CLogin::initUserProfile} ENTER 

2018-07-04 08:37.168( 7972)[INFO]{uc::model::login::CLogin::initUserProfile} UserData dir created: -1

2018-07-04 08:37.168( 7972)[INFO]{uc::model::login::CLogin::initUserProfile} LEAVE 

2018-07-04 08:37.168( 7972)[INFO]{uc::model::PasswordWrapper::GetPwd} KM_GetAesKey, ret=0

2018-07-04 08:37.168( 7972)[INFO]{uc::model::PasswordWrapper::GetPwd} cryptlen = 69 iResult = 0

2018-07-04 08:37.169( 7972)[INFO]{Profile::ReadAllREGISTERItems} enable 0, type 2

2018-07-04 08:37.183( 7972)[INFO]{CConfigSetting::CF_SetRegisterTransportMode} set cert path./\cert\root_cert_huawei.pem

2018-07-04 08:37.186( 7972)[INFO]{CConfigSetting::CF_SetSipAliveTime} sip alive time = 300

2018-07-04 08:37.186( 7972)[INFO]{CConfigSetting::SetSipServiceAddr} SetSipServiceAddr  proxyServerIp : 80.194.85.72, proxyServerPort : 5061, sipDomain : 80.194.85.72

2018-07-04 08:37.196( 7972)[INFO]{CConfigSetting::SetSipConfigInfos} CALL_D_CFG_SIP_STRICT_CHECK_CSEQ, seq  =  1

2018-07-04 08:37.198( 7972)[INFO]{Profile::getLocalIpBySocket} ENTER 

2018-07-04 08:37.198( 7972)[INFO]{Profile::getLocalIpBySocket} destIp = x.X.X.X

2018-07-04 08:37.198( 7972)[INFO]{Profile::getLocalIpBySocket} current server port:5061

2018-07-04 08:37.259( 7972)[INFO]{Profile::getLocalIpBySocket} LEAVE 

2018-07-04 08:37.259( 7972)[INFO]{CConfigSetting::SetSipLocalAddr} strDestIp : X.X.X.X, uiDestPort : 5061, strLocalIP : 192.168.100.60, LocalSipPort : 5060

2018-07-04 08:37.287( 7972)[INFO]{CConfigSetting::SetVideoDecodeLevelTable} cpuType = 2

2018-07-04 08:37.288( 7972)[INFO]{CConfigSetting::UpdateVideoCaps} cpuType = 2

2018-07-04 08:37.289( 7972)[INFO]{CConfigSetting::BeSpecialCPUType} CPU INFO IS NOT E3845, DO NOT START HDACC!

2018-07-04 08:37.298( 7972)[INFO]{CConfigSetting::SetVideoResolutionAdjTable} cpuType = 2

2018-07-04 08:37.298( 7972)[INFO]{CConfigSetting::SetVideoResolutionAdjTable} isLowerCpu = 1

2018-07-04 08:37.311( 7972)[INFO]{CConfigSetting::UpdateDataCaps} Set CALL_D_CFG_DATA_COMPLEXITY_CTRL_ENBALE is: 1, CALL_D_CFG_DATA_FIR_RSPN_INTERVAL is :1000

2018-07-04 08:37.313( 7972)[INFO]{uc::model::PasswordWrapper::GetPwd} KM_GetAesKey, ret=0

2018-07-04 08:37.313( 7972)[INFO]{uc::model::PasswordWrapper::GetPwd} cryptlen = 69 iResult = 0

2018-07-04 08:37.313( 7972)[INFO]{uc::model::PasswordUtil::DecPwd} ENTER 

2018-07-04 08:37.313( 7972)[INFO]{uc::model::PasswordUtil::DecPwd} KM_GetAesKey, ret=0

2018-07-04 08:37.313( 7972)[INFO]{uc::model::PasswordUtil::DecPwd} LEAVE 

2018-07-04 08:37.313( 7972)[INFO]{uc::model::PasswordWrapper::ToPwd} KM_GetAesKey, ret=0

2018-07-04 08:37.313( 7972)[INFO]{uc::model::PasswordWrapper::ToPwd} KM_GetAesIV, ret=0

2018-07-04 08:37.313( 7972)[INFO]{uc::model::PasswordWrapper::ToPwd} KM_GetAesKey, ret=0

2018-07-04 08:37.314( 7972)[INFO]{uc::model::PasswordWrapper::ToPwd} KM_GetAesIV, ret=0

2018-07-04 08:37.314( 7972)[INFO]{uc::model::PasswordWrapper::GetPwd} KM_GetAesKey, ret=0

2018-07-04 08:37.314( 7972)[INFO]{uc::model::PasswordWrapper::GetPwd} cryptlen = 14 iResult = 0

2018-07-04 08:37.314( 7972)[INFO]{uc::model::PasswordWrapper::GetPwd} KM_GetAesKey, ret=0

2018-07-04 08:37.314( 7972)[INFO]{uc::model::PasswordWrapper::GetPwd} cryptlen = 20 iResult = 0

2018-07-04 08:37.314( 7972)[INFO]{uc::model::login::CLogin::startRegister} ENTER 

2018-07-04 08:37.368( 7972)[INFO]{uc::model::login::CLogin::startRegister} LEAVE 

2018-07-04 08:37.368( 7972)[INFO]{uc::model::login::CLogin::login} LEAVE 

2018-07-04 08:37.368( 7972)[INFO]{uc::model::UCClientImpl::handleLoginMediaXFail}  start sip register ret = 1

2018-07-04 08:37.368( 7972)[INFO]{uc::model::UCClientImpl::handleMediaXLoginOperateParamResult} LEAVE 

2018-07-04 08:37.368( 7972)[INFO]{uc::model::UCClientImpl::handleGetLoginParamResultOnMediaXEnvNotify} mediax token operate is : 0, bResult is : 0

2018-07-04 08:37.368( 7972)[INFO]{uc::model::UCClientImpl::handleGetLoginParamResultOnMediaXEnvNotify} LEAVE 

2018-07-04 08:37.368( 7972)[INFO]{uc::model::conversation::sipRegisterResultMI} ENTER 

2018-07-04 08:37.368( 7972)[INFO]{uc::model::login::CLogin::sipRegisterResult} RegState = 1(3 is REGISTERED),ServerName =  state:1  ulReasonCode: 0

2018-07-04 08:37.368( 7972)[INFO]{uc::model::conversation::sipRegisterResultMI} LEAVE 

2018-07-04 08:37.868( 7972)[INFO]{uc::model::conversation::networkAddressBeginDownloadMI} ENTER 

2018-07-04 08:37.868( 7972)[INFO]{uc::model::conversation::networkAddressBeginDownloadMI} CALL_E_EUA_TYPE_FTP, ftps information

2018-07-04 08:37.868( 7972)[INFO]{uc::model::UCClientImpl::SetNetAddressBookType} ENTER 

2018-07-04 08:37.868( 7972)[INFO]{uc::model::UCClientImpl::SetNetAddressBookType} LEAVE 

2018-07-04 08:37.868( 7972)[INFO]{uc::model::conversation::networkAddressBeginDownloadMI} LEAVE 

2018-07-04 08:37.868( 7972)[INFO]{uc::model::UC2ContactMgrImpl::BeginToDownloadNetContact} ENTER 

2018-07-04 08:37.868( 7972)[INFO]{uc::model::PasswordWrapper::ToPwd} KM_GetAesKey, ret=0

2018-07-04 08:37.869( 7972)[INFO]{uc::model::PasswordWrapper::ToPwd} KM_GetAesIV, ret=0

2018-07-04 08:37.869( 7972)[INFO]{uc::model::PasswordWrapper::ToPwd} KM_GetAesKey, ret=0

2018-07-04 08:37.869( 7972)[INFO]{uc::model::PasswordWrapper::ToPwd} KM_GetAesIV, ret=0

2018-07-04 08:37.869( 7972)[INFO]{uc::model::PasswordWrapper::ToPwd} KM_GetAesKey, ret=0

2018-07-04 08:37.869( 7972)[INFO]{uc::model::PasswordWrapper::ToPwd} KM_GetAesIV, ret=0

2018-07-04 08:37.870( 7972)[INFO]{uc::model::PasswordWrapper::ToPwd} KM_GetAesKey, ret=0

2018-07-04 08:37.870( 7972)[INFO]{uc::model::PasswordWrapper::ToPwd} KM_GetAesIV, ret=0

2018-07-04 08:37.877( 7972)[INFO]{CConfigSetting::RefreshDefaultRootCert} TLSCerType = HUAWEI, refresh

2018-07-04 08:37.885( 7972)[INFO]{uc::model::UC2ContactMgrImpl::BeginToDownloadNetContact} tup_corpdir_download_netadd, sucess

2018-07-04 08:37.885( 7972)[INFO]{uc::model::UC2ContactMgrImpl::BeginToDownloadNetContact} LEAVE 

2018-07-04 08:37.885( 7972)[INFO]{uc::model::conversation::ConevntEnableNotifyMI} ENTER 

2018-07-04 08:37.886( 7972)[INFO]{uc::model::conversation::ConevntEnableNotifyMI} ConevntEnableNotifyMI sipAccount:0 isUnSupport:0

2018-07-04 08:37.886( 7972)[INFO]{uc::model::conversation::ConevntEnableNotifyMI} LEAVE 

2018-07-04 08:37.886( 7972)[INFO]{uc::model::UCClientImpl::handleConevneEnableNotify} ENTER 

2018-07-04 08:37.886( 7972)[INFO]{uc::model::UCClientImpl::handleConevneEnableNotify} _item.param_ = <ConevntEnableNotifyMsg>

    <sipAccount>0</sipAccount>

    <unSupport>0</unSupport>

</ConevntEnableNotifyMsg>

2018-07-04 08:37.886( 7972)[INFO]{uc::model::UCClientImpl::handleConevneEnableNotify} LEAVE 

2018-07-04 08:37.886( 7972)[INFO]{uc::model::conversation::sipRegisterResultMI} ENTER 

2018-07-04 08:37.886( 7972)[INFO]{uc::model::login::CLogin::sipRegisterResult} RegState = 3(3 is REGISTERED),ServerName =  state:3  ulReasonCode: 0

2018-07-04 08:37.886( 7972)[INFO]{uc::model::login::CLogin::sipRegisterResult}CALL_E_REG_STATE_REGISTERED  tup call back register success.

2018-07-04 08:37.886( 7972)[INFO]{uc::model::login::CLogin::sipRegisterResult} Dest server ip : X.X.X.X, dest server port : 5061

2018-07-04 08:37.886( 7972)[INFO]{uc::model::login::CLogin::sipRegisterResult} CALL_E_REG_STATE_REGISTERED  LocalSipPort : 5060, usLocalStackSipPort : 5060, strDestServerIp : X.X.X.X, strLocalSipIp : 192.168.100.60


Solution:


Our Research and Development stated that a new patch will be released at the end of July in order to address the issue. At the moment the workaround is to skip the login when attempting to register for the first time; log out of the client and afterwards use the login credentials needed for the participants


This article contains more resources

You need to log in to download or view. No account? Register

x

good case
View more
  • x
  • convention:

2018-07-09 03:35.877( 7972)[INFO]{CConfigSetting::RefreshDefaultRootCert} TLSCerType = HUAWEI, refresh.Can you provide a more detailed explanation?
View more
  • x
  • convention:

this is really a good case
View more
  • x
  • convention:

Comment

You need to log in to comment to the post Login | Register
Comment

Notice: To protect the legitimate rights and interests of you, the community, and third parties, do not release content that may bring legal risks to all parties, including but are not limited to the following:
  • Politically sensitive content
  • Content concerning pornography, gambling, and drug abuse
  • Content that may disclose or infringe upon others ' commercial secrets, intellectual properties, including trade marks, copyrights, and patents, and personal privacy
Do not share your account and password with others. All operations performed using your account will be regarded as your own actions and all consequences arising therefrom will be borne by you. For details, see " User Agreement."

My Followers

Login and enjoy all the member benefits

Login

Block
Are you sure to block this user?
Users on your blacklist cannot comment on your post,cannot mention you, cannot send you private messages.
Reminder
Please bind your phone number to obtain invitation bonus.