OpenHarmony开发者论坛

标题: 软总线设备组网（JoinLNN）失败 [打印本页]

作者: liuxinli 时间: 2024-7-10 16:35
标题: 软总线设备组网（JoinLNN）失败
【问题描述】
问题现象和发生的背景

StartDiscovery确认成功
>>>OnDiscoverySuccess subscribeId = 1.
==>Found Devices shown below, please input ENTER key to break.
2024-07-10 15:11:24.547 INFO - >>>OnDeviceFound: IP ADDR

ort - 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

作者: 深开鸿_王石 时间: 2024-7-11 11:34
看起来是auth失败，你干了啥呢

作者: liuxinli 时间: 2024-7-11 13:59
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的系统上运行就正常，现在是无法理解。

作者: liuxinli 时间: 2024-7-11 14:04
回复 深开鸿_王石: 设备认证方式：

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

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

作者: shihao-y 时间: 2024-8-3 16:05
回复 liuxinli: 仅就pkgName这一块发表个人看法。
按接口头文件softbus_bus_center.h来看，其中在每个接口的说明中都强调了

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

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

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

作者: faceoh 时间: 2024-8-19 09:58
回复 liuxinli: 重启设备或者重新认证一下。

作者: 晚秋大魔王 时间: 2025-4-11 16:42
请问大佬是怎么编译softbustool的，求教

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