Pixel 6 Pro updated to Android 13 and AndroidX UWB Alpha 3 with Controller support…
New low level messages from the Android Qorvo driver layer, but it still times out!
2022-08-26 11:28:41.295 1750-3646/? I/UwbSessionManager: initSession() : Enter - sessionId : 1
2022-08-26 11:28:41.297 1750-32530/? I/uwb: uwb_uci_jni_rust: Java_com_android_server_uwb_jni_NativeUwbManager_nativeSessionInit: enter
2022-08-26 11:28:41.297 1750-27465/? D/uwb: uwb_uci_rust::uci: Received blocking cmd UciSessionInit(1, 0)
2022-08-26 11:28:41.297 1750-27465/? D/uwb: uwb_uci_rust::uci: Sending HAL UCI message UciCommandPacket { uci_packet: UciPacketData { group_id: SessionConfig, message_type: Command, opcode: 0, child: UciCommand(UciCommandData { child: SessionCommand(SessionCommandData { child: SessionInitCmd(SessionInitCmdData { session_id: 1, session_type: FiraRangingSession }) }) }) }, uci_command: UciCommandData { child: SessionCommand(SessionCommandData { child: SessionInitCmd(SessionInitCmdData { session_id: 1, session_type: FiraRangingSession }) }) } }
2022-08-26 11:28:41.297 1750-27465/? I/uwb: uwb_uci_rust::uci: UWB state change from Ready to W4UciResp
2022-08-26 11:28:41.297 904-904/? I/qorvo.uwb.UciBackendAndroidCCC: SessionInit: MT/GID/OID 2100 CMD
2022-08-26 11:28:41.298 904-904/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_session_init: MT/GID/OID 2100 CMD
2022-08-26 11:28:41.298 904-904/? I/qorvo.uwb.fira_helper: call_fira: call_id 1 session_id 1
2022-08-26 11:28:41.298 904-904/? I/qorvo.uwb.uwbmac: ack_handler
2022-08-26 11:28:41.298 904-904/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_session_init: MT/GID/OID 2100 RSP 0
2022-08-26 11:28:41.298 904-904/? I/qorvo.uwb.uci_backend_fira: state_notification_with_code: MT/GID/OID 6102 NTF 0 (0)
2022-08-26 11:28:41.298 1750-27465/? D/uwb: uwb_uci_rust::uci: Received HAL UCI message SessionInitRsp(SessionInitRspPacket { uci_packet: UciPacketData { group_id: SessionConfig, message_type: Response, opcode: 0, child: UciResponse(UciResponseData { child: SessionResponse(SessionResponseData { child: SessionInitRsp(SessionInitRspData { status: UciStatusOk }) }) }) }, uci_response: UciResponseData { child: SessionResponse(SessionResponseData { child: SessionInitRsp(SessionInitRspData { status: UciStatusOk }) }) }, session_response: SessionResponseData { child: SessionInitRsp(SessionInitRspData { status: UciStatusOk }) }, session_init_rsp: SessionInitRspData { status: UciStatusOk } })
2022-08-26 11:28:41.298 1750-27465/? I/uwb: uwb_uci_rust::uci: UWB state change from W4UciResp to Ready
2022-08-26 11:28:41.298 1750-32530/? V/uwb: uwb_uci_rust::uci: SessionInitRsp(SessionInitRspPacket { uci_packet: UciPacketData { group_id: SessionConfig, message_type: Response, opcode: 0, child: UciResponse(UciResponseData { child: SessionResponse(SessionResponseData { child: SessionInitRsp(SessionInitRspData { status: UciStatusOk }) }) }) }, uci_response: UciResponseData { child: SessionResponse(SessionResponseData { child: SessionInitRsp(SessionInitRspData { status: UciStatusOk }) }) }, session_response: SessionResponseData { child: SessionInitRsp(SessionInitRspData { status: UciStatusOk }) }, session_init_rsp: SessionInitRspData { status: UciStatusOk } })
2022-08-26 11:28:41.298 1750-27465/? D/uwb: uwb_uci_rust::uci: Received hal notification SessionStatusNtf(SessionStatusNtfPacket { uci_packet: UciPacketData { group_id: SessionConfig, message_type: Notification, opcode: 2, child: UciNotification(UciNotificationData { child: SessionNotification(SessionNotificationData { child: SessionStatusNtf(SessionStatusNtfData { session_id: 1, session_state: SessionStateInit, reason_code: StateChangeWithSessionManagementCommands }) }) }) }, uci_notification: UciNotificationData { child: SessionNotification(SessionNotificationData { child: SessionStatusNtf(SessionStatusNtfData { session_id: 1, session_state: SessionStateInit, reason_code: StateChangeWithSessionManagementCommands }) }) }, session_notification: SessionNotificationData { child: SessionStatusNtf(SessionStatusNtfData { session_id: 1, session_state: SessionStateInit, reason_code: StateChangeWithSessionManagementCommands }) }, session_status_ntf: SessionStatusNtfData { session_id: 1, session_state: SessionStateInit, reason_code: StateChangeWithSessionManagementCommands } })
2022-08-26 11:28:41.298 1750-27465/? I/uwb: uwb_uci_rust::uci: Session 1 initialized, invoking session init HAL API
2022-08-26 11:28:41.299 1750-27465/? D/NativeUwbManager: onSessionStatusNotificationReceived(1, 0, 0)
2022-08-26 11:28:41.299 1750-27465/? I/UwbSessionManager: onSessionStatusNotificationReceived - Session ID : 1, state : INIT reasonCode:0
2022-08-26 11:28:41.299 1750-32530/? D/UwbConfManager: setAppConfigurations for protocol: fira
2022-08-26 11:28:41.300 1750-32530/? I/uwb: uwb_uci_jni_rust: Java_com_android_server_uwb_jni_NativeUwbManager_nativeSetAppConfigurations: enter
2022-08-26 11:28:41.300 1750-27465/? D/uwb: uwb_uci_rust::uci: Received blocking cmd UciSetAppConfig { session_id: 1, no_of_params: 44, app_config_param_len: 153, app_configs: [0, 1, 1, 1, 1, 2, 2, 1, 0, 3, 1, 0, 4, 1, 9, 5, 1, 1, 6, 2, 240, 242, 7, 2, 253, 183, 8, 2, 96, 9, 9, 4, 240, 0, 0, 0, 11, 1, 0, 12, 1, 3, 13, 1, 1, 14, 1, 1, 15, 2, 0, 0, 16, 2, 32, 78, 17, 1, 1, 18, 1, 3, 20, 1, 10, 21, 1, 2, 22, 1, 0, 23, 1, 1, 27, 1, 6, 28, 1, 0, 31, 1, 0, 35, 1, 0, 36, 1, 0, 37, 1, 50, 38, 1, 0, 39, 2, 0, 76, 40, 6, 0, 0, 0, 0, 0, 0, 41, 1, 1, 42, 2, 0, 0, 43, 4, 0, 0, 0, 0, 44, 1, 0, 45, 1, 0, 46, 1, 1, 47, 1, 3, 48, 4, 0, 0, 0, 0, 49, 1, 0, 53, 1, 1, 227, 1, 0, 228, 1, 0, 229, 1, 0] }
2022-08-26 11:28:41.300 1750-27465/? D/uwb: uwb_uci_rust::uci: Sending HAL UCI message UciCommandPacket { uci_packet: UciPacketData { group_id: SessionConfig, message_type: Command, opcode: 3, child: UciCommand(UciCommandData { child: SessionCommand(SessionCommandData { child: SessionSetAppConfigCmd(SessionSetAppConfigCmdData { session_id: 1, tlvs: [AppConfigTlv { cfg_id: DeviceType, v: [1] }, AppConfigTlv { cfg_id: RangingRoundUsage, v: [2] }, AppConfigTlv { cfg_id: StsConfig, v: [0] }, AppConfigTlv { cfg_id: MultiNodeMode, v: [0] }, AppConfigTlv { cfg_id: ChannelNumber, v: [9] }, AppConfigTlv { cfg_id: NoOfControlee, v: [1] }, AppConfigTlv { cfg_id: DeviceMacAddress, v: [240, 242] }, AppConfigTlv { cfg_id: DstMacAddress, v: [253, 183] }, AppConfigTlv { cfg_id: SlotDuration, v: [96, 9] }, AppConfigTlv { cfg_id: RangingInterval, v: [240, 0, 0, 0] }, AppConfigTlv { cfg_id: MacFcsType, v: [0] }, AppConfigTlv { cfg_id: RangingRoundControl, v: [3] }, AppConfigTlv { cfg_id: AoaResultReq, v: [1] }, AppConfigTlv { cfg_id: RngDataNtf, v: [1] }, AppConfigTlv { cfg_id: RngDataNtfProximityNear, v: [0, 0] }, AppConfigTlv { cfg_id: RngDataNtfProximityFar, v: [32, 78] }, AppConfigTlv { cfg_id: DeviceRole, v: [1] }, AppConfigTlv { cfg_id: RframeConfig, v: [3] }, AppConfigTlv { cfg_id: PreambleCodeIndex, v: [10] }, AppConfigTlv { cfg_id: SfdId, v: [2] }, AppConfigTlv { cfg_id: PsduDataRate, v: [0] }, AppConfigTlv { cfg_id: PreambleDuration, v: [1] }, AppConfigTlv { cfg_id: SlotsPerRr, v: [6] }, AppConfigTlv { cfg_id: TxAdaptivePayloadPower, v: [0] }, AppConfigTlv { cfg_id: PrfMode, v: [0] }, AppConfigTlv { cfg_id: KeyRotation, v: [0] }, AppConfigTlv { cfg_id: KeyRotationRate, v: [0] }, AppConfigTlv { cfg_id: SessionPriority, v: [50] }, AppConfigTlv { cfg_id: MacAddressMode, v: [0] }, AppConfigTlv { cfg_id: VendorId, v: [0, 76] }, AppConfigTlv { cfg_id: StaticStsIv, v: [0, 0, 0, 0, 0, 0] }, AppConfigTlv { cfg_id: NumberOfStsSegments, v: [1] }, AppConfigTlv { cfg_id: MaxRrRetry, v: [0, 0] }, AppConfigTlv { cfg_id: UwbInitiationTime, v: [0, 0, 0, 0] }, AppConfigTlv { cfg_id: HoppingMode, v: [0] }, AppConfigTlv { cfg_id: BlockStrideLength, v: [0] }, AppConfigTlv { cfg_id: ResultReportConfig, v: [1] }, AppConfigTlv { cfg_id: InBandTerminationAttemptCount, v: [3] }, AppConfigTlv { cfg_id: SubSessionId, v: [0, 0, 0, 0] }, AppConfigTlv { cfg_id: BprfPhrDataRate, v: [0] }, AppConfigTlv { cfg_id: StsLength, v: [1] }, AppConfigTlv { cfg_id: NbOfRangeMeasurements, v: [0] }, AppConfigTlv { cfg_id: NbOfAzimuthMeasurements, v: [0] }, AppConfigTlv { cfg_id: NbOfElevationMeasurements, v: [0] }] }) }) }) }, uci_command: UciCommandData { child: SessionCommand(SessionCommandData { child: SessionSetAppConfigCmd(SessionSetAppConfigCmdData { session_id: 1, tlvs: [AppConfigTlv { cfg_id: DeviceType, v: [1] }, AppConfigTlv { cfg_id: RangingRoundUsage, v: [2] }, AppConfigTlv { cfg_id: StsConfig, v: [0] }, AppConfigTlv { cfg_id: MultiNodeMode, v: [0] }, AppConfigTlv { cfg_id: ChannelNumber, v: [9] }, AppConfigTlv { cfg_id: NoOfControlee, v: [1] }, AppConfigTlv { cfg_id: DeviceMacAddress, v: [240, 242] }, AppConfigTlv { cfg_id: DstMacAddress, v: [253, 183] }, AppConfigTlv { cfg_id: SlotDuration, v: [96, 9] }, AppConfigTlv { cfg_id: RangingInterval, v: [240, 0, 0, 0] }, AppConfigTlv { cfg_id: MacFcsType, v: [0] }, AppConfigTlv { cfg_id: RangingRoundControl, v: [3] }, AppConfigTlv { cfg_id: AoaResultReq, v: [1] }, AppConfigTlv { cfg_id: RngDataNtf, v: [1] }, AppConfigTlv { cfg_id: RngDataNtfProximityNear, v: [0, 0] }, AppConfigTlv { cfg_id: RngDataNtfProximityFar, v: [32, 78] }, AppConfigTlv { cfg_id: DeviceRole, v: [1] }, AppConfigTlv { cfg_id: RframeConfig, v: [3] }, AppConfigTlv { cfg_id: PreambleCodeIndex, v: [10] }, AppConfigTlv { cfg_id: SfdId, v: [2] }, AppConfigTlv { cfg_id: PsduDataRate, v: [0] }, AppConfigTlv { cfg_id: PreambleDuration, v: [1] }, AppConfigTlv { cfg_id: SlotsPerRr, v: [6] }, AppConfigTlv { cfg_id: TxAdaptivePayloadPower, v: [0] }, AppConfigTlv { cfg_id: PrfMode, v: [0] }, AppConfigTlv { cfg
2022-08-26 11:28:41.300 1750-27465/? D/uwb: _id: KeyRotation, v: [0] }, AppConfigTlv { cfg_id: KeyRotationRate, v: [0] }, AppConfigTlv { cfg_id: SessionPriority, v: [50] }, AppConfigTlv { cfg_id: MacAddressMode, v: [0] }, AppConfigTlv { cfg_id: VendorId, v: [0, 76] }, AppConfigTlv { cfg_id: StaticStsIv, v: [0, 0, 0, 0, 0, 0] }, AppConfigTlv { cfg_id: NumberOfStsSegments, v: [1] }, AppConfigTlv { cfg_id: MaxRrRetry, v: [0, 0] }, AppConfigTlv { cfg_id: UwbInitiationTime, v: [0, 0, 0, 0] }, AppConfigTlv { cfg_id: HoppingMode, v: [0] }, AppConfigTlv { cfg_id: BlockStrideLength, v: [0] }, AppConfigTlv { cfg_id: ResultReportConfig, v: [1] }, AppConfigTlv { cfg_id: InBandTerminationAttemptCount, v: [3] }, AppConfigTlv { cfg_id: SubSessionId, v: [0, 0, 0, 0] }, AppConfigTlv { cfg_id: BprfPhrDataRate, v: [0] }, AppConfigTlv { cfg_id: StsLength, v: [1] }, AppConfigTlv { cfg_id: NbOfRangeMeasurements, v: [0] }, AppConfigTlv { cfg_id: NbOfAzimuthMeasurements, v: [0] }, AppConfigTlv { cfg_id: NbOfElevationMeasurements, v: [0] }] }) }) } }
2022-08-26 11:28:41.300 1750-27465/? I/uwb: uwb_uci_rust::uci: UWB state change from Ready to W4UciResp
2022-08-26 11:28:41.300 904-904/? I/qorvo.uwb.UciBackendAndroidCCC: SessionSetAppConfig: MT/GID/OID 2103 CMD
2022-08-26 11:28:41.300 904-904/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_session_set_app_config: MT/GID/OID 2103 CMD
2022-08-26 11:28:41.300 904-904/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_session_set_app_config: session_id=1
2022-08-26 11:28:41.300 904-904/? I/qorvo.uwb.fira_helper: call_fira: call_id 10 session_id 1
2022-08-26 11:28:41.300 904-904/? I/qorvo.uwb.uwbmac: report_nl_cb
2022-08-26 11:28:41.300 904-904/? I/qorvo.uwb.uwbmac: ack_handler
2022-08-26 11:28:41.300 904-904/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_session_set_app_config: mac_state=3
2022-08-26 11:28:41.300 904-904/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_session_set_app_config: uci_state=0
2022-08-26 11:28:41.301 904-904/? I/qorvo.uwb.fira_helper: call_fira: call_id 12 session_id 1
2022-08-26 11:28:41.301 904-904/? I/qorvo.uwb.uwbmac: ack_handler
2022-08-26 11:28:41.301 904-904/? I/qorvo.uwb.uwbmac: ack_handler
2022-08-26 11:28:41.301 904-904/? I/qorvo.uwb.fira_helper: call_fira: call_id 10 session_id 1
2022-08-26 11:28:41.301 904-904/? I/qorvo.uwb.uwbmac: report_nl_cb
2022-08-26 11:28:41.301 904-904/? I/qorvo.uwb.uwbmac: ack_handler
2022-08-26 11:28:41.301 904-904/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_session_set_app_config: mac_state=3
2022-08-26 11:28:41.301 904-904/? I/qorvo.uwb.fira_helper: call_fira: call_id 9 session_id 1
2022-08-26 11:28:41.301 904-904/? I/qorvo.uwb.uwbmac: report_nl_cb
2022-08-26 11:28:41.301 904-904/? I/qorvo.uwb.uwbmac: ack_handler
2022-08-26 11:28:41.301 904-904/? I/qorvo.uwb.fira_helper: call_fira: call_id 5 session_id 1
2022-08-26 11:28:41.301 904-904/? I/qorvo.uwb.uwbmac: ack_handler
2022-08-26 11:28:41.301 904-904/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_session_set_app_config: MT/GID/OID 4103 RSP 0
2022-08-26 11:28:41.301 904-904/? I/qorvo.uwb.uci_backend_fira: state_notification_with_code: MT/GID/OID 6102 NTF 3 (0)
2022-08-26 11:28:41.302 1750-27465/? D/uwb: uwb_uci_rust::uci: Received HAL UCI message SessionSetAppConfigRsp(SessionSetAppConfigRspPacket { uci_packet: UciPacketData { group_id: SessionConfig, message_type: Response, opcode: 3, child: UciResponse(UciResponseData { child: SessionResponse(SessionResponseData { child: SessionSetAppConfigRsp(SessionSetAppConfigRspData { status: UciStatusOk, cfg_status: [] }) }) }) }, uci_response: UciResponseData { child: SessionResponse(SessionResponseData { child: SessionSetAppConfigRsp(SessionSetAppConfigRspData { status: UciStatusOk, cfg_status: [] }) }) }, session_response: SessionResponseData { child: SessionSetAppConfigRsp(SessionSetAppConfigRspData { status: UciStatusOk, cfg_status: [] }) }, session_set_app_config_rsp: SessionSetAppConfigRspData { status: UciStatusOk, cfg_status: [] } })
2022-08-26 11:28:41.302 1750-27465/? I/uwb: uwb_uci_rust::uci: UWB state change from W4UciResp to Ready
2022-08-26 11:28:41.302 1750-27465/? D/uwb: uwb_uci_rust::uci: Received hal notification SessionStatusNtf(SessionStatusNtfPacket { uci_packet: UciPacketData { group_id: SessionConfig, message_type: Notification, opcode: 2, child: UciNotification(UciNotificationData { child: SessionNotification(SessionNotificationData { child: SessionStatusNtf(SessionStatusNtfData { session_id: 1, session_state: SessionStateIdle, reason_code: StateChangeWithSessionManagementCommands }) }) }) }, uci_notification: UciNotificationData { child: SessionNotification(SessionNotificationData { child: SessionStatusNtf(SessionStatusNtfData { session_id: 1, session_state: SessionStateIdle, reason_code: StateChangeWithSessionManagementCommands }) }) }, session_notification: SessionNotificationData { child: SessionStatusNtf(SessionStatusNtfData { session_id: 1, session_state: SessionStateIdle, reason_code: StateChangeWithSessionManagementCommands }) }, session_status_ntf: SessionStatusNtfData { session_id: 1, session_state: SessionStateIdle, reason_code: StateChangeWithSessionManagementCommands } })
2022-08-26 11:28:41.302 1750-27465/? D/NativeUwbManager: onSessionStatusNotificationReceived(1, 3, 0)
2022-08-26 11:28:41.302 1750-27465/? I/UwbSessionManager: onSessionStatusNotificationReceived - Session ID : 1, state : IDLE reasonCode:0
2022-08-26 11:28:41.302 1750-32530/? V/uwb: uwb_uci_rust::uci: SessionSetAppConfigRsp(SessionSetAppConfigRspPacket { uci_packet: UciPacketData { group_id: SessionConfig, message_type: Response, opcode: 3, child: UciResponse(UciResponseData { child: SessionResponse(SessionResponseData { child: SessionSetAppConfigRsp(SessionSetAppConfigRspData { status: UciStatusOk, cfg_status: [] }) }) }) }, uci_response: UciResponseData { child: SessionResponse(SessionResponseData { child: SessionSetAppConfigRsp(SessionSetAppConfigRspData { status: UciStatusOk, cfg_status: [] }) }) }, session_response: SessionResponseData { child: SessionSetAppConfigRsp(SessionSetAppConfigRspData { status: UciStatusOk, cfg_status: [] }) }, session_set_app_config_rsp: SessionSetAppConfigRspData { status: UciStatusOk, cfg_status: [] } })
2022-08-26 11:28:41.302 1750-32530/? I/UwbConfManager: setAppConfigurations respData: UwbConfigStatusData { status = 0 length = 0, tlvs = [[]] }
2022-08-26 11:28:41.303 1750-32530/? I/UwbSessionNotiManager: IUwbRangingCallbacks - onRangingOpened
2022-08-26 11:28:41.304 1750-2025/? I/UwbSessionManager: sessionInit() : finish - sessionId : 1
2022-08-26 11:28:41.305 2692-31836/? I/NearbyUWB: UWB session opened: 1 [CONTEXT service_id=49 ]
2022-08-26 11:28:41.305 2692-29735/? I/NearbyUWB: exec UWB operation Start ranging [CONTEXT service_id=49 ]
2022-08-26 11:28:41.305 1750-3646/? I/UwbSessionManager: startRanging() - Session ID : 1
2022-08-26 11:28:41.306 1750-32531/? I/uwb: uwb_uci_jni_rust: Java_com_android_server_uwb_jni_NativeUwbManager_nativeRangingStart: enter
2022-08-26 11:28:41.306 1750-27465/? D/uwb: uwb_uci_rust::uci: Received blocking cmd UciStartRange(1)
2022-08-26 11:28:41.306 1750-27465/? D/uwb: uwb_uci_rust::uci: Sending HAL UCI message UciCommandPacket { uci_packet: UciPacketData { group_id: RangingSessionControl, message_type: Command, opcode: 0, child: UciCommand(UciCommandData { child: RangingCommand(RangingCommandData { child: RangeStartCmd(RangeStartCmdData { session_id: 1 }) }) }) }, uci_command: UciCommandData { child: RangingCommand(RangingCommandData { child: RangeStartCmd(RangeStartCmdData { session_id: 1 }) }) } }
2022-08-26 11:28:41.306 1750-27465/? I/uwb: uwb_uci_rust::uci: UWB state change from Ready to W4UciResp
2022-08-26 11:28:41.306 904-904/? I/qorvo.uwb.UciBackendAndroidCCC: RangingStart: MT/GID/OID 2200 CMD
2022-08-26 11:28:41.306 904-904/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_ranging_start: MT/GID/OID 2200 CMD
2022-08-26 11:28:41.306 904-904/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_ranging_start: session_id=1
2022-08-26 11:28:41.306 904-904/? I/qorvo.uwb.fira_helper: call_fira: call_id 10 session_id 1
2022-08-26 11:28:41.306 904-904/? I/qorvo.uwb.uwbmac: report_nl_cb
2022-08-26 11:28:41.306 904-904/? I/qorvo.uwb.uwbmac: ack_handler
2022-08-26 11:28:41.306 904-904/? I/qorvo.uwb.uwbmac: ack_handler
2022-08-26 11:28:41.310 1093-1093/? D/AOC: A3:MSG: controller.cc, 811: CNTRL: ipc: uwb_service, cmd ID: 0x00cd, tag: 0x3e, rc: 0
2022-08-26 11:28:41.310 1093-1093/? D/AOC: A3:MSG: controller.cc, 811: CNTRL: ipc: uwb_service, cmd ID: 0x00cb, tag: 0x3f, rc: 0
2022-08-26 11:28:41.311 1093-1093/? D/AOC: A3:MSG: controller.cc, 811: CNTRL: ipc: uwb_service, cmd ID: 0x00cd, tag: 0x40, rc: 0
2022-08-26 11:28:41.324 1093-1093/? D/AOC: A3:MSG: controller.cc, 811: CNTRL: ipc: uwb_service, cmd ID: 0x00cd, tag: 0x41, rc: 0
2022-08-26 11:28:41.334 904-904/? I/qorvo.uwb.fira_helper: call_fira: call_id 2 session_id 1
2022-08-26 11:28:41.336 904-904/? I/qorvo.uwb.uwbmac: ack_handler
2022-08-26 11:28:41.336 904-904/? I/qorvo.uwb.uci_backend_fira: uci_backend_fira_ranging_start: MT/GID/OID 2200 RSP 0
2022-08-26 11:28:41.337 904-904/? I/qorvo.uwb.uci_backend_fira: state_notification_with_code: MT/GID/OID 6102 NTF 2 (0)
2022-08-26 11:28:41.337 1750-27465/? D/uwb: uwb_uci_rust::uci: Received HAL UCI message RangeStartRsp(RangeStartRspPacket { uci_packet: UciPacketData { group_id: RangingSessionControl, message_type: Response, opcode: 0, child: UciResponse(UciResponseData { child: RangingResponse(RangingResponseData { child: RangeStartRsp(RangeStartRspData { status: UciStatusOk }) }) }) }, uci_response: UciResponseData { child: RangingResponse(RangingResponseData { child: RangeStartRsp(RangeStartRspData { status: UciStatusOk }) }) }, ranging_response: RangingResponseData { child: RangeStartRsp(RangeStartRspData { status: UciStatusOk }) }, range_start_rsp: RangeStartRspData { status: UciStatusOk } })
2022-08-26 11:28:41.337 1750-27465/? I/uwb: uwb_uci_rust::uci: UWB state change from W4UciResp to Ready
2022-08-26 11:28:41.337 1750-32531/? V/uwb: uwb_uci_rust::uci: RangeStartRsp(RangeStartRspPacket { uci_packet: UciPacketData { group_id: RangingSessionControl, message_type: Response, opcode: 0, child: UciResponse(UciResponseData { child: RangingResponse(RangingResponseData { child: RangeStartRsp(RangeStartRspData { status: UciStatusOk }) }) }) }, uci_response: UciResponseData { child: RangingResponse(RangingResponseData { child: RangeStartRsp(RangeStartRspData { status: UciStatusOk }) }) }, ranging_response: RangingResponseData { child: RangeStartRsp(RangeStartRspData { status: UciStatusOk }) }, range_start_rsp: RangeStartRspData { status: UciStatusOk } })
2022-08-26 11:28:41.337 1750-27465/? D/uwb: uwb_uci_rust::uci: Received hal notification DeviceStatusNtf(DeviceStatusNtfPacket { uci_packet: UciPacketData { group_id: Core, message_type: Notification, opcode: 1, child: UciNotification(UciNotificationData { child: CoreNotification(CoreNotificationData { child: DeviceStatusNtf(DeviceStatusNtfData { device_state: DeviceStateActive }) }) }) }, uci_notification: UciNotificationData { child: CoreNotification(CoreNotificationData { child: DeviceStatusNtf(DeviceStatusNtfData { device_state: DeviceStateActive }) }) }, core_notification: CoreNotificationData { child: DeviceStatusNtf(DeviceStatusNtfData { device_state: DeviceStateActive }) }, device_status_ntf: DeviceStatusNtfData { device_state: DeviceStateActive } })
2022-08-26 11:28:41.338 1750-27465/? D/NativeUwbManager: onDeviceStatusNotificationReceived(2)
2022-08-26 11:28:41.338 1750-27465/? I/UwbServiceCore: handleDeviceStatusNotification = ACTIVE
2022-08-26 11:28:41.338 1750-27465/? D/uwb: uwb_uci_rust::uci: Received hal notification SessionStatusNtf(SessionStatusNtfPacket { uci_packet: UciPacketData { group_id: SessionConfig, message_type: Notification, opcode: 2, child: UciNotification(UciNotificationData { child: SessionNotification(SessionNotificationData { child: SessionStatusNtf(SessionStatusNtfData { session_id: 1, session_state: SessionStateActive, reason_code: StateChangeWithSessionManagementCommands }) }) }) }, uci_notification: UciNotificationData { child: SessionNotification(SessionNotificationData { child: SessionStatusNtf(SessionStatusNtfData { session_id: 1, session_state: SessionStateActive, reason_code: StateChangeWithSessionManagementCommands }) }) }, session_notification: SessionNotificationData { child: SessionStatusNtf(SessionStatusNtfData { session_id: 1, session_state: SessionStateActive, reason_code: StateChangeWithSessionManagementCommands }) }, session_status_ntf: SessionStatusNtfData { session_id: 1, session_state: SessionStateActive, reason_code: StateChangeWithSessionManagementCommands } })
2022-08-26 11:28:41.339 1750-27465/? D/NativeUwbManager: onSessionStatusNotificationReceived(1, 2, 0)
2022-08-26 11:28:41.339 1750-27465/? I/UwbSessionManager: onSessionStatusNotificationReceived - Session ID : 1, state : ACTIVE reasonCode:0
2022-08-26 11:28:41.339 1750-32531/? I/UwbSessionNotiManager: IUwbRangingCallbacks - onRangingStarted
2022-08-26 11:28:41.340 2692-31836/? I/NearbyUWB: UWB ranging started [CONTEXT service_id=49 ]
2022-08-26 11:28:41.341 2692-31836/? I/NearbyUWB: Local UWB address is 0XF2F0 [CONTEXT service_id=49 ]
2022-08-26 11:28:41.341 2692-31836/? I/NearbyUWB: Local UWB address is 0XF2F0 [CONTEXT service_id=49 ]
2022-08-26 11:28:41.345 32373-32373/com.example.app I/UwbClientSessionScope: Started UWB ranging.
2022-08-26 11:28:41.391 904-904/? I/qorvo.uwb.uwbmac: report_nl_cb
2022-08-26 11:28:41.392 1750-27465/? D/uwb: uwb_uci_rust::uci: Received hal notification ShortMacTwoWayRangeDataNtf(ShortMacTwoWayRangeDataNtfPacket { uci_packet: UciPacketData { group_id: RangingSessionControl, message_type: Notification, opcode: 0, child: UciNotification(UciNotificationData { child: RangingNotification(RangingNotificationData { child: RangeDataNtf(RangeDataNtfData { sequence_number: 0, session_id: 1, rcr_indicator: 0, current_ranging_interval: 240, ranging_measurement_type: TwoWay, mac_address_indicator: ShortAddress, child: ShortMacTwoWayRangeDataNtf(ShortMacTwoWayRangeDataNtfData { two_way_ranging_measurements: [ShortAddressTwoWayRangingMeasurement { mac_address: 47101, status: UciStatusRangingRxTimeout, nlos: 0, distance: 0, aoa_azimuth: 0, aoa_azimuth_fom: 0, aoa_elevation: 0, aoa_elevation_fom: 0, aoa_destination_azimuth: 0, aoa_destination_azimuth_fom: 0, aoa_destination_elevation: 0, aoa_destination_elevation_fom: 0, slot_index: 2 }] }) }) }) }) }, uci_notification: UciNotificationData { child: RangingNotification(RangingNotificationData { child: RangeDataNtf(RangeDataNtfData { sequence_number: 0, session_id: 1, rcr_indicator: 0, current_ranging_interval: 240, ranging_measurement_type: TwoWay, mac_address_indicator: ShortAddress, child: ShortMacTwoWayRangeDataNtf(ShortMacTwoWayRangeDataNtfData { two_way_ranging_measurements: [ShortAddressTwoWayRangingMeasurement { mac_address: 47101, status: UciStatusRangingRxTimeout, nlos: 0, distance: 0, aoa_azimuth: 0, aoa_azimuth_fom: 0, aoa_elevation: 0, aoa_elevation_fom: 0, aoa_destination_azimuth: 0, aoa_destination_azimuth_fom: 0, aoa_destination_elevation: 0, aoa_destination_elevation_fom: 0, slot_index: 2 }] }) }) }) }, ranging_notification: RangingNotificationData { child: RangeDataNtf(RangeDataNtfData { sequence_number: 0, session_id: 1, rcr_indicator: 0, current_ranging_interval: 240, ranging_measurement_type: TwoWay, mac_address_indicator: ShortAddress, child: ShortMacTwoWayRangeDataNtf(ShortMacTwoWayRangeDataNtfData { two_way_ranging_measurements: [ShortAddressTwoWayRangingMeasurement { mac_address: 47101, status: UciStatusRangingRxTimeout, nlos: 0, distance: 0, aoa_azimuth: 0, aoa_azimuth_fom: 0, aoa_elevation: 0, aoa_elevation_fom: 0, aoa_destination_azimuth: 0, aoa_destination_azimuth_fom: 0, aoa_destination_elevation: 0, aoa_destination_elevation_fom: 0, slot_index: 2 }] }) }) }, range_data_ntf: RangeDataNtfData { sequence_number: 0, session_id: 1, rcr_indicator: 0, current_ranging_interval: 240, ranging_measurement_type: TwoWay, mac_address_indicator: ShortAddress, child: ShortMacTwoWayRangeDataNtf(ShortMacTwoWayRangeDataNtfData { two_way_ranging_measurements: [ShortAddressTwoWayRangingMeasurement { mac_address: 47101, status: UciStatusRangingRxTimeout, nlos: 0, distance: 0, aoa_azimuth: 0, aoa_azimuth_fom: 0, aoa_elevation: 0, aoa_elevation_fom: 0, aoa_destination_azimuth: 0, aoa_destination_azimuth_fom: 0, aoa_destination_elevation: 0, aoa_destination_elevation_fom: 0, slot_index: 2 }] }) }, short_mac_two_way_range_data_ntf: ShortMacTwoWayRangeDataNtfData { two_way_ranging_measurements: [ShortAddressTwoWayRangingMeasurement { mac_address: 47101, status: UciStatusRangingRxTimeout, nlos: 0, distance: 0, aoa_azimuth: 0, aoa_azimuth_fom: 0, aoa_elevation: 0, aoa_elevation_fom: 0, aoa_destination_azimuth: 0, aoa_destination_azimuth_fom: 0, aoa_destination_elevation: 0, aoa_destination_elevation_fom: 0, slot_index: 2 }] } })
2022-08-26 11:28:41.393 1750-27465/? D/NativeUwbManager: onRangeDataNotificationReceived : UwbRangingData { SeqCounter = 0, SessionId = 1, RcrIndication = 0, CurrRangingInterval = 240, RangingMeasuresType = 1, MacAddressMode = 0, NoOfRangingMeasures = 1, RangingTwoWayMeasures = [UwbTwoWayMeasurement { MacAddress = FDB7, RangingStatus = 33, NLoS = 0, Distance = 0, AoaAzimuth = 0.0, AoaAzimuthFom = 0, AoaElevation = 0.0, AoaElevationFom = 0, AoaDestAzimuth = 0.0, AoaDestAzimuthFom = 0, AoaDestElevation = 0.0, AoaDestElevationFom = 0, SlotIndex = 0x02000000}]}
2022-08-26 11:28:41.394 1750-27465/? I/UwbSessionNotiManager: IUwbRangingCallbacks - onRangingResult
2022-08-26 11:28:41.396 2692-32532/? I/NearbyUWB: UWB Ranging Data from peer 0XB7FD Status 33 [CONTEXT service_id=49 ]