OpenHarmony开发者论坛

标题: device_manager里创建组时失败，回调onError()里打印错误码4098，这个是什么意思？ [打印本页]

作者: lvguanghao 时间: 2025-1-13 14:13
标题: device_manager里创建组时失败，回调onError()里打印错误码4098，这个是什么意思？
[md]### 【问题描述】

1. 尝试使用2个Hi3516CV610板子进行设备认证绑定，然后中间看到串口打印如下

[I][devicemanagerserviceimpl][onError]:HichainAuthenCallBack::onError reqId:6388512403, operation:0, errorCode:4098.
[E][devicemanagerserviceimpl][onError]:Create group failed
2. 不知道这个错误码的含义是什么，哪里可以找到说明吗？
3. 打印如下

y
[I][dm_lite_demo]Set user confirm begin, pkgName: OH.DM.Lite.Demo, confirm 1.
[I][dm_lite_demo]SetConfirmStart: confirmStart_=1.
[I][devicemanagerkit][SetUserOperation]:SetUserOperation start, pkgName: OH.DM.Lite.Demo, action: 6.
[I][devicemanagerkit][SendCmd]:IpcClientServerProxy::SendCmd:28
[I][devicemanagerkit][IpcSetRequestSERVER_USER_AUTH_OPERATION]:Enter set user operation request. pkgName: OH.DM.Lite.Demo, action: 6.
[I][devicemanagerservice][OnRemoteRequestLite]:Receive funcId:28
[I][devicemanagerservice][IpcServerCmdProcessSERVER_USER_AUTH_OPERATION]:Enter set user operation. pkgName: OH.DM.Lite.Demo, action: 6.
[I][devicemanagerserviceimpl][OnUserOperation]:DmAuthManager:: on user operation, action: 6.
[I][devicemanagerserviceimpl][StartAuthProcess]:DmAuthManager::StartAuthProcess
[I][devicemanagerserviceimpl][TransitionTo]:AuthResponseState::TransitionTo
[I][devicemanagerserviceimpl][Enter]:AuthResponse::AuthResponseGroupState Enter
[I][devicemanagerserviceimpl][CreateGroup]:DmAuthManager::CreateGroup start
01-01 00:01:57.583 602 570 I 02F00/[DEVAUTH]: IpcGmGetGroupInfo: starting ...
01-01 00:01:57.583 602 570 I 02F00/[DEVAUTH]: EncodeCallRequest: type 32, paramSz 4
01-01 00:01:57.583 602 570 I 02F00/[DEVAUTH]: EncodeCallRequest: type 1, paramSz 39
01-01 00:01:57.583 602 570 I 02F00/[DEVAUTH]: EncodeCallRequest: type 18, paramSz 82
01-01 00:01:57.583 602 570 I 02F00/[DEVAUTH]: DoBinderCall: proc method 14
01-01 00:01:57.583 602 570 I 02F00/[DEVAUTH]: FinalCallRequest: method id 14, param num 3, data length 152, flag 1, io offset 0
01-01 00:01:57.583 602 570 I 02F00/[DEVAUTH]: ActCall: start to invoke ipc call...
[E][devicemanagerserviceimpl][GetGroupInfo]:[HICHAIN]return groups info number is zero.
01-01 00:01:57.583 595 568 I 02F00/[DEVAUTH]: OnRemoteInvoke: request code 1
01-01 00:01:57.583 595 568 I 02F00/[DEVAUTH]: GetMethodId: GetMethodId, id code 14
[I][devicemanagerserviceimpl][CreateGroup]:HiChainConnector::CreateGroup requestId 6388512403

01-01 00:01:57.583 595 568 I 02F00/[DEVAUTH]: DevAuthRequestCall: request method id 14
01-01 00:01:57.583 595 568 I 02F00/[DEVAUTH]: DecodeCallRequest: request data length(152), param number: 3
01-01 00:01:57.583 595 568 I 02F00/[DEVAUTH]: DecodeCallRequest: decode success, param type 32, val size 4
01-01 00:01:57.583 595 568 I 02F00/[DEVAUTH]: DecodeCallRequest: decode success, param type 1, val size 39
01-01 00:01:57.583 595 568 I 02F00/[DEVAUTH]: DecodeCallRequest: decode success, param type 18, val size 82
01-01 00:01:57.583 595 568 I 02F00/[DEVAUTH]: IpcServiceGmGetGroupInfo: starting ...
01-01 00:01:57.583 595 568 I 02F00/[DEVAUTH]: GetIpcRequestParamByType: type 32, result 0x0
01-01 00:01:57.583 595 568 I 02F00/[DEVAUTH]: GetIpcRequestParamByType: type 1, result 0x0
01-01 00:01:57.583 595 568 I 02F00/[DEVAUTH]: GetIpcRequestParamByType: type 18, result 0x0
01-01 00:01:57.584 595 568 I 02F00/[DEVAUTH]: GenerateReturnGroupVec: No group is found based on the query parameters!
01-01 00:01:57.584 595 568 I 02F00/[DEVAUTH]: IpcEncodeCallReplay: reply type 23, success
01-01 00:01:57.584 595 568 I 02F00/[DEVAUTH]: IpcEncodeCallReplay: reply type 24, success
01-01 00:01:57.584 595 568 I 02F00/[DEVAUTH]: IpcEncodeCallReplay: reply type 28, success
01-01 00:01:57.584 595 568 I 02F00/[DEVAUTH]: IpcEncodeCallReplay: reply type 20, success
01-01 00:01:57.584 595 568 I 02F00/[DEVAUTH]: IpcServiceGmGetGroupInfo: process done, call ret 0, ipc ret 0
01-01 00:01:57.584 595 568 I 02F00/[DEVAUTH]: OnRemoteInvoke: form service result done, result length(48)
01-01 00:01:57.584 595 568 I 02F00/[DEVAUTH]: OnRemoteInvoke: done, request code 1, call result 0
01-01 00:01:57.584 602 570 I 02F00/[DEVAUTH]: CliInvokeRetCallback: starting...
01-01 00:01:57.584 602 570 I 02F00/[DEVAUTH]: CliInvokeRetCallback: done, reply data length 56
01-01 00:01:57.584 602 570 I 02F00/[DEVAUTH]: ActCall: invoke call done, ipc result(0)
01-01 00:01:57.584 602 570 I 02F00/[DEVAUTH]: ActCall: service call result(0)
01-01 00:01:57.584 602 570 I 02F00/[DEVAUTH]: DecodeCallReply: to decode data length 48
01-01 00:01:57.584 602 570 I 02F00/[DEVAUTH]: DecodeCallReply: decode success, type 23
01-01 00:01:57.584 602 570 I 02F00/[DEVAUTH]: DecodeCallReply: decode success, type 24
[I][devicemanagerkit][SendCmdResultCb]:SendCmdResultCb code:28
[I][devicemanagerserviceimpl][onError]:HichainAuthenCallBack::onError reqId:6388512403, operation:0, errorCode:4098.
[E][devicemanagerserviceimpl][onError]:Create group failed
[I][devicemanagerserviceimpl][OnGroupCreated]:DmAuthManager::OnGroupCreated start group id ******
01-01 00:01:57.619 593 568 I 02F00/HUKS: HksHash[477]: leave hash, result = 0

[I][devicemanagerserviceimpl][CreateSimpleMessage]:AuthMessageProcessor::CreateSimpleMessage start. msgType is 200
01-01 00:01:57.620 593 568 I 02F00/HUKS: HksKeyExist[231]: enter check key exist

01-01 00:01:57.626 590 573 I 02F00/HUKS: MakeDirIfNotExist[292]: dir not exist, path = /storage/data/service/el1/public/huks_service/maindata/+0+0+0+0

01-01 00:01:57.626 590 573 E 02F00/HUKS: HksStoreIsKeyBlobExist[1012]: check is key exist, ret = -13.

01-01 00:01:57.626 590 573 E 02F00/HUKS: HksIpcServiceKeyExist[238]: HksServiceKeyExist fail, ret = -13

01-01 00:01:57.627 593 568 I 02F00/HUKS: HksKeyExist[234]: leave check key exist, result = -13

01-01 00:01:57.627 593 568 I 02F00/[DEVAUTH]: <6388512403>CheckKeyExist: [HUKS]: HksKeyExist fail. [Res]: -13
01-01 00:01:57.627 593 568 I 02F00/[DEVAUTH]: <6388512403>GenerateKeyPairWithStorage: [HUKS]: HksGenerateKey enter.
[I][devicemanagerserviceimpl][SendData]:start, msgType: 200.

01-01 00:01:57.627 593 568 I 02F00/HUKS: HksGenerateKey[105]: enter generate key

01-01 00:01:57.627 590 573 I 02F00/HUKS: RecordKeyOperation[941]: delete key or certchain, storage path: /storage/data/service/el1/public/huks_service/maindata/+0+0+0+0/key, key alias: 4B63169CF4F4D77EAB27918FC9FA6D33E22E5060BA07A51DB63D2B8E****C005

01-01 00:01:57.627 590 573 W 02F00/HUKS: GetFileCount[1067]: can't open directory

[I][devicemanagerkit][SendCmd]:IpcClientServerProxy::SendCmd:28, end
[I][devicemanagerkit][SetUserOperation]:SetUserOperation completed, pkgName: OH.DM.Lite.Demo

01-01 00:01:57.632 590 573 E 02F00/HUKS: HksCryptoHalFillRandom[330]: ss_mpi_cipher_trng_get_multi_random failed! cipher ret = 0x44

01-01 00:01:57.632 590 573 E 02F00/HUKS: HksCoreGenerateKey[304]: GenerateKey failed, ret:fffffffe!

01-01 00:01:57.632 590 573 E 02F00/HUKS: HksServiceGenerateKey[764]: access level generate key failed, ret = -2

01-01 00:01:57.632 590 573 E 02F00/HUKS: HksIpcServiceGenerateKey[83]: HksServiceGenerateKey fail, ret = -2

01-01 00:01:57.632 593 568 E 02F00/HUKS: HksClientGenerateKey[93]: HksSendRequest fail, ret = -2

01-01 00:01:57.632 593 568 I 02F00/HUKS: HksGenerateKey[122]: leave generate persistent key, result = -2

01-01 00:01:57.632 593 568 I 02F00/[DEVAUTH]: <6388512403>GenerateKeyPairWithStorage: [HUKS]: HksGenerateKey quit. [Res]: -2
01-01 00:01:57.632 593 568 E 02F00/[DEVAUTH]: <6388512403>GenerateKeyPairWithStorage: [HUKS]: HksGenerateKey fail. [Res]: -2
01-01 00:01:57.632 593 568 E 02F00/[DEVAUTH]: <6388512403>RegisterLocalIdentity: Failed to generate key pair!
01-01 00:01:57.632 593 568 E 02F00/[DEVAUTH]: <6388512403>RegisterLocalIdentityInTask: Protocol type: 2, registerLocalIdentity failed, res: -1!
01-01 00:01:57.632 593 568 E 02F00/[DEVAUTH]: <6388512403>RegisterLocalIdentity: Register local identity failed, res: 1002
01-01 00:01:57.632 593 568 I 02F00/[DEVAUTH]: <6388512403>ProcessErrorCallback: [Service][In]: ProcessErrorCallback! [ReqId]: 6388512403
01-01 00:01:57.632 593 568 I 02F00/[DEVAUTH]: <6388512403>GaCbOnErrorWithType: starting ... request id: 6388512403, type 1
01-01 00:01:57.632 593 568 I 02F00/[DEVAUTH]: <6388512403>ShowIpcSvcInfo: svc information - handle(3), token(0), cookie(0)
01-01 00:01:57.632 593 568 I 02F00/[DEVAUTH]: <6388512403>ShowIpcSvcInfo: svc information - handle(3), token(0), cookie(0)
01-01 00:01:57.632 593 568 I 02F00/[DEVAUTH]: <6388512403>CbProxySendRequest: to form callback params data length(40)
01-01 00:01:57.633 593 568 I 02F00/[DEVAUTH]: <6388512403>CbProxySendRequest: SendRequest(1) done, return(0)
[I][devicemanagerserviceimpl][OnBytesReceived]:start, sessionId: 1, dataLen: 47.
01-01 00:01:57.633 593 568 I 02F00/[DEVAUTH]: <6388512403>GaCbOnErrorWithType: process done, request id: 6388512403
01-01 00:01:57.633 593 568 I 02F00/[DEVAUTH]: <6388512403>ProcessErrorCallback: [Service][Out]: ProcessErrorCallback!
01-01 00:01:57.633 602 570 I 02F00/[DEVAUTH]: CbStubOnRemoteRequest: enter invoking callback...
01-01 00:01:57.633 602 570 I 02F00/[DEVAUTH]: CbStubOnRemoteRequest: receive ipc transact code(2)
[I][devicemanagerserviceimpl][ParseMessage]:AuthMessageProcessor::ParseMessage message type 104
01-01 00:01:57.633 602 570 I 02F00/[DEVAUTH]: ProcCbHook: Process call back hook, callback id 4
01-01 00:01:57.633 602 570 I 02F00/[DEVAUTH]: GetIpcRequestParamByType: type 4, result 0x0
[I][devicemanagerserviceimpl][ProcessSinkMsg]:OnDataReceived for sink device, authResponseContext msgType = 104, authResponseState stateType = 23
01-01 00:01:57.640 602 570 I 02F00/[DEVAUTH]: GetIpcRequestParamByType: type 22, result 0x0
[I][devicemanagerserviceimpl][TransitionTo]:AuthResponseState::TransitionTo
01-01 00:01:57.641 602 570 I 02F00/[DEVAUTH]: GetIpcRequestParamByType: type 25, result 0x0
[I][devicemanagerserviceimpl][AuthenticateFinish]:DmAuthManager::AuthenticateFinish start
[I][devicemanagerservice][OnUiCall]:OnUiCall in pkgName: OH.DM.Lite.Demo, paramJson: {"uiStateMsg":2}
01-01 00:01:57.641 602 570 I 02F00/[DEVAUTH]: GetIpcRequestParamByType: type 26, result 0x3008
[I][devicemanagerserviceimpl][UpdateUiState]:AuthUiStateManager::UpdateUiState complete.
[I][devicemanagerserviceimpl][DeleteAll]:DmTimer DeleteAll start
[I][devicemanagerkit][IpcCmdProcessSERVER_DEVICE_FA_NOTIFY]:Enter get FA notify info. pkgName: OH.DM.Lite.Demo, paramJson: {"uiStateMsg":2}.
[I][devicemanagerserviceimpl][DeleteAll]:DmTimer DeleteAll timer.name = deviceManagerTimer:authenticate
[I][devicemanagerkit][OnUiCall]:DeviceManagerNotify::OnUiCall in, pkgName:OH.DM.Lite.Demo
[I][dm_lite_demo]OnCall for OH.DM.Lite.Demo, paramJson {"uiStateMsg":2}.
[I][devicemanagerserviceimpl][AuthenticateFinish]:DmAuthManager::AuthenticateFinish complete
[I][devicemanagerserviceimpl][OnBytesReceived]:completed.
[I][dm_lite_demo]SetConfirmStart: confirmStart_=0.
[I][devicemanagerkit][ClientIpcInterfaceMsgHandle]:receive ipc transact code:29, retCode = 0
01-01 00:01:57.946 602 570 I 015C0/dsoftbus: [TRAN][SendBytes:47]SendBytes: sessionId=1
01-01 00:01:57.946 602 570 E 015C0/dsoftbus: [TRAN][CheckSendLen:36]send data len:4096
01-01 00:01:57.946 602 570 I 015C0/dsoftbus: [TRAN][ServerIpcSendMessage:268]ServerIpcSendMessage
01-01 00:01:57.946 597 569 I 015C0/dsoftbus: [COMM][Invoke:213]RECEIVE FUNCID:136
01-01 00:01:57.946 597 569 I 015C0/dsoftbus: [TRAN][ServerSendSessionMsg:225]server send session msg ipc server pop
01-01 00:01:57.946 597 569 I 015C0/dsoftbus: [TRAN][TransSendMsg:696]send msg: id=1, type=3
01-01 00:01:57.946 597 569 I 015C0/dsoftbus: [AUTH][SocketPostBytes:438]SocketPostBytes: fd=20, module=9, seq=0, flag=0, len=88.
01-01 00:01:57.947 602 570 I 015C0/dsoftbus: [TRAN][ProxyCallback:41]publish service return[0].
01-01 00:01:57.947 602 570 I 015C0/dsoftbus: [TRAN][ServerIpcSendMessage:298]ServerIpcSendMessage
01-01 00:01:57.947 602 570 I 015C0/dsoftbus: [TRAN][TransAuthChannelSendBytes:100]send bytes: channelId=1, ret=0
01-01 00:01:57.947 602 570 I 02F00/[DEVAUTH]: ProcCbHook: ProcCbHook done
01-01 00:01:57.947 602 570 I 02F00/[DEVAUTH]: CbStubOnRemoteRequest: Invoke callback done, result(0)
01-01 00:01:57.949 609 569 I 015C0/dsoftbus: [CONN][SelectTask:1192][base-listener] select task, wakeup from select, select trace=2, wakeup trace=8, events=1
01-01 00:01:57.949 609 569 D 015C0/dsoftbus: [CONN][ProcessSpecifiedListenerNodeEvent:1026][base-listener] process spectified listener node event trace, trigger IN event, wakeup trace=8, module=1, fd=20, trigger set=1
[D][devicemanagerserviceimpl][OnSessionClosed]:OnSessionClosed, sessionId: 1.
[I][devicemanagerserviceimpl][operator()]:DmTimer TimerRunning timer.name = deviceManagerTimer:authenticate
01-01 00:01:57.949 602 570 I 015C0/dsoftbus: [COMM][ClientIpcInterfaceMsgHandle:73]receive ipc transact code(260)
01-01 00:01:57.949 602 570 I 015C0/dsoftbus: [TRAN][TransOnChannelMsgReceived:114][client] TransOnChannelMsgReceived: channelId=1, channelType=3.
01-01 00:01:58.458 609 569 I 015C0/dsoftbus: [CONN][SelectTask:1192][base-listener] select task, wakeup from select, select trace=2, wakeup trace=9, events=1
01-01 00:01:58.458 609 569 D 015C0/dsoftbus: [CONN][ProcessSpecifiedListenerNodeEvent:1026][base-listener] process spectified listener node event trace, trigger IN event, wakeup trace=9, module=1, fd=20, trigger set=1
01-01 00:01:58.458 609 569 W 015C0/dsoftbus: [CONN][OnRecvData:268]recv data fail, peer close connection, fd=20
01-01 00:01:58.458 609 569 E 015C0/dsoftbus: [AUTH][RecvPacketHead:173]recv head fail(=0).
01-01 00:01:58.458 609 569 I 015C0/dsoftbus: [CONN][DelTrigger:703][base-listener] receive delete trigger request, module=1, fd=20, trigger=1
01-01 00:01:58.458 609 569 I 015C0/dsoftbus: [CONN][DelTrigger:757][base-listener] delete trigger success, there is not exist any trigger, free fd node now, module=1, fd=20, trigger=1
01-01 00:01:58.458 609 569 I 015C0/dsoftbus: [CONN][WakeupSelectThread:1321][base-listener] wakeup select thread, wakeup ctrl message sent, write length=4, ctrl trace=3
01-01 00:01:58.458 609 569 I 015C0/dsoftbus: [AUTH][OnWiFiDisconnected:317]OnWiFiDisconnected: fd=20.
01-01 00:01:58.458 609 569 I 015C0/dsoftbus: [COMM][PostMessageAtTime:277][BusCenter]PostMessageAtTime what =5 time= 118458737 us
01-01 00:01:58.458 609 569 D 015C0/dsoftbus: [COMM][PostMessageAtTime:326][BusCenter]PostMessageAtTime. insert
01-01 00:01:58.458 609 569 D 015C0/dsoftbus: [COMM][DumpLooperLocked:240]DumpLooper. i=0,handler=AuthHandler,what =5,arg1=3067472577 arg2=0, time=118458737
01-01 00:01:58.458 609 569 I 015C0/dsoftbus: [TRAN][OnDisconnect:461]recv auth=20 channel disconnect event.
01-01 00:01:58.458 609 569 I 015C0/dsoftbus: [TRAN][TransLaneMgrDelLane:187]del trans land mgr.[chanid=1][type=3]
01-01 00:01:58.458 609 569 E 015C0/dsoftbus: [TRAN][TransLaneMgrDelLane:209]trans lane not found.[channelId = 1, channelType = 3]
01-01 00:01:58.458 600 569 I 015C0/dsoftbus: [COMM][LoopTask:147]LoopTask[BusCenter], get message. handle=AuthHandler,what=5,arg1=3067472577,msgSize=2,time=118458737
01-01 00:01:58.458 609 569 W 015C0/dsoftbus: [TRAN][TransServerOnChannelClosed:56]delete lane object failed.
01-01 00:01:58.458 600 569 I 015C0/dsoftbus: [COMM][LoopTask:165]LoopTask[BusCenter], HandleMessage message. handle=AuthHandler,what=5
01-01 00:01:58.458 609 569 I 015C0/dsoftbus: [TRAN][ClientIpcOnChannelClosed:177]on channel closed ipc server push
01-01 00:01:58.459 602 570 I 015C0/dsoftbus: [COMM][ClientIpcInterfaceMsgHandle:73]receive ipc transact code(259)
01-01 00:01:58.459 602 570 I 015C0/dsoftbus: [TRAN][TransOnChannelClosed:95][client] TransOnChannelClosed: channelId=1, channelType=3.
01-01 00:01:58.459 602 570 I 015C0/dsoftbus: [TRAN][TransOnSessionClosed:151]TransOnSessionClosed: channelId=1, channelType=3
01-01 00:01:58.459 609 569 E 015C0/dsoftbus: [TRAN][OnDisconnect:458]auth=20 channel already removed
01-01 00:01:58.459 609 569 E 015C0/dsoftbus: [AUTH][RecvPacketHead:177]head not enough, len=-1, abandon it.
01-01 00:01:58.459 609 569 I 015C0/dsoftbus: [CONN][SelectTask:1192][base-listener] select task, wakeup from select, select trace=2, wakeup trace=10, events=1
01-01 00:01:58.459 609 569 I 015C0/dsoftbus: [CONN][ProcessCtrlFdEvent:810][base-listener] process ctrl fd event, wakeup ctrl message received, wakeup trace=10, fd=15, ctrl trace=3, read length=4
01-01 00:01:58.459 600 569 W 015C0/dsoftbus: [AUTH][GetAuthIdByConnId:376]auth manager[client] not found, conn[wifi/eth:20]
01-01 00:01:58.459 600 569 W 015C0/dsoftbus: [AUTH][GetAuthIdByConnId:376]auth manager[server] not found, conn[wifi/eth:20]
01-01 00:01:58.460 600 569 I 015C0/dsoftbus: [COMM][LoopTask:176]LoopTask[BusCenter], after HandleMessage message. what=5,arg1=3067472577
01-01 00:01:58.623 602 570 I 015C0/dsoftbus: [TRAN][TransOnSessionClosed:167]TransOnSessionClosed ok

### 【运行环境】

硬件：Hi3516CV610
SDK版本：OpenHarmony4.0 + Hi3516CV610SDK
[/md]

作者: kuaile 时间: 7 天前
能提供日志或者出现问题页面的截图这些信息吗

作者: lvguanghao 时间: 7 天前
回复 kuaile: 已填加日志打印

作者: Hazel 时间: 7 天前
看起来像是hks创建key失败了，海思的芯片应该是调用海思的库生成的

欢迎光临 OpenHarmony开发者论坛 (https://forums.openharmony.cn/)