• Lv0
    粉丝0

积分17 / 贡献0

提问3答案被采纳0文章0

软总线设备组网(JoinLNN)失败

liuxinli 显示全部楼层 发表于 2024-7-10 16:35:31
【问题描述】
问题现象和发生的背景

StartDiscovery确认成功
>>>OnDiscoverySuccess subscribeId = 1.
==>Found Devices shown below, please input ENTER key to break.
2024-07-10 15:11:24.547 INFO - >>>OnDeviceFound: IP ADDRort - 192.168.0.113:39987  |  DevId - ae5b554c0837dc32
2024-07-10 15:11:24.548 INFO - >>>OnDeviceFound: capability-0: 194
StartDiscovery成功后进行JoinLNN提示如下错误

Execute: JoinLNN
==>lease input connection type(0 - WiFi, 1 - BR, 2 - BLE, 3 - ETH):3
==>lease input ip:192.168.0.113
==>lease input port:39987
>>>OnJoinLNNResult networkId = (null), retCode = -425983985.

相关的代码,截图,日志信息
07-10 15:11:53.950  4344  4344 E C05700/CommSdk: [softbus_client_frame_manager.c:68] AddClientPkgName# check PkgNameInfo invalid.
07-10 15:11:53.951  4344  4344 E C05700/CommSdk: [softbus_client_frame_manager.c:117] ClientRegisterPkgName# AddClientPkgName failed. ret=-426442749
07-10 15:11:53.953   585  3326 I C05701/CommSvc: [softbus_server_stub.cpp:853] JoinLNNInner# enter
07-10 15:11:53.953   585  3326 I C05783/LnnBuilder: [lnn_net_builder.c:2252] LnnServerJoin# enter!
07-10 15:11:53.953   585   878 I C05783/LnnBuilder: [lnn_net_builder.c:1381] NetBuilderMessageHandler# net builder process msg=0
07-10 15:11:53.953   585   878 E C05783/LnnBuilder: [lnn_net_builder.c:437] TryPendingJoinRequest# NeedPendingJoinRequest fail
07-10 15:11:53.958   585   878 I C05783/LnnBuilder: [lnn_connection_fsm.c:1097] LnnCreateConnectionFsm# create a new connection fsm. id=14, peerAddr=Ip=192.168.0.***, needConnect=1
07-10 15:11:53.958   585   878 I C05783/LnnBuilder: [lnn_connection_fsm.c:1127] LnnStartConnectionFsm# connection fsm is starting. id=14
07-10 15:11:53.958   585   878 I C05787/LnnState: [lnn_state_machine.c:201] FsmStateMsgHandler# process fsm ctrl msgType=0
07-10 15:11:53.958   585   878 I C05783/LnnBuilder: [lnn_connection_fsm.c:721] AuthStateProcess# auth process. id=14, msgType=0
07-10 15:11:53.960   256   660 I C02d10/HiView-SysEventSource: CheckValidSysEvent: event[DSOFTBUS|BUSCENTER_BEHAVIOR|12070] is valid.
07-10 15:11:53.961   585   878 I C05783/LnnBuilder: [lnn_connection_fsm.c:608] OnJoinLNN# begin join request, id=14, peerIp=192.168.0.***, isNeedConnect=1
07-10 15:11:53.962   585   878 I C05722/AuthConn: [ddf5bbcc000001a, 0, 0] [auth_manager.c:730] StartVerifyDevice# start verify device: requestId=31
07-10 15:11:53.962   585   878 I C05722/AuthConn: [ddf5bbcc000001a, 0, 0] [auth_connection.c:612] ConnectAuthDevice# requestId=31, connType=1, sideType=0
07-10 15:11:53.962   585   878 I C05722/AuthConn: [auth_manager.c:775] StartVerifyDevice# verify device succ. requestId=31
07-10 15:11:53.962   585   878 I C05783/LnnBuilder: [lnn_connection_fsm.c:639] OnJoinLNN# verify request. id=14, requestId=31
07-10 15:11:53.963   256   660 I C02d10/HiView-SysEventSource: CheckValidSysEvent: event[DSOFTBUS|BUSCENTER_BEHAVIOR|12071] is valid.
07-10 15:11:53.966   585   878 I C05763/ConnCommon: [softbus_socket.c:190] ConnToggleNonBlockMode# set to nonblock. fd=24
07-10 15:11:53.966   585   878 E C05763/ConnCommon: [softbus_tcp_socket.c:73] SetQuickStart# set TCP_QUICK_START
07-10 15:11:53.966   585   878 I C05763/ConnCommon: [softbus_tcp_socket.c:235] BindTcpClientAddr# using specified bind addr
07-10 15:11:53.966   585   878 E C05708/CommAdapter: [softbus_adapter_socket.c:282] SoftBusSocketConnect# connect=Operation in progress
07-10 15:11:53.966   585   878 I C05763/ConnCommon: [softbus_tcp_socket.c:292] OpenTcpClientSocket# client open tcp socket, serverIp=192.***.*.113, serverPort=39987, fd=24
07-10 15:11:53.966   585   878 I C05763/ConnCommon: [softbus_base_listener.c:563] AddTrigger# receive request, module=1, fd=24, trigger=2
07-10 15:11:53.966   585   878 I C05763/ConnCommon: [softbus_base_listener.c:631] AddTrigger# add trigger success, module=1, fd=24, trigger=2
07-10 15:11:53.966   585   906 I C05763/ConnCommon: [softbus_base_listener.c:1124] SelectTask# select task, wakeup from select, selectTrace=2, wakeupTraceId=394, events=1
07-10 15:11:53.966   585   906 I C05763/ConnCommon: [softbus_base_listener.c:758] ProcessCtrlFdEvent# wakeup ctrl message received, wakeupTrace=394, fd=16, ctrlTraceId=293, readLength=4
07-10 15:11:53.966   585   878 I C05763/ConnCommon: [softbus_base_listener.c:1247] WakeupSelectThread# wakeup ctrl message sent, writeLength=4, ctrlTraceId=293
07-10 15:11:53.967   585   906 I C05763/ConnCommon: [softbus_base_listener.c:1124] SelectTask# select task, wakeup from select, selectTrace=2, wakeupTraceId=395, events=1
07-10 15:11:53.967   585   906 I C05722/AuthConn: [auth_tcp_connection.c:203] ProcessSocketOutEvent# socket client connect succ: fd=24.
07-10 15:11:53.967   585   906 I C05763/ConnCommon: [softbus_base_listener.c:653] DelTrigger# receive request, module=1, fd=24, trigger=2
07-10 15:11:53.967   585   906 I C05763/ConnCommon: [softbus_base_listener.c:706] DelTrigger# delete trigger success, free fd node now, module=1, fd=24, trigger=2
07-10 15:11:53.967   585   906 I C05763/ConnCommon: [softbus_base_listener.c:1247] WakeupSelectThread# wakeup ctrl message sent, writeLength=4, ctrlTraceId=294
07-10 15:11:53.967   585   906 I C05763/ConnCommon: [softbus_base_listener.c:563] AddTrigger# receive request, module=1, fd=24, trigger=1
07-10 15:11:53.967   585   906 I C05763/ConnCommon: [softbus_base_listener.c:631] AddTrigger# add trigger success, module=1, fd=24, trigger=1
07-10 15:11:53.967   585   906 I C05763/ConnCommon: [softbus_base_listener.c:1247] WakeupSelectThread# wakeup ctrl message sent, writeLength=4, ctrlTraceId=295
07-10 15:11:53.967   585   906 I C05763/ConnCommon: [softbus_socket.c:195] ConnToggleNonBlockMode# nonblock state is already ok. fd=24
07-10 15:11:53.967   585   906 I C05722/AuthConn: [auth_connection.c:350] OnWiFiConnected# OnWiFiConnected: fd=24, side=client
07-10 15:11:53.968   585   906 I C05763/ConnCommon: [softbus_base_listener.c:1124] SelectTask# select task, wakeup from select, selectTrace=2, wakeupTraceId=396, events=1
07-10 15:11:53.968   585   906 I C05763/ConnCommon: [softbus_base_listener.c:758] ProcessCtrlFdEvent# wakeup ctrl message received, wakeupTrace=396, fd=16, ctrlTraceId=294, readLength=4
07-10 15:11:53.968   585   906 I C05763/ConnCommon: [softbus_base_listener.c:758] ProcessCtrlFdEvent# wakeup ctrl message received, wakeupTrace=396, fd=16, ctrlTraceId=295, readLength=4
07-10 15:11:53.969   585   878 I C05722/AuthConn: [auth_manager.c:1055] OnConnectResult# OnConnectResult: requestId=31, result=0
07-10 15:11:53.969   585   878 I C05723/AuthFsm: [ddf5bbcc000001a, 0, 0] [auth_session_fsm.c:240] CreateAuthFsm# create auth fsm. authSeq=7917147536173826074, name=AuthFsm-15, side=client, reqId=31, conn=wifi/eth:24
07-10 15:11:53.969   585   878 I C05787/LnnState: [lnn_state_machine.c:201] FsmStateMsgHandler# process fsm ctrl msgType=0
07-10 15:11:53.969   585   878 I C05723/AuthFsm: [ddf5bbcc000001a, 0, 0] [auth_session_fsm.c:467] SyncDevIdStateEnter# SyncDevIdState: auth fsm enter. authSeq=7917147536173826074
07-10 15:11:53.970   585   878 I C05723/AuthFsm: [ddf5bbcc000001a, 0, 0] [auth_session_message.c:524] PackDeviceIdJson# connType=1
07-10 15:11:53.971   256   660 I C02d10/HiView-SysEventSource: CheckValidSysEvent: event[DSOFTBUS|BUSCENTER_BEHAVIOR|12072] is valid.
07-10 15:11:53.972   256   660 I C02d10/HiView-SysEventSource: CheckValidSysEvent: event[DSOFTBUS|BUSCENTER_BEHAVIOR|12073] is valid.
07-10 15:11:53.974   585   878 I C05723/AuthFsm: [ddf5bbcc000001a, 0, 0] [auth_session_message.c:514] VerifySessionInfoIdType# exchangeIdType=0, udid=0C970**5E537
07-10 15:11:53.974   585   878 I C05723/AuthFsm: [ddf5bbcc000001a, 0, 0] [auth_session_message.c:518] VerifySessionInfoIdType# session info verify succ.
07-10 15:11:53.974   585   878 E C05723/AuthFsm: [ddf5bbcc000001a, 0, 0] [auth_session_message.c:367] PackFastAuth# get udid fail, bypass fastAuth
07-10 15:11:53.974   585   878 I C05722/AuthConn: [ddf5bbcc000001a, 0, 0] [auth_connection.c:698] PostAuthData# auth post dataType=0xffff0003, dataModule=1, dataSeq=7917147536173826074, dataFlag=0, dataLen=349, conn=wifi/eth:24, toServer=server
07-10 15:11:53.974   585   878 I C05722/AuthConn: [ddf5bbcc000001a, 0, 0] [auth_tcp_connection.c:454] SocketPostBytes# fd=24, module=1, seq=7917147536173826074, flag=0, len=349.
07-10 15:11:53.979   585   906 I C05763/ConnCommon: [softbus_base_listener.c:1124] SelectTask# select task, wakeup from select, selectTrace=2, wakeupTraceId=397, events=1
07-10 15:11:53.979   585   906 I C05722/AuthConn: [auth_tcp_connection.c:231] ProcessSocketInEvent# RecvSocketData: fd=24, module=1, seq=7917147536173826074, flag=1, len=221.
07-10 15:11:53.979   585   906 I C05722/AuthConn: [ddf5bbcc000001a, 0, 0] [auth_manager.c:1248] OnDataReceived# auth recv data. type=0xffff0003, module=1, seq=7917147536173826074, flag=1, len=221, conn=wifi/eth:24, fromServer=server
07-10 15:11:53.979   585   878 I C05723/AuthFsm: [ddf5bbcc000001a, 0, 0] [auth_session_fsm.c:671] SyncDevIdStateProcess# auth fsm process. authSeq=7917147536173826074, message=RECV_DEVICE_ID
07-10 15:11:53.979   585   878 E C05708/CommAdapter: [ddf5bbcc000001a, 0, 0] [softbus_adapter_json.cpp:184] JSON_GetIntegerFromObject# [<private>] Cannot find or invalid key. key=exchangeIdType
07-10 15:11:53.979   585   878 I C05723/AuthFsm: [ddf5bbcc000001a, 0, 0] [auth_session_message.c:678] SetExchangeIdTypeAndValue# parse idType failed, ignore
07-10 15:11:53.979   585   878 I C05723/AuthFsm: [ddf5bbcc000001a, 0, 0] [auth_session_message.c:431] UnpackFastAuth# old version or not support fastAuth
07-10 15:11:53.981   585   878 I C05723/AuthFsm: [ddf5bbcc000001a, 0, 0] [auth_session_fsm.c:645] HandleMsgRecvDeviceId# start auth send udid=8D2EC**B69E6
07-10 15:11:53.981   585   878 I C02f03/[DEVAUTH]: [ddf5bbcc000001a, 0, 0] IpcGaAuthDevice: starting ...
07-10 15:11:53.982   400   412 I C02f03/[DEVAUTH]: [ddf5bbcc000001a, 1b6c121, 0] SetRemoteObject: remote object cache index 2
07-10 15:11:53.982   400   412 I C02f03/[DEVAUTH]: [ddf5bbcc000001a, 1b6c121, 0] WithObject: object trans success, set id 2
07-10 15:11:53.982   400   412 I C02f03/[DEVAUTH]: [ddf5bbcc000001a, 1b6c121, 0] AddIpcCallBackByReqId: new callback to add, request id 7917147536173826074, type 2
07-10 15:11:53.982   400   412 I C02f03/[DEVAUTH]: [ddf5bbcc000001a, 1b6c121, 0] AddIpcCallBackByReqId: callback added success, request id 7917147536173826074, type 2
07-10 15:11:53.982   400   412 I C02f03/[DEVAUTH]: [ddf5bbcc000001a, 1b6c121, 0] AddIpcCbObjByReqId: ipc object add success, request id 7917147536173826074, type 2, proxy id 2
07-10 15:11:53.982   400   412 I C02f03/[DEVAUTH]: [ddf5bbcc000001a, 1b6c121, 0] <7917147536173826074>AuthDevice: Begin AuthDevice. [ReqId]:7917147536173826074
07-10 15:11:53.984   400   412 I C02f03/[DEVAUTH]: [ddf5bbcc000001a, 1b6c121, 0] <7917147536173826074>BuildClientAuthContext: [PeerUdid]: 8D2E****
07-10 15:11:53.984   400   412 I C02f03/[DEVAUTH]: [ddf5bbcc000001a, 1b6c121, 0] <7917147536173826074>OpenDevSession: create session success. [AppId]: softbus_auth, [CurNum]: 1, [Id]: 7917147536173826074
07-10 15:11:53.984   400   412 I C02f03/[DEVAUTH]: [ddf5bbcc000001a, 1b6c121, 0] <7917147536173826074>ushStartSessionTask: push start session task success.
07-10 15:11:53.984   400   415 I C02f03/[DEVAUTH]: <7917147536173826072>DoStartSession: start session task begin.
07-10 15:11:53.984   400   415 I C02f03/[DEVAUTH]: <7917147536173826074>GetCandidateAuthInfo: No groupId specified, extract group info without groupId.
07-10 15:11:53.984   585   878 I C02f03/[DEVAUTH]: [ddf5bbcc000001a, 0, 0] IpcGaAuthDevice: process done, ret 0
07-10 15:11:53.984   400   415 I C02f03/[DEVAUTH]: <7917147536173826074>GetCandidateGroups: Try to get device-level candidate groups for auth.
07-10 15:11:53.984   400   415 I C02f03/[DEVAUTH]: <7917147536173826074>GetGroupAuth: Account-related auth type.
07-10 15:11:53.984   400   415 I C02f03/[DEVAUTH]: <7917147536173826074>GaGetAccountGroup: Try to get account group info, groupType: 1.
07-10 15:11:53.984   400   415 I C02f03/[DEVAUTH]: <7917147536173826074>GaGetAccountGroup: The candidate account group size is: 0
07-10 15:11:53.984   400   415 I C02f03/[DEVAUTH]: <7917147536173826074>GetAccountRelatedCandidateGroups: Account related groups not found!
07-10 15:11:53.984   400   415 I C02f03/[DEVAUTH]: <7917147536173826074>HasAccountAuthPlugin: [ACCOUNT_AUTH_PLUGIN]: processSession: plugin is invalid.
07-10 15:11:53.984   585   878 I C05721/AuthHiChain: [ddf5bbcc000001a, 0, 0] [auth_hichain_adapter.c:106] AuthDevice# hichain call authDevice success, times=1
07-10 15:11:53.984   585   878 I C05721/AuthHiChain: [ddf5bbcc000001a, 0, 0] [auth_hichain.c:308] HichainStartAuth# hichain call authDevice succ
07-10 15:11:53.984   400   415 I C02f03/[DEVAUTH]: <7917147536173826074>GaGetLocalDeviceInfo: [SelfUdid]: 0C97****
07-10 15:11:53.984   400   415 I C02f03/[DEVAUTH]: <7917147536173826074>GetGroupAuth: Non-account auth type.
07-10 15:11:53.985   400   415 I C02f03/[DEVAUTH]: <7917147536173826074>FillAuthParams: The candidate group size is: 1
07-10 15:11:53.985   400   415 I C02f03/[DEVAUTH]: <7917147536173826074>CreateTask: Start to create task, moduleType: 1
07-10 15:11:53.985   400   415 E C02f06/HUKS: HksGetParam[335]: invalid params!
07-10 15:11:53.985   399   399 I C02f06/HUKS: OnRemoteRequest[231]: OnRemoteRequest code:7, sessionId = 196
07-10 15:11:53.985   399   399 I C02f06/HUKS: HksGetProcessInfoForIPC[103]: Get callingUid = 3333, userId = 0, sessionId = 196
07-10 15:11:53.985   399   399 I C02f06/HUKS: OnRemoteRequest[275]: finish code:7, total cost 0 ms, sessionId = 196
07-10 15:11:53.985   400   415 I C02f03/[DEVAUTH]: <7917147536173826074>CreateTask: Create task success, taskId: -217416324, moduleType: 1
07-10 15:11:53.985   400   415 E C02f06/HUKS: HksGetParam[335]: invalid params!
07-10 15:11:53.986   399   399 I C02f06/HUKS: OnRemoteRequest[231]: OnRemoteRequest code:7, sessionId = 197
07-10 15:11:53.986   399   399 I C02f06/HUKS: HksGetProcessInfoForIPC[103]: Get callingUid = 3333, userId = 0, sessionId = 197
07-10 15:11:53.986   399   399 I C02f06/HUKS: OnRemoteRequest[275]: finish code:7, total cost 0 ms, sessionId = 197
07-10 15:11:53.986   400   415 E C02f06/HUKS: HksGetParam[335]: invalid params!
07-10 15:11:53.986   399   399 I C02f06/HUKS: OnRemoteRequest[231]: OnRemoteRequest code:7, sessionId = 198
07-10 15:11:53.986   399   399 I C02f06/HUKS: HksGetProcessInfoForIPC[103]: Get callingUid = 3333, userId = 0, sessionId = 198
07-10 15:11:53.986   399   399 I C02f06/HUKS: OnRemoteRequest[275]: finish code:7, total cost 0 ms, sessionId = 198
07-10 15:11:53.987   400   415 I C02f03/[DEVAUTH]: <7917147536173826074>rocessTask: Process task success, taskId: -217416324, moduleType: 1.
07-10 15:11:53.987   400   415 I C02f03/[DEVAUTH]: <7917147536173826074>GaGetAccountGroup: Try to get account group info, groupType: 1.
07-10 15:11:53.987   400   415 I C02f03/[DEVAUTH]: <7917147536173826074>GaGetAccountGroup: The candidate account group size is: 0
07-10 15:11:53.987   400   415 I C02f03/[DEVAUTH]: <7917147536173826074>HasAccountAuthPlugin: [ACCOUNT_AUTH_PLUGIN]: processSession: plugin is invalid.
07-10 15:11:53.987   399   399 I C02f06/HUKS: OnRemoteRequest[231]: OnRemoteRequest code:6, sessionId = 199
07-10 15:11:53.988   399   399 I C02f06/HUKS: HksGetProcessInfoForIPC[103]: Get callingUid = 3333, userId = 0, sessionId = 199
07-10 15:11:53.988   399   399 E C02f06/HUKS: HksStoreIsKeyBlobExist[1179]: check is key exist, ret = -13.
07-10 15:11:53.988   399   399 E C02f06/HUKS: HksIpcServiceKeyExist[293]: HksServiceKeyExist fail, ret = -13
07-10 15:11:53.988   399   399 I C02f06/HUKS: OnRemoteRequest[275]: finish code:6, total cost 0 ms, sessionId = 199
07-10 15:11:53.988   400   415 I C02f03/[DEVAUTH]: <7917147536173826074>CheckKeyExist: [HUKS]: HksKeyExist fail. [Res]: -13
07-10 15:11:53.988   400   415 E C02f03/[DEVAUTH]: <7917147536173826074>IsP2pAuthTokenExist: auth token not exist!
07-10 15:11:53.988   400   415 I C02f03/[DEVAUTH]: <7917147536173826074>GetIdentityInfos: The identity info size is: 1
07-10 15:11:53.989   400   415 I C02f03/[DEVAUTH]: <7917147536173826074>IsKeyExistReturnAliasIfNeeded: KeyPair alias(HEX): 46433633****.
07-10 15:11:53.989   399   399 I C02f06/HUKS: OnRemoteRequest[231]: OnRemoteRequest code:6, sessionId = 200
07-10 15:11:53.989   399   399 I C02f06/HUKS: HksGetProcessInfoForIPC[103]: Get callingUid = 3333, userId = 0, sessionId = 200
07-10 15:11:53.989   256   660 I C02d10/HiView-SysEventSource: CheckValidSysEvent: event[HUKS|FAULT|12074] is valid.
07-10 15:11:53.989   399   399 E C02f06/HUKS: HksStoreIsKeyBlobExist[1179]: check is key exist, ret = -13.
07-10 15:11:53.989   399   399 E C02f06/HUKS: HksIpcServiceKeyExist[293]: HksServiceKeyExist fail, ret = -13
07-10 15:11:53.989   399   399 I C02f06/HUKS: OnRemoteRequest[275]: finish code:6, total cost 0 ms, sessionId = 200
07-10 15:11:53.990   256   660 I C02d10/HiView-SysEventSource: CheckValidSysEvent: event[HUKS|FAULT|12075] is valid.
07-10 15:11:53.991   400   415 I C02f03/[DEVAUTH]: <7917147536173826074>CheckKeyExist: [HUKS]: HksKeyExist fail. [Res]: -13
07-10 15:11:53.991   400   415 E C02f03/[DEVAUTH]: <7917147536173826074>GetCredInfosByPeerIdentity: Failed to get peer device public key!
07-10 15:11:53.991   400   415 I C02f03/[DEVAUTH]: <7917147536173826074>GetAllCredsWithPeer: Get creds with peer success. [CredNum]: 1
07-10 15:11:53.991   400   415 E C02f06/HUKS: HksGetParam[335]: invalid params!
07-10 15:11:53.992   399   399 I C02f06/HUKS: OnRemoteRequest[231]: OnRemoteRequest code:7, sessionId = 201
07-10 15:11:53.992   399   399 I C02f06/HUKS: HksGetProcessInfoForIPC[103]: Get callingUid = 3333, userId = 0, sessionId = 201
07-10 15:11:53.992   399   399 I C02f06/HUKS: OnRemoteRequest[275]: finish code:7, total cost 0 ms, sessionId = 201
07-10 15:11:53.992   400   415 I C02f03/[DEVAUTH]: <7917147536173826074>AddAuthInfoToContextByCred: [SelfUdid]: 0C97****
07-10 15:11:53.993   400   415 I C02f03/[DEVAUTH]: <7917147536173826074>CreateEcSpekeSubSession: create EC_SPEKE authSubSession success.
07-10 15:11:53.993   400   415 I C02f03/[DEVAUTH]: <7917147536173826074>AddStartHandshakeMsg: Start handshake with peer. [CredIndex]: 1, [CredTotalNum]: 1
07-10 15:11:53.993   400   415 I C02f03/[DEVAUTH]: <7917147536173826074>EcSpekeProtocolSwitchState: event: 0, curState: 0, nextState: 2
07-10 15:11:53.993   400   415 E C02f06/HUKS: HksGetParam[335]: invalid params!
07-10 15:11:53.993   399   399 I C02f06/HUKS: OnRemoteRequest[231]: OnRemoteRequest code:7, sessionId = 202
07-10 15:11:53.993   399   399 I C02f06/HUKS: HksGetProcessInfoForIPC[103]: Get callingUid = 3333, userId = 0, sessionId = 202
07-10 15:11:53.994   399   399 I C02f06/HUKS: OnRemoteRequest[275]: finish code:7, total cost 1 ms, sessionId = 202
07-10 15:11:53.994   400   415 I C02f03/[DEVAUTH]: <7917147536173826074>rocStartEvent: process start event success.
07-10 15:11:53.994   400   415 I C02f03/[DEVAUTH]: <7917147536173826074>SessionSwitchState: [Event]: 0, [CurState]: 0, [nextState]: 2
07-10 15:11:53.994   400   415 I C02f03/[DEVAUTH]: <7917147536173826074>rocessTransmitCallback: [Service][In]: ProcessTransmitCallback! [DataLen]: 1188, [ReqId]: 7917147536173826074
07-10 15:11:53.994   400   415 I C02f03/[DEVAUTH]: <7917147536173826074>GaCbOnTransmitWithType: starting ... request id: 7917147536173826074, type 2
07-10 15:11:53.994   585  3326 I C05721/AuthHiChain: [auth_hichain.c:81] OnTransmit# hichain OnTransmit: authSeq=7917147536173826074, len=1188
07-10 15:11:53.995   585  3326 I C05722/AuthConn: [auth_connection.c:698] PostAuthData# auth post dataType=0xffff0001, dataModule=3, dataSeq=7917147536173826074, dataFlag=0, dataLen=1188, conn=wifi/eth:24, toServer=server
07-10 15:11:53.995   585  3326 I C05722/AuthConn: [auth_tcp_connection.c:454] SocketPostBytes# fd=24, module=3, seq=7917147536173826074, flag=0, len=1188.
07-10 15:11:53.995   400   415 I C02f03/[DEVAUTH]: <7917147536173826074>GaCbOnTransmitWithType: process done, request id: 7917147536173826074
07-10 15:11:53.995   400   415 I C02f03/[DEVAUTH]: <7917147536173826074>rocessTransmitCallback: [Service][Out]: ProcessTransmitCallback!
07-10 15:11:54.019   585   906 I C05763/ConnCommon: [softbus_base_listener.c:1124] SelectTask# select task, wakeup from select, selectTrace=2, wakeupTraceId=398, events=1
07-10 15:11:54.019   585   906 I C05722/AuthConn: [auth_tcp_connection.c:231] ProcessSocketInEvent# RecvSocketData: fd=24, module=3, seq=7917147536173826074, flag=0, len=125.
07-10 15:11:54.019   585   906 I C05722/AuthConn: [ddf5bbcc000001a, 0, 0] [auth_manager.c:1248] OnDataReceived# auth recv data. type=0xffff0001, module=3, seq=7917147536173826074, flag=0, len=125, conn=wifi/eth:24, fromServer=server
07-10 15:11:54.019   585   878 I C05723/AuthFsm: [ddf5bbcc000001a, 0, 0] [auth_session_fsm.c:809] DeviceAuthStateProcess# auth fsm process. authSeq=7917147536173826074, message=RECV_AUTH_DATA
07-10 15:11:54.019   585   878 I C02f03/[DEVAUTH]: [ddf5bbcc000001a, 0, 0] IpcGaProcessData: starting ...
07-10 15:11:54.021   400   412 I C02f03/[DEVAUTH]: [ddf5bbcc000001a, 3c9fe01, 0] SetRemoteObject: remote object cache index 3
07-10 15:11:54.021   400   412 I C02f03/[DEVAUTH]: [ddf5bbcc000001a, 3c9fe01, 0] WithObject: object trans success, set id 3
07-10 15:11:54.022   400   412 I C02f03/[DEVAUTH]: [ddf5bbcc000001a, 3c9fe01, 0] IpcServiceGaProcessData: starting ...
07-10 15:11:54.022   400   412 I C02f03/[DEVAUTH]: [ddf5bbcc000001a, 3c9fe01, 0] ResetRemoteObject: remote object used done, idx 2
07-10 15:11:54.022   400   412 I C02f03/[DEVAUTH]: [ddf5bbcc000001a, 3c9fe01, 0] AddIpcCallBackByReqId: callback replaced success, request id 7917147536173826074, type 2
07-10 15:11:54.022   400   412 I C02f03/[DEVAUTH]: [ddf5bbcc000001a, 3c9fe01, 0] AddIpcCbObjByReqId: ipc object add success, request id 7917147536173826074, type 2, proxy id 3
07-10 15:11:54.022   400   412 I C02f03/[DEVAUTH]: [ddf5bbcc000001a, 3c9fe01, 0] <7917147536173826074>rocessData: [GA] Begin ProcessData. [DataLen]: 125, [ReqId]: 7917147536173826074
07-10 15:11:54.022   400   412 I C02f03/[DEVAUTH]: [ddf5bbcc000001a, 3c9fe01, 0] <7917147536173826074>HasAccountAuthPlugin: [ACCOUNT_AUTH_PLUGIN]: processSession: plugin is invalid.
07-10 15:11:54.022   400   412 I C02f03/[DEVAUTH]: [ddf5bbcc000001a, 3c9fe01, 0] <7917147536173826074>ushProcSessionTask: push start session task success.
07-10 15:11:54.022   400   415 I C02f03/[DEVAUTH]: <7917147536173826074>DoProcSession: proc session task begin.
07-10 15:11:54.022   400   415 E C02f03/[DEVAUTH]: <7917147536173826074>HandlePeerAuthError: Failed to auth on next group!
07-10 15:11:54.022   400   415 E C02f03/[DEVAUTH]: <7917147536173826074>ProcV1SessionMsg: process compatibleSubSession fail. [Res]: 12
07-10 15:11:54.022   585   878 I C02f03/[DEVAUTH]: [ddf5bbcc000001a, 0, 0] IpcGaProcessData: process done, ret 0
07-10 15:11:54.023   400   415 I C02f03/[DEVAUTH]: <7917147536173826074>ProcessErrorCallback: [Service][In]: ProcessErrorCallback! [ReqId]: 7917147536173826074
07-10 15:11:54.023   400   415 I C02f03/[DEVAUTH]: <7917147536173826074>GaCbOnErrorWithType: starting ... request id: 7917147536173826074, type 2
07-10 15:11:54.024   585  3326 E C05721/AuthHiChain: [auth_hichain.c:136] OnError# hichain OnError: authSeq=7917147536173826074, errCode=12
07-10 15:11:54.024   400   415 I C02f03/[DEVAUTH]: <7917147536173826074>ResetIpcCallBackNode: appid is invalid
07-10 15:11:54.024   400   415 I C02f03/[DEVAUTH]: <7917147536173826074>ResetRemoteObject: remote object used done, idx 3
07-10 15:11:54.024   400   415 I C02f03/[DEVAUTH]: <7917147536173826074>GaCbOnErrorWithType: process done, request id: 7917147536173826074
07-10 15:11:54.024   400   415 I C02f03/[DEVAUTH]: <7917147536173826074>ProcessErrorCallback: [Service][Out]: ProcessErrorCallback!
07-10 15:11:54.024   400   415 E C02f03/[DEVAUTH]: <7917147536173826074>DoProcSession: ProcessDevSession fail. [Res]: 12
07-10 15:11:54.024   585   878 I C05723/AuthFsm: [ddf5bbcc000001a, 0, 0] [auth_session_fsm.c:809] DeviceAuthStateProcess# auth fsm process. authSeq=7917147536173826074, message=AUTH_ERROR
07-10 15:11:54.024   585   878 E C05723/AuthFsm: [ddf5bbcc000001a, 0, 0] [auth_session_fsm.c:756] HandleMsgAuthError# auth fsm handle hichain error, authSeq=7917147536173826074, reason=-425983985
07-10 15:11:54.024   400   415 I C02f03/[DEVAUTH]: <7917147536173826074>CloseDevSession: close session success. [CurNum]: 0, [Id]: 7917147536173826074
07-10 15:11:54.024   585   878 I C05723/AuthFsm: [ddf5bbcc000001a, 0, 0] [auth_session_fsm.c:402] CompleteAuthSession# auth fsm complete. authSeq=7917147536173826074, side=client, result=-425983985
07-10 15:11:54.024   585   878 E C05723/AuthFsm: [ddf5bbcc000001a, 0, 0] [auth_session_fsm.c:326] ReportAuthResultEvt# report auth result evt enter
07-10 15:11:54.026   585   878 I C05787/LnnState: [ddf5bbcc000001a, 0, 0] [lnn_state_machine.c:245] RemoveMessageFunc# remove fsm data msgType=7
07-10 15:11:54.026   585   878 E C05723/AuthFsm: [ddf5bbcc000001a, 0, 0] [auth_manager.c:925] AuthManagerSetAuthFailed# SetAuthFailed: authSeq=7917147536173826074, requestId=31, reason=-425983985
07-10 15:11:54.027   256   660 I C02d10/HiView-SysEventSource: CheckValidSysEvent: event[DSOFTBUS|BUSCENTER_BEHAVIOR|12076] is valid.
07-10 15:11:54.032   256   660 I C02d10/HiView-SysEventSource: CheckValidSysEvent: event[DSOFTBUS|BUSCENTER_BEHAVIOR|12077] is valid.
07-10 15:11:54.032   585   878 I C05723/AuthFsm: [ddf5bbcc000001a, 0, 0] [auth_manager.c:422] GetAuthManagerByConnInfo# auth manager not found, connType=1, side=client
07-10 15:11:54.032   585   878 E C05723/AuthFsm: [ddf5bbcc000001a, 0, 0] [auth_manager.c:107] DelAuthManager# auth is null
07-10 15:11:54.037   256   660 I C02d10/HiView-SysEventSource: CheckValidSysEvent: event[DSOFTBUS|BUS_CENTER_FAULT_EVT|12078] is valid.
07-10 15:11:54.039   585   878 I C05722/AuthConn: [ddf5bbcc000001a, 0, 0] [auth_manager.c:347] RemoveAuthManagerByConnInfo# auth manager already removed, connType=1, side=client
07-10 15:11:54.039   585   878 E C05722/AuthConn: [ddf5bbcc000001a, 0, 0] [auth_request.c:216] CheckAuthConnCallback# onConnOpened or onConnOpenFailed is null
07-10 15:11:54.039   585   878 I C05783/LnnBuilder: [ddf5bbcc000001a, 0, 0] [lnn_net_builder.c:1702] OnVerifyFailed# verify failed. requestId=31, reason=-425983985
07-10 15:11:54.040   585   878 I C05722/AuthConn: [ddf5bbcc000001a, 0, 0] [auth_connection.c:670] DisconnectAuthDevice# connType=1, connectionId=24
07-10 15:11:54.040   585   878 I C05763/ConnCommon: [ddf5bbcc000001a, 0, 0] [softbus_base_listener.c:653] DelTrigger# receive request, module=1, fd=24, trigger=3
07-10 15:11:54.040   585   878 I C05763/ConnCommon: [ddf5bbcc000001a, 0, 0] [softbus_base_listener.c:706] DelTrigger# delete trigger success, free fd node now, module=1, fd=24, trigger=3
07-10 15:11:54.040   585   906 I C05763/ConnCommon: [softbus_base_listener.c:1124] SelectTask# select task, wakeup from select, selectTrace=2, wakeupTraceId=399, events=1
07-10 15:11:54.040   585   906 I C05763/ConnCommon: [softbus_base_listener.c:758] ProcessCtrlFdEvent# wakeup ctrl message received, wakeupTrace=399, fd=16, ctrlTraceId=296, readLength=4
07-10 15:11:54.040   585   878 I C05763/ConnCommon: [ddf5bbcc000001a, 0, 0] [softbus_base_listener.c:1247] WakeupSelectThread# wakeup ctrl message sent, writeLength=4, ctrlTraceId=296
07-10 15:11:54.041   585   878 I C05763/ConnCommon: [ddf5bbcc000001a, 0, 0] [softbus_socket.c:293] ConnShutdownSocket# shutdown fd=24
07-10 15:11:54.041   585   878 I C05783/LnnBuilder: [lnn_net_builder.c:1381] NetBuilderMessageHandler# net builder process msg=3
07-10 15:11:54.041   585   878 I C05783/LnnBuilder: [lnn_net_builder.c:790] ProcessVerifyResult# connection fsm auth done, fsmId=14, authId=0, retCode=-425983985
07-10 15:11:54.041   585   878 I C05787/LnnState: [lnn_state_machine.c:201] FsmStateMsgHandler# process fsm ctrl msgType=2
07-10 15:11:54.041   585   878 I C05787/LnnState: [lnn_state_machine.c:201] FsmStateMsgHandler# process fsm ctrl msgType=3
07-10 15:11:54.042   585   878 I C05723/AuthFsm: [auth_session_fsm.c:258] AuthFsmDeinitCallback# auth fsm deinit callback enter
07-10 15:11:54.042   585   878 I C05723/AuthFsm: [auth_session_fsm.c:247] DestroyAuthFsm# destroy auth. authSeq=7917147536173826074, side=client, reqId=31
07-10 15:11:54.042   585   878 I C05783/LnnBuilder: [lnn_connection_fsm.c:721] AuthStateProcess# auth process. id=14, msgType=1
07-10 15:11:54.042   585   878 I C05783/LnnBuilder: [lnn_connection_fsm.c:692] OnAuthDone# auth done, id=14, authId=0, result=-425983985, connType=3
07-10 15:11:54.042   585   878 E C05783/LnnBuilder: [lnn_connection_fsm.c:703] OnAuthDone# auth failed, id=14, authId=0, requestId=31, reason=-425983985, connType=3
07-10 15:11:54.042   585   878 I C05787/LnnState: [lnn_state_machine.c:245] RemoveMessageFunc# remove fsm data msgType=6
07-10 15:11:54.042   585   878 E C05783/LnnBuilder: [lnn_connection_fsm.c:304] IsDeviceTypePc# nodeInfo is NULL
07-10 15:11:54.042   585   878 I C05783/LnnBuilder: [lnn_connection_fsm.c:278] ReportLnnResultEvt# report lnn result evt enter
07-10 15:11:54.043   585   878 I C05786/LnnEvent: [bus_center_event.c:372] LnnNotifyJoinResult# notify join LNN result=-425983985
07-10 15:11:54.044   585   878 I C05701/CommSvc: [softbus_client_info_manager.cpp:91] GetSoftbusClientProxy# GetSoftbusClientProxy, pid=4344, pkgname=ohos.dsoftbus.sansi
07-10 15:11:54.044   585   878 I C05723/AuthFsm: [auth_manager.c:1339] AuthHandleLeaveLNN# auth handle leave LNN, authId=0
07-10 15:11:54.045   585   878 E C05723/AuthFsm: [auth_manager.c:209] FindAuthManagerByAuthId# auth manager not found. authId=0
07-10 15:11:54.045   585   878 E C05723/AuthFsm: [auth_manager.c:1346] AuthHandleLeaveLNN# auth manager not found, authId=0
07-10 15:11:54.045   585   878 E C05783/LnnBuilder: [lnn_connection_fsm.c:433] CompleteJoinLNN# join failed, ready clean, id=14, retCode=-425983985

我想要达到的结果

JoinLNN组网成功


【运行环境】
硬件:rk3568
SDK版本:4.1
您尚未登录,无法参与评论,登录后可以:
参与开源共建问题交流
认同或收藏高质量问答
获取积分成为开源共建先驱

精彩评论5

深开鸿_王石

沙发 发表于 2024-7-11 11:34:19
看起来是auth失败,你干了啥呢

liuxinli

发表于 2024-7-11 14:04  IP属地: 上海市

回复 深开鸿_王石: 设备认证 方式:

启动A,B两端设备,打开设置->WLAN,连接同一WiFi;
A端打开计算器,点击右上角“向上→”图标,B端设备上会弹出6位数字的验证图片;
在A端输入6位验证码;
绑定成功;

上面的过程成功后就啥也没有修改,其它操作都是softbus_tool 命令行,先StartDiscovery发现设备,然后JoinLNN组网,其它未操作。

faceoh

发表于 2024-8-19 09:58  IP属地: 江苏省扬州市

回复 liuxinli: 重启设备或者重新认证一下。

【2 条回复】

liuxinli

板凳 发表于 2024-7-11 13:59:42
rk3568开发板4.1版本上运行softbus_tool软总线总是报: check PkgNameInfo invalid

查询软总线源码发现,pkgName重复时会报此错误:

static bool CheckPkgNameInfo(const char *pkgName)
{
    ListNode *item = NULL;
    PkgNameInfo *info = NULL;
    uint32_t totalNum = 0;
    LIST_FOR_EACH(item, &g_pkgNameList) {
        totalNum++;
        info = LIST_ENTRY(item, PkgNameInfo, node);
        if (strcmp(info->pkgName, pkgName) == 0) {
            return false;
        }
    }
    if (totalNum >= SOFTBUS_PKGNAME_MAX_NUM) {
        COMM_LOGI(COMM_SDK, "number of pkgName exceeds maximum");
        return false;
    }
    return true;
}

疑惑:

软总线接口中有很多地方需要用到pkgName,比如StartDiscovery、JoinLNN、PublishLNN、RefreshLNN等接口,如果按照上面的不可重复,则每个接口都要一个新的pkgName;但软总线源码中又有限制pkgName最多两个,这里面就很矛盾(SOFTBUS_PKGNAME_MAX_NUM 定义值是 2)。

其它:

softbus_tool 工具在3.2的系统上运行就正常,现在是无法理解。




shihao-y

发表于 2024-8-3 16:05  IP属地: 广东省广州市

回复 liuxinli: 仅就pkgName这一块发表个人看法。
按接口头文件softbus_bus_center.h来看,其中在每个接口的说明中都强调了

For the same caller, the value of this parameter must be the same for all functions.

。所以应该使用一致的pkgName进行调用。
至于贴出来的检查报错部分,你再仔细看一下它的上层调用就能发现肯定是有一个初始化标志来进行“是否已经初始化”过的判断的。

至于调用joinLNN失败,抱歉给不到建议。

【1 条回复】

Copyright   ©2023  OpenHarmony开发者论坛  京ICP备2020036654号-3 |技术支持 Discuz!

返回顶部